Daily Usenet report for news.niel.me: Dec 8 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 9 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:u38yoYjqKKeF81r3AtT0Vkb4JQU=] matches Cancel-Lock[sha1:2cG7yfDyYCBgp/EHgHTvlm+M4TQ=] target=<de-gruppen-finden-02.12.2023@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	55559	74.2%	10.3 MB	80.9%
innd	13844	18.5%	1.7 MB	13.5%
nnrpd	3331	4.5%	376.5 KB	2.9%
innfeed	2112	2.8%	358.6 KB	2.7%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	74848	100.0%	12.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	104063	45.5%
Negative hits	69231	30.2%
Do not exist	54700	23.9%
Cache misses	878	0.4%
TOTAL: 4	228872	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.410	0.0%	328	0.000	1.250	8.000
article cancel	00:00:00.059	0.0%	329	0.000	0.179	1.000
article cleanup	00:00:01.164	0.0%	54945	0.009	0.021	0.046
article logging	00:00:04.212	0.0%	54945	0.047	0.077	0.129
article parse	00:00:16.699	0.0%	302179	0.034	0.055	0.073
article write	00:00:38.660	0.0%	43292	0.616	0.893	3.183
artlog/artcncl	00:00:00.010	0.0%	329	0.000	0.030	1.000
artlog/artcncl/perl	00:00:00.024	0.0%	328	0.000	0.073	1.000
data move	00:00:02.922	0.0%	554052	0.002	0.005	0.009
hisgrep/artcncl	00:00:00.011	0.0%	329	0.000	0.033	0.250
hisgrep/artcncl/perl	00:00:00.011	0.0%	328	0.000	0.034	1.000
hisgrep/perl	00:00:00.034	0.0%	329	0.000	0.103	1.667
hishave/artcncl	00:00:00.003	0.0%	329	0.000	0.009	0.500
hishave/artcncl/perl	00:00:00.006	0.0%	328	0.000	0.018	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.147	0.0%	229615	0.008	0.018	0.033
history sync	00:00:00.045	0.0%	379	0.000	0.119	1.000
history write	00:00:12.749	0.0%	54945	0.130	0.232	0.323
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:53:42.105	99.5%	475063	120.578	181.075	383.917
nntp read	00:00:13.985	0.0%	475124	0.020	0.029	0.044
overview write	00:00:16.834	0.0%	43292	0.173	0.389	4.037
perl filter	00:03:55.140	0.3%	54945	3.494	4.280	5.438
python filter	00:00:00.227	0.0%	54945	0.000	0.004	0.015
site send	00:00:12.003	0.0%	88583	0.061	0.136	0.260
TOTAL: 24:00:57.241	23:59:41.460	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.402	0.0%	44083	0.000	0.009	0.035
article prepare	00:00:00.086	0.0%	20166	0.000	0.004	0.250
article read	00:00:00.652	0.0%	4086	0.000	0.160	0.500
backlog stats	00:00:03.090	0.0%	673267	0.002	0.005	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:10.920	0.0%	459586	0.014	0.024	0.040
data write	00:00:18.189	0.0%	362313	0.038	0.050	0.063
idle	23:51:09.568	99.9%	673267	78.740	127.542	271.600
status file	00:00:00.418	0.0%	229	0.000	1.825	7.000
TOTAL: 23:52:12.882	23:51:43.325	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:12.480	0.0%	261818	0.000	0.048	0.138
idle	00:00:48.602	0.1%	1448	26.500	33.565	1526.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:24.638	0.0%	527603	0.000	0.047	0.500
TOTAL: 15:13:05.539	00:01:25.720	0.2%	-	-	-	-

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	news.nntp4.net	5	53033	21177	22125	9731	39%	24:08:47
2	news.glou.org	3	27552	16625	9573	1354	60%	24:36:04
3	nntp.terraraq.uk	3	18226	3130	14636	460	17%	24:24:45
4	feed-out.gegeweb.org	2	4063	1157	2901	5	28%	23:59:42
5	usenet.pasdenom.info	9	42081	924	41125	32	2%	44:30:50
6	gegeweb-out.news.tnetconsulting.net	1	3502	265	3174	63	7%	19:43:04
7	news.samoylyk.net	2	5019	40	4978	1	0%	26:02:00
8	i2pn.org	1	748	39	709	0	5%	03:13:01
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		51	154224	43357	99221	11646	28%	190:38:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	228.7 MB	0.0 KB	91.5 MB	320.2 MB	71%	10.6 KB
2	news.glou.org	137.5 MB	0.0 KB	7.6 MB	145.1 MB	94%	8.3 KB
3	nntp.terraraq.uk	21.1 MB	0.0 KB	4.1 MB	25.2 MB	83%	7.2 KB
4	feed-out.gegeweb.org	14.7 MB	0.0 KB	30.3 KB	14.8 MB	99%	13.0 KB
5	usenet.pasdenom.info	4.1 MB	8.0 KB	48.8 KB	4.2 MB	98%	4.5 KB
6	gegeweb-out.news.tnetconsulting.net	790.5 KB	9.1 KB	202.0 KB	1001.5 KB	78%	3.1 KB
7	i2pn.org	584.1 KB	0.0 KB	0.0 KB	584.1 KB	100%	15.0 KB
8	news.samoylyk.net	282.4 KB	0.0 KB	1.1 KB	283.5 KB	99%	6.9 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		407.8 MB	17.0 KB	103.5 MB	511.3 MB	79%	9.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 8 04:15:01 - 04:59:59	1302	3.0%	0.48	14.1 MB	3.4%	5.33
Dec 8 05:00:00 - 05:59:59	1926	4.5%	0.54	20.0 MB	4.9%	5.68
Dec 8 06:00:00 - 06:59:59	1915	4.4%	0.53	19.9 MB	4.9%	5.65
Dec 8 07:00:00 - 07:59:59	2146	5.0%	0.60	19.4 MB	4.7%	5.51
Dec 8 08:00:00 - 08:59:59	2314	5.3%	0.64	23.9 MB	5.8%	6.81
Dec 8 09:00:00 - 09:59:59	2144	5.0%	0.60	21.2 MB	5.2%	6.04
Dec 8 10:00:00 - 10:59:59	2578	6.0%	0.72	22.0 MB	5.4%	6.25
Dec 8 11:00:00 - 11:59:59	2310	5.3%	0.64	21.6 MB	5.3%	6.14
Dec 8 12:00:00 - 12:59:59	2143	5.0%	0.60	18.4 MB	4.5%	5.25
Dec 8 13:00:00 - 13:59:59	2253	5.2%	0.63	20.1 MB	4.9%	5.72
Dec 8 14:00:00 - 14:59:59	2089	4.8%	0.58	19.2 MB	4.7%	5.45
Dec 8 15:00:00 - 15:59:59	2229	5.2%	0.62	22.9 MB	5.6%	6.51
Dec 8 16:00:00 - 16:59:59	1964	4.5%	0.55	17.8 MB	4.4%	5.07
Dec 8 17:00:00 - 17:59:59	1993	4.6%	0.55	16.3 MB	4.0%	4.65
Dec 8 18:00:00 - 18:59:59	1845	4.3%	0.51	18.2 MB	4.4%	5.17
Dec 8 19:00:00 - 19:59:59	1506	3.5%	0.42	15.7 MB	3.8%	4.46
Dec 8 20:00:00 - 20:59:59	1337	3.1%	0.37	12.2 MB	3.0%	3.46
Dec 8 21:00:00 - 21:59:59	1244	2.9%	0.35	11.7 MB	2.9%	3.34
Dec 8 22:00:00 - 22:59:59	1376	3.2%	0.38	12.4 MB	3.0%	3.52
Dec 8 23:00:00 - 23:59:59	1313	3.0%	0.36	11.3 MB	2.8%	3.21
Dec 9 00:00:00 - 00:59:59	1090	2.5%	0.30	9.4 MB	2.3%	2.67
Dec 9 01:00:00 - 01:59:59	1382	3.2%	0.38	13.4 MB	3.3%	3.81
Dec 9 02:00:00 - 02:59:59	1218	2.8%	0.34	13.3 MB	3.2%	3.78
Dec 9 03:00:00 - 03:59:59	1346	3.1%	0.37	12.2 MB	3.0%	3.47
Dec 9 04:00:00 - 04:15:01	307	0.7%	0.34	2.8 MB	0.7%	3.22
TOTAL: 24:00:00	43270	100.0%	0.50	409.3 MB	100.0%	4.85

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	9747	34	9713
2	news.glou.org	1325	1	1324
3	nntp.terraraq.uk	458	0	458
4	gegeweb-out.news.tnetconsulting.net	81	0	81
5	usenet.pasdenom.info	28	0	28
6	feed-out.gegeweb.org	5	0	5
TOTAL: 6		11644	35	11609

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.lettres.langue.allemande	15
fr.education.superieur	13
fr.misc.gestion	5
fr.petites-annonces.informatique.materiel	1
comp.lang.go	1
TOTAL: 5	35

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11089
Too many newsgroups	184
EMP (phl)	155
Bad path (news.giganews.com.POSTED)	92
EMP (phn path)	59
User-issued spam cancel	22
Cancels abusifs	4
Cancel for rejected article	4
TOTAL: 8	11609

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nntp.terraraq.uk	46938	642	37600	400	0	3	1%	23:59:53
2	news.nntp4.net	30296	508	21378	0	0	0	1%	23:59:44
3	i2pn.org	67543	163	40031	2627	1	851	0%	23:59:54
4	news.samoylyk.net	64616	41	42945	260	0	52	0%	23:59:53
5	news.tnetconsulting.net	61369	27	43006	236	0	52	0%	23:59:54
6	news.gegeweb.org	43068	20	42107	0	0	0	0%	23:59:57
7	news.pasdenom.info	50374	4	42535	121	0	20	0%	23:59:56
8	news.aioe.org	0	0	0	0	0	8455	0%	23:59:57
TOTAL: 8		364204	1405	269602	3644	1	9433	0%	191:59:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nntp.terraraq.uk	6.8 MB	4.4 MB	11.2 MB	0.1 KB/s	11.0 KB	23:59:53
2	news.nntp4.net	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	10.1 KB	23:59:44
3	i2pn.org	937.5 KB	24.3 MB	25.2 MB	0.3 KB/s	9.2 KB	23:59:54
4	news.gegeweb.org	540.4 KB	0.0 KB	540.4 KB	0.0 KB/s	27.0 KB	23:59:57
5	news.samoylyk.net	193.4 KB	3.0 MB	3.2 MB	0.0 KB/s	11.0 KB	23:59:53
6	news.tnetconsulting.net	142.5 KB	2.6 MB	2.7 MB	0.0 KB/s	10.7 KB	23:59:54
7	news.pasdenom.info	27.9 KB	3.2 MB	3.2 MB	0.0 KB/s	26.5 KB	23:59:56
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
TOTAL: 8		13.6 MB	37.5 MB	51.1 MB	0.1 KB/s	10.4 KB	191:59:08

NNRP readership statistics:

System		Conn	Elapsed
1	106.211.203.35.bc.googleusercontent.com	1	00:00:00
2	lively.monitoring.internet-measurement.com	1	00:00:01
3	162.243.132.53	1	00:00:01
4	185.170.144.3	1	00:00:00
5	mighty.monitoring.internet-measurement.com	1	00:00:00
6	mail.gegeweb.eu	288	00:01:51
7	198.199.113.7	1	00:00:00
8	cheery.monitoring.internet-measurement.com	1	00:00:00
9	89.149.23.89	256	15:10:58
10	masterful.monitoring.internet-measurement.com	1	00:00:02
11	80.66.88.215	1	00:00:00
12	cheerful.monitoring.internet-measurement.com	2	00:00:00
13	adored.monitoring.internet-measurement.com	1	00:00:06
TOTAL: 13		556	15:13:04

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:51
2	unresolved	260	15:11:01
3	*.211.203.35.bc.googleusercontent.com	1	00:00:00
4	*.monitoring.internet-measurement.com	7	00:00:11
TOTAL: 4		556	15:13:04

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
89.149.23.89	43.629	51.393	0.000	15:10:58
mail.gegeweb.eu	4.453	4.280	1.578	00:01:51
adored.monitoring.internet-measurement.com	0.052	0.020	0.000	00:00:06
masterful.monitoring.internet-measurement.com	0.030	0.025	0.000	00:00:02
lively.monitoring.internet-measurement.com	0.015	0.027	0.000	00:00:01
162.243.132.53	0.021	0.032	0.000	00:00:01
106.211.203.35.bc.googleusercontent.com	0.025	0.015	0.000	00:00:00
198.199.113.7	0.019	0.004	0.000	00:00:00
185.170.144.3	0.011	0.011	0.000	00:00:00
mighty.monitoring.internet-measurement.com	0.011	0.018	0.000	00:00:00
cheerful.monitoring.internet-measurement.com	0.050	0.042	0.000	00:00:00
80.66.88.215	0.035	0.020	0.000	00:00:00
TOTAL: 12	48.351	55.887	1.578	15:13:04

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
106.211.203.35.bc.googleusercontent.com	3
198.199.113.7	1
162.243.132.53	1
185.170.144.3	1
TOTAL: 4	6

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	2
Host: 45.13.105.36:563	1
GET / HTTP/1.1	1
User-Agent: Expanse, a Palo Alto Network...	1
#003	1
TOTAL: 5	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.170.144.3	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Dec 8 04:15:01 -- Dec 9 04:15:01