Daily Usenet report for news.niel.me: Jun 28 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 29 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:W9IdMMge/gkB6Qe0fudScTAGR6M=] matches Cancel-Lock[sha1:S9thP+vQONMGO4IguVE38X2WSDo=] target=<de-gruppen-finden-22.06.2024@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2625	30.3%	481.1 KB	35.3%
innd	2178	25.1%	367.3 KB	26.9%
nnrpd	2127	24.5%	232.6 KB	17.1%
innfeed	1740	20.1%	282.7 KB	20.7%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8672	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	12205	56.6%
Negative hits	6758	31.3%
Do not exist	2599	12.1%
Cache misses	2	0.0%
TOTAL: 4	21564	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	2.000
article cancel	00:00:00.001	0.0%	6	0.000	0.167	1.000
article cleanup	00:00:00.071	0.0%	2604	0.000	0.027	0.273
article logging	00:00:00.184	0.0%	2605	0.000	0.071	0.333
article parse	00:00:00.338	0.0%	7386	0.000	0.046	0.250
article write	00:00:01.079	0.0%	2255	0.000	0.478	1.143
artlog/artcncl	00:00:00.001	0.0%	6	0.000	0.167	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:00:00.139	0.0%	24320	0.000	0.006	0.043
hisgrep/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/perl	00:00:00.002	0.0%	12	0.000	0.167	1.000
hishave/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	5	0.000	0.200	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.374	0.0%	21610	0.000	0.017	0.064
history sync	00:00:00.036	0.0%	330	0.000	0.109	1.000
history write	00:00:00.407	0.0%	2604	0.000	0.156	0.417
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:50:37.354	100.0%	26896	1235.200	3191.454	19356.226
nntp read	00:00:00.678	0.0%	25995	0.000	0.026	0.087
overview write	00:00:00.788	0.0%	2255	0.000	0.349	3.600
perl filter	00:00:11.165	0.0%	2604	2.371	4.288	8.400
python filter	00:00:00.014	0.0%	2604	0.000	0.005	0.125
site send	00:00:00.776	0.0%	4602	0.000	0.169	0.500
TOTAL: 23:51:01.317	23:50:53.413	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.020	0.0%	2282	0.000	0.009	0.143
article prepare	00:00:00.003	0.0%	756	0.000	0.004	0.250
article read	00:00:00.038	0.0%	182	0.000	0.209	8.000
backlog stats	00:00:00.156	0.0%	32723	0.000	0.005	0.025
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.429	0.0%	17151	0.000	0.025	0.100
data write	00:00:00.795	0.0%	15033	0.000	0.053	0.143
idle	23:50:14.585	100.0%	32723	1118.294	2622.455	7974.215
status file	00:00:00.510	0.0%	216	0.000	2.361	8.000
TOTAL: 23:50:22.613	23:50:16.536	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:46.419	32.2%	1551	19.600	29.928	9357.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.130	0.1%	3537	0.000	0.037	0.500
TOTAL: 00:02:24.293	00:00:46.549	32.3%	-	-	-	-

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	feed-out.gegeweb.org	2	2212	935	1274	3	42%	23:59:36
2	news.samoylyk.net	1	2520	391	2105	24	15%	25:20:28
3	news.nntp4.net	2	3408	388	2925	95	11%	33:41:50
4	gegeweb-out.news.tnetconsulting.net	1	2040	213	1763	64	10%	24:30:48
5	i2pn.org	1	2078	197	1871	10	9%	23:59:57
6	usenet.pasdenom.info	3	1369	134	1199	36	9%	23:32:03
7	news.glou.org	2	2108	124	1823	161	5%	24:27:03
8	nntp.terraraq.uk	3	2086	55	2029	2	2%	29:02:45
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	17821	2437	14989	395	13%	208:34:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.4 MB	0.0 KB	19.8 KB	3.5 MB	99%	3.8 KB
2	news.samoylyk.net	2.7 MB	0.0 KB	57.6 KB	2.8 MB	97%	6.9 KB
3	news.nntp4.net	1.7 MB	0.0 KB	384.3 KB	2.1 MB	81%	4.4 KB
4	gegeweb-out.news.tnetconsulting.net	738.2 KB	0.0 KB	197.8 KB	936.0 KB	78%	3.4 KB
5	i2pn.org	605.9 KB	0.0 KB	82.8 KB	688.8 KB	87%	3.3 KB
6	news.glou.org	484.7 KB	0.0 KB	529.9 KB	1014.6 KB	47%	3.6 KB
7	usenet.pasdenom.info	329.7 KB	0.0 KB	74.4 KB	404.1 KB	81%	2.4 KB
8	nntp.terraraq.uk	278.9 KB	5.8 KB	7.2 KB	291.9 KB	95%	5.1 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		10.3 MB	5.8 KB	1.3 MB	11.6 MB	88%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 28 04:15:02 - 04:59:59	46	2.0%	0.02	165.3 KB	1.7%	0.06
Jun 28 05:00:00 - 05:59:59	83	3.7%	0.02	266.3 KB	2.7%	0.07
Jun 28 06:00:00 - 06:59:59	45	2.0%	0.01	182.0 KB	1.9%	0.05
Jun 28 07:00:00 - 07:59:59	54	2.4%	0.01	389.0 KB	4.0%	0.11
Jun 28 08:00:00 - 08:59:59	55	2.4%	0.02	229.8 KB	2.4%	0.06
Jun 28 09:00:00 - 09:59:59	68	3.0%	0.02	527.0 KB	5.4%	0.15
Jun 28 10:00:00 - 10:59:59	74	3.3%	0.02	319.6 KB	3.3%	0.09
Jun 28 11:00:00 - 11:59:59	72	3.2%	0.02	226.9 KB	2.3%	0.06
Jun 28 12:00:00 - 12:59:59	66	2.9%	0.02	246.9 KB	2.5%	0.07
Jun 28 13:00:00 - 13:59:59	98	4.3%	0.03	359.2 KB	3.7%	0.10
Jun 28 14:00:00 - 14:59:59	80	3.5%	0.02	271.0 KB	2.8%	0.08
Jun 28 15:00:00 - 15:59:59	125	5.5%	0.03	407.2 KB	4.2%	0.11
Jun 28 16:00:00 - 16:59:59	115	5.1%	0.03	358.8 KB	3.7%	0.10
Jun 28 17:00:00 - 17:59:59	142	6.3%	0.04	808.6 KB	8.3%	0.22
Jun 28 18:00:00 - 18:59:59	115	5.1%	0.03	597.4 KB	6.2%	0.17
Jun 28 19:00:00 - 19:59:59	123	5.4%	0.03	367.6 KB	3.8%	0.10
Jun 28 20:00:00 - 20:59:59	172	7.6%	0.05	721.0 KB	7.4%	0.20
Jun 28 21:00:00 - 21:59:59	125	5.5%	0.03	540.0 KB	5.6%	0.15
Jun 28 22:00:00 - 22:59:59	158	7.0%	0.04	1016.8 KB	10.5%	0.28
Jun 28 23:00:00 - 23:59:59	129	5.7%	0.04	613.6 KB	6.3%	0.17
Jun 29 00:00:00 - 00:59:59	108	4.8%	0.03	381.7 KB	3.9%	0.11
Jun 29 01:00:00 - 01:59:59	85	3.8%	0.02	294.5 KB	3.0%	0.08
Jun 29 02:00:00 - 02:59:59	65	2.9%	0.02	213.9 KB	2.2%	0.06
Jun 29 03:00:00 - 03:59:59	47	2.1%	0.01	163.4 KB	1.7%	0.05
Jun 29 04:00:00 - 04:15:02	9	0.4%	0.01	23.9 KB	0.2%	0.03
TOTAL: 24:00:00	2259	100.0%	0.03	9.5 MB	100.0%	0.11

Sites sending bad articles:

Server		Total	Other
1	news.glou.org	161	161
2	news.nntp4.net	73	73
3	gegeweb-out.news.tnetconsulting.net	67	67
4	usenet.pasdenom.info	28	28
5	news.samoylyk.net	21	21
6	feed-out.gegeweb.org	3	3
7	i2pn.org	2	2
TOTAL: 7		355	355

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	215
Bad path (news.giganews.com.POSTED)	74
Too many newsgroups	33
EMP (md5)	23
Too many newsgroups (meow)	1
Cancel of <v5ncjk$1e66m$1@i2pn2.org> without Cancel-Key	1
HTML Multipart	1
Cancel of <v5n0uh$1dn1g$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5ncaa$1e5rq$1@i2pn2.org> without Cancel-Key	1
Cancels abusifs	1
Cancel of <v5ng5b$1earc$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5mvpf$1dlhh$1@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <v5m67b$3ak14$1@dont-email.me>	1
TOTAL: 13	354

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	gegeweb-out.news.tnetconsulting.net	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3321	94	1944	5	11	2%	23:59:57
2	news.gegeweb.org	1378	68	1265	0	0	4%	23:57:41
3	news.tnetconsulting.net	3788	7	2243	9	13	0%	23:56:39
4	news.pasdenom.info	2022	4	1800	0	0	0%	23:59:57
5	news.samoylyk.net	2235	2	1875	0	4	0%	23:59:57
6	nntp.terraraq.uk	304	0	275	0	1196	0%	23:57:52
7	news.aioe.org	0	0	0	0	1949	0%	24:00:00
8	news.nntp4.net	2081	0	1959	0	0	0%	23:55:18
TOTAL: 8		15129	175	11361	14	3173	1%	191:47:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	390.2 KB	25.9 KB	416.1 KB	4.2 KB	23:59:57
2	news.gegeweb.org	192.7 KB	0.0 KB	192.7 KB	2.8 KB	23:57:41
3	news.tnetconsulting.net	31.3 KB	33.6 KB	64.9 KB	4.1 KB	23:56:39
4	news.pasdenom.info	11.5 KB	0.0 KB	11.5 KB	2.9 KB	23:59:57
5	news.samoylyk.net	9.1 KB	0.0 KB	9.1 KB	4.5 KB	23:59:57
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:57:52
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
8	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:55:18
TOTAL: 8		634.8 KB	59.4 KB	694.2 KB	3.7 KB	191:47:21

NNRP readership statistics:

System		Conn	Elapsed
1	4.150.216.162.bc.googleusercontent.com	1	00:00:00
2	merciful.monitoring.internet-measurement.com	1	00:00:00
3	masterful.monitoring.internet-measurement.com	1	00:00:00
4	equitable.monitoring.internet-measurement.com	1	00:00:06
5	158.149.216.162.bc.googleusercontent.com	1	00:00:00
6	45.227.254.49	1	00:00:01
7	azpdwsb60.stretchoid.com	1	00:00:09
8	vivid.monitoring.internet-measurement.com	1	00:00:00
9	remarkable.monitoring.internet-measurement.com	1	00:00:06
10	mail.gegeweb.eu	288	00:01:24
11	2607:ff10:c8:594::5	1	00:00:01
12	194.165.16.10	1	00:00:00
13	172-104-137-47.ip.linodeusercontent.com	103	00:00:22
14	enthusiastic.monitoring.internet-measurement.com	2	00:00:00
15	147.185.133.123	1	00:00:09
TOTAL: 15		405	00:02:23

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.149.216.162.bc.googleusercontent.com	1	00:00:00
2	?	1	00:00:01
3	unresolved	3	00:00:11
4	*.monitoring.internet-measurement.com	7	00:00:13
5	*.stretchoid.com	1	00:00:09
6	*.ip.linodeusercontent.com	103	00:00:22
7	*.150.216.162.bc.googleusercontent.com	1	00:00:00
8	*.gegeweb.eu	288	00:01:24
TOTAL: 8		405	00:02:23

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	4.607	4.136	1.489	00:01:24
172-104-137-47.ip.linodeusercontent.com	2.728	1.495	0.000	00:00:22
azpdwsb60.stretchoid.com	0.032	0.020	0.000	00:00:09
147.185.133.123	0.029	0.013	0.000	00:00:09
remarkable.monitoring.internet-measurement.com	0.059	0.025	0.000	00:00:06
equitable.monitoring.internet-measurement.com	0.043	0.018	0.000	00:00:06
2607:ff10:c8:594::5	0.040	0.012	0.000	00:00:01
45.227.254.49	0.025	0.025	0.000	00:00:01
194.165.16.10	0.040	0.024	0.000	00:00:00
158.149.216.162.bc.googleusercontent.com	0.028	0.012	0.000	00:00:00
vivid.monitoring.internet-measurement.com	0.043	0.021	0.000	00:00:00
4.150.216.162.bc.googleusercontent.com	0.012	0.012	0.000	00:00:00
enthusiastic.monitoring.internet-measurement.com	0.079	0.050	0.000	00:00:00
TOTAL: 13	7.765	5.863	1.489	00:02:23

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
remarkable.monitoring.internet-measurement.com	6
4.150.216.162.bc.googleusercontent.com	4
147.185.133.123	4
158.149.216.162.bc.googleusercontent.com	4
45.227.254.49	1
TOTAL: 5	19

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	4
Accept-Encoding: gzip	4
User-Agent: Expanse, a Palo Alto Network...	3
Host: 45.13.105.36:563	2
User-Agent: Mozilla/5.0 (compatible; Int...	1
Accept: /	1
Connection: close	1
#003	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
Host: 45.13.105.36:119	1
TOTAL: 10	19

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.49	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Jun 28 04:15:02 -- Jun 29 04:15:02