Daily Usenet report: Nov 15 03:00:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Nov 15 05:08:38 news innd: message repeated 2 times: [ ctlinnd command s]
Nov 15 17:00:07 news pgpverify[3466503]: pgpverify: verification failed
Nov 15 17:00:13 news pgpverify[3466513]: pgpverify: verification failed
Nov 15 17:16:52 news nnrpd[3468725]: filter: Syntax check: postfilter.conf
Nov 15 17:16:52 news nnrpd[3468725]: filter: Analyzing message <10fa91k$39rdk$1@news.chimia.se>, MD5 B3tFuitQVdnMG2z/PW280w
Nov 15 17:16:52 news nnrpd[3468725]: filter: Message comes from localhost, DNSBL check is not needed
Nov 15 17:16:52 news nnrpd[3468725]: filter: No URI in the body, skipping URIBL checks
Nov 15 17:16:52 news nnrpd[3468725]: filter: Message comes from 127.0.0.1 that can't be a TOR router or an onion domain, TOR check is not needed
Nov 15 17:16:52 news nnrpd[3468725]: filter: Checking user's rights: IP 127.0.0.1
Nov 15 17:16:52 news nnrpd[3468725]: filter: Multipost: 1
Nov 15 17:16:52 news nnrpd[3468725]: filter: Checking user's rights: Domain localhost
Nov 15 17:16:52 news nnrpd[3468725]: filter: Multipost: 1
Nov 15 17:16:52 news nnrpd[3468725]: filter: Message <10fa91k$39rdk$1@news.chimia.se> accepted

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	250517	71.5%	34.3 MB	75.4%
innd	97042	27.7%	10.9 MB	24.0%
innfeed	2406	0.7%	264.0 KB	0.6%
nnrpd	299	0.1%	32.1 KB	0.1%
controlchan	23	0.0%	4.8 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
TOTAL: 6	350289	100.0%	45.5 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	251073	49.4%
Do not exist	250369	49.2%
Cache misses	3828	0.8%
Positive hits	3467	0.7%
TOTAL: 4	508737	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.025	0.0%	98	0.000	41.071	901.000
article cleanup	00:00:01.297	0.0%	250513	0.000	0.005	0.111
article logging	00:00:03.655	0.0%	250589	0.000	0.015	0.066
article parse	00:00:02.176	0.0%	742882	0.000	0.003	0.016
article write	01:16:29.699	5.3%	169143	2.710	27.135	1061.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.607	0.0%	769756	0.000	0.001	9.800
hisgrep/artcncl	00:00:00.036	0.0%	98	0.000	0.367	1.182
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:17:45.756	1.2%	509155	0.000	2.093	8.970
history sync	00:00:00.197	0.0%	303	0.000	0.650	75.000
history write	00:04:32.303	0.3%	250511	0.000	1.087	6.503
idle	22:00:08.900	91.6%	758845	54.470	104.381	19946.333
nntp read	00:00:06.838	0.0%	758308	0.000	0.009	0.200
overview write	00:00:36.476	0.0%	169143	0.000	0.216	71.667
perl filter	00:16:16.687	1.1%	250511	0.503	3.899	80.048
python filter	00:00:14.315	0.0%	495240	0.000	0.029	0.321
site send	00:00:01.399	0.0%	169130	0.000	0.008	0.058
TOTAL: 24:00:27.149	23:56:24.366	99.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.259	0.0%	169719	0.000	0.002	0.027
article prepare	00:00:00.051	0.0%	665068	0.000	0.000	0.003
article read	00:10:33.219	0.7%	162632	0.000	3.894	38.195
backlog stats	00:00:00.272	0.0%	845665	0.000	0.000	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.524	0.0%	506288	0.000	0.007	0.029
data write	00:00:05.481	0.0%	338008	0.000	0.016	0.059
idle	23:48:31.532	99.2%	845665	41.198	101.354	7584.625
status file	00:00:00.240	0.0%	174	0.000	1.379	86.500
TOTAL: 23:59:23.903	23:59:14.578	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.026	0.0%	4	0.000	6.500	22.000
idle	00:00:32.288	0.5%	93	0.000	347.183	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	350	0.000	0.011	0.077
readart	00:00:05.117	0.1%	244	3.000	20.971	79.000
TOTAL: 01:48:05.606	00:00:37.435	0.6%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	2	2	0	2
TOTAL	0	0	2	2	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	3	244655	160928	4091	79636	65%	23:53:34
2	usenet.blueworldhosting.com	1394	9863	6370	1794	1699	64%	23:48:32
3	news.sklaffkom.se	1	2880	1633	1246	1	56%	21:30:46
4	localhost	26	1	1	0	0	100%	00:00:01
5	news.thebunker.nu	13	0	0	0	0	0%	00:00:01
TOTAL: 5		1437	257399	168932	7131	81336	65%	69:12:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	379.2 MB	7.6 KB	164.6 MB	543.8 MB	69%	2.3 KB
2	usenet.blueworldhosting.com	19.2 MB	0.0 KB	4.1 MB	23.2 MB	82%	2.9 KB
3	news.sklaffkom.se	2.6 MB	0.0 KB	3.5 KB	2.6 MB	99%	1.7 KB
4	localhost	1.0 KB	0.0 KB	0.0 KB	1.0 KB	100%	1.0 KB
5	news.thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		401.0 MB	7.6 KB	168.6 MB	569.7 MB	70%	2.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 15 03:00:02 - 03:59:59	5072	3.0%	1.41	9.8 MB	2.4%	2.79
Nov 15 04:00:00 - 04:59:59	6157	3.6%	1.71	13.2 MB	3.3%	3.74
Nov 15 05:00:00 - 05:59:59	8236	4.9%	2.29	17.6 MB	4.4%	5.00
Nov 15 06:00:00 - 06:59:59	7471	4.4%	2.08	16.2 MB	4.0%	4.61
Nov 15 07:00:00 - 07:59:59	8115	4.8%	2.25	22.0 MB	5.5%	6.25
Nov 15 08:00:00 - 08:59:59	6672	3.9%	1.85	15.7 MB	3.9%	4.46
Nov 15 09:00:00 - 09:59:59	6462	3.8%	1.79	15.6 MB	3.9%	4.43
Nov 15 10:00:00 - 10:59:59	6703	4.0%	1.86	14.1 MB	3.5%	4.02
Nov 15 11:00:00 - 11:59:59	5544	3.3%	1.54	10.8 MB	2.7%	3.08
Nov 15 12:00:00 - 12:59:59	6652	3.9%	1.85	16.8 MB	4.2%	4.76
Nov 15 13:00:00 - 13:59:59	6641	3.9%	1.84	13.3 MB	3.3%	3.80
Nov 15 14:00:00 - 14:59:59	5972	3.5%	1.66	15.1 MB	3.8%	4.30
Nov 15 15:00:00 - 15:59:59	6602	3.9%	1.83	16.0 MB	4.0%	4.54
Nov 15 16:00:00 - 16:59:59	5303	3.1%	1.47	12.4 MB	3.1%	3.54
Nov 15 17:00:00 - 17:59:59	5230	3.1%	1.45	12.7 MB	3.2%	3.61
Nov 15 18:00:00 - 18:59:59	7737	4.6%	2.15	26.9 MB	6.7%	7.64
Nov 15 19:00:00 - 19:59:59	8246	4.9%	2.29	26.1 MB	6.5%	7.42
Nov 15 20:00:00 - 20:59:59	8481	5.0%	2.36	16.1 MB	4.0%	4.59
Nov 15 21:00:00 - 21:59:59	8826	5.2%	2.45	17.3 MB	4.3%	4.93
Nov 15 22:00:00 - 22:59:59	7030	4.2%	1.95	14.4 MB	3.6%	4.09
Nov 15 23:00:00 - 23:59:59	6803	4.0%	1.89	13.6 MB	3.4%	3.86
Nov 16 00:00:00 - 00:59:59	7910	4.7%	2.20	19.0 MB	4.7%	5.39
Nov 16 01:00:00 - 01:59:59	8588	5.1%	2.39	25.9 MB	6.4%	7.36
Nov 16 02:00:00 - 02:59:59	8649	5.1%	2.40	20.8 MB	5.2%	5.92
Nov 16 03:00:00 - 03:00:02	3	0.0%	1.50	27.0 KB	0.0%	13.52
TOTAL: 24:00:00	169105	100.0%	1.96	401.3 MB	100.0%	4.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	chimia.se	79653	0	2	1	79650
2	usenet.blueworldhosting.com	1756	365	0	0	1391
3	news.sklaffkom.se	3	1	0	0	2
TOTAL: 3		81412	366	2	1	81043

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.test.9	18
dk.livssyn.kristendom	16
aus.computers	15
hispagatos.hacking.ransomware	15
sfnet.huuhaa	13
spline.fli4l.geschnatter	13
de.comm.infosystems.www.authoring.misc	10
it.comp.os.linux.iniziare	10
uk.tech.broadcast	10
hispagatos.mastodon.tag.hacking	9
muc.lists.netbsd.port.mips	9
de.comp.lang.perl	8
de.rec.film.misc	8
fr.usenet.abus.d	8
al.christnet.bible	7
christnet.bible	7
fr.rec.moto	7
ba.test	6
linux.debian.devel.mentors	6
microsoft.public.windowsxp.general	6
TOTAL: 114	366

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

Groups	Count
news.newusers.questions	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	35291
EMP (phn path)	28304
EMP (phl)	14860
EMP (md5)	1507
HTML Multipart	418
Subject Rejected	152
Too many newsgroups (meow)	105
Too many newsgroups	83
MI5 Filter	65
Scoring filter	47
Too many newsgroups (low_xpost)	45
EMP (fsl)	39
HTML post	31
User-issued cancel	15
HTML file attachment	3
Binary: misplaced binary	1
hierarchy violation - crosspost outside sdnet	1
TOTAL: 17	80967

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	353
	TOTAL: 1	353
Including strange strings
	usenet.blueworldhosting.com	57
	chimia.se	17
	news.sklaffkom.se	2
	TOTAL: 3	76
TOTAL: 2		429

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaffkom.se	167859	1750	4588	160944	698	1%	23:59:57
2	usenet.blueworldhosting.com	5320	4	1641	3637	0	0%	23:57:08
3	sklaff.dev	0	0	0	0	169088	0%	23:57:45
4	thebunker.nu	0	0	0	0	169088	0%	23:59:57
TOTAL: 4		173179	1754	6229	164581	338874	1%	95:54:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaffkom.se	3.7 MB	380.0 MB	383.7 MB	4.5 KB/s	2.4 KB	23:59:57
2	usenet.blueworldhosting.com	3.0 KB	21.3 MB	21.3 MB	0.3 KB/s	6.0 KB	23:57:08
3	sklaff.dev	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:45
4	thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
TOTAL: 4		3.7 MB	401.3 MB	405.0 MB	1.2 KB/s	2.5 KB	95:54:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	81-229-79-101-no600.tbcn.telia.com	15	8	6.1 KB	6	0	01:46:49
2	localhost	1	0	0.0 KB	0	1	00:00:00
TOTAL: 2		16	8	6.1 KB	6	1	01:48:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.tbcn.telia.com	15	8	6.1 KB	6	0	01:46:49
2	unresolved	9	0	0.0 KB	0	0	00:00:35
3	*.monitoring.internet-measurement.com	6	0	0.0 KB	0	0	00:00:06
4	*.stretchoid.com	4	0	0.0 KB	0	0	00:00:20
5	*.149.216.162.bc.googleusercontent.com	3	0	0.0 KB	0	0	00:00:12
6	*.ip.linodeusercontent.com	2	0	0.0 KB	0	0	00:00:00
7	*.150.216.162.bc.googleusercontent.com	1	0	0.0 KB	0	0	00:00:00
8	*.scanner.modat.io	1	0	0.0 KB	0	0	00:00:00
9	?	1	0	0.0 KB	0	1	00:00:00
TOTAL: 9		42	8	6.1 KB	6	1	01:48:05

NNRP auth users [Top 20]:

User	Conn
peterlondon	15
TOTAL: 1	15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
81-229-79-101-no600.tbcn.telia.com	0.257	0.091	0.305	01:46:49
147.185.132.143	0.167	0.020	0.000	00:00:27
31.149.216.162.bc.googleusercontent.com	0.082	0.012	0.000	00:00:11
azpdcshz0bgu.stretchoid.com	0.014	0.011	0.000	00:00:10
azpdsgykc2ek.stretchoid.com	0.008	0.015	0.000	00:00:10
147.185.133.62	0.006	0.009	0.000	00:00:05
curious.monitoring.internet-measurement.com	0.041	0.002	0.000	00:00:03
auspicious.monitoring.internet-measurement.com	0.047	0.002	0.000	00:00:03
198.235.24.110	0.010	0.002	0.000	00:00:01
66.132.153.138	0.039	0.009	0.000	00:00:01
172-236-228-197.ip.linodeusercontent.com	0.082	0.008	0.000	00:00:00
145.150.216.162.bc.googleusercontent.com	0.043	0.007	0.000	00:00:00
38.149.216.162.bc.googleusercontent.com	0.042	0.005	0.000	00:00:00
localhost	0.088	0.024	0.000	00:00:00
agile.monitoring.internet-measurement.com	0.082	0.007	0.000	00:00:00
balanced.monitoring.internet-measurement.com	0.040	0.004	0.000	00:00:00
agreeable.monitoring.internet-measurement.com	0.039	0.004	0.000	00:00:00
o46.scanner.modat.io	0.010	0.006	0.000	00:00:00
153.92.19.153	0.046	0.010	0.000	00:00:00
TOTAL: 19	1.143	0.248	0.305	01:48:05

Curious NNRP server explorers [Top 20]:

System	Conn
147.185.132.143	4
153.92.19.153	2
172-236-228-197.ip.linodeusercontent.com	2
31.149.216.162.bc.googleusercontent.com	2
agile.monitoring.internet-measurement.com	2
azpdcshz0bgu.stretchoid.com	2
azpdsgykc2ek.stretchoid.com	2
145.150.216.162.bc.googleusercontent.com	1
147.185.133.62	1
198.235.24.110	1
38.149.216.162.bc.googleusercontent.com	1
66.132.153.138	1
agreeable.monitoring.internet-measurement.com	1
auspicious.monitoring.internet-measurement.com	1
balanced.monitoring.internet-measurement.com	1
curious.monitoring.internet-measurement.com	1
o46.scanner.modat.io	1
TOTAL: 17	26

NNRP gethostbyaddr failures [Top 20]:

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

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

System	Conn
147.185.132.143	8
172-236-228-197.ip.linodeusercontent.com	5
145.150.216.162.bc.googleusercontent.com	4
147.185.133.62	4
31.149.216.162.bc.googleusercontent.com	4
38.149.216.162.bc.googleusercontent.com	4
66.132.153.138	1
azpdcshz0bgu.stretchoid.com	1
azpdsgykc2ek.stretchoid.com	1
TOTAL: 9	32

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

Command	Count
GET / HTTP/1.1	7
Host: 109.238.130.155:563	6
Accept-Encoding: gzip	4
Accept: /	4
User-Agent: Hello from Palo Alto Network...	3
User-Agent: curl/7.68.0	3
MGLNDD_109.238.130.155_119	2
EHLO www.censys.io	1
Host: 109.238.130.155:119	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 10	32

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.185.132.143	2	4
81-229-79-101-no600.tbcn.telia.com	2	0
145.150.216.162.bc.googleusercontent.com	1	1
172-236-228-197.ip.linodeusercontent.com	1	1
31.149.216.162.bc.googleusercontent.com	1	2
38.149.216.162.bc.googleusercontent.com	1	1
TOTAL: 6	8	11

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	swnet	8	100.0%
TOTAL: 1		8	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	swnet.sklaff.test	8
TOTAL: 1		8

Daily Usenet report

Nov 15 03:00:02 -- Nov 16 03:00:02