Daily Usenet report: Mar 4 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Mar 4 13:17:55 newsfeed nnrpd[781]: SERVER perl filter not defined
Mar 4 16:10:54 newsfeed nnrpd[6991]: SERVER perl filter not defined
Mar 4 16:11:40 newsfeed nnrpd[6992]: SERVER perl filter not defined
Mar 4 16:13:36 newsfeed nnrpd[7012]: SERVER perl filter not defined
Mar 4 22:02:15 newsfeed nnrpd[18807]: SERVER perl filter not defined
Mar 4 22:51:41 newsfeed nnrpd[20517]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	742153	93.0%	95.8 MB	93.4%
innfeed	44082	5.5%	5.2 MB	5.0%
nnrpd	5955	0.7%	638.9 KB	0.6%
innd	5865	0.7%	1.0 MB	1.0%
overchan	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	798057	100.0%	102.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	738054	98.8%
Negative hits	8399	1.1%
Positive hits	222	0.0%
Cache misses	71	0.0%
TOTAL: 4	746746	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.844	0.0%	123	0.000	14.992	51.000
article cleanup	00:00:08.894	0.0%	740227	0.005	0.012	0.020
article logging	00:00:31.840	0.0%	740722	0.034	0.043	0.069
article parse	00:12:36.945	0.9%	25158865	0.017	0.030	0.040
article write	00:05:06.363	0.4%	740034	0.146	0.414	0.929
artlog/artcncl	00:00:00.004	0.0%	123	0.000	0.033	1.000
data move	00:00:28.883	0.0%	29552552	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.509	0.0%	120	0.000	4.242	25.500
hishave/artcncl	00:00:00.426	0.0%	123	0.000	3.463	32.750
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:22:13.106	1.5%	748788	0.083	1.780	11.060
history sync	00:00:00.031	0.0%	314	0.000	0.099	1.000
history write	00:12:43.578	0.9%	740194	0.284	1.032	4.255
hiswrite/artcncl	00:00:00.005	0.0%	3	0.000	1.667	4.000
idle	21:16:15.189	88.6%	24346177	1.375	3.145	155.002
nntp read	00:20:45.157	1.4%	24809027	0.035	0.050	0.059
overview write	00:00:03.758	0.0%	740034	0.000	0.005	0.013
perl filter	01:19:48.914	5.5%	740194	3.043	6.470	11.139
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:38.569	0.0%	1495056	0.020	0.026	0.043
TOTAL: 24:00:18.126	23:51:24.015	99.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.122	0.0%	51517	0.000	0.002	0.016
article prepare	00:00:00.001	0.0%	5664	0.000	0.000	0.019
article read	00:00:09.731	0.0%	1415	0.000	6.877	55.846
backlog stats	00:00:00.094	0.0%	126670	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.161	0.0%	62153	0.004	0.019	0.040
data write	00:00:03.125	0.0%	47254	0.026	0.066	0.120
idle	23:52:44.773	100.0%	126670	463.553	678.651	1231.131
status file	00:00:17.973	0.0%	16768	0.642	1.072	14.229
TOTAL: 23:53:23.011	23:53:16.980	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:16:27.841	99.9%	3434	0.000	3432.685	300089.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.292	0.0%	7608	0.000	0.038	0.842
readart	00:00:00.046	0.0%	36	0.000	1.278	6.286
TOTAL: 03:16:40.956	03:16:28.179	99.9%	-	-	-	-

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	303	741544	741361	0	183	99%	110:10:11
2	news.icm.edu.pl	52	175	27	138	10	15%	15:14:25
3	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 3		362	741725	741394	138	193	99%	125:24:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	194.8 GB	718.4 KB	1.1 MB	194.8 GB	99%	275.5 KB
2	news.icm.edu.pl	692.7 KB	129.0 KB	165.9 KB	987.6 KB	70%	26.7 KB
3	localhost	8.5 KB	0.0 KB	0.0 KB	8.5 KB	100%	1.4 KB
TOTAL: 3		194.8 GB	847.4 KB	1.2 MB	194.8 GB	99%	275.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 4 04:15:01 - 04:59:59	13686	1.8%	5.07	5.0 GB	2.6%	1958.71
Mar 4 05:00:00 - 05:59:59	29735	4.0%	8.26	7.7 GB	3.9%	2232.32
Mar 4 06:00:00 - 06:59:59	17931	2.4%	4.98	6.2 GB	3.2%	1814.44
Mar 4 07:00:00 - 07:59:59	45218	6.1%	12.56	6.3 GB	3.2%	1825.56
Mar 4 08:00:00 - 08:59:59	30290	4.1%	8.41	10.0 GB	5.1%	2899.51
Mar 4 09:00:00 - 09:59:59	48603	6.6%	13.50	11.2 GB	5.8%	3275.83
Mar 4 10:00:00 - 10:59:59	45452	6.1%	12.63	8.3 GB	4.3%	2431.70
Mar 4 11:00:00 - 11:59:59	50896	6.9%	14.14	11.5 GB	5.9%	3350.70
Mar 4 12:00:00 - 12:59:59	34518	4.7%	9.59	7.3 GB	3.7%	2134.22
Mar 4 13:00:00 - 13:59:59	25594	3.5%	7.11	9.0 GB	4.6%	2623.21
Mar 4 14:00:00 - 14:59:59	23629	3.2%	6.56	4.6 GB	2.3%	1328.42
Mar 4 15:00:00 - 15:59:59	17259	2.3%	4.79	5.3 GB	2.7%	1556.69
Mar 4 16:00:00 - 16:59:59	13930	1.9%	3.87	6.2 GB	3.2%	1807.13
Mar 4 17:00:00 - 17:59:59	23638	3.2%	6.57	13.0 GB	6.6%	3779.71
Mar 4 18:00:00 - 18:59:59	26488	3.6%	7.36	5.2 GB	2.7%	1526.51
Mar 4 19:00:00 - 19:59:59	19111	2.6%	5.31	8.1 GB	4.2%	2366.25
Mar 4 20:00:00 - 20:59:59	54727	7.4%	15.20	13.5 GB	6.9%	3935.42
Mar 4 21:00:00 - 21:59:59	33657	4.5%	9.35	7.2 GB	3.7%	2088.75
Mar 4 22:00:00 - 22:59:59	23765	3.2%	6.60	7.8 GB	4.0%	2276.57
Mar 4 23:00:00 - 23:59:59	26210	3.5%	7.28	9.1 GB	4.7%	2659.25
Mar 5 00:00:00 - 00:59:59	40884	5.5%	11.36	9.4 GB	4.8%	2741.04
Mar 5 01:00:00 - 01:59:59	25694	3.5%	7.14	4.5 GB	2.3%	1321.19
Mar 5 02:00:00 - 02:59:59	40636	5.5%	11.29	8.0 GB	4.1%	2338.06
Mar 5 03:00:00 - 03:59:59	23524	3.2%	6.53	10.4 GB	5.3%	3028.16
Mar 5 04:00:00 - 04:15:02	6265	0.8%	6.95	396.0 MB	0.2%	449.56
TOTAL: 24:00:01	741340	100.0%	8.58	195.4 GB	100.0%	2371.71

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	680	149	27	6	498
2	news.icm.edu.pl	10	0	6	0	4
TOTAL: 2		690	149	33	6	502

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	68
it.tlc.cellulari.android	30
it.comp.os.win.windows10	21
comp.mobile.android	16
comp.sys.raspberry-pi	9
uk.rec.cycling.moderated	3
de.comm.software.mailreader	1
it.comp.virtualizzazione	1
TOTAL: 8	149

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

Groups	Count
pl.praca.oferowana	5
pl.sci.matematyka	1
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3
Scoring filter (9)	1
TOTAL: 2	4

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	23962	406	14300	23	0	0	1%	23:59:58
2	panorama	41544	373	13289	136	6	37901	0%	23:59:58
3	nask	0	0	0	0	0	9597	0%	23:57:51
4	okapi	0	0	0	0	0	949	0%	23:57:51
5	poznan	37	0	25	0	0	0	0%	03:55:25
TOTAL: 5		65543	779	27614	159	6	48447	1%	99:51:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	1.1 MB	765.3 KB	1.9 MB	4.5 KB	23:59:58
2	panorama	995.5 KB	468.1 KB	1.4 MB	2.9 KB	23:59:58
3	nask	0.0 KB	0.0 KB	0.0 KB	0	23:57:51
4	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:57:51
5	poznan	0.0 KB	0.0 KB	0.0 KB	0	03:55:25
TOTAL: 5		2.1 MB	1.2 MB	3.3 MB	3.6 KB	99:51:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	28	28.1 KB	13	0	00:00:16
2	user-5-173-201-97.play-internet.pl	4	7	10.8 KB	1	0	02:00:15
3	user-5-173-186-84.play-internet.pl	4	1	1.4 KB	1	0	01:09:16
4	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 4		302	36	40.3 KB	15	6	03:16:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	582	28	28.1 KB	13	6	00:00:20
2	*.play-internet.pl	10	8	12.2 KB	2	0	03:16:04
3	*.sfj.corp.censys.io	1	0	0.0 KB	0	0	00:00:00
4	unresolved	291	0	0.0 KB	0	0	00:00:15
5	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
TOTAL: 5		885	36	40.3 KB	15	6	03:16:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user-5-173-201-97.play-internet.pl	0.024	0.036	02:00:15
user-5-173-186-84.play-internet.pl	0.012	0.052	01:09:16
user-94-254-140-67.play-internet.pl	0.004	0.020	00:06:32
web.man.lodz.pl	1.516	2.876	00:00:16
92.116.28.215	0.996	1.608	00:00:09
80.111.142.213	0.044	0.036	00:00:04
mon.man.lodz.pl	1.328	2.608	00:00:02
212.51.192.185	0.404	0.840	00:00:00
worker-18.sfj.corp.censys.io	0.008	0.008	00:00:00
isvcs01.man.lodz.pl	0.040	0.060	00:00:00
ec2-54-202-234-133.us-west-2.compute.amazonaws.com	0.000	0.020	00:00:00
45.136.108.65	0.000	0.008	00:00:00
TOTAL: 12	4.376	8.172	03:16:40

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
92.116.28.215	190
212.51.192.185	96
80.111.142.213	4
user-94-254-140-67.play-internet.pl	2
worker-18.sfj.corp.censys.io	1
45.136.108.65	1
ec2-54-202-234-133.us-west-2.compute.amazonaws.com	1
TOTAL: 8	583

NNRP unrecognized commands (by host):

System	Conn
45.136.108.65	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
user-5-173-201-97.play-internet.pl	4	0
user-5-173-186-84.play-internet.pl	2	0
45.136.108.65	1	1
worker-18.sfj.corp.censys.io	1	1
TOTAL: 4	8	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	28	77.8%
2	lodman	8	22.2%
TOTAL: 2		36	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	14
2	pl.comp.os.ms-windows.winnt	14
3	lodman.info	8
TOTAL: 3		36

Daily Usenet report

Mar 4 04:15:01 -- Mar 5 04:15:02