Daily Usenet report: Nov 24 03:00:01

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Nov 24 03:52:03 news innd: message repeated 2 times: [ ctlinnd command s]
Nov 24 03:53:01 news innd: tradspool: could not open /usr/local/news/spool/articles/uk/current-events/terrorism/1664: No space left on device
Nov 24 05:56:39 news nnrpd[1208610]: o45.scanner.modat.io rejected No space left on device writing SMstore file -- throttling
Nov 24 11:23:40 news nnrpd[1260234]: 199.45.155.97 rejected No space left on device writing SMstore file -- throttling
Nov 24 11:23:45 news nnrpd[1260236]: 199.45.155.97 rejected No space left on device writing SMstore file -- throttling
Nov 24 11:23:49 news nnrpd[1260237]: 199.45.155.97 rejected No space left on device writing SMstore file -- throttling
Nov 24 11:23:53 news nnrpd[1260243]: 199.45.155.97 rejected No space left on device writing SMstore file -- throttling
Nov 24 15:11:55 news nnrpd[1296125]: 178.22.24.39 rejected No space left on device writing SMstore file -- throttling
Nov 24 15:11:55 news nnrpd[1296126]: 178.22.24.39 rejected No space left on device writing SMstore file -- throttling
Nov 24 18:50:58 news nnrpd[1331247]: azpdssao9sjd.stretchoid.com rejected No space left on device writing SMstore file -- throttling
Nov 24 18:50:59 news nnrpd[1331248]: azpdssao9sjd.stretchoid.com rejected No space left on device writing SMstore file -- throttling
Nov 24 19:17:33 news pgpverify[1335382]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	100958	60.4%	13.2 MB	62.8%
innd	63757	38.1%	7.6 MB	36.0%
innfeed	2229	1.3%	235.8 KB	1.1%
nnrpd	165	0.1%	18.2 KB	0.1%
rnews	16	0.0%	2.3 KB	0.0%
controlchan	13	0.0%	2.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	167139	100.0%	21.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	102895	51.5%
Negative hits	93961	47.0%
Positive hits	2226	1.1%
Cache misses	867	0.4%
TOTAL: 4	199949	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.339	0.0%	17	1.000	19.941	102.000
article cleanup	00:00:00.514	0.0%	100780	0.000	0.005	0.131
article logging	00:00:01.386	0.0%	100793	0.000	0.014	0.063
article parse	00:00:00.846	0.0%	276034	0.000	0.003	0.013
article write	00:07:20.498	0.5%	60316	0.019	7.303	90.807
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
artlog/artwrite	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.145	0.0%	272735	0.000	0.001	0.053
hisgrep/artcncl	00:00:00.007	0.0%	17	0.000	0.412	1.000
hissync/artwrite	00:00:00.000	0.0%	1	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:07:29.454	0.5%	199999	0.000	2.247	9.722
history sync	00:00:00.002	0.0%	106	0.000	0.019	1.000
history write	00:01:23.944	0.1%	100327	0.000	0.837	5.725
idle	23:40:27.467	98.7%	282012	0.341	302.212	6096.300
nntp read	00:00:02.444	0.0%	269797	0.000	0.009	0.111
overview write	00:00:09.875	0.0%	60315	0.000	0.164	2.553
perl filter	00:01:46.512	0.1%	100327	0.311	1.062	22.154
python filter	00:00:04.518	0.0%	186247	0.000	0.024	0.385
site send	00:00:00.416	0.0%	60316	0.000	0.007	0.060
TOTAL: 23:59:15.051	23:58:48.367	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.074	0.0%	66817	0.000	0.001	0.023
article prepare	00:00:00.016	0.0%	175732	0.000	0.000	0.003
article read	00:00:19.751	0.0%	43933	0.000	0.450	2.904
backlog stats	00:00:00.085	0.0%	257714	0.000	0.000	0.333
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.082	0.0%	157468	0.000	0.007	0.250
data write	00:00:01.575	0.0%	98859	0.000	0.016	0.055
idle	23:57:34.669	100.0%	257714	5.813	334.691	30100.500
status file	00:00:00.352	0.0%	200	0.000	1.760	44.000
TOTAL: 23:58:00.531	23:57:57.604	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:03.394	5.3%	6	41.333	565.667	3146.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.000	0.0%	100	0.000	0.000	0.000
TOTAL: 00:01:03.905	00:00:03.394	5.3%	-	-	-	-

Control commands to INND:

Command	Number
flush	2
flushlogs	2
go	2
logmode	2
lowmark	1
mode	146
name	2
pause	1
paused	1
reload	2
throttle	2
xexec	1
TOTAL: 12	164

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
nl-admin@nic.surfnet.nl	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	1	86077	45708	819	39550	53%	08:06:00
2	news.sklaffkom.se	2	13399	11878	1065	456	88%	04:43:49
3	usenet.blueworldhosting.com	516	3737	2631	571	535	70%	08:02:07
4	localhost	9	0	0	0	0	0%	00:00:00
TOTAL: 4		528	103213	60217	2455	40541	58%	20:51:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	98.0 MB	0.0 KB	85.2 MB	183.2 MB	53%	2.2 KB
2	news.sklaffkom.se	15.0 MB	1.3 MB	3.4 KB	16.4 MB	91%	1.4 KB
3	usenet.blueworldhosting.com	7.8 MB	1.7 KB	1.2 MB	9.0 MB	86%	2.9 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		120.8 MB	1.3 MB	86.4 MB	208.6 MB	57%	2.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 24 03:00:01 - 03:59:59	11	0.0%	0.00	9.6 KB	0.0%	0.00
Nov 24 04:00:00 - 04:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 05:00:00 - 05:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 06:00:00 - 06:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 07:00:00 - 07:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 08:00:00 - 08:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 09:00:00 - 09:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 10:00:00 - 10:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 11:00:00 - 11:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 12:00:00 - 12:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 13:00:00 - 13:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 14:00:00 - 14:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 16:00:00 - 16:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 17:00:00 - 17:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Nov 24 18:00:00 - 18:59:59	3031	5.0%	0.84	9.7 MB	27.0%	2.77
Nov 24 19:00:00 - 19:59:59	17417	28.8%	4.84	26.8 MB	74.4%	7.63
Nov 24 20:00:00 - 20:59:59	5663	9.4%	1.57	12.0 MB	33.2%	3.41
Nov 24 21:00:00 - 21:59:59	6166	10.2%	1.71	12.8 MB	35.4%	3.63
Nov 24 22:00:00 - 22:59:59	5775	9.6%	1.60	12.2 MB	33.8%	3.47
Nov 24 23:00:00 - 23:59:59	6329	10.5%	1.76	12.8 MB	35.5%	3.64
Nov 25 00:00:00 - 00:59:59	5213	8.6%	1.45	11.3 MB	31.4%	3.22
Nov 25 01:00:00 - 01:59:59	5191	8.6%	1.44	11.2 MB	31.0%	3.18
Nov 25 02:00:00 - 02:59:59	5607	9.3%	1.56	12.3 MB	34.0%	3.49
Nov 25 03:00:00 - 03:00:02	7	0.0%	3.50	12.3 KB	0.0%	6.15
TOTAL: 24:00:01	60410	100.0%	0.70	36.1 MB	100.0%	0.43

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	chimia.se	39550	0	0	39550
2	usenet.blueworldhosting.com	541	126	1	414
3	news.sklaffkom.se	457	0	452	5
TOTAL: 3		40548	126	453	39969

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.comm.provider.mail	12
aus.computers	10
hispagatos.hacking.ransomware	8
hispagatos.mastodon.tag.hacking	8
linux.gentoo.announce	5
pl.comp.os.ms-windows.winnt	5
uk.telecom.mobile	5
de.comm.software.newsreader	3
de.rec.musik.hifi	3
fido7.nodex.tech	3
fido7.ru.aviation	3
fr.rec.moto	3
linux.debian.ports.hurd	3
de.etc.notfallrettung	2
de.rec.tv.serien	2
es.charla.misc	2
fido7.su.pilot	2
fido7.xsu.cellular	2
free.willey	2
it.arti.cinema	2
TOTAL: 57	126

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	18450
EMP (phn path)	13453
EMP (phl)	7421
EMP (md5)	505
Too many newsgroups (meow)	51
Too many newsgroups	33
HTML Multipart	29
HTML post	4
Scoring filter	3
MID-Bot	2
Too many newsgroups (low_xpost)	1
User-issued cancel	1
TOTAL: 12	39953

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	6
	news.sklaffkom.se	1
	TOTAL: 2	7
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		8

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaffkom.se	48600	635	4312	43390	0	1%	08:26:10
2	usenet.blueworldhosting.com	12687	3	12675	0	6508	0%	08:05:38
3	sklaff.dev	0	0	0	0	60406	0%	23:55:26
4	thebunker.nu	0	0	0	0	60406	0%	23:59:53
TOTAL: 4		61287	638	16987	43390	127320	1%	64:27:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaffkom.se	1.6 MB	91.2 MB	92.8 MB	3.1 KB/s	2.2 KB	08:26:10
2	usenet.blueworldhosting.com	3.2 KB	0.0 KB	3.2 KB	0.0 KB/s	1.1 KB	08:05:38
3	sklaff.dev	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:55:26
4	thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 4		1.6 MB	91.2 MB	92.8 MB	0.4 KB/s	2.2 KB	64:27:07

Rnews connections rejected:

Reason	Conn
400 No space left on device writing SMstore file -- throttling	16
TOTAL: 1	16

NNRP readership statistics:

System		Conn	Elapsed
1	199.45.155.97	4	00:00:00
2	92.211.203.35.bc.googleusercontent.com	4	00:00:30
3	178.22.24.39	2	00:00:00
4	184.211.203.35.bc.googleusercontent.com	2	00:00:23
5	66-228-53-204.ip.linodeusercontent.com	2	00:00:00
6	azpdssao9sjd.stretchoid.com	2	00:00:00
7	108.210.203.35.bc.googleusercontent.com	1	00:00:00
8	205.210.31.97	1	00:00:03
9	53.211.203.35.bc.googleusercontent.com	1	00:00:00
10	appealing.monitoring.internet-measurement.com	1	00:00:00
11	healthy.monitoring.internet-measurement.com	1	00:00:02
12	invigorating.monitoring.internet-measurement.com	1	00:00:00
13	o45.scanner.modat.io	1	00:00:00
14	tenacious.monitoring.internet-measurement.com	1	00:00:00
15	thoughtful.monitoring.internet-measurement.com	1	00:00:00
16	valiant.monitoring.internet-measurement.com	1	00:00:03
TOTAL: 16		26	00:01:03

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.211.203.35.bc.googleusercontent.com	7	00:00:53
2	unresolved	7	00:00:03
3	*.monitoring.internet-measurement.com	6	00:00:06
4	*.ip.linodeusercontent.com	2	00:00:00
5	*.stretchoid.com	2	00:00:00
6	*.210.203.35.bc.googleusercontent.com	1	00:00:00
7	*.scanner.modat.io	1	00:00:00
TOTAL: 7		26	00:01:03

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
92.211.203.35.bc.googleusercontent.com	0.188	0.027	00:00:30
184.211.203.35.bc.googleusercontent.com	0.087	0.005	00:00:23
205.210.31.97	0.008	0.005	00:00:03
valiant.monitoring.internet-measurement.com	0.037	0.007	00:00:03
healthy.monitoring.internet-measurement.com	0.039	0.009	00:00:02
66-228-53-204.ip.linodeusercontent.com	0.015	0.007	00:00:00
108.210.203.35.bc.googleusercontent.com	0.037	0.010	00:00:00
53.211.203.35.bc.googleusercontent.com	0.039	0.007	00:00:00
invigorating.monitoring.internet-measurement.com	0.038	0.005	00:00:00
thoughtful.monitoring.internet-measurement.com	0.038	0.005	00:00:00
azpdssao9sjd.stretchoid.com	0.011	0.011	00:00:00
tenacious.monitoring.internet-measurement.com	0.040	0.003	00:00:00
appealing.monitoring.internet-measurement.com	0.039	0.005	00:00:00
199.45.155.97	0.024	0.021	00:00:00
o45.scanner.modat.io	0.007	0.006	00:00:00
178.22.24.39	0.016	0.010	00:00:00
TOTAL: 16	0.663	0.143	00:01:03

NNRP gethostbyaddr failures [Top 20]:

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

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

System	Conn
92.211.203.35.bc.googleusercontent.com	8
66-228-53-204.ip.linodeusercontent.com	7
108.210.203.35.bc.googleusercontent.com	4
184.211.203.35.bc.googleusercontent.com	4
53.211.203.35.bc.googleusercontent.com	4
TOTAL: 5	27

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

Command	Count
GET / HTTP/1.1	6
Host: 109.238.130.155:563	5
Accept: /	4
Accept-Encoding: gzip	3
User-Agent: curl/7.68.0	3
User-Agent: Hello from Palo Alto Network...	2
#001	1
#026#003#001	1
Host: 109.238.130.155:119	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 10	27

NNRP client timeouts [Top 20]:

System	Conn	Peer
92.211.203.35.bc.googleusercontent.com	2	4
108.210.203.35.bc.googleusercontent.com	1	1
184.211.203.35.bc.googleusercontent.com	1	2
53.211.203.35.bc.googleusercontent.com	1	1
TOTAL: 4	5	10

Daily Usenet report

Nov 24 03:00:01 -- Nov 25 03:00:02