Daily Usenet report: Jun 1 04:15:05

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jun 1 04:39:09 newsfeed pgpverify[28423]: pgpverify: verification failed
Jun 1 04:39:10 newsfeed controlchan[2339]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1401588242.3876@news.a3.xs4all.nl>
Jun 1 04:39:10 newsfeed pgpverify[28429]: pgpverify: verification failed
Jun 1 04:39:10 newsfeed controlchan[2339]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1401588242.3922@news.a3.xs4all.nl>
Jun 1 07:04:03 newsfeed pgpverify[2592]: pgpverify: verification failed
Jun 1 12:49:36 newsfeed nnrpd[17970]: SERVER perl filter not defined
Jun 1 14:38:43 newsfeed nnrpd[21903]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1206313	96.1%	136.6 MB	95.4%
nnrpd	28777	2.3%	3.4 MB	2.4%
innd	12527	1.0%	2.0 MB	1.4%
innfeed	7089	0.6%	1.0 MB	0.7%
controlchan	7	0.0%	1.0 KB	0.0%
pgpverify	3	0.0%	0.4 KB	0.0%
TOTAL: 6	1254716	100.0%	143.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1201611	95.1%
Positive hits	32711	2.6%
Negative hits	28519	2.3%
Cache misses	179	0.0%
TOTAL: 4	1263020	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.358	0.0%	320	0.000	29.244	531.000
article cleanup	00:00:19.017	0.0%	1205684	0.000	0.016	0.038
article logging	00:00:37.120	0.0%	1205829	0.010	0.031	0.499
article parse	00:50:06.285	3.5%	169239034	0.005	0.018	0.042
article write	00:11:27.854	0.8%	1200970	0.284	0.573	1.667
artlog/artcncl	00:00:00.005	0.0%	315	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:03:33.007	0.2%	177992021	0.000	0.001	0.046
hisgrep/artcncl	00:00:01.569	0.0%	245	0.000	6.404	239.000
hishave/artcncl	00:00:01.186	0.0%	320	0.000	3.706	260.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:37:10.783	6.7%	1263633	0.011	4.614	11.917
history sync	00:00:00.010	0.0%	290	0.000	0.034	1.000
history write	03:15:18.947	13.6%	1202352	0.425	9.747	31.861
hiswrite/artcncl	00:00:00.647	0.0%	75	0.000	8.627	227.000
idle	13:02:12.209	54.3%	143460236	0.175	0.327	1.582
nntp read	00:57:46.566	4.0%	168411085	0.008	0.021	0.044
overview write	00:00:07.660	0.0%	1200970	0.000	0.006	0.020
perl filter	03:03:05.259	12.7%	1202344	6.026	9.137	18.553
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:25.044	0.2%	2432903	0.035	0.060	0.093
TOTAL: 24:00:23.520	23:04:22.526	96.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.098	0.0%	30967	0.000	0.003	0.167
article prepare	00:00:00.008	0.0%	23630	0.000	0.000	0.125
article read	00:00:03.668	0.0%	5749	0.000	0.638	248.500
backlog stats	00:00:00.197	0.0%	262302	0.000	0.001	0.021
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.587	0.0%	146965	0.000	0.011	0.068
data write	00:00:05.408	0.0%	130972	0.000	0.041	1.125
idle	23:59:35.813	100.0%	262302	0.000	329.299	12310.000
status file	00:00:00.556	0.0%	230	0.000	2.417	103.667
TOTAL: 23:59:53.233	23:59:47.335	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	19:44:27.823	99.8%	339120	0.000	209.565	499490.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:10.681	0.0%	685401	0.000	0.016	1.500
readart	00:00:00.171	0.0%	390	0.000	0.438	5.769
TOTAL: 19:46:38.866	19:44:38.675	99.8%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	Other	Bad PGP	OK
bofh-control@killfile.org	1	0	1
us-control@lists.killfile.org	0	1	0
TOTAL	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	681	1194579	1191732	253	2594	99%	205:53:08
2	news.task.gda.pl	19	24807	5362	18457	988	21%	45:13:31
3	news.nask.pl	2	14985	3009	10867	1109	20%	24:22:36
4	localhost	3	2	2	0	0	100%	00:00:00
5	news.icm.edu.pl	47	296	1	236	59	0%	18:51:17
TOTAL: 5		752	1234669	1200106	29813	4750	97%	294:20:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	435.2 GB	7.5 MB	17.3 MB	435.2 GB	99%	382.1 KB
2	news.task.gda.pl	10.7 MB	37.9 KB	2.7 MB	13.5 MB	79%	2.2 KB
3	news.nask.pl	9.7 MB	3.1 MB	3.1 MB	15.8 MB	60%	3.9 KB
4	news.icm.edu.pl	84.4 KB	82.6 KB	802.8 KB	969.8 KB	8%	16.2 KB
5	localhost	2.4 KB	0.0 KB	0.0 KB	2.4 KB	100%	1.2 KB
TOTAL: 5		435.2 GB	10.7 MB	23.9 MB	435.2 GB	99%	378.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 1 04:15:05 - 04:59:59	27749	2.3%	10.30	11.4 GB	2.6%	4445.69
Jun 1 05:00:00 - 05:59:59	33483	2.8%	9.30	13.6 GB	3.1%	3970.89
Jun 1 06:00:00 - 06:59:59	37254	3.1%	10.35	15.3 GB	3.5%	4459.17
Jun 1 07:00:00 - 07:59:59	35800	3.0%	9.94	14.9 GB	3.4%	4345.75
Jun 1 08:00:00 - 08:59:59	37110	3.1%	10.31	15.6 GB	3.6%	4555.42
Jun 1 09:00:00 - 09:59:59	36733	3.1%	10.20	14.9 GB	3.4%	4327.48
Jun 1 10:00:00 - 10:59:59	34288	2.9%	9.52	13.4 GB	3.1%	3904.72
Jun 1 11:00:00 - 11:59:59	36930	3.1%	10.26	13.6 GB	3.1%	3960.30
Jun 1 12:00:00 - 12:59:59	39846	3.3%	11.07	14.8 GB	3.4%	4299.30
Jun 1 13:00:00 - 13:59:59	46583	3.9%	12.94	16.9 GB	3.9%	4910.08
Jun 1 14:00:00 - 14:59:59	51046	4.2%	14.18	17.9 GB	4.1%	5218.56
Jun 1 15:00:00 - 15:59:59	46959	3.9%	13.04	17.0 GB	3.9%	4965.31
Jun 1 16:00:00 - 16:59:59	45845	3.8%	12.73	16.7 GB	3.8%	4860.18
Jun 1 17:00:00 - 17:59:59	47433	3.9%	13.18	17.6 GB	4.0%	5128.17
Jun 1 18:00:00 - 18:59:59	49088	4.1%	13.64	18.6 GB	4.3%	5423.61
Jun 1 19:00:00 - 19:59:59	40680	3.4%	11.30	15.2 GB	3.5%	4413.70
Jun 1 20:00:00 - 20:59:59	43921	3.7%	12.20	16.5 GB	3.8%	4799.30
Jun 1 21:00:00 - 21:59:59	50605	4.2%	14.06	15.2 GB	3.5%	4439.93
Jun 1 22:00:00 - 22:59:59	47181	3.9%	13.11	14.5 GB	3.3%	4219.61
Jun 1 23:00:00 - 23:59:59	43084	3.6%	11.97	15.7 GB	3.6%	4564.33
Jun 2 00:00:00 - 00:59:59	78631	6.5%	21.84	26.1 GB	6.0%	7612.32
Jun 2 01:00:00 - 01:59:59	91339	7.6%	25.37	29.9 GB	6.9%	8714.90
Jun 2 02:00:00 - 02:59:59	93226	7.8%	25.90	31.4 GB	7.2%	9157.62
Jun 2 03:00:00 - 03:59:59	85864	7.1%	23.85	31.4 GB	7.2%	9155.91
Jun 2 04:00:00 - 04:15:02	20453	1.7%	22.68	7.9 GB	1.8%	9185.70
TOTAL: 23:59:57	1201131	100.0%	13.90	436.1 GB	100.0%	5293.36

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	2725	155	2238	0	332
2	news.nask.pl	1098	8	1077	1	12
3	news.task.gda.pl	1004	812	24	9	159
4	news.icm.edu.pl	40	0	1	0	39
TOTAL: 4		4867	975	3340	10	542

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	154
mailing.freebsd.svn-ports-head	154
comp.mobile.ipad	49
alt.politics.scorched-earth	45
uk.railway	36
mailing.openssl.dev	36
fr.usenet.abus.nocem	36
alt.windows7.general	28
mailing.freebsd.ports	26
gmane.linux.alsa.devel	24
mailing.openssl.cvs	23
mailing.freebsd.svn-src-all	22
fa.freebsd.cvs-all	21
mailing.freebsd.svn-src-head	20
mailing.postfix.users	19
mailing.openbsd.source-changes	18
mailing.gcc.gcc-cvs	15
it.tlc.cellulari.android	15
gnu.hurd.bug	13
it.comp.os.win.windows8	12
TOTAL: 74	975

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

Groups	Count
fr.comp.securite	9
alt.books.chesterton	1
TOTAL: 2	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	183
EMP (fsl)	152
EMP (md5)	39
HTML post	6
Attached HTML file	5
EMP (phl)	3
Scoring filter (8)	1
TOTAL: 7	389

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	21588	1553	11944	11	0	0	7%	23:58:56
2	panorama	34974	1086	29634	265	0	0	3%	24:03:51
3	icm	51026	1048	27446	1864	1	5	2%	24:00:07
4	task	1284	57	632	10	0	0	4%	21:05:25
5	poznan	9351	10	8212	9	0	0	0%	23:56:10
6	okapi	0	0	0	0	0	1663	0%	24:00:00
TOTAL: 6		118223	3754	77868	2159	1	1668	3%	141:04:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	30.2 MB	732.1 MB	762.3 MB	9.0 KB/s	268.1 KB	24:00:07
2	nask	4.4 MB	18.8 KB	4.4 MB	0.1 KB/s	2.9 KB	23:58:56
3	panorama	3.6 MB	1.1 MB	4.7 MB	0.1 KB/s	3.5 KB	24:03:51
4	task	119.5 KB	14.4 KB	133.9 KB	0.0 KB/s	2.0 KB	21:05:25
5	poznan	30.8 KB	43.3 KB	74.1 KB	0.0 KB/s	3.9 KB	23:56:10
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		38.4 MB	733.3 MB	771.6 MB	1.6 KB/s	133.6 KB	141:04:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	312	436.2 KB	288	0	00:00:09
2	web.man.lodz.pl	288	78	66.1 KB	38	0	00:00:25
3	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 3		578	390	502.2 KB	326	2	19:46:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	436.2 KB	288	0	00:00:09
2	*.man.lodz.pl	674	78	66.1 KB	38	2	00:00:31
3	*.pa.nsw.optusnet.com.au	5427	0	0.0 KB	0	0	03:23:45
4	*.181.14.62.static.jazztel.es	2	0	0.0 KB	0	0	14:47:36
5	*.wroclaw.mm.pl	10	0	0.0 KB	0	0	01:23:49
6	unresolved	1	0	0.0 KB	0	0	00:10:38
7	*.toya.net.pl	47	0	0.0 KB	0	0	00:00:03
TOTAL: 7		6449	390	502.2 KB	326	2	19:46:32

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
10.181.14.62.static.jazztel.es	15.576	20.868	14:47:36
host-89-231-50-15.wroclaw.mm.pl	0.008	0.148	01:23:49
pa49-181-247-52.pa.nsw.optusnet.com.au	3.724	18.100	00:56:19
pa49-181-246-165.pa.nsw.optusnet.com.au	3.284	16.744	00:35:53
pa49-181-246-42.pa.nsw.optusnet.com.au	0.996	5.412	00:28:36
pa49-181-246-94.pa.nsw.optusnet.com.au	2.660	13.396	00:27:15
pa49-181-246-243.pa.nsw.optusnet.com.au	0.208	1.216	00:19:33
pa49-181-246-111.pa.nsw.optusnet.com.au	1.540	7.680	00:17:22
194.33.77.50	0.008	0.008	00:10:38
pa49-181-246-139.pa.nsw.optusnet.com.au	0.980	5.184	00:10:18
pa49-181-246-212.pa.nsw.optusnet.com.au	0.292	1.744	00:08:25
web.man.lodz.pl	0.700	3.888	00:00:25
pc-skrzep2.cm.p.lodz.pl	0.724	3.728	00:00:09
mon.man.lodz.pl	0.548	3.592	00:00:04
staticline41435.toya.net.pl	0.076	0.672	00:00:03
david.man.lodz.pl	0.196	1.192	00:00:01
isvcs01.man.lodz.pl	0.000	0.036	00:00:00
TOTAL: 17	31.520	103.608	19:46:32

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-247-52.pa.nsw.optusnet.com.au	1415
pa49-181-246-165.pa.nsw.optusnet.com.au	1302
pa49-181-246-94.pa.nsw.optusnet.com.au	1034
pa49-181-246-111.pa.nsw.optusnet.com.au	613
pa49-181-246-42.pa.nsw.optusnet.com.au	429
pa49-181-246-139.pa.nsw.optusnet.com.au	399
mon.man.lodz.pl	288
pa49-181-246-212.pa.nsw.optusnet.com.au	132
pa49-181-246-243.pa.nsw.optusnet.com.au	103
david.man.lodz.pl	96
staticline41435.toya.net.pl	47
host-89-231-50-15.wroclaw.mm.pl	10
10.181.14.62.static.jazztel.es	2
194.33.77.50	1
TOTAL: 14	5871

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-246-243.pa.nsw.optusnet.com.au	2	0
pa49-181-247-52.pa.nsw.optusnet.com.au	2	0
pa49-181-246-42.pa.nsw.optusnet.com.au	2	0
TOTAL: 3	6	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	80.0%
2	pl	78	20.0%
TOTAL: 2		390	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	66
3	pl.comp.os.ms-windows.winnt	12
TOTAL: 3		390

Daily Usenet report

Jun 1 04:15:05 -- Jun 2 04:15:02