Daily Usenet report: Aug 31 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Aug 31 08:46:29 newsfeed nnrpd[28188]: SERVER perl filter not defined
Aug 31 09:28:33 newsfeed nnrpd[29504]: SERVER perl filter not defined
Aug 31 12:00:26 newsfeed nnrpd[1844]: SERVER perl filter not defined
Aug 31 12:02:54 newsfeed nnrpd[1995]: SERVER perl filter not defined
Sep 1 03:12:51 newsfeed pgpverify[30673]: pgpverify: verification failed
Sep 1 03:12:51 newsfeed controlchan[10869]: skipping newgroup m gcn@news.nic.it (pgpverify failed) in <20130831224017$510a@wonderland.linux.it>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1339159	97.9%	153.1 MB	97.3%
innd	15117	1.1%	2.4 MB	1.5%
innfeed	7029	0.5%	1.1 MB	0.7%
nnrpd	6898	0.5%	725.2 KB	0.5%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1368205	100.0%	157.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1334046	94.1%
Negative hits	47046	3.3%
Positive hits	35623	2.5%
Cache misses	318	0.0%
TOTAL: 4	1417033	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:13.235	0.0%	432	0.000	30.637	805.000
article cleanup	00:00:18.215	0.0%	1338176	0.003	0.014	0.032
article logging	00:00:40.913	0.0%	1338303	0.008	0.031	0.897
article parse	00:47:10.811	3.3%	69602411	0.022	0.041	0.087
article write	00:18:11.224	1.3%	1331299	0.438	0.820	2.645
artlog/artcncl	00:00:00.007	0.0%	418	0.000	0.017	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:21.429	0.1%	78202659	0.000	0.001	0.004
hisgrep/artcncl	00:00:02.835	0.0%	294	0.000	9.643	279.000
hishave/artcncl	00:00:01.647	0.0%	432	0.000	3.812	191.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:53:58.703	12.1%	1417704	0.267	7.363	14.544
history sync	00:00:00.015	0.0%	292	0.000	0.051	1.000
history write	03:38:13.121	15.2%	1334895	2.384	9.808	23.319
hiswrite/artcncl	00:00:02.032	0.0%	138	0.000	14.725	275.000
idle	11:55:21.183	49.7%	54083521	0.269	0.794	2.722
nntp read	00:31:16.541	2.2%	68594966	0.018	0.027	0.042
overview write	00:00:07.918	0.0%	1331299	0.000	0.006	0.025
perl filter	03:27:25.391	14.4%	1334894	7.016	9.323	11.809
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:18.706	0.2%	2731830	0.029	0.051	0.084
TOTAL: 23:59:35.272	23:36:43.926	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.256	0.0%	81060	0.000	0.003	0.111
article prepare	00:00:00.034	0.0%	151988	0.000	0.000	0.083
article read	00:02:31.432	0.2%	37048	0.000	4.087	78.000
backlog stats	00:00:00.311	0.0%	502665	0.000	0.001	0.025
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.109	0.0%	300723	0.000	0.010	0.060
data write	00:00:12.273	0.0%	263157	0.000	0.047	1.500
idle	23:56:54.057	99.8%	502665	0.000	171.514	10355.000
status file	00:00:01.477	0.0%	232	0.000	6.366	173.000
TOTAL: 23:59:52.310	23:59:42.949	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:52.557	0.1%	286982	0.096	0.183	0.195
idle	11:21:37.895	99.7%	293493	0.000	139.349	301116.833
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:10.647	0.0%	588728	0.000	0.018	1.000
readart	00:00:44.159	0.1%	287209	0.000	0.154	59.308
TOTAL: 11:23:59.573	11:23:25.258	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	566	1327823	1321534	948	5341	99%	257:52:39
2	news.task.gda.pl	11	26064	5827	19540	697	22%	45:13:55
3	news.nask.pl	2	15668	3972	11020	676	25%	23:58:08
4	news.icm.edu.pl	39	351	7	189	155	1%	16:21:01
5	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 5		623	1369910	1331344	31697	6869	97%	343:25:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	475.4 GB	39.9 MB	348.5 MB	475.7 GB	99%	376.0 KB
2	news.nask.pl	33.8 MB	1.9 MB	2.0 MB	37.7 MB	89%	8.3 KB
3	news.task.gda.pl	11.1 MB	42.4 KB	2.1 MB	13.3 MB	83%	2.1 KB
4	news.icm.edu.pl	467.2 KB	192.2 KB	7.6 MB	8.2 MB	5%	52.0 KB
5	localhost	6.6 KB	0.0 KB	0.0 KB	6.6 KB	100%	1.7 KB
TOTAL: 5		475.4 GB	42.1 MB	360.2 MB	475.8 GB	99%	372.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 31 04:15:03 - 04:59:59	60336	4.5%	22.37	22.3 GB	4.7%	8676.60
Aug 31 05:00:00 - 05:59:59	64775	4.9%	17.99	23.6 GB	5.0%	6872.89
Aug 31 06:00:00 - 06:59:59	100367	7.5%	27.88	34.6 GB	7.3%	10085.54
Aug 31 07:00:00 - 07:59:59	44913	3.4%	12.48	16.0 GB	3.4%	4673.68
Aug 31 08:00:00 - 08:59:59	46954	3.5%	13.04	14.2 GB	3.0%	4135.50
Aug 31 09:00:00 - 09:59:59	52994	4.0%	14.72	16.4 GB	3.4%	4763.60
Aug 31 10:00:00 - 10:59:59	50814	3.8%	14.12	15.7 GB	3.3%	4585.38
Aug 31 11:00:00 - 11:59:59	51428	3.9%	14.29	16.8 GB	3.5%	4903.57
Aug 31 12:00:00 - 12:59:59	45087	3.4%	12.52	15.2 GB	3.2%	4432.36
Aug 31 13:00:00 - 13:59:59	44657	3.4%	12.40	14.7 GB	3.1%	4285.32
Aug 31 14:00:00 - 14:59:59	45958	3.5%	12.77	16.4 GB	3.4%	4772.22
Aug 31 15:00:00 - 15:59:59	50044	3.8%	13.90	17.8 GB	3.8%	5195.84
Aug 31 16:00:00 - 16:59:59	45799	3.4%	12.72	15.6 GB	3.3%	4547.59
Aug 31 17:00:00 - 17:59:59	41555	3.1%	11.54	14.5 GB	3.1%	4231.28
Aug 31 18:00:00 - 18:59:59	40835	3.1%	11.34	16.0 GB	3.4%	4674.67
Aug 31 19:00:00 - 19:59:59	41534	3.1%	11.54	15.4 GB	3.2%	4497.45
Aug 31 20:00:00 - 20:59:59	37395	2.8%	10.39	13.2 GB	2.8%	3831.02
Aug 31 21:00:00 - 21:59:59	44177	3.3%	12.27	16.0 GB	3.4%	4647.04
Aug 31 22:00:00 - 22:59:59	45271	3.4%	12.58	16.5 GB	3.5%	4792.37
Aug 31 23:00:00 - 23:59:59	48555	3.6%	13.49	18.7 GB	3.9%	5452.54
Sep 1 00:00:00 - 00:59:59	72068	5.4%	20.02	26.1 GB	5.5%	7608.56
Sep 1 01:00:00 - 01:59:59	72899	5.5%	20.25	27.1 GB	5.7%	7882.94
Sep 1 02:00:00 - 02:59:59	86825	6.5%	24.12	32.4 GB	6.8%	9451.41
Sep 1 03:00:00 - 03:59:59	78049	5.9%	21.68	32.2 GB	6.8%	9370.56
Sep 1 04:00:00 - 04:15:03	18445	1.4%	20.43	8.0 GB	1.7%	9310.01
TOTAL: 24:00:00	1331734	100.0%	15.41	475.6 GB	100.0%	5772.03

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5450	186	2594	3	2667
2	news.task.gda.pl	706	537	22	0	147
3	news.nask.pl	694	10	663	1	20
4	news.icm.edu.pl	157	0	5	0	152
TOTAL: 4		7007	733	3284	4	2986

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	77
mailing.freebsd.svn-ports-head	76
uk.railway	42
mailing.freebsd.ports-bugs	37
it.tlc.cellulari.android	30
alt.politics.scorched-earth	29
gmane.linux.alsa.devel	24
mailing.freebsd.ports	21
mozilla.dev.tree-management	21
it.comp.os.win.windows7	20
mailing.freebsd.svn-src-all	20
fa.freebsd.cvs-all	20
uk.rec.cycling.moderated	18
mailing.freebsd.svn-src-head	18
mailing.unix.samba	17
gmane.linux.ports.arm.kernel	17
alt.windows7.general	16
fr.rec.loisirs-creatifs	16
fr.comp.os.android	14
it.comp.os.win.windows8	13
TOTAL: 65	733

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
pl.sci.historia	3
alt.france.homos.rencontres	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	2481
EMP (md5)	157
EMP (fsl)	131
Attached HTML file	85
HTML post	3
Angle-bracket bot	1
TOTAL: 6	2858

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	85832	25373	36226	6137	2	11819	29%	24:01:01
2	panorama	70306	2268	66182	649	0	0	3%	23:55:29
3	task	1633	84	819	6	0	0	5%	22:16:48
4	poznan	11031	15	9617	12	0	0	0%	23:57:04
5	nask	38315	14	24855	3342	0	0	0%	24:01:37
6	okapi	0	0	0	0	0	1825	0%	24:00:02
TOTAL: 6		207117	27754	137699	10146	2	13644	13%	142:12:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.8 GB	1.3 GB	4.1 GB	50.2 KB/s	137.7 KB	24:01:01
2	panorama	6.8 MB	2.5 MB	9.4 MB	0.1 KB/s	3.3 KB	23:55:29
3	task	167.9 KB	8.5 KB	176.4 KB	0.0 KB/s	2.0 KB	22:16:48
4	poznan	43.0 KB	24.9 KB	67.9 KB	0.0 KB/s	2.5 KB	23:57:04
5	nask	32.4 KB	824.1 MB	824.2 MB	9.8 KB/s	251.5 KB	24:01:37
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		2.8 GB	2.1 GB	5.0 GB	10.1 KB/s	137.0 KB	142:12:01

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	326	517.1 KB	288	0	00:00:07
2	web.man.lodz.pl	288	116	111.0 KB	50	0	00:00:22
3	ip-176.105.135.22.tvsat364.lodz.pl	1	2	2.3 KB	1	0	00:30:06
4	kujawiak.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 4		581	444	630.3 KB	339	4	11:23:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	326	517.1 KB	288	0	00:00:07
2	*.man.lodz.pl	676	116	111.0 KB	50	4	00:00:28
3	*.105.135.22.tvsat364.lodz.pl	1	2	2.3 KB	1	0	00:30:06
4	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:01:22
5	*.207.15.62.static.jazztel.es	2	0	0.0 KB	0	0	10:51:52
6	*.toya.net.pl	4	0	0.0 KB	0	0	00:00:00
TOTAL: 6		973	444	630.3 KB	339	4	11:23:59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
116.207.15.62.static.jazztel.es	21.457	68.957	10:51:52
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.020	00:30:06
ebu60.neoplus.adsl.tpnet.pl	0.000	0.036	00:01:22
web.man.lodz.pl	0.736	5.016	00:00:22
vegas.cm.p.lodz.pl	0.636	4.408	00:00:07
f-oper-v100.man.lodz.pl	0.624	4.656	00:00:04
kujawiak.man.lodz.pl	0.024	0.064	00:00:00
david.man.lodz.pl	0.164	1.464	00:00:00
staticline41435.toya.net.pl	0.000	0.080	00:00:00
TOTAL: 9	23.641	84.701	11:23:59

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	4
ebu60.neoplus.adsl.tpnet.pl	2
116.207.15.62.static.jazztel.es	2
TOTAL: 5	392

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	1	0
116.207.15.62.static.jazztel.es	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	326	73.4%
2	pl	118	26.6%
TOTAL: 2		444	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	lodman.info	326
2	pl.comp.os.ms-windows.winnt	68
3	pl.comp.pecet	48
4	pl.regionalne.lodz	2
TOTAL: 4		444

Daily Usenet report

Aug 31 04:15:03 -- Sep 1 04:15:03