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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 2 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:UH21QNt3E5KcLScv5CXlAcEA4I8=] matches Cancel-Lock[sha1:d++3SieM4WCRtU3pepjPMi05T9Y=] target=<de-gruppen-finden-26.08.2023@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	6926	48.2%	1.2 MB	54.3%
innd	3801	26.5%	568.9 KB	24.3%
nnrpd	1845	12.8%	195.7 KB	8.4%
innfeed	1746	12.2%	299.5 KB	12.8%
nocem	42	0.3%	5.5 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	14361	100.0%	2.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	31868	57.5%
Negative hits	16682	30.1%
Do not exist	6868	12.4%
Cache misses	5	0.0%
TOTAL: 4	55423	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.025	0.0%	17	0.500	1.471	3.500
article cancel	00:00:00.013	0.0%	100	0.000	0.130	1.000
article cleanup	00:00:00.139	0.0%	6828	0.000	0.020	0.103
article logging	00:00:00.517	0.0%	6828	0.000	0.076	0.273
article parse	00:00:01.442	0.0%	27698	0.000	0.052	0.121
article write	00:00:02.724	0.0%	5019	0.267	0.543	1.000
artlog/artcncl	00:00:00.002	0.0%	73	0.000	0.027	0.333
artlog/artcncl/perl	00:00:00.001	0.0%	17	0.000	0.059	1.000
data move	00:00:00.414	0.0%	76770	0.000	0.005	0.016
hisgrep/artcncl	00:00:00.000	0.0%	45	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	17	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	19	0.000	0.000	0.000
hishave/artcncl	00:00:00.002	0.0%	100	0.000	0.020	1.000
hishave/artcncl/perl	00:00:00.001	0.0%	17	0.000	0.059	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.853	0.0%	55448	0.004	0.015	0.041
history sync	00:00:00.064	0.0%	366	0.000	0.175	1.500
history write	00:00:01.259	0.0%	6828	0.025	0.184	0.405
hiswrite/artcncl	00:00:00.006	0.0%	55	0.000	0.109	0.333
idle	24:00:00.691	99.9%	76361	486.688	1131.477	2971.421
nntp read	00:00:02.044	0.0%	75721	0.006	0.027	0.054
overview write	00:00:01.393	0.0%	5019	0.071	0.278	1.696
perl filter	00:00:29.683	0.0%	6828	2.841	4.347	7.630
python filter	00:00:00.032	0.0%	6828	0.000	0.005	0.062
site send	00:00:01.790	0.0%	10178	0.000	0.176	0.425
TOTAL: 24:00:59.060	24:00:43.095	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	6109	0.000	0.006	0.062
article prepare	00:00:00.007	0.0%	1704	0.000	0.004	0.083
article read	00:00:00.033	0.0%	234	0.000	0.141	3.500
backlog stats	00:00:00.364	0.0%	72941	0.000	0.005	0.016
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.090	0.0%	44359	0.000	0.025	0.051
data write	00:00:02.305	0.0%	39649	0.017	0.058	0.103
idle	23:49:54.480	100.0%	72941	549.500	1176.217	2454.404
status file	00:00:00.547	0.0%	205	0.500	2.668	8.000
TOTAL: 23:50:03.078	23:49:58.862	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:27.410	21.4%	1463	9.500	18.735	10006.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.109	0.1%	3265	0.000	0.033	0.364
TOTAL: 00:02:08.080	00:00:27.519	21.5%	-	-	-	-

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	2391	1317	1074	0	55%	23:58:14
2	news.glou.org	2	5684	1008	4239	437	17%	23:59:51
3	i2pn.org	2	6020	978	4444	598	16%	23:32:20
4	gegeweb-out.news.tnetconsulting.net	1	4842	967	3849	26	19%	22:34:25
5	news.nntp4.net	2	6280	391	5347	542	6%	22:21:14
6	nntp.terraraq.uk	1	9464	247	9068	149	2%	43:39:01
7	usenet.pasdenom.info	4	2472	151	2313	8	6%	24:37:10
8	news.samoylyk.net	2	2461	19	2442	0	0%	12:39:47
9	localhost	27	0	0	0	0	0%	00:00:00
TOTAL: 9		43	39614	5078	32776	1760	12%	197:22:02

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	gegeweb-out.news.tnetconsulting.net	7.4 MB	143.4 KB	7.5 MB	98%	7.8 KB
2	feed-out.gegeweb.org	7.1 MB	0.0 KB	7.1 MB	100%	5.5 KB
3	news.glou.org	6.6 MB	3.7 MB	10.2 MB	64%	7.2 KB
4	i2pn.org	4.2 MB	4.9 MB	9.1 MB	45%	5.9 KB
5	news.nntp4.net	2.8 MB	5.9 MB	8.6 MB	31%	9.4 KB
6	nntp.terraraq.uk	1.1 MB	1.6 MB	2.7 MB	39%	7.0 KB
7	usenet.pasdenom.info	739.2 KB	15.7 KB	754.9 KB	97%	4.7 KB
8	news.samoylyk.net	105.6 KB	0.0 KB	105.6 KB	100%	5.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		29.9 MB	16.2 MB	46.1 MB	64%	6.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 1 04:15:01 - 04:59:59	116	2.3%	0.04	960.4 KB	3.2%	0.36
Sep 1 05:00:00 - 05:59:59	119	2.4%	0.03	567.7 KB	1.9%	0.16
Sep 1 06:00:00 - 06:59:59	121	2.4%	0.03	799.6 KB	2.7%	0.22
Sep 1 07:00:00 - 07:59:59	203	4.0%	0.06	855.0 KB	2.8%	0.24
Sep 1 08:00:00 - 08:59:59	255	5.1%	0.07	1.5 MB	5.2%	0.44
Sep 1 09:00:00 - 09:59:59	199	4.0%	0.06	1022.8 KB	3.4%	0.28
Sep 1 10:00:00 - 10:59:59	172	3.4%	0.05	1.0 MB	3.5%	0.29
Sep 1 11:00:00 - 11:59:59	259	5.2%	0.07	1.4 MB	4.8%	0.40
Sep 1 12:00:00 - 12:59:59	212	4.2%	0.06	1.5 MB	4.9%	0.41
Sep 1 13:00:00 - 13:59:59	214	4.3%	0.06	764.5 KB	2.5%	0.21
Sep 1 14:00:00 - 14:59:59	268	5.3%	0.07	929.4 KB	3.1%	0.26
Sep 1 15:00:00 - 15:59:59	200	4.0%	0.06	972.4 KB	3.2%	0.27
Sep 1 16:00:00 - 16:59:59	207	4.1%	0.06	2.6 MB	8.9%	0.75
Sep 1 17:00:00 - 17:59:59	247	4.9%	0.07	1.3 MB	4.6%	0.38
Sep 1 18:00:00 - 18:59:59	263	5.2%	0.07	1.1 MB	3.8%	0.32
Sep 1 19:00:00 - 19:59:59	230	4.6%	0.06	1.1 MB	3.7%	0.31
Sep 1 20:00:00 - 20:59:59	275	5.5%	0.08	1.6 MB	5.5%	0.46
Sep 1 21:00:00 - 21:59:59	263	5.2%	0.07	1.6 MB	5.5%	0.46
Sep 1 22:00:00 - 22:59:59	237	4.7%	0.07	1.5 MB	5.0%	0.42
Sep 1 23:00:00 - 23:59:59	280	5.6%	0.08	1.9 MB	6.3%	0.53
Sep 2 00:00:00 - 00:59:59	212	4.2%	0.06	2.3 MB	7.9%	0.66
Sep 2 01:00:00 - 01:59:59	162	3.2%	0.04	869.7 KB	2.9%	0.24
Sep 2 02:00:00 - 02:59:59	140	2.8%	0.04	668.1 KB	2.2%	0.19
Sep 2 03:00:00 - 03:59:59	129	2.6%	0.04	543.1 KB	1.8%	0.15
Sep 2 04:00:00 - 04:15:01	43	0.9%	0.05	189.6 KB	0.6%	0.21
TOTAL: 24:00:00	5026	100.0%	0.06	29.4 MB	100.0%	0.35

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	652	0	652
2	i2pn.org	616	0	616
3	news.glou.org	437	0	437
4	nntp.terraraq.uk	81	0	81
5	gegeweb-out.news.tnetconsulting.net	22	1	21
6	usenet.pasdenom.info	2	0	2
TOTAL: 6		1810	1	1809

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.education.entraide.physique-chimie	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phr nph)	659
EMP (md5)	622
Too many newsgroups	150
EMP (phn nph)	136
Bad path (news.giganews.com.POSTED)	116
EMP (phn path)	82
EMP (phl)	40
Binary: misplaced binary	2
HTML Multipart	1
Cancels abusifs	1
TOTAL: 10	1809

NoCeM on Spool:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	7297	105	4901	9	24	1%	23:59:52
2	i2pn.org	6312	95	3902	7	40	1%	23:59:29
3	nntp.terraraq.uk	4591	69	3840	16	0	1%	23:59:57
4	news.nntp4.net	5267	68	4514	0	16	1%	23:59:51
5	news.samoylyk.net	8458	44	5931	3	35	0%	23:57:47
6	news.gegeweb.org	3857	7	3723	1	0	0%	23:59:12
7	news.pasdenom.info	4675	1	4513	1	9	0%	23:59:44
8	news.aioe.org	0	0	0	0	3445	0%	23:59:55
TOTAL: 8		40457	389	31324	37	3569	0%	191:55:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	nntp.terraraq.uk	536.6 KB	642.3 KB	1.2 MB	13.9 KB	23:59:57
2	news.nntp4.net	529.4 KB	0.0 KB	529.4 KB	7.8 KB	23:59:51
3	news.tnetconsulting.net	479.2 KB	24.3 KB	503.5 KB	4.4 KB	23:59:52
4	i2pn.org	415.3 KB	27.5 KB	442.8 KB	4.3 KB	23:59:29
5	news.samoylyk.net	283.9 KB	13.8 KB	297.7 KB	6.3 KB	23:57:47
6	news.gegeweb.org	68.3 KB	1.8 KB	70.1 KB	8.8 KB	23:59:12
7	news.pasdenom.info	2.8 KB	3.9 KB	6.7 KB	3.4 KB	23:59:44
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:55
TOTAL: 8		2.3 MB	713.6 KB	3.0 MB	7.1 KB	191:55:47

NNRP readership statistics:

System		Conn	Elapsed
1	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:10
2	80.66.76.80	6	00:00:01
3	194.165.16.10	1	00:00:00
4	mail.gegeweb.eu	288	00:01:43
5	71.6.134.230	3	00:00:01
6	208.150.216.162.bc.googleusercontent.com	1	00:00:00
7	162.243.131.39	1	00:00:02
8	183.136.225.32	1	00:00:02
9	80.66.88.211	1	00:00:00
10	94.102.61.41	1	00:00:01
11	247.210.203.35.bc.googleusercontent.com	1	00:00:01
TOTAL: 11		305	00:02:07

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.210.203.35.bc.googleusercontent.com	1	00:00:01
2	*.150.216.162.bc.googleusercontent.com	1	00:00:00
3	*.w92-154.abo.wanadoo.fr	1	00:00:10
4	*.gegeweb.eu	288	00:01:43
5	unresolved	14	00:00:10
TOTAL: 5		305	00:02:07

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.358	3.906	1.539	00:01:43
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.024	0.028	0.000	00:00:10
183.136.225.32	0.024	0.027	0.000	00:00:02
162.243.131.39	0.012	0.012	0.000	00:00:02
71.6.134.230	0.037	0.052	0.000	00:00:01
247.210.203.35.bc.googleusercontent.com	0.069	0.018	0.000	00:00:01
80.66.76.80	0.122	0.116	0.000	00:00:01
94.102.61.41	0.035	0.013	0.000	00:00:01
208.150.216.162.bc.googleusercontent.com	0.035	0.017	0.000	00:00:00
194.165.16.10	0.038	0.017	0.000	00:00:00
80.66.88.211	0.041	0.015	0.000	00:00:00
?	0.025	0.020	0.000	00:00:00
TOTAL: 12	4.820	4.241	1.539	00:02:07

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
71.6.134.230	8
94.102.61.41	5
183.136.225.32	5
247.210.203.35.bc.googleusercontent.com	3
80.66.76.80	3
208.150.216.162.bc.googleusercontent.com	3
194.165.16.10	1
162.243.131.39	1
TOTAL: 8	29

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	5
#003	4
Host: 45.13.105.36:119	4
Accept: /	3
User-Agent: Expanse, a Palo Alto Network...	2
Accept-Encoding: gzip	2
User-Agent: Mozilla/5.0 (Windows NT 10.0...	2
ÜŽ#014y·»Ê×#004¸#014Q¯ÜwŒnõ#020q	1
Host: 45.13.105.36:563	1
MGLNDD_45.13.105.36_119	1
#023#003#023#001#023#002#001	1
Connection: keep-alive	1
#026#003#001	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 14	29

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.76.80	3	3
194.165.16.10	1	1
TOTAL: 2	4	4

Daily Usenet report for news.niel.me

Sep 1 04:15:01 -- Sep 2 04:15:01