Daily Usenet report: Oct 20 04:15:02

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Oct 20 05:54:52 newsfeed nnrpd[527]: SERVER perl filter not defined
Oct 20 11:18:44 newsfeed nnrpd[10966]: message repeated 4 times: [ 91.234.114.21 group lodman.info 0]
Oct 20 11:18:44 newsfeed nnrpd[16353]: message repeated 4 times: [ 91.234.114.21 group lodman.test 0]
Oct 20 11:19:00 newsfeed nnrpd[16353]: message repeated 21 times: [ 91.234.114.21 group lodman.test 0]
Oct 20 11:19:00 newsfeed nnrpd[10966]: message repeated 21 times: [ 91.234.114.21 group lodman.info 0]
Oct 20 11:19:05 newsfeed nnrpd[10966]: message repeated 8 times: [ 91.234.114.21 group lodman.info 0]
Oct 20 11:29:10 newsfeed nnrpd[16700]: message repeated 2 times: [ 91.234.114.21 group lodz.ogloszenia 0]
Oct 20 11:19:05 newsfeed nnrpd[16353]: message repeated 8 times: [ 91.234.114.21 group lodman.test 0]
Oct 20 11:53:29 newsfeed nnrpd[17634]: SERVER perl filter not defined
Oct 20 12:10:46 newsfeed nnrpd[18351]: SERVER perl filter not defined
Oct 20 12:14:40 newsfeed nnrpd[18546]: SERVER perl filter not defined
Oct 20 21:52:10 newsfeed nnrpd[5963]: SERVER perl filter not defined
Oct 20 21:58:04 newsfeed nnrpd[6204]: SERVER perl filter not defined
Oct 20 22:08:54 newsfeed nnrpd[6578]: SERVER perl filter not defined
Oct 20 22:10:50 newsfeed overchan[2602]: input line too long, skipping
Oct 20 23:09:23 newsfeed nnrpd[8651]: SERVER perl filter not defined
Oct 20 23:10:39 newsfeed nnrpd[8711]: SERVER perl filter not defined
Oct 21 01:23:32 newsfeed nnrpd[13363]: SERVER perl filter not defined
Oct 21 01:24:43 newsfeed nnrpd[13387]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3163420	98.5%	369.4 MB	98.3%
innd	37860	1.2%	5.3 MB	1.4%
nnrpd	5043	0.2%	525.4 KB	0.1%
innfeed	4889	0.2%	697.1 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	3211214	100.0%	375.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3153466	96.7%
Negative hits	91177	2.8%
Positive hits	17006	0.5%
Cache misses	236	0.0%
TOTAL: 4	3261885	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.759	0.0%	153	0.000	4.961	77.000
article cleanup	00:00:45.967	0.1%	3160768	0.004	0.015	0.027
article logging	00:01:39.753	0.1%	3161403	0.015	0.032	0.985
article parse	01:20:12.083	5.6%	201674636	0.014	0.024	0.077
article write	00:33:17.220	2.3%	3140878	0.307	0.636	2.780
artlog/artcncl	00:00:00.008	0.0%	153	0.000	0.052	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:58.274	0.2%	220432656	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.184	0.0%	135	0.000	1.363	20.000
hishave/artcncl	00:00:00.053	0.0%	153	0.000	0.346	21.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:25:34.724	1.8%	3262161	0.073	0.470	7.055
history sync	00:00:00.016	0.0%	289	0.000	0.055	1.000
history write	00:39:11.103	2.7%	3153791	0.164	0.745	11.313
hiswrite/artcncl	00:00:00.039	0.0%	18	0.000	2.167	7.000
idle	12:51:40.174	53.7%	142227724	0.237	0.326	7.830
nntp read	01:11:12.735	5.0%	199149987	0.014	0.021	0.042
overview write	00:00:17.121	0.0%	3140878	0.001	0.005	0.012
perl filter	06:13:22.642	26.0%	3153791	5.073	7.103	9.078
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:03.467	0.4%	6324434	0.030	0.048	0.074
TOTAL: 23:58:17.362	23:25:16.322	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.200	0.0%	52045	0.000	0.004	0.118
article prepare	00:00:00.022	0.0%	66868	0.000	0.000	0.036
article read	00:00:20.330	0.0%	16719	0.000	1.216	139.750
backlog stats	00:00:00.213	0.0%	257639	0.000	0.001	0.015
callbacks	00:00:00.000	0.0%	14	0.000	0.000	0.000
data read	00:00:01.927	0.0%	145342	0.000	0.013	0.091
data write	00:00:10.437	0.0%	110354	0.000	0.095	0.538
idle	23:57:39.297	100.0%	257639	76.410	334.807	1212.208
status file	00:00:01.200	0.0%	1483	0.000	0.809	4.000
TOTAL: 23:58:20.226	23:58:13.626	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:45:53.035	99.9%	3345	0.000	4051.729	216143.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.406	0.0%	7422	0.000	0.055	13.889
readart	00:00:01.582	0.0%	65	0.000	24.338	125.375
TOTAL: 03:46:09.630	03:45:55.023	99.9%	-	-	-	-

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	1505	3139344	3128512	4265	6567	99%	404:05:34
2	news.nask.pl	3	31809	12873	5665	13271	40%	23:45:33
3	news.icm.edu.pl	48	201	13	185	3	6%	15:09:21
4	localhost	12	11	11	0	0	100%	00:00:00
TOTAL: 4		1568	3171365	3141409	10115	19841	99%	443:00:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	865.8 GB	307.7 MB	324.5 MB	866.4 GB	99%	289.8 KB
2	news.nask.pl	425.3 MB	47.9 MB	192.7 MB	665.9 MB	63%	26.1 KB
3	news.icm.edu.pl	546.0 KB	14.2 KB	461.9 KB	1022.1 KB	53%	63.9 KB
4	localhost	16.6 KB	0.0 KB	0.0 KB	16.6 KB	100%	1.5 KB
TOTAL: 4		866.2 GB	355.6 MB	517.6 MB	867.1 GB	99%	287.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 20 04:15:02 - 04:59:59	87636	2.8%	32.48	28.7 GB	3.3%	11144.23
Oct 20 05:00:00 - 05:59:59	113357	3.6%	31.49	38.2 GB	4.4%	11116.33
Oct 20 06:00:00 - 06:59:59	131832	4.2%	36.62	41.2 GB	4.8%	12007.69
Oct 20 07:00:00 - 07:59:59	134064	4.3%	37.24	40.9 GB	4.7%	11900.96
Oct 20 08:00:00 - 08:59:59	124481	4.0%	34.58	38.5 GB	4.4%	11208.12
Oct 20 09:00:00 - 09:59:59	131787	4.2%	36.61	40.0 GB	4.6%	11647.85
Oct 20 10:00:00 - 10:59:59	152831	4.9%	42.45	40.3 GB	4.7%	11725.08
Oct 20 11:00:00 - 11:59:59	126874	4.0%	35.24	33.0 GB	3.8%	9605.45
Oct 20 12:00:00 - 12:59:59	149223	4.7%	41.45	40.6 GB	4.7%	11819.85
Oct 20 13:00:00 - 13:59:59	148288	4.7%	41.19	40.1 GB	4.6%	11672.72
Oct 20 14:00:00 - 14:59:59	143760	4.6%	39.93	39.5 GB	4.6%	11490.98
Oct 20 15:00:00 - 15:59:59	140047	4.5%	38.90	41.2 GB	4.8%	12014.28
Oct 20 16:00:00 - 16:59:59	142920	4.5%	39.70	40.7 GB	4.7%	11844.50
Oct 20 17:00:00 - 17:59:59	150517	4.8%	41.81	39.9 GB	4.6%	11619.89
Oct 20 18:00:00 - 18:59:59	127692	4.1%	35.47	33.8 GB	3.9%	9846.42
Oct 20 19:00:00 - 19:59:59	132181	4.2%	36.72	33.9 GB	3.9%	9870.84
Oct 20 20:00:00 - 20:59:59	103740	3.3%	28.82	24.2 GB	2.8%	7060.97
Oct 20 21:00:00 - 21:59:59	97259	3.1%	27.02	23.7 GB	2.7%	6893.35
Oct 20 22:00:00 - 22:59:59	134179	4.3%	37.27	32.0 GB	3.7%	9312.13
Oct 20 23:00:00 - 23:59:59	138278	4.4%	38.41	35.0 GB	4.0%	10197.77
Oct 21 00:00:00 - 00:59:59	112350	3.6%	31.21	30.4 GB	3.5%	8867.27
Oct 21 01:00:00 - 01:59:59	134056	4.3%	37.24	35.0 GB	4.0%	10190.19
Oct 21 02:00:00 - 02:59:59	133024	4.2%	36.95	34.8 GB	4.0%	10142.88
Oct 21 03:00:00 - 03:59:59	121267	3.9%	33.69	31.2 GB	3.6%	9094.18
Oct 21 04:00:00 - 04:15:02	31092	1.0%	34.47	8.1 GB	0.9%	9449.18
TOTAL: 24:00:00	3142735	100.0%	36.37	864.7 GB	100.0%	10494.55

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13532	7	717	10	12798
2	newsfeed.pionier.net.pl	6995	151	6263	0	581
3	news.icm.edu.pl	5	0	1	0	4
TOTAL: 3		20532	158	6981	10	13383

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	83
it.tlc.cellulari.android	45
it.comp.os.win.windows10	13
it.discussioni.energia	6
comp.mobile.ipad	3
comp.sys.raspberry-pi	3
comp.mobile.android	2
it.comp.os.win.windows7	2
de.comp.lang.vbclassic	1
TOTAL: 9	158

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

Groups	Count
pl.sci.matematyka	7
pl.praca.oferowana	2
bit.listserv.catholic	1
TOTAL: 3	10

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12225
Binary in non-binary group	263
Scoring filter (8)	153
Scoring filter (9)	41
Scoring filter (10)	34
Scoring filter (13)	11
EMP (phl)	8
Too many newsgroups	5
HTML post	5
Scoring filter (22)	1
Scoring filter (15)	1
TOTAL: 11	12747

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	20441	1599	16258	405	59	33362	7%	23:52:34
2	icm	51254	481	27102	13844	0	0	0%	24:00:00
3	poznan	18561	169	12362	38	0	0	0%	23:50:00
4	nask	10985	57	7099	2	0	0	0%	24:00:00
5	okapi	0	0	0	0	0	2177	0%	23:50:00
TOTAL: 5		101241	2306	62821	14289	59	35539	2%	119:32:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	poznan	16.4 MB	4.3 MB	20.7 MB	0.2 KB/s	102.3 KB	23:50:00
2	panorama	7.4 MB	1.4 MB	8.9 MB	0.1 KB/s	4.5 KB	23:52:34
3	icm	3.9 MB	2.7 GB	2.7 GB	32.8 KB/s	197.7 KB	24:00:00
4	nask	776.0 KB	5.7 KB	781.7 KB	0.0 KB/s	13.2 KB	24:00:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 5		28.4 MB	2.7 GB	2.7 GB	6.7 KB/s	172.5 KB	119:32:34

Backlog files shrunk by innfeed:

Server	Size
panorama	976.8 KB
TOTAL: 1	976.8 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	34	30.6 KB	16	0	00:00:21
2	lze200.lze.lodz.pl	11	23	33.6 KB	5	0	00:54:23
3	91.234.114.21	11	8	12.3 KB	5	0	02:51:18
4	isvcs01.man.lodz.pl	11	0	0.0 KB	0	11	00:00:01
TOTAL: 4		321	65	76.6 KB	26	11	03:46:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	683	34	30.6 KB	16	11	00:00:27
2	*.lze.lodz.pl	11	23	33.6 KB	5	0	00:54:23
3	unresolved	11	8	12.3 KB	5	0	02:51:18
TOTAL: 3		705	65	76.6 KB	26	11	03:46:09

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.234.114.21	0.178	0.095	02:51:18
lze200.lze.lodz.pl	0.016	0.208	00:54:23
web.man.lodz.pl	0.982	4.118	00:00:21
mon.man.lodz.pl	0.928	3.715	00:00:03
isvcs01.man.lodz.pl	0.070	0.159	00:00:01
david.man.lodz.pl	0.298	1.200	00:00:01
TOTAL: 6	2.472	9.495	03:46:09

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.234.114.21	3	0
TOTAL: 1	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	57	87.7%
2	lodz	5	7.7%
3	lodman	3	4.6%
TOTAL: 3		65	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	25
2	pl.comp.os.ms-windows.winnt	16
3	pl.rec.kuchnia	10
4	pl.misc.telefonia.gsm	6
5	lodz.ogloszenia	5
6	lodman.test	2
7	lodman.info	1
TOTAL: 7		65

Daily Usenet report

Oct 20 04:15:02 -- Oct 21 04:15:02