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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Oct 16 22:00:03 rpi-studio pgpverify[18487]: pgpverify: verification failed
Oct 16 22:00:03 rpi-studio controlchan[10222]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231016200002$5a6b@isc.org>
Oct 16 22:00:06 rpi-studio pgpverify[18497]: pgpverify: verification failed
Oct 16 22:00:06 rpi-studio controlchan[10222]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231016200003$1896@isc.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	33559	75.9%	6.6 MB	82.1%
innd	6122	13.8%	861.2 KB	10.5%
nnrpd	2534	5.7%	276.8 KB	3.4%
innfeed	2003	4.5%	333.1 KB	4.1%
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	44224	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	100329	51.5%
Negative hits	60932	31.3%
Do not exist	33444	17.2%
Cache misses	8	0.0%
TOTAL: 4	194713	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.014	0.0%	15	0.000	0.933	2.000
article cancel	00:00:00.001	0.0%	15	0.000	0.067	1.000
article cleanup	00:00:00.716	0.0%	33537	0.004	0.021	0.049
article logging	00:00:02.534	0.0%	33537	0.035	0.076	0.130
article parse	00:00:08.568	0.0%	157035	0.033	0.055	0.080
article write	00:00:28.336	0.0%	29553	0.516	0.959	3.016
artlog/artcncl	00:00:00.000	0.0%	15	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	15	0.000	0.133	0.500
data move	00:00:01.912	0.0%	363654	0.002	0.005	0.011
hisgrep/artcncl	00:00:00.001	0.0%	15	0.000	0.067	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	15	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	16	0.000	0.062	1.000
hishave/artcncl	00:00:00.000	0.0%	15	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.002	0.0%	15	0.000	0.133	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.344	0.0%	195271	0.007	0.017	0.027
history sync	00:00:00.045	0.0%	388	0.000	0.116	0.667
history write	00:00:06.887	0.0%	33537	0.102	0.205	0.318
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:56:54.245	99.7%	317039	144.394	271.936	510.704
nntp read	00:00:08.564	0.0%	317526	0.017	0.027	0.038
overview write	00:00:11.868	0.0%	29553	0.120	0.402	16.810
perl filter	00:01:49.257	0.1%	33537	2.677	3.258	4.843
python filter	00:00:00.147	0.0%	33537	0.000	0.004	0.022
site send	00:00:07.118	0.0%	61152	0.035	0.116	0.238
TOTAL: 24:00:58.734	24:00:03.562	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.282	0.0%	29558	0.000	0.010	0.042
article prepare	00:00:00.033	0.0%	7192	0.000	0.005	0.125
article read	00:00:00.185	0.0%	1121	0.000	0.165	1.000
backlog stats	00:00:02.104	0.0%	461762	0.001	0.005	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:07.127	0.0%	309422	0.010	0.023	0.034
data write	00:00:12.221	0.0%	244632	0.039	0.050	0.062
idle	23:52:51.389	100.0%	461762	92.147	186.181	376.293
status file	00:00:00.495	0.0%	221	0.000	2.240	8.000
TOTAL: 23:53:34.237	23:53:13.836	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:21.307	0.0%	470066	0.040	0.045	0.053
idle	00:00:40.587	0.0%	1478	1.000	27.461	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:41.043	0.0%	943485	0.000	0.044	1.000
TOTAL: 151:26:51.216	00:01:42.937	0.0%	-	-	-	-

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	6	30701	13071	16673	957	42%	24:14:28
2	nntp.terraraq.uk	5	23332	5326	16075	1931	22%	16:36:39
3	gegeweb-out.news.tnetconsulting.net	5	30490	3697	26599	194	12%	25:21:04
4	feed-out.gegeweb.org	4	3625	2834	791	0	78%	24:01:07
5	news.glou.org	1	8424	2321	5318	785	27%	24:11:02
6	news.samoylyk.net	4	29700	2006	27694	0	6%	24:39:53
7	i2pn.org	3	8047	222	7824	1	2%	45:40:05
8	usenet.pasdenom.info	1	2456	170	2281	5	6%	18:02:46
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		54	136775	29647	103255	3873	21%	202:47:04

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	106.7 MB	8.4 MB	115.1 MB	92%	8.4 KB
2	nntp.terraraq.uk	51.9 MB	16.1 MB	68.0 MB	76%	9.6 KB
3	gegeweb-out.news.tnetconsulting.net	27.8 MB	958.5 KB	28.8 MB	96%	7.6 KB
4	news.samoylyk.net	17.9 MB	0.0 KB	17.9 MB	100%	9.1 KB
5	news.glou.org	14.3 MB	4.6 MB	18.9 MB	75%	6.2 KB
6	feed-out.gegeweb.org	13.4 MB	0.0 KB	13.4 MB	100%	4.8 KB
7	i2pn.org	5.1 MB	63.4 KB	5.1 MB	98%	23.5 KB
8	usenet.pasdenom.info	382.5 KB	9.6 KB	392.1 KB	97%	2.2 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		237.4 MB	30.1 MB	267.4 MB	88%	8.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 16 04:15:01 - 04:59:59	1082	3.7%	0.40	7.7 MB	3.3%	2.92
Oct 16 05:00:00 - 05:59:59	1380	4.7%	0.38	10.1 MB	4.3%	2.88
Oct 16 06:00:00 - 06:59:59	1291	4.4%	0.36	9.3 MB	4.0%	2.65
Oct 16 07:00:00 - 07:59:59	1177	4.0%	0.33	12.2 MB	5.2%	3.46
Oct 16 08:00:00 - 08:59:59	768	2.6%	0.21	9.2 MB	3.9%	2.63
Oct 16 09:00:00 - 09:59:59	1004	3.4%	0.28	10.1 MB	4.3%	2.88
Oct 16 10:00:00 - 10:59:59	2031	6.9%	0.56	17.7 MB	7.5%	5.03
Oct 16 11:00:00 - 11:59:59	1525	5.2%	0.42	12.4 MB	5.3%	3.52
Oct 16 12:00:00 - 12:59:59	1008	3.4%	0.28	8.1 MB	3.4%	2.29
Oct 16 13:00:00 - 13:59:59	1130	3.8%	0.31	7.6 MB	3.2%	2.17
Oct 16 14:00:00 - 14:59:59	1308	4.4%	0.36	10.4 MB	4.5%	2.97
Oct 16 15:00:00 - 15:59:59	1052	3.6%	0.29	7.8 MB	3.3%	2.21
Oct 16 16:00:00 - 16:59:59	1307	4.4%	0.36	10.1 MB	4.3%	2.88
Oct 16 17:00:00 - 17:59:59	1005	3.4%	0.28	7.7 MB	3.3%	2.20
Oct 16 18:00:00 - 18:59:59	1310	4.4%	0.36	11.4 MB	4.8%	3.23
Oct 16 19:00:00 - 19:59:59	1241	4.2%	0.34	10.2 MB	4.3%	2.89
Oct 16 20:00:00 - 20:59:59	1127	3.8%	0.31	8.5 MB	3.6%	2.42
Oct 16 21:00:00 - 21:59:59	886	3.0%	0.25	7.8 MB	3.3%	2.22
Oct 16 22:00:00 - 22:59:59	1588	5.4%	0.44	12.2 MB	5.2%	3.47
Oct 16 23:00:00 - 23:59:59	1440	4.9%	0.40	9.4 MB	4.0%	2.68
Oct 17 00:00:00 - 00:59:59	1147	3.9%	0.32	7.4 MB	3.1%	2.10
Oct 17 01:00:00 - 01:59:59	947	3.2%	0.26	6.0 MB	2.6%	1.71
Oct 17 02:00:00 - 02:59:59	1288	4.4%	0.36	10.6 MB	4.5%	3.01
Oct 17 03:00:00 - 03:59:59	1238	4.2%	0.34	8.7 MB	3.7%	2.49
Oct 17 04:00:00 - 04:15:01	266	0.9%	0.30	1.9 MB	0.8%	2.19
TOTAL: 24:00:00	29546	100.0%	0.34	234.5 MB	100.0%	2.78

Sites sending bad articles:

Server		Total	Group	Other
1	nntp.terraraq.uk	2049	0	2049
2	news.nntp4.net	967	1	966
3	news.glou.org	771	1	770
4	gegeweb-out.news.tnetconsulting.net	192	2	190
5	usenet.pasdenom.info	3	1	2
6	i2pn.org	1	0	1
TOTAL: 6		3983	5	3978

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	3
comp.lang.go	2
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3041
EMP (phr nph)	453
Too many newsgroups	293
Bad path (news.giganews.com.POSTED)	81
EMP (phn path)	54
EMP (phl)	53
Too many newsgroups (meow)	1
Cancel for rejected article	1
Cancel of non-existing ID <ugk8bp$2f0j2$1@dont-email.me>	1
TOTAL: 9	3978

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	19002	355	15882	1	0	1%	23:59:44
2	nntp.terraraq.uk	25518	227	23315	16	31	0%	23:59:57
3	i2pn.org	51291	210	28770	413	37	0%	23:59:57
4	news.tnetconsulting.net	50551	204	29282	60	30	0%	23:59:57
5	news.samoylyk.net	38798	142	27326	50	21	0%	23:59:55
6	news.gegeweb.org	28699	28	26647	6	0	0%	23:59:57
7	news.pasdenom.info	31256	1	29097	91	0	0%	23:59:57
8	news.aioe.org	0	0	0	0	4560	0%	23:59:56
TOTAL: 8		245115	1167	180319	637	4679	0%	191:59:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	3.2 MB	2.2 KB	3.2 MB	9.1 KB	23:59:44
2	nntp.terraraq.uk	2.1 MB	404.5 KB	2.5 MB	10.3 KB	23:59:57
3	news.tnetconsulting.net	1.6 MB	209.8 KB	1.8 MB	7.1 KB	23:59:57
4	news.samoylyk.net	1.2 MB	201.3 KB	1.4 MB	7.6 KB	23:59:55
5	i2pn.org	1014.6 KB	3.1 MB	4.1 MB	6.7 KB	23:59:57
6	news.gegeweb.org	740.1 KB	76.6 KB	816.7 KB	24.0 KB	23:59:57
7	news.pasdenom.info	2.0 KB	1007.4 KB	1009.4 KB	11.0 KB	23:59:57
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		9.8 MB	4.9 MB	14.7 MB	8.4 KB	191:59:20

NNRP readership statistics:

System		Conn	Elapsed
1	208.100.26.244	10	00:00:04
2	2607:ff10:c8:594::d	3	00:00:03
3	107.170.230.37	1	00:00:00
4	192.241.237.25	1	00:00:01
5	45.133.172.77	24	151:23:56
6	194.165.16.37	2	00:00:01
7	mail.gegeweb.eu	288	00:02:00
8	185.114.175.11	1	00:00:11
9	104.156.155.23	85	00:00:18
10	79.124.59.254	1	00:00:11
TOTAL: 10		416	151:26:50

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:02:00
2	?	3	00:00:03
3	unresolved	125	151:24:46
TOTAL: 3		416	151:26:50

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
45.133.172.77	57.604	80.305	0.000	151:23:56
mail.gegeweb.eu	4.308	4.064	1.588	00:02:00
104.156.155.23	2.319	0.989	0.000	00:00:18
79.124.59.254	0.023	0.031	0.000	00:00:11
185.114.175.11	0.028	0.021	0.000	00:00:11
208.100.26.244	0.223	0.135	0.000	00:00:04
2607:ff10:c8:594::d	0.074	0.095	0.000	00:00:03
194.165.16.37	0.067	0.047	0.000	00:00:01
192.241.237.25	0.032	0.021	0.000	00:00:01
107.170.230.37	0.014	0.009	0.000	00:00:00
TOTAL: 10	64.692	85.717	1.588	151:26:50

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
2607:ff10:c8:594::d	7
107.170.230.37	1
192.241.237.25	1
194.165.16.37	1
TOTAL: 4	10

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	2
Accept-Encoding: gzip	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
#003	1
#026#003#001	1
GET / HTTP/1.1	1
#023#003#023#001#023#002#001	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Accept: /	1
TOTAL: 9	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.133.172.77	8	8
194.165.16.37	1	1
TOTAL: 2	9	9

Daily Usenet report for news.niel.me

Oct 16 04:15:01 -- Oct 17 04:15:01