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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 5 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:NAKG6uDiXEvo2jSKNFXlAN5hJPM=] matches Cancel-Lock[sha1:kdIE5lm8l8gopcnPOnWusPOagm0=] target=<de-gruppen-finden-28.09.2024@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2443	28.4%	446.2 KB	32.7%
nnrpd	2194	25.5%	246.7 KB	18.1%
innd	2152	25.0%	369.7 KB	27.1%
innfeed	1808	21.0%	303.2 KB	22.2%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	8599	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	13413	60.2%
Negative hits	6440	28.9%
Do not exist	2412	10.8%
Cache misses	2	0.0%
TOTAL: 4	22267	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.014	0.0%	13	1.000	1.077	2.000
article cancel	00:00:00.005	0.0%	14	0.000	0.357	1.000
article cleanup	00:00:00.040	0.0%	2414	0.000	0.017	0.182
article logging	00:00:00.197	0.0%	2414	0.000	0.082	0.500
article parse	00:00:00.356	0.0%	5432	0.000	0.066	0.429
article write	00:00:01.149	0.0%	2193	0.000	0.524	2.000
artlog/artcncl	00:00:00.002	0.0%	14	0.000	0.143	0.500
artlog/artcncl/perl	00:00:00.001	0.0%	13	0.000	0.077	1.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.120	0.0%	22860	0.000	0.005	0.025
hisgrep/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	13	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	21	0.000	0.048	1.000
hishave/artcncl	00:00:00.001	0.0%	14	0.000	0.071	0.500
hishave/artcncl/perl	00:00:00.000	0.0%	13	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.344	0.0%	22249	0.000	0.015	0.089
history sync	00:00:00.065	0.0%	398	0.000	0.163	1.000
history write	00:00:00.426	0.0%	2413	0.000	0.177	0.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:57.319	100.0%	25244	1228.020	3424.866	20031.833
nntp read	00:00:00.673	0.0%	24257	0.000	0.028	0.080
overview write	00:00:00.746	0.0%	2193	0.000	0.340	7.400
perl filter	00:00:08.554	0.0%	2413	1.658	3.545	14.500
python filter	00:00:00.017	0.0%	2413	0.000	0.007	0.143
site send	00:00:01.045	0.0%	4436	0.000	0.236	0.519
TOTAL: 24:01:18.916	24:01:11.075	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.019	0.0%	2329	0.000	0.008	0.167
article prepare	00:00:00.001	0.0%	576	0.000	0.002	0.125
article read	00:00:00.027	0.0%	144	0.000	0.188	8.000
backlog stats	00:00:00.159	0.0%	31854	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.416	0.0%	16809	0.000	0.025	0.104
data write	00:00:00.821	0.0%	15039	0.000	0.055	0.133
idle	23:59:48.126	100.0%	31854	1210.790	2712.002	9845.689
status file	00:00:00.430	0.0%	218	0.000	1.972	5.500
TOTAL: 23:59:54.868	23:59:49.999	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:57:58.306	93.4%	1572	7.500	2212.663	600039.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.166	0.0%	3640	0.000	0.046	3.333
TOTAL: 01:02:05.992	00:57:58.472	93.4%	-	-	-	-

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	feed-out.gegeweb.org	3	2157	871	1286	0	40%	23:58:07
2	news.nntp4.net	2	2200	362	1738	100	16%	22:05:41
3	news.samoylyk.net	2	2496	339	2157	0	13%	26:12:02
4	gegeweb-out.news.tnetconsulting.net	1	2046	268	1717	61	13%	25:19:43
5	i2pn.org	1	2164	198	1966	0	9%	23:24:58
6	usenet.pasdenom.info	7	1198	91	1099	8	7%	22:12:43
7	nntp.terraraq.uk	3	1949	48	1900	1	2%	22:23:08
8	localhost	25	0	0	0	0	0%	00:00:00
9	news.glou.org	1	1	0	1	0	0%	00:05:27
TOTAL: 9		45	14211	2177	11864	170	15%	165:41:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	2.8 MB	0.0 KB	0.0 KB	2.8 MB	100%	3.3 KB
2	news.samoylyk.net	2.2 MB	0.0 KB	0.0 KB	2.2 MB	100%	6.5 KB
3	news.nntp4.net	1.6 MB	0.0 KB	359.0 KB	1.9 MB	81%	4.2 KB
4	gegeweb-out.news.tnetconsulting.net	955.1 KB	0.0 KB	238.3 KB	1.2 MB	80%	3.6 KB
5	i2pn.org	660.5 KB	0.0 KB	0.0 KB	660.5 KB	100%	3.3 KB
6	usenet.pasdenom.info	188.9 KB	0.0 KB	11.6 KB	200.5 KB	94%	2.0 KB
7	nntp.terraraq.uk	115.1 KB	1.7 KB	1.7 KB	118.5 KB	97%	2.4 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
9	news.glou.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		8.4 MB	1.7 KB	610.6 KB	9.0 MB	93%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 4 04:15:01 - 04:59:59	29	1.3%	0.01	103.7 KB	1.2%	0.04
Oct 4 05:00:00 - 05:59:59	46	2.1%	0.01	155.2 KB	1.8%	0.04
Oct 4 06:00:00 - 06:59:59	39	1.8%	0.01	113.3 KB	1.3%	0.03
Oct 4 07:00:00 - 07:59:59	36	1.6%	0.01	116.1 KB	1.3%	0.03
Oct 4 08:00:00 - 08:59:59	36	1.6%	0.01	123.5 KB	1.4%	0.03
Oct 4 09:00:00 - 09:59:59	87	4.0%	0.02	436.5 KB	5.0%	0.12
Oct 4 10:00:00 - 10:59:59	73	3.3%	0.02	244.0 KB	2.8%	0.07
Oct 4 11:00:00 - 11:59:59	102	4.6%	0.03	322.0 KB	3.7%	0.09
Oct 4 12:00:00 - 12:59:59	84	3.8%	0.02	278.4 KB	3.2%	0.08
Oct 4 13:00:00 - 13:59:59	119	5.4%	0.03	360.3 KB	4.1%	0.10
Oct 4 14:00:00 - 14:59:59	121	5.5%	0.03	678.1 KB	7.8%	0.19
Oct 4 15:00:00 - 15:59:59	82	3.7%	0.02	259.6 KB	3.0%	0.07
Oct 4 16:00:00 - 16:59:59	104	4.7%	0.03	396.0 KB	4.6%	0.11
Oct 4 17:00:00 - 17:59:59	137	6.2%	0.04	593.0 KB	6.8%	0.16
Oct 4 18:00:00 - 18:59:59	99	4.5%	0.03	403.0 KB	4.6%	0.11
Oct 4 19:00:00 - 19:59:59	162	7.4%	0.04	876.7 KB	10.1%	0.24
Oct 4 20:00:00 - 20:59:59	114	5.2%	0.03	366.9 KB	4.2%	0.10
Oct 4 21:00:00 - 21:59:59	134	6.1%	0.04	471.3 KB	5.4%	0.13
Oct 4 22:00:00 - 22:59:59	134	6.1%	0.04	396.2 KB	4.6%	0.11
Oct 4 23:00:00 - 23:59:59	115	5.2%	0.03	574.9 KB	6.6%	0.16
Oct 5 00:00:00 - 00:59:59	92	4.2%	0.03	403.5 KB	4.6%	0.11
Oct 5 01:00:00 - 01:59:59	96	4.4%	0.03	429.1 KB	4.9%	0.12
Oct 5 02:00:00 - 02:59:59	91	4.1%	0.03	362.3 KB	4.2%	0.10
Oct 5 03:00:00 - 03:59:59	59	2.7%	0.02	214.8 KB	2.5%	0.06
Oct 5 04:00:00 - 04:15:01	4	0.2%	0.00	10.0 KB	0.1%	0.01
TOTAL: 24:00:00	2195	100.0%	0.03	8.5 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	104	0	0	104
2	gegeweb-out.news.tnetconsulting.net	61	1	0	60
3	news.glou.org	45	0	0	45
4	usenet.pasdenom.info	9	0	0	9
5	nntp.terraraq.uk	1	0	1	0
6	i2pn.org	1	0	0	1
TOTAL: 6		221	1	1	219

Unwanted newsgroups [Top 20]:

Newsgroup	Count
soc.culture.iran	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	78
EMP (phn path)	67
Bad path (news.giganews.com.POSTED)	62
Cancel for rejected article	2
Too many newsgroups (meow)	2
Cancel of <c0206e8befc62b0e25922958dfbf4cb6a05e71bd@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <vdp7pb$a5el$1@dont-email.me>	1
Cancel of <6738c20373b04373f42080ff401dc582c15c2903@i2pn2.org> without Cancel-Key	1
Cancel of <5d99a02bd1425a45b11412d516b33ef58a172b69@i2pn2.org> without Cancel-Key	1
Cancels abusifs	1
Cancel of <0accfc03f49c772c20eb10533410fa8a8a5deebb@i2pn2.org> without Cancel-Key	1
Cancel of <cf36fd14218399de47cdd2c09503d47076244327@i2pn2.org> without Cancel-Key	1
Cancel of <c5f1b79a6e14890d36a7573b94f288330cf232e4@i2pn2.org> without Cancel-Key	1
TOTAL: 13	219

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3325	85	1922	0	75	2%	23:59:51
2	news.gegeweb.org	1469	42	1274	0	0	2%	23:22:32
3	news.tnetconsulting.net	3654	5	2185	1	36	0%	23:59:44
4	news.samoylyk.net	2179	2	1848	8	23	0%	23:59:56
5	news.nntp4.net	1913	1	1783	0	0	0%	23:59:02
6	news.aioe.org	0	0	0	0	1687	0%	24:00:00
7	nntp.terraraq.uk	1216	0	1184	0	0	0%	23:58:07
8	news.pasdenom.info	1705	0	1525	0	0	0%	23:59:56
TOTAL: 8		15461	135	11721	9	1821	0%	191:19:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	296.0 KB	0.0 KB	296.0 KB	3.5 KB	23:59:51
2	news.gegeweb.org	140.0 KB	0.0 KB	140.0 KB	3.3 KB	23:22:32
3	news.tnetconsulting.net	11.7 KB	1.8 KB	13.5 KB	2.3 KB	23:59:44
4	news.samoylyk.net	4.3 KB	25.7 KB	30.0 KB	3.0 KB	23:59:56
5	news.nntp4.net	3.1 KB	0.0 KB	3.1 KB	3.1 KB	23:59:02
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:58:07
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		455.2 KB	27.5 KB	482.7 KB	3.4 KB	191:19:08

NNRP readership statistics:

System		Conn	Elapsed
1	206.168.34.41	1	00:00:30
2	206.168.34.196	1	00:00:31
3	29.150.216.162.bc.googleusercontent.com	1	00:00:00
4	azpdwgb63.stretchoid.com	1	00:00:00
5	li1721-54.members.linode.com	1	00:26:54
6	nifty.monitoring.internet-measurement.com	1	00:00:05
7	172-104-137-47.ip.linodeusercontent.com	111	00:00:21
8	206.168.34.39	1	00:00:30
9	congratulated.monitoring.internet-measurement.com	1	00:00:00
10	palpatine.proxad.net	1	00:30:01
11	stupendous.monitoring.internet-measurement.com	1	00:00:00
12	147.45.112.222	2	00:00:02
13	198.235.24.108	1	00:00:00
14	199.45.154.123	3	00:00:05
15	206.168.34.218	1	00:00:38
16	equitable.monitoring.internet-measurement.com	1	00:00:02
17	fun.monitoring.internet-measurement.com	1	00:00:00
18	server-0-3.survey.inspici.com	1	00:00:04
19	mail.gegeweb.eu	288	00:01:53
20	121.210.203.35.bc.googleusercontent.com	1	00:00:01
21	147.185.132.162	1	00:00:10
22	205.210.31.205	1	00:00:10
TOTAL: 22		422	01:02:05

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.proxad.net	1	00:30:01
2	*.ip.linodeusercontent.com	111	00:00:21
3	*.150.216.162.bc.googleusercontent.com	1	00:00:00
4	*.monitoring.internet-measurement.com	5	00:00:07
5	*.members.linode.com	1	00:26:54
6	*.stretchoid.com	1	00:00:00
7	*.210.203.35.bc.googleusercontent.com	1	00:00:01
8	*.survey.inspici.com	1	00:00:04
9	*.gegeweb.eu	288	00:01:53
10	unresolved	12	00:02:40
TOTAL: 10		422	01:02:05

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
palpatine.proxad.net	0.017	0.008	0.000	00:30:01
li1721-54.members.linode.com	0.024	0.028	0.000	00:26:54
mail.gegeweb.eu	5.048	4.520	1.713	00:01:53
206.168.34.218	0.033	0.020	0.000	00:00:38
206.168.34.196	0.041	0.021	0.000	00:00:31
206.168.34.41	0.025	0.033	0.000	00:00:30
206.168.34.39	0.030	0.026	0.000	00:00:30
172-104-137-47.ip.linodeusercontent.com	2.731	1.618	0.000	00:00:21
147.185.132.162	0.032	0.012	0.000	00:00:10
205.210.31.205	0.008	0.015	0.000	00:00:10
199.45.154.123	0.063	0.067	0.000	00:00:05
nifty.monitoring.internet-measurement.com	0.055	0.004	0.000	00:00:05
server-0-3.survey.inspici.com	0.034	0.017	0.000	00:00:04
147.45.112.222	0.051	0.058	0.000	00:00:02
equitable.monitoring.internet-measurement.com	0.026	0.026	0.000	00:00:02
121.210.203.35.bc.googleusercontent.com	0.068	0.020	0.000	00:00:01
29.150.216.162.bc.googleusercontent.com	0.073	0.016	0.000	00:00:00
198.235.24.108	0.023	0.027	0.000	00:00:00
congratulated.monitoring.internet-measurement.com	0.049	0.015	0.000	00:00:00
azpdwgb63.stretchoid.com	0.030	0.021	0.000	00:00:00
TOTAL: 22	8.525	6.601	1.713	01:02:05

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
29.150.216.162.bc.googleusercontent.com	4
121.210.203.35.bc.googleusercontent.com	4
azpdwgb63.stretchoid.com	1
147.45.112.222	1
199.45.154.123	1
TOTAL: 5	11

NNRP unrecognized commands (by command):

Command	Count
User-Agent: Expanse, a Palo Alto Network...	2
GET / HTTP/1.1	2
Host: 45.13.105.36:563	2
Accept-Encoding: gzip	2
EHLO www.censys.io	1
#003	1
MGLNDD_45.13.105.36_119	1
TOTAL: 7	11

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.222	1	1
199.45.154.123	1	2
palpatine.proxad.net	1	0
TOTAL: 3	3	4

Daily Usenet report for news.niel.me

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