Daily Usenet Report: Aug 26 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 27 00:00:01 newsfeed nnrpd[1913850]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	9241	30.0%	3.5 MB	54.7%
innd	8688	28.2%	1.2 MB	18.9%
nnrpd	8261	26.8%	863.2 KB	13.0%
innfeed	4044	13.1%	785.1 KB	11.8%
cnfsstat	432	1.4%	69.8 KB	1.1%
nocem	140	0.5%	33.9 KB	0.5%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	30807	100.0%	6.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	125025	60.1%
Negative hits	73869	35.5%
Do not exist	8666	4.2%
Cache misses	492	0.2%
TOTAL: 4	208052	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.790	0.0%	559	0.000	1.413	22.500
article cleanup	00:00:00.107	0.0%	8751	0.000	0.012	0.091
article logging	00:00:00.413	0.0%	8831	0.000	0.047	0.158
article parse	00:00:00.253	0.0%	12976	0.000	0.019	0.793
article write	00:00:02.127	0.0%	7164	0.000	0.297	2.417
artlog/artcncl	00:00:00.010	0.0%	413	0.000	0.024	0.250
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.850	0.0%	210302	0.000	0.004	0.132
hisgrep/artcncl	00:00:00.559	0.0%	561	0.000	0.996	22.143
hishave/artcncl	00:00:00.052	0.0%	521	0.000	0.100	1.750
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:39.052	0.2%	208952	0.075	0.761	6.332
history sync	00:00:00.081	0.0%	401	0.000	0.202	1.000
history write	00:02:10.665	0.2%	8736	3.000	14.957	138.123
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:38:12.017	98.4%	209036	88.097	407.069	1306.338
nntp read	00:00:03.213	0.0%	210699	0.003	0.015	0.448
overview write	00:16:28.916	1.1%	7164	0.467	138.040	14075.625
perl filter	00:00:29.082	0.0%	8735	1.549	3.329	17.453
python filter	00:00:01.891	0.0%	8735	0.000	0.216	7.395
site send	00:00:01.028	0.0%	14401	0.000	0.071	0.233
TOTAL: 24:01:04.688	24:00:11.106	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.019	0.0%	9135	0.000	0.002	0.059
article prepare	00:00:00.008	0.0%	44532	0.000	0.000	0.006
article read	00:00:00.611	0.0%	4151	0.000	0.147	0.582
backlog stats	00:00:00.334	0.0%	253401	0.000	0.001	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.283	0.0%	177734	0.002	0.013	0.039
data write	00:00:03.084	0.0%	173144	0.006	0.018	0.039
idle	23:58:56.538	100.0%	253401	155.184	340.711	1089.940
status file	00:00:00.286	0.0%	240	0.971	1.192	10.000
TOTAL: 23:59:12.808	23:59:03.163	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:32:55.804	62.3%	8185	0.000	241.393	300459.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.841	0.2%	95220	0.000	0.051	0.333
TOTAL: 00:52:51.442	00:33:00.645	62.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	1	6806	1798	4917	91	26%	23:59:57
2	usenet.goja.nl.eu.org	10	7977	1005	6729	243	12%	23:53:54
3	endofthelinebbs-out.news.weretis.net	3	14445	929	13345	171	6%	49:57:30
4	usenet.blueworldhosting.com	721	8576	686	7210	680	7%	23:55:49
5	nntp.comgw.net	2	8023	621	7196	206	7%	23:59:13
6	news.hispagatos.org	6	7911	485	7390	36	6%	24:26:14
7	news.corradoroberto.it	6	1496	386	1110	0	25%	23:23:55
8	usenet.network	1	6160	312	5846	2	5%	23:59:56
9	i2pn.org	2	7773	238	7481	54	3%	23:59:26
10	endoftheline-out.news.chmurka.net	3	3793	219	3563	11	5%	23:57:53
11	newsfeed.bofh.team	1	8517	196	8296	25	2%	23:59:56
12	endofthelinebbs.peers.news.panix.com	25	7752	102	7628	22	1%	23:47:30
13	news.samoylyk.net	6	6968	74	6894	0	1%	16:59:52
14	newsfeed.xs3.de	1	6260	30	6222	8	0%	23:59:55
15	news.nntp4.net	1	6768	18	6748	2	0%	23:59:56
16	news.netfront.net	1	5300	17	5245	38	0%	22:29:47
17	news.bbs.nz	2	6259	7	6251	1	0%	23:59:26
18	glockenspiel.complete.org	2	5637	7	5628	2	0%	24:01:34
19	news.furie.org.uk	2	4174	6	4166	2	0%	23:58:39
20	photonic.trudheim.com	3	1434	4	1430	0	0%	17:25:54
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	35	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:17
TOTAL: 23		837	132029	7140	123295	1594	5%	490:46:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	6.6 MB	5.9 KB	127.6 KB	6.7 MB	98%	3.6 KB
2	endofthelinebbs-out.news.weretis.net	4.0 MB	14.9 KB	333.1 KB	4.4 MB	92%	4.1 KB
3	usenet.network	3.3 MB	5.9 KB	1.1 KB	3.3 MB	99%	10.6 KB
4	usenet.goja.nl.eu.org	2.8 MB	5.8 KB	426.8 KB	3.2 MB	86%	2.7 KB
5	news.corradoroberto.it	2.1 MB	0.0 KB	0.0 KB	2.1 MB	100%	5.7 KB
6	usenet.blueworldhosting.com	2.0 MB	0.0 KB	1.6 MB	3.6 MB	56%	2.7 KB
7	nntp.comgw.net	1.8 MB	5.9 KB	735.4 KB	2.6 MB	71%	3.2 KB
8	news.hispagatos.org	1.5 MB	5.9 KB	98.7 KB	1.6 MB	93%	3.2 KB
9	i2pn.org	936.1 KB	5.9 KB	69.7 KB	1011.6 KB	92%	3.5 KB
10	endoftheline-out.news.chmurka.net	796.5 KB	0.0 KB	18.8 KB	815.3 KB	97%	3.5 KB
11	newsfeed.bofh.team	505.2 KB	5.8 KB	49.6 KB	560.7 KB	90%	2.5 KB
12	news.samoylyk.net	349.0 KB	0.0 KB	0.0 KB	349.0 KB	100%	4.7 KB
13	endofthelinebbs.peers.news.panix.com	293.8 KB	5.9 KB	27.7 KB	327.4 KB	89%	2.6 KB
14	newsfeed.xs3.de	174.6 KB	0.0 KB	24.1 KB	198.7 KB	87%	5.2 KB
15	news.nntp4.net	33.8 KB	5.9 KB	4.0 KB	43.6 KB	77%	2.2 KB
16	news.netfront.net	30.9 KB	5.9 KB	296.8 KB	333.6 KB	9%	6.1 KB
17	glockenspiel.complete.org	28.6 KB	5.9 KB	1.5 KB	35.9 KB	79%	4.0 KB
18	news.furie.org.uk	23.9 KB	5.9 KB	3.2 KB	33.1 KB	72%	4.1 KB
19	photonic.trudheim.com	17.2 KB	0.0 KB	0.0 KB	17.2 KB	100%	4.3 KB
20	news.bbs.nz	15.3 KB	5.9 KB	0.0 KB	21.2 KB	72%	2.6 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
TOTAL: 23		27.4 MB	91.3 KB	3.7 MB	31.2 MB	87%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 26 00:01:01 - 00:59:59	162	2.3%	0.05	702.2 KB	2.4%	0.20
Aug 26 01:00:00 - 01:59:59	221	3.1%	0.06	1.0 MB	3.6%	0.29
Aug 26 02:00:00 - 02:59:59	282	3.9%	0.08	841.2 KB	2.9%	0.23
Aug 26 03:00:00 - 03:59:59	301	4.2%	0.08	879.7 KB	3.0%	0.24
Aug 26 04:00:00 - 04:59:59	360	5.0%	0.10	1.1 MB	4.0%	0.32
Aug 26 05:00:00 - 05:59:59	349	4.9%	0.10	1.2 MB	4.2%	0.34
Aug 26 06:00:00 - 06:59:59	346	4.8%	0.10	1.4 MB	4.8%	0.38
Aug 26 07:00:00 - 07:59:59	361	5.0%	0.10	1.2 MB	4.2%	0.34
Aug 26 08:00:00 - 08:59:59	333	4.6%	0.09	1.2 MB	4.2%	0.33
Aug 26 09:00:00 - 09:59:59	434	6.1%	0.12	1.7 MB	6.0%	0.48
Aug 26 10:00:00 - 10:59:59	352	4.9%	0.10	1.8 MB	6.4%	0.51
Aug 26 11:00:00 - 11:59:59	482	6.7%	0.13	2.0 MB	7.1%	0.57
Aug 26 12:00:00 - 12:59:59	452	6.3%	0.13	1.9 MB	6.7%	0.54
Aug 26 13:00:00 - 13:59:59	333	4.6%	0.09	1.0 MB	3.6%	0.29
Aug 26 14:00:00 - 14:59:59	388	5.4%	0.11	1.5 MB	5.4%	0.44
Aug 26 15:00:00 - 15:59:59	396	5.5%	0.11	1.5 MB	5.3%	0.43
Aug 26 16:00:00 - 16:59:59	356	5.0%	0.10	1.3 MB	4.5%	0.36
Aug 26 17:00:00 - 17:59:59	230	3.2%	0.06	1.2 MB	4.1%	0.33
Aug 26 18:00:00 - 18:59:59	200	2.8%	0.06	777.8 KB	2.7%	0.22
Aug 26 19:00:00 - 19:59:59	164	2.3%	0.05	565.9 KB	2.0%	0.16
Aug 26 20:00:00 - 20:59:59	177	2.5%	0.05	640.1 KB	2.2%	0.18
Aug 26 21:00:00 - 21:59:59	147	2.1%	0.04	900.1 KB	3.1%	0.25
Aug 26 22:00:00 - 22:59:59	144	2.0%	0.04	665.1 KB	2.3%	0.18
Aug 26 23:00:00 - 23:59:59	181	2.5%	0.05	1.4 MB	5.0%	0.40
Aug 27 00:00:00 - 00:01:01	16	0.2%	0.26	36.5 KB	0.1%	0.60
TOTAL: 24:00:00	7167	100.0%	0.08	28.2 MB	100.0%	0.33

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	96.7 GB	6.3%	0	0.49	37701.85
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.5%	0	0.03	2087.92
TOTAL: 3	-	3077.0 GB	100.6 GB	3.3%	1	0.35	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	705	1	0	704
2	usenet.goja.nl.eu.org	268	0	1	267
3	nntp.comgw.net	212	0	1	211
4	endofthelinebbs-out.news.weretis.net	180	0	3	177
5	outgoing.news.lysator.liu.se	94	0	1	93
6	i2pn.org	52	0	1	51
7	news.hispagatos.org	39	1	1	37
8	news.netfront.net	37	0	1	36
9	newsfeed.bofh.team	23	0	1	22
10	endofthelinebbs.peers.news.panix.com	22	0	1	21
11	endoftheline-out.news.chmurka.net	10	0	0	10
12	newsfeed.xs3.de	9	0	0	9
13	glockenspiel.complete.org	2	0	1	1
14	news.furie.org.uk	2	0	1	1
15	news.nntp4.net	2	0	1	1
16	usenet.network	2	0	1	1
17	news.bbs.nz	1	0	1	0
18	news.samoylyk.net	1	0	0	1
TOTAL: 18		1661	2	16	1643

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.squidpickles	1
hispagatos.hacking.exploits	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	909
EMP (phr path)	343
EMP (md5)	172
EMP (phn nph)	62
Too many newsgroups	60
User-issued cancel	7
EMP (fsl)	6
HTML Multipart	3
TOTAL: 8	1562

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	66	519	0	519
nocem@usenet.ovh (faqfr)	2	0	54	54
robot@pasdenom.info (spam3)	4	0	6	6
robot@pasdenom.info (spam2)	1	0	1	1
TOTAL: 4	73	519	61	580

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	25
	usenet.goja.nl.eu.org	24
	endofthelinebbs-out.news.weretis.net	19
	nntp.comgw.net	6
	outgoing.news.lysator.liu.se	3
	news.hispagatos.org	1
	news.samoylyk.net	1
	endofthelinebbs.peers.news.panix.com	1
	TOTAL: 8	80
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		81

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10869	2861	4289	0	4	26%	23:59:52
2	nyheter.lysator.liu.se	5576	1228	3751	393	0	22%	23:59:41
3	photonic.trudheim.com	7693	551	5857	750	41	7%	23:59:53
4	news.furie.org.uk	4517	526	3256	6	0	11%	23:59:06
5	endofthelinebbs.peers.news.panix.com	7428	400	6761	0	0	5%	23:59:54
6	usenet.network	7251	344	5971	27	0	4%	23:59:54
7	news.hispagatos.org	7703	274	6057	329	2	3%	23:59:54
8	news.nk.ca	10748	181	6530	386	23	1%	23:59:54
9	newsfeed.bofh.team	7753	179	6694	0	0	2%	23:58:11
10	news.bbs.nz	12085	165	6664	333	50	1%	23:59:41
11	usenet.goja.nl.eu.org	7297	160	5842	137	0	2%	23:59:54
12	news.corradoroberto.it	1324	145	925	0	0	10%	23:37:42
13	news.tnetconsulting.net	7925	135	6853	172	11	1%	23:59:54
14	news.weretis.net	7464	121	6927	118	0	1%	23:59:54
15	news.quux.org	7585	113	6721	327	0	1%	23:59:54
16	news.nntp4.net	8025	85	6989	18	5	1%	23:59:54
17	nntp.comgw.net	7484	85	6368	93	0	1%	23:59:54
18	i2pn.org	7873	78	6795	42	31	0%	23:59:54
19	newsfeed.xs3.de	8406	59	6942	116	0	0%	23:58:49
20	news.chmurka.net	4142	26	3389	1	0	0%	23:59:54
21	news.samoylyk.net	8781	16	7315	152	1387	0%	23:59:54
22	usenet.blueworldhosting.com	6710	7	6251	0	0	0%	23:59:54
23	feed.opticnetworks.net	0	0	0	0	7167	0%	23:59:54
TOTAL: 23		164639	7739	127147	3400	8721	4%	551:31:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	12.5 MB	0.0 KB	12.5 MB	0.1 KB/s	4.5 KB	23:59:52
2	nyheter.lysator.liu.se	6.3 MB	3.2 MB	9.5 MB	0.1 KB/s	6.0 KB	23:59:41
3	news.furie.org.uk	4.0 MB	78.6 KB	4.0 MB	0.0 KB/s	7.8 KB	23:59:06
4	photonic.trudheim.com	3.5 MB	3.4 MB	6.9 MB	0.1 KB/s	5.5 KB	23:59:53
5	endofthelinebbs.peers.news.panix.com	1.9 MB	0.0 KB	1.9 MB	0.0 KB/s	5.0 KB	23:59:54
6	news.quux.org	1.9 MB	2.2 MB	4.1 MB	0.0 KB/s	9.6 KB	23:59:54
7	news.hispagatos.org	1.8 MB	2.1 MB	3.9 MB	0.0 KB/s	6.7 KB	23:59:54
8	newsfeed.bofh.team	1.7 MB	0.0 KB	1.7 MB	0.0 KB/s	9.4 KB	23:58:11
9	usenet.network	1.4 MB	97.9 KB	1.5 MB	0.0 KB/s	4.1 KB	23:59:54
10	news.nntp4.net	947.0 KB	314.4 KB	1.2 MB	0.0 KB/s	12.2 KB	23:59:54
11	news.tnetconsulting.net	585.0 KB	1.3 MB	1.8 MB	0.0 KB/s	6.1 KB	23:59:54
12	news.bbs.nz	579.5 KB	3.4 MB	4.0 MB	0.0 KB/s	8.2 KB	23:59:41
13	news.weretis.net	575.7 KB	2.3 MB	2.9 MB	0.0 KB/s	12.3 KB	23:59:54
14	nntp.comgw.net	510.7 KB	2.0 MB	2.5 MB	0.0 KB/s	14.1 KB	23:59:54
15	news.nk.ca	505.4 KB	3.7 MB	4.2 MB	0.0 KB/s	7.5 KB	23:59:54
16	news.corradoroberto.it	454.4 KB	0.0 KB	454.4 KB	0.0 KB/s	3.1 KB	23:37:42
17	usenet.goja.nl.eu.org	439.3 KB	1.3 MB	1.7 MB	0.0 KB/s	5.9 KB	23:59:54
18	newsfeed.xs3.de	424.9 KB	1.3 MB	1.7 MB	0.0 KB/s	9.9 KB	23:58:49
19	i2pn.org	279.3 KB	137.5 KB	416.8 KB	0.0 KB/s	3.5 KB	23:59:54
20	news.samoylyk.net	133.9 KB	1.8 MB	1.9 MB	0.0 KB/s	11.8 KB	23:59:54
21	news.chmurka.net	109.5 KB	1.7 KB	111.1 KB	0.0 KB/s	4.1 KB	23:59:54
22	usenet.blueworldhosting.com	15.7 KB	0.0 KB	15.7 KB	0.0 KB/s	2.2 KB	23:59:54
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 23		40.4 MB	28.6 MB	69.0 MB	0.0 KB/s	6.3 KB	551:31:25

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2727	00:30:15
2	167.94.146.57	3	00:00:03
3	199.45.154.152	3	00:00:06
4	147.185.132.39	1	00:00:10
5	198.235.24.180	1	00:00:00
6	198.235.24.196	1	00:00:10
7	2001:470:28:a8:3ad5:47ff:fe02:c2fd	1	00:10:02
8	91.238.181.24	1	00:00:00
9	azpdwgc38.stretchoid.com	1	00:00:00
10	imaginative.monitoring.internet-measurement.com	1	00:00:01
11	localhost	1	00:12:00
TOTAL: 11		2741	00:52:50

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2727	00:30:15
2	unresolved	10	00:00:30
3	?	2	00:22:02
4	*.monitoring.internet-measurement.com	1	00:00:01
5	*.stretchoid.com	1	00:00:00
TOTAL: 5		2741	00:52:50

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.628	36.510	0.000	00:30:15
localhost	178.490	362.671	0.071	00:12:00
2001:470:28:a8:3ad5:47ff:fe02:c2fd	0.005	0.011	0.000	00:10:02
147.185.132.39	0.005	0.011	0.000	00:00:10
198.235.24.196	0.008	0.008	0.000	00:00:10
199.45.154.152	0.020	0.041	0.000	00:00:06
167.94.146.57	0.019	0.045	0.000	00:00:03
imaginative.monitoring.internet-measurement.com	0.000	0.016	0.000	00:00:01
198.235.24.180	0.000	0.016	0.000	00:00:00
91.238.181.24	0.004	0.012	0.000	00:00:00
azpdwgc38.stretchoid.com	0.005	0.014	0.000	00:00:00
TOTAL: 11	188.184	399.355	0.071	00:52:50

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

System	Conn
167.94.146.57	1
199.45.154.152	1
91.238.181.24	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.146.57	1	2
199.45.154.152	1	2
91.238.181.24	1	1
TOTAL: 3	3	6

Daily Usenet Report

Aug 26 00:01:01 -- Aug 27 00:01:01