Daily Usenet Report: Oct 9 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 9 08:17:34 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Oct 10 00:00:01 newsfeed nnrpd[2574329]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	22435	51.2%	2.7 MB	32.6%
inn	8703	19.8%	3.6 MB	43.2%
innd	8072	18.4%	1.1 MB	13.9%
innfeed	4152	9.5%	803.7 KB	9.5%
cnfsstat	432	1.0%	64.5 KB	0.8%
nocem	53	0.1%	6.2 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	43848	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	116998	60.0%
Negative hits	69357	35.6%
Do not exist	8598	4.4%
Cache misses	18	0.0%
TOTAL: 4	194971	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.132	0.0%	48	0.000	2.750	11.000
article cleanup	00:00:00.480	0.0%	8629	0.000	0.056	4.875
article logging	00:00:01.114	0.0%	8707	0.000	0.128	8.630
article parse	00:00:00.190	0.0%	13563	0.000	0.014	0.071
article write	00:00:03.903	0.0%	7369	0.019	0.530	29.280
artlog/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.838	0.0%	198183	0.000	0.004	0.187
hisgrep/artcncl	00:00:00.104	0.0%	50	0.000	2.080	9.000
hishave/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hishave/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:24.173	0.2%	195765	0.090	0.736	4.467
history sync	00:00:00.087	0.0%	391	0.000	0.223	1.000
history write	00:01:55.258	0.1%	8629	3.136	13.357	105.262
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:53:04.711	99.4%	202525	77.154	424.563	1550.829
nntp read	00:00:04.097	0.0%	197866	0.002	0.021	0.466
overview write	00:01:39.291	0.1%	7369	0.714	13.474	743.937
perl filter	00:00:30.335	0.0%	8628	1.523	3.516	31.276
python filter	00:00:02.239	0.0%	8629	0.000	0.259	6.410
site send	00:00:01.274	0.0%	14789	0.000	0.086	0.580
TOTAL: 24:01:04.805	23:59:48.226	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.015	0.0%	7861	0.000	0.002	0.026
article prepare	00:00:00.011	0.0%	69700	0.000	0.000	0.003
article read	00:00:00.831	0.0%	5498	0.000	0.151	0.364
backlog stats	00:00:00.336	0.0%	271574	0.000	0.001	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.438	0.0%	188657	0.000	0.013	0.038
data write	00:00:03.548	0.0%	184758	0.005	0.019	0.034
idle	23:58:54.030	100.0%	271574	162.716	317.902	1652.849
status file	00:00:00.335	0.0%	290	0.800	1.155	10.000
TOTAL: 23:59:14.963	23:59:01.544	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:11:51.608	78.1%	16691	0.500	258.319	300349.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.254	0.1%	112290	0.000	0.038	2.167
TOTAL: 01:32:02.766	01:11:55.862	78.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	3	13444	1787	11116	541	13%	49:37:33
2	outgoing.news.lysator.liu.se	2	6005	1383	4618	4	23%	23:59:49
3	usenet.blueworldhosting.com	722	8392	771	7264	357	9%	23:44:55
4	usenet.network	2	5878	607	5269	2	10%	23:59:55
5	news.hispagatos.org	6	7530	548	6960	22	7%	24:26:01
6	news.corradoroberto.it	11	1382	527	855	0	38%	25:15:14
7	nntp.comgw.net	1	7596	420	7067	109	5%	23:59:56
8	i2pn.org	1	7320	357	6913	50	4%	23:59:54
9	usenet.goja.nl.eu.org	11	7682	342	7276	64	4%	25:40:27
10	endoftheline-out.news.chmurka.net	2	3205	175	3029	1	5%	21:41:38
11	endofthelinebbs.peers.news.panix.com	26	7799	158	7613	28	2%	24:03:53
12	newsfeed.bofh.team	2	8309	93	8213	3	1%	23:59:40
13	news.nntp4.net	2	6128	69	6058	1	1%	23:58:05
14	newsfeed.xs3.de	2	5913	53	5860	0	0%	23:58:26
15	news.netfront.net	1	4100	53	3937	110	1%	23:22:00
16	news.samoylyk.net	3	6383	49	6333	1	0%	23:59:12
17	news.furie.org.uk	3	8163	14	8149	0	0%	47:56:00
18	glockenspiel.complete.org	2	5607	8	5599	0	0%	23:56:29
19	news.bbs.nz	2	6436	4	6432	0	0%	23:59:20
20	localhost	28	1	1	0	0	100%	00:00:00
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	news.nk.ca	2	0	0	0	0	0%	00:20:13
TOTAL: 22		835	127273	7419	118561	1293	5%	506:08:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.network	6.7 MB	8.6 KB	0.9 KB	6.7 MB	99%	11.3 KB
2	endofthelinebbs-out.news.weretis.net	5.6 MB	0.0 KB	1.4 MB	7.0 MB	80%	3.1 KB
3	outgoing.news.lysator.liu.se	4.3 MB	0.0 KB	3.8 KB	4.3 MB	99%	3.2 KB
4	news.corradoroberto.it	2.6 MB	0.0 KB	0.0 KB	2.6 MB	100%	5.1 KB
5	news.hispagatos.org	2.1 MB	0.0 KB	36.7 KB	2.2 MB	98%	3.9 KB
6	usenet.blueworldhosting.com	1.9 MB	0.0 KB	704.7 KB	2.6 MB	73%	2.3 KB
7	i2pn.org	1.6 MB	0.0 KB	72.0 KB	1.7 MB	95%	4.2 KB
8	nntp.comgw.net	1.2 MB	0.0 KB	214.4 KB	1.4 MB	85%	2.7 KB
9	usenet.goja.nl.eu.org	1.0 MB	0.0 KB	74.8 KB	1.1 MB	93%	2.8 KB
10	news.samoylyk.net	1.0 MB	0.0 KB	0.7 KB	1.0 MB	99%	20.6 KB
11	endoftheline-out.news.chmurka.net	683.8 KB	0.0 KB	0.7 KB	684.5 KB	99%	3.9 KB
12	endofthelinebbs.peers.news.panix.com	408.6 KB	0.0 KB	41.8 KB	450.4 KB	90%	2.4 KB
13	newsfeed.bofh.team	321.8 KB	0.0 KB	7.7 KB	329.5 KB	97%	3.4 KB
14	newsfeed.xs3.de	197.2 KB	0.0 KB	0.0 KB	197.2 KB	100%	3.7 KB
15	news.nntp4.net	163.5 KB	0.0 KB	0.8 KB	164.3 KB	99%	2.3 KB
16	glockenspiel.complete.org	106.7 KB	0.0 KB	0.0 KB	106.7 KB	100%	13.3 KB
17	news.netfront.net	94.8 KB	0.0 KB	607.4 KB	702.3 KB	13%	4.3 KB
18	news.furie.org.uk	58.3 KB	0.0 KB	0.0 KB	58.3 KB	100%	4.2 KB
19	news.bbs.nz	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	1.8 KB
20	localhost	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
21	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
22	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 22		30.1 MB	8.6 KB	3.1 MB	33.2 MB	90%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 9 00:01:01 - 00:59:59	155	2.1%	0.04	485.0 KB	1.6%	0.14
Oct 9 01:00:00 - 01:59:59	265	3.6%	0.07	776.9 KB	2.6%	0.22
Oct 9 02:00:00 - 02:59:59	291	4.0%	0.08	1.1 MB	3.7%	0.31
Oct 9 03:00:00 - 03:59:59	421	5.7%	0.12	1.9 MB	6.4%	0.54
Oct 9 04:00:00 - 04:59:59	439	6.0%	0.12	1.4 MB	4.8%	0.40
Oct 9 05:00:00 - 05:59:59	347	4.7%	0.10	1.4 MB	4.9%	0.41
Oct 9 06:00:00 - 06:59:59	339	4.6%	0.09	1.2 MB	4.0%	0.33
Oct 9 07:00:00 - 07:59:59	407	5.5%	0.11	1.4 MB	4.9%	0.41
Oct 9 08:00:00 - 08:59:59	344	4.7%	0.10	1.3 MB	4.4%	0.37
Oct 9 09:00:00 - 09:59:59	431	5.9%	0.12	1.6 MB	5.3%	0.44
Oct 9 10:00:00 - 10:59:59	484	6.6%	0.13	2.0 MB	6.7%	0.56
Oct 9 11:00:00 - 11:59:59	474	6.4%	0.13	1.6 MB	5.4%	0.45
Oct 9 12:00:00 - 12:59:59	413	5.6%	0.11	2.2 MB	7.4%	0.62
Oct 9 13:00:00 - 13:59:59	360	4.9%	0.10	1.4 MB	4.6%	0.38
Oct 9 14:00:00 - 14:59:59	383	5.2%	0.11	1.2 MB	4.2%	0.35
Oct 9 15:00:00 - 15:59:59	355	4.8%	0.10	1.5 MB	5.3%	0.44
Oct 9 16:00:00 - 16:59:59	337	4.6%	0.09	1.3 MB	4.5%	0.38
Oct 9 17:00:00 - 17:59:59	307	4.2%	0.09	1.5 MB	5.0%	0.42
Oct 9 18:00:00 - 18:59:59	151	2.1%	0.04	556.1 KB	1.9%	0.15
Oct 9 19:00:00 - 19:59:59	149	2.0%	0.04	629.7 KB	2.1%	0.17
Oct 9 20:00:00 - 20:59:59	134	1.8%	0.04	1.4 MB	4.6%	0.39
Oct 9 21:00:00 - 21:59:59	150	2.0%	0.04	816.3 KB	2.7%	0.23
Oct 9 22:00:00 - 22:59:59	106	1.4%	0.03	457.2 KB	1.5%	0.13
Oct 9 23:00:00 - 23:59:59	114	1.5%	0.03	355.0 KB	1.2%	0.10
Oct 10 00:00:00 - 00:01:02	5	0.1%	0.08	29.1 KB	0.1%	0.47
TOTAL: 24:00:01	7361	100.0%	0.09	29.3 MB	100.0%	0.35

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	98.4 GB	6.4%	0	0.52	35527.01
CYCTXT1	TEXT	5.0 GB	3.9 GB	77.8%	0	0.03	2222.36
TOTAL: 3	-	3077.0 GB	102.3 GB	3.3%	1	0.37	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	endofthelinebbs-out.news.weretis.net	608	0	0	0	608
2	usenet.blueworldhosting.com	369	0	0	1	368
3	nntp.comgw.net	105	0	0	0	105
4	news.netfront.net	82	0	0	0	82
5	usenet.goja.nl.eu.org	57	0	0	0	57
6	i2pn.org	50	0	0	0	50
7	endofthelinebbs.peers.news.panix.com	31	0	0	0	31
8	news.hispagatos.org	23	17	0	0	6
9	outgoing.news.lysator.liu.se	4	0	0	0	4
10	newsfeed.bofh.team	3	1	0	0	2
11	glockenspiel.complete.org	2	0	0	0	2
12	usenet.network	2	0	1	0	1
13	endoftheline-out.news.chmurka.net	1	0	0	0	1
14	news.nntp4.net	1	0	0	0	1
15	news.samoylyk.net	1	0	0	0	1
TOTAL: 15		1339	18	1	1	1319

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	9
hispagatos.hacking.news	7
hispagatos.hacking.exploits	1
soc.culture.russia	1
TOTAL: 4	18

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

Groups	Count
rec.arts.movies.reviews	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	618
EMP (phr path)	315
EMP (md5)	94
Too many newsgroups (meow)	55
User-issued cancel	50
Too many newsgroups	49
EMP (fsl)	47
HTML Multipart	7
Binary: misplaced binary	5
TOTAL: 9	1240

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	1	1	0	1
i2pn2-nocem@i2pn2.org (multipost)	31	0	153	153
robot@pasdenom.info (spam2)	9	0	10	10
i2pn2-nocem@i2pn2.org (rmp)	5	0	9	9
robot@pasdenom.info (spam3)	5	0	8	8
TOTAL: 5	51	1	180	181

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	57
	usenet.blueworldhosting.com	12
	usenet.goja.nl.eu.org	3
	nntp.comgw.net	3
	endofthelinebbs.peers.news.panix.com	2
	glockenspiel.complete.org	1
	TOTAL: 6	78
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		79

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	9862	4269	3032	8	14	43%	23:58:23
2	nyheter.lysator.liu.se	6228	1494	3762	719	2	23%	23:58:47
3	news.furie.org.uk	4492	946	2818	13	24	21%	23:59:51
4	photonic.trudheim.com	7801	792	5459	1106	3	10%	23:59:39
5	endofthelinebbs.peers.news.panix.com	7734	610	6745	1	1	7%	23:58:55
6	usenet.network	7246	372	5863	34	8	5%	23:59:51
7	usenet.goja.nl.eu.org	8106	365	6202	409	8	4%	23:59:51
8	news.hispagatos.org	8191	351	6118	343	8	4%	23:59:00
9	newsfeed.bofh.team	8438	347	6807	0	11	4%	23:58:37
10	news.bbs.nz	13147	252	6281	826	122	1%	23:59:44
11	news.nk.ca	10698	170	6331	807	36	1%	23:59:51
12	i2pn.org	8331	167	6754	63	130	2%	23:59:51
13	news.tnetconsulting.net	8322	161	6849	345	26	1%	23:59:50
14	news.nntp4.net	8292	141	7027	87	11	1%	23:59:50
15	nntp.comgw.net	8005	139	6600	216	5	1%	23:59:51
16	news.weretis.net	7706	131	6980	249	2	1%	23:59:23
17	news.quux.org	7843	107	6461	785	15	1%	23:59:51
18	newsfeed.xs3.de	8763	57	6860	390	9	0%	23:59:51
19	news.corradoroberto.it	835	49	690	8	0	5%	22:36:54
20	news.chmurka.net	4768	32	3001	4	38	0%	23:59:51
21	news.samoylyk.net	8475	17	7244	36	23	0%	23:59:51
22	usenet.blueworldhosting.com	6710	7	6348	0	0	0%	23:58:52
23	feed.opticnetworks.net	0	0	0	0	7361	0%	23:59:51
TOTAL: 23		169993	10976	124232	6449	7857	6%	550:26:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	18.7 MB	18.6 KB	18.7 MB	0.2 KB/s	4.5 KB	23:58:23
2	news.furie.org.uk	8.0 MB	77.5 KB	8.0 MB	0.1 KB/s	8.6 KB	23:59:51
3	photonic.trudheim.com	6.2 MB	3.4 MB	9.7 MB	0.1 KB/s	5.2 KB	23:59:39
4	nyheter.lysator.liu.se	5.0 MB	7.2 MB	12.2 MB	0.1 KB/s	5.7 KB	23:58:47
5	endofthelinebbs.peers.news.panix.com	4.1 MB	8.5 KB	4.1 MB	0.0 KB/s	7.0 KB	23:58:55
6	newsfeed.bofh.team	3.2 MB	0.0 KB	3.2 MB	0.0 KB/s	9.5 KB	23:58:37
7	news.quux.org	1.4 MB	6.0 MB	7.5 MB	0.1 KB/s	8.6 KB	23:59:51
8	usenet.network	1.4 MB	84.8 KB	1.5 MB	0.0 KB/s	3.7 KB	23:59:51
9	news.hispagatos.org	1.3 MB	3.5 MB	4.8 MB	0.1 KB/s	7.0 KB	23:59:00
10	nntp.comgw.net	1.2 MB	2.6 MB	3.8 MB	0.0 KB/s	11.0 KB	23:59:51
11	news.bbs.nz	1.1 MB	6.9 MB	8.0 MB	0.1 KB/s	7.6 KB	23:59:44
12	newsfeed.xs3.de	1.1 MB	3.0 MB	4.1 MB	0.0 KB/s	9.3 KB	23:59:51
13	news.tnetconsulting.net	969.3 KB	3.3 MB	4.2 MB	0.1 KB/s	8.6 KB	23:59:50
14	usenet.goja.nl.eu.org	891.5 KB	4.6 MB	5.5 MB	0.1 KB/s	7.2 KB	23:59:51
15	news.samoylyk.net	825.1 KB	240.8 KB	1.0 MB	0.0 KB/s	20.1 KB	23:59:51
16	news.weretis.net	781.9 KB	4.0 MB	4.8 MB	0.1 KB/s	12.8 KB	23:59:23
17	news.nntp4.net	728.9 KB	1.2 MB	2.0 MB	0.0 KB/s	8.8 KB	23:59:50
18	i2pn.org	566.7 KB	422.4 KB	989.2 KB	0.0 KB/s	4.3 KB	23:59:51
19	news.nk.ca	499.9 KB	7.2 MB	7.6 MB	0.1 KB/s	8.0 KB	23:59:51
20	news.corradoroberto.it	153.0 KB	25.8 KB	178.8 KB	0.0 KB/s	3.1 KB	22:36:54
21	news.chmurka.net	113.2 KB	71.5 KB	184.7 KB	0.0 KB/s	5.1 KB	23:59:51
22	usenet.blueworldhosting.com	72.9 KB	0.0 KB	72.9 KB	0.0 KB/s	10.4 KB	23:58:52
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
TOTAL: 23		58.2 MB	53.9 MB	112.1 MB	0.1 KB/s	6.6 KB	550:26:15

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	?	2794	0	01:01:37
2	*.demos.su	2771	0	00:29:30
3	unresolved	14	2	00:00:38
4	*.stretchoid.com	1	0	00:00:09
5	*.survey.inspici.com	1	0	00:00:03
TOTAL: 5		5581	2	01:32:00

NNRP auth users [Top 20]:

User	Conn
getlist	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
2001:470:28:a8:3ad5:47ff:fe02:c2fd	0.008	0.042	0.000	00:30:01
ddt.demos.su	9.539	36.883	0.000	00:29:30
2001:470:28:a8:ca7f:54ff:fe03:41e0	9.878	37.357	0.000	00:20:21
localhost	188.493	371.107	0.074	00:11:14
147.185.132.81	0.004	0.012	0.000	00:00:10
205.210.31.221	0.008	0.008	0.000	00:00:10
azpdcsc35.stretchoid.com	0.003	0.013	0.000	00:00:09
199.45.154.156	0.020	0.041	0.000	00:00:07
206.168.34.62	0.025	0.037	0.000	00:00:06
167.94.138.124	0.021	0.040	0.000	00:00:03
server-0-3.survey.inspici.com	0.000	0.017	0.000	00:00:03
91.238.181.31	0.000	0.016	0.000	00:00:00
147.45.112.151	0.000	0.016	0.000	00:00:00
207.90.244.4	0.003	0.013	0.000	00:00:00
TOTAL: 14	208.002	445.602	0.074	01:32:00

Curious NNRP server explorers [Top 20]:

System	Conn
2001:470:28:a8:ca7f:54ff:fe03:41e0	2790
ddt.demos.su	2771
167.94.138.124	3
199.45.154.156	3
2001:470:28:a8:3ad5:47ff:fe02:c2fd	3
206.168.34.62	3
147.185.132.81	1
147.45.112.151	1
205.210.31.221	1
207.90.244.4	1
91.238.181.31	1
azpdcsc35.stretchoid.com	1
localhost	1
server-0-3.survey.inspici.com	1
TOTAL: 14	5581

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

System	Conn
147.45.112.151	1
167.94.138.124	1
199.45.154.156	1
206.168.34.62	1
91.238.181.31	1
TOTAL: 5	5

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.151	1	1
167.94.138.124	1	2
199.45.154.156	1	2
206.168.34.62	1	2
91.238.181.31	1	1
TOTAL: 5	5	8

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	comp	2	66.7%
2	alt	1	33.3%
TOTAL: 2		3	100.0%

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

Newsgroup		Count
1	comp.sys.acorn.games	2
2	alt.music.lyrics	1
TOTAL: 2		3

Daily Usenet Report

Oct 9 00:01:01 -- Oct 10 00:01:02