Daily Usenet report: Sep 28 03:00:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 28 03:09:05 news innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	256489	76.4%	36.6 MB	80.5%
innd	76433	22.8%	8.6 MB	18.9%
innfeed	2404	0.7%	280.4 KB	0.6%
nnrpd	197	0.1%	20.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	335524	100.0%	45.5 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	258693	48.4%
Do not exist	256279	48.0%
Positive hits	11490	2.1%
Cache misses	7991	1.5%
TOTAL: 4	534453	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.441	0.0%	529	0.000	0.834	66.000
article cleanup	00:00:01.421	0.0%	256285	0.000	0.006	0.200
article logging	00:00:04.443	0.0%	256433	0.000	0.017	0.111
article parse	00:00:02.784	0.0%	763089	0.000	0.004	0.023
article write	00:03:17.693	0.2%	195058	0.000	1.014	262.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.460	0.0%	816522	0.000	0.001	0.188
hisgrep/artcncl	00:00:00.184	0.0%	529	0.000	0.348	33.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:11:51.944	0.8%	534707	0.000	1.331	9.296
history sync	00:00:00.009	0.0%	301	0.000	0.030	1.000
history write	00:03:38.556	0.3%	256277	0.000	0.853	16.000
idle	23:32:48.556	98.1%	795058	65.125	106.619	1166.115
nntp read	00:00:07.350	0.0%	793827	0.000	0.009	0.147
overview write	00:00:24.925	0.0%	195058	0.000	0.128	9.271
perl filter	00:06:39.380	0.5%	256276	0.724	1.558	36.000
python filter	00:00:14.029	0.0%	511389	0.000	0.027	0.391
site send	00:00:02.237	0.0%	195049	0.000	0.011	0.417
TOTAL: 24:00:11.458	23:59:14.412	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.346	0.0%	195318	0.000	0.002	0.056
article prepare	00:00:00.116	0.0%	1520148	0.000	0.000	0.002
article read	00:00:55.579	0.1%	187013	0.000	0.297	7.437
backlog stats	00:00:00.449	0.0%	1543807	0.000	0.000	0.050
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:06.093	0.0%	972229	0.000	0.006	0.070
data write	00:00:10.389	0.0%	778399	0.000	0.013	0.143
idle	23:57:32.754	99.9%	1543807	28.266	55.870	30100.000
status file	00:00:00.051	0.0%	177	0.000	0.288	1.000
TOTAL: 23:59:01.884	23:58:45.777	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:26.172	66.1%	97	23.143	269.814	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.001	0.0%	301	0.000	0.003	0.067
TOTAL: 00:00:39.601	00:00:26.173	66.1%	-	-	-	-

Control commands to INND:

Command	Number
flush	2
flushlogs	2
go	2
logmode	2
lowmark	1
mode	147
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	166

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	0	256264	186005	10507	59752	72%	24:06:25
2	news.sklaff.dev	4	10165	4985	5036	144	49%	24:22:44
3	usenet.blueworldhosting.com	1333	10694	4294	5057	1343	40%	23:51:05
4	localhost	25	0	0	0	0	0%	00:00:00
5	news.thebunker.nu	12	0	0	0	0	0%	00:00:00
TOTAL: 5		1374	277123	195284	20600	61239	70%	72:20:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	472.1 MB	0.0 KB	150.5 MB	622.5 MB	75%	2.6 KB
2	news.sklaff.dev	15.3 MB	15.2 KB	322.6 KB	15.6 MB	97%	3.1 KB
3	usenet.blueworldhosting.com	13.2 MB	3.7 KB	2.7 MB	16.0 MB	82%	2.9 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
5	news.thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		500.6 MB	18.9 KB	153.5 MB	654.1 MB	76%	2.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 28 03:00:02 - 03:59:59	5190	2.7%	1.44	13.2 MB	2.6%	3.76
Sep 28 04:00:00 - 04:59:59	8355	4.3%	2.32	26.5 MB	5.3%	7.55
Sep 28 05:00:00 - 05:59:59	8077	4.1%	2.24	18.7 MB	3.7%	5.33
Sep 28 06:00:00 - 06:59:59	7784	4.0%	2.16	18.2 MB	3.6%	5.19
Sep 28 07:00:00 - 07:59:59	7711	4.0%	2.14	17.7 MB	3.5%	5.04
Sep 28 08:00:00 - 08:59:59	7597	3.9%	2.11	21.2 MB	4.2%	6.04
Sep 28 09:00:00 - 09:59:59	10355	5.3%	2.88	25.2 MB	5.0%	7.18
Sep 28 10:00:00 - 10:59:59	9580	4.9%	2.66	21.5 MB	4.3%	6.10
Sep 28 11:00:00 - 11:59:59	9477	4.9%	2.63	34.3 MB	6.9%	9.75
Sep 28 12:00:00 - 12:59:59	8310	4.3%	2.31	22.1 MB	4.4%	6.28
Sep 28 13:00:00 - 13:59:59	8863	4.5%	2.46	21.1 MB	4.2%	5.99
Sep 28 14:00:00 - 14:59:59	8684	4.5%	2.41	20.2 MB	4.0%	5.75
Sep 28 15:00:00 - 15:59:59	8885	4.6%	2.47	22.8 MB	4.6%	6.48
Sep 28 16:00:00 - 16:59:59	6828	3.5%	1.90	16.1 MB	3.2%	4.59
Sep 28 17:00:00 - 17:59:59	7332	3.8%	2.04	18.5 MB	3.7%	5.25
Sep 28 18:00:00 - 18:59:59	6176	3.2%	1.72	15.2 MB	3.0%	4.32
Sep 28 19:00:00 - 19:59:59	6026	3.1%	1.67	14.2 MB	2.8%	4.05
Sep 28 20:00:00 - 20:59:59	5766	3.0%	1.60	14.2 MB	2.8%	4.04
Sep 28 21:00:00 - 21:59:59	5662	2.9%	1.57	33.2 MB	6.6%	9.44
Sep 28 22:00:00 - 22:59:59	8271	4.2%	2.30	25.4 MB	5.1%	7.21
Sep 28 23:00:00 - 23:59:59	10864	5.6%	3.02	21.7 MB	4.3%	6.18
Sep 29 00:00:00 - 00:59:59	10344	5.3%	2.87	21.7 MB	4.3%	6.16
Sep 29 01:00:00 - 01:59:59	9684	5.0%	2.69	19.3 MB	3.9%	5.48
Sep 29 02:00:00 - 02:59:59	9277	4.8%	2.58	17.9 MB	3.6%	5.11
Sep 29 03:00:00 - 03:00:02	5	0.0%	2.50	7.8 KB	0.0%	3.91
TOTAL: 24:00:00	195103	100.0%	2.26	500.2 MB	100.0%	5.93

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	chimia.se	59807	0	0	131	59676
2	usenet.blueworldhosting.com	1409	226	2	0	1181
3	news.sklaff.dev	170	0	7	0	163
TOTAL: 3		61386	226	9	131	61020

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.sport.motociclismo	9
pl.comp.pecet	9
de.comp.os.unix.apps.kde	7
fr.test	7
de.rec.tiere.katzen	6
free.willey	6
de.rec.heimwerken	5
free.it.scienza.fisica	5
de.soc.arbeitslos	4
de.soc.recht.strafrecht	4
fido7.starper.unlimited	4
fido7.su.kitchen	4
it.comp.retrocomputing	4
it.scienza.fisica	4
linux.debian.maint.kde	4
linux.debian.ports.hurd	4
nz.general	4
alt.usenet.legends.lester-mosley	3
de.rec.luftfahrt	3
fido7.f4441.robots	3
TOTAL: 102	226

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
comp.ai	128
news.newusers.questions	3
TOTAL: 2	131

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	33780
EMP (phn nph)	18791
EMP (phl)	4825
EMP (md5)	2637
HTML Multipart	203
Too many newsgroups	174
HTML post	78
MI5 Filter	72
Too many newsgroups (meow)	71
Too many newsgroups (low_xpost)	54
Scoring filter	53
EMP (fsl)	35
Subject Rejected	30
Excessive Supersedes (soda.berkeley.edu)	17
Poison newsgroup	14
U2 violation - invalid distribution	10
User-issued cancel	6
Binary: misplaced binary	4
HTML Image Tags	4
HTML file attachment	4
TOTAL: 26	60871

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	16
	TOTAL: 1	16
Including strange strings
	usenet.blueworldhosting.com	66
	chimia.se	56
	news.sklaff.dev	26
	TOTAL: 3	148
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 3		165

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaff.dev	190985	3419	3900	182796	14	1%	23:59:57
2	sklaffkom.se	198455	208	8265	186618	198	0%	23:59:57
3	usenet.blueworldhosting.com	12221	0	4558	7238	133	0%	23:59:56
4	thebunker.nu	0	0	0	0	195089	0%	23:59:57
TOTAL: 4		401661	3627	16723	376652	195434	0%	95:59:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaff.dev	23.1 MB	450.0 MB	473.1 MB	5.6 KB/s	2.6 KB	23:59:57
2	sklaffkom.se	238.7 KB	473.9 MB	474.1 MB	5.6 KB/s	2.6 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	41.3 MB	41.3 MB	0.5 KB/s	5.8 KB	23:59:56
TOTAL: 4		23.3 MB	965.2 MB	988.5 MB	2.9 KB/s	2.7 KB	95:59:47

NNRP readership statistics:

System		Conn	Elapsed
1	81-229-79-101-no600.tbcn.telia.com	12	00:00:05
2	162.142.125.209	3	00:00:03
3	147.185.133.163	2	00:00:05
4	224.211.203.35.bc.googleusercontent.com	2	00:00:00
5	azpdwsxvcqbu.stretchoid.com	2	00:00:10
6	147.185.132.133	1	00:00:00
7	147.185.133.58	1	00:00:05
8	198.235.24.220	1	00:00:03
9	198.235.24.96	1	00:00:01
10	207.90.244.14	1	00:00:03
TOTAL: 10		26	00:00:39

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.tbcn.telia.com	12	00:00:05
2	unresolved	10	00:00:22
3	*.211.203.35.bc.googleusercontent.com	2	00:00:00
4	*.stretchoid.com	2	00:00:10
TOTAL: 4		26	00:00:39

NNRP auth users [Top 20]:

User	Conn
peterlondon	12
TOTAL: 1	12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
azpdwsxvcqbu.stretchoid.com	0.013	0.010	0.000	00:00:10
81-229-79-101-no600.tbcn.telia.com	0.101	0.077	0.274	00:00:05
147.185.133.163	0.087	0.008	0.000	00:00:05
147.185.133.58	0.038	0.009	0.000	00:00:05
162.142.125.209	0.067	0.014	0.000	00:00:03
207.90.244.14	0.008	0.004	0.000	00:00:03
198.235.24.220	0.007	0.007	0.000	00:00:03
198.235.24.96	0.009	0.005	0.000	00:00:01
224.211.203.35.bc.googleusercontent.com	0.090	0.008	0.000	00:00:00
147.185.132.133	0.041	0.011	0.000	00:00:00
TOTAL: 10	0.461	0.153	0.274	00:00:39

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.132.133	4
147.185.133.163	4
147.185.133.58	4
224.211.203.35.bc.googleusercontent.com	4
162.142.125.209	1
azpdwsxvcqbu.stretchoid.com	1
TOTAL: 6	18

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.185.132.133	1	1
147.185.133.163	1	2
147.185.133.58	1	1
162.142.125.209	1	2
224.211.203.35.bc.googleusercontent.com	1	2
TOTAL: 5	5	10

Daily Usenet report

Sep 28 03:00:02 -- Sep 29 03:00:02