Daily Usenet report: Apr 16 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Apr 16 13:00:01 newsfeed nnrpd[7542]: SERVER perl filter not defined
Apr 16 13:24:51 newsfeed nnrpd[8452]: SERVER perl filter not defined
Apr 16 14:39:19 newsfeed nnrpd[10850]: SERVER perl filter not defined
Apr 16 15:28:00 newsfeed nnrpd[12481]: SERVER perl filter not defined
Apr 16 15:28:48 newsfeed nnrpd[12503]: SERVER perl filter not defined
Apr 16 15:30:03 newsfeed nnrpd[12567]: SERVER perl filter not defined
Apr 16 17:01:43 newsfeed overchan[2426]: input line too long, skipping
Apr 16 17:32:20 newsfeed overchan[2426]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1586451	98.2%	180.7 MB	97.8%
innd	14457	0.9%	2.4 MB	1.3%
innfeed	7139	0.4%	1.0 MB	0.6%
nnrpd	7128	0.4%	757.4 KB	0.4%
overchan	2	0.0%	0.2 KB	0.0%
TOTAL: 5	1615177	100.0%	184.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1581564	97.2%
Negative hits	27911	1.7%
Positive hits	16697	1.0%
Cache misses	144	0.0%
TOTAL: 4	1626316	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.385	0.0%	286	0.000	4.843	76.000
article cleanup	00:00:20.422	0.0%	1586673	0.000	0.013	0.039
article logging	00:00:50.183	0.1%	1586894	0.012	0.032	0.079
article parse	00:39:16.808	2.7%	82784700	0.017	0.028	0.046
article write	00:15:59.226	1.1%	1582192	0.210	0.606	1.211
artlog/artcncl	00:00:00.006	0.0%	284	0.000	0.021	1.000
data move	00:01:19.434	0.1%	92543215	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.355	0.0%	266	0.000	1.335	17.000
hishave/artcncl	00:00:00.022	0.0%	286	0.000	0.077	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:30.818	0.2%	1627769	0.028	0.130	0.549
history sync	00:00:00.019	0.0%	315	0.000	0.060	1.000
history write	00:10:26.140	0.7%	1583239	0.099	0.395	4.923
hiswrite/artcncl	00:00:00.015	0.0%	20	0.000	0.750	6.000
idle	18:22:33.229	76.5%	61437161	0.571	1.077	26.882
nntp read	00:31:09.608	2.2%	81498256	0.017	0.023	0.034
overview write	00:00:11.059	0.0%	1582192	0.000	0.007	0.026
perl filter	03:32:14.314	14.7%	1583239	4.432	8.043	10.907
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:09.473	0.2%	3198996	0.037	0.059	0.131
TOTAL: 24:00:32.399	23:41:02.516	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.122	0.0%	34618	0.000	0.004	0.125
article prepare	00:00:00.011	0.0%	42292	0.000	0.000	0.083
article read	00:00:01.201	0.0%	10281	0.000	0.117	1.000
backlog stats	00:00:00.162	0.0%	235753	0.000	0.001	0.030
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.682	0.0%	149388	0.000	0.011	1.000
data write	00:00:06.689	0.0%	115271	0.000	0.058	40.000
idle	23:59:42.211	100.0%	235753	0.000	366.410	20201.000
status file	00:00:00.179	0.0%	195	0.000	0.918	8.000
TOTAL: 23:59:59.063	23:59:52.257	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.018	0.0%	76	0.125	0.237	0.361
idle	03:11:50.647	99.8%	5709	0.000	2016.228	450295.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.304	0.0%	13247	0.000	0.023	0.889
readart	00:00:00.197	0.0%	449	0.000	0.439	9.500
TOTAL: 03:12:10.510	03:11:51.166	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	570	1581966	1577115	795	4056	99%	215:29:28
2	news.nask.pl	2	17121	4441	12337	343	25%	24:36:33
3	news.icm.edu.pl	48	289	33	169	87	11%	17:20:02
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		627	1599382	1581595	13301	4486	98%	257:26:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	517.0 GB	14.6 MB	259.3 MB	517.2 GB	99%	343.0 KB
2	news.nask.pl	17.3 MB	973.2 KB	1.1 MB	19.3 MB	89%	4.1 KB
3	news.icm.edu.pl	707.0 KB	14.9 KB	1.7 MB	2.4 MB	28%	20.6 KB
4	localhost	19.8 KB	0.0 KB	0.0 KB	19.8 KB	100%	3.3 KB
TOTAL: 4		517.0 GB	15.5 MB	262.1 MB	517.2 GB	99%	342.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 16 04:15:02 - 04:59:59	52670	3.3%	19.52	18.3 GB	3.5%	7122.89
Apr 16 05:00:00 - 05:59:59	51162	3.2%	14.21	18.3 GB	3.5%	5339.14
Apr 16 06:00:00 - 06:59:59	72694	4.6%	20.19	25.0 GB	4.8%	7291.60
Apr 16 07:00:00 - 07:59:59	63872	4.0%	17.74	23.1 GB	4.5%	6741.87
Apr 16 08:00:00 - 08:59:59	33242	2.1%	9.23	9.9 GB	1.9%	2886.29
Apr 16 09:00:00 - 09:59:59	66850	4.2%	18.57	20.3 GB	3.9%	5926.46
Apr 16 10:00:00 - 10:59:59	101612	6.4%	28.23	28.2 GB	5.5%	8218.81
Apr 16 11:00:00 - 11:59:59	90934	5.7%	25.26	27.1 GB	5.2%	7888.20
Apr 16 12:00:00 - 12:59:59	71795	4.5%	19.94	25.3 GB	4.9%	7379.63
Apr 16 13:00:00 - 13:59:59	92143	5.8%	25.60	29.0 GB	5.6%	8454.55
Apr 16 14:00:00 - 14:59:59	99962	6.3%	27.77	28.7 GB	5.6%	8351.60
Apr 16 15:00:00 - 15:59:59	72885	4.6%	20.25	27.9 GB	5.4%	8137.72
Apr 16 16:00:00 - 16:59:59	73273	4.6%	20.35	24.3 GB	4.7%	7074.11
Apr 16 17:00:00 - 17:59:59	50478	3.2%	14.02	17.3 GB	3.4%	5045.48
Apr 16 18:00:00 - 18:59:59	62623	4.0%	17.40	21.3 GB	4.1%	6197.78
Apr 16 19:00:00 - 19:59:59	59020	3.7%	16.39	16.1 GB	3.1%	4697.29
Apr 16 20:00:00 - 20:59:59	11883	0.8%	3.30	1.4 GB	0.3%	416.50
Apr 16 21:00:00 - 21:59:59	38061	2.4%	10.57	11.9 GB	2.3%	3463.67
Apr 16 22:00:00 - 22:59:59	71740	4.5%	19.93	24.3 GB	4.7%	7086.70
Apr 16 23:00:00 - 23:59:59	76276	4.8%	21.19	25.3 GB	4.9%	7373.10
Apr 17 00:00:00 - 00:59:59	43085	2.7%	11.97	13.9 GB	2.7%	4057.81
Apr 17 01:00:00 - 01:59:59	61540	3.9%	17.09	21.2 GB	4.1%	6171.80
Apr 17 02:00:00 - 02:59:59	78465	5.0%	21.80	26.1 GB	5.1%	7615.43
Apr 17 03:00:00 - 03:59:59	67672	4.3%	18.80	25.6 GB	5.0%	7463.42
Apr 17 04:00:00 - 04:15:01	17531	1.1%	19.46	6.4 GB	1.2%	7438.61
TOTAL: 23:59:59	1581468	100.0%	18.30	516.6 GB	100.0%	6270.03

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4256	259	3110	0	887
2	news.nask.pl	358	9	323	2	24
3	news.icm.edu.pl	85	0	1	0	84
TOTAL: 3		4699	268	3434	2	995

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	151
it.tlc.cellulari.android	42
comp.mobile.android	17
comp.sys.raspberry-pi	15
uk.rec.cycling.moderated	11
nl.comp.os.linux.techniek	7
it.comp.os.win.windows7	7
it.comp.os.win.windows8	5
de.comp.lang.php	3
de.comp.office-pakete.ms-office.outlook	2
hr.ponuda.hardver.periferije	1
alt.sex.telephones	1
hr.alt.cellular.gsm.android	1
de.comp.office-pakete.ms-office.excel	1
de.comp.lang.vbclassic	1
it.comp.os.linux.ubuntu	1
comp.mobile.ipad	1
hr.ponuda.hardver.komponente	1
TOTAL: 18	268

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	683
EMP (md5)	85
HTML post	4
EMP (fsl)	1
Scoring filter (8)	1
TOTAL: 5	774

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	35572	2662	31683	372	0	7%	24:07:52
2	icm	41580	885	25224	6571	15	2%	23:59:54
3	poznan	6206	40	4195	30	0	0%	23:54:52
4	nask	22666	22	13193	4	0	0%	24:03:40
5	okapi	0	0	0	0	2439	0%	24:00:01
TOTAL: 5		106024	3609	74295	6977	2454	3%	120:06:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	9.0 MB	1.1 MB	10.1 MB	0.1 KB/s	3.4 KB	24:07:52
2	icm	3.3 MB	1.2 GB	1.2 GB	14.5 KB/s	168.0 KB	23:59:54
3	poznan	319.6 KB	70.9 KB	390.5 KB	0.0 KB/s	5.6 KB	23:54:52
4	nask	131.3 KB	834.2 KB	965.5 KB	0.0 KB/s	37.1 KB	24:03:40
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		12.8 MB	1.2 GB	1.2 GB	2.9 KB/s	119.5 KB	120:06:19

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	340	661.4 KB	288	0	00:00:07
2	pc-212-51-218-225.p.lodz.pl	10	71	170.3 KB	7	0	03:07:36
3	web.man.lodz.pl	288	38	37.5 KB	16	0	00:00:28
4	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 4		592	449	869.2 KB	311	6	03:12:10

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	340	661.4 KB	288	0	00:00:07
2	*.p.lodz.pl	10	71	170.3 KB	7	0	03:07:36
3	*.man.lodz.pl	678	38	37.5 KB	16	6	00:00:33
4	*.router8.tygrys.net	1	0	0.0 KB	0	0	00:00:59
5	unresolved	4	0	0.0 KB	0	0	00:02:49
6	*.toya.net.pl	59	0	0.0 KB	0	0	00:00:03
TOTAL: 6		1040	449	869.2 KB	311	6	03:12:10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.049	0.126	03:07:36
89.38.41.242	0.067	0.193	00:02:49
host8.router8.tygrys.net	0.038	0.009	00:00:59
web.man.lodz.pl	1.151	3.768	00:00:28
pc-skrzep2.cm.p.lodz.pl	1.028	3.727	00:00:07
staticline41435.toya.net.pl	0.214	0.740	00:00:03
mon.man.lodz.pl	1.129	3.549	00:00:03
david.man.lodz.pl	0.333	1.148	00:00:01
isvcs01.man.lodz.pl	0.041	0.080	00:00:00
TOTAL: 9	4.050	13.340	03:12:10

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	59
89.38.41.242	4
host8.router8.tygrys.net	1
TOTAL: 5	448

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	340	75.7%
2	pl	109	24.3%
TOTAL: 2		449	100.0%

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

Newsgroup		Count
1	lodman.info	340
2	pl.misc.elektronika	55
3	pl.comp.pecet	34
4	pl.misc.kolej	16
5	pl.comp.os.ms-windows.winnt	4
TOTAL: 5		449

Daily Usenet report

Apr 16 04:15:02 -- Apr 17 04:15:01