Daily Usenet report: Nov 8 03:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Nov 8 03:57:18 news innd: message repeated 2 times: [ ctlinnd command s]
Nov 8 10:57:44 news innd: message repeated 5 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	97836	52.9%	11.3 MB	53.1%
innd	84494	45.7%	9.7 MB	45.6%
innfeed	2318	1.3%	244.7 KB	1.1%
nnrpd	240	0.1%	25.5 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	184889	100.0%	21.3 MB	100.0%

History cache:

Reason	Count	%Count
Cache misses	163545	45.0%
Negative hits	98499	27.1%
Do not exist	97765	26.9%
Positive hits	3436	0.9%
TOTAL: 4	363245	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.471	0.0%	30	0.000	15.700	74.000
article cleanup	00:00:00.494	0.0%	97816	0.000	0.005	0.167
article logging	00:00:01.324	0.0%	97853	0.000	0.014	0.300
article parse	00:00:00.900	0.0%	278264	0.000	0.003	0.067
article write	00:08:45.901	0.6%	27839	0.000	18.891	2128.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:00:00.272	0.0%	455627	0.000	0.001	0.099
hisgrep/artcncl	00:00:00.005	0.0%	30	0.000	0.167	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:20:04.190	1.4%	363410	0.000	3.314	23.013
history sync	00:00:00.016	0.0%	346	0.000	0.046	2.000
history write	00:02:25.059	0.2%	97818	0.000	1.483	152.000
idle	23:25:05.547	97.6%	455051	118.944	185.266	2234.926
nntp read	00:00:03.847	0.0%	453863	0.000	0.008	0.455
overview write	00:00:14.340	0.0%	27839	0.000	0.515	161.000
perl filter	00:02:32.173	0.2%	97815	0.000	1.556	80.000
python filter	00:00:07.203	0.0%	349350	0.000	0.021	0.270
site send	00:00:00.212	0.0%	27837	0.000	0.008	1.000
TOTAL: 24:00:02.381	23:59:21.954	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	27816	0.000	0.001	0.100
article prepare	00:00:00.003	0.0%	86024	0.000	0.000	0.009
article read	00:00:12.966	0.0%	21336	0.000	0.608	601.000
backlog stats	00:00:00.047	0.0%	132718	0.000	0.000	0.100
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.510	0.0%	77389	0.000	0.007	0.250
data write	00:00:00.733	0.0%	49928	0.000	0.015	1.000
idle	23:59:41.490	100.0%	132718	69.678	650.865	30097.000
status file	00:00:00.063	0.0%	182	0.000	0.346	8.000
TOTAL: 23:59:57.407	23:59:55.847	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:25.134	31.1%	103	16.000	244.019	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	358	0.000	0.011	0.091
TOTAL: 00:01:20.715	00:00:25.138	31.1%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	0	252226	19621	164254	68351	7%	24:04:19
2	usenet.blueworldhosting.com	1388	9983	6406	1862	1715	64%	23:46:55
3	news.sklaffkom.se	1	2873	1713	1160	0	59%	20:44:05
4	localhost	25	0	0	0	0	0%	00:00:00
5	news.thebunker.nu	11	0	0	0	0	0%	00:00:01
TOTAL: 5		1425	265082	27740	167276	70066	10%	68:35:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	51.2 MB	4.8 KB	168.2 MB	219.4 MB	23%	2.6 KB
2	usenet.blueworldhosting.com	17.3 MB	0.0 KB	3.5 MB	20.8 MB	83%	2.6 KB
3	news.sklaffkom.se	2.4 MB	0.0 KB	0.0 KB	2.4 MB	100%	1.4 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		70.9 MB	4.8 KB	171.8 MB	242.7 MB	29%	2.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 8 03:00:02 - 03:59:59	855	3.1%	0.24	2.0 MB	2.8%	0.56
Nov 8 04:00:00 - 04:59:59	1020	3.7%	0.28	2.5 MB	3.5%	0.70
Nov 8 05:00:00 - 05:59:59	1084	3.9%	0.30	2.8 MB	3.9%	0.79
Nov 8 06:00:00 - 06:59:59	611	2.2%	0.17	1.5 MB	2.0%	0.41
Nov 8 07:00:00 - 07:59:59	1314	4.7%	0.36	3.1 MB	4.4%	0.89
Nov 8 08:00:00 - 08:59:59	995	3.6%	0.28	2.8 MB	3.9%	0.79
Nov 8 09:00:00 - 09:59:59	606	2.2%	0.17	1.2 MB	1.7%	0.35
Nov 8 10:00:00 - 10:59:59	1365	4.9%	0.38	4.0 MB	5.7%	1.14
Nov 8 11:00:00 - 11:59:59	1173	4.2%	0.33	3.1 MB	4.3%	0.87
Nov 8 12:00:00 - 12:59:59	548	2.0%	0.15	1.4 MB	2.0%	0.40
Nov 8 13:00:00 - 13:59:59	678	2.4%	0.19	1.8 MB	2.5%	0.50
Nov 8 14:00:00 - 14:59:59	992	3.6%	0.28	2.2 MB	3.2%	0.64
Nov 8 15:00:00 - 15:59:59	1238	4.5%	0.34	2.6 MB	3.6%	0.73
Nov 8 16:00:00 - 16:59:59	835	3.0%	0.23	2.2 MB	3.0%	0.62
Nov 8 17:00:00 - 17:59:59	2248	8.1%	0.62	5.9 MB	8.4%	1.69
Nov 8 18:00:00 - 18:59:59	1949	7.0%	0.54	5.1 MB	7.2%	1.46
Nov 8 19:00:00 - 19:59:59	1449	5.2%	0.40	4.0 MB	5.7%	1.14
Nov 8 20:00:00 - 20:59:59	1590	5.7%	0.44	4.2 MB	6.0%	1.21
Nov 8 21:00:00 - 21:59:59	1810	6.5%	0.50	5.2 MB	7.4%	1.49
Nov 8 22:00:00 - 22:59:59	1358	4.9%	0.38	3.9 MB	5.4%	1.10
Nov 8 23:00:00 - 23:59:59	1412	5.1%	0.39	3.4 MB	4.7%	0.96
Nov 9 00:00:00 - 00:59:59	1016	3.7%	0.28	2.5 MB	3.5%	0.71
Nov 9 01:00:00 - 01:59:59	793	2.9%	0.22	1.7 MB	2.4%	0.48
Nov 9 02:00:00 - 03:00:02	874	3.1%	0.24	2.0 MB	2.8%	0.57
TOTAL: 24:00:00	27813	100.0%	0.32	71.0 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	chimia.se	68274	0	1	88	68185
2	usenet.blueworldhosting.com	1749	321	0	0	1428
TOTAL: 2		70023	321	1	88	69613

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.rec.motorrad	14
newsreader.test	14
fido7.altyn.local	13
de.comm.infosystems.www.authoring.misc	10
de.rec.heimwerken	10
free.willey	9
de.comm.software.gnus	8
de.sci.ing.elektrotechnik	8
fido7.ru.sex.condoms	7
hispagatos.mastodon.tag.hacking	7
it.discussioni.energie-alternative	7
aus.computers	6
alt.my.job.sucks	5
de.comm.internet.misc	5
de.etc.finanz.banken+broker	5
fido7.su.tormoz	5
it.sport.motociclismo	5
microsoft.public.windowsxp.general	5
nz.general	5
uk.people.silversurfers	5
TOTAL: 122	321

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

Groups	Count
comp.ai	88
TOTAL: 1	88

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	31878
EMP (phn path)	23808
EMP (phl)	11697
EMP (md5)	1820
HTML Multipart	155
Too many newsgroups	73
HTML post	44
MI5 Filter	40
Subject Rejected	23
User-issued cancel	12
Scoring filter	8
Too many newsgroups (low_xpost)	7
HTML Image Tags	4
Binary: misplaced binary	1
HTML file attachment	1
PFM bot misc	1
Supersedes with optus in path	1
TOTAL: 17	69573

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	4
	TOTAL: 1	4
Including strange strings
	usenet.blueworldhosting.com	34
	chimia.se	3
	TOTAL: 2	37
No colon-space in header field
	usenet.blueworldhosting.com	3
	TOTAL: 1	3
TOTAL: 3		44

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaffkom.se	26696	1482	4603	19854	3	5%	23:59:58
2	usenet.blueworldhosting.com	1928	0	1692	170	0	0%	23:48:19
3	sklaff.dev	0	0	0	0	27811	0%	23:57:45
4	thebunker.nu	0	0	0	0	27811	0%	23:59:58
TOTAL: 4		28624	1482	6295	20024	55625	5%	95:46:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaffkom.se	2.9 MB	52.2 MB	55.2 MB	0.7 KB/s	2.6 KB	23:59:58
2	sklaff.dev	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:45
3	thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:58
4	usenet.blueworldhosting.com	0.0 KB	1.1 MB	1.1 MB	0.0 KB/s	6.7 KB	23:48:19
TOTAL: 4		2.9 MB	53.4 MB	56.3 MB	0.2 KB/s	2.7 KB	95:46:00

NNRP readership statistics:

System		Conn	Elapsed
1	81-229-79-101-no600.tbcn.telia.com	11	00:00:04
2	169.149.216.162.bc.googleusercontent.com	4	00:00:35
3	scanner-207.ch1.censys-scanner.com	3	00:00:03
4	147.185.132.169	2	00:00:17
5	172-236-228-197.ip.linodeusercontent.com	2	00:00:00
6	66-228-53-174.ip.linodeusercontent.com	2	00:00:00
7	azpdwsv12bqv.stretchoid.com	2	00:00:10
8	120.150.216.162.bc.googleusercontent.com	1	00:00:00
9	147.185.132.100	1	00:00:02
10	147.185.132.137	1	00:00:00
11	160.210.203.35.bc.googleusercontent.com	1	00:00:00
12	205.210.31.67	1	00:00:02
13	71.6.134.235	1	00:00:00
14	captivating.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 14		33	00:01:20

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.tbcn.telia.com	11	00:00:04
2	unresolved	6	00:00:23
3	*.149.216.162.bc.googleusercontent.com	4	00:00:35
4	*.ip.linodeusercontent.com	4	00:00:01
5	*.ch1.censys-scanner.com	3	00:00:03
6	*.stretchoid.com	2	00:00:10
7	*.150.216.162.bc.googleusercontent.com	1	00:00:00
8	*.210.203.35.bc.googleusercontent.com	1	00:00:00
9	*.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 9		33	00:01:20

NNRP auth users [Top 20]:

User	Conn
peterlondon	11
TOTAL: 1	11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
169.149.216.162.bc.googleusercontent.com	0.166	0.022	0.000	00:00:35
147.185.132.169	0.085	0.011	0.000	00:00:17
azpdwsv12bqv.stretchoid.com	0.016	0.009	0.000	00:00:10
81-229-79-101-no600.tbcn.telia.com	0.074	0.072	0.221	00:00:04
scanner-207.ch1.censys-scanner.com	0.054	0.016	0.000	00:00:03
205.210.31.67	0.007	0.005	0.000	00:00:02
147.185.132.100	0.008	0.005	0.000	00:00:02
captivating.monitoring.internet-measurement.com	0.010	0.001	0.000	00:00:01
172-236-228-197.ip.linodeusercontent.com	0.015	0.008	0.000	00:00:00
147.185.132.137	0.043	0.007	0.000	00:00:00
120.150.216.162.bc.googleusercontent.com	0.041	0.006	0.000	00:00:00
71.6.134.235	0.008	0.006	0.000	00:00:00
66-228-53-174.ip.linodeusercontent.com	0.016	0.006	0.000	00:00:00
160.210.203.35.bc.googleusercontent.com	0.007	0.005	0.000	00:00:00
TOTAL: 14	0.550	0.179	0.221	00:01:20

NNRP gethostbyaddr failures [Top 20]:

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

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

System	Conn
169.149.216.162.bc.googleusercontent.com	8
172-236-228-197.ip.linodeusercontent.com	8
66-228-53-174.ip.linodeusercontent.com	7
120.150.216.162.bc.googleusercontent.com	4
147.185.132.137	4
147.185.132.169	4
160.210.203.35.bc.googleusercontent.com	4
azpdwsv12bqv.stretchoid.com	1
scanner-207.ch1.censys-scanner.com	1
TOTAL: 9	41

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

Command	Count
GET / HTTP/1.1	7
Host: 109.238.130.155:563	5
Accept-Encoding: gzip	4
Accept: /	4
#001	3
User-Agent: Hello from Palo Alto Network...	3
User-Agent: curl/7.68.0	3
#026#003#001#001	2
Host: 109.238.130.155:119	2
À#024	2
À$À(À#À'	2
#026#003#001	1
EHLO www.censys.io	1
MGLNDD_109.238.130.155_119	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 15	41

NNRP client timeouts [Top 20]:

System	Conn	Peer
169.149.216.162.bc.googleusercontent.com	2	4
120.150.216.162.bc.googleusercontent.com	1	1
147.185.132.137	1	1
147.185.132.169	1	2
scanner-207.ch1.censys-scanner.com	1	2
TOTAL: 5	6	12

Daily Usenet report

Nov 8 03:00:02 -- Nov 9 03:00:02