Daily Usenet report: Sep 17 03:00:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 17 03:02:10 news innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	211085	69.3%	26.3 MB	71.9%
innd	90992	29.9%	10.0 MB	27.4%
innfeed	2194	0.7%	253.7 KB	0.7%
nnrpd	149	0.0%	15.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	304421	100.0%	36.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	213239	48.9%
Do not exist	210954	48.4%
Positive hits	7343	1.7%
Cache misses	4285	1.0%
TOTAL: 4	435821	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.180	0.0%	208	0.000	0.865	31.000
article cleanup	00:00:01.006	0.0%	210912	0.000	0.005	0.183
article logging	00:00:03.593	0.0%	211009	0.000	0.017	1.000
article parse	00:00:01.971	0.0%	629343	0.000	0.003	0.500
article write	00:01:02.904	0.1%	132063	0.000	0.476	205.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.345	0.0%	661063	0.000	0.001	2.857
hisgrep/artcncl	00:00:00.062	0.0%	208	0.000	0.298	11.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:12:18.371	0.9%	435886	0.000	1.694	17.000
history sync	00:00:00.092	0.0%	571	0.000	0.161	24.000
history write	00:06:36.855	0.5%	210912	0.000	1.882	44.000
idle	23:33:33.621	98.2%	652477	67.684	129.987	60060.000
nntp read	00:00:05.080	0.0%	651384	0.000	0.008	1.185
overview write	00:00:27.089	0.0%	132063	0.000	0.205	162.000
perl filter	00:04:35.687	0.3%	210912	0.000	1.307	188.000
python filter	00:00:10.975	0.0%	418864	0.000	0.026	1.000
site send	00:00:01.242	0.0%	132063	0.000	0.009	0.500
TOTAL: 23:59:33.182	23:58:59.073	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.214	0.0%	132100	0.000	0.002	0.500
article prepare	00:00:00.063	0.0%	1042144	0.000	0.000	0.003
article read	00:00:25.133	0.0%	132098	0.000	0.190	2.483
backlog stats	00:00:00.222	0.0%	1022670	0.000	0.000	0.028
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.141	0.0%	641395	0.000	0.006	0.250
data write	00:00:06.897	0.0%	509642	0.000	0.014	0.333
idle	23:59:07.541	99.9%	1022670	29.529	84.433	30100.000
status file	00:00:00.160	0.0%	165	0.000	0.970	40.000
TOTAL: 23:59:53.257	23:59:44.371	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:09.353	60.7%	33	22.429	283.424	2922.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.001	0.0%	148	0.000	0.007	0.067
TOTAL: 00:00:15.396	00:00:09.354	60.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	localhost	32	208168	126080	4773	77315	60%	19:10:53
2	usenet.blueworldhosting.com	714	7664	4168	1962	1534	54%	23:45:09
3	news.sklaff.dev	2	6496	1835	4611	50	28%	21:21:09
4	news.thebunker.nu	5	0	0	0	0	0%	00:10:00
TOTAL: 4		753	222328	132083	11346	78899	59%	64:27:11

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	localhost	307.7 MB	178.0 MB	485.7 MB	63%	2.4 KB
2	usenet.blueworldhosting.com	14.7 MB	3.4 MB	18.1 MB	81%	3.2 KB
3	news.sklaff.dev	7.1 MB	88.2 KB	7.2 MB	98%	3.9 KB
4	news.thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		329.5 MB	181.5 MB	511.0 MB	64%	2.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 17 03:00:01 - 03:59:59	131	0.1%	0.04	1.2 MB	0.4%	0.34
Sep 17 04:00:00 - 04:59:59	86	0.1%	0.02	276.4 KB	0.1%	0.08
Sep 17 05:00:00 - 05:59:59	148	0.1%	0.04	640.1 KB	0.2%	0.18
Sep 17 06:00:00 - 06:59:59	99	0.1%	0.03	381.3 KB	0.1%	0.11
Sep 17 07:00:00 - 07:59:59	1628	1.2%	0.45	5.0 MB	1.5%	1.44
Sep 17 08:00:00 - 08:59:59	6904	5.2%	1.92	11.4 MB	3.4%	3.24
Sep 17 09:00:00 - 09:59:59	7419	5.6%	2.06	16.4 MB	5.0%	4.65
Sep 17 10:00:00 - 10:59:59	7148	5.4%	1.99	14.4 MB	4.4%	4.10
Sep 17 11:00:00 - 11:59:59	5795	4.4%	1.61	12.0 MB	3.6%	3.40
Sep 17 12:00:00 - 12:59:59	5245	4.0%	1.46	10.7 MB	3.2%	3.04
Sep 17 13:00:00 - 13:59:59	4753	3.6%	1.32	10.1 MB	3.1%	2.87
Sep 17 14:00:00 - 14:59:59	4421	3.3%	1.23	8.3 MB	2.5%	2.37
Sep 17 15:00:00 - 15:59:59	4304	3.3%	1.20	9.5 MB	2.9%	2.70
Sep 17 16:00:00 - 16:59:59	5440	4.1%	1.51	13.1 MB	4.0%	3.73
Sep 17 17:00:00 - 17:59:59	9125	6.9%	2.53	24.2 MB	7.3%	6.88
Sep 17 18:00:00 - 18:59:59	4635	3.5%	1.29	12.8 MB	3.9%	3.64
Sep 17 19:00:00 - 19:59:59	5683	4.3%	1.58	15.8 MB	4.8%	4.49
Sep 17 20:00:00 - 20:59:59	8902	6.7%	2.47	21.6 MB	6.6%	6.16
Sep 17 21:00:00 - 21:59:59	8198	6.2%	2.28	19.8 MB	6.0%	5.63
Sep 17 22:00:00 - 22:59:59	8310	6.3%	2.31	29.1 MB	8.8%	8.28
Sep 17 23:00:00 - 23:59:59	8549	6.5%	2.37	20.0 MB	6.1%	5.68
Sep 18 00:00:00 - 00:59:59	8065	6.1%	2.24	19.3 MB	5.9%	5.50
Sep 18 01:00:00 - 01:59:59	9247	7.0%	2.57	34.1 MB	10.3%	9.69
Sep 18 02:00:00 - 02:59:59	7870	6.0%	2.19	19.7 MB	6.0%	5.61
Sep 18 03:00:00 - 03:00:02	6	0.0%	3.00	17.4 KB	0.0%	8.70
TOTAL: 24:00:01	132111	100.0%	1.53	329.8 MB	100.0%	3.91

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	localhost	77305	0	87	77218
2	usenet.blueworldhosting.com	1581	343	0	1238
3	news.sklaff.dev	88	0	0	88
TOTAL: 3		78974	343	87	78544

Unwanted newsgroups [Top 20]:

Newsgroup	Count
test.group	23
fido7.obec.f3boh	13
fr.comp.os.mac-os.x	13
de.rec.heimwerken	12
fr.rec.bricolage	12
linux.gentoo.user	12
de.etc.finanz.banken+broker	9
de.admin.infos	8
de.rec.garten	8
perl.modules	8
de.comp.sys.mac.misc	7
de.rec.tv.misc	6
fr.misc.finance	6
linux.debian.ports.hurd	6
uk.comp.os.linux	6
fr.comp.os.linux.configuration	5
it.comp.giochi.action	5
muc.lists.freebsd.multimedia	5
de.rec.film.misc	4
de.rec.musik.hifi	4
TOTAL: 120	343

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

Groups	Count
comp.ai	87
TOTAL: 1	87

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	28303
EMP (phn path)	26183
EMP (phl)	16329
EMP (md5)	3851
HTML Multipart	3212
EMP (fsl)	119
HTML post	111
Too many newsgroups (low_xpost)	108
Too many newsgroups	105
MI5 Filter	48
Subject Rejected	22
Scoring filter	14
Too many newsgroups (meow)	13
User-issued cancel	9
Angle-bracket bot	6
Bot - Newsgroup autoposter	5
HTML Image Tags	5
PFM bot misc	2
Binary Image: misplaced gif	1
HTML file attachment	1
TOTAL: 20	78447

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	localhost	17
	usenet.blueworldhosting.com	1
	TOTAL: 2	18
Including strange strings
	usenet.blueworldhosting.com	47
	news.sklaff.dev	36
	localhost	14
	TOTAL: 3	97
TOTAL: 2		115

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaff.dev	131000	15708	3343	111083	1	11%	23:59:58
2	thebunker.nu	132110	5315	0	126794	0	4%	23:59:58
3	usenet.blueworldhosting.com	3546	0	1838	1660	0	0%	23:59:39
TOTAL: 3		266656	21023	5181	239537	1	7%	71:59:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaff.dev	47.2 MB	263.6 MB	310.7 MB	3.7 KB/s	2.5 KB	23:59:58
2	thebunker.nu	18.6 MB	311.6 MB	330.2 MB	3.9 KB/s	2.6 KB	23:59:58
3	usenet.blueworldhosting.com	0.0 KB	20.3 MB	20.3 MB	0.2 KB/s	12.5 KB	23:59:39
TOTAL: 3		65.7 MB	595.4 MB	661.2 MB	2.6 KB/s	2.6 KB	71:59:35

NNRP readership statistics:

System		Conn	Elapsed
1	81-229-79-101-no600.tbcn.telia.com	4	00:00:01
2	147.185.132.3	2	00:00:00
3	147.185.132.6	2	00:00:01
4	37.210.203.35.bc.googleusercontent.com	2	00:00:00
5	54.150.216.162.bc.googleusercontent.com	2	00:00:01
6	167.94.138.166	1	00:00:01
7	190.210.203.35.bc.googleusercontent.com	1	00:00:00
8	198.235.24.125	1	00:00:02
9	198.235.24.67	1	00:00:02
10	72.210.203.35.bc.googleusercontent.com	1	00:00:00
11	affectionate.monitoring.internet-measurement.com	1	00:00:01
12	ec2-54-219-0-237.us-west-1.compute.amazonaws.com	1	00:00:00
TOTAL: 12		19	00:00:15

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	7	00:00:09
2	*.210.203.35.bc.googleusercontent.com	4	00:00:01
3	*.tbcn.telia.com	4	00:00:01
4	*.150.216.162.bc.googleusercontent.com	2	00:00:01
5	*.monitoring.internet-measurement.com	1	00:00:01
6	*.us-west-1.compute.amazonaws.com	1	00:00:00
TOTAL: 6		19	00:00:15

NNRP auth users [Top 20]:

User	Conn
peterlondon	4
TOTAL: 1	4

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
198.235.24.67	0.008	0.005	0.000	00:00:02
198.235.24.125	0.007	0.007	0.000	00:00:02
affectionate.monitoring.internet-measurement.com	0.008	0.003	0.000	00:00:01
167.94.138.166	0.040	0.005	0.000	00:00:01
147.185.132.6	0.082	0.010	0.000	00:00:01
81-229-79-101-no600.tbcn.telia.com	0.024	0.024	0.079	00:00:01
54.150.216.162.bc.googleusercontent.com	0.079	0.011	0.000	00:00:01
147.185.132.3	0.082	0.010	0.000	00:00:00
37.210.203.35.bc.googleusercontent.com	0.084	0.008	0.000	00:00:00
ec2-54-219-0-237.us-west-1.compute.amazonaws.com	0.039	0.008	0.000	00:00:00
190.210.203.35.bc.googleusercontent.com	0.074	0.006	0.000	00:00:00
72.210.203.35.bc.googleusercontent.com	0.041	0.004	0.000	00:00:00
TOTAL: 12	0.568	0.101	0.079	00:00:15

NNRP gethostbyaddr failures [Top 20]:

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

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

System	Conn
147.185.132.3	4
147.185.132.6	4
190.210.203.35.bc.googleusercontent.com	4
37.210.203.35.bc.googleusercontent.com	4
54.150.216.162.bc.googleusercontent.com	4
72.210.203.35.bc.googleusercontent.com	4
167.94.138.166	1
TOTAL: 7	25

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.185.132.3	1	2
147.185.132.6	1	2
190.210.203.35.bc.googleusercontent.com	1	1
37.210.203.35.bc.googleusercontent.com	1	2
54.150.216.162.bc.googleusercontent.com	1	2
72.210.203.35.bc.googleusercontent.com	1	1
TOTAL: 6	6	12

Daily Usenet report

Sep 17 03:00:01 -- Sep 18 03:00:02