Daily Usenet report: Mar 19 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Mar 19 11:33:15 newsfeed nnrpd[1477]: SERVER perl filter not defined
Mar 19 12:42:29 newsfeed nnrpd[4342]: SERVER perl filter not defined
Mar 19 19:26:56 newsfeed overchan[18147]: input line too long, skipping
Mar 19 19:57:41 newsfeed overchan[18147]: input line too long, skipping
Mar 19 19:58:55 newsfeed overchan[18147]: input line too long, skipping
Mar 19 20:34:24 newsfeed nnrpd[20526]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	570370	93.4%	67.4 MB	93.2%
innfeed	30548	5.0%	3.6 MB	5.0%
nnrpd	5138	0.8%	552.4 KB	0.7%
innd	4875	0.8%	815.7 KB	1.1%
overchan	4	0.0%	0.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	610936	100.0%	72.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	569244	98.9%
Negative hits	5877	1.0%
Positive hits	336	0.1%
Cache misses	39	0.0%
TOTAL: 4	575496	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.305	0.0%	130	0.000	10.038	101.000
article cleanup	00:00:06.429	0.0%	569652	0.004	0.011	0.026
article logging	00:00:23.741	0.0%	570336	0.027	0.042	0.069
article parse	00:05:28.397	0.4%	16254011	0.015	0.020	0.025
article write	00:02:41.215	0.2%	569410	0.142	0.283	0.739
artlog/artcncl	00:00:00.004	0.0%	130	0.000	0.031	1.000
data move	00:00:15.853	0.0%	19327817	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.461	0.0%	125	0.000	3.688	41.000
hishave/artcncl	00:00:00.086	0.0%	130	0.000	0.662	14.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:27.150	1.9%	575751	0.645	2.861	15.483
history sync	00:00:00.027	0.0%	339	0.000	0.080	0.500
history write	00:14:16.806	1.0%	569620	0.515	1.504	6.348
hiswrite/artcncl	00:00:00.001	0.0%	5	0.000	0.200	1.000
idle	22:04:59.987	92.0%	16038336	0.896	4.957	110.701
nntp read	00:10:36.116	0.7%	16076342	0.029	0.040	0.051
overview write	00:00:02.739	0.0%	569410	0.001	0.005	0.017
perl filter	00:48:54.743	3.4%	569620	2.822	5.152	9.102
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:30.023	0.0%	1156385	0.018	0.026	0.034
TOTAL: 24:00:39.888	23:55:45.083	99.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.138	0.0%	48937	0.000	0.003	0.021
article prepare	00:00:00.002	0.0%	1880	0.000	0.001	0.083
article read	00:00:00.314	0.0%	470	0.000	0.668	23.500
backlog stats	00:00:00.095	0.0%	124283	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.157	0.0%	62937	0.000	0.018	0.037
data write	00:00:03.390	0.0%	45379	0.029	0.075	0.116
idle	23:55:08.656	100.0%	124283	449.987	692.843	2467.914
status file	00:00:08.761	0.0%	10749	0.000	0.815	1.727
TOTAL: 23:55:27.711	23:55:22.513	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:11:23.185	99.8%	3263	0.000	3519.211	300105.167
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.246	0.0%	7254	0.000	0.034	0.500
readart	00:00:00.026	0.0%	53	0.000	0.491	2.667
TOTAL: 03:11:41.265	03:11:23.457	99.8%	-	-	-	-

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	236	569477	569264	1	212	99%	94:48:53
2	news.icm.edu.pl	48	312	66	216	30	21%	18:14:15
3	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 3		288	569792	569333	217	242	99%	113:03:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	82.6 GB	700.7 KB	1.1 MB	82.6 GB	99%	152.0 KB
2	news.icm.edu.pl	1.7 MB	45.5 KB	555.7 KB	2.3 MB	74%	24.8 KB
3	localhost	4.1 KB	0.0 KB	0.0 KB	4.1 KB	100%	1.4 KB
TOTAL: 3		82.6 GB	746.3 KB	1.7 MB	82.6 GB	99%	152.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 19 04:15:02 - 04:59:59	14365	2.5%	5.32	908.6 MB	1.1%	344.84
Mar 19 05:00:00 - 05:59:59	18558	3.3%	5.16	1.1 GB	1.3%	321.02
Mar 19 06:00:00 - 06:59:59	21076	3.7%	5.85	1.3 GB	1.6%	375.90
Mar 19 07:00:00 - 07:59:59	11931	2.1%	3.31	706.6 MB	0.8%	200.98
Mar 19 08:00:00 - 08:59:59	5594	1.0%	1.55	295.9 MB	0.3%	84.16
Mar 19 09:00:00 - 09:59:59	12907	2.3%	3.59	750.0 MB	0.9%	213.34
Mar 19 10:00:00 - 10:59:59	13437	2.4%	3.73	770.5 MB	0.9%	219.17
Mar 19 11:00:00 - 11:59:59	21357	3.8%	5.93	1.2 GB	1.4%	347.11
Mar 19 12:00:00 - 12:59:59	34473	6.1%	9.58	2.0 GB	2.4%	579.56
Mar 19 13:00:00 - 13:59:59	6132	1.1%	1.70	283.9 MB	0.3%	80.76
Mar 19 14:00:00 - 14:59:59	5213	0.9%	1.45	203.9 MB	0.2%	57.99
Mar 19 15:00:00 - 15:59:59	6437	1.1%	1.79	243.7 MB	0.3%	69.31
Mar 19 16:00:00 - 16:59:59	20418	3.6%	5.67	1.1 GB	1.4%	332.79
Mar 19 17:00:00 - 17:59:59	8668	1.5%	2.41	378.6 MB	0.4%	107.69
Mar 19 18:00:00 - 18:59:59	37445	6.6%	10.40	5.6 GB	6.7%	1623.09
Mar 19 19:00:00 - 19:59:59	18111	3.2%	5.03	4.1 GB	5.0%	1192.79
Mar 19 20:00:00 - 20:59:59	17748	3.1%	4.93	6.6 GB	7.9%	1909.29
Mar 19 21:00:00 - 21:59:59	33684	5.9%	9.36	5.2 GB	6.3%	1514.67
Mar 19 22:00:00 - 22:59:59	6869	1.2%	1.91	260.2 MB	0.3%	74.00
Mar 19 23:00:00 - 23:59:59	56713	10.0%	15.75	11.5 GB	13.9%	3350.43
Mar 20 00:00:00 - 00:59:59	42186	7.4%	11.72	10.9 GB	13.2%	3186.39
Mar 20 01:00:00 - 01:59:59	37398	6.6%	10.39	5.9 GB	7.1%	1711.20
Mar 20 02:00:00 - 02:59:59	47561	8.4%	13.21	10.2 GB	12.3%	2959.01
Mar 20 03:00:00 - 03:59:59	60782	10.7%	16.88	10.7 GB	12.9%	3111.74
Mar 20 04:00:00 - 04:15:02	10251	1.8%	11.36	636.8 MB	0.8%	722.94
TOTAL: 24:00:00	569314	100.0%	6.59	82.6 GB	100.0%	1002.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.pionier.net.pl	896	180	29	687
2	news.icm.edu.pl	30	0	3	27
TOTAL: 2		926	180	32	714

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	64
comp.sys.raspberry-pi	37
it.comp.os.win.windows10	22
it.tlc.cellulari.android	22
comp.mobile.android	11
de.rec.sf.startrek	8
uk.rec.cycling.moderated	5
it.comp.os.win.windows7	5
de.comp.office-pakete.ms-office.word	3
de.comp.office-pakete.ms-office.outlook	2
comp.mobile.ipad	1
TOTAL: 11	180

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	21
Binary in non-binary group	6
Too many newsgroups	3
Scoring filter (8)	1
TOTAL: 4	31

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	683
	TOTAL: 1	683
TOTAL: 1		683

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	27038	343	17033	21	0	1%	23:59:40
2	panorama	33940	16	16173	43	32791	0%	23:59:57
3	poznan	86	1	64	0	0	1%	05:49:00
4	nask	0	0	0	0	11075	0%	23:57:50
5	okapi	0	0	0	0	1104	0%	23:57:50
TOTAL: 5		61064	360	33270	64	44970	0%	101:44:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	916.2 KB	203.9 KB	1.1 MB	3.1 KB	23:59:40
2	panorama	548.0 KB	261.6 KB	809.6 KB	13.7 KB	23:59:57
3	poznan	10.7 KB	0.0 KB	10.7 KB	10.7 KB	05:49:00
4	nask	0.0 KB	0.0 KB	0.0 KB	0	23:57:50
5	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:57:50
TOTAL: 5		1.4 MB	465.5 KB	1.9 MB	4.6 KB	101:44:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	36	39.5 KB	18	0	00:00:17
2	user-5-173-187-117.play-internet.pl	6	10	14.4 KB	2	0	03:00:36
3	ip-79-111-18-66.bb.netbynet.ru	9	2	2.8 KB	2	0	00:01:16
4	user-94-254-136-35.play-internet.pl	2	1	1.4 KB	1	0	00:08:40
5	0890788407.static.corbina.ru	5	1	1.3 KB	1	0	00:00:35
6	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 6		313	50	59.4 KB	24	3	03:11:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	579	36	39.5 KB	18	3	00:00:20
2	*.play-internet.pl	8	11	15.8 KB	3	0	03:09:17
3	*.bb.netbynet.ru	9	2	2.8 KB	2	0	00:01:16
4	*.static.corbina.ru	5	1	1.3 KB	1	0	00:00:35
5	*.sfj.corp.censys.io	1	0	0.0 KB	0	0	00:00:00
6	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
7	unresolved	101	0	0.0 KB	0	0	00:00:10
TOTAL: 7		704	50	59.4 KB	24	3	03:11:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user-5-173-187-117.play-internet.pl	0.020	0.056	03:00:36
user-94-254-136-35.play-internet.pl	0.008	0.012	00:08:40
ip-79-111-18-66.bb.netbynet.ru	0.060	0.100	00:01:16
0890788407.static.corbina.ru	0.056	0.056	00:00:35
web.man.lodz.pl	1.460	2.192	00:00:17
84.70.24.22	0.020	0.012	00:00:05
80.111.142.213	0.020	0.032	00:00:03
mon.man.lodz.pl	1.296	1.824	00:00:02
212.51.192.185	0.428	0.592	00:00:00
worker-18.sfj.corp.censys.io	0.008	0.004	00:00:00
scan-141.security.ipip.net	0.000	0.016	00:00:00
isvcs01.man.lodz.pl	0.020	0.032	00:00:00
TOTAL: 12	3.396	4.928	03:11:40

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
scan-141.security.ipip.net	1
84.70.24.22	1
worker-18.sfj.corp.censys.io	1
TOTAL: 6	391

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
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
user-5-173-187-117.play-internet.pl	6	0
scan-141.security.ipip.net	1	1
worker-18.sfj.corp.censys.io	1	1
TOTAL: 3	8	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	36	72.0%
2	lodman	14	28.0%
TOTAL: 2		50	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	20
2	pl.comp.pecet	16
3	lodman.info	14
TOTAL: 3		50

Daily Usenet report

Mar 19 04:15:02 -- Mar 20 04:15:02