Daily Usenet report for news.niel.me: Jan 4 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 4 18:41:45 rpi-studio innd: filter: No Cancel-Key[sha1:KdyPBK0OGx9LihHJM8RdG8REJwU= sha256:2ITq9dKIr+6YIPbvywlwhyZiEhoMQ1Wu+qewFYJGoY4=] matches Cancel-Lock[sha1:UwMv044aWaf6SgZUNmrKJg0XDY0= sha256:aT3nH2vgNSmbzCE7mgDExnEW+l7CvqPY/rfNS7UvVKM=] target=<10je8ku$lrcl$1@gwaiyur.mb-net.net>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2392	27.5%	407.8 KB	31.0%
innd	2267	26.0%	388.9 KB	29.6%
nnrpd	2144	24.6%	236.1 KB	18.0%
innfeed	1903	21.9%	282.3 KB	21.5%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8708	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8481	49.4%
Negative hits	6301	36.7%
Do not exist	2381	13.9%
Cache misses	1	0.0%
TOTAL: 4	17164	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	1	6.000	6.000	6.000
article cancel	00:00:00.002	0.0%	2	1.000	1.000	1.000
article cleanup	00:00:00.050	0.0%	2397	0.000	0.021	0.222
article logging	00:00:00.186	0.0%	2399	0.000	0.078	0.375
article parse	00:00:00.707	0.0%	9397	0.000	0.075	0.188
article write	00:00:00.977	0.0%	1805	0.000	0.541	2.200
artlog/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.112	0.0%	23745	0.000	0.005	0.045
hisgrep/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	1	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.298	0.0%	17272	0.000	0.017	0.067
history sync	00:00:00.050	0.0%	400	0.000	0.125	0.667
history write	00:00:00.463	0.0%	2397	0.000	0.193	0.750
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:50.181	100.0%	24624	352.121	3510.810	18209.182
nntp read	00:00:00.707	0.0%	23737	0.000	0.030	0.095
overview write	00:00:00.571	0.0%	1805	0.000	0.316	2.667
perl filter	00:00:14.652	0.0%	2397	1.889	6.113	27.800
python filter	00:00:00.012	0.0%	2397	0.000	0.005	0.167
site send	00:00:00.673	0.0%	3663	0.000	0.184	0.500
TOTAL: 24:01:21.186	24:01:09.648	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.017	0.0%	1818	0.000	0.009	0.214
article prepare	00:00:00.000	0.0%	56	0.000	0.000	0.000
article read	00:00:00.007	0.0%	14	0.000	0.500	2.500
backlog stats	00:00:00.129	0.0%	22823	0.000	0.006	0.029
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.289	0.0%	11126	0.000	0.026	0.103
data write	00:00:00.583	0.0%	9389	0.000	0.062	0.227
idle	23:57:46.801	100.0%	22823	2026.272	3779.819	8700.232
status file	00:00:09.471	0.0%	303	1.000	31.257	91.000
TOTAL: 23:58:01.885	23:57:57.297	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:11:30.087	84.1%	1571	11.800	439.266	301923.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.104	0.0%	3776	0.000	0.028	0.333
TOTAL: 00:13:40.473	00:11:30.191	84.1%	-	-	-	-

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	2	2365	765	1045	555	32%	23:59:50
2	feed-out.gegeweb.org	3	1820	538	1282	0	29%	23:59:22
3	news.samoylyk.net	3	2553	347	2206	0	13%	29:02:43
4	nntp.terraraq.uk	5	2724	136	2582	6	4%	42:06:56
5	news.glou.org	5	2156	97	1976	83	4%	34:53:01
6	gegeweb-out.news.tnetconsulting.net	3	1493	46	1447	0	3%	23:48:18
7	usenet.pasdenom.info	10	886	23	863	0	2%	21:34:05
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		56	13997	1952	11401	644	13%	199:24:15

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	3.7 MB	13.6 MB	17.2 MB	21%	13.4 KB
2	news.samoylyk.net	2.0 MB	0.0 KB	2.0 MB	100%	6.0 KB
3	feed-out.gegeweb.org	1.8 MB	0.0 KB	1.8 MB	100%	3.5 KB
4	nntp.terraraq.uk	1.1 MB	32.2 KB	1.1 MB	97%	8.0 KB
5	news.glou.org	424.2 KB	274.0 KB	698.2 KB	60%	3.9 KB
6	gegeweb-out.news.tnetconsulting.net	150.2 KB	0.0 KB	150.2 KB	100%	3.3 KB
7	usenet.pasdenom.info	53.2 KB	0.0 KB	53.2 KB	100%	2.3 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		9.2 MB	13.9 MB	23.1 MB	39%	9.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 4 04:15:01 - 04:59:59	40	2.2%	0.01	169.9 KB	1.8%	0.06
Jan 4 05:00:00 - 05:59:59	50	2.8%	0.01	262.5 KB	2.8%	0.07
Jan 4 06:00:00 - 06:59:59	50	2.8%	0.01	214.6 KB	2.3%	0.06
Jan 4 07:00:00 - 07:59:59	37	2.1%	0.01	181.2 KB	2.0%	0.05
Jan 4 08:00:00 - 08:59:59	30	1.7%	0.01	197.1 KB	2.1%	0.05
Jan 4 09:00:00 - 09:59:59	35	1.9%	0.01	155.0 KB	1.7%	0.04
Jan 4 10:00:00 - 10:59:59	40	2.2%	0.01	177.1 KB	1.9%	0.05
Jan 4 11:00:00 - 11:59:59	62	3.5%	0.02	285.1 KB	3.1%	0.08
Jan 4 12:00:00 - 12:59:59	86	4.8%	0.02	288.3 KB	3.1%	0.08
Jan 4 13:00:00 - 13:59:59	87	4.8%	0.02	602.5 KB	6.5%	0.17
Jan 4 14:00:00 - 14:59:59	82	4.6%	0.02	387.3 KB	4.2%	0.11
Jan 4 15:00:00 - 15:59:59	97	5.4%	0.03	451.1 KB	4.9%	0.13
Jan 4 16:00:00 - 16:59:59	100	5.6%	0.03	803.3 KB	8.6%	0.22
Jan 4 17:00:00 - 17:59:59	107	6.0%	0.03	650.7 KB	7.0%	0.18
Jan 4 18:00:00 - 18:59:59	125	7.0%	0.03	518.7 KB	5.6%	0.14
Jan 4 19:00:00 - 19:59:59	116	6.5%	0.03	527.6 KB	5.7%	0.15
Jan 4 20:00:00 - 20:59:59	132	7.3%	0.04	604.0 KB	6.5%	0.17
Jan 4 21:00:00 - 21:59:59	95	5.3%	0.03	400.1 KB	4.3%	0.11
Jan 4 22:00:00 - 22:59:59	91	5.1%	0.03	389.3 KB	4.2%	0.11
Jan 4 23:00:00 - 23:59:59	73	4.1%	0.02	808.7 KB	8.7%	0.22
Jan 5 00:00:00 - 00:59:59	66	3.7%	0.02	242.8 KB	2.6%	0.07
Jan 5 01:00:00 - 01:59:59	86	4.8%	0.02	313.2 KB	3.4%	0.09
Jan 5 02:00:00 - 02:59:59	50	2.8%	0.01	296.2 KB	3.2%	0.08
Jan 5 03:00:00 - 03:59:59	48	2.7%	0.01	319.6 KB	3.4%	0.09
Jan 5 04:00:00 - 04:15:02	12	0.7%	0.01	45.5 KB	0.5%	0.05
TOTAL: 24:00:01	1797	100.0%	0.02	9.1 MB	100.0%	0.11

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	557	153	404
2	news.glou.org	31	0	31
3	nntp.terraraq.uk	4	0	4
TOTAL: 3		592	153	439

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	122
linux.kernel.git	30
news.test	1
TOTAL: 3	153

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	310
Bad path (news.giganews.com.POSTED)	125
Too many newsgroups	1
HTML Multipart	1
TOTAL: 4	437

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nntp4.net	2
	TOTAL: 1	2
TOTAL: 1		2

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	3279	5	1778	9	20	0%	23:59:54
2	news.aioe.org	0	0	0	0	1398	0%	24:00:00
3	news.pasdenom.info	1271	0	1258	0	0	0%	23:58:53
4	news.nntp4.net	1045	0	1007	0	1	0%	23:14:15
5	news.samoylyk.net	1625	0	1445	0	0	0%	23:59:15
6	news.gegeweb.org	1288	0	1259	0	1	0%	23:54:12
7	nntp.terraraq.uk	952	0	933	0	1	0%	23:30:46
8	i2pn.org	0	0	0	0	1784	0%	23:57:48
TOTAL: 8		9460	5	7680	9	3205	0%	190:35:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	13.9 KB	31.1 KB	45.0 KB	3.2 KB	23:59:54
2	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
3	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:58:53
4	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:14:15
5	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:15
6	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:54:12
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:30:46
8	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	23:57:48
TOTAL: 8		13.9 KB	31.1 KB	45.0 KB	3.2 KB	190:35:03

NNRP readership statistics:

System		Conn	Elapsed
1	66.132.153.119	3	00:00:05
2	224.150.216.162.bc.googleusercontent.com	1	00:00:00
3	132.150.216.162.bc.googleusercontent.com	2	00:00:06
4	reykjavik.scan.bufferover.run	2	00:00:11
5	50.150.216.162.bc.googleusercontent.com	4	00:00:53
6	o013.scanner.modat.io	1	00:00:00
7	66.132.153.134	1	00:00:32
8	parks.probe.onyphe.net	1	00:00:01
9	2a06:4882:9000::a0	1	00:00:00
10	172-236-228-229.ip.linodeusercontent.com	2	00:00:00
11	2a06:4883:1000::15	1	00:00:06
12	azpdesfia1qg.stretchoid.com	2	00:00:10
13	66.61.98.95	30	00:00:21
14	250.149.216.162.bc.googleusercontent.com	1	00:00:00
15	2a06:4882:9000::95	1	00:00:00
16	2001:41d0:700:1273::	1	00:10:03
17	66.132.153.122	1	00:00:00
18	2a06:4882:9000::b4	1	00:00:00
19	205.210.31.58	1	00:00:03
20	tillie.probe.onyphe.net	1	00:00:03
21	mail.gegeweb.eu	288	00:01:20
22	2a06:4883:1000::a	1	00:00:03
23	2603:6010:6800:36:1a03:73ff:fe28:be0f	6	00:00:05
TOTAL: 23		353	00:14:13

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	36	00:01:03
2	*.scan.bufferover.run	2	00:00:11
3	*.150.216.162.bc.googleusercontent.com	7	00:01:00
4	*.probe.onyphe.net	2	00:00:05
5	*.stretchoid.com	2	00:00:10
6	*.gegeweb.eu	288	00:01:20
7	?	12	00:10:18
8	*.ip.linodeusercontent.com	2	00:00:00
9	*.scanner.modat.io	1	00:00:00
10	*.149.216.162.bc.googleusercontent.com	1	00:00:00
TOTAL: 10		353	00:14:13

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
2001:41d0:700:1273::	0.014	0.010	0.000	00:10:03
mail.gegeweb.eu	4.528	3.865	1.634	00:01:20
50.150.216.162.bc.googleusercontent.com	0.548	0.130	0.000	00:00:53
66.132.153.134	0.014	0.014	0.000	00:00:32
66.61.98.95	0.686	0.436	0.000	00:00:21
reykjavik.scan.bufferover.run	0.046	0.046	0.000	00:00:11
azpdesfia1qg.stretchoid.com	0.030	0.043	0.000	00:00:10
132.150.216.162.bc.googleusercontent.com	0.216	0.050	0.000	00:00:06
2a06:4883:1000::15	0.145	0.080	0.000	00:00:06
66.132.153.119	0.047	0.054	0.000	00:00:05
2603:6010:6800:36:1a03:73ff:fe28:be0f	0.157	0.131	0.000	00:00:05
205.210.31.58	0.027	0.024	0.000	00:00:03
tillie.probe.onyphe.net	0.066	0.021	0.000	00:00:03
2a06:4883:1000::a	0.039	0.022	0.000	00:00:03
parks.probe.onyphe.net	0.068	0.018	0.000	00:00:01
172-236-228-229.ip.linodeusercontent.com	0.056	0.017	0.000	00:00:00
224.150.216.162.bc.googleusercontent.com	0.060	0.036	0.000	00:00:00
66.132.153.122	0.046	0.017	0.000	00:00:00
250.149.216.162.bc.googleusercontent.com	0.036	0.012	0.000	00:00:00
o013.scanner.modat.io	0.031	0.019	0.000	00:00:00
TOTAL: 22	6.911	5.081	1.634	00:14:13

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
50.150.216.162.bc.googleusercontent.com	8
reykjavik.scan.bufferover.run	5
172-236-228-229.ip.linodeusercontent.com	5
132.150.216.162.bc.googleusercontent.com	4
250.149.216.162.bc.googleusercontent.com	4
224.150.216.162.bc.googleusercontent.com	4
66.132.153.119	1
66.132.153.122	1
azpdesfia1qg.stretchoid.com	1
TOTAL: 9	33

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	6
Host: 45.13.105.36:563	6
Accept: /	4
Accept-Encoding: gzip	3
User-Agent: curl/7.68.0	3
EHLO www.censys.io	2
User-Agent: Hello from Palo Alto Network...	2
#026#003#001#001	1
À#024	1
À$À(À#À'	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
MGLNDD_45.13.105.36_119	1
#001	1
üá;çzõ\#002Iql©¦ –dûŠuº5à=Å;+#006~OQ@Ò{#010a¬#023ÅùÌ...	1
TOTAL: 14	33

NNRP client timeouts [Top 20]:

System	Conn	Peer
50.150.216.162.bc.googleusercontent.com	2	4
reykjavik.scan.bufferover.run	1	1
132.150.216.162.bc.googleusercontent.com	1	2
66.132.153.119	1	2
TOTAL: 4	5	10

Daily Usenet report for news.niel.me

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