Daily Usenet report: May 24 04:15:03

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

May 24 04:20:15 newsfeed nnrpd[23898]: 212.191.78.190 rejected Expiring process 23822
May 24 04:20:15 newsfeed nnrpd[23899]: 212.191.78.190 rejected Expiring process 23822
May 24 17:39:22 newsfeed nnrpd[11773]: SERVER perl filter not defined
May 24 22:00:05 newsfeed pgpverify[21522]: pgpverify: verification failed
May 24 22:00:05 newsfeed controlchan[12201]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20130524200002$508b@isc.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2100472	98.7%	237.1 MB	98.4%
innd	17773	0.8%	2.8 MB	1.1%
nnrpd	6813	0.3%	727.0 KB	0.3%
innfeed	2647	0.1%	404.2 KB	0.2%
overchan	4	0.0%	0.7 KB	0.0%
controlchan	3	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2127713	100.0%	241.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	599498	94.6%
Negative hits	25750	4.1%
Positive hits	8715	1.4%
Cache misses	58	0.0%
TOTAL: 4	634021	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.262	0.0%	59	0.000	21.390	244.000
article cleanup	00:00:08.108	0.0%	600843	0.003	0.013	0.150
article logging	00:00:19.948	0.1%	600879	0.015	0.033	0.385
article parse	00:18:09.610	5.5%	16943766	0.031	0.064	0.137
article write	00:08:44.152	2.6%	596263	0.412	0.879	2.941
artlog/artcncl	00:00:00.000	0.0%	58	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:06.290	0.3%	20114822	0.001	0.003	0.020
hisgrep/artcncl	00:00:00.616	0.0%	54	0.000	11.407	27.000
hishave/artcncl	00:00:00.077	0.0%	59	0.000	1.305	10.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:52:20.454	15.8%	633962	2.614	4.954	13.171
history sync	00:00:00.002	0.0%	66	0.000	0.030	1.000
history write	01:14:59.888	22.6%	599418	5.035	7.507	19.302
hiswrite/artcncl	00:00:00.028	0.0%	5	0.000	5.600	24.000
idle	01:06:09.629	20.0%	7606584	0.186	0.522	6.844
nntp read	00:10:38.902	3.2%	16434332	0.019	0.039	0.093
overview write	00:00:04.346	0.0%	596263	0.002	0.007	0.081
perl filter	01:33:09.184	28.1%	599417	6.022	9.324	19.050
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:36.162	0.5%	1213810	0.046	0.079	0.294
TOTAL: 05:31:12.893	05:27:28.658	98.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.081	0.0%	21310	0.000	0.004	0.048
article prepare	00:00:00.012	0.0%	62864	0.000	0.000	0.006
article read	00:00:11.505	0.1%	15503	0.000	0.742	22.423
backlog stats	00:00:00.146	0.0%	165849	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.445	0.0%	86988	0.000	0.017	1.000
data write	00:00:10.599	0.1%	93336	0.000	0.114	3.621
idle	05:30:56.198	99.8%	165849	0.000	119.725	4388.000
status file	00:00:01.457	0.0%	147	0.000	9.912	148.500
TOTAL: 05:31:27.227	05:31:21.443	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:15.263	70.2%	1009	0.000	15.127	1187.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.047	0.2%	2461	0.000	0.019	0.333
readart	00:00:00.009	0.0%	90	0.000	0.100	1.000
TOTAL: 00:00:21.757	00:00:15.319	70.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	21
flushlogs	2
go	2
logmode	2
mode	133
name	7
pause	2
paused	2
reload	2
reserve	2
shutdown	9
TOTAL: 12	185

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	538	160098089	159381648	283734	432707	99%	22750:06:29
2	news.nask.pl	4	83581	42705	27479	13397	51%	125:59:03
3	news.task.gda.pl	24	110938	28823	76407	5708	25%	110:40:58
4	news.icm.edu.pl	30	4520	1358	1462	1700	30%	17:23:57
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		598	160297129	159454535	389082	453512	99%	23004:10:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	48054.2 GB	31.1 GB	35.0 GB	48120.3 GB	99%	315.7 KB
2	news.icm.edu.pl	591.1 MB	28.1 MB	774.8 MB	1.4 GB	42%	466.8 KB
3	news.nask.pl	173.1 MB	39.1 MB	42.9 MB	255.1 MB	67%	4.7 KB
4	news.task.gda.pl	58.1 MB	833.4 KB	15.1 MB	73.9 MB	78%	2.2 KB
5	localhost	0.8 KB	0.0 KB	0.0 KB	0.8 KB	100%	0.8 KB
TOTAL: 5		48055.0 GB	31.2 GB	35.8 GB	48122.0 GB	99%	315.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 24 04:15:03 - 04:59:59	83870	4.0%	31.10	25.0 GB	0.0%	9703.05
May 24 05:00:00 - 05:59:59	118092	5.7%	32.80	35.7 GB	0.0%	10389.35
May 24 06:00:00 - 06:59:59	102672	4.9%	28.52	32.3 GB	23.7%	9407.55
May 24 07:00:00 - 07:59:59	92121	4.4%	25.59	29.0 GB	21.3%	8460.31
May 24 08:00:00 - 08:59:59	87943	4.2%	24.43	26.7 GB	19.6%	7774.34
May 24 09:00:00 - 09:59:59	103814	5.0%	28.84	32.5 GB	23.8%	9454.77
May 24 10:00:00 - 10:59:59	124682	6.0%	34.63	36.9 GB	27.1%	10757.81
May 24 11:00:00 - 11:59:59	131106	6.3%	36.42	36.4 GB	26.7%	10594.95
May 24 12:00:00 - 12:59:59	120985	5.8%	33.61	36.8 GB	27.0%	10710.23
May 24 13:00:00 - 13:59:59	111908	5.4%	31.09	29.2 GB	21.5%	8511.18
May 24 14:00:00 - 14:59:59	31745	1.5%	8.82	8.4 GB	6.2%	2459.30
May 24 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 24 16:00:00 - 16:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 24 17:00:00 - 17:59:59	1	0.0%	0.00	0.8 KB	0.0%	0.00
May 24 18:00:00 - 18:59:59	43813	2.1%	12.17	10.7 GB	7.8%	3106.14
May 24 19:00:00 - 19:59:59	93384	4.5%	25.94	30.9 GB	22.7%	9011.48
May 24 20:00:00 - 20:59:59	95866	4.6%	26.63	30.9 GB	22.7%	8989.08
May 24 21:00:00 - 21:59:59	85953	4.1%	23.88	28.3 GB	20.8%	8243.91
May 24 22:00:00 - 22:59:59	90663	4.3%	25.18	28.3 GB	20.8%	8235.56
May 24 23:00:00 - 23:59:59	124176	6.0%	34.49	35.0 GB	25.7%	10187.65
May 25 00:00:00 - 00:59:59	100308	4.8%	27.86	32.4 GB	23.8%	9434.33
May 25 01:00:00 - 01:59:59	104159	5.0%	28.93	33.3 GB	24.5%	9707.20
May 25 02:00:00 - 02:59:59	106922	5.1%	29.70	34.5 GB	25.4%	10061.94
May 25 03:00:00 - 03:59:59	113011	5.4%	31.39	36.3 GB	26.6%	10563.20
May 25 04:00:00 - 04:15:04	19347	0.9%	21.40	5.2 GB	3.8%	6053.09
TOTAL: 24:00:01	2086541	100.0%	24.15	136.2 GB	100.0%	1652.62

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9132	181	5531	1	3419
2	news.nask.pl	1724	15	1583	1	125
3	news.task.gda.pl	1596	1217	177	1	201
4	news.icm.edu.pl	728	0	22	0	706
TOTAL: 4		13180	1413	7313	3	4451

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	148
mailing.freebsd.svn-ports-all	117
mailing.freebsd.svn-ports-head	117
uk.rec.cycling.moderated	78
mailing.freebsd.perl	75
uk.railway	71
mailing.unix.samba-technical	68
mailing.gcc.gcc-cvs	50
mailing.unix.dovecot	42
mailing.openbsd.ports-changes	31
mailing.database.pgsql-general	26
mozilla.dev.gaia	24
alt.politics.scorched-earth	24
mailing.gcc.libstdc++	24
hr.alt.cellular.gsm.android	23
mozilla.dev.tree-management	22
mozilla.dev.platform	21
fa.freebsd.cvs-all	21
mailing.openbsd.misc	21
mailing.freebsd.svn-src-all	20
TOTAL: 78	1413

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

Groups	Count
fr.bienvenue	1
pl.sci.matematyka	1
pl.sci.historia	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3210
EMP (phl)	703
EMP (fsl)	125
HTML post	66
Attached HTML file	36
EMP (md5)	18
Scoring filter (9)	1
Scoring filter (8)	1
TOTAL: 8	4160

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	621149	57417	344324	52480	7	314	9%	314:48:30
2	panorama	869236	42216	782528	12100	0	0	4%	338:58:43
3	poznan	233790	1607	201435	1361	0	0	0%	374:58:21
4	task	16536	176	9169	60	0	0	1%	168:44:06
5	nask	190685	80	159776	4	0	0	0%	335:07:03
6	okapi	0	0	0	0	0	24697	0%	399:36:54
TOTAL: 6		1931396	101496	1497232	66005	7	25011	5%	1932:13:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	678.2 MB	8.7 GB	9.4 GB	8.7 KB/s	89.3 KB	314:48:30
2	panorama	134.8 MB	638.2 MB	773.0 MB	0.6 KB/s	14.6 KB	338:58:43
3	poznan	5.4 MB	2.8 MB	8.1 MB	0.0 KB/s	2.8 KB	374:58:21
4	task	312.0 KB	131.0 KB	443.0 KB	0.0 KB/s	1.9 KB	168:44:06
5	nask	213.2 KB	1.8 MB	2.0 MB	0.0 KB/s	25.0 KB	335:07:03
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	399:36:54
TOTAL: 6		818.8 MB	9.3 GB	10.1 GB	1.5 KB/s	63.4 KB	1932:13:37

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	ip-176.105.135.22.tvsat364.lodz.pl	18	510	135.9 MB	11	1	01:23:21
2	vegas.cm.p.lodz.pl	246	246	325.0 KB	246	0	00:00:14
3	web.man.lodz.pl	248	120	133.6 KB	51	0	00:00:58
4	lze200.lze.lodz.pl	4	59	126.0 KB	6	0	01:19:07
5	212.191.78.190	121	4	7.9 KB	2	0	19:44:16
TOTAL: 5		637	939	136.4 MB	316	1	24:38:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.105.135.22.tvsat364.lodz.pl	18	510	135.9 MB	11	1	01:23:21
2	*.cm.p.lodz.pl	246	246	325.0 KB	246	0	00:00:14
3	*.man.lodz.pl	577	120	133.6 KB	51	0	00:01:06
4	*.lze.lodz.pl	4	59	126.0 KB	6	0	01:19:07
5	unresolved	121	4	7.9 KB	2	0	19:44:16
6	*.cust.telecolumbus.net	1	0	0.0 KB	0	0	00:00:00
7	*.207.15.62.static.jazztel.es	3	0	0.0 KB	0	0	02:09:32
8	*.internetdsl.tpnet.pl	3	0	0.0 KB	0	0	00:00:00
9	*.toya.net.pl	75	0	0.0 KB	0	0	00:00:22
TOTAL: 9		1048	939	136.4 MB	316	1	24:38:02

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.330	2.660	19:44:16
123.207.15.62.static.jazztel.es	0.152	0.176	01:36:26
ip-176.105.135.22.tvsat364.lodz.pl	0.356	2.264	01:23:21
lze200.lze.lodz.pl	0.000	0.160	01:19:07
197.207.15.62.static.jazztel.es	0.008	0.012	00:33:05
web.man.lodz.pl	1.186	8.788	00:00:58
staticline41435.toya.net.pl	0.424	2.776	00:00:22
vegas.cm.p.lodz.pl	1.144	8.496	00:00:14
f-oper-v100.man.lodz.pl	0.732	4.214	00:00:05
david.man.lodz.pl	0.304	2.280	00:00:02
gcq193.internetdsl.tpnet.pl	0.010	0.080	00:00:00
cable-95-168-137-169.cust.telecolumbus.net	0.004	0.020	00:00:00
TOTAL: 12	4.650	31.926	24:38:02

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	246
david.man.lodz.pl	83
staticline41435.toya.net.pl	75
gcq193.internetdsl.tpnet.pl	3
123.207.15.62.static.jazztel.es	2
cable-95-168-137-169.cust.telecolumbus.net	1
197.207.15.62.static.jazztel.es	1
TOTAL: 7	411

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	1
123.207.15.62.static.jazztel.es	1	0
197.207.15.62.static.jazztel.es	1	0
TOTAL: 4	6	1

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	503	53.6%
2	lodman	253	26.9%
3	pl	182	19.4%
4	de	1	0.1%
TOTAL: 4		939	100.0%

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

Newsgroup		Count
1	alt.binaries.pictures.erotica.blondes	501
2	lodman.info	246
3	pl.comp.os.ms-windows.winnt	66
4	pl.comp.pecet	57
5	pl.rec.kuchnia	49
6	lodman.test	7
7	pl.rec.gory	5
8	pl.rec.rowery	3
9	alt.binaries.pictures.erotica.female	2
10	pl.regionalne.lodz	2
11	de.comp.os.ms-windows.misc	1
TOTAL: 11		939

Daily Usenet report

May 24 04:15:03 -- May 25 04:15:04