Daily Usenet report: Feb 11 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Feb 11 04:39:06 newsfeed pgpverify[4560]: pgpverify: verification failed
Feb 11 04:39:06 newsfeed controlchan[2165]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1423623841.21560@news.a3.xs4all.nl>
Feb 11 08:34:59 newsfeed nnrpd[12986]: SERVER perl filter not defined
Feb 11 08:38:23 newsfeed nnrpd[13079]: SERVER perl filter not defined
Feb 11 08:39:16 newsfeed nnrpd[13081]: SERVER perl filter not defined
Feb 11 10:46:27 newsfeed nnrpd[17335]: SERVER perl filter not defined
Feb 11 10:47:22 newsfeed nnrpd[17343]: SERVER perl filter not defined
Feb 11 11:30:03 newsfeed nnrpd[18757]: SERVER perl filter not defined
Feb 11 13:39:37 newsfeed nnrpd[23027]: SERVER perl filter not defined
Feb 11 19:15:44 newsfeed nnrpd[1859]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1450259	98.1%	165.2 MB	97.7%
innd	13643	0.9%	2.2 MB	1.3%
nnrpd	7077	0.5%	735.0 KB	0.4%
innfeed	6814	0.5%	997.6 KB	0.6%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1477795	100.0%	169.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1381298	96.8%
Negative hits	28350	2.0%
Positive hits	17346	1.2%
Cache misses	195	0.0%
TOTAL: 4	1427189	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.094	0.0%	268	0.000	33.933	473.000
article cleanup	00:00:19.377	0.0%	1386035	0.000	0.014	0.039
article logging	00:00:34.760	0.0%	1450841	0.000	0.024	0.112
article parse	00:42:44.275	3.0%	82987890	0.010	0.031	0.068
article write	00:10:33.091	0.7%	1381806	0.091	0.458	4.269
artlog/artcncl	00:00:00.002	0.0%	258	0.000	0.008	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:44.788	0.1%	90641694	0.000	0.001	0.003
hisgrep/artcncl	00:00:01.507	0.0%	259	0.000	5.819	235.000
hishave/artcncl	00:00:00.370	0.0%	268	0.000	1.381	20.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:30:42.981	6.3%	1428797	0.008	3.809	14.201
history sync	00:00:00.020	0.0%	349	0.000	0.057	1.000
history write	02:09:06.338	9.0%	1383036	0.053	5.601	17.846
hiswrite/artcncl	00:00:00.238	0.0%	9	0.000	26.444	74.000
idle	15:27:46.431	64.4%	56549806	0.320	0.984	11.046
nntp read	00:35:49.773	2.5%	81920068	0.012	0.026	0.041
overview write	00:00:08.794	0.0%	1381806	0.000	0.006	0.035
perl filter	02:50:36.926	11.8%	1383034	3.457	7.402	10.796
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:34.734	0.2%	2795920	0.009	0.055	0.144
TOTAL: 24:00:32.959	23:32:53.499	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.096	0.0%	32306	0.000	0.003	1.000
article prepare	00:00:00.007	0.0%	13210	0.000	0.001	0.125
article read	00:00:00.282	0.0%	3249	0.000	0.087	2.000
backlog stats	00:00:00.104	0.0%	207327	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.193	0.0%	134192	0.000	0.009	1.000
data write	00:00:04.371	0.0%	102578	0.000	0.043	23.000
idle	23:59:52.496	100.0%	207327	0.000	416.697	20087.000
status file	00:00:00.685	0.0%	198	0.000	3.460	166.000
TOTAL: 24:00:03.710	23:59:59.234	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.214	0.0%	73	0.667	2.932	3.673
idle	01:38:19.500	99.3%	8895	0.000	663.238	450041.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.310	0.1%	21804	0.000	0.152	1.556
readart	00:00:14.034	0.2%	1558	0.000	9.008	35.000
TOTAL: 01:39:00.550	01:38:37.058	99.6%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	532	1381456	1377439	305	3712	99%	247:33:59
2	news.nask.pl	2	17528	3149	13864	515	17%	23:56:46
3	news.icm.edu.pl	44	243	31	204	8	12%	19:16:04
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		587	1399235	1380627	14373	4235	98%	290:46:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	340.2 GB	19.1 MB	121.4 MB	340.3 GB	99%	258.4 KB
2	news.nask.pl	10.8 MB	1.4 MB	1.5 MB	13.8 MB	78%	3.8 KB
3	news.icm.edu.pl	2.0 MB	48.8 KB	183.3 KB	2.2 MB	89%	57.9 KB
4	localhost	12.5 KB	0.0 KB	0.0 KB	12.5 KB	100%	1.6 KB
TOTAL: 4		340.2 GB	20.6 MB	123.0 MB	340.3 GB	99%	257.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 11 04:15:02 - 04:59:59	61436	4.4%	22.77	17.1 GB	5.0%	6632.51
Feb 11 05:00:00 - 05:59:59	68437	5.0%	19.01	19.5 GB	5.7%	5690.82
Feb 11 06:00:00 - 06:59:59	41455	3.0%	11.52	11.9 GB	3.5%	3469.87
Feb 11 07:00:00 - 07:59:59	76422	5.5%	21.23	22.6 GB	6.7%	6595.43
Feb 11 08:00:00 - 08:59:59	89107	6.5%	24.75	25.0 GB	7.4%	7292.38
Feb 11 09:00:00 - 09:59:59	103003	7.5%	28.61	27.2 GB	8.0%	7910.96
Feb 11 10:00:00 - 10:59:59	104207	7.5%	28.95	27.6 GB	8.1%	8052.58
Feb 11 11:00:00 - 11:59:59	56616	4.1%	15.73	14.5 GB	4.3%	4213.52
Feb 11 12:00:00 - 12:59:59	38161	2.8%	10.60	10.4 GB	3.1%	3038.43
Feb 11 13:00:00 - 13:59:59	9311	0.7%	2.59	1.1 GB	0.3%	317.81
Feb 11 14:00:00 - 14:59:59	12791	0.9%	3.55	1.9 GB	0.6%	550.33
Feb 11 15:00:00 - 15:59:59	27621	2.0%	7.67	2.5 GB	0.7%	735.56
Feb 11 16:00:00 - 16:59:59	33107	2.4%	9.20	2.8 GB	0.8%	801.50
Feb 11 17:00:00 - 17:59:59	20689	1.5%	5.75	2.0 GB	0.6%	587.55
Feb 11 18:00:00 - 18:59:59	18067	1.3%	5.02	1.9 GB	0.6%	563.60
Feb 11 19:00:00 - 19:59:59	17957	1.3%	4.99	1.9 GB	0.6%	550.27
Feb 11 20:00:00 - 20:59:59	21974	1.6%	6.10	2.7 GB	0.8%	792.96
Feb 11 21:00:00 - 21:59:59	69412	5.0%	19.28	17.2 GB	5.0%	5000.36
Feb 11 22:00:00 - 22:59:59	54178	3.9%	15.05	10.7 GB	3.1%	3118.64
Feb 11 23:00:00 - 23:59:59	101028	7.3%	28.06	23.7 GB	7.0%	6905.36
Feb 12 00:00:00 - 00:59:59	69258	5.0%	19.24	18.0 GB	5.3%	5237.19
Feb 12 01:00:00 - 01:59:59	72652	5.3%	20.18	19.3 GB	5.7%	5634.56
Feb 12 02:00:00 - 02:59:59	99858	7.2%	27.74	26.8 GB	7.9%	7802.56
Feb 12 03:00:00 - 03:59:59	90051	6.5%	25.01	24.9 GB	7.3%	7256.72
Feb 12 04:00:00 - 04:15:04	24167	1.8%	26.73	7.0 GB	2.0%	8081.32
TOTAL: 24:00:02	1380965	100.0%	15.98	340.3 GB	100.0%	4129.86

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	68505	208	2499	0	65798
2	news.nask.pl	523	10	497	1	15
3	news.icm.edu.pl	8	0	4	0	4
TOTAL: 3		69036	218	3000	1	65817

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	87
it.tlc.cellulari.android	54
comp.sys.raspberry-pi	13
it.comp.os.win.windows8	10
de.rec.sf.startrek	8
comp.mobile.android	8
uk.rec.cycling.moderated	7
alt.sex.telephones	6
nl.wetenschap.regulier	6
it.comp.os.win.windows7	5
it.comp.os.linux.ubuntu	3
nl.comp.os.linux.techniek	3
de.comp.office-pakete.ms-office.outlook	2
hr.alt.cellular.gsm.android	2
hr.ponuda.hardver.komponente	2
it.discussioni.energia	1
nl.actueel	1
TOTAL: 17	218

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

Groups	Count
pl.sci.historia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1001
EMP (md5)	5
EMP (fsl)	1
Scoring filter (9)	1
Scoring filter (10)	1
TOTAL: 5	1009

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	32956	1438	30656	186	0	0	4%	23:58:13
2	icm	40272	361	29226	1227	1	11	0%	23:55:32
3	nask	23120	60	15132	9	0	0	0%	24:00:06
4	poznan	4086	19	2867	14	0	0	0%	24:04:31
5	okapi	0	0	0	0	0	2354	0%	24:00:02
TOTAL: 5		100434	1878	77881	1436	1	2365	1%	119:58:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	36.4 MB	214.2 MB	250.6 MB	3.0 KB/s	161.6 KB	23:55:32
2	panorama	5.4 MB	1.1 MB	6.6 MB	0.1 KB/s	4.1 KB	23:58:13
3	nask	2.0 MB	1.3 MB	3.3 MB	0.0 KB/s	48.6 KB	24:00:06
4	poznan	54.8 KB	41.3 KB	96.1 KB	0.0 KB/s	2.9 KB	24:04:31
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		43.8 MB	216.7 MB	260.5 MB	0.6 KB/s	80.5 KB	119:58:24

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	ip-193-151-114-232.mnc.pl	4	1091	1.7 MB	7	0	0	00:05:36
2	pc-skrzep2.cm.p.lodz.pl	288	364	592.6 KB	288	0	0	00:00:08
3	web.man.lodz.pl	288	84	76.3 KB	39	0	0	00:00:18
4	staticline-31-182-78-80.toya.net.pl	4	17	14.0 KB	1	0	1	00:08:18
5	pc-vicki.man.lodz.pl	3	2	96.5 KB	2	0	0	01:00:00
6	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	0	00:00:00
TOTAL: 6		595	1558	2.5 MB	337	8	1	01:39:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.mnc.pl	4	1091	1.7 MB	7	0	0	00:05:36
2	*.cm.p.lodz.pl	288	364	592.6 KB	288	0	0	00:00:08
3	*.man.lodz.pl	682	86	172.8 KB	41	8	0	01:00:25
4	*.toya.net.pl	20	17	14.0 KB	1	0	1	00:08:32
5	unresolved	4	0	0.0 KB	0	0	0	00:22:52
6	*.adsl.inetia.pl	9	0	0.0 KB	0	0	0	00:01:10
7	*.elsnet.pl	2	0	0.0 KB	0	0	0	00:00:13
8	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 8		1010	1558	2.5 MB	337	8	1	01:39:00

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-vicki.man.lodz.pl	0.006	0.043	01:00:00
89.38.41.61	0.129	0.291	00:22:52
staticline-31-182-78-80.toya.net.pl	0.072	0.050	00:08:18
ip-193-151-114-232.mnc.pl	0.151	0.290	00:05:36
178-36-244-163.adsl.inetia.pl	0.061	0.100	00:01:10
web.man.lodz.pl	1.026	3.544	00:00:18
host227.elsnet.pl	0.004	0.027	00:00:13
staticline43485.toya.net.pl	0.004	0.039	00:00:13
pc-skrzep2.cm.p.lodz.pl	1.057	3.547	00:00:08
mon.man.lodz.pl	1.010	3.455	00:00:04
staticline41435.toya.net.pl	0.039	0.160	00:00:01
david.man.lodz.pl	0.317	1.058	00:00:01
isvcs01.man.lodz.pl	0.052	0.101	00:00:00
census6.shodan.io	0.000	0.018	00:00:00
TOTAL: 14	3.928	12.723	01:39:00

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
staticline41435.toya.net.pl	13
178-36-244-163.adsl.inetia.pl	9
89.38.41.61	4
staticline43485.toya.net.pl	3
host227.elsnet.pl	2
census6.shodan.io	1
TOTAL: 8	415

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-vicki.man.lodz.pl	2	0
TOTAL: 1	2	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	1455	93.4%
2	pl	86	5.5%
3	lodz	17	1.1%
TOTAL: 3		1558	100.0%

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

Newsgroup		Count
1	lodman.info	1455
2	pl.comp.pecet	68
3	lodz.test	17
4	pl.comp.os.ms-windows.winnt	16
5	pl.sci.medycyna	2
TOTAL: 5		1558

Daily Usenet report

Feb 11 04:15:02 -- Feb 12 04:15:04