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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 27 01:30:03 rpi-studio innd: filter: No Cancel-Key[sha1:d69CluiuIf6P7ukX2Lph2uiCrDo=] matches Cancel-Lock[sha1:FUftipghVO/ytzNkP5jCCmhxvVU=] target=<de-gruppen-finden-20.05.2023@dnqmail.priggish.de>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3560	37.6%	681.4 KB	43.5%
innd	2357	24.9%	401.8 KB	25.6%
nnrpd	1804	19.1%	195.7 KB	12.5%
innfeed	1737	18.4%	288.8 KB	18.4%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	9460	100.0%	1.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	19090	60.2%
Negative hits	9088	28.7%
Do not exist	3527	11.1%
Cache misses	1	0.0%
TOTAL: 4	31706	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.020	0.0%	10	0.000	2.000	6.000
article cancel	00:00:00.003	0.0%	11	0.000	0.273	1.000
article cleanup	00:00:00.086	0.0%	3534	0.000	0.024	0.188
article logging	00:00:00.263	0.0%	3543	0.000	0.074	0.240
article parse	00:00:00.625	0.0%	8824	0.000	0.071	0.208
article write	00:00:02.854	0.0%	3090	0.000	0.924	2.200
artlog/artcncl	00:00:00.002	0.0%	11	0.000	0.182	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
data move	00:00:00.118	0.0%	35431	0.000	0.003	0.040
hisgrep/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	10	0.000	0.100	1.000
hisgrep/perl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	10	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.360	0.0%	31736	0.000	0.011	0.047
history sync	00:00:00.062	0.0%	411	0.000	0.151	1.000
history write	00:00:00.828	0.0%	3534	0.000	0.234	0.528
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:32.327	100.0%	37302	946.424	2317.096	6008.440
nntp read	00:00:01.167	0.0%	36884	0.000	0.032	0.061
overview write	00:00:01.918	0.0%	3090	0.077	0.621	6.000
perl filter	00:00:17.023	0.0%	3534	3.095	4.817	11.281
python filter	00:00:00.013	0.0%	3534	0.000	0.004	0.083
site send	00:00:01.300	0.0%	6244	0.000	0.208	0.488
TOTAL: 24:01:14.058	24:00:58.970	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.015	0.0%	3070	0.000	0.005	0.125
article prepare	00:00:00.000	0.0%	344	0.000	0.000	0.000
article read	00:00:00.022	0.0%	81	0.000	0.272	4.000
backlog stats	00:00:00.126	0.0%	41368	0.000	0.003	0.016
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.579	0.0%	24499	0.000	0.024	0.057
data write	00:00:01.263	0.0%	21485	0.011	0.059	0.143
idle	23:50:41.740	100.0%	41368	1038.768	2075.076	4589.847
status file	00:00:00.520	0.0%	205	1.000	2.537	7.000
TOTAL: 23:50:51.857	23:50:44.265	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.490	20.2%	1470	9.000	13.259	84.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.071	0.1%	3288	0.000	0.022	0.273
readart	00:00:00.013	0.0%	2	6.500	6.500	6.500
TOTAL: 00:01:36.303	00:00:19.574	20.3%	-	-	-	-

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	2	3430	1058	2199	173	30%	26:08:47
2	feed-out.gegeweb.org	3	2008	738	1270	0	36%	23:57:25
3	news.nntp4.net	1	3499	482	2792	225	13%	24:39:07
4	news.glou.org	1	2867	237	2603	27	8%	23:59:58
5	gegeweb-out.news.tnetconsulting.net	2	3093	182	2908	3	5%	23:59:40
6	news.samoylyk.net	2	1068	89	978	1	8%	10:23:35
7	nntp.terraraq.uk	1	1275	63	1209	3	4%	10:46:42
8	usenet.pasdenom.info	2	987	54	928	5	5%	11:28:30
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		39	18227	2903	14887	437	15%	155:23:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	gegeweb-out.news.tnetconsulting.net	4.4 MB	2.1 KB	6.9 KB	4.4 MB	99%	24.3 KB
2	i2pn.org	3.7 MB	0.0 KB	499.0 KB	4.2 MB	88%	3.5 KB
3	news.nntp4.net	2.4 MB	0.0 KB	784.6 KB	3.2 MB	75%	4.6 KB
4	feed-out.gegeweb.org	2.2 MB	0.0 KB	0.0 KB	2.2 MB	100%	3.1 KB
5	news.glou.org	1.0 MB	0.0 KB	56.7 KB	1.1 MB	94%	4.3 KB
6	news.samoylyk.net	476.4 KB	0.0 KB	4.4 KB	480.8 KB	99%	5.3 KB
7	nntp.terraraq.uk	384.5 KB	0.0 KB	32.6 KB	417.0 KB	92%	6.3 KB
8	usenet.pasdenom.info	131.2 KB	0.0 KB	7.4 KB	138.6 KB	94%	2.3 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		14.8 MB	2.1 KB	1.4 MB	16.1 MB	91%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 26 04:15:01 - 04:59:59	89	2.9%	0.03	1.5 MB	10.3%	0.58
May 26 05:00:00 - 05:59:59	106	3.4%	0.03	447.7 KB	2.9%	0.12
May 26 06:00:00 - 06:59:59	61	2.0%	0.02	293.3 KB	1.9%	0.08
May 26 07:00:00 - 07:59:59	107	3.5%	0.03	331.8 KB	2.2%	0.09
May 26 08:00:00 - 08:59:59	97	3.1%	0.03	410.2 KB	2.7%	0.11
May 26 09:00:00 - 09:59:59	104	3.4%	0.03	794.3 KB	5.2%	0.22
May 26 10:00:00 - 10:59:59	92	3.0%	0.03	254.4 KB	1.7%	0.07
May 26 11:00:00 - 11:59:59	77	2.5%	0.02	263.4 KB	1.7%	0.07
May 26 12:00:00 - 12:59:59	124	4.0%	0.03	557.4 KB	3.7%	0.15
May 26 13:00:00 - 13:59:59	136	4.4%	0.04	448.1 KB	2.9%	0.12
May 26 14:00:00 - 14:59:59	118	3.8%	0.03	561.0 KB	3.7%	0.16
May 26 15:00:00 - 15:59:59	116	3.8%	0.03	358.9 KB	2.4%	0.10
May 26 16:00:00 - 16:59:59	157	5.1%	0.04	517.7 KB	3.4%	0.14
May 26 17:00:00 - 17:59:59	157	5.1%	0.04	598.9 KB	3.9%	0.17
May 26 18:00:00 - 18:59:59	198	6.4%	0.06	1.5 MB	10.3%	0.44
May 26 19:00:00 - 19:59:59	135	4.4%	0.04	464.7 KB	3.0%	0.13
May 26 20:00:00 - 20:59:59	132	4.3%	0.04	492.9 KB	3.2%	0.14
May 26 21:00:00 - 21:59:59	152	4.9%	0.04	522.3 KB	3.4%	0.15
May 26 22:00:00 - 22:59:59	178	5.8%	0.05	632.7 KB	4.2%	0.18
May 26 23:00:00 - 23:59:59	208	6.7%	0.06	1.9 MB	12.7%	0.54
May 27 00:00:00 - 00:59:59	126	4.1%	0.04	525.4 KB	3.4%	0.15
May 27 01:00:00 - 01:59:59	134	4.3%	0.04	560.6 KB	3.7%	0.16
May 27 02:00:00 - 02:59:59	136	4.4%	0.04	556.6 KB	3.7%	0.15
May 27 03:00:00 - 03:59:59	111	3.6%	0.03	440.1 KB	2.9%	0.12
May 27 04:00:00 - 04:15:02	37	1.2%	0.04	139.4 KB	0.9%	0.15
TOTAL: 24:00:01	3088	100.0%	0.04	14.9 MB	100.0%	0.18

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	221	221
2	i2pn.org	161	161
3	news.glou.org	27	27
4	nntp.terraraq.uk	16	16
5	usenet.pasdenom.info	9	9
6	news.samoylyk.net	8	8
7	gegeweb-out.news.tnetconsulting.net	5	5
8	feed-out.gegeweb.org	4	4
TOTAL: 8		451	451

INND Perl filter [Top 20]:

Reason	Count
Bad path (news.giganews.com.POSTED)	108
EMP (phn nph)	106
EMP (md5)	64
Too many newsgroups (meow)	63
EMP (phl)	47
EMP (phn path)	27
Too many newsgroups	16
EMP (phr nph)	6
User-issued spam cancel	5
TOTAL: 9	442

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.samoylyk.net	5
	feed-out.gegeweb.org	4
	TOTAL: 2	9
TOTAL: 1		9

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3526	33	2043	0	1	0%	23:50:21
2	news.gegeweb.org	2494	24	2301	19	0	0%	23:59:53
3	news.tnetconsulting.net	4593	6	3078	2	1	0%	23:59:51
4	news.samoylyk.net	3153	3	2869	0	0	0%	23:51:24
5	nntp.terraraq.uk	2219	0	2175	0	0	0%	23:57:46
6	news.nntp4.net	2680	0	2612	0	0	0%	23:59:06
7	news.pasdenom.info	2815	0	2771	0	0	0%	23:59:53
8	news.aioe.org	0	0	0	0	2384	0%	23:59:53
TOTAL: 8		21480	66	17849	21	2386	0%	191:38:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	77.9 KB	0.0 KB	77.9 KB	2.4 KB	23:50:21
2	news.gegeweb.org	74.8 KB	69.4 KB	144.2 KB	3.4 KB	23:59:53
3	news.tnetconsulting.net	15.5 KB	4.2 KB	19.7 KB	2.5 KB	23:59:51
4	news.samoylyk.net	8.7 KB	0.0 KB	8.7 KB	2.9 KB	23:51:24
5	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:57:46
6	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:06
7	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:53
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:53
TOTAL: 8		176.9 KB	73.6 KB	250.5 KB	2.9 KB	191:38:07

NNRP readership statistics:

System		Conn	Elapsed
1	94.102.61.41	1	00:00:00
2	mail.gegeweb.eu	289	00:01:22
3	46.219.209.156	1	00:00:02
4	exhilarating.monitoring.internet-measurement.com	1	00:00:00
5	186.150.216.162.bc.googleusercontent.com	1	00:00:00
6	194.165.16.72	1	00:00:00
7	luminous.monitoring.internet-measurement.com	1	00:00:00
8	198.199.107.247	1	00:00:00
9	194.165.16.10	1	00:00:00
10	energetic.monitoring.internet-measurement.com	1	00:00:06
11	valiant.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 11		299	00:01:35

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.150.216.162.bc.googleusercontent.com	1	00:00:00
2	*.monitoring.internet-measurement.com	4	00:00:06
3	unresolved	5	00:00:05
4	*.gegeweb.eu	289	00:01:22
TOTAL: 4		299	00:01:35

NNRP auth users [Top 20]:

User	Conn
mailman	289
TOTAL: 1	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	4.633	4.610	1.994	00:01:22
energetic.monitoring.internet-measurement.com	0.078	0.020	0.000	00:00:06
46.219.209.156	0.041	0.041	0.000	00:00:02
198.199.107.247	0.019	0.028	0.000	00:00:00
186.150.216.162.bc.googleusercontent.com	0.032	0.022	0.000	00:00:00
94.102.61.41	0.027	0.027	0.000	00:00:00
194.165.16.72	0.021	0.031	0.000	00:00:00
194.165.16.10	0.055	0.011	0.000	00:00:00
luminous.monitoring.internet-measurement.com	0.031	0.031	0.000	00:00:00
exhilarating.monitoring.internet-measurement.com	0.028	0.038	0.000	00:00:00
valiant.monitoring.internet-measurement.com	0.035	0.026	0.000	00:00:00
TOTAL: 11	5.000	4.885	1.994	00:01:35

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
94.102.61.41	5
186.150.216.162.bc.googleusercontent.com	3
194.165.16.72	1
198.199.107.247	1
TOTAL: 4	10

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:119	2
GET / HTTP/1.1	2
Accept: /	1
MGLNDD_45.13.105.36_119	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
User-Agent: Expanse, a Palo Alto Network...	1
#003	1
Accept-Encoding: gzip	1
TOTAL: 8	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.72	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

May 26 04:15:01 -- May 27 04:15:02