Daily Usenet report for news.niel.me: Sep 16 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 17 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:i4ACmAQBBfSOu+AR1WnCcizxt0U=] matches Cancel-Lock[sha1:gkVWByO3/IIPUfx3D41//hcd/ms=] target=<de-gruppen-finden-10.09.2022@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3738	40.1%	726.1 KB	45.7%
innd	2377	25.5%	419.9 KB	26.4%
innfeed	1719	18.5%	299.7 KB	18.9%
nnrpd	1476	15.8%	142.0 KB	8.9%
nocem	3	0.0%	0.3 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	9314	100.0%	1.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	24771	68.1%
Negative hits	7931	21.8%
Do not exist	3671	10.1%
Cache misses	3	0.0%
TOTAL: 4	36376	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.030	0.0%	18	0.000	1.667	9.000
article cancel	00:00:00.002	0.0%	22	0.000	0.091	1.000
article cleanup	00:00:00.081	0.0%	3687	0.000	0.022	0.273
article logging	00:00:00.252	0.0%	3702	0.000	0.068	0.357
article parse	00:00:00.665	0.0%	7492	0.000	0.089	0.440
article write	00:00:03.487	0.0%	3403	0.150	1.025	66.391
artlog/artcncl	00:00:00.000	0.0%	22	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	18	0.000	0.000	0.000
data move	00:00:00.101	0.0%	40122	0.000	0.003	0.013
hisgrep/artcncl	00:00:00.001	0.0%	21	0.000	0.048	1.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	18	0.000	0.056	1.000
hisgrep/perl	00:00:00.000	0.0%	20	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	22	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	18	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.497	0.0%	36458	0.000	0.014	0.041
history sync	00:00:00.051	0.0%	409	0.000	0.125	2.000
history write	00:00:00.831	0.0%	3683	0.000	0.226	1.069
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	24:00:29.920	100.0%	40720	780.108	2122.542	7507.675
nntp read	00:00:01.304	0.0%	40245	0.004	0.032	0.061
overview write	00:00:02.556	0.0%	3403	0.071	0.751	5.048
perl filter	00:00:16.484	0.0%	3683	3.025	4.476	13.409
python filter	00:00:00.000	0.0%	3683	0.000	0.000	0.000
site send	00:00:00.829	0.0%	6854	0.000	0.121	0.425
TOTAL: 24:01:08.873	24:00:57.092	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	3400	0.000	0.011	0.167
article prepare	00:00:00.000	0.0%	632	0.000	0.000	0.000
article read	00:00:00.021	0.0%	132	0.000	0.159	6.000
backlog stats	00:00:00.122	0.0%	52930	0.000	0.002	0.014
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.743	0.0%	31567	0.000	0.024	0.065
data write	00:00:01.751	0.0%	28169	0.026	0.062	0.121
idle	23:59:26.689	100.0%	52930	840.258	1631.715	4032.812
status file	00:00:00.479	0.0%	176	1.000	2.722	9.000
TOTAL: 23:59:33.076	23:59:29.841	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:33.422	31.5%	1448	11.400	23.081	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.100	0.1%	3185	0.000	0.031	0.273
TOTAL: 00:01:45.974	00:00:33.522	31.6%	-	-	-	-

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-out.aioe.org	2	3523	1413	2054	56	40%	24:55:06
2	i2pn.org	2	3235	1273	1835	127	39%	22:25:24
3	news.glou.org	1	3363	305	3045	13	9%	26:21:15
4	feed-out.gegeweb.org	4	2108	137	1971	0	6%	24:56:51
5	nntp.terraraq.uk	1	3265	80	3133	52	2%	23:59:55
6	usenet.pasdenom.info	1	1702	75	1622	5	4%	13:10:45
7	gegeweb-out.news.tnetconsulting.net	2	3350	55	3290	5	1%	23:58:37
8	news.nntp4.net	1	3545	4	3504	37	0%	23:59:56
9	localhost	26	0	0	0	0	0%	00:00:00
10	news.samoylyk.net	1	44	0	44	0	0%	00:27:43
TOTAL: 10		41	24135	3342	20498	295	13%	184:15:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	4.7 MB	21.9 KB	189.3 KB	4.9 MB	95%	3.4 KB
2	i2pn.org	4.0 MB	21.9 KB	419.5 KB	4.4 MB	90%	3.2 KB
3	news.glou.org	3.1 MB	21.9 KB	51.3 KB	3.1 MB	97%	10.1 KB
4	nntp.terraraq.uk	776.3 KB	0.0 KB	127.8 KB	904.1 KB	85%	6.8 KB
5	feed-out.gegeweb.org	499.5 KB	0.0 KB	0.0 KB	499.5 KB	100%	3.6 KB
6	usenet.pasdenom.info	184.8 KB	0.0 KB	7.4 KB	192.2 KB	96%	2.4 KB
7	gegeweb-out.news.tnetconsulting.net	163.9 KB	0.0 KB	75.9 KB	239.8 KB	68%	4.0 KB
8	news.nntp4.net	81.8 KB	0.0 KB	98.7 KB	180.5 KB	45%	4.4 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
10	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		13.5 MB	65.7 KB	969.9 KB	14.5 MB	93%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 16 04:15:01 - 04:59:59	84	2.5%	0.03	272.9 KB	1.8%	0.10
Sep 16 05:00:00 - 05:59:59	88	2.6%	0.02	298.1 KB	2.0%	0.08
Sep 16 06:00:00 - 06:59:59	98	2.9%	0.03	403.2 KB	2.7%	0.11
Sep 16 07:00:00 - 07:59:59	87	2.6%	0.02	483.5 KB	3.2%	0.13
Sep 16 08:00:00 - 08:59:59	82	2.4%	0.02	271.4 KB	1.8%	0.08
Sep 16 09:00:00 - 09:59:59	123	3.6%	0.03	413.3 KB	2.7%	0.11
Sep 16 10:00:00 - 10:59:59	94	2.8%	0.03	340.7 KB	2.3%	0.09
Sep 16 11:00:00 - 11:59:59	186	5.5%	0.05	671.8 KB	4.4%	0.19
Sep 16 12:00:00 - 12:59:59	138	4.1%	0.04	399.0 KB	2.6%	0.11
Sep 16 13:00:00 - 13:59:59	126	3.7%	0.04	346.1 KB	2.3%	0.10
Sep 16 14:00:00 - 14:59:59	160	4.7%	0.04	523.8 KB	3.5%	0.15
Sep 16 15:00:00 - 15:59:59	156	4.6%	0.04	620.6 KB	4.1%	0.17
Sep 16 16:00:00 - 16:59:59	183	5.4%	0.05	718.5 KB	4.8%	0.20
Sep 16 17:00:00 - 17:59:59	182	5.4%	0.05	698.6 KB	4.6%	0.19
Sep 16 18:00:00 - 18:59:59	231	6.8%	0.06	784.6 KB	5.2%	0.22
Sep 16 19:00:00 - 19:59:59	184	5.4%	0.05	1.4 MB	9.8%	0.41
Sep 16 20:00:00 - 20:59:59	189	5.6%	0.05	676.4 KB	4.5%	0.19
Sep 16 21:00:00 - 21:59:59	182	5.4%	0.05	1.5 MB	10.1%	0.42
Sep 16 22:00:00 - 22:59:59	166	4.9%	0.05	723.9 KB	4.8%	0.20
Sep 16 23:00:00 - 23:59:59	191	5.6%	0.05	574.1 KB	3.8%	0.16
Sep 17 00:00:00 - 00:59:59	158	4.6%	0.04	644.4 KB	4.3%	0.18
Sep 17 01:00:00 - 01:59:59	112	3.3%	0.03	558.1 KB	3.7%	0.16
Sep 17 02:00:00 - 02:59:59	90	2.6%	0.03	1.2 MB	8.1%	0.34
Sep 17 03:00:00 - 03:59:59	88	2.6%	0.02	372.6 KB	2.5%	0.10
Sep 17 04:00:00 - 04:15:02	20	0.6%	0.02	99.8 KB	0.7%	0.11
TOTAL: 24:00:01	3398	100.0%	0.04	14.8 MB	100.0%	0.18

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	i2pn.org	165	0	1	164
2	news-out.aioe.org	64	1	1	62
3	news.nntp4.net	33	0	1	32
4	nntp.terraraq.uk	15	0	0	15
5	news.glou.org	13	0	1	12
6	news.samoylyk.net	7	0	0	7
7	gegeweb-out.news.tnetconsulting.net	3	2	0	1
TOTAL: 7		300	3	4	293

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.divers	2
news.test	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
Bad path (news.giganews.com.POSTED)	96
EMP (phl)	76
Too many newsgroups	47
EMP (md5)	31
EMP (phn nph)	26
HTML Multipart	1
Too many newsgroups (low_xpost)	1
TOTAL: 7	278

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	2	0	2	2
TOTAL: 1	2	0	2	2

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	8
	news.samoylyk.net	7
	TOTAL: 2	15
TOTAL: 1		15

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3741	73	1953	3	1	1%	23:59:01
2	news.tnetconsulting.net	5124	13	3374	2	1	0%	23:59:41
3	news.nntp4.net	4317	10	3378	0	0	0%	23:59:24
4	news.gegeweb.org	3371	10	3224	23	0	0%	23:59:57
5	nntp.terraraq.uk	2686	7	2448	8	0	0%	23:59:43
6	news.samoylyk.net	4030	1	3378	2	0	0%	23:59:57
7	news.aioe.org	1700	1	1508	4	0	0%	23:58:11
8	news.pasdenom.info	3181	1	3039	0	0	0%	23:59:57
TOTAL: 8		28150	116	22302	42	2	0%	191:55:51

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	338.7 KB	0.0 KB	338.7 KB	33.9 KB	23:59:24
2	i2pn.org	221.1 KB	4.2 KB	225.3 KB	3.0 KB	23:59:01
3	nntp.terraraq.uk	153.1 KB	655.9 KB	808.9 KB	53.9 KB	23:59:43
4	news.tnetconsulting.net	45.4 KB	2.8 KB	48.2 KB	3.2 KB	23:59:41
5	news.gegeweb.org	39.3 KB	115.9 KB	155.2 KB	4.7 KB	23:59:57
6	news.aioe.org	19.8 KB	11.7 KB	31.6 KB	6.3 KB	23:58:11
7	news.samoylyk.net	1.7 KB	2.8 KB	4.4 KB	1.5 KB	23:59:57
8	news.pasdenom.info	1.6 KB	0.0 KB	1.6 KB	1.6 KB	23:59:57
TOTAL: 8		820.8 KB	793.2 KB	1.6 MB	10.2 KB	191:55:51

NNRP readership statistics:

System		Conn	Elapsed
1	104.152.52.210	1	00:00:10
2	198.199.94.30	1	00:00:00
3	45.227.254.55	2	00:00:00
4	mail.gegeweb.eu	288	00:01:33
5	104.152.52.124	1	00:00:00
TOTAL: 5		293	00:01:45

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	5	00:00:11
2	*.gegeweb.eu	288	00:01:33
TOTAL: 2		293	00:01:45

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	7.695	8.403	2.475	00:01:33
104.152.52.210	0.022	0.033	0.000	00:00:10
45.227.254.55	0.040	0.048	0.000	00:00:00
198.199.94.30	0.011	0.043	0.000	00:00:00
104.152.52.124	0.046	0.009	0.000	00:00:00
?	0.032	0.011	0.000	00:00:00
TOTAL: 6	7.846	8.547	2.475	00:01:45

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
45.227.254.55	2
198.199.94.30	1
TOTAL: 2	3

NNRP unrecognized commands (by command):

Command	Count
#003	2
MGLNDD_45.13.105.36_119	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.55	2	2
TOTAL: 1	2	2

Daily Usenet report for news.niel.me

Sep 16 04:15:01 -- Sep 17 04:15:02