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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Oct 10 22:00:02 rpi-studio pgpverify[16538]: pgpverify: verification failed
Oct 10 22:00:02 rpi-studio controlchan[9654]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231010200002$135f@isc.org>
Oct 10 22:00:05 rpi-studio pgpverify[16546]: pgpverify: verification failed
Oct 10 22:00:05 rpi-studio controlchan[9654]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231010200002$0960@isc.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	40855	79.8%	8.2 MB	85.3%
innd	6242	12.2%	862.6 KB	8.8%
innfeed	2042	4.0%	356.3 KB	3.6%
nnrpd	2031	4.0%	225.7 KB	2.3%
controlchan	3	0.0%	0.3 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 7	51176	100.0%	9.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	102082	49.6%
Negative hits	63395	30.8%
Do not exist	40354	19.6%
Cache misses	9	0.0%
TOTAL: 4	205840	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.022	0.0%	23	0.333	0.957	2.000
article cancel	00:00:00.004	0.0%	23	0.000	0.174	1.000
article cleanup	00:00:00.836	0.0%	40497	0.000	0.021	0.040
article logging	00:00:03.001	0.0%	40497	0.021	0.074	0.115
article parse	00:00:10.772	0.0%	193503	0.035	0.056	0.085
article write	00:00:25.017	0.0%	36389	0.462	0.687	0.875
artlog/artcncl	00:00:00.001	0.0%	23	0.000	0.043	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	23	0.000	0.000	0.000
data move	00:00:02.577	0.0%	426130	0.002	0.006	0.018
hisgrep/artcncl	00:00:00.001	0.0%	23	0.000	0.043	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	23	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	23	0.000	0.043	1.000
hishave/artcncl	00:00:00.000	0.0%	23	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	23	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:03.518	0.0%	206555	0.010	0.017	0.032
history sync	00:00:00.058	0.0%	369	0.000	0.157	1.000
history write	00:00:07.940	0.0%	40497	0.084	0.196	0.327
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:56:22.937	99.7%	358185	109.855	240.610	849.692
nntp read	00:00:10.071	0.0%	357785	0.018	0.028	0.048
overview write	00:00:09.934	0.0%	36389	0.142	0.273	0.615
perl filter	00:02:08.237	0.1%	40497	2.662	3.167	4.735
python filter	00:00:00.164	0.0%	40497	0.000	0.004	0.020
site send	00:00:09.123	0.0%	74916	0.036	0.122	0.216
TOTAL: 24:00:54.639	23:59:54.214	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.335	0.0%	36783	0.000	0.009	0.031
article prepare	00:00:00.019	0.0%	6588	0.000	0.003	0.036
article read	00:00:00.205	0.0%	1197	0.000	0.171	0.600
backlog stats	00:00:02.539	0.0%	557267	0.001	0.005	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:08.819	0.0%	388823	0.011	0.023	0.034
data write	00:00:14.147	0.0%	296780	0.033	0.048	0.072
idle	23:52:28.084	99.9%	557267	76.168	154.231	563.664
status file	00:00:00.371	0.0%	222	0.000	1.671	7.000
TOTAL: 23:53:19.037	23:52:54.519	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:45.092	28.0%	1539	10.600	29.300	10017.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.121	0.1%	3454	0.000	0.035	2.667
TOTAL: 00:02:40.948	00:00:45.213	28.1%	-	-	-	-

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	38797	20810	15254	2733	53%	25:06:50
2	gegeweb-out.news.tnetconsulting.net	8	35970	7268	28626	76	20%	24:57:25
3	news.glou.org	3	9618	3766	4915	937	39%	24:11:26
4	feed-out.gegeweb.org	3	3891	2504	1387	0	64%	23:59:37
5	news.samoylyk.net	4	42561	1333	41225	3	3%	30:29:21
6	nntp.terraraq.uk	2	10722	851	9491	380	7%	23:16:35
7	usenet.pasdenom.info	1	1005	72	931	2	7%	08:14:42
8	i2pn.org	1	383	0	383	0	0%	02:04:48
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		52	142947	36604	102212	4131	25%	162:20:44

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	190.7 MB	17.7 MB	208.4 MB	91%	9.1 KB
2	gegeweb-out.news.tnetconsulting.net	63.4 MB	337.7 KB	63.8 MB	99%	8.9 KB
3	news.glou.org	24.5 MB	5.4 MB	29.9 MB	81%	6.5 KB
4	feed-out.gegeweb.org	12.8 MB	0.0 KB	12.8 MB	100%	5.2 KB
5	news.samoylyk.net	12.1 MB	16.2 KB	12.1 MB	99%	9.3 KB
6	nntp.terraraq.uk	6.8 MB	2.3 MB	9.1 MB	74%	7.6 KB
7	usenet.pasdenom.info	157.2 KB	4.6 KB	161.7 KB	97%	2.2 KB
8	i2pn.org	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		310.4 MB	25.8 MB	336.2 MB	92%	8.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 10 04:15:01 - 04:59:59	740	2.0%	0.27	5.3 MB	1.7%	2.01
Oct 10 05:00:00 - 05:59:59	2051	5.6%	0.57	17.3 MB	5.6%	4.93
Oct 10 06:00:00 - 06:59:59	1868	5.1%	0.52	15.8 MB	5.1%	4.48
Oct 10 07:00:00 - 07:59:59	1688	4.6%	0.47	14.0 MB	4.5%	3.98
Oct 10 08:00:00 - 08:59:59	2520	6.9%	0.70	21.9 MB	7.0%	6.22
Oct 10 09:00:00 - 09:59:59	1271	3.5%	0.35	10.3 MB	3.3%	2.92
Oct 10 10:00:00 - 10:59:59	1167	3.2%	0.32	9.2 MB	3.0%	2.63
Oct 10 11:00:00 - 11:59:59	1359	3.7%	0.38	11.5 MB	3.7%	3.28
Oct 10 12:00:00 - 12:59:59	1938	5.3%	0.54	17.6 MB	5.7%	5.02
Oct 10 13:00:00 - 13:59:59	1519	4.1%	0.42	12.5 MB	4.0%	3.57
Oct 10 14:00:00 - 14:59:59	1113	3.0%	0.31	8.5 MB	2.7%	2.41
Oct 10 15:00:00 - 15:59:59	1316	3.6%	0.37	10.4 MB	3.3%	2.95
Oct 10 16:00:00 - 16:59:59	1259	3.4%	0.35	10.0 MB	3.2%	2.85
Oct 10 17:00:00 - 17:59:59	2026	5.5%	0.56	16.8 MB	5.4%	4.79
Oct 10 18:00:00 - 18:59:59	1753	4.8%	0.49	15.2 MB	4.9%	4.33
Oct 10 19:00:00 - 19:59:59	1632	4.4%	0.45	15.2 MB	4.9%	4.31
Oct 10 20:00:00 - 20:59:59	1805	4.9%	0.50	15.0 MB	4.8%	4.28
Oct 10 21:00:00 - 21:59:59	2172	5.9%	0.60	17.9 MB	5.8%	5.09
Oct 10 22:00:00 - 22:59:59	2160	5.9%	0.60	18.9 MB	6.1%	5.38
Oct 10 23:00:00 - 23:59:59	1226	3.3%	0.34	11.3 MB	3.6%	3.20
Oct 11 00:00:00 - 00:59:59	826	2.2%	0.23	8.1 MB	2.6%	2.30
Oct 11 01:00:00 - 01:59:59	721	2.0%	0.20	6.2 MB	2.0%	1.75
Oct 11 02:00:00 - 02:59:59	645	1.8%	0.18	5.4 MB	1.7%	1.53
Oct 11 03:00:00 - 03:59:59	1151	3.1%	0.32	9.9 MB	3.2%	2.82
Oct 11 04:00:00 - 04:15:01	797	2.2%	0.88	6.7 MB	2.1%	7.58
TOTAL: 24:00:00	36723	100.0%	0.43	310.8 MB	100.0%	3.68

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	2692	2	2690
2	news.glou.org	932	7	925
3	nntp.terraraq.uk	378	0	378
4	gegeweb-out.news.tnetconsulting.net	75	2	73
5	usenet.pasdenom.info	6	3	3
6	news.samoylyk.net	3	0	3
TOTAL: 6		4086	14	4072

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.lang.go	10
fr.petites-annonces.informatique.materiel	4
TOTAL: 2	14

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2879
EMP (phr nph)	698
EMP (phl)	147
Bad path (news.giganews.com.POSTED)	112
Too many newsgroups	95
EMP (phn path)	83
hierarchy violation - crosspost outside sdnet	39
Too many newsgroups (meow)	13
Too many newsgroups (low_xpost)	3
EMP (fsl)	2
Cancels abusifs	1
TOTAL: 11	4072

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	17655	230	15545	1	8	1%	23:59:57
2	nntp.terraraq.uk	38284	195	34852	38	1	0%	23:59:56
3	i2pn.org	59852	165	35900	572	326	0%	23:59:20
4	news.samoylyk.net	51554	160	35239	82	26	0%	23:59:36
5	news.tnetconsulting.net	60341	116	36543	58	49	0%	23:59:53
6	news.pasdenom.info	37972	12	36328	8	13	0%	23:59:57
7	news.gegeweb.org	35342	2	34197	15	0	0%	23:59:51
8	news.aioe.org	0	0	0	0	5363	0%	23:59:56
TOTAL: 8		301000	880	228604	774	5786	0%	191:58:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	2.0 MB	12.3 KB	2.0 MB	0.0 KB/s	8.8 KB	23:59:57
2	nntp.terraraq.uk	1.5 MB	1.5 MB	3.0 MB	0.0 KB/s	13.1 KB	23:59:56
3	news.samoylyk.net	1.2 MB	511.4 KB	1.7 MB	0.0 KB/s	7.4 KB	23:59:36
4	news.tnetconsulting.net	954.1 KB	342.9 KB	1.3 MB	0.0 KB/s	7.5 KB	23:59:53
5	i2pn.org	683.3 KB	4.8 MB	5.5 MB	0.1 KB/s	7.6 KB	23:59:20
6	news.pasdenom.info	49.7 KB	114.4 KB	164.1 KB	0.0 KB/s	8.2 KB	23:59:57
7	news.gegeweb.org	4.7 KB	138.3 KB	143.0 KB	0.0 KB/s	8.4 KB	23:59:51
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
TOTAL: 8		6.4 MB	7.4 MB	13.7 MB	0.0 KB/s	8.5 KB	191:58:26

NNRP readership statistics:

System		Conn	Elapsed
1	107.170.227.25	1	00:00:00
2	172-104-137-47.ip.linodeusercontent.com	93	00:00:34
3	172.245.90.197	1	00:00:11
4	80.66.88.215	1	00:00:00
5	mail.gegeweb.eu	288	00:01:53
6	94.102.61.39	1	00:00:00
TOTAL: 6		385	00:02:40

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.ip.linodeusercontent.com	93	00:00:34
2	*.gegeweb.eu	288	00:01:53
3	unresolved	4	00:00:13
TOTAL: 3		385	00:02:40

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.267	3.958	1.572	00:01:53
172-104-137-47.ip.linodeusercontent.com	2.256	1.249	0.000	00:00:34
172.245.90.197	0.010	0.014	0.000	00:00:11
94.102.61.39	0.035	0.022	0.000	00:00:00
107.170.227.25	0.019	0.005	0.000	00:00:00
80.66.88.215	0.032	0.020	0.000	00:00:00
TOTAL: 6	6.619	5.268	1.572	00:02:40

NNRP unrecognized commands (by host):

System	Conn
80.66.88.215	1
107.170.227.25	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
#003	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.88.215	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Oct 10 04:15:01 -- Oct 11 04:15:01