Daily Usenet report for news.niel.me: Aug 23 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 23 06:30:08 rpi-studio innd: filter: No Cancel-Key[sha1:LscjhZ/8xvyebKfrwfyLZv5OZ1E= sha256:Sz9bziJmAKqxed2IBK6ON2pbYPKHYxt2xZTNciugT/c=] matches Cancel-Lock[sha1:PSbys7RxG+81gwFc98FCdpiP1Qs= sha256:KFFFjh5NSrAIJ9MeOokfPClAbd8PlDJzHOlCbTtvcGY=] target=<116ds5q$r70$2@nnrp.usenet.blueworldhosting.com>
Aug 23 08:03:09 rpi-studio innd: filter: No Cancel-Key[sha1:yABSFMhzkW946ca2W8HdMjcUHjE= sha256:FjTOrkiZjMQ/EN7mreyTsn31womJhhSIwA6mxWlg01A=] matches Cancel-Lock[sha1:0eJEUtWx0s9DrxqaIVpeZ6XvhT4= sha256:Kwz2C9Fe1F4l/wcID5zwhgPtIal5gVoTTlQkhBfQRWc=] target=<aolsfaq-1-1786600981@therockgarden.ca>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	2372	28.0%	280.0 KB	21.8%
inn	2062	24.3%	367.0 KB	28.5%
innd	2042	24.1%	354.6 KB	27.6%
innfeed	2001	23.6%	283.9 KB	22.1%
controlchan	1	0.0%	0.1 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8479	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	7843	50.2%
Negative hits	5734	36.7%
Do not exist	2042	13.1%
Cache misses	6	0.0%
TOTAL: 4	15625	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	7	0.000	0.857	1.000
article cancel	00:00:00.002	0.0%	9	0.000	0.222	1.000
article cleanup	00:00:00.050	0.0%	2047	0.000	0.024	0.333
article logging	00:00:00.153	0.0%	2047	0.000	0.075	0.444
article parse	00:00:00.310	0.0%	5561	0.000	0.056	0.400
article write	00:00:00.875	0.0%	1802	0.000	0.486	1.429
artlog/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	7	0.000	0.000	0.000
data move	00:00:00.082	0.0%	17590	0.000	0.005	0.069
hisgrep/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	7	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.240	0.0%	15654	0.000	0.015	0.143
history sync	00:00:00.047	0.0%	342	0.000	0.137	1.000
history write	00:00:00.326	0.0%	2047	0.000	0.159	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:29.588	100.0%	19305	923.189	4477.057	22232.778
nntp read	00:00:00.492	0.0%	18536	0.000	0.027	0.077
overview write	00:00:00.634	0.0%	1802	0.000	0.352	7.000
perl filter	00:00:14.111	0.0%	2047	1.341	6.894	45.333
python filter	00:00:00.010	0.0%	2047	0.000	0.005	0.143
site send	00:00:00.678	0.0%	3653	0.000	0.186	0.500
TOTAL: 24:01:06.702	24:00:47.604	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.015	0.0%	1885	0.000	0.008	0.250
article prepare	00:00:00.000	0.0%	8	0.000	0.000	0.000
article read	00:00:00.007	0.0%	2	0.000	3.500	7.000
backlog stats	00:00:00.141	0.0%	22371	0.000	0.006	0.045
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.320	0.0%	10942	0.000	0.029	0.154
data write	00:00:00.506	0.0%	9246	0.000	0.055	0.250
idle	23:51:12.496	100.0%	22371	895.397	3838.563	9590.359
status file	00:00:12.502	0.0%	388	0.000	32.222	85.000
TOTAL: 23:51:30.892	23:51:25.987	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:50.917	40.4%	1513	10.800	33.653	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.140	0.1%	3555	0.000	0.039	1.000
TOTAL: 00:02:06.043	00:00:51.057	40.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.samoylyk.net	2	2644	691	1953	0	26%	33:59:50
2	feed-out.gegeweb.org	3	1821	576	1245	0	31%	23:56:35
3	news.nntp4.net	2	2035	426	1393	216	20%	23:38:29
4	news.glou.org	10	1200	131	1049	20	10%	23:39:26
5	nntp.terraraq.uk	10	1186	106	1074	6	8%	23:11:27
6	usenet.pasdenom.info	24	725	36	687	2	4%	21:57:25
7	gegeweb-out.news.tnetconsulting.net	4	1139	8	1130	1	0%	23:58:40
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		80	10750	1974	8531	245	18%	174:21:52

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	3.9 MB	0.0 KB	3.9 MB	100%	5.7 KB
2	news.nntp4.net	1.9 MB	2.6 MB	4.5 MB	41%	7.3 KB
3	feed-out.gegeweb.org	1.8 MB	0.0 KB	1.8 MB	100%	3.2 KB
4	news.glou.org	451.9 KB	57.5 KB	509.4 KB	88%	3.4 KB
5	nntp.terraraq.uk	268.7 KB	65.6 KB	334.2 KB	80%	3.0 KB
6	usenet.pasdenom.info	97.8 KB	2.4 KB	100.2 KB	97%	2.6 KB
7	gegeweb-out.news.tnetconsulting.net	45.5 KB	2.5 KB	48.0 KB	94%	5.3 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		8.4 MB	2.8 MB	11.2 MB	75%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 23 04:15:01 - 04:59:59	17	0.9%	0.01	64.3 KB	0.8%	0.02
Aug 23 05:00:00 - 05:59:59	36	2.0%	0.01	187.5 KB	2.4%	0.05
Aug 23 06:00:00 - 06:59:59	29	1.6%	0.01	120.7 KB	1.5%	0.03
Aug 23 07:00:00 - 07:59:59	41	2.3%	0.01	159.8 KB	2.0%	0.04
Aug 23 08:00:00 - 08:59:59	35	1.9%	0.01	133.6 KB	1.7%	0.04
Aug 23 09:00:00 - 09:59:59	77	4.3%	0.02	462.8 KB	5.9%	0.13
Aug 23 10:00:00 - 10:59:59	56	3.1%	0.02	172.1 KB	2.2%	0.05
Aug 23 11:00:00 - 11:59:59	76	4.2%	0.02	224.8 KB	2.9%	0.06
Aug 23 12:00:00 - 12:59:59	81	4.5%	0.02	358.8 KB	4.6%	0.10
Aug 23 13:00:00 - 13:59:59	64	3.6%	0.02	355.5 KB	4.5%	0.10
Aug 23 14:00:00 - 14:59:59	77	4.3%	0.02	282.1 KB	3.6%	0.08
Aug 23 15:00:00 - 15:59:59	109	6.1%	0.03	416.6 KB	5.3%	0.12
Aug 23 16:00:00 - 16:59:59	87	4.8%	0.02	351.7 KB	4.5%	0.10
Aug 23 17:00:00 - 17:59:59	82	4.6%	0.02	333.3 KB	4.3%	0.09
Aug 23 18:00:00 - 18:59:59	83	4.6%	0.02	423.5 KB	5.4%	0.12
Aug 23 19:00:00 - 19:59:59	72	4.0%	0.02	289.0 KB	3.7%	0.08
Aug 23 20:00:00 - 20:59:59	149	8.3%	0.04	937.9 KB	12.0%	0.26
Aug 23 21:00:00 - 21:59:59	120	6.7%	0.03	444.4 KB	5.7%	0.12
Aug 23 22:00:00 - 22:59:59	200	11.1%	0.06	757.8 KB	9.7%	0.21
Aug 23 23:00:00 - 23:59:59	89	4.9%	0.02	398.6 KB	5.1%	0.11
Aug 24 00:00:00 - 00:59:59	72	4.0%	0.02	252.1 KB	3.2%	0.07
Aug 24 01:00:00 - 01:59:59	81	4.5%	0.02	365.7 KB	4.7%	0.10
Aug 24 02:00:00 - 02:59:59	41	2.3%	0.01	215.8 KB	2.8%	0.06
Aug 24 03:00:00 - 03:59:59	21	1.2%	0.01	81.7 KB	1.0%	0.02
Aug 24 04:00:00 - 04:15:01	6	0.3%	0.01	28.5 KB	0.4%	0.03
TOTAL: 24:00:00	1801	100.0%	0.02	7.6 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	216	123	93
2	news.glou.org	20	0	20
3	nntp.terraraq.uk	6	0	6
4	usenet.pasdenom.info	2	0	2
5	gegeweb-out.news.tnetconsulting.net	1	0	1
TOTAL: 5		245	123	122

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	84
linux.kernel.git	39
TOTAL: 2	123

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	89
Bad path (news.giganews.com.POSTED)	25
EMP (md5)	4
Cancels abusifs	3
HTML Multipart	1
TOTAL: 5	122

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.samoylyk.net	1342	1	1194	0	0	0%	23:39:30
2	news.tnetconsulting.net	3038	0	1797	1	66	0%	23:57:16
3	news.gegeweb.org	1391	0	1240	0	22	0%	23:49:17
4	news.nntp4.net	1404	0	1361	0	0	0%	23:24:44
5	news.pasdenom.info	1243	0	1224	0	0	0%	23:41:44
6	nntp.terraraq.uk	884	0	856	0	0	0%	23:34:44
7	news.aioe.org	0	0	0	0	1478	0%	24:00:00
8	i2pn.org	0	0	0	0	1797	0%	24:00:00
TOTAL: 8		9302	1	7672	1	3363	0%	190:07:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.samoylyk.net	2.9 KB	0.0 KB	2.9 KB	2.9 KB	23:39:30
2	news.tnetconsulting.net	0.0 KB	4.7 KB	4.7 KB	4.7 KB	23:57:16
3	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:49:17
4	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:24:44
5	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:41:44
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:34:44
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
8	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		2.9 KB	4.7 KB	7.6 KB	3.8 KB	190:07:15

NNRP readership statistics:

System		Conn	Elapsed
1	r3-131-83.monitoring.internet-measurement.com	1	00:00:01
2	89-21-67-149.infrawat.ch	1	00:00:00
3	o024.scanner.modat.io	1	00:00:00
4	rueben.probe.onyphe.net	1	00:00:02
5	66-228-36-223.ip.linodeusercontent.com	85	00:00:16
6	lozano.probe.onyphe.net	1	00:00:02
7	o009.scanner.modat.io	1	00:00:00
8	hutchinson.probe.onyphe.net	1	00:00:00
9	n300.scanner.modat.io	1	00:00:00
10	202.210.203.35.bc.googleusercontent.com	1	00:00:01
11	mail.gegeweb.eu	288	00:00:34
12	205.210.31.222	1	00:00:03
13	80.94.92.22	1	00:00:16
14	174.149.216.162.bc.googleusercontent.com	2	00:00:31
15	45-133-173-47.infrawat.ch	1	00:00:00
16	66.61.98.95	6	00:00:06
17	74.155.45.199.censys-scanner.com	3	00:00:01
18	brian.probe.onyphe.net	1	00:00:01
19	2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	8	00:00:06
20	130.172.132.66.censys-scanner.com	1	00:00:00
21	o014.scanner.modat.io	1	00:00:00
22	azpdsgwc39l8.stretchoid.com	2	00:00:10
TOTAL: 22		409	00:02:21

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.probe.onyphe.net	4	00:00:07
2	*.210.203.35.bc.googleusercontent.com	1	00:00:01
3	*.infrawat.ch	2	00:00:00
4	*.scanner.modat.io	4	00:00:01
5	*.gegeweb.eu	288	00:00:34
6	*.stretchoid.com	2	00:00:10
7	unresolved	8	00:00:26
8	*.155.45.199.censys-scanner.com	3	00:00:01
9	*.172.132.66.censys-scanner.com	1	00:00:00
10	?	8	00:00:06
11	*.149.216.162.bc.googleusercontent.com	2	00:00:31
12	*.ip.linodeusercontent.com	85	00:00:16
13	*.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 13		409	00:02:21

NNRP auth users [Top 20]:

User	Conn
mailman	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	5.189	4.698	1.802	00:00:34
174.149.216.162.bc.googleusercontent.com	0.239	0.076	0.000	00:00:31
66-228-36-223.ip.linodeusercontent.com	3.072	1.034	0.000	00:00:16
80.94.92.22	0.027	0.022	0.000	00:00:16
azpdsgwc39l8.stretchoid.com	0.074	0.033	0.000	00:00:10
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	0.189	0.102	0.000	00:00:06
66.61.98.95	0.147	0.096	0.000	00:00:06
205.210.31.222	0.038	0.015	0.000	00:00:03
lozano.probe.onyphe.net	0.023	0.027	0.000	00:00:02
rueben.probe.onyphe.net	0.068	0.020	0.000	00:00:02
r3-131-83.monitoring.internet-measurement.com	0.024	0.028	0.000	00:00:01
74.155.45.199.censys-scanner.com	0.077	0.053	0.000	00:00:01
202.210.203.35.bc.googleusercontent.com	0.084	0.016	0.000	00:00:01
brian.probe.onyphe.net	0.021	0.030	0.000	00:00:01
130.172.132.66.censys-scanner.com	0.046	0.019	0.000	00:00:00
o024.scanner.modat.io	0.086	0.023	0.000	00:00:00
o014.scanner.modat.io	0.081	0.024	0.000	00:00:00
hutchinson.probe.onyphe.net	0.061	0.023	0.000	00:00:00
o009.scanner.modat.io	0.030	0.021	0.000	00:00:00
n300.scanner.modat.io	0.033	0.014	0.000	00:00:00
TOTAL: 22	9.656	6.432	1.802	00:02:21

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	2
TOTAL: 1	2

NNRP unrecognized commands (by host):

System	Conn
174.149.216.162.bc.googleusercontent.com	4
202.210.203.35.bc.googleusercontent.com	4
80.94.92.22	2
brian.probe.onyphe.net	2
o024.scanner.modat.io	1
74.155.45.199.censys-scanner.com	1
n300.scanner.modat.io	1
130.172.132.66.censys-scanner.com	1
o014.scanner.modat.io	1
azpdsgwc39l8.stretchoid.com	1
TOTAL: 10	18

NNRP unrecognized commands (by command):

Command	Count
HELO	3
Host: 45.13.105.36:563	2
GET / HTTP/1.1	2
EHLO www.censys.io	2
#026#003#003#002a#001	1
#026#003#001	1
MGLNDD_45.13.105.36_119	1
User-Agent: Hello from Palo Alto Network...	1
Accept: /	1
À$ÀÀ¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa...	1
Accept-Encoding: gzip	1
User-Agent: curl/7.68.0	1
#001	1
TOTAL: 13	18

NNRP client timeouts [Top 20]:

System	Conn	Peer
89-21-67-149.infrawat.ch	1	1
80.94.92.22	1	1
174.149.216.162.bc.googleusercontent.com	1	2
74.155.45.199.censys-scanner.com	1	2
TOTAL: 4	4	8

Daily Usenet report for news.niel.me

Aug 23 04:15:01 -- Aug 24 04:15:01