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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 4 10:43:29 rpi-studio innd: filter: No Cancel-Key[sha1:onNIFBhv5n5mFaissRqhgI/OtdI= sha256:b4PgTwnutG4P5xCQmJcU1hrFzCEpn4YMmpE1L0arydQ=] matches Cancel-Lock[sha1:TF2KQMcLBkfMS932kWDnvJqc2Nk= sha256:G16k/GuUAHxfLzgpo7LNV4xVVSfTTsacwS0G4L44kKM=] target=<1s2g9y1.18ri16e3mhxibN%alice43@free.invalid>
Oct 4 10:47:32 rpi-studio innd: filter: No Cancel-Key[sha1:UjZqYldIcXVlekZzaEh0eDNCdHpWSHVsT2g4PQ==#015#012#011sha1:a/vFMVKglF1weV+pHy0qqTxs35c= sha256:2CCQtEJ/l0f4PdBmTGr6X3mPR9BNQmlhyeX5FXiooI4=] matches Cancel-Lock[sha1:LqWM1LQYfitc4tb+ExkLOcWx9lQ=] target=<119rirc$28u0$1@csiph.com>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2573	29.7%	450.8 KB	33.7%
nnrpd	2161	25.0%	259.0 KB	19.3%
innd	1959	22.6%	336.7 KB	25.2%
innfeed	1956	22.6%	291.8 KB	21.8%
controlchan	1	0.0%	0.1 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8651	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	9196	49.5%
Negative hits	6808	36.7%
Do not exist	2564	13.8%
Cache misses	4	0.0%
TOTAL: 4	18572	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.002	0.0%	2	1.000	1.000	1.000
article cancel	00:00:00.001	0.0%	3	0.000	0.333	1.000
article cleanup	00:00:00.060	0.0%	2581	0.000	0.023	0.250
article logging	00:00:00.223	0.0%	2581	0.000	0.086	0.286
article parse	00:00:00.519	0.0%	7123	0.000	0.073	0.250
article write	00:00:01.091	0.0%	2116	0.000	0.516	1.286
artlog/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:00.111	0.0%	21874	0.000	0.005	0.069
hisgrep/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	2	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.314	0.0%	18716	0.000	0.017	0.059
history sync	00:00:00.049	0.0%	349	0.000	0.140	1.000
history write	00:00:00.475	0.0%	2580	0.000	0.184	0.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:28.332	100.0%	23295	1181.465	3710.167	18192.182
nntp read	00:00:00.598	0.0%	22458	0.000	0.027	0.113
overview write	00:00:00.657	0.0%	2116	0.000	0.310	1.900
perl filter	00:00:13.817	0.0%	2580	1.000	5.355	23.667
python filter	00:00:00.009	0.0%	2580	0.000	0.003	0.077
site send	00:00:00.858	0.0%	4282	0.000	0.200	0.500
TOTAL: 24:01:05.802	24:00:47.116	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.012	0.0%	2206	0.000	0.005	0.125
article prepare	00:00:00.001	0.0%	36	0.000	0.028	0.250
article read	00:00:00.009	0.0%	9	0.000	1.000	7.000
backlog stats	00:00:00.132	0.0%	25590	0.000	0.005	0.036
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.359	0.0%	12983	0.000	0.028	0.120
data write	00:00:00.626	0.0%	10978	0.000	0.057	0.250
idle	23:57:17.095	100.0%	25590	1338.254	3369.953	8986.104
status file	00:00:06.012	0.0%	288	0.000	20.875	83.000
TOTAL: 23:57:28.500	23:57:24.246	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:56.708	45.0%	1554	8.000	36.492	9346.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.120	0.1%	3483	0.000	0.034	0.500
TOTAL: 00:02:06.018	00:00:56.828	45.1%	-	-	-	-

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	3	2164	719	1445	0	33%	24:06:17
2	news.nntp4.net	2	2823	577	1815	431	20%	27:30:27
3	feed-out.gegeweb.org	3	2117	515	1602	0	24%	23:59:02
4	news.glou.org	3	1374	192	1137	45	13%	23:26:00
5	nntp.terraraq.uk	4	1705	137	1557	11	8%	28:01:19
6	usenet.pasdenom.info	9	842	45	796	1	5%	23:55:50
7	gegeweb-out.news.tnetconsulting.net	1	1153	36	1113	4	3%	20:30:09
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		50	12178	2221	9465	492	18%	171:29:04

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	3.6 MB	0.0 KB	3.6 MB	100%	5.2 KB
2	news.nntp4.net	2.8 MB	4.3 MB	7.1 MB	39%	7.2 KB
3	feed-out.gegeweb.org	1.8 MB	0.0 KB	1.8 MB	100%	3.5 KB
4	news.glou.org	593.5 KB	136.1 KB	729.5 KB	81%	3.1 KB
5	nntp.terraraq.uk	384.6 KB	21.0 KB	405.6 KB	94%	2.7 KB
6	usenet.pasdenom.info	103.5 KB	1.2 KB	104.8 KB	98%	2.3 KB
7	gegeweb-out.news.tnetconsulting.net	71.3 KB	26.0 KB	97.3 KB	73%	2.4 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		9.3 MB	4.5 MB	13.8 MB	67%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 4 04:15:02 - 04:59:59	31	1.5%	0.01	291.2 KB	3.2%	0.11
Oct 4 05:00:00 - 05:59:59	41	1.9%	0.01	120.4 KB	1.3%	0.03
Oct 4 06:00:00 - 06:59:59	44	2.1%	0.01	140.0 KB	1.5%	0.04
Oct 4 07:00:00 - 07:59:59	64	3.0%	0.02	409.0 KB	4.5%	0.11
Oct 4 08:00:00 - 08:59:59	37	1.8%	0.01	126.4 KB	1.4%	0.04
Oct 4 09:00:00 - 09:59:59	48	2.3%	0.01	340.2 KB	3.7%	0.09
Oct 4 10:00:00 - 10:59:59	61	2.9%	0.02	221.1 KB	2.4%	0.06
Oct 4 11:00:00 - 11:59:59	68	3.2%	0.02	232.8 KB	2.6%	0.06
Oct 4 12:00:00 - 12:59:59	123	5.8%	0.03	786.8 KB	8.6%	0.22
Oct 4 13:00:00 - 13:59:59	105	5.0%	0.03	439.1 KB	4.8%	0.12
Oct 4 14:00:00 - 14:59:59	93	4.4%	0.03	290.8 KB	3.2%	0.08
Oct 4 15:00:00 - 15:59:59	104	4.9%	0.03	322.5 KB	3.5%	0.09
Oct 4 16:00:00 - 16:59:59	131	6.2%	0.04	420.2 KB	4.6%	0.12
Oct 4 17:00:00 - 17:59:59	95	4.5%	0.03	358.7 KB	3.9%	0.10
Oct 4 18:00:00 - 18:59:59	87	4.1%	0.02	290.2 KB	3.2%	0.08
Oct 4 19:00:00 - 19:59:59	138	6.6%	0.04	465.9 KB	5.1%	0.13
Oct 4 20:00:00 - 20:59:59	139	6.6%	0.04	457.4 KB	5.0%	0.13
Oct 4 21:00:00 - 21:59:59	137	6.5%	0.04	504.3 KB	5.5%	0.14
Oct 4 22:00:00 - 22:59:59	102	4.8%	0.03	451.7 KB	5.0%	0.13
Oct 4 23:00:00 - 23:59:59	95	4.5%	0.03	787.6 KB	8.6%	0.22
Oct 5 00:00:00 - 00:59:59	97	4.6%	0.03	496.2 KB	5.4%	0.14
Oct 5 01:00:00 - 01:59:59	180	8.6%	0.05	849.6 KB	9.3%	0.24
Oct 5 02:00:00 - 02:59:59	49	2.3%	0.01	163.8 KB	1.8%	0.05
Oct 5 03:00:00 - 03:59:59	31	1.5%	0.01	132.6 KB	1.5%	0.04
Oct 5 04:00:00 - 04:15:01	5	0.2%	0.01	24.1 KB	0.3%	0.03
TOTAL: 23:59:59	2105	100.0%	0.02	8.9 MB	100.0%	0.11

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	406	320	0	86
2	news.glou.org	46	0	0	46
3	nntp.terraraq.uk	8	0	0	8
4	gegeweb-out.news.tnetconsulting.net	2	0	1	1
5	usenet.pasdenom.info	1	0	0	1
TOTAL: 5		463	320	1	142

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	279
linux.kernel.git	41
TOTAL: 2	320

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	81
Bad path (news.giganews.com.POSTED)	59
Cancels abusifs	1
No Cancel-Key matches Cancel-Lock. target=<119rirc$28u0$1@csiph.com>	1
TOTAL: 4	142

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	3712	3	2094	4	100	0%	23:59:05
2	news.gegeweb.org	1773	1	1581	0	0	0%	23:57:12
3	nntp.terraraq.uk	1023	1	965	0	1	0%	23:43:07
4	news.pasdenom.info	1483	0	1417	0	0	0%	23:57:51
5	news.aioe.org	0	0	0	0	1756	0%	23:59:59
6	news.samoylyk.net	1502	0	1315	0	0	0%	23:46:33
7	news.nntp4.net	1678	0	1610	0	0	0%	23:52:21
8	i2pn.org	0	0	0	0	2096	0%	23:59:59
TOTAL: 8		11171	5	8982	4	3953	0%	191:16:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	5.9 KB	13.2 KB	19.1 KB	2.7 KB	23:59:05
2	news.gegeweb.org	2.8 KB	0.0 KB	2.8 KB	2.8 KB	23:57:12
3	nntp.terraraq.uk	1.6 KB	0.0 KB	1.6 KB	1.6 KB	23:43:07
4	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:57:51
5	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
6	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:46:33
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:52:21
8	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
TOTAL: 8		10.3 KB	13.2 KB	23.5 KB	2.6 KB	191:16:07

NNRP readership statistics:

System		Conn	Elapsed
1	45-79-115-59.ip.linodeusercontent.com	1	00:00:01
2	188-240-59-41.infrawat.ch	1	00:00:00
3	193-176-29-18.infrawat.ch	1	00:00:00
4	azpdes42x1cg.stretchoid.com	2	00:00:10
5	2602:f71d:200::10	1	00:00:00
6	131.172.132.66.censys-scanner.com	1	00:00:01
7	217-146-80-116.infrawat.ch	1	00:00:00
8	2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	48	00:00:18
9	2a01:4020:1:1:101::6d	1	00:00:00
10	172-104-210-105.ip.linodeusercontent.com	1	00:00:00
11	147.185.133.154	2	00:00:23
12	185-223-235-26.infrawat.ch	1	00:00:00
13	o008.scanner.modat.io	1	00:00:03
14	mail.gegeweb.eu	288	00:00:36
15	o018.scanner.modat.io	1	00:00:29
16	66.61.98.95	20	00:00:09
TOTAL: 16		371	00:02:17

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	50	00:00:19
2	*.stretchoid.com	2	00:00:10
3	*.gegeweb.eu	288	00:00:36
4	*.infrawat.ch	4	00:00:01
5	*.172.132.66.censys-scanner.com	1	00:00:01
6	unresolved	22	00:00:32
7	*.ip.linodeusercontent.com	2	00:00:01
8	*.scanner.modat.io	2	00:00:33
TOTAL: 8		371	00:02:17

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.520	4.618	1.763	00:00:36
o018.scanner.modat.io	0.038	0.018	0.000	00:00:29
147.185.133.154	0.258	0.092	0.000	00:00:23
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	1.040	0.754	0.000	00:00:18
azpdes42x1cg.stretchoid.com	0.036	0.039	0.000	00:00:10
66.61.98.95	0.469	0.367	0.000	00:00:09
o008.scanner.modat.io	0.039	0.013	0.000	00:00:03
131.172.132.66.censys-scanner.com	0.034	0.030	0.000	00:00:01
45-79-115-59.ip.linodeusercontent.com	0.034	0.017	0.000	00:00:01
185-223-235-26.infrawat.ch	0.025	0.021	0.000	00:00:00
172-104-210-105.ip.linodeusercontent.com	0.030	0.021	0.000	00:00:00
217-146-80-116.infrawat.ch	0.011	0.011	0.000	00:00:00
2602:f71d:200::10	0.038	0.013	0.000	00:00:00
188-240-59-41.infrawat.ch	0.012	0.015	0.000	00:00:00
193-176-29-18.infrawat.ch	0.022	0.004	0.000	00:00:00
2a01:4020:1:1:101::6d	0.017	0.007	0.000	00:00:00
TOTAL: 16	7.623	6.040	1.763	00:02:17

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.185.133.154	4
131.172.132.66.censys-scanner.com	1
azpdes42x1cg.stretchoid.com	1
TOTAL: 3	6

NNRP unrecognized commands (by command):

Command	Count
User-Agent: curl/7.68.0	1
MGLNDD_45.13.105.36_119	1
EHLO www.censys.io	1
Accept: /	1
GET / HTTP/1.1	1
Host: 45.13.105.36:563	1
TOTAL: 6	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
217-146-80-116.infrawat.ch	1	1
147.185.133.154	1	2
TOTAL: 2	2	7

Daily Usenet report for news.niel.me

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