Daily Usenet report for news.niel.me: Jun 14 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 15 01:30:03 rpi-studio innd: filter: No Cancel-Key[sha1:wAuUtSutnJ4XBPRAeEUHdlxAeQw=] matches Cancel-Lock[sha1:/XP+wx5hAKu4fNNTckqjBIR1vQI=] target=<de-gruppen-finden-08.06.2024@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2356	29.5%	437.9 KB	33.9%
innd	2136	26.7%	368.0 KB	28.5%
nnrpd	1764	22.1%	200.6 KB	15.5%
innfeed	1730	21.7%	284.0 KB	22.0%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	7988	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	13159	59.7%
Negative hits	6530	29.6%
Do not exist	2337	10.6%
Cache misses	3	0.0%
TOTAL: 4	22029	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	5	1.000	1.200	2.000
article cancel	00:00:00.003	0.0%	6	0.000	0.500	2.000
article cleanup	00:00:00.056	0.0%	2348	0.000	0.024	0.400
article logging	00:00:00.162	0.0%	2348	0.000	0.069	0.333
article parse	00:00:00.282	0.0%	5728	0.000	0.049	0.167
article write	00:00:01.039	0.0%	2125	0.000	0.489	1.200
artlog/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:00:00.125	0.0%	23142	0.000	0.005	0.027
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.003	0.0%	20	0.000	0.150	1.000
hishave/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	5	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.286	0.0%	22097	0.000	0.013	0.073
history sync	00:00:00.052	0.0%	362	0.000	0.144	1.000
history write	00:00:00.376	0.0%	2346	0.000	0.160	0.526
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:38.468	100.0%	25591	1233.302	3377.690	18764.844
nntp read	00:00:00.601	0.0%	24935	0.000	0.024	0.082
overview write	00:00:00.929	0.0%	2125	0.000	0.437	3.444
perl filter	00:00:09.427	0.0%	2346	2.135	4.018	9.500
python filter	00:00:00.009	0.0%	2346	0.000	0.004	0.062
site send	00:00:00.966	0.0%	4359	0.000	0.222	0.548
TOTAL: 24:01:00.806	24:00:52.790	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.012	0.0%	2163	0.000	0.006	0.200
article prepare	00:00:00.000	0.0%	592	0.000	0.000	0.000
article read	00:00:00.030	0.0%	145	0.000	0.207	8.000
backlog stats	00:00:00.168	0.0%	31725	0.000	0.005	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.375	0.0%	16810	0.000	0.022	0.055
data write	00:00:00.788	0.0%	14919	0.000	0.053	0.172
idle	23:51:32.719	100.0%	31725	1284.450	2707.414	6233.175
status file	00:00:00.444	0.0%	207	0.000	2.145	10.000
TOTAL: 23:51:41.974	23:51:34.536	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:40.679	29.9%	1442	19.600	28.210	9146.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.099	0.1%	3200	0.000	0.031	0.273
TOTAL: 00:02:16.240	00:00:40.778	29.9%	-	-	-	-

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	2	2019	951	1066	2	47%	23:58:39
2	i2pn.org	2	3912	373	3537	2	9%	43:28:36
3	gegeweb-out.news.tnetconsulting.net	1	3087	357	2630	100	11%	39:36:37
4	news.nntp4.net	1	3004	199	2761	44	6%	29:46:39
5	news.samoylyk.net	2	2101	149	1952	0	7%	22:40:08
6	news.glou.org	1	1842	87	1657	98	4%	23:59:56
7	usenet.pasdenom.info	3	483	53	424	6	10%	11:57:10
8	nntp.terraraq.uk	1	196	7	189	0	3%	04:42:05
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	16644	2176	14216	252	13%	200:09:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.1 MB	0.0 KB	6.6 KB	3.1 MB	99%	3.4 KB
2	gegeweb-out.news.tnetconsulting.net	1.2 MB	13.9 KB	401.2 KB	1.6 MB	74%	3.5 KB
3	i2pn.org	1.2 MB	0.0 KB	2.1 KB	1.2 MB	99%	3.2 KB
4	news.nntp4.net	1.1 MB	6.9 KB	167.8 KB	1.3 MB	87%	5.6 KB
5	news.samoylyk.net	1.1 MB	0.0 KB	0.0 KB	1.1 MB	100%	7.4 KB
6	news.glou.org	244.9 KB	0.0 KB	250.4 KB	495.2 KB	49%	2.7 KB
7	usenet.pasdenom.info	132.9 KB	0.0 KB	10.0 KB	142.8 KB	93%	2.4 KB
8	nntp.terraraq.uk	15.5 KB	0.0 KB	0.0 KB	15.5 KB	100%	2.2 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		8.1 MB	20.8 KB	838.1 KB	8.9 MB	90%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 14 04:15:01 - 04:59:59	49	2.3%	0.02	167.3 KB	2.1%	0.06
Jun 14 05:00:00 - 05:59:59	43	2.0%	0.01	152.0 KB	1.9%	0.04
Jun 14 06:00:00 - 06:59:59	58	2.7%	0.02	321.2 KB	4.1%	0.09
Jun 14 07:00:00 - 07:59:59	36	1.7%	0.01	104.1 KB	1.3%	0.03
Jun 14 08:00:00 - 08:59:59	58	2.7%	0.02	186.7 KB	2.4%	0.05
Jun 14 09:00:00 - 09:59:59	70	3.3%	0.02	333.3 KB	4.2%	0.09
Jun 14 10:00:00 - 10:59:59	100	4.7%	0.03	497.9 KB	6.3%	0.14
Jun 14 11:00:00 - 11:59:59	90	4.2%	0.03	448.1 KB	5.7%	0.12
Jun 14 12:00:00 - 12:59:59	71	3.3%	0.02	260.0 KB	3.3%	0.07
Jun 14 13:00:00 - 13:59:59	118	5.6%	0.03	363.3 KB	4.6%	0.10
Jun 14 14:00:00 - 14:59:59	76	3.6%	0.02	402.4 KB	5.1%	0.11
Jun 14 15:00:00 - 15:59:59	77	3.6%	0.02	306.2 KB	3.9%	0.09
Jun 14 16:00:00 - 16:59:59	110	5.2%	0.03	462.7 KB	5.9%	0.13
Jun 14 17:00:00 - 17:59:59	83	3.9%	0.02	232.5 KB	3.0%	0.06
Jun 14 18:00:00 - 18:59:59	104	4.9%	0.03	281.5 KB	3.6%	0.08
Jun 14 19:00:00 - 19:59:59	129	6.1%	0.04	419.3 KB	5.3%	0.12
Jun 14 20:00:00 - 20:59:59	137	6.5%	0.04	446.5 KB	5.7%	0.12
Jun 14 21:00:00 - 21:59:59	102	4.8%	0.03	355.2 KB	4.5%	0.10
Jun 14 22:00:00 - 22:59:59	133	6.3%	0.04	555.6 KB	7.1%	0.15
Jun 14 23:00:00 - 23:59:59	130	6.1%	0.04	391.6 KB	5.0%	0.11
Jun 15 00:00:00 - 00:59:59	117	5.5%	0.03	370.0 KB	4.7%	0.10
Jun 15 01:00:00 - 01:59:59	97	4.6%	0.03	391.6 KB	5.0%	0.11
Jun 15 02:00:00 - 02:59:59	63	3.0%	0.02	181.6 KB	2.3%	0.05
Jun 15 03:00:00 - 03:59:59	58	2.7%	0.02	188.0 KB	2.4%	0.05
Jun 15 04:00:00 - 04:15:01	13	0.6%	0.01	46.9 KB	0.6%	0.05
TOTAL: 24:00:00	2122	100.0%	0.02	7.7 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.glou.org	98	0	98
2	gegeweb-out.news.tnetconsulting.net	60	0	60
3	news.nntp4.net	47	2	45
4	usenet.pasdenom.info	15	0	15
5	feed-out.gegeweb.org	2	0	2
6	nntp.terraraq.uk	1	0	1
TOTAL: 6		223	2	221

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	124
Bad path (news.giganews.com.POSTED)	63
Too many newsgroups	12
EMP (md5)	7
Cancel of <v4hkd5$3vgem$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hi3m$3vddc$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4i627$71j$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hjbb$3vf4i$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hkjo$3vglj$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4iinp$lp9$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hkpl$3vh3d$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hahf$3v3je$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hd2g$3v6ij$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hahh$3v3je$3@i2pn2.org> without Cancel-Key	1
Cancels abusifs	1
Cancel of <v4ija2$mcg$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4ijkq$mlm$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hdo7$3v7m3$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4hd0r$3v6ha$1@i2pn2.org> without Cancel-Key	1
TOTAL: 19	221

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1208	80	1087	0	0	6%	23:53:18
2	i2pn.org	2868	35	1839	14	16	1%	23:59:56
3	news.tnetconsulting.net	3529	12	2102	7	29	0%	23:58:33
4	news.nntp4.net	1875	0	1803	0	0	0%	23:55:14
5	news.samoylyk.net	2385	0	1970	0	4	0%	23:59:56
6	nntp.terraraq.uk	1357	0	1233	0	0	0%	23:57:09
7	news.aioe.org	0	0	0	0	1711	0%	23:59:59
8	news.pasdenom.info	1875	0	1663	0	0	0%	23:59:52
TOTAL: 8		15097	127	11697	21	1760	0%	191:43:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	291.2 KB	0.0 KB	291.2 KB	3.6 KB	23:53:18
2	i2pn.org	169.8 KB	64.4 KB	234.2 KB	4.8 KB	23:59:56
3	news.tnetconsulting.net	31.7 KB	25.7 KB	57.3 KB	3.0 KB	23:58:33
4	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:55:14
5	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:57:09
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:52
TOTAL: 8		492.7 KB	90.1 KB	582.7 KB	3.9 KB	191:43:57

NNRP readership statistics:

System		Conn	Elapsed
1	194.165.16.76	1	00:00:00
2	24.211.203.35.bc.googleusercontent.com	1	00:00:01
3	55.149.216.162.bc.googleusercontent.com	1	00:00:01
4	mail.gegeweb.eu	288	00:01:58
5	dexterous.monitoring.internet-measurement.com	1	00:00:02
6	azpdssc44.stretchoid.com	1	00:00:09
7	136.210.203.35.bc.googleusercontent.com	1	00:00:00
TOTAL: 7		294	00:02:15

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	1	00:00:02
2	*.gegeweb.eu	288	00:01:58
3	unresolved	1	00:00:00
4	*.stretchoid.com	1	00:00:09
5	*.149.216.162.bc.googleusercontent.com	1	00:00:01
6	*.211.203.35.bc.googleusercontent.com	1	00:00:01
7	*.210.203.35.bc.googleusercontent.com	1	00:00:00
TOTAL: 7		294	00:02:15

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.433	3.998	1.455	00:01:58
azpdssc44.stretchoid.com	0.029	0.017	0.000	00:00:09
dexterous.monitoring.internet-measurement.com	0.024	0.024	0.000	00:00:02
55.149.216.162.bc.googleusercontent.com	0.074	0.016	0.000	00:00:01
24.211.203.35.bc.googleusercontent.com	0.059	0.019	0.000	00:00:01
136.210.203.35.bc.googleusercontent.com	0.061	0.028	0.000	00:00:00
194.165.16.76	0.051	0.011	0.000	00:00:00
TOTAL: 7	4.731	4.113	1.455	00:02:15

NNRP unrecognized commands (by host):

System	Conn
136.210.203.35.bc.googleusercontent.com	3
55.149.216.162.bc.googleusercontent.com	3
24.211.203.35.bc.googleusercontent.com	3
TOTAL: 3	9

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	3
User-Agent: Expanse, a Palo Alto Network...	3
Host: 45.13.105.36:563	3
TOTAL: 3	9

Daily Usenet report for news.niel.me

Jun 14 04:15:01 -- Jun 15 04:15:01