Daily Usenet report: Jun 8 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jun 8 04:39:08 newsfeed pgpverify[25730]: pgpverify: verification failed
Jun 8 04:39:08 newsfeed controlchan[2331]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1402193042.23179@news.a3.xs4all.nl>
Jun 8 04:39:08 newsfeed pgpverify[25736]: pgpverify: verification failed
Jun 8 04:39:08 newsfeed controlchan[2331]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1402193042.23124@news.a3.xs4all.nl>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1565627	98.1%	178.6 MB	97.6%
innd	14921	0.9%	2.5 MB	1.4%
nnrpd	8185	0.5%	881.9 KB	0.5%
innfeed	7009	0.4%	1.1 MB	0.6%
controlchan	2	0.0%	0.1 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 6	1595746	100.0%	183.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1560869	94.5%
Negative hits	53103	3.2%
Positive hits	37502	2.3%
Cache misses	183	0.0%
TOTAL: 4	1651657	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.222	0.0%	317	0.000	29.091	595.000
article cleanup	00:00:27.736	0.0%	1564642	0.003	0.018	0.034
article logging	00:00:52.804	0.1%	1564765	0.016	0.034	0.258
article parse	01:01:34.470	4.3%	162313903	0.011	0.023	0.096
article write	00:14:37.129	1.0%	1559980	0.299	0.562	1.792
artlog/artcncl	00:00:00.007	0.0%	312	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:03:04.928	0.2%	172945194	0.000	0.001	0.018
hisgrep/artcncl	00:00:01.893	0.0%	197	0.000	9.609	275.000
hishave/artcncl	00:00:01.185	0.0%	317	0.000	3.738	78.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:42:53.367	11.3%	1652635	0.012	5.914	14.468
history sync	00:00:00.012	0.0%	291	0.000	0.041	1.000
history write	03:37:25.752	15.1%	1561542	0.118	8.354	21.722
hiswrite/artcncl	00:00:01.004	0.0%	120	0.000	8.367	260.000
idle	10:35:59.306	44.2%	123175864	0.117	0.310	1.462
nntp read	01:00:17.252	4.2%	161165497	0.014	0.022	0.048
overview write	00:00:10.423	0.0%	1559980	0.000	0.007	0.018
perl filter	03:49:37.373	16.0%	1561537	5.894	8.823	12.473
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:29.495	0.2%	3156056	0.044	0.066	0.140
TOTAL: 23:59:37.483	23:10:43.358	96.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.138	0.0%	36109	0.000	0.004	0.267
article prepare	00:00:00.014	0.0%	22678	0.000	0.001	0.250
article read	00:00:02.803	0.0%	5440	0.000	0.515	47.000
backlog stats	00:00:00.183	0.0%	277729	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.945	0.0%	160414	0.000	0.012	0.120
data write	00:00:04.503	0.0%	136391	0.000	0.033	0.577
idle	24:00:14.894	100.0%	277729	0.000	311.148	23286.000
status file	00:00:01.083	0.0%	231	0.000	4.688	91.000
TOTAL: 24:00:32.389	24:00:25.563	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:58:12.098	99.2%	5276	0.000	661.884	600055.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.344	0.0%	12459	0.000	0.028	1.444
readart	00:00:00.023	0.0%	326	0.000	0.071	1.000
TOTAL: 00:58:39.626	00:58:12.465	99.2%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	816	1556874	1552815	919	3140	99%	284:53:53
2	news.task.gda.pl	19	26504	5053	20420	1031	19%	45:56:45
3	news.nask.pl	2	17524	3859	13151	514	22%	24:11:39
4	news.icm.edu.pl	47	235	8	210	17	3%	16:05:57
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		885	1601137	1561735	34700	4702	97%	371:08:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	520.7 GB	51.7 MB	90.5 MB	520.9 GB	99%	351.0 KB
2	news.nask.pl	15.6 MB	1.8 MB	1.8 MB	19.1 MB	81%	4.5 KB
3	news.task.gda.pl	9.2 MB	50.0 KB	2.6 MB	11.9 MB	77%	2.0 KB
4	news.icm.edu.pl	256.3 KB	0.0 KB	267.1 KB	523.4 KB	48%	20.9 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		520.8 GB	53.5 MB	95.1 MB	520.9 GB	99%	348.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 8 04:15:02 - 04:59:59	53713	3.4%	19.91	21.1 GB	4.1%	8204.80
Jun 8 05:00:00 - 05:59:59	79250	5.1%	22.01	30.2 GB	5.8%	8785.28
Jun 8 06:00:00 - 06:59:59	84761	5.4%	23.54	31.0 GB	6.0%	9037.66
Jun 8 07:00:00 - 07:59:59	79587	5.1%	22.11	30.2 GB	5.8%	8787.09
Jun 8 08:00:00 - 08:59:59	76013	4.9%	21.11	29.6 GB	5.7%	8629.73
Jun 8 09:00:00 - 09:59:59	77760	5.0%	21.60	28.8 GB	5.5%	8381.49
Jun 8 10:00:00 - 10:59:59	39559	2.5%	10.99	15.4 GB	3.0%	4476.08
Jun 8 11:00:00 - 11:59:59	41880	2.7%	11.63	15.4 GB	3.0%	4484.36
Jun 8 12:00:00 - 12:59:59	43381	2.8%	12.05	15.5 GB	3.0%	4522.91
Jun 8 13:00:00 - 13:59:59	43295	2.8%	12.03	15.2 GB	2.9%	4440.88
Jun 8 14:00:00 - 14:59:59	43848	2.8%	12.18	15.5 GB	3.0%	4502.43
Jun 8 15:00:00 - 15:59:59	44711	2.9%	12.42	15.9 GB	3.1%	4636.93
Jun 8 16:00:00 - 16:59:59	43073	2.8%	11.96	15.2 GB	2.9%	4416.69
Jun 8 17:00:00 - 17:59:59	44356	2.8%	12.32	15.3 GB	2.9%	4442.50
Jun 8 18:00:00 - 18:59:59	44755	2.9%	12.43	16.2 GB	3.1%	4721.89
Jun 8 19:00:00 - 19:59:59	45506	2.9%	12.64	16.3 GB	3.1%	4745.27
Jun 8 20:00:00 - 20:59:59	69250	4.4%	19.24	15.9 GB	3.1%	4631.66
Jun 8 21:00:00 - 21:59:59	88023	5.6%	24.45	20.8 GB	4.0%	6055.39
Jun 8 22:00:00 - 22:59:59	86222	5.5%	23.95	19.6 GB	3.8%	5715.13
Jun 8 23:00:00 - 23:59:59	82370	5.3%	22.88	19.8 GB	3.8%	5769.31
Jun 9 00:00:00 - 00:59:59	89624	5.7%	24.90	26.6 GB	5.1%	7733.83
Jun 9 01:00:00 - 01:59:59	79915	5.1%	22.20	27.5 GB	5.3%	8008.04
Jun 9 02:00:00 - 02:59:59	83105	5.3%	23.08	29.1 GB	5.6%	8466.67
Jun 9 03:00:00 - 03:59:59	77639	5.0%	21.57	28.0 GB	5.4%	8169.82
Jun 9 04:00:00 - 04:15:04	18929	1.2%	20.94	6.8 GB	1.3%	7935.79
TOTAL: 24:00:02	1560525	100.0%	18.06	520.8 GB	100.0%	6320.81

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3252	246	2587	1	418
2	news.task.gda.pl	1006	827	27	0	152
3	news.nask.pl	515	12	491	5	7
4	news.icm.edu.pl	17	0	0	0	17
TOTAL: 4		4790	1085	3105	6	594

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	121
mailing.freebsd.svn-ports-all	113
mailing.freebsd.svn-ports-head	111
uk.railway	101
it.tlc.cellulari.android	46
mailing.freebsd.ports	43
24hoursupport.helpdesk	40
alt.politics.scorched-earth	38
mailing.freebsd.svn-src-all	32
it.comp.os.win.windows8	31
comp.mobile.ipad	30
alt.windows7.general	29
mailing.openbsd.misc	28
fa.freebsd.cvs-all	26
mailing.freebsd.svn-src-head	25
fr.usenet.abus.nocem	25
mailing.openbsd.source-changes	17
uk.rec.cycling.moderated	12
mailing.unix.samba	12
mailing.dragonflybsd.commits	12
TOTAL: 68	1085

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

Groups	Count
pl.sci.matematyka	5
pl.sci.historia	1
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	273
EMP (fsl)	141
Attached HTML file	32
EMP (md5)	15
HTML post	4
Scoring filter (9)	1
TOTAL: 6	466

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	54563	1904	28643	1308	3	8	3%	24:00:00
2	panorama	39645	1674	33961	294	0	0	4%	23:50:42
3	task	1107	134	502	16	0	0	12%	21:33:32
4	nask	23741	74	14854	200	0	0	0%	24:03:19
5	poznan	10520	30	8757	16	0	0	0%	23:56:04
6	okapi	0	0	0	0	0	1385	0%	24:00:00
TOTAL: 6		129576	3816	86717	1834	3	1393	2%	141:23:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	62.1 MB	135.5 MB	197.6 MB	2.3 KB/s	63.0 KB	24:00:00
2	panorama	8.4 MB	1021.6 KB	9.4 MB	0.1 KB/s	4.9 KB	23:50:42
3	nask	794.9 KB	76.1 MB	76.9 MB	0.9 KB/s	287.2 KB	24:03:19
4	task	279.7 KB	32.4 KB	312.0 KB	0.0 KB/s	2.1 KB	21:33:32
5	poznan	82.2 KB	36.1 KB	118.3 KB	0.0 KB/s	2.6 KB	23:56:04
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		71.6 MB	212.7 MB	284.3 MB	0.6 KB/s	51.5 KB	141:23:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	288	492.8 KB	288	00:00:07
2	web.man.lodz.pl	288	38	49.3 KB	17	00:00:25
TOTAL: 2		576	326	542.1 KB	305	00:58:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cm.p.lodz.pl	288	288	492.8 KB	288	00:00:07
2	*.man.lodz.pl	672	38	49.3 KB	17	00:00:30
3	*.pa.nsw.optusnet.com.au	320	0	0.0 KB	0	00:16:57
4	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	00:30:10
5	unresolved	1	0	0.0 KB	0	00:10:49
6	*.toya.net.pl	34	0	0.0 KB	0	00:00:02
TOTAL: 6		1317	326	542.1 KB	305	00:58:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.024	00:30:10
pa49-181-246-224.pa.nsw.optusnet.com.au	0.812	3.044	00:16:57
194.33.77.50	0.004	0.016	00:10:49
web.man.lodz.pl	0.752	3.160	00:00:25
pc-skrzep2.cm.p.lodz.pl	0.740	2.764	00:00:07
mon.man.lodz.pl	0.664	2.840	00:00:04
staticline41435.toya.net.pl	0.100	0.328	00:00:02
david.man.lodz.pl	0.252	0.916	00:00:01
TOTAL: 8	3.324	13.092	00:58:38

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-246-224.pa.nsw.optusnet.com.au	320
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	34
ip-176.105.135.22.tvsat364.lodz.pl	2
194.33.77.50	1
TOTAL: 6	741

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	288	88.3%
2	pl	38	11.7%
TOTAL: 2		326	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.pecet	22
3	pl.comp.os.ms-windows.winnt	16
TOTAL: 3		326

Daily Usenet report

Jun 8 04:15:02 -- Jun 9 04:15:04