Daily Usenet report: Jan 24 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Jan 24 08:55:38 newsfeed nnrpd[2642]: SERVER perl filter not defined
Jan 24 08:57:08 newsfeed nnrpd[2655]: SERVER perl filter not defined
Jan 24 08:57:50 newsfeed nnrpd[2673]: SERVER perl filter not defined
Jan 24 08:58:55 newsfeed nnrpd[2686]: SERVER perl filter not defined
Jan 24 10:38:52 newsfeed nnrpd[6129]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1098823	95.1%	128.5 MB	94.9%
innfeed	42075	3.6%	4.9 MB	3.6%
innd	9229	0.8%	1.5 MB	1.1%
nnrpd	4924	0.4%	523.6 KB	0.4%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1155053	100.0%	135.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1087151	97.3%
Negative hits	19165	1.7%
Positive hits	8234	0.7%
Cache misses	2992	0.3%
TOTAL: 4	1117542	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.000	0.0%	74	0.000	13.514	39.000
article cleanup	00:00:14.691	0.0%	1099575	0.000	0.013	0.042
article logging	00:00:47.489	0.1%	1100035	0.010	0.043	0.111
article parse	00:18:58.471	1.3%	51402552	0.000	0.022	0.059
article write	00:07:22.911	0.5%	1095667	0.205	0.404	12.734
artlog/artcncl	00:00:00.002	0.0%	73	0.000	0.027	0.100
data move	00:00:46.301	0.1%	57655417	0.000	0.001	0.022
hisgrep/artcncl	00:00:00.209	0.0%	68	0.000	3.074	31.000
hishave/artcncl	00:00:00.004	0.0%	74	0.000	0.054	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:06.408	0.2%	1128652	0.010	0.165	0.500
history sync	00:00:00.028	0.0%	344	0.000	0.081	1.000
history write	00:06:40.060	0.5%	1097535	0.103	0.365	0.655
hiswrite/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
idle	20:44:14.119	86.4%	43754947	0.590	1.706	3426.051
nntp read	00:33:55.729	2.4%	50497605	0.028	0.040	0.130
overview write	00:00:05.740	0.0%	1095667	0.000	0.005	0.030
perl filter	01:46:26.533	7.4%	1097535	1.205	5.819	6.813
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:13.406	0.2%	2208460	0.009	0.060	0.105
TOTAL: 24:00:27.777	23:44:53.101	98.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.129	0.0%	57461	0.000	0.002	0.016
article prepare	00:00:00.002	0.0%	15974	0.000	0.000	0.004
article read	00:00:20.033	0.0%	3906	0.000	5.129	295.600
backlog stats	00:00:00.114	0.0%	136835	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.449	0.0%	71926	0.003	0.020	0.317
data write	00:00:03.350	0.0%	54957	0.016	0.061	0.299
idle	23:53:52.547	99.9%	136835	45.134	628.732	1208.792
status file	00:00:14.563	0.0%	15760	0.698	0.924	1.273
TOTAL: 23:54:42.694	23:54:32.187	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:54.501	99.2%	3083	0.000	601.525	600057.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.637	0.0%	6823	0.000	0.093	7.211
readart	00:00:00.033	0.0%	35	0.000	0.943	32.000
TOTAL: 00:31:08.924	00:30:55.171	99.3%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	252	1094146	1087566	5608	972	99%	105:50:16
2	news.nask.pl	2	12816	6548	3344	2924	51%	23:54:38
3	news.icm.edu.pl	43	289	71	208	10	24%	17:18:41
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		303	1107256	1094190	9160	3906	98%	147:03:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	292.5 GB	6.4 MB	7.0 MB	292.5 GB	99%	281.8 KB
2	news.nask.pl	29.6 MB	5.2 MB	16.5 MB	51.4 MB	57%	5.6 KB
3	news.icm.edu.pl	1.3 MB	9.8 KB	236.3 KB	1.5 MB	84%	19.1 KB
4	localhost	8.7 KB	0.0 KB	0.0 KB	8.7 KB	100%	1.7 KB
TOTAL: 4		292.5 GB	11.6 MB	23.7 MB	292.6 GB	99%	279.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 24 04:15:02 - 04:59:59	65888	6.0%	24.42	17.2 GB	5.9%	6674.88
Jan 24 05:00:00 - 05:59:59	66534	6.1%	18.48	17.1 GB	5.9%	4990.36
Jan 24 06:00:00 - 06:59:59	99400	9.1%	27.61	18.7 GB	6.4%	5439.60
Jan 24 07:00:00 - 07:59:59	89452	8.2%	24.85	19.7 GB	6.8%	5751.86
Jan 24 08:00:00 - 08:59:59	92847	8.5%	25.79	22.3 GB	7.6%	6481.57
Jan 24 09:00:00 - 09:59:59	75086	6.9%	20.86	21.8 GB	7.5%	6353.66
Jan 24 10:00:00 - 10:59:59	67136	6.1%	18.65	21.1 GB	7.2%	6135.80
Jan 24 11:00:00 - 11:59:59	80695	7.4%	22.42	23.5 GB	8.0%	6843.02
Jan 24 12:00:00 - 12:59:59	75061	6.9%	20.85	21.7 GB	7.4%	6307.41
Jan 24 13:00:00 - 13:59:59	83286	7.6%	23.14	22.7 GB	7.8%	6600.18
Jan 24 14:00:00 - 14:59:59	89752	8.2%	24.93	27.6 GB	9.4%	8025.10
Jan 24 15:00:00 - 15:59:59	88098	8.0%	24.47	23.7 GB	8.1%	6901.30
Jan 24 16:00:00 - 16:59:59	56430	5.2%	15.68	18.2 GB	6.2%	5313.80
Jan 24 17:00:00 - 17:59:59	633	0.1%	0.18	3.8 MB	0.0%	1.07
Jan 24 18:00:00 - 18:59:59	593	0.1%	0.16	3.7 MB	0.0%	1.04
Jan 24 19:00:00 - 19:59:59	505	0.0%	0.14	1.5 MB	0.0%	0.43
Jan 24 20:00:00 - 20:59:59	606	0.1%	0.17	2.3 MB	0.0%	0.65
Jan 24 21:00:00 - 21:59:59	667	0.1%	0.19	2.8 MB	0.0%	0.81
Jan 24 22:00:00 - 22:59:59	639	0.1%	0.18	2.4 MB	0.0%	0.69
Jan 24 23:00:00 - 23:59:59	586	0.1%	0.16	2.3 MB	0.0%	0.66
Jan 25 00:00:00 - 00:59:59	559	0.1%	0.16	2.8 MB	0.0%	0.81
Jan 25 01:00:00 - 01:59:59	420	0.0%	0.12	1.9 MB	0.0%	0.54
Jan 25 02:00:00 - 02:59:59	9106	0.8%	2.53	1.5 GB	0.5%	431.16
Jan 25 03:00:00 - 03:59:59	35617	3.3%	9.89	10.9 GB	3.7%	3161.18
Jan 25 04:00:00 - 04:15:02	14790	1.4%	16.40	4.7 GB	1.6%	5445.45
TOTAL: 24:00:00	1094386	100.0%	12.67	292.2 GB	100.0%	3546.23

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3080	17	1256	3	1804
2	newsfeed.pionier.net.pl	1266	169	779	6	312
3	news.icm.edu.pl	18	0	1	0	17
TOTAL: 3		4364	186	2036	9	2133

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	97
it.comp.os.win.windows10	35
comp.sys.raspberry-pi	19
it.tlc.cellulari.android	16
comp.mobile.android	9
it.comp.os.win.windows7	6
uk.rec.cycling.moderated	2
it.discussioni.energia	2
TOTAL: 8	186

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

Groups	Count
pl.praca.oferowana	8
bit.listserv.catholic	1
TOTAL: 2	9

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1562
EMP (phl)	47
Scoring filter (8)	27
Binary in non-binary group	21
Scoring filter (10)	6
Scoring filter (9)	4
Scoring filter (20)	3
Scoring filter (21)	1
Scoring filter (23)	1
Too many newsgroups	1
TOTAL: 10	1673

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	294
	news.nask.pl	158
	news.icm.edu.pl	8
	TOTAL: 3	460
TOTAL: 1		460

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40532	2270	11855	312	5	37529	5%	23:59:58
2	icm	21646	60	16655	4	0	1	0%	23:59:58
3	poznan	7051	21	6569	31	0	5549	0%	23:54:18
4	nask	5237	2	4448	0	0	1	0%	17:18:50
5	okapi	0	0	0	0	0	1229	0%	23:57:51
TOTAL: 5		74466	2353	39527	347	5	44309	3%	113:10:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.3 MB	1.3 MB	9.6 MB	0.1 KB/s	3.8 KB	23:59:58
2	icm	523.9 KB	19.9 KB	543.9 KB	0.0 KB/s	8.5 KB	23:59:58
3	poznan	125.1 KB	101.9 KB	227.0 KB	0.0 KB/s	4.4 KB	23:54:18
4	nask	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	2.2 KB	17:18:50
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		8.9 MB	1.4 MB	10.4 MB	0.0 KB/s	3.9 KB	113:10:55

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	web.man.lodz.pl	288	34	52.8 KB	16	0	00:00:27
2	az1-ss13.a2hosting.com	2	1	1.1 KB	1	0	00:00:12
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 3		295	35	53.9 KB	17	5	00:31:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	581	34	52.8 KB	16	5	00:00:31
2	*.a2hosting.com	2	1	1.1 KB	1	0	00:00:12
3	*.dynamic.chello.pl	4	0	0.0 KB	0	0	00:30:10
4	*.prz.edu.pl	3	0	0.0 KB	0	0	00:00:11
5	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
6	unresolved	96	0	0.0 KB	0	0	00:00:01
TOTAL: 6		687	35	53.9 KB	17	5	00:31:08

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89-71-178-217.dynamic.chello.pl	0.048	0.036	00:30:10
web.man.lodz.pl	1.632	4.620	00:00:27
az1-ss13.a2hosting.com	0.020	0.024	00:00:12
pawel.prz.edu.pl	0.020	0.040	00:00:11
mon.man.lodz.pl	1.108	3.088	00:00:03
212.51.192.185	0.400	0.996	00:00:01
scan-141.security.ipip.net	0.008	0.004	00:00:00
isvcs01.man.lodz.pl	0.032	0.084	00:00:00
TOTAL: 8	3.268	8.892	00:31:08

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
89-71-178-217.dynamic.chello.pl	4
pawel.prz.edu.pl	3
scan-141.security.ipip.net	1
TOTAL: 5	392

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Host: 212.191.2.21:119	1
GET / HTTP/1.1	1
Connection: close	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
89-71-178-217.dynamic.chello.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	34	97.1%
2	lodz	1	2.9%
TOTAL: 2		35	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	24
2	pl.comp.os.ms-windows.winnt	10
3	lodz.ogloszenia	1
TOTAL: 3		35

Daily Usenet report

Jan 24 04:15:02 -- Jan 25 04:15:02