Daily Usenet Report: Sep 11 00:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 12 00:00:01 newsfeed nnrpd[1791513]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	8655	29.1%	3.5 MB	55.2%
nnrpd	8321	28.0%	873.3 KB	13.4%
innd	8161	27.5%	1.2 MB	18.3%
innfeed	4112	13.8%	796.7 KB	12.2%
cnfsstat	432	1.5%	63.0 KB	1.0%
nocem	27	0.1%	3.8 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	29709	100.0%	6.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	118794	57.8%
Negative hits	72570	35.3%
Do not exist	8455	4.1%
Cache misses	5814	2.8%
TOTAL: 4	205633	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.163	0.0%	49	0.000	3.327	8.000
article cleanup	00:00:00.094	0.0%	8514	0.000	0.011	0.065
article logging	00:00:00.446	0.0%	8607	0.000	0.052	0.644
article parse	00:00:00.226	0.0%	12477	0.000	0.018	0.071
article write	00:00:02.440	0.0%	7249	0.000	0.337	4.292
artlog/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:01.240	0.0%	199778	0.000	0.006	0.122
hisgrep/artcncl	00:00:00.130	0.0%	59	0.000	2.203	4.727
hishave/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hishave/artparse	00:00:00.000	0.0%	2	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:03.749	0.2%	206531	0.085	0.890	5.639
history sync	00:00:00.061	0.0%	403	0.000	0.151	0.667
history write	00:02:22.603	0.2%	8480	3.259	16.816	164.142
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.009	0.0%	2	3.000	4.500	6.000
idle	23:37:58.172	98.9%	197436	105.509	430.915	2298.908
nntp read	00:00:04.045	0.0%	200304	0.000	0.020	0.447
overview write	00:09:14.657	0.6%	7249	0.538	76.515	11212.870
perl filter	00:00:29.866	0.0%	8480	1.471	3.522	26.242
python filter	00:00:02.216	0.0%	8480	0.000	0.261	6.070
site send	00:00:01.136	0.0%	14524	0.000	0.078	0.242
TOTAL: 23:54:14.175	23:53:21.253	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	14252	0.000	0.002	0.056
article prepare	00:00:00.005	0.0%	40448	0.000	0.000	0.005
article read	00:00:00.372	0.0%	2891	0.000	0.129	0.600
backlog stats	00:00:00.316	0.0%	252796	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.307	0.0%	178756	0.000	0.013	0.055
data write	00:00:03.027	0.0%	175112	0.007	0.017	0.032
idle	23:58:01.463	100.0%	252796	143.757	341.309	1593.972
status file	00:00:00.319	0.0%	273	0.714	1.168	12.000
TOTAL: 23:58:18.525	23:58:07.841	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:39:08.769	67.6%	8260	0.500	284.355	423515.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.979	0.1%	95324	0.000	0.042	0.333
TOTAL: 00:57:53.361	00:39:12.748	67.7%	-	-	-	-

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	6435	1699	4687	49	26%	23:59:58
2	endofthelinebbs-out.news.weretis.net	3	13086	1369	11419	298	10%	43:23:45
3	usenet.blueworldhosting.com	719	8155	943	6766	446	11%	23:48:23
4	nntp.comgw.net	2	7495	900	6514	81	12%	23:59:12
5	usenet.network	2	5884	438	5421	25	7%	23:59:50
6	news.hispagatos.org	10	7429	431	6962	36	5%	24:50:18
7	news.corradoroberto.it	5	1386	413	969	4	29%	24:45:28
8	endoftheline-out.news.chmurka.net	1	3641	262	3377	2	7%	23:59:57
9	endofthelinebbs.peers.news.panix.com	26	7725	206	7486	33	2%	24:05:05
10	usenet.goja.nl.eu.org	10	7446	125	7308	13	1%	23:56:33
11	i2pn.org	2	7484	118	7347	19	1%	23:59:51
12	newsfeed.bofh.team	2	8189	95	8092	2	1%	23:59:20
13	news.samoylyk.net	1	6637	72	6563	2	1%	23:59:48
14	newsfeed.xs3.de	1	5933	57	5873	3	0%	23:59:55
15	news.nntp4.net	2	6424	46	6377	1	0%	23:59:50
16	news.bbs.nz	2	7697	35	7599	63	0%	05:45:51
17	news.netfront.net	1	6325	15	6157	153	0%	23:46:51
18	glockenspiel.complete.org	3	5677	14	5662	1	0%	24:02:34
19	news.furie.org.uk	2	3824	8	3811	5	0%	23:58:53
20	photonic.trudheim.com	1	1715	4	1708	3	0%	19:23:14
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	26	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:13
TOTAL: 23		825	128587	7250	120098	1239	5%	478:14:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	4.7 MB	3.6 KB	62.0 KB	4.7 MB	98%	2.8 KB
2	endofthelinebbs-out.news.weretis.net	4.1 MB	3.2 KB	577.5 KB	4.7 MB	87%	2.9 KB
3	usenet.network	3.8 MB	3.9 KB	2.7 MB	6.5 MB	58%	14.3 KB
4	nntp.comgw.net	2.6 MB	19.3 KB	105.0 KB	2.7 MB	95%	2.9 KB
5	usenet.blueworldhosting.com	2.3 MB	0.0 KB	982.3 KB	3.2 MB	70%	2.4 KB
6	news.corradoroberto.it	2.2 MB	24.5 KB	0.0 KB	2.2 MB	98%	5.5 KB
7	news.hispagatos.org	1.2 MB	2.0 KB	56.7 KB	1.2 MB	95%	2.7 KB
8	endoftheline-out.news.chmurka.net	1.0 MB	2.3 KB	0.8 KB	1.0 MB	99%	4.0 KB
9	endofthelinebbs.peers.news.panix.com	644.0 KB	4.5 KB	102.5 KB	751.0 KB	85%	3.1 KB
10	i2pn.org	509.1 KB	0.0 KB	42.2 KB	551.3 KB	92%	4.0 KB
11	usenet.goja.nl.eu.org	356.8 KB	3.9 KB	8.1 KB	368.8 KB	96%	2.7 KB
12	news.samoylyk.net	294.0 KB	0.0 KB	1.5 KB	295.5 KB	99%	4.0 KB
13	newsfeed.bofh.team	266.7 KB	0.0 KB	8.1 KB	274.8 KB	97%	2.8 KB
14	newsfeed.xs3.de	175.9 KB	1.4 KB	2.4 KB	179.7 KB	97%	3.0 KB
15	news.nntp4.net	110.7 KB	1.6 KB	0.0 KB	112.4 KB	98%	2.4 KB
16	news.bbs.nz	63.1 KB	0.0 KB	70.6 KB	133.7 KB	47%	1.4 KB
17	glockenspiel.complete.org	45.9 KB	0.0 KB	0.7 KB	46.6 KB	98%	3.1 KB
18	news.netfront.net	41.1 KB	16.3 KB	465.4 KB	522.9 KB	7%	3.1 KB
19	news.furie.org.uk	36.5 KB	4.0 KB	2.5 KB	43.0 KB	84%	3.3 KB
20	photonic.trudheim.com	17.4 KB	22.3 KB	0.0 KB	39.7 KB	43%	5.7 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		24.4 MB	113.1 KB	5.1 MB	29.6 MB	82%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 11 00:01:02 - 00:59:59	173	2.4%	0.05	509.7 KB	2.0%	0.14
Sep 11 01:00:00 - 01:59:59	271	3.7%	0.08	772.1 KB	3.1%	0.21
Sep 11 02:00:00 - 02:59:59	323	4.4%	0.09	866.3 KB	3.4%	0.24
Sep 11 03:00:00 - 03:59:59	471	6.5%	0.13	1.6 MB	6.6%	0.46
Sep 11 04:00:00 - 04:59:59	421	5.8%	0.12	1.2 MB	4.9%	0.34
Sep 11 05:00:00 - 05:59:59	325	4.5%	0.09	950.7 KB	3.8%	0.26
Sep 11 06:00:00 - 06:59:59	322	4.4%	0.09	1001.6 KB	4.0%	0.28
Sep 11 07:00:00 - 07:59:59	342	4.7%	0.10	987.7 KB	3.9%	0.27
Sep 11 08:00:00 - 08:59:59	439	6.0%	0.12	1.7 MB	6.8%	0.47
Sep 11 09:00:00 - 09:59:59	388	5.3%	0.11	1.3 MB	5.3%	0.37
Sep 11 10:00:00 - 10:59:59	477	6.6%	0.13	1.6 MB	6.6%	0.46
Sep 11 11:00:00 - 11:59:59	428	5.9%	0.12	1.6 MB	6.7%	0.47
Sep 11 12:00:00 - 12:59:59	422	5.8%	0.12	1.5 MB	5.9%	0.41
Sep 11 13:00:00 - 13:59:59	395	5.4%	0.11	1.3 MB	5.2%	0.37
Sep 11 14:00:00 - 14:59:59	285	3.9%	0.08	827.6 KB	3.3%	0.23
Sep 11 15:00:00 - 15:59:59	354	4.9%	0.10	1.2 MB	5.0%	0.35
Sep 11 16:00:00 - 16:59:59	345	4.7%	0.10	1.2 MB	4.7%	0.33
Sep 11 17:00:00 - 17:59:59	272	3.7%	0.08	1.3 MB	5.1%	0.36
Sep 11 18:00:00 - 18:59:59	187	2.6%	0.05	809.3 KB	3.2%	0.22
Sep 11 19:00:00 - 19:59:59	138	1.9%	0.04	593.4 KB	2.4%	0.16
Sep 11 20:00:00 - 20:59:59	115	1.6%	0.03	475.6 KB	1.9%	0.13
Sep 11 21:00:00 - 21:59:59	131	1.8%	0.04	565.9 KB	2.2%	0.16
Sep 11 22:00:00 - 22:59:59	90	1.2%	0.03	419.7 KB	1.7%	0.12
Sep 11 23:00:00 - 23:59:59	153	2.1%	0.04	462.1 KB	1.8%	0.13
Sep 12 00:00:00 - 00:01:01	14	0.2%	0.23	141.5 KB	0.6%	2.32
TOTAL: 23:59:59	7281	100.0%	0.08	24.6 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	97.3 GB	6.3%	0	0.46	40751.59
CYCTXT1	TEXT	5.0 GB	3.9 GB	77.1%	0	0.03	2270.87
TOTAL: 3	-	3077.0 GB	101.2 GB	3.3%	1	0.31	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	484	2	0	482
2	endofthelinebbs-out.news.weretis.net	357	0	2	355
3	news.netfront.net	157	0	7	150
4	nntp.comgw.net	85	0	2	83
5	news.bbs.nz	66	0	0	66
6	outgoing.news.lysator.liu.se	52	0	2	50
7	news.hispagatos.org	40	18	1	21
8	endofthelinebbs.peers.news.panix.com	36	0	3	33
9	usenet.network	25	0	2	23
10	i2pn.org	19	0	0	19
11	usenet.goja.nl.eu.org	14	0	2	12
12	news.corradoroberto.it	5	0	4	1
13	news.furie.org.uk	5	0	2	3
14	newsfeed.xs3.de	5	0	1	4
15	news.samoylyk.net	3	0	0	3
16	newsfeed.bofh.team	3	0	0	3
17	photonic.trudheim.com	3	0	3	0
18	endoftheline-out.news.chmurka.net	2	0	1	1
19	glockenspiel.complete.org	2	0	1	1
20	news.nntp4.net	1	0	1	0
TOTAL: 20		1364	20	34	1310

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	10
hispagatos.hacking.news	7
comp.os.linux.help	1
comp.os.linux.suse	1
hispagatos.hacking.exploits	1
TOTAL: 5	20

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	479
EMP (phr path)	435
Too many newsgroups	180
EMP (md5)	85
User-issued cancel	25
HTML Multipart	7
Too many newsgroups (meow)	2
Subject (LSDRhNCw0YE)	1
Subject (lSdk-v)	1
TOTAL: 9	1215

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
i2pn2-nocem@i2pn2.org (rmp)	12	53	53
i2pn2-nocem@i2pn2.org (multipost)	12	26	26
robot@pasdenom.info (spam3)	2	3	3
TOTAL: 3	26	82	82

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	usenet.network	2
	TOTAL: 1	2
Including strange strings
	usenet.blueworldhosting.com	38
	endofthelinebbs-out.news.weretis.net	38
	nntp.comgw.net	4
	outgoing.news.lysator.liu.se	3
	news.bbs.nz	3
	endofthelinebbs.peers.news.panix.com	3
	newsfeed.xs3.de	2
	news.corradoroberto.it	1
	usenet.goja.nl.eu.org	1
	TOTAL: 9	93
TOTAL: 2		95

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	11749	1856	5412	0	20	15%	23:59:52
2	nyheter.lysator.liu.se	5777	1152	3953	476	0	19%	23:59:34
3	photonic.trudheim.com	7625	513	5884	869	0	6%	23:59:42
4	news.furie.org.uk	4193	469	3027	10	2	11%	23:59:14
5	endofthelinebbs.peers.news.panix.com	7456	307	6968	6	0	4%	23:59:44
6	usenet.network	7445	291	6120	23	0	3%	23:59:52
7	news.bbs.nz	19023	266	12847	402	548	1%	23:59:52
8	news.hispagatos.org	7913	266	6380	182	11	3%	23:59:52
9	news.nk.ca	9558	231	6456	542	26	2%	23:59:52
10	usenet.goja.nl.eu.org	8093	226	6742	159	0	2%	23:59:02
11	newsfeed.bofh.team	8141	180	6877	0	0	2%	23:58:21
12	news.nntp4.net	8116	102	7073	14	0	1%	23:59:52
13	i2pn.org	8313	94	6911	33	78	1%	23:59:52
14	news.tnetconsulting.net	7965	79	6992	210	1	0%	23:59:51
15	nntp.comgw.net	7283	72	6191	102	0	0%	23:59:40
16	news.weretis.net	7475	58	7114	108	0	0%	23:59:11
17	news.quux.org	7622	46	6723	497	0	0%	23:59:52
18	newsfeed.xs3.de	8342	32	7051	133	0	0%	23:58:48
19	news.chmurka.net	3556	28	3102	2	0	0%	23:59:43
20	news.samoylyk.net	8407	25	7123	38	0	0%	23:59:52
21	news.corradoroberto.it	928	25	804	12	0	2%	23:29:00
22	usenet.blueworldhosting.com	6262	0	6037	0	6	0%	23:59:52
23	feed.opticnetworks.net	0	0	0	0	7281	0%	23:59:52
TOTAL: 23		171242	6318	135787	3818	7973	3%	551:20:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	8.1 MB	0.0 KB	8.1 MB	0.1 KB/s	4.5 KB	23:59:52
2	nyheter.lysator.liu.se	3.5 MB	4.0 MB	7.6 MB	0.1 KB/s	4.8 KB	23:59:34
3	photonic.trudheim.com	3.4 MB	2.2 MB	5.7 MB	0.1 KB/s	4.2 KB	23:59:42
4	news.furie.org.uk	3.2 MB	43.2 KB	3.2 MB	0.0 KB/s	6.8 KB	23:59:14
5	endofthelinebbs.peers.news.panix.com	1.8 MB	29.5 KB	1.9 MB	0.0 KB/s	6.1 KB	23:59:44
6	newsfeed.bofh.team	1.3 MB	0.0 KB	1.3 MB	0.0 KB/s	7.3 KB	23:58:21
7	news.hispagatos.org	1.0 MB	1.5 MB	2.6 MB	0.0 KB/s	5.9 KB	23:59:52
8	usenet.network	1016.4 KB	50.3 KB	1.0 MB	0.0 KB/s	3.4 KB	23:59:52
9	news.bbs.nz	770.2 KB	2.1 MB	2.9 MB	0.0 KB/s	4.4 KB	23:59:52
10	news.nk.ca	450.5 KB	3.2 MB	3.7 MB	0.0 KB/s	4.9 KB	23:59:52
11	usenet.goja.nl.eu.org	431.4 KB	1.6 MB	2.0 MB	0.0 KB/s	5.4 KB	23:59:02
12	news.tnetconsulting.net	359.1 KB	1.5 MB	1.8 MB	0.0 KB/s	6.5 KB	23:59:51
13	news.nntp4.net	265.3 KB	150.5 KB	415.7 KB	0.0 KB/s	3.6 KB	23:59:52
14	i2pn.org	261.3 KB	197.6 KB	458.9 KB	0.0 KB/s	3.6 KB	23:59:52
15	news.weretis.net	195.5 KB	1.1 MB	1.3 MB	0.0 KB/s	8.2 KB	23:59:11
16	news.quux.org	194.0 KB	2.9 MB	3.1 MB	0.0 KB/s	5.8 KB	23:59:52
17	nntp.comgw.net	191.8 KB	1.1 MB	1.2 MB	0.0 KB/s	7.3 KB	23:59:40
18	newsfeed.xs3.de	94.5 KB	1.1 MB	1.2 MB	0.0 KB/s	7.5 KB	23:58:48
19	news.corradoroberto.it	83.2 KB	27.3 KB	110.5 KB	0.0 KB/s	3.0 KB	23:29:00
20	news.samoylyk.net	75.4 KB	140.3 KB	215.7 KB	0.0 KB/s	3.4 KB	23:59:52
21	news.chmurka.net	69.5 KB	4.6 KB	74.0 KB	0.0 KB/s	2.5 KB	23:59:43
22	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
23	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 23		26.7 MB	23.1 MB	49.8 MB	0.0 KB/s	5.0 KB	551:20:22

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2752	00:32:14
2	167.94.145.103	3	00:00:05
3	69.10.48.174	2	00:14:07
4	198.235.24.3	1	00:00:09
5	205.210.31.176	1	00:00:10
6	80.66.76.134	1	00:00:00
7	91.238.181.32	1	00:00:00
8	azpdeg54.stretchoid.com	1	00:00:00
9	localhost	1	00:11:04
TOTAL: 9		2763	00:57:51

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2752	00:32:14
2	unresolved	9	00:14:32
3	*.stretchoid.com	1	00:00:00
4	?	1	00:11:04
TOTAL: 4		2763	00:57:51

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.652	36.466	0.000	00:32:14
69.10.48.174	0.016	0.020	0.000	00:14:07
localhost	180.654	369.834	0.069	00:11:04
205.210.31.176	0.008	0.008	0.000	00:00:10
198.235.24.3	0.005	0.011	0.000	00:00:09
167.94.145.103	0.024	0.037	0.000	00:00:05
80.66.76.134	0.007	0.011	0.000	00:00:00
91.238.181.32	0.004	0.012	0.000	00:00:00
azpdeg54.stretchoid.com	0.000	0.016	0.000	00:00:00
TOTAL: 9	190.370	406.415	0.069	00:57:51

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

System	Conn
167.94.145.103	1
80.66.76.134	1
91.238.181.32	1
azpdeg54.stretchoid.com	1
TOTAL: 4	4

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.145.103	1	2
80.66.76.134	1	1
91.238.181.32	1	1
TOTAL: 3	3	5

Daily Usenet Report

Sep 11 00:01:02 -- Sep 12 00:01:01