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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 16 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:33HHUBOdFt6Cy98W+seNZMCQ+ho=] matches Cancel-Lock[sha1:o+DN1mAcJPmB5TZu5GAb52k7KI8=] target=<de-gruppen-finden-09.09.2023@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	7688	49.8%	1.4 MB	55.5%
innd	4101	26.6%	593.0 KB	23.7%
nnrpd	1900	12.3%	209.0 KB	8.3%
innfeed	1744	11.3%	312.9 KB	12.5%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	15435	100.0%	2.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	32160	56.7%
Negative hits	16924	29.8%
Do not exist	7612	13.4%
Cache misses	6	0.0%
TOTAL: 4	56702	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.017	0.0%	17	0.000	1.000	3.500
article cancel	00:00:00.004	0.0%	18	0.000	0.222	1.000
article cleanup	00:00:00.162	0.0%	7652	0.000	0.021	0.103
article logging	00:00:00.561	0.0%	7652	0.000	0.073	0.296
article parse	00:00:02.058	0.0%	36771	0.000	0.056	0.109
article write	00:00:02.984	0.0%	5515	0.250	0.541	1.045
artlog/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	17	0.000	0.059	1.000
data move	00:00:00.460	0.0%	89930	0.000	0.005	0.015
hisgrep/artcncl	00:00:00.000	0.0%	18	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.001	0.0%	21	0.000	0.048	0.500
hishave/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	17	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.916	0.0%	56969	0.000	0.016	0.046
history sync	00:00:00.056	0.0%	412	0.000	0.136	0.667
history write	00:00:01.394	0.0%	7652	0.023	0.182	0.464
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:04.831	99.9%	85903	360.938	1005.842	2491.037
nntp read	00:00:02.325	0.0%	85281	0.005	0.027	0.060
overview write	00:00:01.604	0.0%	5515	0.050	0.291	1.400
perl filter	00:00:33.138	0.0%	7652	2.286	4.331	7.143
python filter	00:00:00.031	0.0%	7652	0.000	0.004	0.045
site send	00:00:01.719	0.0%	11167	0.000	0.154	0.457
TOTAL: 24:01:08.375	24:00:52.262	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	5678	0.000	0.011	0.083
article prepare	00:00:00.004	0.0%	1552	0.000	0.003	0.250
article read	00:00:00.050	0.0%	249	0.000	0.201	1.000
backlog stats	00:00:00.444	0.0%	86253	0.000	0.005	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.295	0.0%	55309	0.005	0.023	0.060
data write	00:00:02.324	0.0%	42679	0.020	0.054	0.095
idle	23:49:57.444	100.0%	86253	414.986	994.718	2574.829
status file	00:00:00.418	0.0%	196	1.000	2.133	7.000
TOTAL: 23:50:07.599	23:50:02.041	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:52.613	33.0%	1477	7.000	35.622	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.122	0.1%	3327	0.000	0.037	0.455
TOTAL: 00:02:39.372	00:00:52.735	33.1%	-	-	-	-

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	i2pn.org	1	6344	1639	4323	382	25%	23:15:46
2	feed-out.gegeweb.org	2	2307	1222	1085	0	52%	23:59:03
3	news.glou.org	1	5478	968	4200	310	17%	25:52:31
4	news.nntp4.net	1	7250	847	5201	1202	11%	24:04:11
5	gegeweb-out.news.tnetconsulting.net	1	4468	274	4171	23	6%	19:27:50
6	nntp.terraraq.uk	1	4252	159	3974	119	3%	20:21:59
7	usenet.pasdenom.info	7	1850	106	1743	1	5%	16:43:57
8	news.samoylyk.net	1	63	0	63	0	0%	00:18:08
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	32012	5215	24760	2037	16%	154:03:25

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	9.4 MB	14.4 MB	23.8 MB	39%	11.9 KB
2	news.glou.org	9.2 MB	2.7 MB	11.9 MB	77%	9.5 KB
3	i2pn.org	9.1 MB	3.0 MB	12.1 MB	75%	6.1 KB
4	gegeweb-out.news.tnetconsulting.net	4.6 MB	59.3 KB	4.6 MB	98%	16.0 KB
5	feed-out.gegeweb.org	3.9 MB	0.0 KB	3.9 MB	100%	3.2 KB
6	nntp.terraraq.uk	895.3 KB	1.5 MB	2.4 MB	36%	8.8 KB
7	usenet.pasdenom.info	251.3 KB	1.2 KB	252.5 KB	99%	2.4 KB
8	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0%	0
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		37.2 MB	21.6 MB	58.9 MB	63%	8.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 15 04:15:01 - 04:59:59	109	2.0%	0.04	534.1 KB	1.3%	0.20
Sep 15 05:00:00 - 05:59:59	183	3.3%	0.05	1.2 MB	3.1%	0.36
Sep 15 06:00:00 - 06:59:59	138	2.5%	0.04	1.3 MB	3.3%	0.38
Sep 15 07:00:00 - 07:59:59	187	3.4%	0.05	1.9 MB	4.7%	0.53
Sep 15 08:00:00 - 08:59:59	189	3.4%	0.05	1.9 MB	4.8%	0.55
Sep 15 09:00:00 - 09:59:59	274	5.0%	0.08	2.7 MB	6.8%	0.77
Sep 15 10:00:00 - 10:59:59	300	5.5%	0.08	2.9 MB	7.2%	0.82
Sep 15 11:00:00 - 11:59:59	296	5.4%	0.08	1.9 MB	4.7%	0.54
Sep 15 12:00:00 - 12:59:59	256	4.7%	0.07	2.0 MB	5.1%	0.58
Sep 15 13:00:00 - 13:59:59	263	4.8%	0.07	1.7 MB	4.1%	0.47
Sep 15 14:00:00 - 14:59:59	265	4.8%	0.07	2.0 MB	4.9%	0.56
Sep 15 15:00:00 - 15:59:59	258	4.7%	0.07	2.3 MB	5.8%	0.66
Sep 15 16:00:00 - 16:59:59	332	6.0%	0.09	1.7 MB	4.2%	0.48
Sep 15 17:00:00 - 17:59:59	263	4.8%	0.07	2.1 MB	5.2%	0.60
Sep 15 18:00:00 - 18:59:59	225	4.1%	0.06	1.3 MB	3.3%	0.38
Sep 15 19:00:00 - 19:59:59	187	3.4%	0.05	1.0 MB	2.6%	0.30
Sep 15 20:00:00 - 20:59:59	234	4.3%	0.07	1.1 MB	2.8%	0.32
Sep 15 21:00:00 - 21:59:59	221	4.0%	0.06	1.3 MB	3.3%	0.37
Sep 15 22:00:00 - 22:59:59	242	4.4%	0.07	2.4 MB	6.0%	0.69
Sep 15 23:00:00 - 23:59:59	243	4.4%	0.07	1.3 MB	3.3%	0.38
Sep 16 00:00:00 - 00:59:59	277	5.0%	0.08	1.5 MB	3.7%	0.42
Sep 16 01:00:00 - 01:59:59	182	3.3%	0.05	1.2 MB	3.0%	0.34
Sep 16 02:00:00 - 02:59:59	171	3.1%	0.05	1.3 MB	3.2%	0.37
Sep 16 03:00:00 - 03:59:59	168	3.1%	0.05	1.0 MB	2.6%	0.30
Sep 16 04:00:00 - 04:15:02	39	0.7%	0.04	379.0 KB	0.9%	0.42
TOTAL: 24:00:01	5502	100.0%	0.06	40.1 MB	100.0%	0.48

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	1325	1325
2	i2pn.org	380	380
3	news.glou.org	290	290
4	nntp.terraraq.uk	118	118
5	gegeweb-out.news.tnetconsulting.net	36	36
6	usenet.pasdenom.info	2	2
TOTAL: 6		2151	2151

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	996
EMP (phr nph)	928
Bad path (news.giganews.com.POSTED)	142
EMP (phn path)	66
Too many newsgroups	9
EMP (phl)	4
Too many newsgroups (meow)	3
Cancel of non-existing ID <d7f3403a-5bd2-428c-9dfb-14b70d6b01d8n@googlegroups.com>	1
Cancel of non-existing ID <ue25f1$3blsa$1@dont-email.me>	1
Cancel of non-existing ID <2c7b8328-c1c9-4cf4-b53e-dd28044ccb9en@googlegroups.com>	1
TOTAL: 10	2151

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nntp.terraraq.uk	5051	85	4279	14	0	1%	23:59:54
2	news.nntp4.net	5166	70	4536	0	0	1%	23:59:57
3	news.samoylyk.net	8462	59	5368	19	62	0%	23:59:57
4	news.tnetconsulting.net	8163	54	5435	9	66	0%	23:59:57
5	i2pn.org	6445	52	3744	4	49	0%	23:59:35
6	news.gegeweb.org	4425	2	4259	14	0	0%	23:58:29
7	news.pasdenom.info	5294	1	5004	6	28	0%	23:59:57
8	news.aioe.org	0	0	0	0	3575	0%	23:59:56
TOTAL: 8		43006	323	32625	66	3780	0%	191:57:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	nntp.terraraq.uk	1.1 MB	138.4 KB	1.2 MB	12.9 KB	23:59:54
2	news.nntp4.net	1.0 MB	0.0 KB	1.0 MB	15.1 KB	23:59:57
3	news.samoylyk.net	574.3 KB	126.0 KB	700.3 KB	9.0 KB	23:59:57
4	i2pn.org	515.0 KB	14.8 KB	529.8 KB	9.5 KB	23:59:35
5	news.tnetconsulting.net	507.1 KB	34.7 KB	541.7 KB	8.6 KB	23:59:57
6	news.pasdenom.info	15.2 KB	223.1 KB	238.3 KB	34.0 KB	23:59:57
7	news.gegeweb.org	3.9 KB	168.0 KB	171.9 KB	10.7 KB	23:58:29
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		3.7 MB	705.0 KB	4.4 MB	11.6 KB	191:57:42

NNRP readership statistics:

System		Conn	Elapsed
1	80.66.88.215	1	00:00:00
2	mail.gegeweb.eu	288	00:01:21
3	2607:ff10:c8:594::5	9	00:00:25
4	bubbly.monitoring.internet-measurement.com	1	00:00:05
5	104.152.52.199	1	00:00:00
6	104.131.144.29	1	00:00:09
7	192.241.230.63	1	00:00:00
8	genius.monitoring.internet-measurement.com	2	00:00:00
9	effervescent.monitoring.internet-measurement.com	1	00:00:05
10	104.152.52.132	1	00:00:10
11	76.210.203.35.bc.googleusercontent.com	1	00:00:01
12	185.73.124.50	2	00:00:01
13	renowned.monitoring.internet-measurement.com	1	00:00:00
14	adorable.monitoring.internet-measurement.com	1	00:00:02
15	194.165.16.10	1	00:00:00
16	exquisite.monitoring.internet-measurement.com	1	00:00:01
17	165.154.174.108	3	00:00:11
TOTAL: 17		316	00:02:38

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.210.203.35.bc.googleusercontent.com	1	00:00:01
2	*.gegeweb.eu	288	00:01:21
3	*.monitoring.internet-measurement.com	7	00:00:15
4	?	9	00:00:25
5	unresolved	11	00:00:35
TOTAL: 5		316	00:02:38

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.256	3.984	1.511	00:01:21
2607:ff10:c8:594::5	0.110	0.102	0.000	00:00:25
165.154.174.108	0.090	0.057	0.000	00:00:11
104.152.52.132	0.010	0.014	0.000	00:00:10
104.131.144.29	0.034	0.017	0.000	00:00:09
bubbly.monitoring.internet-measurement.com	0.016	0.027	0.000	00:00:05
effervescent.monitoring.internet-measurement.com	0.048	0.024	0.000	00:00:05
adorable.monitoring.internet-measurement.com	0.019	0.005	0.000	00:00:02
exquisite.monitoring.internet-measurement.com	0.020	0.032	0.000	00:00:01
76.210.203.35.bc.googleusercontent.com	0.036	0.005	0.000	00:00:01
185.73.124.50	0.040	0.034	0.000	00:00:01
192.241.230.63	0.024	0.028	0.000	00:00:00
104.152.52.199	0.012	0.012	0.000	00:00:00
194.165.16.10	0.016	0.013	0.000	00:00:00
genius.monitoring.internet-measurement.com	0.050	0.042	0.000	00:00:00
renowned.monitoring.internet-measurement.com	0.024	0.004	0.000	00:00:00
80.66.88.215	0.018	0.004	0.000	00:00:00
TOTAL: 17	4.823	4.404	1.511	00:02:38

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
2607:ff10:c8:594::5	21
76.210.203.35.bc.googleusercontent.com	3
165.154.174.108	2
185.73.124.50	1
192.241.230.63	1
80.66.88.215	1
TOTAL: 6	29

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	4
#023#003#023#001#023#002#001	4
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	3
Accept-Encoding: gzip	3
#026#003#001	3
User-Agent: Mozilla/5.0 (Windows NT 10.0...	3
Accept: /	3
#003	2
User-Agent: Expanse, a Palo Alto Network...	1
MGLNDD_45.13.105.36_119	1
#026#003#001#001#011#001	1
Host: 45.13.105.36:563	1
TOTAL: 12	29

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.73.124.50	1	1
80.66.88.215	1	1
TOTAL: 2	2	2

Daily Usenet report for news.niel.me

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