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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Oct 9 22:00:03 rpi-studio pgpverify[28351]: pgpverify: verification failed
Oct 9 22:00:03 rpi-studio controlchan[21792]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231009200003$1bd5@isc.org>
Oct 9 22:00:11 rpi-studio pgpverify[28370]: pgpverify: verification failed
Oct 9 22:00:11 rpi-studio controlchan[21792]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231009200003$3131@isc.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	26997	72.9%	5.3 MB	79.4%
innd	6228	16.8%	862.0 KB	12.6%
innfeed	1961	5.3%	341.9 KB	5.0%
nnrpd	1820	4.9%	198.7 KB	2.9%
controlchan	3	0.0%	0.2 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 7	37012	100.0%	6.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	72953	51.0%
Negative hits	43114	30.2%
Do not exist	26837	18.8%
Cache misses	13	0.0%
TOTAL: 4	142917	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.021	0.0%	11	0.667	1.909	6.000
article cancel	00:00:00.005	0.0%	11	0.000	0.455	1.000
article cleanup	00:00:00.542	0.0%	26846	0.000	0.020	0.060
article logging	00:00:01.965	0.0%	26846	0.030	0.073	0.125
article parse	00:00:05.789	0.0%	103813	0.017	0.056	0.104
article write	00:00:14.611	0.0%	22781	0.397	0.641	0.991
artlog/artcncl	00:00:00.002	0.0%	11	0.000	0.182	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	11	0.000	0.091	1.000
data move	00:00:01.250	0.0%	239249	0.001	0.005	0.011
hisgrep/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hisgrep/perl	00:00:00.005	0.0%	11	0.000	0.455	1.667
hishave/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	11	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:02.509	0.0%	142961	0.008	0.018	0.031
history sync	00:00:00.056	0.0%	374	0.000	0.150	1.000
history write	00:00:05.597	0.0%	26846	0.068	0.208	0.417
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:48:00.389	99.8%	219414	149.294	390.496	1030.354
nntp read	00:00:06.030	0.0%	218930	0.013	0.028	0.042
overview write	00:00:06.549	0.0%	22781	0.128	0.287	2.058
perl filter	00:01:26.968	0.1%	26846	2.174	3.240	4.992
python filter	00:00:00.119	0.0%	26846	0.000	0.004	0.019
site send	00:00:05.463	0.0%	47088	0.034	0.116	0.243
TOTAL: 23:50:59.179	23:50:17.871	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.228	0.0%	23044	0.000	0.010	0.039
article prepare	00:00:00.022	0.0%	5820	0.000	0.004	0.062
article read	00:00:00.159	0.0%	928	0.000	0.171	3.000
backlog stats	00:00:01.601	0.0%	339576	0.001	0.005	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:05.313	0.0%	233032	0.012	0.023	0.043
data write	00:00:09.382	0.0%	186044	0.036	0.050	0.074
idle	23:54:36.880	100.0%	339576	87.541	253.483	778.095
status file	00:00:00.532	0.0%	217	0.000	2.452	21.000
TOTAL: 23:55:09.494	23:54:54.117	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:36.708	19.3%	1460	10.400	25.142	10051.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.095	0.0%	3243	0.000	0.029	1.000
TOTAL: 00:03:10.370	00:00:36.803	19.3%	-	-	-	-

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	10	25206	10605	11869	2732	42%	26:06:04
2	gegeweb-out.news.tnetconsulting.net	4	22830	5232	17528	70	22%	25:30:40
3	news.glou.org	4	8939	3442	4632	865	38%	24:56:37
4	feed-out.gegeweb.org	5	3623	2132	1491	0	58%	24:10:00
5	nntp.terraraq.uk	2	9876	765	8680	431	7%	24:04:01
6	news.samoylyk.net	5	21227	440	20780	7	2%	26:01:02
7	usenet.pasdenom.info	2	3423	343	3069	11	10%	27:13:50
8	i2pn.org	3	8774	29	8742	3	0%	45:54:59
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		60	103898	22988	76791	4119	22%	223:57:13

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	64.9 MB	17.1 MB	82.0 MB	79%	6.3 KB
2	gegeweb-out.news.tnetconsulting.net	39.9 MB	251.4 KB	40.1 MB	99%	7.8 KB
3	news.glou.org	24.9 MB	7.4 MB	32.3 MB	77%	7.7 KB
4	feed-out.gegeweb.org	9.2 MB	0.0 KB	9.2 MB	100%	4.4 KB
5	nntp.terraraq.uk	4.6 MB	3.4 MB	8.0 MB	57%	6.9 KB
6	news.samoylyk.net	3.0 MB	42.8 KB	3.0 MB	98%	7.0 KB
7	usenet.pasdenom.info	818.5 KB	21.5 KB	839.9 KB	97%	2.4 KB
8	i2pn.org	60.9 KB	4.9 KB	65.8 KB	92%	2.1 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		147.4 MB	28.2 MB	175.6 MB	83%	6.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 9 04:15:01 - 04:59:59	308	1.3%	0.11	1.6 MB	1.1%	0.62
Oct 9 05:00:00 - 05:59:59	466	2.0%	0.13	3.0 MB	2.1%	0.86
Oct 9 06:00:00 - 06:59:59	414	1.8%	0.12	2.7 MB	1.8%	0.77
Oct 9 07:00:00 - 07:59:59	533	2.3%	0.15	4.7 MB	3.2%	1.33
Oct 9 08:00:00 - 08:59:59	637	2.8%	0.18	5.7 MB	3.9%	1.63
Oct 9 09:00:00 - 09:59:59	1664	7.3%	0.46	7.0 MB	4.8%	1.98
Oct 9 10:00:00 - 10:59:59	1593	7.0%	0.44	7.4 MB	5.1%	2.11
Oct 9 11:00:00 - 11:59:59	1161	5.1%	0.32	6.4 MB	4.4%	1.83
Oct 9 12:00:00 - 12:59:59	1185	5.2%	0.33	6.7 MB	4.6%	1.90
Oct 9 13:00:00 - 13:59:59	946	4.1%	0.26	4.2 MB	2.9%	1.19
Oct 9 14:00:00 - 14:59:59	679	3.0%	0.19	3.9 MB	2.7%	1.11
Oct 9 15:00:00 - 15:59:59	815	3.6%	0.23	4.4 MB	3.0%	1.26
Oct 9 16:00:00 - 16:59:59	835	3.7%	0.23	5.6 MB	3.9%	1.61
Oct 9 17:00:00 - 17:59:59	1100	4.8%	0.31	6.1 MB	4.2%	1.75
Oct 9 18:00:00 - 18:59:59	742	3.2%	0.21	4.8 MB	3.3%	1.37
Oct 9 19:00:00 - 19:59:59	930	4.1%	0.26	4.9 MB	3.3%	1.38
Oct 9 20:00:00 - 20:59:59	1195	5.2%	0.33	8.4 MB	5.7%	2.39
Oct 9 21:00:00 - 21:59:59	746	3.3%	0.21	6.3 MB	4.3%	1.79
Oct 9 22:00:00 - 22:59:59	1961	8.6%	0.54	14.5 MB	9.9%	4.14
Oct 9 23:00:00 - 23:59:59	1579	6.9%	0.44	12.6 MB	8.6%	3.60
Oct 10 00:00:00 - 00:59:59	1200	5.2%	0.33	9.6 MB	6.6%	2.74
Oct 10 01:00:00 - 01:59:59	646	2.8%	0.18	4.0 MB	2.7%	1.12
Oct 10 02:00:00 - 02:59:59	673	2.9%	0.19	4.8 MB	3.3%	1.37
Oct 10 03:00:00 - 03:59:59	722	3.2%	0.20	4.8 MB	3.3%	1.36
Oct 10 04:00:00 - 04:15:01	145	0.6%	0.16	1.9 MB	1.3%	2.17
TOTAL: 24:00:00	22875	100.0%	0.26	146.2 MB	100.0%	1.73

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	2726	0	2726
2	news.glou.org	849	1	848
3	nntp.terraraq.uk	444	0	444
4	gegeweb-out.news.tnetconsulting.net	69	0	69
5	usenet.pasdenom.info	8	1	7
6	news.samoylyk.net	4	0	4
TOTAL: 6		4100	2	4098

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.lang.go	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2052
EMP (phr nph)	1804
Bad path (news.giganews.com.POSTED)	98
EMP (phn path)	69
EMP (phl)	47
Too many newsgroups	10
Too many newsgroups (meow)	9
Cancel for rejected article	4
Too many newsgroups (low_xpost)	2
Cancels abusifs	2
HTML Multipart	1
TOTAL: 11	4098

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	14099	264	11887	0	0	1%	23:59:47
2	news.samoylyk.net	32828	255	22114	89	76	0%	23:59:53
3	nntp.terraraq.uk	23472	242	20916	21	0	1%	23:59:49
4	news.tnetconsulting.net	36414	125	22695	54	47	0%	23:59:46
5	i2pn.org	34746	111	22374	260	99	0%	23:59:57
6	news.pasdenom.info	23351	19	22382	9	0	0%	23:59:53
7	news.gegeweb.org	21559	3	20697	3	0	0%	23:59:57
8	news.aioe.org	0	0	0	0	4879	0%	23:59:55
TOTAL: 8		186469	1019	143065	436	5101	0%	191:58:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	nntp.terraraq.uk	2.7 MB	878.2 KB	3.6 MB	13.9 KB	23:59:49
2	news.nntp4.net	2.2 MB	0.0 KB	2.2 MB	8.7 KB	23:59:47
3	news.samoylyk.net	1.5 MB	544.1 KB	2.1 MB	6.1 KB	23:59:53
4	news.tnetconsulting.net	1.1 MB	243.1 KB	1.3 MB	7.6 KB	23:59:46
5	i2pn.org	422.9 KB	1.7 MB	2.1 MB	5.7 KB	23:59:57
6	news.pasdenom.info	101.0 KB	54.1 KB	155.1 KB	5.5 KB	23:59:53
7	news.gegeweb.org	4.2 KB	28.8 KB	33.0 KB	5.5 KB	23:59:57
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:55
TOTAL: 8		8.1 MB	3.4 MB	11.4 MB	8.1 KB	191:58:57

NNRP readership statistics:

System		Conn	Elapsed
1	mail.gegeweb.eu	288	00:02:09
2	88.165.5.23	2	00:00:01
3	gracious.monitoring.internet-measurement.com	1	00:00:10
4	80.150.216.162.bc.googleusercontent.com	1	00:00:00
5	radiant.monitoring.internet-measurement.com	1	00:00:00
6	194.165.16.76	1	00:00:00
7	elite.monitoring.internet-measurement.com	1	00:00:01
8	71.6.134.231	3	00:00:01
9	152.32.140.12	3	00:00:32
10	beautiful.monitoring.internet-measurement.com	1	00:00:00
11	162.243.133.47	1	00:00:09
12	162.243.144.11	1	00:00:00
TOTAL: 12		304	00:03:09

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	4	00:00:12
2	unresolved	11	00:00:47
3	*.gegeweb.eu	288	00:02:09
4	*.150.216.162.bc.googleusercontent.com	1	00:00:00
TOTAL: 4		304	00:03:09

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.353	4.143	1.635	00:02:09
152.32.140.12	0.069	0.055	0.000	00:00:32
gracious.monitoring.internet-measurement.com	0.053	0.034	0.000	00:00:10
162.243.133.47	0.016	0.008	0.000	00:00:09
88.165.5.23	0.061	0.042	0.000	00:00:01
elite.monitoring.internet-measurement.com	0.019	0.011	0.000	00:00:01
71.6.134.231	0.047	0.046	0.000	00:00:01
194.165.16.76	0.033	0.024	0.000	00:00:00
162.243.144.11	0.024	0.028	0.000	00:00:00
80.150.216.162.bc.googleusercontent.com	0.048	0.028	0.000	00:00:00
radiant.monitoring.internet-measurement.com	0.036	0.024	0.000	00:00:00
beautiful.monitoring.internet-measurement.com	0.039	0.027	0.000	00:00:00
TOTAL: 12	4.798	4.470	1.635	00:03:09

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
71.6.134.231	7
80.150.216.162.bc.googleusercontent.com	3
152.32.140.12	2
162.243.144.11	1
TOTAL: 4	13

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
#023#003#023#001#023#002#001	2
Accept: /	1
User-Agent: Expanse, a Palo Alto Network...	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Host: 45.13.105.36:563	1
#026#003#001#001#011#001	1
Accept-Encoding: gzip	1
#026#003#001	1
MGLNDD_45.13.105.36_119	1
Host: 45.13.105.36:119	1
TOTAL: 11	13

Daily Usenet report for news.niel.me

Oct 9 04:15:01 -- Oct 10 04:15:01