Daily Usenet report: Jan 9 04:15:03

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jan 9 08:56:19 newsfeed nnrpd[20353]: SERVER perl filter not defined
Jan 9 11:52:35 newsfeed nnrpd[26140]: SERVER perl filter not defined
Jan 9 12:04:55 newsfeed nnrpd[26645]: SERVER perl filter not defined
Jan 9 12:44:46 newsfeed nnrpd[27962]: SERVER perl filter not defined
Jan 9 13:17:15 newsfeed nnrpd[29027]: SERVER perl filter not defined
Jan 9 16:00:05 newsfeed pgpverify[2064]: pgpverify: verification failed
Jan 9 16:00:05 newsfeed controlchan[10997]: skipping newgroup y control@usenet.org.uk (pgpverify failed) in <makegroup-uk.current-events.charlie-hebdo-20150109145828$643f@matrix.darkstorm.co.uk>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1870879	98.4%	210.4 MB	98.0%
innd	16001	0.8%	2.7 MB	1.2%
nnrpd	7365	0.4%	775.3 KB	0.4%
innfeed	6580	0.3%	969.8 KB	0.4%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1900827	100.0%	214.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1816673	96.9%
Negative hits	39229	2.1%
Positive hits	18377	1.0%
Cache misses	142	0.0%
TOTAL: 4	1874421	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.053	0.0%	190	0.000	42.384	835.000
article cleanup	00:00:28.211	0.0%	1822840	0.000	0.015	0.035
article logging	00:00:51.713	0.1%	1871928	0.005	0.028	0.381
article parse	01:06:37.393	4.6%	121839023	0.008	0.033	0.064
article write	00:17:30.581	1.2%	1818005	0.253	0.578	1.739
artlog/artcncl	00:00:00.008	0.0%	174	0.000	0.046	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:02:21.037	0.2%	132524069	0.000	0.001	0.006
hisgrep/artcncl	00:00:01.402	0.0%	173	0.000	8.104	284.000
hishave/artcncl	00:00:00.237	0.0%	190	0.000	1.247	17.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:37:16.772	6.8%	1877199	0.012	3.109	7.169
history sync	00:00:00.018	0.0%	304	0.000	0.059	2.000
history write	02:50:22.619	11.8%	1819347	0.072	5.619	14.314
hiswrite/artcncl	00:00:00.060	0.0%	17	0.000	3.529	17.500
idle	12:18:35.984	51.3%	74397420	0.316	0.596	4.615
nntp read	00:53:52.365	3.7%	120302338	0.010	0.027	0.040
overview write	00:00:11.747	0.0%	1818005	0.000	0.006	0.028
perl filter	04:13:37.927	17.6%	1819342	4.534	8.365	12.126
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:38.686	0.3%	3695104	0.015	0.059	0.111
TOTAL: 24:00:41.992	23:25:34.813	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.203	0.0%	60802	0.000	0.003	0.200
article prepare	00:00:00.008	0.0%	41244	0.000	0.000	0.031
article read	00:00:31.481	0.0%	10174	0.000	3.094	138.274
backlog stats	00:00:00.283	0.0%	406933	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.419	0.0%	216137	0.000	0.011	1.000
data write	00:00:12.391	0.0%	236616	0.000	0.052	4.000
idle	23:58:46.517	99.9%	406933	0.000	212.139	12512.000
status file	00:00:01.447	0.0%	204	0.000	7.093	209.500
TOTAL: 23:59:42.841	23:59:34.749	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:51:46.160	99.7%	4771	0.000	2160.168	362508.400
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.267	0.0%	11444	0.000	0.023	0.667
readart	00:00:01.440	0.0%	423	0.000	3.404	293.000
TOTAL: 02:52:13.873	02:51:47.867	99.7%	-	-	-	-

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	625	1817983	1813083	867	4033	99%	328:02:50
2	news.nask.pl	4	18569	3963	13836	770	21%	24:13:55
3	news.icm.edu.pl	37	291	24	219	48	8%	17:45:42
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		672	1836848	1817075	14922	4851	98%	370:02:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	536.4 GB	33.0 MB	145.3 MB	536.5 GB	99%	309.6 KB
2	news.nask.pl	14.4 MB	1.9 MB	1.9 MB	18.3 MB	79%	3.9 KB
3	news.icm.edu.pl	354.1 KB	83.6 KB	4.4 MB	4.9 MB	7%	69.4 KB
4	localhost	13.7 KB	0.0 KB	0.0 KB	13.7 KB	100%	2.7 KB
TOTAL: 4		536.4 GB	35.0 MB	151.7 MB	536.6 GB	99%	308.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 9 04:15:03 - 04:59:59	69743	3.8%	25.86	19.8 GB	3.7%	7714.82
Jan 9 05:00:00 - 05:59:59	87528	4.8%	24.31	26.7 GB	5.0%	7789.87
Jan 9 06:00:00 - 06:59:59	60220	3.3%	16.73	18.7 GB	3.5%	5460.82
Jan 9 07:00:00 - 07:59:59	70384	3.9%	19.55	22.5 GB	4.2%	6553.50
Jan 9 08:00:00 - 08:59:59	88311	4.9%	24.53	27.1 GB	5.1%	7894.70
Jan 9 09:00:00 - 09:59:59	87588	4.8%	24.33	27.0 GB	5.0%	7878.54
Jan 9 10:00:00 - 10:59:59	65161	3.6%	18.10	19.3 GB	3.6%	5621.38
Jan 9 11:00:00 - 11:59:59	91507	5.0%	25.42	27.4 GB	5.1%	7985.44
Jan 9 12:00:00 - 12:59:59	91132	5.0%	25.31	29.0 GB	5.4%	8434.11
Jan 9 13:00:00 - 13:59:59	63491	3.5%	17.64	20.0 GB	3.7%	5820.00
Jan 9 14:00:00 - 14:59:59	81416	4.5%	22.62	23.5 GB	4.4%	6857.19
Jan 9 15:00:00 - 15:59:59	93014	5.1%	25.84	28.4 GB	5.3%	8263.04
Jan 9 16:00:00 - 16:59:59	63308	3.5%	17.59	17.5 GB	3.3%	5107.99
Jan 9 17:00:00 - 17:59:59	61496	3.4%	17.08	17.6 GB	3.3%	5113.05
Jan 9 18:00:00 - 18:59:59	101780	5.6%	28.27	24.4 GB	4.6%	7117.22
Jan 9 19:00:00 - 19:59:59	82272	4.5%	22.85	26.8 GB	5.0%	7817.48
Jan 9 20:00:00 - 20:59:59	66159	3.6%	18.38	21.5 GB	4.0%	6247.86
Jan 9 21:00:00 - 21:59:59	78229	4.3%	21.73	23.8 GB	4.4%	6926.32
Jan 9 22:00:00 - 22:59:59	52508	2.9%	14.59	15.4 GB	2.9%	4492.84
Jan 9 23:00:00 - 23:59:59	13264	0.7%	3.68	2.3 GB	0.4%	666.11
Jan 10 00:00:00 - 00:59:59	45917	2.5%	12.75	12.5 GB	2.3%	3640.83
Jan 10 01:00:00 - 01:59:59	84501	4.7%	23.47	23.6 GB	4.4%	6867.11
Jan 10 02:00:00 - 02:59:59	108766	6.0%	30.21	25.9 GB	4.8%	7550.49
Jan 10 03:00:00 - 03:59:59	88704	4.9%	24.64	28.9 GB	5.4%	8407.31
Jan 10 04:00:00 - 04:15:03	20381	1.1%	22.57	6.7 GB	1.3%	7806.55
TOTAL: 24:00:00	1816780	100.0%	21.03	536.4 GB	100.0%	6510.46

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	53101	278	2744	0	50079
2	news.nask.pl	776	11	745	2	18
3	news.icm.edu.pl	48	0	7	0	41
TOTAL: 3		53925	289	3496	2	50138

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	128
it.tlc.cellulari.android	62
it.comp.os.win.windows7	16
comp.mobile.ipad	14
comp.mobile.android	14
comp.sys.raspberry-pi	12
uk.rec.cycling.moderated	12
alt.sex.telephones	4
nl.comp.os.linux.techniek	4
uk.current-events.charlie-hebdo	4
it.comp.os.win.windows8	4
de.comp.os.os2	3
de.comp.office-pakete.ms-office.excel	3
hr.ponuda.hardver.komponente	3
hr.ponuda.hardver.periferije	1
hr.alt.cellular.gsm.android	1
comp.sys.mac.vintage	1
de.comp.lang.vbclassic	1
it.comp.os.linux.ubuntu	1
hr.rec.glazba.hifi	1
TOTAL: 20	289

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

Groups	Count
bit.listserv.catholic	1
pl.sci.historia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1006
EMP (md5)	35
HTML post	3
EMP (fsl)	1
TOTAL: 4	1045

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	60007	1615	57399	164	0	2%	24:05:23
2	icm	54875	751	43924	2901	39	1%	23:52:18
3	nask	36169	37	22769	4284	7502	0%	23:59:41
4	poznan	5216	35	3672	36	0	0%	24:03:28
5	okapi	0	0	0	0	2243	0%	24:00:00
TOTAL: 5		156267	2438	127764	7385	9784	1%	120:00:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	55.2 MB	597.0 MB	652.2 MB	7.8 KB/s	182.9 KB	23:52:18
2	panorama	6.6 MB	1.9 MB	8.4 MB	0.1 KB/s	4.9 KB	24:05:23
3	nask	206.8 KB	1.9 GB	1.9 GB	22.9 KB/s	458.4 KB	23:59:41
4	poznan	120.2 KB	244.6 KB	364.8 KB	0.0 KB/s	5.1 KB	24:03:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		62.1 MB	2.5 GB	2.5 GB	6.2 KB/s	270.5 KB	120:00:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	312	415.6 KB	288	0	00:00:08
2	lze200.lze.lodz.pl	8	63	98.1 KB	5	0	02:19:18
3	web.man.lodz.pl	291	47	46.3 KB	24	3	00:00:26
4	212.51.203.199	2	1	1.7 KB	1	0	00:12:15
5	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 5		591	423	561.7 KB	318	5	02:52:13

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	415.6 KB	288	0	00:00:08
2	*.lze.lodz.pl	8	63	98.1 KB	5	0	02:19:18
3	*.man.lodz.pl	677	47	46.3 KB	24	5	00:00:33
4	unresolved	2	1	1.7 KB	1	0	00:12:15
5	*.elsnet.pl	8	0	0.0 KB	0	0	00:00:41
6	*.105.135.22.tvsat364.lodz.pl	1	0	0.0 KB	0	0	00:01:37
7	*.206.15.62.static.jazztel.es	2	0	0.0 KB	0	0	00:17:30
8	*.toya.net.pl	108	0	0.0 KB	0	0	00:00:09
TOTAL: 8		1094	423	561.7 KB	318	5	02:52:13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.044	0.172	02:19:18
141.206.15.62.static.jazztel.es	0.028	0.059	00:17:30
212.51.203.199	0.000	0.046	00:12:15
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.021	00:01:37
host227.elsnet.pl	0.023	0.162	00:00:41
web.man.lodz.pl	1.031	5.620	00:00:26
staticline41435.toya.net.pl	0.409	2.139	00:00:09
pc-skrzep2.cm.p.lodz.pl	1.076	5.714	00:00:08
mon.man.lodz.pl	0.961	5.603	00:00:04
david.man.lodz.pl	0.337	1.851	00:00:01
isvcs01.man.lodz.pl	0.009	0.047	00:00:00
?	0.004	0.015	00:00:00
TOTAL: 12	3.926	21.449	02:52:13

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	108
david.man.lodz.pl	96
host227.elsnet.pl	8
141.206.15.62.static.jazztel.es	2
ip-176.105.135.22.tvsat364.lodz.pl	1
TOTAL: 6	503

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	5	1
141.206.15.62.static.jazztel.es	1	0
TOTAL: 2	6	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	73.8%
2	pl	111	26.2%
TOTAL: 2		423	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	67
3	pl.misc.telefonia.gsm	26
4	pl.comp.os.ms-windows.winnt	14
5	pl.rec.mineraly	3
6	pl.rec.radio.amatorskie	1
TOTAL: 6		423

Daily Usenet report

Jan 9 04:15:03 -- Jan 10 04:15:03