Daily Usenet report: Sep 5 04:15:03

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 5 11:42:09 newsfeed nnrpd[8061]: SERVER perl filter not defined
Sep 5 12:03:53 newsfeed nnrpd[8741]: SERVER perl filter not defined
Sep 5 19:26:37 newsfeed overchan[2164]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1125119	97.8%	128.4 MB	97.3%
innd	11525	1.0%	1.9 MB	1.5%
nnrpd	7177	0.6%	739.2 KB	0.5%
innfeed	6403	0.6%	948.6 KB	0.7%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	1150225	100.0%	132.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1121036	95.6%
Negative hits	33648	2.9%
Positive hits	18311	1.6%
Cache misses	113	0.0%
TOTAL: 4	1173108	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.925	0.0%	296	0.000	23.395	584.000
article cleanup	00:00:18.406	0.0%	1124187	0.000	0.016	0.042
article logging	00:00:33.760	0.0%	1124284	0.011	0.030	0.680
article parse	00:36:32.613	2.5%	158229755	0.008	0.014	0.047
article write	00:12:01.805	0.8%	1120825	0.350	0.644	2.150
artlog/artcncl	00:00:00.004	0.0%	277	0.000	0.014	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:01:53.465	0.1%	165989096	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.486	0.0%	289	0.000	5.142	135.000
hishave/artcncl	00:00:00.284	0.0%	296	0.000	0.959	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:48:42.584	3.4%	1173446	0.013	2.491	6.116
history sync	00:00:00.020	0.0%	289	0.000	0.069	1.000
history write	02:31:17.370	10.5%	1121564	0.232	8.093	22.140
hiswrite/artcncl	00:00:00.043	0.0%	7	0.000	6.143	17.000
idle	16:19:21.633	68.0%	114491151	0.227	0.513	1.652
nntp read	00:37:57.690	2.6%	157327609	0.011	0.014	0.029
overview write	00:00:06.318	0.0%	1120825	0.000	0.006	0.022
perl filter	02:17:13.825	9.5%	1121560	5.446	7.341	10.086
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:10.399	0.2%	2281918	0.022	0.057	0.093
TOTAL: 23:59:20.396	23:28:18.630	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.117	0.0%	40313	0.000	0.003	0.071
article prepare	00:00:00.004	0.0%	18854	0.000	0.000	0.050
article read	00:00:00.592	0.0%	4643	0.000	0.128	8.000
backlog stats	00:00:00.128	0.0%	247554	0.000	0.001	0.015
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.324	0.0%	152155	0.000	0.009	0.060
data write	00:00:04.995	0.0%	128073	0.000	0.039	2.000
idle	24:00:12.075	100.0%	247554	0.000	349.064	16693.000
status file	00:00:00.523	0.0%	191	0.000	2.738	185.000
TOTAL: 24:00:24.324	24:00:19.758	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:35:19.168	99.6%	12577	0.000	454.732	600095.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.611	0.0%	27144	0.000	0.023	13.800
readart	00:00:00.589	0.0%	506	0.000	1.164	216.000
TOTAL: 01:35:41.370	01:35:20.368	99.6%	-	-	-	-

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	500	1121519	1117799	986	2734	99%	236:22:46
2	news.nask.pl	3	18309	3737	14071	501	20%	23:12:12
3	news.icm.edu.pl	48	323	29	176	118	8%	17:49:03
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		554	1140153	1121567	15233	3353	98%	277:24:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	395.7 GB	12.8 MB	58.4 MB	395.8 GB	99%	370.4 KB
2	news.nask.pl	17.0 MB	1.5 MB	1.7 MB	20.1 MB	84%	4.9 KB
3	news.icm.edu.pl	1.1 MB	164.7 KB	9.7 MB	10.9 MB	10%	76.2 KB
4	localhost	3.1 KB	0.0 KB	0.0 KB	3.1 KB	100%	1.6 KB
TOTAL: 4		395.8 GB	14.5 MB	69.8 MB	395.8 GB	99%	369.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 5 04:15:03 - 04:59:59	51607	4.6%	19.13	18.9 GB	4.8%	7347.10
Sep 5 05:00:00 - 05:59:59	74062	6.6%	20.57	27.1 GB	6.8%	7880.96
Sep 5 06:00:00 - 06:59:59	73732	6.6%	20.48	27.3 GB	6.9%	7962.29
Sep 5 07:00:00 - 07:59:59	77348	6.9%	21.49	28.0 GB	7.1%	8166.46
Sep 5 08:00:00 - 08:59:59	66046	5.9%	18.35	22.3 GB	5.6%	6489.28
Sep 5 09:00:00 - 09:59:59	79762	7.1%	22.16	26.6 GB	6.7%	7737.20
Sep 5 10:00:00 - 10:59:59	75623	6.7%	21.01	26.0 GB	6.6%	7564.56
Sep 5 11:00:00 - 11:59:59	52589	4.7%	14.61	19.3 GB	4.9%	5617.74
Sep 5 12:00:00 - 12:59:59	31139	2.8%	8.65	11.3 GB	2.8%	3283.20
Sep 5 13:00:00 - 13:59:59	32383	2.9%	9.00	11.9 GB	3.0%	3475.07
Sep 5 14:00:00 - 14:59:59	29828	2.7%	8.29	10.9 GB	2.8%	3171.02
Sep 5 15:00:00 - 15:59:59	27881	2.5%	7.74	9.6 GB	2.4%	2789.81
Sep 5 16:00:00 - 16:59:59	26679	2.4%	7.41	9.8 GB	2.5%	2850.04
Sep 5 17:00:00 - 17:59:59	23852	2.1%	6.63	8.7 GB	2.2%	2536.20
Sep 5 18:00:00 - 18:59:59	26335	2.3%	7.32	9.3 GB	2.3%	2699.45
Sep 5 19:00:00 - 19:59:59	27770	2.5%	7.71	8.8 GB	2.2%	2561.46
Sep 5 20:00:00 - 20:59:59	29248	2.6%	8.12	10.9 GB	2.8%	3185.13
Sep 5 21:00:00 - 21:59:59	29660	2.6%	8.24	10.8 GB	2.7%	3139.71
Sep 5 22:00:00 - 22:59:59	29556	2.6%	8.21	10.9 GB	2.8%	3184.73
Sep 5 23:00:00 - 23:59:59	25497	2.3%	7.08	9.2 GB	2.3%	2677.91
Sep 6 00:00:00 - 00:59:59	46811	4.2%	13.00	16.5 GB	4.2%	4815.54
Sep 6 01:00:00 - 01:59:59	39901	3.6%	11.08	14.3 GB	3.6%	4171.40
Sep 6 02:00:00 - 02:59:59	61483	5.5%	17.08	19.5 GB	4.9%	5665.53
Sep 6 03:00:00 - 03:59:59	67296	6.0%	18.69	22.2 GB	5.6%	6473.95
Sep 6 04:00:00 - 04:15:02	15292	1.4%	16.95	5.5 GB	1.4%	6384.30
TOTAL: 23:59:59	1121380	100.0%	12.98	395.5 GB	100.0%	4800.16

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	2833	139	2155	1	538
2	news.nask.pl	523	48	466	1	8
3	news.icm.edu.pl	106	0	6	0	100
TOTAL: 3		3462	187	2627	2	646

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	78
alt.sex.telephones	44
it.tlc.cellulari.android	15
it.comp.os.win.windows8	8
nl.comp.os.linux.techniek	6
comp.mobile.android	6
nl.wetenschap.regulier	5
hr.ponuda.hardver.komponente	5
comp.sys.raspberry-pi	4
de.comp.office-pakete.ms-office.excel	4
de.comp.office-pakete.ms-office.word	3
comp.mobile.ipad	2
uk.rec.cycling.moderated	2
hr.alt.cellular.gsm.iphone	2
de.rec.sf.startrek	1
hr.ponuda.hardver.periferije	1
hr.alt.cellular.gsm.android	1
TOTAL: 17	187

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	444
EMP (md5)	99
Scoring filter (8)	1
Scoring filter (12)	1
TOTAL: 4	545

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	41055	1582	38232	269	0	0	3%	23:50:59
2	icm	34866	465	28941	1751	1	22	1%	23:50:27
3	nask	26624	76	16195	456	0	0	0%	24:02:15
4	poznan	5231	17	3746	3	0	0	0%	24:07:30
5	okapi	0	0	0	0	0	2718	0%	24:00:01
TOTAL: 5		107776	2140	87114	2479	1	2740	1%	119:51:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.2 MB	868.3 KB	9.1 MB	0.1 KB/s	5.0 KB	23:50:59
2	icm	4.5 MB	591.0 MB	595.5 MB	7.1 KB/s	275.2 KB	23:50:27
3	nask	712.7 KB	384.9 MB	385.6 MB	4.6 KB/s	742.1 KB	24:02:15
4	poznan	44.8 KB	16.6 KB	61.4 KB	0.0 KB/s	3.1 KB	24:07:30
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		13.5 MB	976.7 MB	990.2 MB	2.3 KB/s	219.5 KB	119:51:12

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	312	469.4 KB	288	0	00:00:09
2	web.man.lodz.pl	288	192	163.3 KB	71	0	00:00:21
3	ip-176.105.135.22.tvsat364.lodz.pl	6	2	85.2 KB	2	0	01:14:01
4	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 4		584	506	717.8 KB	361	2	01:35:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	469.4 KB	288	0	00:00:09
2	*.man.lodz.pl	673	192	163.3 KB	71	2	00:00:27
3	*.105.135.22.tvsat364.lodz.pl	6	2	85.2 KB	2	0	01:14:01
4	*.191.14.62.static.jazztel.es	4	0	0.0 KB	0	0	00:20:49
5	*.cbmm.lodz.pl	2	0	0.0 KB	0	0	00:00:06
6	*.toya.net.pl	62	0	0.0 KB	0	0	00:00:05
TOTAL: 6		1035	506	717.8 KB	361	2	01:35:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.076	01:14:01
42.191.14.62.static.jazztel.es	0.386	0.559	00:20:49
web.man.lodz.pl	0.967	3.445	00:00:21
pc-skrzep2.cm.p.lodz.pl	0.920	3.107	00:00:09
router2.cbmm.lodz.pl	0.007	0.022	00:00:06
staticline41435.toya.net.pl	0.205	0.675	00:00:05
mon.man.lodz.pl	0.833	3.137	00:00:04
david.man.lodz.pl	0.273	0.943	00:00:01
isvcs01.man.lodz.pl	0.023	0.023	00:00:00
TOTAL: 9	3.622	11.987	01:35:41

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
staticline41435.toya.net.pl	62
42.191.14.62.static.jazztel.es	4
router2.cbmm.lodz.pl	2
TOTAL: 5	451

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	61.7%
2	pl	194	38.3%
TOTAL: 2		506	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	166
3	pl.comp.os.ms-windows.winnt	26
4	pl.regionalne.lodz	2
TOTAL: 4		506

Daily Usenet report

Sep 5 04:15:03 -- Sep 6 04:15:02