Daily Usenet report: Nov 9 03:00:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Nov 9 03:39:29 news innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	90724	52.2%	9.9 MB	52.1%
innd	80431	46.3%	8.8 MB	46.5%
innfeed	2347	1.4%	245.2 KB	1.3%
nnrpd	139	0.1%	14.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	173642	100.0%	19.0 MB	100.0%

History cache:

Reason	Count	%Count
Cache misses	203537	52.2%
Negative hits	91192	23.4%
Do not exist	90577	23.2%
Positive hits	4687	1.2%
TOTAL: 4	389993	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.301	0.0%	60	0.000	5.017	73.000
article cleanup	00:00:00.380	0.0%	90631	0.000	0.004	0.250
article logging	00:00:01.185	0.0%	90722	0.000	0.013	1.000
article parse	00:00:00.835	0.0%	255514	0.000	0.003	0.500
article write	00:08:21.320	0.6%	24292	0.000	20.637	300.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:00.313	0.0%	461780	0.000	0.001	0.117
hisgrep/artcncl	00:00:00.004	0.0%	60	0.000	0.067	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:22:38.545	1.6%	390198	0.000	3.482	34.767
history sync	00:00:00.042	0.0%	311	0.000	0.135	22.000
history write	00:01:43.679	0.1%	90590	0.000	1.144	20.000
idle	23:23:10.048	97.5%	473368	38.605	177.853	60017.000
nntp read	00:00:03.147	0.0%	472248	0.000	0.007	0.091
overview write	00:00:14.635	0.0%	24292	0.000	0.602	54.000
perl filter	00:02:18.263	0.2%	90588	0.308	1.526	84.000
python filter	00:00:07.345	0.0%	376354	0.000	0.020	0.455
site send	00:00:00.225	0.0%	24290	0.000	0.009	0.500
TOTAL: 23:59:24.721	23:58:40.267	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.027	0.0%	24292	0.000	0.001	0.143
article prepare	00:00:00.009	0.0%	73068	0.000	0.000	0.014
article read	00:00:08.411	0.0%	18259	0.000	0.461	40.000
backlog stats	00:00:00.044	0.0%	115350	0.000	0.000	0.333
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.440	0.0%	66814	0.000	0.007	0.500
data write	00:00:00.660	0.0%	42991	0.000	0.015	0.667
idle	23:59:46.335	100.0%	115350	88.770	748.906	23709.000
status file	00:00:00.117	0.0%	193	0.000	0.606	6.800
TOTAL: 23:59:57.588	23:59:56.043	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:21.308	35.2%	13	64.500	1639.077	10041.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.000	0.0%	136	0.000	0.000	0.000
TOTAL: 00:01:00.508	00:00:21.308	35.2%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	2	276993	16028	196642	64323	5%	23:12:01
2	usenet.blueworldhosting.com	1382	10076	6651	1504	1921	66%	23:48:43
3	news.sklaffkom.se	1	3255	1657	1598	0	50%	24:53:45
4	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 4		1410	290324	24336	199744	66244	8%	71:54:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	40.1 MB	297.1 KB	141.1 MB	181.6 MB	22%	2.3 KB
2	usenet.blueworldhosting.com	16.1 MB	0.0 KB	3.4 MB	19.5 MB	82%	2.3 KB
3	news.sklaffkom.se	2.4 MB	0.0 KB	0.0 KB	2.4 MB	100%	1.5 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		58.7 MB	297.1 KB	144.5 MB	203.5 MB	28%	2.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 9 03:00:02 - 03:59:59	790	3.3%	0.22	1.6 MB	2.7%	0.45
Nov 9 04:00:00 - 04:59:59	893	3.7%	0.25	2.0 MB	3.4%	0.56
Nov 9 05:00:00 - 05:59:59	1042	4.3%	0.29	2.2 MB	3.8%	0.64
Nov 9 06:00:00 - 06:59:59	1290	5.3%	0.36	2.7 MB	4.5%	0.75
Nov 9 07:00:00 - 07:59:59	1369	5.6%	0.38	2.9 MB	4.9%	0.82
Nov 9 08:00:00 - 08:59:59	1373	5.7%	0.38	3.3 MB	5.7%	0.95
Nov 9 09:00:00 - 09:59:59	1223	5.0%	0.34	2.6 MB	4.5%	0.75
Nov 9 10:00:00 - 10:59:59	1287	5.3%	0.36	2.7 MB	4.6%	0.76
Nov 9 11:00:00 - 11:59:59	1559	6.4%	0.43	3.7 MB	6.3%	1.06
Nov 9 12:00:00 - 12:59:59	1114	4.6%	0.31	2.5 MB	4.2%	0.70
Nov 9 13:00:00 - 13:59:59	1311	5.4%	0.36	3.0 MB	5.1%	0.85
Nov 9 14:00:00 - 14:59:59	1200	4.9%	0.33	3.1 MB	5.3%	0.88
Nov 9 15:00:00 - 15:59:59	643	2.6%	0.18	1.3 MB	2.2%	0.37
Nov 9 16:00:00 - 16:59:59	928	3.8%	0.26	2.2 MB	3.7%	0.61
Nov 9 17:00:00 - 17:59:59	778	3.2%	0.22	2.2 MB	3.7%	0.61
Nov 9 18:00:00 - 18:59:59	740	3.0%	0.21	1.7 MB	2.8%	0.47
Nov 9 19:00:00 - 19:59:59	921	3.8%	0.26	2.1 MB	3.5%	0.59
Nov 9 20:00:00 - 20:59:59	639	2.6%	0.18	1.6 MB	2.7%	0.45
Nov 9 21:00:00 - 21:59:59	428	1.8%	0.12	940.4 KB	1.6%	0.26
Nov 9 22:00:00 - 22:59:59	885	3.6%	0.25	2.7 MB	4.6%	0.76
Nov 9 23:00:00 - 23:59:59	1816	7.5%	0.50	6.8 MB	11.5%	1.92
Nov 10 00:00:00 - 00:59:59	1383	5.7%	0.38	3.5 MB	6.0%	1.00
Nov 10 01:00:00 - 01:59:59	371	1.5%	0.10	902.1 KB	1.5%	0.25
Nov 10 02:00:00 - 02:59:59	305	1.3%	0.08	693.5 KB	1.2%	0.19
Nov 10 03:00:00 - 03:00:01	1	0.0%	1.00	0.8 KB	0.0%	0.79
TOTAL: 23:59:59	24289	100.0%	0.28	58.6 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	chimia.se	64432	0	43	350	64039
2	usenet.blueworldhosting.com	2003	348	0	1	1654
TOTAL: 2		66435	348	43	351	65693

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.comm.internet.misc	23
free.it.scienza.fisica	22
de.rec.tv.technik	16
de.comm.infosystems.www.authoring.misc	15
uk.tech.broadcast	15
hispagatos.hacking.ransomware	14
linux.debian.maint.kde	12
de.rec.motorrad	11
hispagatos.mastodon.tag.hacking	11
aus.computers	9
fr.comp.os.linux.configuration	9
it.sport.motociclismo	9
de.alt.comm.android	8
linux.gentoo.user	7
relcom.wheels	7
fido7.altyn.local	6
hamster.de.misc	6
de.comp.office-pakete.ms-office.excel	5
free.willey	5
linux.debian.maint.python	5
TOTAL: 106	348

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

Groups	Count
comp.ai	348
news.newusers.questions	2
misc.taxes.moderated	1
TOTAL: 3	351

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	24849
EMP (phn path)	23471
EMP (phl)	15183
EMP (md5)	1559
HTML Multipart	174
Too many newsgroups	94
Subject Rejected	93
MI5 Filter	43
HTML post	42
User-issued cancel	31
Scoring filter	23
Too many newsgroups (low_xpost)	11
Binary: misplaced binary	8
HTML Image Tags	8
Too many newsgroups (meow)	8
PFM bot misc	2
MID-Bot	1
TOTAL: 17	65600

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	30
	TOTAL: 1	30
Including strange strings
	usenet.blueworldhosting.com	82
	chimia.se	9
	TOTAL: 2	91
No colon-space in header field
	usenet.blueworldhosting.com	2
	TOTAL: 1	2
TOTAL: 3		123

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaffkom.se	23623	1796	4461	16463	7	7%	23:59:57
2	usenet.blueworldhosting.com	1485	0	1432	8	0	0%	23:48:15
3	sklaff.dev	0	0	0	0	24285	0%	23:57:43
4	thebunker.nu	0	0	0	0	24285	0%	23:59:57
TOTAL: 4		25108	1796	5893	16471	48577	7%	95:45:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaffkom.se	3.1 MB	41.5 MB	44.6 MB	0.5 KB/s	2.5 KB	23:59:57
2	sklaff.dev	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:43
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	44.8 KB	44.8 KB	0.0 KB/s	5.6 KB	23:48:15
TOTAL: 4		3.1 MB	41.6 MB	44.7 MB	0.1 KB/s	2.5 KB	95:45:52

NNRP readership statistics:

System		Conn	Elapsed
1	209.149.216.162.bc.googleusercontent.com	4	00:00:23
2	scanner-06.ch1.censys-scanner.com	4	00:00:09
3	147.185.133.5	2	00:00:06
4	azpdcs71oypf.stretchoid.com	2	00:00:10
5	129.149.216.162.bc.googleusercontent.com	1	00:00:00
6	180.149.216.162.bc.googleusercontent.com	1	00:00:00
7	198.235.24.200	1	00:00:02
8	205.210.31.107	1	00:00:00
9	207.90.244.14	1	00:00:00
10	auspicious.monitoring.internet-measurement.com	1	00:00:03
11	celebrated.monitoring.internet-measurement.com	1	00:00:00
12	fascinating.monitoring.internet-measurement.com	1	00:00:00
13	renowned.monitoring.internet-measurement.com	1	00:00:00
14	soothing.monitoring.internet-measurement.com	1	00:00:03
15	unwavering.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 15		23	00:01:00

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.149.216.162.bc.googleusercontent.com	6	00:00:24
2	*.monitoring.internet-measurement.com	6	00:00:06
3	unresolved	5	00:00:10
4	*.ch1.censys-scanner.com	4	00:00:09
5	*.stretchoid.com	2	00:00:10
TOTAL: 5		23	00:01:00

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
209.149.216.162.bc.googleusercontent.com	0.164	0.020	00:00:23
azpdcs71oypf.stretchoid.com	0.014	0.010	00:00:10
scanner-06.ch1.censys-scanner.com	0.097	0.020	00:00:09
147.185.133.5	0.083	0.008	00:00:06
soothing.monitoring.internet-measurement.com	0.038	0.005	00:00:03
auspicious.monitoring.internet-measurement.com	0.043	0.004	00:00:03
198.235.24.200	0.008	0.006	00:00:02
207.90.244.14	0.009	0.003	00:00:00
205.210.31.107	0.008	0.005	00:00:00
129.149.216.162.bc.googleusercontent.com	0.043	0.007	00:00:00
180.149.216.162.bc.googleusercontent.com	0.042	0.004	00:00:00
TOTAL: 11	0.549	0.092	00:01:00

NNRP gethostbyaddr failures [Top 20]:

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

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

System	Conn
209.149.216.162.bc.googleusercontent.com	8
129.149.216.162.bc.googleusercontent.com	4
147.185.133.5	4
180.149.216.162.bc.googleusercontent.com	4
scanner-06.ch1.censys-scanner.com	2
azpdcs71oypf.stretchoid.com	1
TOTAL: 6	23

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
209.149.216.162.bc.googleusercontent.com	2	4
129.149.216.162.bc.googleusercontent.com	1	1
147.185.133.5	1	2
180.149.216.162.bc.googleusercontent.com	1	1
scanner-06.ch1.censys-scanner.com	1	2
TOTAL: 5	6	13

Daily Usenet report

Nov 9 03:00:02 -- Nov 10 03:00:01