Daily Usenet Report: Aug 4 00:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 4 08:09:01 newsfeed innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innfeed	23951	57.5%	3.1 MB	39.1%
inn	8614	20.7%	3.4 MB	42.7%
innd	8430	20.2%	1.3 MB	16.8%
cnfsstat	432	1.0%	76.7 KB	0.9%
nnrpd	130	0.3%	13.8 KB	0.2%
nocem	98	0.2%	27.1 KB	0.3%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	41656	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	115043	60.5%
Negative hits	66901	35.2%
Do not exist	8148	4.3%
Cache misses	35	0.0%
TOTAL: 4	190127	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.372	0.0%	375	0.000	0.992	21.000
article cleanup	00:00:00.093	0.0%	8184	0.000	0.011	0.521
article logging	00:00:00.395	0.0%	8319	0.000	0.047	0.151
article parse	00:00:00.165	0.0%	12061	0.000	0.014	0.085
article write	00:00:01.887	0.0%	6972	0.000	0.271	2.562
artlog/artcncl	00:00:00.013	0.0%	300	0.000	0.043	0.667
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:00.719	0.0%	190945	0.000	0.004	0.096
hisgrep/artcncl	00:00:00.137	0.0%	376	0.000	0.364	5.000
hishave/artcncl	00:00:00.040	0.0%	351	0.000	0.114	8.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:23.854	0.2%	190841	0.075	0.754	4.624
history sync	00:00:00.076	0.0%	407	0.000	0.187	0.667
history write	00:01:45.491	0.1%	8183	3.214	12.891	82.235
hiswrite/artcncl	00:00:00.038	0.0%	6	3.000	6.333	8.000
idle	23:52:04.290	99.4%	186261	116.514	461.311	1425.090
nntp read	00:00:03.148	0.0%	191915	0.003	0.016	0.109
overview write	00:03:47.249	0.3%	6972	0.667	32.595	1783.643
perl filter	00:00:21.834	0.0%	8181	1.278	2.669	17.016
python filter	00:00:01.088	0.0%	8181	0.000	0.133	5.031
site send	00:00:01.041	0.0%	13995	0.000	0.074	0.213
TOTAL: 24:01:08.654	24:00:31.930	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	10160	0.000	0.003	0.044
article prepare	00:00:00.006	0.0%	44512	0.000	0.000	0.004
article read	00:00:00.513	0.0%	3845	0.000	0.133	0.452
backlog stats	00:00:00.412	0.0%	269129	0.000	0.002	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.357	0.0%	178187	0.004	0.013	0.042
data write	00:00:03.204	0.0%	173609	0.007	0.018	0.035
idle	23:57:01.869	100.0%	269129	179.715	320.374	890.814
status file	00:00:09.762	0.0%	8599	1.000	1.135	1.984
TOTAL: 23:57:38.876	23:57:18.151	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:53.401	97.1%	23	21.000	2321.783	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.002	0.0%	173	0.000	0.012	0.200
TOTAL: 00:00:54.977	00:00:53.403	97.1%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	98
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	120

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	4	13209	1919	11000	290	14%	47:45:06
2	outgoing.news.lysator.liu.se	2	6146	1370	4660	116	22%	23:59:52
3	usenet.blueworldhosting.com	718	7951	1020	6405	526	12%	23:56:57
4	news.hispagatos.org	5	7266	541	6699	26	7%	24:26:21
5	news.corradoroberto.it	7	1138	336	802	0	29%	23:42:02
6	nntp.comgw.net	2	7570	317	7169	84	4%	23:59:18
7	usenet.goja.nl.eu.org	10	7399	269	7094	36	3%	23:41:19
8	i2pn.org	2	7062	243	6811	8	3%	23:59:25
9	194.87.241.33	44	319	179	139	1	56%	16:55:37
10	endofthelinebbs.peers.news.panix.com	25	7301	176	7112	13	2%	23:54:03
11	news.samoylyk.net	5	6397	164	6230	3	2%	23:57:58
12	usenet.network	2	5380	148	5229	3	2%	23:58:58
13	endoftheline-out.news.chmurka.net	2	3529	115	3414	0	3%	23:59:40
14	newsfeed.bofh.team	1	7932	84	7847	1	1%	23:59:54
15	news.nntp4.net	1	6135	60	6074	1	0%	23:59:53
16	newsfeed.xs3.de	1	5911	51	5860	0	0%	23:59:54
17	glockenspiel.complete.org	2	5537	11	5526	0	0%	23:56:47
18	news.bbs.nz	1	6414	6	6403	5	0%	23:59:42
19	news.netfront.net	1	5587	5	5485	97	0%	24:49:19
20	news.furie.org.uk	1	3727	3	3723	1	0%	23:59:54
21	photonic.trudheim.com	1	1300	0	1300	0	0%	18:40:30
22	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:06
23	localhost	7	0	0	0	0	0%	00:00:00
24	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 24		847	123210	7017	114982	1211	5%	516:12:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.6 MB	1.8 KB	510.8 KB	6.1 MB	91%	2.8 KB
2	outgoing.news.lysator.liu.se	4.6 MB	0.0 KB	551.8 KB	5.1 MB	89%	3.5 KB
3	usenet.blueworldhosting.com	2.4 MB	0.0 KB	1.0 MB	3.4 MB	70%	2.2 KB
4	usenet.network	2.3 MB	0.0 KB	8.1 KB	2.3 MB	99%	15.7 KB
5	news.corradoroberto.it	2.0 MB	0.0 KB	0.0 KB	2.0 MB	100%	6.1 KB
6	news.hispagatos.org	1.6 MB	0.0 KB	96.7 KB	1.7 MB	94%	3.1 KB
7	nntp.comgw.net	1.3 MB	0.0 KB	234.1 KB	1.5 MB	85%	3.9 KB
8	news.samoylyk.net	971.9 KB	0.0 KB	6.9 KB	978.7 KB	99%	5.9 KB
9	usenet.goja.nl.eu.org	837.1 KB	0.0 KB	67.4 KB	904.4 KB	92%	3.0 KB
10	i2pn.org	786.2 KB	0.0 KB	10.8 KB	797.0 KB	98%	3.2 KB
11	endofthelinebbs.peers.news.panix.com	578.9 KB	0.0 KB	20.0 KB	598.8 KB	96%	3.2 KB
12	194.87.241.33	497.2 KB	1.7 KB	0.0 KB	498.9 KB	99%	2.8 KB
13	endoftheline-out.news.chmurka.net	276.3 KB	0.0 KB	0.0 KB	276.3 KB	100%	2.4 KB
14	newsfeed.bofh.team	207.2 KB	0.0 KB	4.7 KB	211.9 KB	97%	2.5 KB
15	glockenspiel.complete.org	183.5 KB	0.0 KB	0.0 KB	183.5 KB	100%	16.7 KB
16	newsfeed.xs3.de	160.2 KB	0.0 KB	0.0 KB	160.2 KB	100%	3.1 KB
17	news.nntp4.net	147.7 KB	0.0 KB	2.0 KB	149.7 KB	98%	2.5 KB
18	news.netfront.net	18.2 KB	0.0 KB	311.5 KB	329.7 KB	5%	3.2 KB
19	news.bbs.nz	13.4 KB	24.6 KB	0.0 KB	38.0 KB	35%	3.5 KB
20	news.furie.org.uk	9.1 KB	0.0 KB	0.6 KB	9.7 KB	93%	2.4 KB
21	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
22	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
23	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
24	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 24		24.4 MB	28.1 KB	2.8 MB	27.2 MB	89%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 4 00:01:02 - 00:59:59	189	2.7%	0.05	625.5 KB	2.5%	0.18
Aug 4 01:00:00 - 01:59:59	267	3.8%	0.07	771.5 KB	3.1%	0.21
Aug 4 02:00:00 - 02:59:59	312	4.5%	0.09	1.5 MB	6.1%	0.42
Aug 4 03:00:00 - 03:59:59	332	4.8%	0.09	890.8 KB	3.6%	0.25
Aug 4 04:00:00 - 04:59:59	357	5.1%	0.10	1020.3 KB	4.1%	0.28
Aug 4 05:00:00 - 05:59:59	285	4.1%	0.08	820.0 KB	3.3%	0.23
Aug 4 06:00:00 - 06:59:59	329	4.7%	0.09	1.3 MB	5.4%	0.37
Aug 4 07:00:00 - 07:59:59	308	4.4%	0.09	1.0 MB	4.1%	0.28
Aug 4 08:00:00 - 08:59:59	315	4.5%	0.09	1.1 MB	4.3%	0.30
Aug 4 09:00:00 - 09:59:59	387	5.6%	0.11	1.2 MB	5.0%	0.34
Aug 4 10:00:00 - 10:59:59	355	5.1%	0.10	1.1 MB	4.4%	0.30
Aug 4 11:00:00 - 11:59:59	374	5.4%	0.10	1.4 MB	5.8%	0.40
Aug 4 12:00:00 - 12:59:59	383	5.5%	0.11	1.0 MB	4.2%	0.29
Aug 4 13:00:00 - 13:59:59	354	5.1%	0.10	1.3 MB	5.2%	0.36
Aug 4 14:00:00 - 14:59:59	394	5.7%	0.11	1.3 MB	5.2%	0.36
Aug 4 15:00:00 - 15:59:59	343	4.9%	0.10	1.5 MB	6.2%	0.43
Aug 4 16:00:00 - 16:59:59	363	5.2%	0.10	1.3 MB	5.3%	0.37
Aug 4 17:00:00 - 17:59:59	255	3.7%	0.07	908.1 KB	3.6%	0.25
Aug 4 18:00:00 - 18:59:59	241	3.5%	0.07	1.7 MB	7.1%	0.50
Aug 4 19:00:00 - 19:59:59	135	1.9%	0.04	480.1 KB	1.9%	0.13
Aug 4 20:00:00 - 20:59:59	126	1.8%	0.04	442.9 KB	1.8%	0.12
Aug 4 21:00:00 - 21:59:59	216	3.1%	0.06	690.6 KB	2.8%	0.19
Aug 4 22:00:00 - 22:59:59	159	2.3%	0.04	538.2 KB	2.2%	0.15
Aug 4 23:00:00 - 23:59:59	185	2.7%	0.05	702.3 KB	2.8%	0.20
Aug 5 00:00:00 - 00:01:02	2	0.0%	0.03	25.0 KB	0.1%	0.40
TOTAL: 24:00:00	6966	100.0%	0.08	24.3 MB	100.0%	0.29

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
CYCNGS1	NGS	1536.0 GB	48.0 MB	0.0%	1	0.00	0.00
CYCNGS2	NGS	1536.0 GB	95.8 GB	6.2%	0	0.45	41084.69
CYCTXT1	TEXT	5.0 GB	3.6 GB	72.4%	0	0.02	2433.07
TOTAL: 3	-	3077.0 GB	99.5 GB	3.2%	1	0.39	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	565	8	0	557
2	endofthelinebbs-out.news.weretis.net	361	1	1	359
3	outgoing.news.lysator.liu.se	116	5	0	111
4	news.netfront.net	97	0	0	97
5	nntp.comgw.net	86	3	0	83
6	news.hispagatos.org	42	4	0	38
7	usenet.goja.nl.eu.org	41	1	0	40
8	endofthelinebbs.peers.news.panix.com	19	1	0	18
9	i2pn.org	9	0	0	9
10	usenet.network	3	1	0	2
11	news.nntp4.net	2	0	0	2
12	news.samoylyk.net	2	0	0	2
13	newsfeed.bofh.team	2	0	0	2
14	194.87.241.33	1	0	1	0
15	glockenspiel.complete.org	1	0	1	0
16	news.furie.org.uk	1	0	0	1
TOTAL: 16		1348	24	3	1321

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.russian.z1	19
a.b.p.hotteenmodels	2
news.test_messages	2
a.b.models	1
TOTAL: 4	24

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	407
EMP (phr path)	301
EMP (md5)	293
EMP (phl)	74
Too many newsgroups	61
EMP (fsl)	22
HTML Multipart	10
User-issued cancel	10
Too many newsgroups (meow)	3
Binary: misplaced binary	2
Binary Image: misplaced jpg	1
TOTAL: 11	1184

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	46	344	0	344
robot@pasdenom.info (spam3)	4	0	14	14
robot@pasdenom.info (spam2)	1	0	1	1
TOTAL: 3	51	344	15	359

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	81
	usenet.blueworldhosting.com	39
	endofthelinebbs.peers.news.panix.com	6
	usenet.goja.nl.eu.org	5
	i2pn.org	1
	news.nntp4.net	1
	nntp.comgw.net	1
	newsfeed.bofh.team	1
	TOTAL: 8	135
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		136

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10548	2595	4366	0	81	24%	23:59:53
2	nyheter.lysator.liu.se	5808	1527	3807	265	0	26%	23:59:50
3	photonic.trudheim.com	7336	533	5246	1182	0	7%	23:59:53
4	news.furie.org.uk	4349	490	3007	19	15	11%	23:58:35
5	news.hispagatos.org	7793	445	5901	146	10	5%	23:58:25
6	usenet.network	7483	392	5812	40	5	5%	23:59:47
7	news.bbs.nz	12799	290	6320	344	91	2%	23:59:31
8	usenet.goja.nl.eu.org	7981	258	6270	105	13	3%	23:59:53
9	news.nntp4.net	8066	221	6657	15	17	2%	23:59:53
10	news.nk.ca	9887	203	6305	394	36	2%	23:59:53
11	i2pn.org	7568	179	6454	76	40	2%	23:59:53
12	newsfeed.bofh.team	8130	136	6665	2	14	1%	23:59:53
13	news.corradoroberto.it	774	83	534	2	0	10%	22:36:52
14	news.weretis.net	7215	75	6796	90	0	1%	23:59:28
15	nntp.comgw.net	7800	65	6478	75	7	0%	23:59:53
16	news.tnetconsulting.net	7722	60	6726	173	11	0%	23:59:53
17	news.quux.org	7338	55	6539	365	1	0%	23:59:53
18	newsfeed.xs3.de	8450	34	6736	110	13	0%	23:59:53
19	news.chmurka.net	4103	32	3316	1	6	0%	23:58:38
20	news.samoylyk.net	8389	24	6732	34	33	0%	23:59:53
21	usenet.blueworldhosting.com	5941	0	5629	0	0	0%	23:59:49
22	ddt.demos.su	2810	0	0	0	2847	0%	23:59:53
23	endofthelinebbs.peers.news.panix.com	0	0	0	0	6960	0%	23:59:53
24	feed.opticnetworks.net	0	0	0	0	6966	0%	23:57:44
TOTAL: 24		158290	7697	116296	3438	17166	4%	574:27:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	10.1 MB	0.0 KB	10.1 MB	0.1 KB/s	4.0 KB	23:59:53
2	nyheter.lysator.liu.se	5.7 MB	1.9 MB	7.7 MB	0.1 KB/s	4.4 KB	23:59:50
3	news.furie.org.uk	2.8 MB	113.3 KB	3.0 MB	0.0 KB/s	6.0 KB	23:58:35
4	photonic.trudheim.com	2.6 MB	3.7 MB	6.2 MB	0.1 KB/s	3.7 KB	23:59:53
5	news.hispagatos.org	1.7 MB	1.1 MB	2.8 MB	0.0 KB/s	4.9 KB	23:58:25
6	usenet.network	1.3 MB	127.3 KB	1.5 MB	0.0 KB/s	3.4 KB	23:59:47
7	news.quux.org	1.3 MB	1.4 MB	2.7 MB	0.0 KB/s	6.6 KB	23:59:53
8	newsfeed.bofh.team	1.2 MB	3.7 KB	1.2 MB	0.0 KB/s	9.1 KB	23:59:53
9	news.samoylyk.net	1.1 MB	199.6 KB	1.3 MB	0.0 KB/s	22.5 KB	23:59:53
10	news.nntp4.net	1.1 MB	127.1 KB	1.2 MB	0.0 KB/s	5.2 KB	23:59:53
11	news.bbs.nz	745.8 KB	2.6 MB	3.3 MB	0.0 KB/s	5.3 KB	23:59:31
12	usenet.goja.nl.eu.org	734.5 KB	789.1 KB	1.5 MB	0.0 KB/s	4.2 KB	23:59:53
13	i2pn.org	637.7 KB	289.1 KB	926.8 KB	0.0 KB/s	3.6 KB	23:59:53
14	news.nk.ca	440.6 KB	2.5 MB	2.9 MB	0.0 KB/s	5.0 KB	23:59:53
15	news.tnetconsulting.net	399.7 KB	808.0 KB	1.2 MB	0.0 KB/s	5.2 KB	23:59:53
16	nntp.comgw.net	347.4 KB	1.3 MB	1.7 MB	0.0 KB/s	12.3 KB	23:59:53
17	newsfeed.xs3.de	307.6 KB	1.5 MB	1.8 MB	0.0 KB/s	12.6 KB	23:59:53
18	news.corradoroberto.it	307.1 KB	22.4 KB	329.5 KB	0.0 KB/s	3.9 KB	22:36:52
19	news.weretis.net	254.3 KB	1.6 MB	1.9 MB	0.0 KB/s	11.5 KB	23:59:28
20	news.chmurka.net	96.7 KB	1.1 KB	97.8 KB	0.0 KB/s	3.0 KB	23:58:38
21	ddt.demos.su	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
22	endofthelinebbs.peers.news.panix.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:44
24	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:49
TOTAL: 24		33.1 MB	20.0 MB	53.1 MB	0.0 KB/s	4.9 KB	574:27:01

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	unresolved	11	1	00:00:38
2	*.li.binaryedge.ninja	3	0	00:00:04
3	*.monitoring.internet-measurement.com	1	0	00:00:01
4	*.stretchoid.com	1	0	00:00:09
TOTAL: 4		16	1	00:00:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
198.235.24.128	0.003	0.013	00:00:10
104.152.52.229	0.000	0.016	00:00:10
198.235.24.211	0.000	0.023	00:00:10
azpdcs40.stretchoid.com	0.004	0.012	00:00:09
prod-boron-us-southeast-40.li.binaryedge.ninja	0.020	0.029	00:00:04
167.94.138.118	0.021	0.045	00:00:04
167.94.146.50	0.018	0.045	00:00:03
inventive.monitoring.internet-measurement.com	0.008	0.011	00:00:01
45.227.254.8	0.000	0.016	00:00:00
104.152.52.201	0.000	0.016	00:00:00
TOTAL: 10	0.074	0.226	00:00:54

Curious NNRP server explorers [Top 20]:

System	Conn
167.94.138.118	3
167.94.146.50	3
prod-boron-us-southeast-40.li.binaryedge.ninja	3
104.152.52.201	1
104.152.52.229	1
198.235.24.128	1
198.235.24.211	1
45.227.254.8	1
azpdcs40.stretchoid.com	1
inventive.monitoring.internet-measurement.com	1
TOTAL: 10	16

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

System	Conn
167.94.138.118	1
167.94.146.50	1
45.227.254.8	1
TOTAL: 3	3

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

Command	Count
EHLO www.censys.io	2
#003	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.118	1	2
167.94.146.50	1	2
45.227.254.8	1	1
TOTAL: 3	3	5

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	comp	2	100.0%
TOTAL: 1		2	100.0%

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

Newsgroup		Count
1	comp.sys.acorn.announce	2
TOTAL: 1		2

Daily Usenet Report

Aug 4 00:01:02 -- Aug 5 00:01:02