Daily Usenet Report: Sep 8 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 8 08:24:24 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 9 00:00:01 newsfeed nnrpd[4109104]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	8373	28.9%	877.3 KB	14.2%
innd	8109	28.0%	1.1 MB	19.0%
inn	7928	27.3%	3.2 MB	52.8%
innfeed	4126	14.2%	803.6 KB	13.0%
cnfsstat	432	1.5%	64.9 KB	1.0%
nocem	34	0.1%	3.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	29003	100.0%	6.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	108099	59.9%
Negative hits	64762	35.9%
Do not exist	7698	4.3%
Cache misses	15	0.0%
TOTAL: 4	180574	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.156	0.0%	41	1.000	3.805	32.000
article cleanup	00:00:00.087	0.0%	7800	0.000	0.011	0.093
article logging	00:00:00.563	0.0%	7912	0.000	0.071	2.232
article parse	00:00:00.177	0.0%	11413	0.000	0.016	0.100
article write	00:00:02.281	0.0%	6530	0.000	0.349	3.594
artlog/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:01.073	0.0%	181299	0.000	0.006	0.228
hisgrep/artcncl	00:00:00.121	0.0%	41	0.000	2.951	23.000
hishave/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:54.582	0.3%	181442	0.091	1.293	18.521
history sync	00:00:00.076	0.0%	390	0.000	0.195	1.000
history write	00:01:46.217	0.1%	7735	3.281	13.732	104.379
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:39:18.459	99.2%	181442	109.140	469.343	1807.955
nntp read	00:00:03.818	0.0%	182364	0.005	0.021	0.347
overview write	00:04:51.391	0.3%	6530	0.864	44.623	2645.115
perl filter	00:00:26.764	0.0%	7735	1.527	3.460	28.014
python filter	00:00:01.163	0.0%	7735	0.000	0.150	4.754
site send	00:00:00.971	0.0%	13092	0.000	0.074	0.246
TOTAL: 23:51:14.934	23:50:27.899	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.016	0.0%	7171	0.000	0.002	0.067
article prepare	00:00:00.006	0.0%	36828	0.000	0.000	0.007
article read	00:00:00.401	0.0%	2992	0.000	0.134	0.500
backlog stats	00:00:00.307	0.0%	233176	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.084	0.0%	161755	0.000	0.013	0.042
data write	00:00:02.887	0.0%	158781	0.007	0.018	0.037
idle	23:58:44.917	100.0%	233176	175.896	370.214	1281.864
status file	00:00:00.341	0.0%	283	0.000	1.205	28.000
TOTAL: 23:59:00.012	23:58:50.959	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:22:17.854	52.6%	8306	0.250	161.071	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.271	0.2%	95422	0.000	0.045	1.000
TOTAL: 00:42:22.167	00:22:22.125	52.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	3	12056	1654	10102	300	13%	46:33:29
2	outgoing.news.lysator.liu.se	1	5849	1470	4306	73	25%	23:59:57
3	usenet.blueworldhosting.com	718	7467	831	6155	481	11%	23:59:32
4	news.hispagatos.org	5	6828	476	6322	30	6%	24:26:24
5	news.corradoroberto.it	6	1316	431	885	0	32%	24:18:36
6	nntp.comgw.net	2	7027	391	6542	94	5%	23:59:53
7	usenet.goja.nl.eu.org	12	7151	282	6823	46	3%	25:28:34
8	i2pn.org	1	6836	202	6612	22	2%	23:59:54
9	endofthelinebbs.peers.news.panix.com	26	6941	156	6767	18	2%	23:54:37
10	newsfeed.bofh.team	1	7501	152	7337	12	2%	23:59:55
11	usenet.network	2	5232	133	5094	5	2%	23:59:52
12	news.samoylyk.net	3	6038	122	5912	4	2%	23:59:24
13	endoftheline-out.news.chmurka.net	1	3337	89	3245	3	2%	23:59:57
14	newsfeed.xs3.de	1	5534	46	5485	3	0%	23:59:54
15	news.nntp4.net	1	5915	40	5870	5	0%	23:59:54
16	news.netfront.net	1	5820	31	5651	138	0%	23:46:50
17	glockenspiel.complete.org	2	5196	15	5177	4	0%	24:36:33
18	news.bbs.nz	1	5820	6	5810	4	0%	23:59:32
19	news.furie.org.uk	2	3393	0	3385	8	0%	23:59:36
20	photonic.trudheim.com	3	1988	0	1985	3	0%	30:11:11
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:07
22	localhost	27	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 23		822	117245	6527	109465	1253	5%	511:43:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.2 MB	27.9 KB	740.5 KB	5.9 MB	87%	3.1 KB
2	outgoing.news.lysator.liu.se	3.9 MB	37.4 KB	94.4 KB	4.0 MB	96%	2.7 KB
3	nntp.comgw.net	2.4 MB	15.2 KB	248.4 KB	2.6 MB	90%	5.5 KB
4	news.corradoroberto.it	2.3 MB	0.0 KB	0.0 KB	2.3 MB	100%	5.5 KB
5	usenet.blueworldhosting.com	2.1 MB	0.0 KB	907.0 KB	2.9 MB	69%	2.3 KB
6	news.hispagatos.org	1.5 MB	15.3 KB	39.2 KB	1.5 MB	96%	3.1 KB
7	news.samoylyk.net	1.3 MB	13.9 KB	1.2 KB	1.3 MB	98%	10.4 KB
8	usenet.goja.nl.eu.org	892.6 KB	16.4 KB	41.9 KB	950.9 KB	93%	2.9 KB
9	i2pn.org	766.2 KB	15.1 KB	30.3 KB	811.5 KB	94%	3.6 KB
10	usenet.network	672.6 KB	13.9 KB	1.9 KB	688.3 KB	97%	5.0 KB
11	endofthelinebbs.peers.news.panix.com	418.0 KB	15.3 KB	22.7 KB	456.0 KB	91%	2.6 KB
12	newsfeed.bofh.team	396.9 KB	15.2 KB	14.0 KB	426.2 KB	93%	2.6 KB
13	endoftheline-out.news.chmurka.net	210.8 KB	13.8 KB	0.0 KB	224.6 KB	93%	2.4 KB
14	newsfeed.xs3.de	146.2 KB	18.9 KB	0.0 KB	165.0 KB	88%	3.4 KB
15	news.nntp4.net	97.7 KB	13.9 KB	2.0 KB	113.5 KB	86%	2.5 KB
16	news.netfront.net	68.1 KB	15.5 KB	574.4 KB	657.9 KB	10%	3.9 KB
17	glockenspiel.complete.org	50.9 KB	13.9 KB	0.8 KB	65.5 KB	77%	3.4 KB
18	news.bbs.nz	11.7 KB	14.6 KB	0.0 KB	26.3 KB	44%	2.6 KB
19	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	news.furie.org.uk	0.0 KB	12.1 KB	9.3 KB	21.5 KB	0%	2.7 KB
22	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
23	photonic.trudheim.com	0.0 KB	0.0 KB	80.9 KB	80.9 KB	0%	27.0 KB
TOTAL: 23		22.1 MB	288.2 KB	2.7 MB	25.2 MB	87%	3.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 8 00:01:01 - 00:59:59	161	2.5%	0.05	552.6 KB	2.5%	0.16
Sep 8 01:00:00 - 01:59:59	221	3.4%	0.06	604.5 KB	2.7%	0.17
Sep 8 02:00:00 - 02:59:59	230	3.5%	0.06	705.8 KB	3.1%	0.20
Sep 8 03:00:00 - 03:59:59	302	4.6%	0.08	834.0 KB	3.7%	0.23
Sep 8 04:00:00 - 04:59:59	317	4.8%	0.09	860.9 KB	3.8%	0.24
Sep 8 05:00:00 - 05:59:59	334	5.1%	0.09	1.2 MB	5.3%	0.33
Sep 8 06:00:00 - 06:59:59	379	5.8%	0.11	1.0 MB	4.7%	0.30
Sep 8 07:00:00 - 07:59:59	340	5.2%	0.09	1.0 MB	4.6%	0.29
Sep 8 08:00:00 - 08:59:59	344	5.3%	0.10	935.8 KB	4.2%	0.26
Sep 8 09:00:00 - 09:59:59	374	5.7%	0.10	1.0 MB	4.7%	0.30
Sep 8 10:00:00 - 10:59:59	349	5.3%	0.10	1.0 MB	4.7%	0.29
Sep 8 11:00:00 - 11:59:59	335	5.1%	0.09	1.3 MB	6.1%	0.38
Sep 8 12:00:00 - 12:59:59	404	6.2%	0.11	1.3 MB	5.8%	0.36
Sep 8 13:00:00 - 13:59:59	399	6.1%	0.11	1.4 MB	6.3%	0.39
Sep 8 14:00:00 - 14:59:59	347	5.3%	0.10	1020.6 KB	4.5%	0.28
Sep 8 15:00:00 - 15:59:59	408	6.2%	0.11	1.6 MB	7.1%	0.44
Sep 8 16:00:00 - 16:59:59	298	4.6%	0.08	1005.3 KB	4.5%	0.28
Sep 8 17:00:00 - 17:59:59	203	3.1%	0.06	706.1 KB	3.1%	0.20
Sep 8 18:00:00 - 18:59:59	160	2.4%	0.04	752.2 KB	3.3%	0.21
Sep 8 19:00:00 - 19:59:59	128	2.0%	0.04	450.9 KB	2.0%	0.13
Sep 8 20:00:00 - 20:59:59	99	1.5%	0.03	1.0 MB	4.7%	0.30
Sep 8 21:00:00 - 21:59:59	115	1.8%	0.03	580.8 KB	2.6%	0.16
Sep 8 22:00:00 - 22:59:59	115	1.8%	0.03	766.9 KB	3.4%	0.21
Sep 8 23:00:00 - 23:59:59	177	2.7%	0.05	532.4 KB	2.4%	0.15
Sep 9 00:00:00 - 00:01:01	3	0.0%	0.05	23.6 KB	0.1%	0.39
TOTAL: 24:00:00	6542	100.0%	0.08	22.0 MB	100.0%	0.26

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	97.2 GB	6.3%	0	0.42	44881.41
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.9%	0	0.03	1968.86
TOTAL: 3	-	3077.0 GB	101.1 GB	3.3%	1	0.29	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	515	3	0	512
2	endofthelinebbs-out.news.weretis.net	350	0	6	344
3	news.netfront.net	136	0	4	132
4	nntp.comgw.net	113	0	4	109
5	outgoing.news.lysator.liu.se	76	0	6	70
6	usenet.goja.nl.eu.org	55	0	5	50
7	news.hispagatos.org	38	15	4	19
8	i2pn.org	23	0	4	19
9	endofthelinebbs.peers.news.panix.com	21	2	4	15
10	newsfeed.bofh.team	13	0	4	9
11	news.furie.org.uk	8	0	2	6
12	news.nntp4.net	8	0	3	5
13	usenet.network	6	0	3	3
14	glockenspiel.complete.org	5	0	3	2
15	news.bbs.nz	4	0	4	0
16	news.samoylyk.net	4	0	3	1
17	endoftheline-out.news.chmurka.net	3	0	3	0
18	newsfeed.xs3.de	3	0	3	0
19	photonic.trudheim.com	3	0	0	3
TOTAL: 19		1384	20	65	1299

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	14
soc.culture.russia	4
hispagatos.hacking.news	1
soc.culture.brazilian	1
TOTAL: 4	20

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	374
EMP (phr path)	355
EMP (md5)	295
Too many newsgroups	124
User-issued cancel	16
HTML Multipart	15
Too many newsgroups (meow)	7
EMP (phr nph)	1
TOTAL: 8	1187

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
nocem@eternal-september.org (bot)	1	5	0	5
i2pn2-nocem@i2pn2.org (rmp)	14	0	57	57
i2pn2-nocem@i2pn2.org (multipost)	11	0	40	40
robot@pasdenom.info (spam3)	5	0	9	9
robot@pasdenom.info (spam2)	1	0	1	1
TOTAL: 5	32	5	107	112

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	usenet.blueworldhosting.com	2
	TOTAL: 1	2
Including strange strings
	endofthelinebbs-out.news.weretis.net	53
	usenet.blueworldhosting.com	34
	usenet.goja.nl.eu.org	9
	endofthelinebbs.peers.news.panix.com	5
	outgoing.news.lysator.liu.se	3
	news.nntp4.net	3
	nntp.comgw.net	2
	glockenspiel.complete.org	2
	newsfeed.bofh.team	1
	TOTAL: 9	112
TOTAL: 2		114

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10645	1670	4841	0	35	15%	23:59:14
2	nyheter.lysator.liu.se	5266	1175	3597	298	8	22%	23:59:47
3	photonic.trudheim.com	6865	405	5223	897	0	5%	23:59:53
4	news.furie.org.uk	3708	398	2624	11	41	10%	23:59:04
5	news.bbs.nz	11821	361	5854	316	121	3%	23:59:52
6	usenet.network	7218	351	5705	41	15	4%	23:59:08
7	news.hispagatos.org	7042	329	5544	176	1	4%	23:59:13
8	endofthelinebbs.peers.news.panix.com	6756	320	6213	3	0	4%	23:59:47
9	usenet.goja.nl.eu.org	7225	262	5912	56	21	3%	23:59:54
10	news.nk.ca	9708	202	5893	358	79	2%	23:59:54
11	news.tnetconsulting.net	7291	124	6275	134	20	1%	23:59:54
12	newsfeed.bofh.team	7340	123	6188	0	33	1%	23:59:52
13	news.weretis.net	6782	116	6385	36	1	1%	23:59:54
14	news.nntp4.net	7514	106	6364	6	23	1%	23:59:54
15	i2pn.org	7357	100	6171	60	99	1%	23:59:45
16	news.quux.org	6926	83	6121	322	7	1%	23:59:32
17	nntp.comgw.net	7080	81	6038	29	37	1%	23:59:54
18	newsfeed.xs3.de	7686	49	6368	68	36	0%	23:59:42
19	news.chmurka.net	3522	36	2951	0	18	1%	23:59:54
20	news.corradoroberto.it	734	31	609	7	0	4%	23:17:55
21	news.samoylyk.net	7686	26	6336	42	37	0%	23:59:54
22	usenet.blueworldhosting.com	5661	0	5437	0	0	0%	23:59:53
23	feed.opticnetworks.net	0	0	0	0	6542	0%	23:59:54
TOTAL: 23		151833	6348	116649	2860	7174	4%	551:11:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	7.1 MB	0.0 KB	7.1 MB	0.1 KB/s	4.4 KB	23:59:14
2	nyheter.lysator.liu.se	3.6 MB	1.3 MB	4.9 MB	0.1 KB/s	3.4 KB	23:59:47
3	news.furie.org.uk	2.1 MB	102.7 KB	2.2 MB	0.0 KB/s	5.5 KB	23:59:04
4	photonic.trudheim.com	1.9 MB	2.3 MB	4.2 MB	0.1 KB/s	3.3 KB	23:59:53
5	news.bbs.nz	1.2 MB	1.2 MB	2.4 MB	0.0 KB/s	3.6 KB	23:59:52
6	endofthelinebbs.peers.news.panix.com	1.2 MB	2.7 KB	1.2 MB	0.0 KB/s	3.7 KB	23:59:47
7	usenet.network	1.1 MB	77.9 KB	1.2 MB	0.0 KB/s	3.2 KB	23:59:08
8	news.hispagatos.org	1.1 MB	708.1 KB	1.8 MB	0.0 KB/s	3.7 KB	23:59:13
9	usenet.goja.nl.eu.org	573.8 KB	374.1 KB	947.9 KB	0.0 KB/s	3.0 KB	23:59:54
10	news.tnetconsulting.net	539.1 KB	516.1 KB	1.0 MB	0.0 KB/s	4.1 KB	23:59:54
11	news.weretis.net	494.9 KB	179.5 KB	674.4 KB	0.0 KB/s	4.4 KB	23:59:54
12	news.nk.ca	485.5 KB	1.2 MB	1.7 MB	0.0 KB/s	3.1 KB	23:59:54
13	newsfeed.bofh.team	458.8 KB	0.0 KB	458.8 KB	0.0 KB/s	3.7 KB	23:59:52
14	news.nntp4.net	385.9 KB	25.0 KB	410.9 KB	0.0 KB/s	3.7 KB	23:59:54
15	news.quux.org	385.0 KB	871.7 KB	1.2 MB	0.0 KB/s	3.1 KB	23:59:32
16	i2pn.org	342.2 KB	200.6 KB	542.8 KB	0.0 KB/s	3.4 KB	23:59:45
17	nntp.comgw.net	268.0 KB	152.6 KB	420.5 KB	0.0 KB/s	3.8 KB	23:59:54
18	newsfeed.xs3.de	222.3 KB	202.5 KB	424.8 KB	0.0 KB/s	3.6 KB	23:59:42
19	news.corradoroberto.it	164.3 KB	23.3 KB	187.6 KB	0.0 KB/s	4.9 KB	23:17:55
20	news.chmurka.net	126.8 KB	0.0 KB	126.8 KB	0.0 KB/s	3.5 KB	23:59:54
21	news.samoylyk.net	88.4 KB	88.9 KB	177.2 KB	0.0 KB/s	2.6 KB	23:59:54
22	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
23	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 23		23.8 MB	9.5 MB	33.3 MB	0.0 KB/s	3.7 KB	551:11:43

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2766	00:29:05
2	167.94.145.106	3	00:00:03
3	104.152.52.216	1	00:00:00
4	104.152.52.232	1	00:00:10
5	147.185.132.120	1	00:00:03
6	198.235.24.22	1	00:00:03
7	80.66.76.121	1	00:00:00
8	89.211.203.35.bc.googleusercontent.com	1	00:00:00
9	azpdsgb2.stretchoid.com	1	00:00:00
10	azpdwsc9.stretchoid.com	1	00:00:09
11	localhost	1	00:12:36
12	server-0-2.survey.inspici.com	1	00:00:04
13	server-0-3.survey.inspici.com	1	00:00:03
TOTAL: 13		2780	00:42:20

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2766	00:29:05
2	unresolved	8	00:00:21
3	*.stretchoid.com	2	00:00:09
4	*.survey.inspici.com	2	00:00:07
5	*.211.203.35.bc.googleusercontent.com	1	00:00:00
6	?	1	00:12:36
TOTAL: 6		2780	00:42:20

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
ddt.demos.su	9.952	36.438	0.000	00:29:05
localhost	179.366	367.299	0.074	00:12:36
104.152.52.232	0.000	0.016	0.000	00:00:10
azpdwsc9.stretchoid.com	0.004	0.012	0.000	00:00:09
server-0-2.survey.inspici.com	0.005	0.011	0.000	00:00:04
167.94.145.106	0.019	0.046	0.000	00:00:03
198.235.24.22	0.008	0.008	0.000	00:00:03
server-0-3.survey.inspici.com	0.000	0.018	0.000	00:00:03
147.185.132.120	0.000	0.016	0.000	00:00:03
80.66.76.121	0.004	0.012	0.000	00:00:00
89.211.203.35.bc.googleusercontent.com	0.004	0.012	0.000	00:00:00
azpdsgb2.stretchoid.com	0.004	0.012	0.000	00:00:00
104.152.52.216	0.004	0.012	0.000	00:00:00
TOTAL: 13	189.370	403.912	0.074	00:42:20

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

System	Conn
89.211.203.35.bc.googleusercontent.com	4
167.94.145.106	1
80.66.76.121	1
azpdsgb2.stretchoid.com	1
TOTAL: 4	7

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

Command	Count
#003	1
Accept-Encoding: gzip	1
EHLO www.censys.io	1
GET / HTTP/1.1	1
Host: 144.172.126.95:119	1
MGLNDD_144.172.126.95_119	1
User-Agent: Expanse, a Palo Alto Network...	1
TOTAL: 7	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.145.106	1	2
80.66.76.121	1	1
TOTAL: 2	2	5

Daily Usenet Report

Sep 8 00:01:01 -- Sep 9 00:01:01