Daily Usenet Report: Sep 30 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 30 08:12:38 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 30 23:37:05 newsfeed nnrpd[975566]: 47.186.47.228 auth: program exited with status 8
Oct 1 00:00:01 newsfeed nnrpd[983082]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	22207	50.2%	2.6 MB	31.5%
inn	9168	20.7%	3.7 MB	44.4%
innd	8225	18.6%	1.2 MB	13.9%
innfeed	4106	9.3%	795.9 KB	9.3%
cnfsstat	432	1.0%	63.5 KB	0.7%
nocem	47	0.1%	6.2 KB	0.1%
controlchan	23	0.1%	5.7 KB	0.1%
TOTAL: 7	44208	100.0%	8.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	124699	58.8%
Negative hits	78454	37.0%
Do not exist	9009	4.2%
Cache misses	21	0.0%
TOTAL: 4	212183	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.155	0.0%	38	0.000	4.079	17.000
article cleanup	00:00:00.095	0.0%	9101	0.000	0.010	0.071
article logging	00:00:00.881	0.0%	9160	0.000	0.096	4.542
article parse	00:00:00.202	0.0%	12803	0.000	0.016	0.075
article write	00:00:03.217	0.0%	7645	0.034	0.421	23.333
artlog/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
artlog/artparse	00:00:00.001	0.0%	2	0.000	0.500	1.000
data move	00:00:00.613	0.0%	217368	0.000	0.003	0.138
hisgrep/artcncl	00:00:00.095	0.0%	42	0.000	2.262	7.000
hishave/artcncl	00:00:00.007	0.0%	7	0.000	1.000	2.333
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:44.556	0.2%	214420	0.099	0.767	5.204
history sync	00:00:00.071	0.0%	404	0.000	0.176	1.000
history write	00:02:22.000	0.2%	9102	3.061	15.601	127.320
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:48:18.088	99.1%	221005	67.733	387.765	1284.450
nntp read	00:00:03.749	0.0%	217602	0.004	0.017	0.824
overview write	00:05:54.961	0.4%	7645	0.667	46.430	4985.450
perl filter	00:00:25.269	0.0%	9100	1.494	2.777	16.800
python filter	00:00:01.447	0.0%	9100	0.000	0.159	4.590
site send	00:00:01.342	0.0%	15336	0.000	0.088	0.255
TOTAL: 24:01:02.414	23:59:56.749	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.018	0.0%	8006	0.000	0.002	0.067
article prepare	00:00:00.010	0.0%	56492	0.000	0.000	0.004
article read	00:00:00.655	0.0%	5198	0.000	0.126	0.324
backlog stats	00:00:00.364	0.0%	271443	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.452	0.0%	191527	0.002	0.013	0.048
data write	00:00:03.401	0.0%	186065	0.009	0.018	0.038
idle	23:56:19.858	100.0%	271443	154.760	317.488	998.949
status file	00:00:00.312	0.0%	270	1.000	1.156	6.000
TOTAL: 23:56:39.266	23:56:27.070	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:41:52.406	66.7%	16582	0.250	151.514	12162.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.924	0.1%	111970	0.000	0.044	1.000
TOTAL: 01:02:45.580	00:41:57.330	66.9%	-	-	-	-

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

Control Channel:

Sender	Other	DoIt	OK
bofh-control@killfile.org	1	0	1
us-control@lists.killfile.org	1	1	1
TOTAL	2	1	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	3	14330	1808	12127	395	12%	47:28:41
2	outgoing.news.lysator.liu.se	1	6604	1541	5051	12	23%	23:59:56
3	usenet.blueworldhosting.com	717	8883	776	7643	464	8%	23:57:32
4	nntp.comgw.net	2	8214	620	7385	209	7%	23:59:26
5	news.hispagatos.org	6	8123	591	7514	18	7%	24:25:31
6	usenet.goja.nl.eu.org	11	8339	468	7781	90	5%	25:51:08
7	usenet.network	1	6243	452	5783	8	7%	23:59:56
8	i2pn.org	2	7963	389	7470	104	4%	23:57:41
9	news.corradoroberto.it	4	1447	270	1177	0	18%	22:18:58
10	endoftheline-out.news.chmurka.net	2	3833	260	3569	4	6%	23:59:29
11	endofthelinebbs.peers.news.panix.com	25	8091	121	7954	16	1%	23:53:49
12	newsfeed.bofh.team	1	8847	119	8719	9	1%	23:59:57
13	news.samoylyk.net	1	6955	72	6881	2	1%	24:00:00
14	news.netfront.net	1	5128	68	4951	109	1%	25:54:54
15	newsfeed.xs3.de	2	6357	56	6295	6	0%	23:59:50
16	news.furie.org.uk	2	7871	31	7835	5	0%	47:59:49
17	glockenspiel.complete.org	2	5708	18	5687	3	0%	24:01:35
18	news.nntp4.net	1	6883	15	6868	0	0%	23:59:53
19	news.bbs.nz	1	6563	9	6553	1	0%	23:59:40
20	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:04
21	localhost	27	0	0	0	0	0%	00:00:00
22	news.nk.ca	2	0	0	0	0	0%	00:20:18
TOTAL: 22		815	136382	7684	127243	1455	5%	506:18:07

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.7 MB	35.6 KB	771.6 KB	6.5 MB	87%	3.0 KB
2	outgoing.news.lysator.liu.se	4.6 MB	35.6 KB	14.7 KB	4.6 MB	98%	3.1 KB
3	usenet.network	4.5 MB	0.0 KB	7.4 KB	4.5 MB	99%	10.0 KB
4	news.hispagatos.org	2.0 MB	0.0 KB	30.3 KB	2.1 MB	98%	3.5 KB
5	usenet.blueworldhosting.com	1.9 MB	0.0 KB	981.5 KB	2.8 MB	66%	2.4 KB
6	nntp.comgw.net	1.7 MB	35.6 KB	337.2 KB	2.1 MB	82%	2.6 KB
7	usenet.goja.nl.eu.org	1.2 MB	0.0 KB	112.6 KB	1.4 MB	91%	2.5 KB
8	news.corradoroberto.it	1.2 MB	0.0 KB	0.0 KB	1.2 MB	100%	4.6 KB
9	endoftheline-out.news.chmurka.net	1013.5 KB	43.2 KB	1.4 KB	1.0 MB	95%	4.0 KB
10	i2pn.org	1013.3 KB	0.0 KB	140.0 KB	1.1 MB	87%	2.3 KB
11	newsfeed.bofh.team	343.4 KB	0.0 KB	20.1 KB	363.5 KB	94%	2.8 KB
12	endofthelinebbs.peers.news.panix.com	343.1 KB	0.0 KB	33.2 KB	376.3 KB	91%	2.7 KB
13	news.samoylyk.net	274.8 KB	0.0 KB	5.4 KB	280.2 KB	98%	3.8 KB
14	newsfeed.xs3.de	154.0 KB	0.0 KB	34.0 KB	188.0 KB	81%	3.0 KB
15	news.furie.org.uk	148.3 KB	37.6 KB	13.5 KB	199.5 KB	74%	5.5 KB
16	news.netfront.net	133.7 KB	0.0 KB	600.9 KB	734.6 KB	18%	4.2 KB
17	glockenspiel.complete.org	59.8 KB	46.2 KB	0.0 KB	106.0 KB	56%	5.0 KB
18	news.nntp4.net	36.8 KB	0.0 KB	0.0 KB	36.8 KB	100%	2.5 KB
19	news.bbs.nz	15.4 KB	7.7 KB	0.0 KB	23.2 KB	66%	2.3 KB
20	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	localhost	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		26.3 MB	241.6 KB	3.0 MB	29.6 MB	88%	3.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 30 00:01:01 - 00:59:59	186	2.4%	0.05	651.4 KB	2.4%	0.18
Sep 30 01:00:00 - 01:59:59	198	2.6%	0.06	542.2 KB	2.0%	0.15
Sep 30 02:00:00 - 02:59:59	363	4.7%	0.10	1.0 MB	3.9%	0.29
Sep 30 03:00:00 - 03:59:59	392	5.1%	0.11	1.1 MB	4.2%	0.32
Sep 30 04:00:00 - 04:59:59	377	4.9%	0.10	1.2 MB	4.4%	0.33
Sep 30 05:00:00 - 05:59:59	417	5.5%	0.12	1.3 MB	4.8%	0.36
Sep 30 06:00:00 - 06:59:59	408	5.3%	0.11	1.3 MB	4.8%	0.36
Sep 30 07:00:00 - 07:59:59	417	5.5%	0.12	1.5 MB	5.8%	0.43
Sep 30 08:00:00 - 08:59:59	424	5.5%	0.12	1.6 MB	6.1%	0.46
Sep 30 09:00:00 - 09:59:59	441	5.8%	0.12	1.3 MB	5.1%	0.38
Sep 30 10:00:00 - 10:59:59	405	5.3%	0.11	1.2 MB	4.5%	0.34
Sep 30 11:00:00 - 11:59:59	454	5.9%	0.13	1.5 MB	5.8%	0.43
Sep 30 12:00:00 - 12:59:59	429	5.6%	0.12	1.5 MB	5.7%	0.43
Sep 30 13:00:00 - 13:59:59	431	5.6%	0.12	1.4 MB	5.4%	0.41
Sep 30 14:00:00 - 14:59:59	426	5.6%	0.12	1.2 MB	4.7%	0.36
Sep 30 15:00:00 - 15:59:59	366	4.8%	0.10	1.7 MB	6.3%	0.47
Sep 30 16:00:00 - 16:59:59	291	3.8%	0.08	1.2 MB	4.4%	0.33
Sep 30 17:00:00 - 17:59:59	259	3.4%	0.07	1.4 MB	5.2%	0.39
Sep 30 18:00:00 - 18:59:59	261	3.4%	0.07	1.1 MB	4.1%	0.31
Sep 30 19:00:00 - 19:59:59	168	2.2%	0.05	655.7 KB	2.4%	0.18
Sep 30 20:00:00 - 20:59:59	151	2.0%	0.04	627.8 KB	2.3%	0.17
Sep 30 21:00:00 - 21:59:59	115	1.5%	0.03	602.5 KB	2.2%	0.17
Sep 30 22:00:00 - 22:59:59	125	1.6%	0.03	463.3 KB	1.7%	0.13
Sep 30 23:00:00 - 23:59:59	137	1.8%	0.04	415.2 KB	1.5%	0.12
Oct 1 00:00:00 - 00:01:02	2	0.0%	0.03	5.0 KB	0.0%	0.08
TOTAL: 24:00:01	7643	100.0%	0.09	26.4 MB	100.0%	0.31

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.0 GB	6.4%	0	0.49	38237.40
CYCTXT1	TEXT	5.0 GB	3.9 GB	77.5%	0	0.03	1925.99
TOTAL: 3	-	3077.0 GB	102.0 GB	3.3%	1	0.33	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	480	0	0	480
2	endofthelinebbs-out.news.weretis.net	438	0	0	438
3	nntp.comgw.net	209	0	0	209
4	news.netfront.net	108	0	0	108
5	i2pn.org	103	0	0	103
6	usenet.goja.nl.eu.org	93	0	0	93
7	endofthelinebbs.peers.news.panix.com	23	0	0	23
8	news.hispagatos.org	21	13	0	8
9	outgoing.news.lysator.liu.se	13	0	0	13
10	newsfeed.bofh.team	9	0	0	9
11	usenet.network	8	0	0	8
12	newsfeed.xs3.de	6	0	0	6
13	news.furie.org.uk	4	0	1	3
14	news.samoylyk.net	2	0	0	2
15	news.bbs.nz	1	0	0	1
TOTAL: 15		1518	13	1	1504

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	6
hispagatos.hacking.news	6
hispagatos.hacking.exploits	1
TOTAL: 3	13

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	766
EMP (phr path)	303
EMP (md5)	142
Too many newsgroups	132
EMP (phn nph)	49
User-issued cancel	34
Binary: misplaced binary	9
HTML Multipart	4
EMP (fsl)	2
Too many newsgroups (low_xpost)	1
Too many newsgroups (meow)	1
TOTAL: 11	1443

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	1	3	0	3
i2pn2-nocem@i2pn2.org (multipost)	35	0	158	158
i2pn2-nocem@i2pn2.org (rmp)	5	0	9	9
robot@pasdenom.info (spam3)	3	0	5	5
robot@pasdenom.info (spam2)	1	0	1	1
TOTAL: 5	45	3	173	176

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	26
	usenet.blueworldhosting.com	16
	endofthelinebbs.peers.news.panix.com	6
	nntp.comgw.net	4
	usenet.goja.nl.eu.org	3
	news.hispagatos.org	2
	outgoing.news.lysator.liu.se	2
	TOTAL: 7	59
No colon-space in header field
	usenet.blueworldhosting.com	2
	TOTAL: 1	2
TOTAL: 2		61

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10460	4076	3498	0	2	38%	23:59:51
2	nyheter.lysator.liu.se	6235	1381	4143	578	0	22%	23:59:51
3	news.furie.org.uk	4022	567	2949	13	0	14%	23:59:51
4	photonic.trudheim.com	8104	549	6202	891	302	6%	23:59:51
5	endofthelinebbs.peers.news.panix.com	7932	401	7232	8	0	5%	23:59:52
6	usenet.goja.nl.eu.org	8058	363	6509	234	0	4%	23:59:21
7	news.hispagatos.org	8148	282	6535	252	1	3%	23:59:47
8	newsfeed.bofh.team	8334	245	7126	0	0	2%	23:59:23
9	news.nk.ca	10905	215	6679	683	12	1%	23:59:52
10	usenet.network	7644	199	6643	21	0	2%	23:59:36
11	news.bbs.nz	13903	159	6746	727	27	1%	23:59:51
12	news.nntp4.net	8569	138	7410	57	2	1%	23:59:52
13	nntp.comgw.net	7855	127	6799	116	0	1%	23:59:52
14	i2pn.org	8571	99	7100	49	8	1%	23:59:50
15	news.tnetconsulting.net	8917	82	7308	250	6	0%	23:58:49
16	news.corradoroberto.it	1255	82	1006	5	0	6%	23:37:38
17	news.weretis.net	7959	62	7429	150	0	0%	23:59:44
18	news.quux.org	8106	61	6939	625	2	0%	23:59:52
19	newsfeed.xs3.de	8732	35	7246	275	0	0%	23:59:49
20	news.chmurka.net	3795	24	3187	0	0	0%	23:59:29
21	news.samoylyk.net	8743	18	7496	36	7	0%	23:59:22
22	usenet.blueworldhosting.com	7006	0	6667	0	0	0%	23:59:52
23	feed.opticnetworks.net	0	0	0	0	7642	0%	23:59:51
TOTAL: 23		173253	9165	132849	4970	8011	5%	551:31:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	14.4 MB	0.0 KB	14.4 MB	0.2 KB/s	3.6 KB	23:59:51
2	nyheter.lysator.liu.se	4.3 MB	5.0 MB	9.2 MB	0.1 KB/s	4.8 KB	23:59:51
3	photonic.trudheim.com	3.8 MB	2.7 MB	6.4 MB	0.1 KB/s	4.6 KB	23:59:51
4	news.furie.org.uk	3.3 MB	80.4 KB	3.4 MB	0.0 KB/s	6.0 KB	23:59:51
5	endofthelinebbs.peers.news.panix.com	2.0 MB	8.3 KB	2.0 MB	0.0 KB/s	5.0 KB	23:59:52
6	newsfeed.bofh.team	1.5 MB	0.0 KB	1.5 MB	0.0 KB/s	6.5 KB	23:59:23
7	news.hispagatos.org	1.1 MB	2.0 MB	3.1 MB	0.0 KB/s	6.0 KB	23:59:47
8	usenet.network	932.5 KB	34.5 KB	967.0 KB	0.0 KB/s	4.4 KB	23:59:36
9	usenet.goja.nl.eu.org	705.4 KB	2.4 MB	3.1 MB	0.0 KB/s	5.3 KB	23:59:21
10	news.nk.ca	499.0 KB	4.3 MB	4.8 MB	0.1 KB/s	5.4 KB	23:59:52
11	news.bbs.nz	431.6 KB	4.4 MB	4.8 MB	0.1 KB/s	5.6 KB	23:59:51
12	news.corradoroberto.it	430.0 KB	14.5 KB	444.5 KB	0.0 KB/s	5.1 KB	23:37:38
13	news.nntp4.net	326.9 KB	849.4 KB	1.1 MB	0.0 KB/s	6.0 KB	23:59:52
14	news.tnetconsulting.net	318.3 KB	1.9 MB	2.2 MB	0.0 KB/s	6.7 KB	23:58:49
15	nntp.comgw.net	296.7 KB	1.2 MB	1.5 MB	0.0 KB/s	6.2 KB	23:59:52
16	i2pn.org	273.4 KB	318.1 KB	591.5 KB	0.0 KB/s	4.0 KB	23:59:50
17	news.quux.org	258.6 KB	3.8 MB	4.0 MB	0.0 KB/s	6.0 KB	23:59:52
18	news.weretis.net	249.2 KB	1.4 MB	1.6 MB	0.0 KB/s	7.8 KB	23:59:44
19	newsfeed.xs3.de	141.1 KB	1.5 MB	1.6 MB	0.0 KB/s	5.4 KB	23:59:49
20	news.chmurka.net	117.9 KB	0.0 KB	117.9 KB	0.0 KB/s	4.9 KB	23:59:29
21	news.samoylyk.net	67.5 KB	132.6 KB	200.1 KB	0.0 KB/s	3.7 KB	23:59:22
22	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
23	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 23		35.4 MB	31.8 MB	67.2 MB	0.0 KB/s	4.9 KB	551:31:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	47.186.47.228	1	0	0.0 KB	3	0	0	00:00:00
TOTAL: 1		1	0	0.0 KB	3	0	0	01:02:42

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	*.demos.su	2782	0	00:29:53
2	?	2747	0	00:32:09
3	unresolved	9	3	00:00:34
4	*.monitoring.internet-measurement.com	1	0	00:00:01
5	*.stretchoid.com	1	0	00:00:00
6	*.survey.inspici.com	1	0	00:00:03
TOTAL: 6		5541	3	01:02:42

NNRP auth users [Top 20]:

User	Conn
getlist	1
nelgin	1
TOTAL: 2	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ddt.demos.su	9.554	36.751	0.000	00:29:53
2001:470:28:a8:ca7f:54ff:fe03:41e0	9.673	36.293	0.000	00:20:51
localhost	187.240	371.542	0.074	00:11:17
198.235.24.88	0.000	0.016	0.000	00:00:10
205.210.31.194	0.004	0.012	0.000	00:00:10
104.152.52.127	0.003	0.013	0.000	00:00:10
167.94.145.96	0.022	0.039	0.000	00:00:03
server-0-3.survey.inspici.com	0.000	0.016	0.000	00:00:03
exquisite.monitoring.internet-measurement.com	0.004	0.012	0.000	00:00:01
147.45.112.158	0.004	0.012	0.000	00:00:00
azpdwgb81.stretchoid.com	0.000	0.016	0.000	00:00:00
104.152.52.124	0.004	0.012	0.000	00:00:00
TOTAL: 12	206.508	444.734	0.074	01:02:42

Curious NNRP server explorers [Top 20]:

System	Conn
ddt.demos.su	2782
2001:470:28:a8:ca7f:54ff:fe03:41e0	2746
167.94.145.96	3
104.152.52.124	1
104.152.52.127	1
147.45.112.158	1
198.235.24.88	1
205.210.31.194	1
azpdwgb81.stretchoid.com	1
exquisite.monitoring.internet-measurement.com	1
localhost	1
server-0-3.survey.inspici.com	1
TOTAL: 12	5540

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

System	Conn
147.45.112.158	1
167.94.145.96	1
azpdwgb81.stretchoid.com	1
TOTAL: 3	3

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

Command	Count
#003	1
EHLO www.censys.io	1
MGLNDD_144.172.126.95_119	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.158	1	1
167.94.145.96	1	2
TOTAL: 2	2	3

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	news	18	100.0%
TOTAL: 1		18	100.0%

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

Newsgroup		Count
1	news.admin.net-abuse.usenet	8
2	news.software.nntp	6
3	news.admin.peering	4
TOTAL: 3		18

Daily Usenet Report

Sep 30 00:01:01 -- Oct 1 00:01:02