Daily Usenet report: Sep 3 03:00:01

Unknown entries from news log file:

First 50 / 2101 lines (2.4%)

Sep 3 03:01:40 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 08:50:31 news innd: message repeated 11 times: [ ctlinnd command s]
Sep 3 08:50:42 news innd: message repeated 12 times: [ ctlinnd command s]
Sep 3 08:50:46 news innd: message repeated 9 times: [ ctlinnd command s]
Sep 3 08:50:59 news innd: message repeated 5 times: [ ctlinnd command s]
Sep 3 08:51:19 news innd: message repeated 10 times: [ ctlinnd command s]
Sep 3 08:51:31 news innd: message repeated 8 times: [ ctlinnd command s]
Sep 3 08:51:47 news innd: message repeated 5 times: [ ctlinnd command s]
Sep 3 08:51:48 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 08:51:57 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 08:52:13 news innd: message repeated 9 times: [ ctlinnd command s]
Sep 3 08:52:45 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 08:52:55 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 08:53:15 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 08:53:21 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 08:53:35 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 08:53:57 news innd: message repeated 5 times: [ ctlinnd command s]
Sep 3 08:54:33 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 08:54:43 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 08:54:57 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 3 08:55:17 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 08:55:27 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 08:55:37 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 08:55:49 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:12:24 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:12:24 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 3 09:12:25 news innd: message repeated 7 times: [ ctlinnd command s]
Sep 3 09:12:26 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:12:27 news innd: message repeated 6 times: [ ctlinnd command s]
Sep 3 09:12:43 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:16:23 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:16:47 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:16:57 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 09:17:07 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 09:17:15 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:17:49 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 3 09:17:55 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:18:22 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 09:18:30 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:18:38 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:19:00 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 09:19:18 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:19:50 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 09:20:00 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:20:24 news innd: message repeated 4 times: [ ctlinnd command s]
Sep 3 09:20:34 news innd: message repeated 3 times: [ ctlinnd command s]
Sep 3 09:21:14 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:21:24 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:21:36 news innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:21:42 news innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	472836	65.8%	52.9 MB	68.6%
innd	209597	29.1%	20.6 MB	26.7%
nnrpd	34827	4.8%	3.5 MB	4.5%
innfeed	1798	0.3%	139.7 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	719059	100.0%	77.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	470935	34.0%
Do not exist	416218	30.0%
Cache misses	353390	25.5%
Positive hits	145139	10.5%
TOTAL: 4	1385682	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.091	0.0%	43	0.000	2.116	22.000
article cleanup	00:00:02.670	0.0%	473461	0.000	0.006	1.000
article logging	00:00:08.945	0.0%	473482	0.000	0.019	1.000
article parse	00:00:05.864	0.0%	1671771	0.000	0.004	1.000
article write	00:00:51.914	0.1%	238347	0.000	0.218	118.000
data move	00:00:00.980	0.0%	2542583	0.000	0.000	0.667
hisgrep/artcncl	00:00:00.004	0.0%	43	0.000	0.093	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:39.219	0.2%	1386972	0.000	0.115	74.750
history sync	00:00:00.022	0.0%	998	0.000	0.022	3.000
history write	00:04:03.814	0.3%	416739	0.000	0.585	80.000
idle	23:43:25.261	98.8%	2160721	4.806	39.526	60034.000
nntp read	00:00:13.256	0.0%	2114501	0.000	0.006	1.000
overview write	00:00:21.089	0.0%	238347	0.000	0.088	48.500
perl filter	00:06:06.260	0.4%	416264	0.000	0.880	754.000
python filter	00:00:24.377	0.0%	1329299	0.000	0.018	8.000
site send	00:00:01.573	0.0%	236321	0.000	0.007	0.200
TOTAL: 24:00:16.468	23:58:25.339	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.326	0.0%	235852	0.000	0.001	0.056
article prepare	00:00:00.068	0.0%	940640	0.000	0.000	0.002
article read	00:00:22.145	0.0%	235160	0.000	0.094	3.000
backlog stats	00:00:00.129	0.0%	1090122	0.000	0.000	0.500
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:05.716	0.0%	660841	0.000	0.009	0.083
data write	00:00:06.473	0.0%	425393	0.000	0.015	0.500
idle	23:58:46.877	100.0%	1090122	11.221	79.190	30100.500
status file	00:00:00.120	0.0%	116	0.000	1.034	13.667
TOTAL: 23:59:29.611	23:59:21.854	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:11:47.265	67.0%	34426	67.000	334.261	27675.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.756	0.0%	80306	0.000	0.009	0.286
TOTAL: 04:46:09.960	03:11:48.021	67.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	5	912391	235816	441807	234768	25%	11:18:04
2	news.sklaff.dev	240	2313	2092	0	221	90%	24:54:54
3	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 3		270	914704	237908	441807	234989	26%	36:12:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	2.1 GB	237.7 MB	1.0 GB	3.3 GB	61%	7.4 KB
2	news.sklaff.dev	6.9 MB	0.0 KB	593.2 KB	7.5 MB	92%	3.3 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		2.1 GB	237.7 MB	1.0 GB	3.3 GB	62%	7.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 3 03:00:01 - 03:59:59	52	0.0%	0.01	213.7 KB	0.0%	0.06
Sep 3 04:00:00 - 04:59:59	50	0.0%	0.01	132.2 KB	0.0%	0.04
Sep 3 05:00:00 - 05:59:59	80	0.0%	0.02	308.3 KB	0.0%	0.09
Sep 3 06:00:00 - 06:59:59	47	0.0%	0.01	99.1 KB	0.0%	0.03
Sep 3 07:00:00 - 07:59:59	61	0.0%	0.02	190.3 KB	0.0%	0.05
Sep 3 08:00:00 - 08:59:59	2673	1.1%	0.74	8.5 MB	0.4%	2.41
Sep 3 09:00:00 - 09:59:59	47006	19.8%	13.06	386.9 MB	18.3%	110.05
Sep 3 10:00:00 - 10:59:59	53158	22.4%	14.77	590.0 MB	28.0%	167.82
Sep 3 11:00:00 - 11:59:59	49039	20.6%	13.62	434.2 MB	20.6%	123.49
Sep 3 12:00:00 - 12:59:59	24715	10.4%	6.87	218.5 MB	10.4%	62.14
Sep 3 13:00:00 - 13:59:59	19326	8.1%	5.37	169.0 MB	8.0%	48.07
Sep 3 14:00:00 - 14:59:59	2633	1.1%	0.73	17.9 MB	0.8%	5.10
Sep 3 15:00:00 - 15:59:59	5460	2.3%	1.52	46.2 MB	2.2%	13.14
Sep 3 16:00:00 - 16:59:59	1372	0.6%	0.38	13.8 MB	0.7%	3.93
Sep 3 17:00:00 - 17:59:59	6006	2.5%	1.67	34.6 MB	1.6%	9.84
Sep 3 18:00:00 - 18:59:59	20302	8.5%	5.64	157.3 MB	7.5%	44.74
Sep 3 19:00:00 - 19:59:59	2208	0.9%	0.61	19.3 MB	0.9%	5.48
Sep 3 20:00:00 - 20:59:59	102	0.0%	0.03	375.1 KB	0.0%	0.10
Sep 3 21:00:00 - 21:59:59	103	0.0%	0.03	376.8 KB	0.0%	0.10
Sep 3 22:00:00 - 22:59:59	2978	1.3%	0.83	10.3 MB	0.5%	2.92
Sep 3 23:00:00 - 23:59:59	116	0.0%	0.03	473.1 KB	0.0%	0.13
Sep 4 00:00:00 - 00:59:59	118	0.0%	0.03	518.7 KB	0.0%	0.14
Sep 4 01:00:00 - 01:59:59	125	0.1%	0.03	371.1 KB	0.0%	0.10
Sep 4 02:00:00 - 03:00:01	108	0.0%	0.03	503.6 KB	0.0%	0.14
TOTAL: 24:00:00	237838	100.0%	2.75	2.1 GB	100.0%	25.01

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	chimia.se	234768	0	56722	475	177571
2	news.sklaff.dev	230	1	0	0	229
TOTAL: 2		234998	1	56722	475	177800

Unwanted newsgroups [Top 20]:

Newsgroup	Count
eternal-september.config	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	97687
EMP (phn path)	42601
EMP (md5)	25140
Binary: misplaced binary	7337
EMP (phn nph)	2346
EMP (phl)	1388
EMP (fsl)	1021
Too many newsgroups (meow)	216
HTML Multipart	14
EMP (phr path)	9
hierarchy violation - crosspost outside sdnet	7
HTML post	5
Too many newsgroups (low_xpost)	3
User-issued cancel	2
Bad path (news0.telusplanet.net.POSTED)	1
EMP (phr nph)	1
MID-Bot	1
TOTAL: 17	177779

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	12
	TOTAL: 1	12
Including strange strings
	news.sklaff.dev	21
	TOTAL: 1	21
TOTAL: 2		33

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	sklaff.dev	235829	51423	654	183737	0	38	21%	11:48:04
TOTAL: 1		235829	51423	654	183737	0	38	21%	11:48:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaff.dev	341.9 MB	1.7 GB	2.1 GB	50.7 KB/s	9.2 KB	11:48:04
TOTAL: 1		341.9 MB	1.7 GB	2.1 GB	50.7 KB/s	9.2 KB	11:48:04

NNRP readership statistics:

System		Conn	Elapsed
1	usenet.blueworldhosting.com	11307	03:07:50
2	24.182.239.66	205	01:38:05
3	20.15.164.37	2	00:00:10
4	147.185.132.132	1	00:00:00
5	205.210.31.239	1	00:00:00
6	205.210.31.85	1	00:00:00
TOTAL: 6		11517	04:46:07

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.blueworldhosting.com	11307	03:07:50
2	unresolved	210	01:38:17
TOTAL: 2		11517	04:46:07

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
usenet.blueworldhosting.com	93.712	83.426	03:07:50
24.182.239.66	1.649	1.436	01:38:05
20.15.164.37	0.014	0.011	00:00:10
205.210.31.239	0.007	0.005	00:00:00
205.210.31.85	0.008	0.004	00:00:00
147.185.132.132	0.005	0.009	00:00:00
TOTAL: 6	95.395	84.891	04:46:07

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

System	Conn
20.15.164.37	1
TOTAL: 1	1

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

Command	Count
MGLNDD_109.238.130.155_119	1
TOTAL: 1	1

Daily Usenet report

Sep 3 03:00:01 -- Sep 4 03:00:01