Daily Usenet report for news.niel.me: Jul 2 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jul 2 20:16:25 rpi-studio innd: filter: No Cancel-Key[sha1:e+cJtf0NDyEustvpLdDIJD+ji9w=] matches Cancel-Lock[sha1:jjpz4DOOmLBbmHJ7MsWhNDbd1Zk=] target=<68653c5a$0$12946$426a34cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2370	28.2%	411.1 KB	31.5%
innd	2186	26.0%	382.0 KB	29.2%
nnrpd	2013	23.9%	224.8 KB	17.2%
innfeed	1839	21.9%	288.4 KB	22.1%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8410	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	10006	54.8%
Negative hits	5904	32.3%
Do not exist	2346	12.8%
Cache misses	2	0.0%
TOTAL: 4	18258	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.010	0.0%	10	1.000	1.000	1.000
article cancel	00:00:00.003	0.0%	10	0.000	0.300	1.000
article cleanup	00:00:00.052	0.0%	2352	0.000	0.022	0.429
article logging	00:00:00.145	0.0%	2354	0.000	0.062	0.375
article parse	00:00:00.411	0.0%	6036	0.000	0.068	0.200
article write	00:00:00.819	0.0%	1699	0.000	0.482	1.500
artlog/artcncl	00:00:00.001	0.0%	10	0.000	0.100	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
data move	00:00:00.130	0.0%	20953	0.000	0.006	0.045
hisgrep/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	13	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	10	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%	18286	0.000	0.016	0.058
history sync	00:00:00.048	0.0%	368	0.000	0.130	1.000
history write	00:00:00.495	0.0%	2350	0.000	0.211	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:45.954	100.0%	22657	1266.719	3815.419	42890.571
nntp read	00:00:00.622	0.0%	21835	0.000	0.028	0.102
overview write	00:00:00.591	0.0%	1699	0.000	0.348	2.714
perl filter	00:00:08.573	0.0%	2350	1.792	3.648	16.667
python filter	00:00:00.015	0.0%	2350	0.000	0.006	0.111
site send	00:00:00.565	0.0%	3486	0.000	0.162	0.600
TOTAL: 24:01:05.958	24:00:58.732	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	1720	0.000	0.012	0.250
article prepare	00:00:00.000	0.0%	216	0.000	0.000	0.000
article read	00:00:00.013	0.0%	49	0.000	0.265	4.000
backlog stats	00:00:00.152	0.0%	25668	0.000	0.006	0.034
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.377	0.0%	13131	0.000	0.029	0.091
data write	00:00:00.655	0.0%	11522	0.000	0.057	0.200
idle	23:51:14.040	100.0%	25668	1714.357	3345.568	8232.384
status file	00:00:01.835	0.0%	271	1.000	6.771	52.000
TOTAL: 23:51:20.677	23:51:17.093	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:06:10.651	70.7%	1463	9.400	253.350	96017.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.127	0.0%	3389	0.000	0.037	0.500
TOTAL: 00:08:44.158	00:06:10.778	70.7%	-	-	-	-

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.nntp4.net	2	2032	737	740	555	36%	21:02:25
2	feed-out.gegeweb.org	3	1681	478	1203	0	28%	23:51:26
3	news.samoylyk.net	3	2190	161	2029	0	7%	29:16:30
4	gegeweb-out.news.tnetconsulting.net	5	1471	61	1406	4	4%	23:55:21
5	i2pn.org	2	2009	51	1957	1	2%	24:17:23
6	news.glou.org	4	810	31	757	22	3%	15:34:55
7	nntp.terraraq.uk	3	223	6	215	2	2%	06:02:59
8	usenet.pasdenom.info	8	838	0	837	1	0%	25:21:35
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		55	11254	1525	9144	585	13%	169:22:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	2.5 MB	0.0 KB	5.4 MB	7.9 MB	31%	6.3 KB
2	feed-out.gegeweb.org	1.5 MB	0.0 KB	0.0 KB	1.5 MB	100%	3.1 KB
3	news.samoylyk.net	811.7 KB	0.0 KB	0.0 KB	811.7 KB	100%	5.0 KB
4	gegeweb-out.news.tnetconsulting.net	332.8 KB	0.0 KB	52.0 KB	384.8 KB	86%	5.9 KB
5	i2pn.org	123.4 KB	0.0 KB	2.0 KB	125.4 KB	98%	2.4 KB
6	news.glou.org	110.8 KB	0.0 KB	77.8 KB	188.6 KB	58%	3.6 KB
7	nntp.terraraq.uk	24.4 KB	0.0 KB	3.5 KB	27.9 KB	87%	3.5 KB
8	usenet.pasdenom.info	0.0 KB	3.4 KB	3.4 KB	6.9 KB	0%	6.9 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		5.4 MB	3.4 KB	5.5 MB	10.9 MB	49%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 2 04:15:01 - 04:59:59	56	3.3%	0.02	257.8 KB	4.1%	0.10
Jul 2 05:00:00 - 05:59:59	31	1.8%	0.01	90.5 KB	1.4%	0.03
Jul 2 06:00:00 - 06:59:59	28	1.7%	0.01	80.2 KB	1.3%	0.02
Jul 2 07:00:00 - 07:59:59	44	2.6%	0.01	139.9 KB	2.2%	0.04
Jul 2 08:00:00 - 08:59:59	34	2.0%	0.01	138.5 KB	2.2%	0.04
Jul 2 09:00:00 - 09:59:59	54	3.2%	0.01	170.4 KB	2.7%	0.05
Jul 2 10:00:00 - 10:59:59	55	3.2%	0.02	193.1 KB	3.1%	0.05
Jul 2 11:00:00 - 11:59:59	78	4.6%	0.02	259.9 KB	4.1%	0.07
Jul 2 12:00:00 - 12:59:59	44	2.6%	0.01	140.2 KB	2.2%	0.04
Jul 2 13:00:00 - 13:59:59	71	4.2%	0.02	212.8 KB	3.4%	0.06
Jul 2 14:00:00 - 14:59:59	46	2.7%	0.01	114.8 KB	1.8%	0.03
Jul 2 15:00:00 - 15:59:59	67	4.0%	0.02	231.9 KB	3.7%	0.06
Jul 2 16:00:00 - 16:59:59	70	4.1%	0.02	554.9 KB	8.8%	0.15
Jul 2 17:00:00 - 17:59:59	111	6.5%	0.03	492.2 KB	7.8%	0.14
Jul 2 18:00:00 - 18:59:59	113	6.7%	0.03	329.1 KB	5.2%	0.09
Jul 2 19:00:00 - 19:59:59	103	6.1%	0.03	436.4 KB	6.9%	0.12
Jul 2 20:00:00 - 20:59:59	95	5.6%	0.03	300.7 KB	4.8%	0.08
Jul 2 21:00:00 - 21:59:59	71	4.2%	0.02	181.4 KB	2.9%	0.05
Jul 2 22:00:00 - 22:59:59	82	4.8%	0.02	290.8 KB	4.6%	0.08
Jul 2 23:00:00 - 23:59:59	101	6.0%	0.03	306.9 KB	4.9%	0.09
Jul 3 00:00:00 - 00:59:59	124	7.3%	0.03	703.5 KB	11.2%	0.20
Jul 3 01:00:00 - 01:59:59	79	4.7%	0.02	275.4 KB	4.4%	0.08
Jul 3 02:00:00 - 02:59:59	73	4.3%	0.02	228.7 KB	3.6%	0.06
Jul 3 03:00:00 - 03:59:59	48	2.8%	0.01	118.4 KB	1.9%	0.03
Jul 3 04:00:00 - 04:15:01	18	1.1%	0.02	36.4 KB	0.6%	0.04
TOTAL: 24:00:00	1696	100.0%	0.02	6.1 MB	100.0%	0.07

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	594	441	0	153
2	news.glou.org	38	0	0	38
3	nntp.terraraq.uk	12	0	1	11
4	gegeweb-out.news.tnetconsulting.net	6	0	0	6
5	i2pn.org	2	0	0	2
6	news.samoylyk.net	1	0	0	1
7	usenet.pasdenom.info	1	0	1	0
TOTAL: 7		654	441	2	211

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	363
linux.kernel.git	66
alt.france	8
alt.slack	2
news.test	1
alt.radio.college	1
TOTAL: 6	441

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	80
Bad path (news.giganews.com.POSTED)	67
EMP (md5)	55
HTML Multipart	3
Cancel of non-existing ID <1044flf$3pg51$1@dont-email.me>	2
Cancels abusifs	1
No Cancel-Key matches Cancel-Lock. target=<68653c5a$0$12946$426a34cc@news.free.fr>	1
TOTAL: 7	209

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	2420	8	1604	28	13	0%	23:57:25
2	news.gegeweb.org	1257	4	1213	0	0	0%	23:53:28
3	news.tnetconsulting.net	2497	4	1685	5	14	0%	23:57:26
4	news.nntp4.net	888	2	855	0	0	0%	23:31:14
5	news.pasdenom.info	1469	1	1404	2	0	0%	23:54:13
6	nntp.terraraq.uk	1229	0	1145	0	0	0%	23:45:07
7	news.samoylyk.net	1813	0	1553	0	0	0%	23:54:19
8	news.aioe.org	0	0	0	0	1371	0%	24:00:00
TOTAL: 8		11573	19	9459	35	1398	0%	190:53:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	24.9 KB	115.0 KB	140.0 KB	3.9 KB	23:57:25
2	news.tnetconsulting.net	11.5 KB	25.0 KB	36.5 KB	4.1 KB	23:57:26
3	news.gegeweb.org	9.2 KB	0.0 KB	9.2 KB	2.3 KB	23:53:28
4	news.nntp4.net	2.2 KB	0.0 KB	2.2 KB	1.1 KB	23:31:14
5	news.pasdenom.info	1.4 KB	2.2 KB	3.5 KB	1.2 KB	23:54:13
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:45:07
7	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:54:19
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		49.2 KB	142.2 KB	191.4 KB	3.5 KB	190:53:12

NNRP readership statistics:

System		Conn	Elapsed
1	upstanding.monitoring.internet-measurement.com	1	00:00:00
2	collaborative.monitoring.internet-measurement.com	1	00:00:00
3	114.149.216.162.bc.googleusercontent.com	1	00:00:00
4	impressive.monitoring.internet-measurement.com	1	00:00:00
5	165.210.203.35.bc.googleusercontent.com	2	00:00:01
6	207.211.203.35.bc.googleusercontent.com	2	00:00:02
7	198.235.24.80	1	00:00:01
8	2a06:4882:7000::6f	1	00:00:00
9	2a06:4883:b000::b9	1	00:00:03
10	2a06:4882:7000::84	1	00:00:00
11	118.149.216.162.bc.googleusercontent.com	1	00:00:01
12	2603:6010:6800:36:1a03:73ff:fe28:be0f	2	00:00:02
13	198.235.24.114	1	00:00:01
14	empathetic.monitoring.internet-measurement.com	1	00:00:03
15	2a06:4882:b000::bc	1	00:00:04
16	205.210.31.160	3	00:02:25
17	206.168.34.74	1	00:00:05
18	2a06:4883:7000::88	1	00:00:00
19	singular.monitoring.internet-measurement.com	1	00:00:03
20	206.168.34.123	3	00:00:08
21	206.168.34.72	12	00:00:28
22	205.210.31.7	3	00:03:12
23	mail.gegeweb.eu	287	00:01:55
24	azpdegyzkw4r.stretchoid.com	1	00:00:00
TOTAL: 24		330	00:08:45

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.149.216.162.bc.googleusercontent.com	2	00:00:01
2	*.gegeweb.eu	287	00:01:55
3	unresolved	24	00:06:25
4	?	7	00:00:10
5	*.210.203.35.bc.googleusercontent.com	2	00:00:01
6	*.stretchoid.com	1	00:00:00
7	*.monitoring.internet-measurement.com	5	00:00:07
8	*.211.203.35.bc.googleusercontent.com	2	00:00:02
TOTAL: 8		330	00:08:45

NNRP auth users [Top 20]:

User	Conn
mailman	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
205.210.31.7	0.081	0.060	0.000	00:03:12
205.210.31.160	0.083	0.061	0.000	00:02:25
mail.gegeweb.eu	4.944	4.444	1.904	00:01:55
206.168.34.72	0.455	0.235	0.000	00:00:28
206.168.34.123	0.072	0.068	0.000	00:00:08
206.168.34.74	0.029	0.008	0.000	00:00:05
2a06:4882:b000::bc	0.069	0.023	0.000	00:00:04
empathetic.monitoring.internet-measurement.com	0.024	0.003	0.000	00:00:03
2a06:4883:b000::b9	0.018	0.011	0.000	00:00:03
singular.monitoring.internet-measurement.com	0.054	0.019	0.000	00:00:03
2603:6010:6800:36:1a03:73ff:fe28:be0f	0.050	0.025	0.000	00:00:02
207.211.203.35.bc.googleusercontent.com	0.207	0.098	0.000	00:00:02
165.210.203.35.bc.googleusercontent.com	0.118	0.042	0.000	00:00:01
198.235.24.80	0.011	0.011	0.000	00:00:01
198.235.24.114	0.029	0.022	0.000	00:00:01
118.149.216.162.bc.googleusercontent.com	0.057	0.026	0.000	00:00:01
114.149.216.162.bc.googleusercontent.com	0.041	0.037	0.000	00:00:00
azpdegyzkw4r.stretchoid.com	0.036	0.016	0.000	00:00:00
2a06:4882:7000::6f	0.038	0.017	0.000	00:00:00
collaborative.monitoring.internet-measurement.com	0.004	0.024	0.000	00:00:00
TOTAL: 23	6.499	5.285	1.904	00:08:45

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
207.211.203.35.bc.googleusercontent.com	4
118.149.216.162.bc.googleusercontent.com	4
165.210.203.35.bc.googleusercontent.com	4
114.149.216.162.bc.googleusercontent.com	4
azpdegyzkw4r.stretchoid.com	1
206.168.34.123	1
206.168.34.72	1
205.210.31.7	1
206.168.34.74	1
205.210.31.160	1
TOTAL: 10	22

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:563	4
GET / HTTP/1.1	4
EHLO www.censys.io	3
User-Agent: Expanse, a Palo Alto Network...	2
User-Agent: curl/7.68.0	2
Accept-Encoding: gzip	2
Accept: /	2
#026#003	2
MGLNDD_45.13.105.36_119	1
TOTAL: 9	22

NNRP client timeouts [Top 20]:

System	Conn	Peer
205.210.31.7	2	3
205.210.31.160	2	3
165.210.203.35.bc.googleusercontent.com	1	2
206.168.34.123	1	2
207.211.203.35.bc.googleusercontent.com	1	2
TOTAL: 5	7	14

Daily Usenet report for news.niel.me

Jul 2 04:15:01 -- Jul 3 04:15:01