Daily Usenet report: Jan 3 04:15:01

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Jan 3 14:21:31 newsfeed nnrpd[2020671]: SERVER perl filter not defined
Jan 3 14:22:21 newsfeed nnrpd[2020680]: SERVER perl filter not defined
Jan 3 14:25:23 newsfeed nnrpd[2020967]: SERVER perl filter not defined
Jan 3 14:51:54 newsfeed overchan[1991137]: input line too long, skipping
Jan 3 14:53:01 newsfeed overchan[1991137]: input line too long, skipping
Jan 3 15:48:12 newsfeed overchan[1991137]: input line too long, skipping
Jan 3 15:49:14 newsfeed overchan[1991137]: input line too long, skipping
Jan 3 19:46:32 newsfeed overchan[1991137]: input line too long, skipping
Jan 3 20:15:01 newsfeed overchan[1991137]: input line too long, skipping
Jan 3 22:57:27 newsfeed overchan[1991137]: input line too long, skipping
Jan 4 00:41:11 newsfeed overchan[1991137]: input line too long, skipping
Jan 4 02:42:16 newsfeed overchan[1991137]: input line too long, skipping
Jan 4 04:04:09 newsfeed overchan[1991137]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	771308	95.4%	84.6 MB	94.8%
innfeed	23927	3.0%	2.9 MB	3.2%
innd	9633	1.2%	1.5 MB	1.7%
nnrpd	3388	0.4%	323.1 KB	0.4%
controlchan	11	0.0%	2.5 KB	0.0%
overchan	11	0.0%	2.0 KB	0.0%
TOTAL: 6	808278	100.0%	89.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	766391	99.6%
Negative hits	2918	0.4%
Positive hits	190	0.0%
Cache misses	20	0.0%
TOTAL: 4	769519	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.696	0.0%	44	0.000	15.818	75.000
article cleanup	00:00:02.950	0.0%	769163	0.002	0.004	0.008
article logging	00:00:12.049	0.0%	769551	0.011	0.016	0.025
article parse	00:05:44.438	0.4%	162513933	0.002	0.002	0.003
article write	00:03:53.125	0.3%	768948	0.147	0.303	0.456
artlog/artcncl	00:00:00.000	0.0%	44	0.000	0.000	0.000
data move	00:00:23.866	0.0%	168444546	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.157	0.0%	43	0.000	3.651	33.000
hishave/artcncl	00:00:00.011	0.0%	44	0.000	0.250	5.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:06:16.200	0.4%	772627	0.095	0.487	8.701
history sync	00:00:00.011	0.0%	290	0.000	0.038	0.500
history write	00:24:10.619	1.7%	769153	0.187	1.886	17.235
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	22:31:37.743	93.9%	162018310	0.326	0.501	0.755
nntp read	00:15:05.843	1.0%	162061642	0.005	0.006	0.006
overview write	00:00:01.500	0.0%	768948	0.000	0.002	0.004
perl filter	00:23:16.327	1.6%	769153	1.284	1.815	2.342
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:13.103	0.0%	1547492	0.006	0.008	0.012
TOTAL: 24:00:04.624	23:50:58.638	99.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	30771	0.000	0.001	0.014
article prepare	00:00:00.002	0.0%	12140	0.000	0.000	0.009
article read	00:00:08.925	0.0%	3065	0.000	2.912	15.111
backlog stats	00:00:00.049	0.0%	83559	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	35	0.000	0.000	0.000
data read	00:00:00.252	0.0%	39909	0.000	0.006	0.022
data write	00:00:00.557	0.0%	30788	0.000	0.018	0.067
idle	23:55:53.327	100.0%	83559	633.066	1031.048	2335.898
status file	00:00:02.884	0.0%	7854	0.194	0.367	1.592
TOTAL: 23:56:08.309	23:56:06.030	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:31.746	83.8%	2780	0.000	11.419	10011.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.054	0.1%	5947	0.000	0.009	0.158
readart	00:00:00.000	0.0%	48	0.000	0.000	0.000
TOTAL: 00:00:37.902	00:00:31.800	83.9%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
bofh-control@killfile.org	0	0	1	0	0	1
TOTAL	0	0	1	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	138	770548	770423	0	125	99%	74:12:00
2	news.icm.edu.pl	45	455	210	156	89	46%	15:45:03
3	localhost	1444	3	3	0	0	100%	00:00:00
TOTAL: 3		1627	771006	770636	156	214	99%	89:57:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	320.0 GB	1.8 MB	2.2 MB	320.0 GB	99%	435.4 KB
2	news.icm.edu.pl	6.5 MB	0.0 KB	1.4 MB	7.9 MB	82%	27.0 KB
3	localhost	4.0 KB	0.0 KB	0.0 KB	4.0 KB	100%	1.3 KB
TOTAL: 3		320.0 GB	1.8 MB	3.6 MB	320.0 GB	99%	435.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 3 04:15:01 - 04:59:59	17224	2.2%	6.38	8.2 GB	2.6%	3190.41
Jan 3 05:00:00 - 05:59:59	21813	2.8%	6.06	11.2 GB	3.5%	3268.94
Jan 3 06:00:00 - 06:59:59	25134	3.3%	6.98	12.6 GB	3.9%	3678.02
Jan 3 07:00:00 - 07:59:59	23562	3.1%	6.54	11.8 GB	3.7%	3438.56
Jan 3 08:00:00 - 08:59:59	31481	4.1%	8.74	13.1 GB	4.1%	3829.32
Jan 3 09:00:00 - 09:59:59	27066	3.5%	7.52	11.8 GB	3.7%	3424.67
Jan 3 10:00:00 - 10:59:59	22384	2.9%	6.22	11.7 GB	3.7%	3421.69
Jan 3 11:00:00 - 11:59:59	24346	3.2%	6.76	11.9 GB	3.7%	3471.09
Jan 3 12:00:00 - 12:59:59	25742	3.3%	7.15	12.2 GB	3.8%	3559.20
Jan 3 13:00:00 - 13:59:59	23307	3.0%	6.47	11.9 GB	3.7%	3458.90
Jan 3 14:00:00 - 14:59:59	22966	3.0%	6.38	11.5 GB	3.6%	3361.31
Jan 3 15:00:00 - 15:59:59	29925	3.9%	8.31	13.3 GB	4.1%	3867.97
Jan 3 16:00:00 - 16:59:59	54352	7.1%	15.10	13.6 GB	4.3%	3972.63
Jan 3 17:00:00 - 17:59:59	31978	4.1%	8.88	12.7 GB	4.0%	3708.67
Jan 3 18:00:00 - 18:59:59	28507	3.7%	7.92	13.6 GB	4.3%	3968.73
Jan 3 19:00:00 - 19:59:59	37679	4.9%	10.47	14.0 GB	4.4%	4078.38
Jan 3 20:00:00 - 20:59:59	52704	6.8%	14.64	13.6 GB	4.3%	3972.85
Jan 3 21:00:00 - 21:59:59	62660	8.1%	17.41	13.3 GB	4.2%	3874.92
Jan 3 22:00:00 - 22:59:59	28110	3.6%	7.81	12.8 GB	4.0%	3720.26
Jan 3 23:00:00 - 23:59:59	29951	3.9%	8.32	13.9 GB	4.4%	4061.24
Jan 4 00:00:00 - 00:59:59	34498	4.5%	9.58	15.9 GB	5.0%	4643.77
Jan 4 01:00:00 - 01:59:59	34392	4.5%	9.55	17.0 GB	5.3%	4956.30
Jan 4 02:00:00 - 02:59:59	37623	4.9%	10.45	17.8 GB	5.5%	5180.36
Jan 4 03:00:00 - 03:59:59	34932	4.5%	9.70	16.5 GB	5.2%	4813.38
Jan 4 04:00:00 - 04:15:02	8320	1.1%	9.22	4.2 GB	1.3%	4915.60
TOTAL: 24:00:01	770656	100.0%	8.92	320.5 GB	100.0%	3889.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	513	108	10	7	388
2	news.icm.edu.pl	95	0	0	0	95
TOTAL: 2		608	108	10	7	483

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	94
alt.comp.software.seamonkey	7
comp.sys.raspberry-pi	3
it.comp.os.win.windows10	3
comp.mobile.android	1
TOTAL: 5	108

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

Groups	Count
pl.praca.oferowana	7
TOTAL: 1	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	54
Scoring filter (8)	29
Binary in non-binary group	7
TOTAL: 3	90

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	388
	news.icm.edu.pl	5
	TOTAL: 2	393
TOTAL: 1		393

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	15449	2275	6668	161	0	1	14%	23:59:57
2	panorama	21730	163	8533	159	62	21921	0%	23:59:58
3	nask	0	0	0	0	0	7068	0%	23:57:48
4	poznan	229	0	206	2	0	0	0%	03:50:44
5	okapi	0	0	0	0	0	880	0%	23:57:48
TOTAL: 5		37408	2438	15407	322	62	29870	6%	99:46:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	9.1 MB	1.2 MB	10.3 MB	0.1 KB/s	4.3 KB	23:59:57
2	panorama	897.2 KB	3.1 MB	3.9 MB	0.0 KB/s	12.5 KB	23:59:58
3	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
4	poznan	0.0 KB	54.6 KB	54.6 KB	0.0 KB/s	27.3 KB	03:50:44
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
TOTAL: 5		10.0 MB	4.3 MB	14.3 MB	0.0 KB/s	5.3 KB	99:46:15

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	294	48	45.7 KB	22	0	00:00:08
2	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 2		297	48	45.7 KB	22	3	00:00:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	297	48	45.7 KB	22	3	00:00:08
2	unresolved	104	0	0.0 KB	0	0	00:00:23
3	*.ch1.censys-scanner.com	1	0	0.0 KB	0	0	00:00:00
4	*.census.shodan.io	1	0	0.0 KB	0	0	00:00:04
TOTAL: 4		403	48	45.7 KB	22	3	00:00:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
104.152.52.238	0.000	0.007	00:00:10
192.241.216.8	0.000	0.008	00:00:09
web.man.lodz.pl	0.499	1.905	00:00:08
wine.census.shodan.io	0.004	0.004	00:00:04
80.111.142.213	0.008	0.023	00:00:02
212.51.192.185	0.173	0.505	00:00:01
scanner-26.ch1.censys-scanner.com	0.000	0.010	00:00:00
198.199.92.115	0.004	0.004	00:00:00
104.152.52.216	0.000	0.008	00:00:00
isvcs01.man.lodz.pl	0.009	0.018	00:00:00
TOTAL: 10	0.697	2.492	00:00:38

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
198.199.92.115	1
104.152.52.238	1
104.152.52.216	1
wine.census.shodan.io	1
scanner-26.ch1.censys-scanner.com	1
192.241.216.8	1
TOTAL: 8	106

NNRP unrecognized commands (by host):

System	Conn
198.199.92.115	1
scanner-26.ch1.censys-scanner.com	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	1
MGLNDD_212.191.2.21_119	1
TOTAL: 2	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	48	100.0%
TOTAL: 1		48	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	28
2	pl.comp.os.ms-windows.winnt	20
TOTAL: 2		48

Daily Usenet report

Jan 3 04:15:01 -- Jan 4 04:15:02