Daily Usenet report: Feb 18 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Feb 18 04:39:07 newsfeed pgpverify[21967]: pgpverify: verification failed
Feb 18 04:39:07 newsfeed controlchan[2373]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1424228641.20899@news.a3.xs4all.nl>
Feb 18 10:08:54 newsfeed pgpverify[1401]: pgpverify: verification failed
Feb 18 12:27:36 newsfeed nnrpd[6049]: SERVER perl filter not defined
Feb 18 12:32:18 newsfeed nnrpd[6247]: SERVER perl filter not defined
Feb 18 13:09:37 newsfeed nnrpd[7538]: SERVER perl filter not defined
Feb 18 13:11:07 newsfeed nnrpd[7581]: SERVER perl filter not defined
Feb 18 13:44:55 newsfeed nnrpd[8672]: SERVER perl filter not defined
Feb 18 13:45:12 newsfeed nnrpd[8715]: SERVER perl filter not defined
Feb 18 22:47:44 newsfeed nnrpd[26602]: SERVER perl filter not defined
Feb 18 22:48:49 newsfeed nnrpd[26609]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1063273	97.7%	123.9 MB	97.2%
innd	10778	1.0%	1.8 MB	1.4%
nnrpd	7310	0.7%	766.4 KB	0.6%
innfeed	7019	0.6%	995.6 KB	0.8%
controlchan	2	0.0%	0.1 KB	0.0%
pgpverify	2	0.0%	0.4 KB	0.0%
TOTAL: 6	1088384	100.0%	127.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1002405	96.1%
Negative hits	22315	2.1%
Positive hits	18395	1.8%
Cache misses	164	0.0%
TOTAL: 4	1043279	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.463	0.0%	367	0.000	23.060	476.000
article cleanup	00:00:14.553	0.0%	1007503	0.000	0.014	0.055
article logging	00:00:26.886	0.0%	1062786	0.000	0.025	0.128
article parse	00:32:14.157	2.2%	65197271	0.007	0.030	0.063
article write	00:09:44.777	0.7%	1003902	0.131	0.583	4.171
artlog/artcncl	00:00:00.005	0.0%	358	0.000	0.014	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:22.218	0.1%	70795805	0.000	0.001	0.003
hisgrep/artcncl	00:00:01.787	0.0%	316	0.000	5.655	134.000
hishave/artcncl	00:00:00.544	0.0%	367	0.000	1.482	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:59:16.305	4.1%	1045213	0.013	3.402	9.661
history sync	00:00:00.014	0.0%	434	0.000	0.032	2.000
history write	02:03:08.725	8.6%	1004514	0.079	7.356	20.419
hiswrite/artcncl	00:00:00.446	0.0%	51	0.000	8.745	16.000
idle	17:20:52.284	72.3%	46180181	0.417	1.352	12.449
nntp read	00:27:17.079	1.9%	64446146	0.010	0.025	0.042
overview write	00:00:06.591	0.0%	1003902	0.000	0.007	0.027
perl filter	02:04:52.855	8.7%	1004514	2.567	7.459	11.333
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:13.298	0.2%	2048053	0.013	0.065	0.135
TOTAL: 23:59:56.528	23:42:00.987	98.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.146	0.0%	40501	0.000	0.004	0.118
article prepare	00:00:00.003	0.0%	17904	0.000	0.000	0.125
article read	00:00:00.421	0.0%	4408	0.000	0.096	2.000
backlog stats	00:00:00.115	0.0%	249442	0.000	0.000	0.020
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.499	0.0%	158362	0.000	0.009	1.000
data write	00:00:06.208	0.0%	119423	0.000	0.052	14.000
idle	24:00:15.087	100.0%	249442	0.000	346.434	14794.000
status file	00:00:01.892	0.0%	198	0.000	9.556	276.000
TOTAL: 24:00:31.537	24:00:25.371	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:55:38.270	99.7%	4439	0.000	2374.019	450010.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.410	0.0%	10873	0.000	0.038	1.444
readart	00:00:01.600	0.0%	472	0.000	3.390	102.000
TOTAL: 02:56:06.184	02:55:40.280	99.8%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
control@usenet.org.uk	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	337	1004455	1001829	242	2384	99%	189:49:21
2	news.nask.pl	2	18415	2513	14741	1161	13%	24:00:33
3	news.icm.edu.pl	42	313	22	235	56	7%	18:31:26
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		390	1023191	1004372	15218	3601	98%	232:21:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	261.8 GB	10.5 MB	27.8 MB	261.9 GB	99%	273.4 KB
2	news.nask.pl	10.9 MB	3.4 MB	3.4 MB	17.7 MB	61%	4.9 KB
3	news.icm.edu.pl	351.8 KB	124.3 KB	4.2 MB	4.7 MB	7%	61.3 KB
4	localhost	12.0 KB	0.0 KB	0.0 KB	12.0 KB	100%	1.5 KB
TOTAL: 4		261.8 GB	14.0 MB	35.4 MB	261.9 GB	99%	272.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 18 04:15:03 - 04:59:59	54680	5.4%	20.27	19.2 GB	7.4%	7483.75
Feb 18 05:00:00 - 05:59:59	69742	6.9%	19.37	26.2 GB	10.0%	7634.85
Feb 18 06:00:00 - 06:59:59	40067	4.0%	11.13	14.3 GB	5.5%	4177.57
Feb 18 07:00:00 - 07:59:59	100851	10.0%	28.01	28.7 GB	11.0%	8361.45
Feb 18 08:00:00 - 08:59:59	100194	10.0%	27.83	26.3 GB	10.1%	7664.61
Feb 18 09:00:00 - 09:59:59	89324	8.9%	24.81	25.7 GB	9.8%	7488.68
Feb 18 10:00:00 - 10:59:59	45821	4.6%	12.73	14.2 GB	5.4%	4132.55
Feb 18 11:00:00 - 11:59:59	9923	1.0%	2.76	1.6 GB	0.6%	465.66
Feb 18 12:00:00 - 12:59:59	12337	1.2%	3.43	2.0 GB	0.7%	569.30
Feb 18 13:00:00 - 13:59:59	16018	1.6%	4.45	2.2 GB	0.9%	648.39
Feb 18 14:00:00 - 14:59:59	11575	1.2%	3.22	1.8 GB	0.7%	525.61
Feb 18 15:00:00 - 15:59:59	13493	1.3%	3.75	2.7 GB	1.0%	776.16
Feb 18 16:00:00 - 16:59:59	12825	1.3%	3.56	2.1 GB	0.8%	609.02
Feb 18 17:00:00 - 17:59:59	13966	1.4%	3.88	2.3 GB	0.9%	679.63
Feb 18 18:00:00 - 18:59:59	14445	1.4%	4.01	2.1 GB	0.8%	608.00
Feb 18 19:00:00 - 19:59:59	16207	1.6%	4.50	2.3 GB	0.9%	680.48
Feb 18 20:00:00 - 20:59:59	17638	1.8%	4.90	1.9 GB	0.7%	547.12
Feb 18 21:00:00 - 21:59:59	15669	1.6%	4.35	2.1 GB	0.8%	598.38
Feb 18 22:00:00 - 22:59:59	13244	1.3%	3.68	1.6 GB	0.6%	461.89
Feb 18 23:00:00 - 23:59:59	14060	1.4%	3.91	1.9 GB	0.7%	565.09
Feb 19 00:00:00 - 00:59:59	50369	5.0%	13.99	12.2 GB	4.6%	3543.57
Feb 19 01:00:00 - 01:59:59	66971	6.7%	18.60	16.4 GB	6.3%	4767.65
Feb 19 02:00:00 - 02:59:59	100964	10.1%	28.05	25.2 GB	9.6%	7330.34
Feb 19 03:00:00 - 03:59:59	83207	8.3%	23.11	21.5 GB	8.2%	6252.12
Feb 19 04:00:00 - 04:15:03	20438	2.0%	22.63	5.3 GB	2.0%	6182.92
TOTAL: 24:00:00	1004028	100.0%	11.62	261.8 GB	100.0%	3176.90

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	57664	221	1833	4	55606
2	news.nask.pl	1166	6	1149	4	7
3	news.icm.edu.pl	57	0	11	0	46
TOTAL: 3		58887	227	2993	8	55659

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	96
it.tlc.cellulari.android	34
comp.mobile.android	21
hr.ponuda.hardver.komponente	15
comp.sys.raspberry-pi	13
it.comp.os.win.windows7	13
uk.rec.cycling.moderated	9
de.comp.office-pakete.ms-office.word	7
hr.alt.cellular.gsm.iphone	4
it.comp.os.win.windows8	4
de.comp.lang.vbclassic	3
alt.sex.telephones	2
hr.rec.glazba.hifi	2
de.comp.lang.php	1
hr.alt.cellular.gsm.android	1
hr.ponuda.hardver.ostalo	1
comp.mobile.ipad	1
TOTAL: 17	227

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

Groups	Count
pl.sci.matematyka	4
bit.listserv.catholic	2
pl.sci.historia	2
TOTAL: 3	8

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	265
HTML post	63
EMP (md5)	43
Attached HTML file	5
TOTAL: 4	376

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	42565	1118	40390	165	0	2%	24:00:15
2	icm	45296	424	29982	2497	246	0%	24:06:47
3	nask	27220	255	16486	0	0	0%	24:02:22
4	poznan	3169	14	2250	6	0	0%	23:51:51
5	okapi	0	0	0	0	2462	0%	24:00:01
TOTAL: 5		118250	1811	89108	2668	2708	1%	120:01:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	4.7 MB	477.1 KB	5.1 MB	0.1 KB/s	4.1 KB	24:00:15
2	icm	2.8 MB	527.0 MB	529.9 MB	6.3 KB/s	185.8 KB	24:06:47
3	nask	2.6 MB	0.0 KB	2.6 MB	0.0 KB/s	10.3 KB	24:02:22
4	poznan	48.6 KB	10.6 KB	59.1 KB	0.0 KB/s	3.0 KB	23:51:51
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		10.1 MB	527.5 MB	537.6 MB	1.3 KB/s	122.9 KB	120:01:16

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	Rej	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	354	692.9 KB	288	0	0	00:00:08
2	lze200.lze.lodz.pl	8	54	120.2 KB	9	0	0	01:57:43
3	web.man.lodz.pl	288	36	28.5 KB	17	0	0	00:00:18
4	212.51.203.199	4	28	80.1 KB	2	0	0	00:57:02
5	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	0	00:00:00
6	orion.enn.lu	1	0	0.0 KB	0	0	1	00:00:00
TOTAL: 6		597	472	921.8 KB	316	8	1	02:56:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	354	692.9 KB	288	0	0	00:00:08
2	*.lze.lodz.pl	8	54	120.2 KB	9	0	0	01:57:43
3	*.man.lodz.pl	680	36	28.5 KB	17	8	0	00:00:25
4	unresolved	4	28	80.1 KB	2	0	0	00:57:02
5	*.elsnet.pl	6	0	0.0 KB	0	0	0	00:00:38
6	*.enn.lu	2	0	0.0 KB	0	0	1	00:00:00
7	*.toya.net.pl	86	0	0.0 KB	0	0	0	00:00:05
TOTAL: 7		1074	472	921.8 KB	316	8	1	02:56:05

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.045	0.096	01:57:43
212.51.203.199	0.025	0.059	00:57:02
host227.elsnet.pl	0.034	0.049	00:00:38
web.man.lodz.pl	1.049	3.231	00:00:18
pc-skrzep2.cm.p.lodz.pl	1.135	3.239	00:00:08
staticline41435.toya.net.pl	0.332	0.934	00:00:05
mon.man.lodz.pl	1.014	3.121	00:00:05
david.man.lodz.pl	0.334	1.032	00:00:01
isvcs01.man.lodz.pl	0.055	0.094	00:00:00
destiny.enn.lu	0.003	0.014	00:00:00
orion.enn.lu	0.007	0.015	00:00:00
?	0.000	0.012	00:00:00
TOTAL: 12	4.033	11.896	02:56:05

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	86
host227.elsnet.pl	6
destiny.enn.lu	1
TOTAL: 5	477

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
orion.enn.lu	7
TOTAL: 1	7

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HNews	1
Newsgroups: de.alt.test	1
wd passed	1
.	1
From: zisd@hubbard.org	1
Subject: wdcheck	1
Message-ID: <l.1424306242.609c4c215fe712...	1
TOTAL: 7	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	0
212.51.203.199	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	354	75.0%
2	pl	118	25.0%
TOTAL: 2		472	100.0%

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

Newsgroup		Count
1	lodman.info	354
2	pl.rec.kuchnia	32
3	pl.rec.radio.amatorskie	28
4	pl.comp.pecet	26
5	pl.rec.dom	11
6	pl.comp.os.ms-windows.winnt	10
7	pl.news.admin	8
8	pl.misc.telefonia.gsm	2
9	pl.comp.os.linux.sieci	1
TOTAL: 9		472

Daily Usenet report

Feb 18 04:15:03 -- Feb 19 04:15:03