Daily Usenet report for news.niel.me: Oct 21 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Oct 21 14:36:25 rpi-studio innd: filter: No Cancel-Key[sha1:VWPchWZysjOUhBqcDToEtcfGpOk= sha256:Bw0vJF3lXtxQQznuO4rDPKndeRCwZAIFsAO+j7Y1G2Y=] matches Cancel-Lock[sha1:2USYpc30SGyhM67HX207LVqVZxk= sha256:5wcGlbIXpkXhQHvp7eHzym2/tU7V2I46qtYTpZ9jySg=] target=<11ba8us$3t0m5$1@dont-email.me>
Oct 21 14:40:59 rpi-studio innd: filter: No Cancel-Key[sha1:VWPchWZysjOUhBqcDToEtcfGpOk= sha256:Bw0vJF3lXtxQQznuO4rDPKndeRCwZAIFsAO+j7Y1G2Y=] matches Cancel-Lock[sha1:2USYpc30SGyhM67HX207LVqVZxk= sha256:5wcGlbIXpkXhQHvp7eHzym2/tU7V2I46qtYTpZ9jySg=] target=<11ba8us$3t0m5$1@dont-email.me>
Oct 21 14:42:57 rpi-studio innd: filter: No Cancel-Key[sha1:j9korYRin1U9nt4WAExDOC6CsBA= sha256:N2uxgyelVv/UYnbefd/ZuwLCuQdeo9c0NYNsL0SBqTY=] matches Cancel-Lock[sha1:jgeKz0ObwIhrGeOF6XVGfWvktus= sha256:YFo6CbNXnXeAh4W3vAsp8BtICwTsHnMAw0V/xq8Dh+g=] target=<11babsn$3u4tl$1@dont-email.me>
Oct 21 17:03:35 rpi-studio innd: filter: No Cancel-Key[sha1:pcR6AyKp8BQ3meRVuI8XZSxOqTs= sha256:BDfnxRClZ6605yvBKakpRc1AvfQVhgVOf3X/4sdAQlA=] matches Cancel-Lock[sha1:1dwJOQcthkR6zC139u6ByZoXKXk= sha256:pQknCxwI6MihfgWTT0v2UtAImWO2J5sMCT9oHXCJ8+c=] target=<11ba825$3sls9$1@dont-email.me>
Oct 21 17:03:47 rpi-studio innd: filter: No Cancel-Key[sha1:pcR6AyKp8BQ3meRVuI8XZSxOqTs= sha256:BDfnxRClZ6605yvBKakpRc1AvfQVhgVOf3X/4sdAQlA=] matches Cancel-Lock[sha1:1dwJOQcthkR6zC139u6ByZoXKXk= sha256:pQknCxwI6MihfgWTT0v2UtAImWO2J5sMCT9oHXCJ8+c=] target=<11ba825$3sls9$1@dont-email.me>
Oct 21 18:47:01 rpi-studio innd: filter: No Cancel-Key[sha1:yXzKtOtvoVyErhAmZC29kPTUy+M= sha256:Fc/AiFDGxEQmdOZG2pZsLK8aXZuobmnaj7HSt5oI8r8=] matches Cancel-Lock[sha1:a/3WTh3lSrX0kV+/BbYLMxiBDBc= sha256:U6F7wIMbRP4LyjDbCCISPgoUHMmtSKXdAOa0PQk0V4o=] target=<11bac0c$3u63d$1@dont-email.me>
Oct 21 18:47:12 rpi-studio innd: filter: No Cancel-Key[sha1:yXzKtOtvoVyErhAmZC29kPTUy+M= sha256:Fc/AiFDGxEQmdOZG2pZsLK8aXZuobmnaj7HSt5oI8r8=] matches Cancel-Lock[sha1:a/3WTh3lSrX0kV+/BbYLMxiBDBc= sha256:U6F7wIMbRP4LyjDbCCISPgoUHMmtSKXdAOa0PQk0V4o=] target=<11bac0c$3u63d$1@dont-email.me>
Oct 21 21:41:03 rpi-studio innd: filter: No Cancel-Key[sha1:0yDX4ZlNTHjfg+au9YFrHqYlY2s= sha256:mBQie3QPLRiQRrlCt5+29exFU+tzA00wW6SHrQyrTcU=] matches Cancel-Lock[sha1:xfl602ZMwn9rA7xap1ZwBN1DHU8= sha256:AxUVfzreuT2HAmrZ1KGwAVUed9WYmAe42IQDzVT6KvE=] target=<cp4idlhii7jqtpr8a98h81lu0ag3qh2lbf@kgbh.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2514	29.5%	423.7 KB	33.6%
nnrpd	2146	25.2%	250.2 KB	19.9%
innfeed	2004	23.5%	280.6 KB	22.3%
innd	1862	21.8%	305.1 KB	24.2%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	8528	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	6796	43.5%
Negative hits	6334	40.5%
Do not exist	2497	16.0%
Cache misses	3	0.0%
TOTAL: 4	15630	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.005	0.0%	5	0.000	1.000	1.500
article cancel	00:00:00.003	0.0%	7	0.000	0.429	1.500
article cleanup	00:00:00.052	0.0%	2518	0.000	0.021	0.167
article logging	00:00:00.187	0.0%	2518	0.000	0.074	0.429
article parse	00:00:00.623	0.0%	8085	0.000	0.077	0.333
article write	00:00:01.048	0.0%	1773	0.000	0.591	1.500
artlog/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:00:00.119	0.0%	20408	0.000	0.006	0.044
hisgrep/artcncl	00:00:00.001	0.0%	7	0.000	0.143	0.500
hisgrep/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	15	0.000	0.067	1.000
hishave/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	5	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.275	0.0%	15779	0.000	0.017	0.074
history sync	00:00:00.044	0.0%	400	0.000	0.110	1.000
history write	00:00:00.518	0.0%	2518	0.000	0.206	1.000
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:34.674	100.0%	21688	1385.663	3985.369	30025.050
nntp read	00:00:00.643	0.0%	20816	0.000	0.031	0.089
overview write	00:00:00.601	0.0%	1773	0.000	0.339	3.400
perl filter	00:00:11.232	0.0%	2518	1.750	4.461	16.000
python filter	00:00:00.015	0.0%	2518	0.000	0.006	0.118
site send	00:00:00.843	0.0%	3579	0.000	0.236	0.700
TOTAL: 24:01:07.521	24:00:50.884	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.016	0.0%	1757	0.000	0.009	0.167
article prepare	00:00:00.000	0.0%	0	0.000	0.000	0.000
article read	00:00:00.000	0.0%	0	0.000	0.000	0.000
backlog stats	00:00:00.093	0.0%	17157	0.000	0.005	0.042
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.231	0.0%	8022	0.000	0.029	0.100
data write	00:00:00.371	0.0%	6294	0.000	0.059	0.250
idle	23:58:58.296	100.0%	17157	1654.457	5032.249	12536.396
status file	00:00:02.910	0.0%	371	1.000	7.844	24.000
TOTAL: 23:59:05.081	23:59:01.917	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:21.856	76.5%	1593	8.000	51.385	9752.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.119	0.1%	3683	0.000	0.032	0.500
TOTAL: 00:01:47.013	00:01:21.975	76.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	1	2501	602	1174	725	24%	23:59:57
2	news.samoylyk.net	3	1774	536	1238	0	30%	23:56:08
3	feed-out.gegeweb.org	4	1754	363	1391	0	20%	23:55:54
4	usenet.pasdenom.info	12	946	29	916	1	3%	22:36:35
5	nntp.terraraq.uk	9	282	24	257	1	8%	09:03:32
6	news.glou.org	6	283	22	259	2	7%	08:15:40
7	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 7		60	7540	1576	5235	729	20%	111:47:46

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	3.1 MB	0.0 KB	3.1 MB	100%	5.9 KB
2	news.nntp4.net	2.5 MB	8.9 MB	11.5 MB	21%	8.8 KB
3	feed-out.gegeweb.org	1.4 MB	0.0 KB	1.4 MB	100%	3.9 KB
4	news.glou.org	89.3 KB	4.9 KB	94.2 KB	94%	3.9 KB
5	usenet.pasdenom.info	70.8 KB	1.2 KB	72.0 KB	98%	2.4 KB
6	nntp.terraraq.uk	64.1 KB	1.6 KB	65.7 KB	97%	2.6 KB
7	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 7		7.2 MB	8.9 MB	16.1 MB	44%	7.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 21 04:15:02 - 04:59:59	33	1.9%	0.01	133.5 KB	1.6%	0.05
Oct 21 05:00:00 - 05:59:59	17	1.0%	0.00	56.6 KB	0.7%	0.02
Oct 21 06:00:00 - 06:59:59	29	1.7%	0.01	231.1 KB	2.9%	0.06
Oct 21 07:00:00 - 07:59:59	33	1.9%	0.01	283.4 KB	3.5%	0.08
Oct 21 08:00:00 - 08:59:59	34	1.9%	0.01	147.0 KB	1.8%	0.04
Oct 21 09:00:00 - 09:59:59	78	4.4%	0.02	429.8 KB	5.3%	0.12
Oct 21 10:00:00 - 10:59:59	71	4.0%	0.02	268.3 KB	3.3%	0.07
Oct 21 11:00:00 - 11:59:59	91	5.2%	0.03	362.4 KB	4.5%	0.10
Oct 21 12:00:00 - 12:59:59	61	3.5%	0.02	270.6 KB	3.3%	0.08
Oct 21 13:00:00 - 13:59:59	102	5.8%	0.03	389.0 KB	4.8%	0.11
Oct 21 14:00:00 - 14:59:59	98	5.6%	0.03	534.6 KB	6.6%	0.15
Oct 21 15:00:00 - 15:59:59	112	6.4%	0.03	482.4 KB	6.0%	0.13
Oct 21 16:00:00 - 16:59:59	100	5.7%	0.03	304.6 KB	3.8%	0.08
Oct 21 17:00:00 - 17:59:59	103	5.9%	0.03	590.2 KB	7.3%	0.16
Oct 21 18:00:00 - 18:59:59	101	5.7%	0.03	533.2 KB	6.6%	0.15
Oct 21 19:00:00 - 19:59:59	126	7.2%	0.04	449.6 KB	5.5%	0.12
Oct 21 20:00:00 - 20:59:59	68	3.9%	0.02	278.1 KB	3.4%	0.08
Oct 21 21:00:00 - 21:59:59	86	4.9%	0.02	374.5 KB	4.6%	0.10
Oct 21 22:00:00 - 22:59:59	70	4.0%	0.02	281.6 KB	3.5%	0.08
Oct 21 23:00:00 - 23:59:59	80	4.6%	0.02	272.1 KB	3.4%	0.08
Oct 22 00:00:00 - 00:59:59	53	3.0%	0.01	505.7 KB	6.2%	0.14
Oct 22 01:00:00 - 01:59:59	64	3.6%	0.02	229.1 KB	2.8%	0.06
Oct 22 02:00:00 - 02:59:59	79	4.5%	0.02	286.5 KB	3.5%	0.08
Oct 22 03:00:00 - 03:59:59	55	3.1%	0.02	371.2 KB	4.6%	0.10
Oct 22 04:00:00 - 04:15:01	13	0.7%	0.01	36.7 KB	0.5%	0.04
TOTAL: 23:59:59	1757	100.0%	0.02	7.9 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	725	586	139
2	news.glou.org	16	0	16
3	nntp.terraraq.uk	3	0	3
4	usenet.pasdenom.info	1	0	1
TOTAL: 4		745	586	159

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	449
linux.kernel.git	137
TOTAL: 2	586

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	131
Bad path (news.giganews.com.POSTED)	19
Cancel of non-existing ID <11babsn$3u4tl$1@dont-email.me>	2
No Cancel-Key matches Cancel-Lock. target=<11ba825$3sls9$1@dont-email.me>	2
No Cancel-Key matches Cancel-Lock. target=<11bac0c$3u63d$1@dont-email.me>	2
Cancels abusifs	1
No Cancel-Key matches Cancel-Lock. target=<cp4idlhii7jqtpr8a98h81lu0ag3qh2lbf@kgbh.org>	1
No Cancel-Key matches Cancel-Lock. target=<11ba8us$3t0m5$1@dont-email.me>	1
TOTAL: 8	159

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Refused	Spool	%Took	Elapsed
1	i2pn.org	0	0	1751	0%	23:59:59
2	nntp.terraraq.uk	890	846	0	0%	23:14:47
3	news.samoylyk.net	1352	1173	0	0%	23:51:14
4	news.nntp4.net	1205	1151	0	0%	23:31:30
5	news.aioe.org	0	0	1404	0%	23:59:59
6	news.tnetconsulting.net	0	0	1755	0%	23:57:47
7	news.gegeweb.org	1457	1395	0	0%	23:42:55
8	news.pasdenom.info	1253	1210	0	0%	23:31:12
TOTAL: 8		6157	5775	4910	0%	189:49:23

Outgoing Feeds (innfeed) by Volume:

Server		Elapsed
1	i2pn.org	23:59:59
2	nntp.terraraq.uk	23:14:47
3	news.samoylyk.net	23:51:14
4	news.nntp4.net	23:31:30
5	news.aioe.org	23:59:59
6	news.tnetconsulting.net	23:57:47
7	news.gegeweb.org	23:42:55
8	news.pasdenom.info	23:31:12
TOTAL: 8		189:49:23

NNRP readership statistics:

System		Conn	Elapsed
1	2a01:4020:1:1:102::1c8	1	00:00:00
2	r4-212-d4.monitoring.internet-measurement.com	1	00:00:01
3	r5-205-cd.monitoring.internet-measurement.com	1	00:00:00
4	89-21-67-137.infrawat.ch	1	00:00:00
5	95.195.132.66.censys-scanner.com	1	00:00:00
6	r5-69-45.monitoring.internet-measurement.com	1	00:00:01
7	r3-6-6.monitoring.internet-measurement.com	1	00:00:00
8	r5-202-ca.monitoring.internet-measurement.com	1	00:00:00
9	89-21-67-175.infrawat.ch	1	00:00:00
10	o028.scanner.modat.io	1	00:00:00
11	r4-67-43.monitoring.internet-measurement.com	1	00:00:03
12	69-5-169-140.infrawat.ch	1	00:00:00
13	r5-61-3d.monitoring.internet-measurement.com	1	00:00:06
14	2a01:4020:1:1:102::68	1	00:00:00
15	mail.gegeweb.eu	288	00:00:20
16	147.185.133.68	1	00:00:04
17	r3-208-d0.monitoring.internet-measurement.com	1	00:00:00
18	r3-32-20.monitoring.internet-measurement.com	2	00:00:00
19	112.150.216.162.bc.googleusercontent.com	1	00:00:00
20	71.6.146.186	1	00:00:04
21	174.96.78.81	54	00:00:36
22	r4-186-ba.monitoring.internet-measurement.com	1	00:00:00
23	231.224.132.66.censys-scanner.com	5	00:00:18
24	azpdwst9oona.stretchoid.com	1	00:00:09
25	2-57-17-179.infrawat.ch	1	00:00:00
TOTAL: 25		370	00:01:49

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.stretchoid.com	1	00:00:09
2	?	2	00:00:00
3	*.gegeweb.eu	288	00:00:20
4	*.224.132.66.censys-scanner.com	5	00:00:18
5	*.monitoring.internet-measurement.com	11	00:00:13
6	*.scanner.modat.io	1	00:00:00
7	*.150.216.162.bc.googleusercontent.com	1	00:00:00
8	*.infrawat.ch	4	00:00:00
9	unresolved	56	00:00:45
10	*.195.132.66.censys-scanner.com	1	00:00:00
TOTAL: 10		370	00:01:49

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
174.96.78.81	1.411	1.177	0.000	00:00:36
mail.gegeweb.eu	5.033	4.514	1.707	00:00:20
231.224.132.66.censys-scanner.com	0.157	0.097	0.000	00:00:18
azpdwst9oona.stretchoid.com	0.028	0.024	0.000	00:00:09
r5-61-3d.monitoring.internet-measurement.com	0.186	0.040	0.000	00:00:06
71.6.146.186	0.030	0.017	0.000	00:00:04
147.185.133.68	0.041	0.009	0.000	00:00:04
r4-67-43.monitoring.internet-measurement.com	0.020	0.012	0.000	00:00:03
r4-212-d4.monitoring.internet-measurement.com	0.013	0.010	0.000	00:00:01
r5-69-45.monitoring.internet-measurement.com	0.035	0.017	0.000	00:00:01
95.195.132.66.censys-scanner.com	0.043	0.022	0.000	00:00:00
o028.scanner.modat.io	0.029	0.021	0.000	00:00:00
69-5-169-140.infrawat.ch	0.009	0.014	0.000	00:00:00
112.150.216.162.bc.googleusercontent.com	0.023	0.027	0.000	00:00:00
2-57-17-179.infrawat.ch	0.009	0.014	0.000	00:00:00
2a01:4020:1:1:102::1c8	0.028	0.020	0.000	00:00:00
r3-208-d0.monitoring.internet-measurement.com	0.044	0.019	0.000	00:00:00
89-21-67-137.infrawat.ch	0.013	0.013	0.000	00:00:00
r5-202-ca.monitoring.internet-measurement.com	0.032	0.028	0.000	00:00:00
r4-186-ba.monitoring.internet-measurement.com	0.021	0.008	0.000	00:00:00
TOTAL: 24	7.306	6.168	1.707	00:01:49

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.185.133.68	4
112.150.216.162.bc.googleusercontent.com	4
231.224.132.66.censys-scanner.com	3
95.195.132.66.censys-scanner.com	1
TOTAL: 4	12

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	2
User-Agent: Hello from Palo Alto Network...	2
GET / HTTP/1.1	2
Accept-Encoding: gzip	2
Host: 45.13.105.36:119	1
#023#003#023#001#023#002#001	1
#026#003#001#005º#001	1
Host: 45.13.105.36:563	1
TOTAL: 8	12

NNRP client timeouts [Top 20]:

System	Conn	Peer
2a01:4020:1:1:102::1c8	1	1
69-5-169-140.infrawat.ch	1	1
231.224.132.66.censys-scanner.com	1	3
TOTAL: 3	3	7

Daily Usenet report for news.niel.me

Oct 21 04:15:02 -- Oct 22 04:15:01