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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jul 3 09:10:48 rpi-studio innd: filter: No Cancel-Key[sha1:pZlrk06l60fPKknvTQTJt1FyIzM=] matches Cancel-Lock[sha1:tW/rKaXVKfUcqhiJ/aY1yKYmZEQ=] target=<68662cd0$0$10609$426a74cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2266	27.4%	393.4 KB	30.7%
innd	2222	26.9%	382.2 KB	29.8%
nnrpd	1948	23.6%	208.3 KB	16.3%
innfeed	1820	22.0%	296.7 KB	23.2%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8258	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	9533	55.1%
Negative hits	5523	31.9%
Do not exist	2236	12.9%
Cache misses	7	0.0%
TOTAL: 4	17299	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.009	0.0%	10	0.500	0.900	1.000
article cancel	00:00:00.003	0.0%	10	0.000	0.300	1.000
article cleanup	00:00:00.048	0.0%	2235	0.000	0.021	0.333
article logging	00:00:00.171	0.0%	2236	0.000	0.076	0.417
article parse	00:00:00.347	0.0%	5464	0.000	0.064	0.273
article write	00:00:00.787	0.0%	1655	0.000	0.476	2.000
artlog/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
data move	00:00:00.126	0.0%	19553	0.000	0.006	0.041
hisgrep/artcncl	00:00:00.001	0.0%	10	0.000	0.100	1.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	10	0.000	0.100	1.000
hisgrep/perl	00:00:00.001	0.0%	13	0.000	0.077	1.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.290	0.0%	17279	0.000	0.017	0.078
history sync	00:00:00.052	0.0%	348	0.000	0.149	1.000
history write	00:00:00.465	0.0%	2235	0.000	0.208	1.286
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:50:40.185	100.0%	21212	1336.450	4046.775	20017.300
nntp read	00:00:00.584	0.0%	20431	0.000	0.029	0.075
overview write	00:00:00.571	0.0%	1655	0.000	0.345	4.600
perl filter	00:00:08.724	0.0%	2235	1.947	3.903	11.500
python filter	00:00:00.012	0.0%	2235	0.000	0.005	0.167
site send	00:00:00.610	0.0%	3363	0.000	0.181	0.500
TOTAL: 23:51:01.388	23:50:52.987	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.010	0.0%	1661	0.000	0.006	0.154
article prepare	00:00:00.000	0.0%	372	0.000	0.000	0.000
article read	00:00:00.016	0.0%	83	0.000	0.193	4.000
backlog stats	00:00:00.120	0.0%	25511	0.000	0.005	0.027
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.351	0.0%	13062	0.000	0.027	0.115
data write	00:00:00.670	0.0%	11430	0.000	0.059	0.176
idle	23:52:59.285	100.0%	25511	1351.804	3370.283	7798.779
status file	00:00:01.623	0.0%	249	0.000	6.518	76.000
TOTAL: 23:53:06.379	23:53:02.075	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:03:35.816	54.1%	1489	6.000	144.940	89041.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.247	0.1%	3477	0.000	0.071	1.000
readart	00:00:00.592	0.1%	11900	0.050	0.050	0.050
TOTAL: 00:06:38.610	00:03:36.655	54.4%	-	-	-	-

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	2325	875	907	543	37%	25:56:57
2	feed-out.gegeweb.org	2	1589	412	1177	0	25%	23:58:43
3	news.glou.org	2	2164	133	1961	70	6%	33:46:51
4	nntp.terraraq.uk	4	1508	80	1418	10	5%	28:44:54
5	news.samoylyk.net	1	1097	75	1021	1	6%	11:49:49
6	gegeweb-out.news.tnetconsulting.net	1	1427	74	1348	5	5%	23:59:48
7	i2pn.org	1	1688	49	1637	2	2%	23:40:46
8	usenet.pasdenom.info	12	811	1	810	0	0%	24:07:57
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		50	12609	1699	10279	631	13%	196:05:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	2.6 MB	0.0 KB	5.1 MB	7.8 MB	34%	5.6 KB
2	feed-out.gegeweb.org	1.3 MB	0.0 KB	0.0 KB	1.3 MB	100%	3.3 KB
3	news.samoylyk.net	793.0 KB	0.0 KB	2.7 KB	795.7 KB	99%	10.5 KB
4	news.glou.org	429.2 KB	0.0 KB	201.4 KB	630.5 KB	68%	3.1 KB
5	nntp.terraraq.uk	272.9 KB	1.9 KB	27.8 KB	302.6 KB	90%	3.4 KB
6	gegeweb-out.news.tnetconsulting.net	269.6 KB	0.0 KB	13.7 KB	283.3 KB	95%	3.6 KB
7	i2pn.org	134.4 KB	0.0 KB	4.1 KB	138.4 KB	97%	2.7 KB
8	usenet.pasdenom.info	0.8 KB	0.0 KB	0.0 KB	0.8 KB	100%	0.8 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		5.8 MB	1.9 KB	5.4 MB	11.2 MB	52%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 3 04:15:01 - 04:59:59	19	1.1%	0.01	77.6 KB	1.3%	0.03
Jul 3 05:00:00 - 05:59:59	41	2.5%	0.01	112.8 KB	1.9%	0.03
Jul 3 06:00:00 - 06:59:59	32	1.9%	0.01	105.2 KB	1.8%	0.03
Jul 3 07:00:00 - 07:59:59	51	3.1%	0.01	186.9 KB	3.1%	0.05
Jul 3 08:00:00 - 08:59:59	45	2.7%	0.01	120.0 KB	2.0%	0.03
Jul 3 09:00:00 - 09:59:59	55	3.3%	0.02	172.2 KB	2.9%	0.05
Jul 3 10:00:00 - 10:59:59	58	3.5%	0.02	175.3 KB	2.9%	0.05
Jul 3 11:00:00 - 11:59:59	64	3.9%	0.02	182.6 KB	3.0%	0.05
Jul 3 12:00:00 - 12:59:59	65	3.9%	0.02	200.3 KB	3.3%	0.06
Jul 3 13:00:00 - 13:59:59	47	2.8%	0.01	118.9 KB	2.0%	0.03
Jul 3 14:00:00 - 14:59:59	91	5.5%	0.03	312.0 KB	5.2%	0.09
Jul 3 15:00:00 - 15:59:59	78	4.7%	0.02	228.9 KB	3.8%	0.06
Jul 3 16:00:00 - 16:59:59	79	4.8%	0.02	278.2 KB	4.6%	0.08
Jul 3 17:00:00 - 17:59:59	144	8.7%	0.04	1.1 MB	19.2%	0.32
Jul 3 18:00:00 - 18:59:59	86	5.2%	0.02	364.3 KB	6.1%	0.10
Jul 3 19:00:00 - 19:59:59	105	6.3%	0.03	366.8 KB	6.1%	0.10
Jul 3 20:00:00 - 20:59:59	90	5.4%	0.03	232.4 KB	3.9%	0.06
Jul 3 21:00:00 - 21:59:59	89	5.4%	0.02	286.5 KB	4.8%	0.08
Jul 3 22:00:00 - 22:59:59	97	5.8%	0.03	327.1 KB	5.5%	0.09
Jul 3 23:00:00 - 23:59:59	70	4.2%	0.02	194.6 KB	3.2%	0.05
Jul 4 00:00:00 - 00:59:59	61	3.7%	0.02	223.5 KB	3.7%	0.06
Jul 4 01:00:00 - 01:59:59	58	3.5%	0.02	180.9 KB	3.0%	0.05
Jul 4 02:00:00 - 02:59:59	64	3.9%	0.02	226.8 KB	3.8%	0.06
Jul 4 03:00:00 - 03:59:59	63	3.8%	0.02	146.8 KB	2.5%	0.04
Jul 4 04:00:00 - 04:15:01	7	0.4%	0.01	18.3 KB	0.3%	0.02
TOTAL: 24:00:00	1659	100.0%	0.02	5.8 MB	100.0%	0.07

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	524	315	209
2	news.glou.org	51	0	51
3	gegeweb-out.news.tnetconsulting.net	5	0	5
4	nntp.terraraq.uk	5	0	5
5	news.samoylyk.net	2	0	2
TOTAL: 5		587	315	272

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	278
linux.kernel.git	34
alt.france	2
news.test	1
TOTAL: 4	315

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	108
Too many newsgroups	93
Bad path (news.giganews.com.POSTED)	63
HTML Multipart	3
Cancels abusifs	1
No Cancel-Key matches Cancel-Lock. target=<68662cd0$0$10609$426a74cc@news.free.fr>	1
Cancel of <1046bef$8md8$1@dont-email.me> without Cancel-Key	1
Cancel of <1046ggi$ekn6$1@dont-email.me> without Cancel-Key	1
TOTAL: 8	271

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	nntp.terraraq.uk	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	2325	20	1570	20	2	0%	23:58:31
2	news.gegeweb.org	1317	15	1233	1	0	1%	23:59:28
3	news.tnetconsulting.net	2382	11	1637	10	2	0%	23:59:57
4	news.nntp4.net	918	1	840	0	0	0%	23:12:10
5	nntp.terraraq.uk	1239	0	1155	1	0	0%	23:59:26
6	news.aioe.org	0	0	0	0	1380	0%	24:00:00
7	news.samoylyk.net	1743	0	1499	1	0	0%	23:59:57
8	news.pasdenom.info	1457	0	1368	13	0	0%	23:59:31
TOTAL: 8		11381	47	9302	46	1384	0%	191:09:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	79.5 KB	17.2 KB	96.7 KB	4.6 KB	23:59:57
2	i2pn.org	50.5 KB	66.3 KB	116.8 KB	2.9 KB	23:58:31
3	news.gegeweb.org	41.5 KB	1.1 KB	42.7 KB	2.7 KB	23:59:28
4	news.nntp4.net	1.0 KB	0.0 KB	1.0 KB	1.0 KB	23:12:10
5	nntp.terraraq.uk	0.0 KB	1.0 KB	1.0 KB	1.0 KB	23:59:26
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.samoylyk.net	0.0 KB	1.0 KB	1.0 KB	1.0 KB	23:59:57
8	news.pasdenom.info	0.0 KB	13.6 KB	13.6 KB	1.0 KB	23:59:31
TOTAL: 8		172.6 KB	100.3 KB	272.9 KB	2.9 KB	191:09:00

NNRP readership statistics:

System		Conn	Elapsed
1	212.102.40.218	3	00:00:00
2	188.150.216.162.bc.googleusercontent.com	2	00:00:03
3	azpdsg0ipgwx.stretchoid.com	1	00:00:00
4	mail.gegeweb.eu	288	00:01:52
5	147.185.132.53	2	00:00:03
6	147.185.132.253	1	00:00:00
7	198.235.24.116	1	00:00:01
8	2603:6010:6800:36:1a03:73ff:fe28:be0f	2	00:00:01
9	199.45.154.126	3	00:00:00
10	206.168.34.119	1	00:00:36
11	206.168.34.55	1	00:00:30
12	205.210.31.157	3	00:00:07
13	205.210.31.169	1	00:00:01
14	pool-100-38-239-226.nycmny.fios.verizon.net	1	00:00:12
15	147.185.133.19	1	00:00:08
16	205.210.31.7	3	00:02:59
17	ns31410048.ip-145-239-154.eu	2	00:00:00
18	172-236-228-227.ip.linodeusercontent.com	1	00:00:00
TOTAL: 18		317	00:06:41

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	2	00:00:01
2	unresolved	20	00:04:28
3	*.stretchoid.com	1	00:00:00
4	*.nycmny.fios.verizon.net	1	00:00:12
5	*.ip.linodeusercontent.com	1	00:00:00
6	*.ip-145-239-154.eu	2	00:00:00
7	*.gegeweb.eu	288	00:01:52
8	*.150.216.162.bc.googleusercontent.com	2	00:00:03
TOTAL: 8		317	00:06:41

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
205.210.31.7	0.061	0.056	0.000	00:02:59
mail.gegeweb.eu	4.568	4.139	1.705	00:01:52
206.168.34.119	0.029	0.003	0.000	00:00:36
206.168.34.55	0.017	0.013	0.000	00:00:30
pool-100-38-239-226.nycmny.fios.verizon.net	0.357	0.658	0.000	00:00:12
147.185.133.19	0.061	0.021	0.000	00:00:08
205.210.31.157	0.079	0.044	0.000	00:00:07
188.150.216.162.bc.googleusercontent.com	0.144	0.016	0.000	00:00:03
147.185.132.53	0.117	0.042	0.000	00:00:03
2603:6010:6800:36:1a03:73ff:fe28:be0f	0.020	0.028	0.000	00:00:01
205.210.31.169	0.016	0.036	0.000	00:00:01
198.235.24.116	0.028	0.016	0.000	00:00:01
172-236-228-227.ip.linodeusercontent.com	0.029	0.011	0.000	00:00:00
ns31410048.ip-145-239-154.eu	0.040	0.021	0.000	00:00:00
212.102.40.218	0.071	0.051	0.000	00:00:00
199.45.154.126	0.079	0.033	0.000	00:00:00
azpdsg0ipgwx.stretchoid.com	0.017	0.009	0.000	00:00:00
147.185.132.253	0.064	0.013	0.000	00:00:00
TOTAL: 18	5.797	5.210	1.705	00:06:41

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.185.133.19	4
147.185.132.253	4
147.185.132.53	4
188.150.216.162.bc.googleusercontent.com	4
ns31410048.ip-145-239-154.eu	1
205.210.31.7	1
azpdsg0ipgwx.stretchoid.com	1
205.210.31.157	1
206.168.34.55	1
TOTAL: 9	21

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
205.210.31.7	2	3
205.210.31.157	2	3
188.150.216.162.bc.googleusercontent.com	1	2
147.185.132.53	1	2
TOTAL: 4	6	12

Daily Usenet report for news.niel.me

Jul 3 04:15:01 -- Jul 4 04:15:01