Daily Usenet report: Oct 1 03:00:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Oct 1 03:11:15 news innd: message repeated 2 times: [ ctlinnd command s]
Oct 1 07:15:02 news pgpverify[1531161]: pgpverify: verification failed
Oct 1 19:54:56 news innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	264369	77.8%	44.5 MB	83.7%
innd	72708	21.4%	8.4 MB	15.8%
innfeed	2412	0.7%	272.1 KB	0.5%
nnrpd	181	0.1%	19.2 KB	0.0%
controlchan	22	0.0%	4.8 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	339693	100.0%	53.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	267158	49.4%
Do not exist	264221	48.9%
Positive hits	9279	1.7%
Cache misses	80	0.0%
TOTAL: 4	540738	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.323	0.0%	24	0.000	13.458	55.667
article cleanup	00:00:02.049	0.0%	264350	0.000	0.008	0.147
article logging	00:00:05.665	0.0%	264408	0.000	0.021	0.312
article parse	00:00:03.085	0.0%	777334	0.000	0.004	0.020
article write	00:16:08.029	1.1%	202523	0.165	4.780	71.829
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.525	0.0%	797790	0.000	0.001	0.265
hisgrep/artcncl	00:00:00.003	0.0%	24	0.000	0.125	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:11:50.749	0.8%	541012	0.000	1.314	15.610
history sync	00:00:00.047	0.0%	306	0.000	0.154	28.000
history write	00:04:13.678	0.3%	264351	0.024	0.960	13.351
idle	23:17:29.786	97.0%	795964	80.446	105.344	187.889
nntp read	00:00:12.945	0.0%	795939	0.000	0.016	0.182
overview write	00:00:30.074	0.0%	202523	0.000	0.148	25.548
perl filter	00:07:49.508	0.5%	264350	0.785	1.776	5.944
python filter	00:00:19.059	0.0%	518546	0.000	0.037	0.397
site send	00:00:04.023	0.0%	202525	0.000	0.020	0.139
TOTAL: 24:00:11.152	23:58:49.548	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.424	0.0%	204853	0.000	0.002	0.025
article prepare	00:00:00.184	0.0%	2288152	0.000	0.000	0.002
article read	00:02:12.711	0.2%	195437	0.035	0.679	15.723
backlog stats	00:00:00.713	0.0%	2115934	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:15.772	0.0%	1363007	0.000	0.012	0.039
data write	00:00:22.603	0.0%	1162478	0.003	0.019	0.043
idle	23:56:07.459	99.8%	2115934	30.941	40.723	146.939
status file	00:00:00.617	0.0%	175	0.000	3.526	424.000
TOTAL: 23:59:27.239	23:59:00.483	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:29.429	72.3%	68	24.714	432.779	10025.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.002	0.0%	249	0.000	0.008	0.067
TOTAL: 00:00:40.679	00:00:29.431	72.3%	-	-	-	-

Control commands to INND:

Command	Number
flush	2
flushlogs	2
go	2
logmode	2
lowmark	1
mode	176
name	30
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	223

Control Channel:

Sender	Other	Bad PGP	OK
control@eternal-september.org	1	0	1
moderator@dana.de	1	1	1
TOTAL	2	1	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	0	254210	194092	63	60055	76%	24:00:17
2	usenet.blueworldhosting.com	288	10184	5661	2793	1730	55%	23:43:20
3	news.sklaff.dev	2	9383	2794	6542	47	29%	24:09:36
4	localhost	25	0	0	0	0	0%	00:00:00
5	news.sklaffkom.se	2	0	0	0	0	0%	00:10:53
6	news.thebunker.nu	6	0	0	0	0	0%	00:00:00
TOTAL: 6		323	273777	202547	9398	61832	73%	72:04:06

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	368.3 MB	116.7 MB	485.1 MB	75%	2.0 KB
2	usenet.blueworldhosting.com	16.5 MB	4.2 MB	20.7 MB	79%	2.9 KB
3	news.sklaff.dev	6.3 MB	113.8 KB	6.4 MB	98%	2.3 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
5	news.sklaffkom.se	0.0 KB	0.0 KB	0.0 KB	0%	0
6	news.thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 6		391.1 MB	121.1 MB	512.1 MB	76%	2.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 03:00:02 - 03:59:59	8668	4.3%	2.41	17.4 MB	4.4%	4.94
Oct 1 04:00:00 - 04:59:59	7560	3.7%	2.10	14.8 MB	3.8%	4.21
Oct 1 05:00:00 - 05:59:59	8525	4.2%	2.37	17.3 MB	4.4%	4.93
Oct 1 06:00:00 - 06:59:59	7710	3.8%	2.14	15.5 MB	4.0%	4.42
Oct 1 07:00:00 - 07:59:59	7475	3.7%	2.08	15.2 MB	3.9%	4.31
Oct 1 08:00:00 - 08:59:59	7791	3.8%	2.16	16.2 MB	4.1%	4.60
Oct 1 09:00:00 - 09:59:59	7893	3.9%	2.19	16.3 MB	4.2%	4.63
Oct 1 10:00:00 - 10:59:59	6410	3.2%	1.78	12.6 MB	3.2%	3.58
Oct 1 11:00:00 - 11:59:59	6304	3.1%	1.75	11.9 MB	3.1%	3.39
Oct 1 12:00:00 - 12:59:59	6713	3.3%	1.86	12.5 MB	3.2%	3.56
Oct 1 13:00:00 - 13:59:59	7611	3.8%	2.11	14.8 MB	3.8%	4.21
Oct 1 14:00:00 - 14:59:59	7773	3.8%	2.16	15.3 MB	3.9%	4.36
Oct 1 15:00:00 - 15:59:59	9610	4.7%	2.67	19.0 MB	4.9%	5.42
Oct 1 16:00:00 - 16:59:59	9689	4.8%	2.69	18.2 MB	4.7%	5.18
Oct 1 17:00:00 - 17:59:59	9649	4.8%	2.68	16.2 MB	4.1%	4.61
Oct 1 18:00:00 - 18:59:59	9216	4.6%	2.56	15.6 MB	4.0%	4.43
Oct 1 19:00:00 - 19:59:59	9217	4.6%	2.56	15.8 MB	4.1%	4.51
Oct 1 20:00:00 - 20:59:59	9562	4.7%	2.66	16.8 MB	4.3%	4.78
Oct 1 21:00:00 - 21:59:59	9643	4.8%	2.68	18.9 MB	4.8%	5.37
Oct 1 22:00:00 - 22:59:59	8864	4.4%	2.46	17.8 MB	4.5%	5.05
Oct 1 23:00:00 - 23:59:59	9347	4.6%	2.60	18.2 MB	4.7%	5.19
Oct 2 00:00:00 - 00:59:59	9722	4.8%	2.70	19.5 MB	5.0%	5.55
Oct 2 01:00:00 - 01:59:59	9570	4.7%	2.66	19.3 MB	4.9%	5.50
Oct 2 02:00:00 - 02:59:59	7977	3.9%	2.22	15.7 MB	4.0%	4.45
Oct 2 03:00:00 - 03:00:01	2	0.0%	2.00	3.9 KB	0.0%	3.90
TOTAL: 23:59:59	202501	100.0%	2.34	390.9 MB	100.0%	4.63

Sites sending bad articles:

Server		Total	Group	Other
1	chimia.se	60034	0	60034
2	usenet.blueworldhosting.com	1777	558	1219
3	news.sklaff.dev	57	0	57
TOTAL: 3		61868	558	61310

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.comm.provider.usenet	103
fr.rec.bricolage	23
de.rec.heimwerken	19
aus.computers	13
de.comm.software.crosspoint	10
de.comm.software.gnus	10
fr.test	10
free.it.scienza.fisica	10
it.discussioni.geometri	10
linux.ubuntu.security.announce	10
de.alt.comm.android	9
uk.telecom.voip	9
de.admin.infos	8
it.sport.calcio.torino	7
microsoft.public.de.money	7
muc.lists.freebsd.multimedia	7
nz.general	7
de.comm.software.mozilla.browser	6
alt.my.job.sucks	5
de.rec.tv.serien	5
TOTAL: 191	558

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	26676
EMP (phn path)	26248
EMP (phl)	6430
EMP (md5)	1595
Too many newsgroups	157
HTML Multipart	50
User-issued cancel	38
Subject Rejected	31
Scoring filter	11
Too many newsgroups (low_xpost)	5
HTML Image Tags	3
MI5 Filter	3
HTML post	2
Too many newsgroups (meow)	1
Too many test groups in crosspost	1
TOTAL: 15	61251

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	21
	TOTAL: 1	21
Including strange strings
	usenet.blueworldhosting.com	48
	news.sklaff.dev	10
	TOTAL: 2	58
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 3		80

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaff.dev	200528	8984	5215	185524	0	4%	23:59:57
2	sklaffkom.se	203770	158	7413	194931	174	0%	23:59:57
3	usenet.blueworldhosting.com	198650	0	9536	182574	2220	0%	23:59:54
4	thebunker.nu	0	0	0	0	202501	0%	23:59:57
TOTAL: 4		602948	9142	22164	563029	204895	1%	95:59:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaff.dev	19.4 MB	350.4 MB	369.8 MB	4.4 KB/s	1.9 KB	23:59:57
2	sklaffkom.se	168.4 KB	371.2 MB	371.4 MB	4.4 KB/s	1.9 KB	23:59:57
3	thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
4	usenet.blueworldhosting.com	0.0 KB	346.1 MB	346.1 MB	4.1 KB/s	1.9 KB	23:59:54
TOTAL: 4		19.5 MB	1.0 GB	1.1 GB	3.2 KB/s	1.9 KB	95:59:45

NNRP readership statistics:

System		Conn	Elapsed
1	81-229-79-101-no600.tbcn.telia.com	6	00:00:06
2	199.45.154.137	3	00:00:08
3	147.185.133.251	2	00:00:06
4	212.211.203.35.bc.googleusercontent.com	2	00:00:00
5	azpdwspbp1zh.stretchoid.com	2	00:00:10
6	147.185.133.25	1	00:00:00
7	147.185.133.53	1	00:00:00
8	165.154.110.84	1	00:00:00
9	166.149.216.162.bc.googleusercontent.com	1	00:00:00
10	198.235.24.17	1	00:00:02
11	205.210.31.163	1	00:00:01
12	206.168.34.42	1	00:00:00
13	45-79-207-181.ip.linodeusercontent.com	1	00:00:01
14	71.6.134.234	1	00:00:00
15	c-5eea5f51-74736162.cust.telenor.se	1	00:00:00
TOTAL: 15		25	00:00:40

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	12	00:00:21
2	*.tbcn.telia.com	6	00:00:06
3	*.211.203.35.bc.googleusercontent.com	2	00:00:00
4	*.stretchoid.com	2	00:00:10
5	*.149.216.162.bc.googleusercontent.com	1	00:00:00
6	*.cust.telenor.se	1	00:00:00
7	*.ip.linodeusercontent.com	1	00:00:01
TOTAL: 7		25	00:00:40

NNRP auth users [Top 20]:

User	Conn
peterlondon	7
TOTAL: 1	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
azpdwspbp1zh.stretchoid.com	0.017	0.017	0.000	00:00:10
199.45.154.137	0.072	0.024	0.000	00:00:08
147.185.133.251	0.149	0.013	0.000	00:00:06
81-229-79-101-no600.tbcn.telia.com	0.074	0.056	0.183	00:00:06
198.235.24.17	0.005	0.007	0.000	00:00:02
205.210.31.163	0.010	0.005	0.000	00:00:01
45-79-207-181.ip.linodeusercontent.com	0.006	0.009	0.000	00:00:01
212.211.203.35.bc.googleusercontent.com	0.152	0.013	0.000	00:00:00
147.185.133.25	0.075	0.007	0.000	00:00:00
147.185.133.53	0.045	0.009	0.000	00:00:00
206.168.34.42	0.044	0.007	0.000	00:00:00
71.6.134.234	0.008	0.011	0.000	00:00:00
165.154.110.84	0.011	0.011	0.000	00:00:00
166.149.216.162.bc.googleusercontent.com	0.074	0.010	0.000	00:00:00
c-5eea5f51-74736162.cust.telenor.se	0.006	0.007	0.020	00:00:00
TOTAL: 15	0.748	0.206	0.203	00:00:40

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host) [Top 20]:

System	Conn
147.185.133.25	4
147.185.133.251	4
147.185.133.53	4
166.149.216.162.bc.googleusercontent.com	4
212.211.203.35.bc.googleusercontent.com	4
199.45.154.137	1
206.168.34.42	1
azpdwspbp1zh.stretchoid.com	1
TOTAL: 8	23

NNRP unrecognized commands (by command) [Top 20]:

Command	Count
GET / HTTP/1.1	5
Host: 109.238.130.155:563	5
Accept-Encoding: gzip	3
User-Agent: Hello from Palo Alto Network...	3
Accept: /	2
EHLO www.censys.io	2
User-Agent: curl/7.68.0	2
MGLNDD_109.238.130.155_119	1
TOTAL: 8	23

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.185.133.25	1	1
147.185.133.251	1	2
147.185.133.53	1	1
166.149.216.162.bc.googleusercontent.com	1	1
199.45.154.137	1	2
212.211.203.35.bc.googleusercontent.com	1	2
TOTAL: 6	6	12

Daily Usenet report

Oct 1 03:00:02 -- Oct 2 03:00:01