Daily Usenet report: Mar 4 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Mar 4 04:39:09 newsfeed pgpverify[3233]: pgpverify: verification failed
Mar 4 04:39:09 newsfeed controlchan[2351]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1425438242.18716@news.a3.xs4all.nl>
Mar 4 11:49:52 newsfeed pgpverify[3220]: pgpverify: verification failed
Mar 4 15:57:16 newsfeed nnrpd[11311]: SERVER perl filter not defined
Mar 4 16:11:51 newsfeed nnrpd[11894]: SERVER perl filter not defined
Mar 4 18:52:30 newsfeed nnrpd[17387]: SERVER perl filter not defined
Mar 4 21:47:47 newsfeed nnrpd[23124]: SERVER perl filter not defined
Mar 4 22:00:02 newsfeed pgpverify[23681]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	589176	96.3%	67.1 MB	95.6%
innd	8950	1.5%	1.4 MB	2.1%
nnrpd	7107	1.2%	737.7 KB	1.0%
innfeed	6507	1.1%	914.6 KB	1.3%
controlchan	6	0.0%	0.7 KB	0.0%
pgpverify	3	0.0%	0.4 KB	0.0%
TOTAL: 6	611749	100.0%	70.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	584672	93.8%
Negative hits	20411	3.3%
Positive hits	17625	2.8%
Cache misses	629	0.1%
TOTAL: 4	623337	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.457	0.0%	313	0.000	17.435	319.000
article cleanup	00:00:07.858	0.0%	588781	0.000	0.013	0.062
article logging	00:00:13.899	0.0%	588881	0.000	0.024	0.099
article parse	00:17:00.074	1.2%	50249394	0.007	0.020	0.037
article write	00:04:36.251	0.3%	584402	0.113	0.473	4.537
artlog/artcncl	00:00:00.008	0.0%	295	0.000	0.027	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:55.001	0.1%	53551306	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.719	0.0%	272	0.000	6.320	133.000
hishave/artcncl	00:00:00.843	0.0%	313	0.000	2.693	20.444
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:43:56.263	3.1%	623718	0.420	4.227	19.914
history sync	00:00:00.037	0.0%	544	0.000	0.068	2.000
history write	01:01:55.114	4.3%	585273	1.658	6.348	40.366
hiswrite/artcncl	00:00:00.430	0.0%	41	0.000	10.488	302.000
idle	20:13:02.553	84.3%	39580208	0.402	1.839	10.975
nntp read	00:16:27.797	1.1%	49907537	0.009	0.020	0.034
overview write	00:00:03.606	0.0%	584402	0.000	0.006	0.029
perl filter	01:09:08.570	4.8%	585272	3.322	7.088	10.219
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:10.882	0.1%	1208252	0.023	0.059	0.149
TOTAL: 23:58:16.468	23:48:46.362	99.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.137	0.0%	39531	0.000	0.003	1.000
article prepare	00:00:00.001	0.0%	11802	0.000	0.000	0.014
article read	00:00:00.108	0.0%	2867	0.000	0.038	1.000
backlog stats	00:00:00.095	0.0%	236809	0.000	0.000	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.329	0.0%	156641	0.000	0.008	1.000
data write	00:00:03.023	0.0%	117816	0.000	0.026	0.150
idle	23:57:20.953	99.9%	236809	0.000	364.179	26192.000
status file	00:00:00.861	0.0%	235	0.000	3.664	85.667
TOTAL: 23:58:11.673	23:57:26.507	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.016	0.0%	18	0.889	0.889	0.889
idle	06:23:28.906	99.9%	10347	0.000	2223.727	435917.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.582	0.0%	22712	0.000	0.026	7.556
readart	00:00:01.485	0.0%	537	0.000	2.765	18.042
TOTAL: 06:23:47.788	06:23:30.989	99.9%	-	-	-	-

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	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
admin@openwatcom.news-admin.org	0	0	0	1	0	0
group-admin@isc.org	0	0	0	1	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	225	584629	580225	948	3456	99%	128:17:26
2	news.nask.pl	3	17246	3426	12916	904	19%	23:01:28
3	news.icm.edu.pl	46	207	24	164	19	11%	16:02:43
4	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 4		279	602086	583679	14028	4379	96%	167:21:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	135.2 GB	12.8 MB	48.1 MB	135.3 GB	99%	243.1 KB
2	news.nask.pl	12.3 MB	2.4 MB	2.5 MB	17.2 MB	71%	4.1 KB
3	news.icm.edu.pl	2.4 MB	18.5 KB	2.9 MB	5.3 MB	44%	126.9 KB
4	localhost	7.0 KB	0.0 KB	0.0 KB	7.0 KB	100%	1.7 KB
TOTAL: 4		135.3 GB	15.3 MB	53.5 MB	135.3 GB	99%	241.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 4 04:15:02 - 04:59:59	11836	2.0%	4.39	2.8 GB	2.1%	1085.28
Mar 4 05:00:00 - 05:59:59	10828	1.9%	3.01	1.4 GB	1.0%	409.69
Mar 4 06:00:00 - 06:59:59	14089	2.4%	3.91	2.0 GB	1.5%	575.00
Mar 4 07:00:00 - 07:59:59	8891	1.5%	2.47	1.3 GB	1.0%	375.14
Mar 4 08:00:00 - 08:59:59	10738	1.8%	2.98	1.2 GB	0.9%	351.83
Mar 4 09:00:00 - 09:59:59	11678	2.0%	3.24	1.3 GB	1.0%	381.68
Mar 4 10:00:00 - 10:59:59	10155	1.7%	2.82	1.3 GB	1.0%	376.49
Mar 4 11:00:00 - 11:59:59	10221	1.7%	2.84	1.4 GB	1.1%	415.49
Mar 4 12:00:00 - 12:59:59	11350	1.9%	3.15	1.7 GB	1.2%	484.25
Mar 4 13:00:00 - 13:59:59	11971	2.0%	3.33	1.7 GB	1.2%	492.34
Mar 4 14:00:00 - 14:59:59	11914	2.0%	3.31	1.4 GB	1.0%	414.13
Mar 4 15:00:00 - 15:59:59	11627	2.0%	3.23	1.6 GB	1.2%	469.99
Mar 4 16:00:00 - 16:59:59	12567	2.2%	3.49	1.5 GB	1.1%	446.86
Mar 4 17:00:00 - 17:59:59	15070	2.6%	4.19	1.3 GB	1.0%	376.93
Mar 4 18:00:00 - 18:59:59	15750	2.7%	4.38	1.5 GB	1.1%	444.54
Mar 4 19:00:00 - 19:59:59	17285	3.0%	4.80	1.7 GB	1.3%	500.52
Mar 4 20:00:00 - 20:59:59	17472	3.0%	4.85	1.7 GB	1.3%	496.34
Mar 4 21:00:00 - 21:59:59	14035	2.4%	3.90	1.2 GB	0.9%	359.57
Mar 4 22:00:00 - 22:59:59	37493	6.4%	10.41	9.0 GB	6.6%	2613.97
Mar 4 23:00:00 - 23:59:59	41476	7.1%	11.52	11.1 GB	8.2%	3224.09
Mar 5 00:00:00 - 00:59:59	45071	7.7%	12.52	13.2 GB	9.7%	3837.46
Mar 5 01:00:00 - 01:59:59	74346	12.7%	20.65	22.7 GB	16.8%	6618.67
Mar 5 02:00:00 - 02:59:59	79964	13.7%	22.21	25.6 GB	18.9%	7455.36
Mar 5 03:00:00 - 03:59:59	62770	10.7%	17.44	20.4 GB	15.1%	5946.45
Mar 5 04:00:00 - 04:15:03	15806	2.7%	17.50	5.4 GB	4.0%	6248.97
TOTAL: 24:00:01	584403	100.0%	6.76	135.4 GB	100.0%	1643.63

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3557	208	2627	11	711
2	news.nask.pl	906	10	879	10	7
3	news.icm.edu.pl	15	0	2	1	12
TOTAL: 3		4478	218	3508	22	730

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	52
it.tlc.cellulari.android	46
comp.mobile.ipad	31
nl.comp.os.linux.techniek	28
comp.sys.raspberry-pi	20
it.comp.os.win.windows8	11
comp.mobile.android	10
hr.alt.cellular.gsm.android	6
uk.rec.cycling.moderated	5
alt.sex.telephones	4
it.comp.os.win.windows7	3
de.rec.sf.startrek	1
hr.ponuda.hardver.komponente	1
TOTAL: 13	218

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

Groups	Count
pl.sci.matematyka	8
pl.sci.historia	7
pl.praca.oferowana	4
fido7.ru.guitar.soft	2
bit.listserv.catholic	1
TOTAL: 5	22

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	607
EMP (md5)	12
HTML post	6
Too many newsgroups	2
Attached HTML file	1
TOTAL: 5	628

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40172	1550	37703	150	1	0	3%	23:54:34
2	icm	48954	555	37381	590	0	12	1%	23:52:19
3	nask	22584	85	15253	2	0	0	0%	23:50:00
4	poznan	4610	2	3298	5	0	67	0%	23:55:44
5	okapi	0	0	0	0	0	3342	0%	23:50:00
TOTAL: 5		116320	2192	93635	747	1	3421	1%	119:22:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	6.9 MB	636.4 KB	7.5 MB	0.1 KB/s	4.5 KB	23:54:34
2	icm	4.0 MB	28.4 MB	32.3 MB	0.4 KB/s	28.9 KB	23:52:19
3	nask	1.6 MB	15.9 KB	1.6 MB	0.0 KB/s	18.6 KB	23:50:00
4	poznan	9.2 KB	40.3 KB	49.6 KB	0.0 KB/s	7.1 KB	23:55:44
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 5		12.4 MB	29.0 MB	41.5 MB	0.1 KB/s	14.5 KB	119:22:37

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	pc-skrzep2.cm.p.lodz.pl	288	336	561.3 KB	288	0	00:00:07
2	pc-212-51-218-225.p.lodz.pl	12	114	221.6 KB	14	0	03:35:48
3	lze200.lze.lodz.pl	12	57	90.2 KB	12	0	01:46:43
4	web.man.lodz.pl	288	28	24.3 KB	14	0	00:00:23
5	87-205-38-103.adsl.inetia.pl	2	2	2.8 KB	1	0	00:13:36
6	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 6		606	537	900.2 KB	329	4	06:23:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	561.3 KB	288	0	00:00:07
2	*.p.lodz.pl	12	114	221.6 KB	14	0	03:35:48
3	*.lze.lodz.pl	12	57	90.2 KB	12	0	01:46:43
4	*.man.lodz.pl	676	28	24.3 KB	14	4	00:00:28
5	*.adsl.inetia.pl	2	2	2.8 KB	1	0	00:13:36
6	unresolved	5	0	0.0 KB	0	0	00:46:59
7	*.toya.net.pl	17	0	0.0 KB	0	0	00:00:03
TOTAL: 7		1012	537	900.2 KB	329	4	06:23:47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.080	0.097	03:35:48
lze200.lze.lodz.pl	0.035	0.149	01:46:43
185.48.195.123	0.190	0.360	00:27:34
212.191.87.58	0.007	0.027	00:17:05
87-205-38-103.adsl.inetia.pl	0.006	0.016	00:13:36
89.38.40.73	0.023	0.054	00:02:18
web.man.lodz.pl	0.986	2.535	00:00:23
pc-skrzep2.cm.p.lodz.pl	1.123	2.602	00:00:07
staticline41435.toya.net.pl	0.070	0.102	00:00:03
mon.man.lodz.pl	1.065	2.458	00:00:03
david.man.lodz.pl	0.325	0.810	00:00:01
isvcs01.man.lodz.pl	0.023	0.035	00:00:00
?	0.008	0.066	00:00:00
TOTAL: 13	3.941	9.311	06:23:47

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	17
185.48.195.123	2
212.191.87.58	2
89.38.40.73	1
TOTAL: 6	406

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	6
TOTAL: 1	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	3	2
185.48.195.123	1	0
TOTAL: 2	4	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	338	61.2%
2	pl	214	38.8%
TOTAL: 2		552	100.0%

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

Newsgroup		Count
1	lodman.info	338
2	pl.misc.elektronika	60
3	pl.misc.kolej	54
4	pl.misc.telefonia.gsm	30
5	pl.comp.pecet	21
6	pl.rec.kuchnia	15
7	pl.comp.os.ms-windows.winnt	14
8	pl.comp.os.linux.sieci	11
9	pl.rec.dom	9
TOTAL: 9		552

Daily Usenet report

Mar 4 04:15:02 -- Mar 5 04:15:03