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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Nov 9 22:00:03 rpi-studio pgpverify[19221]: pgpverify: verification failed
Nov 9 22:00:03 rpi-studio controlchan[12213]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231109210002$08fe@isc.org>
Nov 9 22:00:06 rpi-studio pgpverify[19229]: pgpverify: verification failed
Nov 9 22:00:06 rpi-studio controlchan[12213]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231109210002$29ef@isc.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	27509	67.4%	5.1 MB	74.7%
innd	8755	21.5%	1.1 MB	16.5%
nnrpd	2563	6.3%	280.8 KB	4.0%
innfeed	1955	4.8%	334.0 KB	4.7%
controlchan	3	0.0%	0.2 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 7	40788	100.0%	6.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	52367	42.1%
Negative hits	44642	35.9%
Do not exist	27304	21.9%
Cache misses	82	0.1%
TOTAL: 4	124395	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	8	0.000	0.750	2.000
article cancel	00:00:00.001	0.0%	8	0.000	0.125	1.000
article cleanup	00:00:00.564	0.0%	27349	0.000	0.021	0.057
article logging	00:00:02.144	0.0%	27349	0.041	0.078	0.133
article parse	00:00:07.767	0.0%	138586	0.034	0.056	0.086
article write	00:00:15.413	0.0%	20688	0.457	0.745	3.111
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:00:02.083	0.0%	269253	0.001	0.008	0.018
hisgrep/artcncl	00:00:00.001	0.0%	8	0.000	0.125	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	8	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.181	0.0%	124617	0.007	0.018	0.035
history sync	00:00:00.051	0.0%	377	0.000	0.135	1.000
history write	00:00:06.176	0.0%	27349	0.105	0.226	0.381
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:47:34.608	99.8%	234636	140.212	365.053	856.827
nntp read	00:00:06.975	0.0%	234042	0.017	0.030	0.046
overview write	00:00:08.878	0.0%	20688	0.126	0.429	22.120
perl filter	00:01:44.924	0.1%	27349	3.189	3.836	5.218
python filter	00:00:00.128	0.0%	27349	0.000	0.005	0.037
site send	00:00:05.459	0.0%	42676	0.037	0.128	0.273
TOTAL: 23:50:57.905	23:50:17.359	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.184	0.0%	21771	0.000	0.008	0.040
article prepare	00:00:00.049	0.0%	11872	0.000	0.004	0.050
article read	00:00:00.417	0.0%	2208	0.000	0.189	1.333
backlog stats	00:00:01.444	0.0%	315356	0.001	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.990	0.0%	216896	0.011	0.023	0.036
data write	00:00:08.671	0.0%	170355	0.035	0.051	0.067
idle	23:54:43.345	100.0%	315356	134.115	272.972	649.278
status file	00:00:00.434	0.0%	194	1.000	2.237	8.000
TOTAL: 23:55:15.263	23:54:59.534	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.054	0.0%	1255	0.019	0.043	0.058
idle	00:00:57.112	2.5%	1529	15.000	37.353	1775.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.226	0.0%	6082	0.000	0.037	1.000
TOTAL: 00:38:26.918	00:00:57.392	2.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	news.nntp4.net	5	26590	9011	11923	5656	33%	24:21:55
2	news.glou.org	3	12496	4775	6817	904	38%	25:07:16
3	gegeweb-out.news.tnetconsulting.net	2	19636	4630	14908	98	23%	23:07:40
4	feed-out.gegeweb.org	2	3726	1435	2291	0	38%	23:59:56
5	usenet.pasdenom.info	1	4540	377	4154	9	8%	42:25:22
6	i2pn.org	2	4648	282	4366	0	6%	26:25:56
7	nntp.terraraq.uk	2	4058	213	3789	56	5%	14:59:52
8	news.samoylyk.net	2	3770	12	3757	1	0%	23:16:05
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		44	79464	20735	52005	6724	26%	203:44:02

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	105.5 MB	53.9 MB	159.4 MB	66%	11.1 KB
2	gegeweb-out.news.tnetconsulting.net	34.6 MB	306.5 KB	34.9 MB	99%	7.6 KB
3	news.glou.org	26.2 MB	5.8 MB	32.0 MB	81%	5.8 KB
4	feed-out.gegeweb.org	11.7 MB	0.0 KB	11.7 MB	100%	8.4 KB
5	i2pn.org	3.0 MB	0.0 KB	3.0 MB	100%	11.0 KB
6	usenet.pasdenom.info	1022.4 KB	13.8 KB	1.0 MB	98%	2.7 KB
7	nntp.terraraq.uk	961.6 KB	490.2 KB	1.4 MB	66%	5.4 KB
8	news.samoylyk.net	330.2 KB	131.2 KB	461.4 KB	71%	35.5 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		183.3 MB	60.6 MB	243.9 MB	75%	9.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 9 04:15:01 - 04:59:59	1025	4.9%	0.38	7.6 MB	4.1%	2.89
Nov 9 05:00:00 - 05:59:59	960	4.6%	0.27	9.6 MB	5.2%	2.73
Nov 9 06:00:00 - 06:59:59	990	4.8%	0.28	10.3 MB	5.6%	2.94
Nov 9 07:00:00 - 07:59:59	1217	5.8%	0.34	13.2 MB	7.2%	3.77
Nov 9 08:00:00 - 08:59:59	1282	6.2%	0.36	13.4 MB	7.2%	3.81
Nov 9 09:00:00 - 09:59:59	1273	6.1%	0.35	12.9 MB	7.0%	3.66
Nov 9 10:00:00 - 10:59:59	1119	5.4%	0.31	9.6 MB	5.2%	2.73
Nov 9 11:00:00 - 11:59:59	946	4.5%	0.26	10.6 MB	5.7%	3.02
Nov 9 12:00:00 - 12:59:59	1014	4.9%	0.28	11.9 MB	6.4%	3.39
Nov 9 13:00:00 - 13:59:59	1190	5.7%	0.33	11.1 MB	6.0%	3.16
Nov 9 14:00:00 - 14:59:59	888	4.3%	0.25	7.5 MB	4.1%	2.13
Nov 9 15:00:00 - 15:59:59	1235	5.9%	0.34	12.1 MB	6.5%	3.44
Nov 9 16:00:00 - 16:59:59	874	4.2%	0.24	9.1 MB	4.9%	2.59
Nov 9 17:00:00 - 17:59:59	876	4.2%	0.24	6.7 MB	3.6%	1.92
Nov 9 18:00:00 - 18:59:59	687	3.3%	0.19	6.2 MB	3.3%	1.75
Nov 9 19:00:00 - 19:59:59	588	2.8%	0.16	4.2 MB	2.3%	1.19
Nov 9 20:00:00 - 20:59:59	658	3.2%	0.18	4.3 MB	2.3%	1.22
Nov 9 21:00:00 - 21:59:59	491	2.4%	0.14	3.1 MB	1.7%	0.88
Nov 9 22:00:00 - 22:59:59	584	2.8%	0.16	3.1 MB	1.7%	0.89
Nov 9 23:00:00 - 23:59:59	502	2.4%	0.14	2.5 MB	1.4%	0.72
Nov 10 00:00:00 - 00:59:59	491	2.4%	0.14	2.6 MB	1.4%	0.74
Nov 10 01:00:00 - 01:59:59	516	2.5%	0.14	3.3 MB	1.8%	0.93
Nov 10 02:00:00 - 02:59:59	577	2.8%	0.16	3.0 MB	1.6%	0.85
Nov 10 03:00:00 - 03:59:59	626	3.0%	0.17	5.2 MB	2.8%	1.48
Nov 10 04:00:00 - 04:15:01	195	0.9%	0.22	1.9 MB	1.0%	2.15
TOTAL: 24:00:00	20804	100.0%	0.24	185.1 MB	100.0%	2.19

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	5646	5646
2	news.glou.org	847	847
3	gegeweb-out.news.tnetconsulting.net	103	103
4	nntp.terraraq.uk	86	86
5	usenet.pasdenom.info	6	6
6	news.samoylyk.net	1	1
TOTAL: 6		6689	6689

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6487
Bad path (news.giganews.com.POSTED)	121
Too many newsgroups	51
EMP (phl)	23
Cancels abusifs	3
Cancel of non-existing ID <uijf10$1si5$1@cabale.usenet-fr.net>	1
HTML Multipart	1
EMP (phn path)	1
Cancel for rejected article	1
TOTAL: 9	6689

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nntp.terraraq.uk	21961	539	18018	946	11	2%	23:59:56
2	news.nntp4.net	14110	315	11423	21	254	2%	23:59:56
3	news.tnetconsulting.net	32018	212	20558	30	98	0%	23:59:56
4	i2pn.org	30651	100	19833	513	375	0%	23:59:36
5	news.pasdenom.info	22110	28	20371	26	155	0%	23:59:56
6	news.samoylyk.net	29478	21	20584	187	142	0%	23:59:52
7	news.gegeweb.org	19500	1	19276	33	0	0%	23:59:56
8	news.aioe.org	0	0	0	0	5010	0%	23:59:56
TOTAL: 8		169828	1216	130063	1756	6045	0%	191:59:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nntp.terraraq.uk	2.9 MB	6.1 MB	9.0 MB	0.1 KB/s	6.2 KB	23:59:56
2	news.nntp4.net	2.1 MB	75.4 KB	2.1 MB	0.0 KB/s	6.5 KB	23:59:56
3	news.tnetconsulting.net	1.6 MB	181.9 KB	1.8 MB	0.0 KB/s	7.5 KB	23:59:56
4	i2pn.org	466.8 KB	4.3 MB	4.7 MB	0.1 KB/s	7.9 KB	23:59:36
5	news.pasdenom.info	93.0 KB	308.4 KB	401.4 KB	0.0 KB/s	7.4 KB	23:59:56
6	news.samoylyk.net	78.5 KB	1.2 MB	1.3 MB	0.0 KB/s	6.4 KB	23:59:52
7	news.gegeweb.org	15.8 KB	569.0 KB	584.8 KB	0.0 KB/s	17.2 KB	23:59:56
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
TOTAL: 8		7.2 MB	12.7 MB	19.9 MB	0.0 KB/s	6.9 KB	191:59:04

NNRP readership statistics:

System		Conn	Elapsed
1	sensational.monitoring.internet-measurement.com	1	00:00:04
2	194.165.16.76	1	00:00:00
3	172-104-137-47.ip.linodeusercontent.com	79	00:00:20
4	143.150.216.162.bc.googleusercontent.com	1	00:00:01
5	104.156.155.23	85	00:00:17
6	45.227.254.48	1	00:00:00
7	138.199.29.15	8	00:35:42
8	swanky.monitoring.internet-measurement.com	1	00:00:01
9	43.211.203.35.bc.googleusercontent.com	1	00:00:00
10	mail.gegeweb.eu	288	00:01:53
11	intelligent.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 11		467	00:38:26

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	95	00:36:01
2	*.ip.linodeusercontent.com	79	00:00:20
3	*.monitoring.internet-measurement.com	3	00:00:08
4	*.150.216.162.bc.googleusercontent.com	1	00:00:01
5	*.gegeweb.eu	288	00:01:53
6	*.211.203.35.bc.googleusercontent.com	1	00:00:00
TOTAL: 6		467	00:38:26

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
138.199.29.15	0.573	0.339	0.000	00:35:42
mail.gegeweb.eu	4.614	4.370	1.635	00:01:53
172-104-137-47.ip.linodeusercontent.com	1.832	1.134	0.000	00:00:20
104.156.155.23	2.260	1.098	0.000	00:00:17
sensational.monitoring.internet-measurement.com	0.020	0.005	0.000	00:00:04
intelligent.monitoring.internet-measurement.com	0.018	0.026	0.000	00:00:01
swanky.monitoring.internet-measurement.com	0.036	0.016	0.000	00:00:01
143.150.216.162.bc.googleusercontent.com	0.050	0.029	0.000	00:00:01
43.211.203.35.bc.googleusercontent.com	0.027	0.027	0.000	00:00:00
45.227.254.48	0.016	0.008	0.000	00:00:00
194.165.16.76	0.034	0.026	0.000	00:00:00
TOTAL: 11	9.480	7.078	1.635	00:38:26

NNRP unrecognized commands (by host):

System	Conn
43.211.203.35.bc.googleusercontent.com	3
143.150.216.162.bc.googleusercontent.com	3
45.227.254.48	1
TOTAL: 3	7

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.48	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Nov 9 04:15:01 -- Nov 10 04:15:01