Daily Usenet Report: Aug 29 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 30 00:00:01 newsfeed nnrpd[3689001]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	8623	29.0%	3.5 MB	54.7%
nnrpd	8348	28.1%	873.5 KB	13.5%
innd	8147	27.4%	1.2 MB	18.3%
innfeed	4045	13.6%	794.8 KB	12.3%
cnfsstat	432	1.5%	62.9 KB	1.0%
nocem	83	0.3%	15.7 KB	0.2%
controlchan	22	0.1%	5.2 KB	0.1%
TOTAL: 7	29700	100.0%	6.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	111936	60.8%
Negative hits	63889	34.7%
Do not exist	8171	4.4%
Cache misses	16	0.0%
TOTAL: 4	184012	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.316	0.0%	233	0.000	1.356	9.000
article cleanup	00:00:00.101	0.0%	8286	0.000	0.012	0.143
article logging	00:00:00.423	0.0%	8430	0.000	0.050	0.176
article parse	00:00:00.194	0.0%	11548	0.000	0.017	0.067
article write	00:00:02.339	0.0%	7179	0.000	0.326	5.571
artlog/artcncl	00:00:00.004	0.0%	199	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:01.019	0.0%	189485	0.000	0.005	0.532
hisgrep/artcncl	00:00:00.156	0.0%	235	0.000	0.664	7.000
hishave/artcncl	00:00:00.001	0.0%	199	0.000	0.005	0.067
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:40.971	0.2%	186048	0.083	0.865	7.373
history sync	00:00:00.068	0.0%	402	0.000	0.169	1.000
history write	00:01:51.452	0.1%	8285	3.235	13.452	105.068
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:52:32.308	99.4%	188713	69.093	455.466	1515.500
nntp read	00:00:04.812	0.0%	189872	0.004	0.025	3.004
overview write	00:02:41.853	0.2%	7179	0.485	22.545	1218.264
perl filter	00:00:25.030	0.0%	8285	1.432	3.021	14.476
python filter	00:00:00.962	0.0%	8285	0.000	0.116	1.054
site send	00:00:01.077	0.0%	14409	0.000	0.075	0.306
TOTAL: 24:01:14.005	24:00:23.086	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	7366	0.000	0.003	0.048
article prepare	00:00:00.006	0.0%	56092	0.000	0.000	0.005
article read	00:00:00.598	0.0%	4605	0.000	0.130	0.556
backlog stats	00:00:00.407	0.0%	264680	0.000	0.002	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.355	0.0%	182175	0.000	0.013	0.040
data write	00:00:03.242	0.0%	177771	0.003	0.018	0.041
idle	23:57:17.534	100.0%	264680	136.297	325.818	1205.323
status file	00:00:00.274	0.0%	230	1.000	1.191	11.000
TOTAL: 23:57:34.896	23:57:24.440	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:22:46.117	54.5%	8285	0.250	164.890	10051.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.691	0.1%	95329	0.000	0.039	1.000
TOTAL: 00:41:48.500	00:22:49.808	54.6%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
news@hispagatos.org	1	0	2	0	0	3
TOTAL	1	0	2	0	0	3

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	1	6116	1164	4940	12	19%	23:59:57
2	news.hispagatos.org	10	7139	1045	6050	44	14%	24:52:34
3	usenet.blueworldhosting.com	718	8041	902	6632	507	11%	23:54:41
4	endofthelinebbs-out.news.weretis.net	3	13534	751	12658	125	5%	50:12:25
5	usenet.goja.nl.eu.org	11	7572	685	6777	110	9%	25:59:35
6	nntp.comgw.net	1	7341	564	6648	129	7%	23:59:56
7	usenet.network	2	5531	517	5013	1	9%	23:59:54
8	newsfeed.xs3.de	1	5702	448	5246	8	7%	23:59:56
9	news.corradoroberto.it	5	1440	372	1066	2	25%	24:06:56
10	i2pn.org	2	7020	215	6776	29	3%	23:59:22
11	endoftheline-out.news.chmurka.net	2	3479	184	3282	13	5%	23:59:39
12	endofthelinebbs.peers.news.panix.com	25	7573	128	7424	21	1%	23:54:40
13	newsfeed.bofh.team	1	7955	127	7816	12	1%	23:59:55
14	news.samoylyk.net	1	6370	64	6306	0	1%	23:59:46
15	news.nntp4.net	1	6115	17	6098	0	0%	23:59:55
16	news.bbs.nz	1	6013	14	5999	0	0%	23:59:30
17	glockenspiel.complete.org	2	5294	6	5287	1	0%	23:57:35
18	photonic.trudheim.com	2	1967	6	1958	3	0%	29:44:53
19	news.netfront.net	1	4287	2	4199	86	0%	22:29:46
20	news.furie.org.uk	1	3812	1	3811	0	0%	23:59:55
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	30	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:18
TOTAL: 23		824	122301	7212	113986	1103	5%	513:41:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.network	4.5 MB	0.0 KB	0.6 KB	4.5 MB	99%	8.9 KB
2	outgoing.news.lysator.liu.se	3.8 MB	0.0 KB	15.3 KB	3.9 MB	99%	3.4 KB
3	news.hispagatos.org	3.0 MB	0.0 KB	78.3 KB	3.1 MB	97%	2.9 KB
4	endofthelinebbs-out.news.weretis.net	2.5 MB	4.6 KB	195.9 KB	2.7 MB	92%	3.1 KB
5	usenet.blueworldhosting.com	2.1 MB	0.0 KB	1.1 MB	3.2 MB	66%	2.4 KB
6	usenet.goja.nl.eu.org	2.0 MB	0.0 KB	180.7 KB	2.1 MB	91%	2.8 KB
7	news.corradoroberto.it	1.7 MB	0.0 KB	3.4 KB	1.7 MB	99%	4.7 KB
8	nntp.comgw.net	1.7 MB	0.0 KB	280.6 KB	1.9 MB	85%	2.9 KB
9	newsfeed.xs3.de	1.5 MB	0.0 KB	14.3 KB	1.5 MB	99%	3.3 KB
10	i2pn.org	792.1 KB	0.0 KB	35.4 KB	827.5 KB	95%	3.4 KB
11	endoftheline-out.news.chmurka.net	690.3 KB	0.0 KB	9.9 KB	700.2 KB	98%	3.6 KB
12	endofthelinebbs.peers.news.panix.com	363.6 KB	0.0 KB	34.0 KB	397.5 KB	91%	2.7 KB
13	newsfeed.bofh.team	335.3 KB	0.0 KB	24.0 KB	359.3 KB	93%	2.6 KB
14	news.samoylyk.net	292.8 KB	0.0 KB	0.0 KB	292.8 KB	100%	4.6 KB
15	news.nntp4.net	41.5 KB	0.0 KB	0.0 KB	41.5 KB	100%	2.4 KB
16	photonic.trudheim.com	30.4 KB	0.0 KB	80.8 KB	111.1 KB	27%	12.3 KB
17	news.bbs.nz	24.9 KB	0.0 KB	0.0 KB	24.9 KB	100%	1.8 KB
18	glockenspiel.complete.org	13.7 KB	0.0 KB	1.4 KB	15.0 KB	90%	2.1 KB
19	news.netfront.net	7.2 KB	0.0 KB	2.1 MB	2.1 MB	0%	24.1 KB
20	news.furie.org.uk	6.1 KB	0.0 KB	0.0 KB	6.1 KB	100%	6.1 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		25.3 MB	4.6 KB	4.1 MB	29.4 MB	86%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 29 00:01:01 - 00:59:59	154	2.1%	0.04	485.8 KB	1.9%	0.14
Aug 29 01:00:00 - 01:59:59	248	3.5%	0.07	783.1 KB	3.0%	0.22
Aug 29 02:00:00 - 02:59:59	296	4.1%	0.08	916.4 KB	3.6%	0.25
Aug 29 03:00:00 - 03:59:59	361	5.0%	0.10	1.2 MB	4.9%	0.35
Aug 29 04:00:00 - 04:59:59	428	6.0%	0.12	1.4 MB	5.7%	0.41
Aug 29 05:00:00 - 05:59:59	350	4.9%	0.10	1.2 MB	4.8%	0.35
Aug 29 06:00:00 - 06:59:59	373	5.2%	0.10	1.4 MB	5.7%	0.41
Aug 29 07:00:00 - 07:59:59	416	5.8%	0.12	1.6 MB	6.4%	0.46
Aug 29 08:00:00 - 08:59:59	364	5.1%	0.10	1.2 MB	4.7%	0.34
Aug 29 09:00:00 - 09:59:59	376	5.2%	0.10	1.4 MB	5.7%	0.41
Aug 29 10:00:00 - 10:59:59	468	6.5%	0.13	1.8 MB	7.0%	0.50
Aug 29 11:00:00 - 11:59:59	364	5.1%	0.10	1.5 MB	6.0%	0.43
Aug 29 12:00:00 - 12:59:59	495	6.9%	0.14	1.3 MB	5.0%	0.36
Aug 29 13:00:00 - 13:59:59	388	5.4%	0.11	1.2 MB	4.7%	0.34
Aug 29 14:00:00 - 14:59:59	440	6.1%	0.12	1.5 MB	5.8%	0.41
Aug 29 15:00:00 - 15:59:59	335	4.7%	0.09	1.2 MB	4.9%	0.35
Aug 29 16:00:00 - 16:59:59	258	3.6%	0.07	1016.9 KB	3.9%	0.28
Aug 29 17:00:00 - 17:59:59	229	3.2%	0.06	1.1 MB	4.3%	0.31
Aug 29 18:00:00 - 18:59:59	161	2.2%	0.04	552.3 KB	2.1%	0.15
Aug 29 19:00:00 - 19:59:59	136	1.9%	0.04	491.5 KB	1.9%	0.14
Aug 29 20:00:00 - 20:59:59	147	2.0%	0.04	482.3 KB	1.9%	0.13
Aug 29 21:00:00 - 21:59:59	121	1.7%	0.03	541.8 KB	2.1%	0.15
Aug 29 22:00:00 - 22:59:59	93	1.3%	0.03	469.9 KB	1.8%	0.13
Aug 29 23:00:00 - 23:59:59	173	2.4%	0.05	493.3 KB	1.9%	0.14
Aug 30 00:00:00 - 00:01:02	11	0.2%	0.18	25.0 KB	0.1%	0.40
TOTAL: 24:00:01	7185	100.0%	0.08	25.2 MB	100.0%	0.30

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.8 GB	6.3%	0	0.46	40217.10
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.6%	0	0.03	2015.96
TOTAL: 3	-	3077.0 GB	100.7 GB	3.3%	1	0.32	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	541	2	0	539
2	endofthelinebbs-out.news.weretis.net	182	0	1	181
3	usenet.goja.nl.eu.org	130	0	0	130
4	nntp.comgw.net	123	0	0	123
5	news.netfront.net	101	0	0	101
6	news.hispagatos.org	42	4	0	38
7	i2pn.org	31	0	0	31
8	endofthelinebbs.peers.news.panix.com	25	0	0	25
9	newsfeed.xs3.de	22	0	0	22
10	outgoing.news.lysator.liu.se	16	0	0	16
11	newsfeed.bofh.team	12	0	0	12
12	endoftheline-out.news.chmurka.net	9	0	0	9
13	news.corradoroberto.it	3	0	0	3
14	glockenspiel.complete.org	1	0	0	1
15	usenet.network	1	0	0	1
TOTAL: 15		1239	6	1	1232

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.exploits	2
hispagatos.hacking.news	2
alt.espn	1
soc.culture.africa	1
TOTAL: 4	6

INND Perl filter [Top 20]:

Reason	Count
EMP (phr path)	352
EMP (phn path)	326
EMP (md5)	315
Too many newsgroups	78
HTML Multipart	7
User-issued cancel	5
Too many newsgroups (meow)	3
EMP (phr nph)	1
TOTAL: 8	1087

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	34	197	0	197
i2pn2-nocem@i2pn2.org (multipost)	12	0	44	44
robot@pasdenom.info (spam3)	2	0	3	3
TOTAL: 3	48	197	47	244

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	58
	usenet.blueworldhosting.com	34
	usenet.goja.nl.eu.org	20
	newsfeed.xs3.de	14
	nntp.comgw.net	6
	endofthelinebbs.peers.news.panix.com	5
	outgoing.news.lysator.liu.se	4
	i2pn.org	2
	news.corradoroberto.it	1
	glockenspiel.complete.org	1
	TOTAL: 10	145
TOTAL: 1		145

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10523	3442	3740	2	8	32%	23:59:12
2	nyheter.lysator.liu.se	6667	1251	4116	654	19	18%	23:59:53
3	photonic.trudheim.com	7657	664	5559	952	0	8%	23:59:53
4	news.furie.org.uk	4209	565	2865	13	5	13%	23:59:53
5	endofthelinebbs.peers.news.panix.com	7539	463	6709	12	3	6%	23:59:48
6	usenet.network	7339	364	5846	46	0	4%	23:59:53
7	news.hispagatos.org	7450	304	5509	321	9	4%	23:58:48
8	newsfeed.bofh.team	8196	257	6714	0	2	3%	23:59:53
9	usenet.goja.nl.eu.org	7595	250	5942	269	0	3%	23:59:53
10	news.nk.ca	10925	201	6257	665	30	1%	23:59:53
11	news.corradoroberto.it	1322	197	860	0	0	14%	23:37:31
12	news.bbs.nz	11831	182	6373	618	25	1%	23:59:53
13	news.tnetconsulting.net	8008	131	6775	277	15	1%	23:59:49
14	news.weretis.net	7437	110	6869	203	0	1%	23:59:53
15	news.nntp4.net	8245	104	6983	36	7	1%	23:59:51
16	nntp.comgw.net	7821	102	6413	132	1	1%	23:59:40
17	i2pn.org	7862	98	6835	28	41	1%	23:59:53
18	news.quux.org	7667	93	6504	577	0	1%	23:59:02
19	newsfeed.xs3.de	8182	69	6486	190	4	0%	23:59:53
20	news.chmurka.net	3673	19	3021	3	1	0%	23:59:53
21	news.samoylyk.net	9389	13	6899	179	26	0%	23:59:30
22	usenet.blueworldhosting.com	6390	1	5984	0	0	0%	23:59:53
23	feed.opticnetworks.net	0	0	0	0	7185	0%	23:59:53
TOTAL: 23		165927	8880	123259	5177	7381	5%	551:31:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	12.7 MB	7.0 KB	12.7 MB	0.2 KB/s	3.8 KB	23:59:12
2	photonic.trudheim.com	4.2 MB	2.7 MB	6.9 MB	0.1 KB/s	4.4 KB	23:59:53
3	news.furie.org.uk	3.9 MB	129.4 KB	4.1 MB	0.0 KB/s	7.2 KB	23:59:53
4	nyheter.lysator.liu.se	3.9 MB	5.0 MB	8.8 MB	0.1 KB/s	4.7 KB	23:59:53
5	endofthelinebbs.peers.news.panix.com	2.5 MB	18.2 KB	2.5 MB	0.0 KB/s	5.3 KB	23:59:48
6	newsfeed.bofh.team	1.7 MB	0.0 KB	1.7 MB	0.0 KB/s	6.8 KB	23:59:53
7	usenet.network	1.0 MB	115.8 KB	1.2 MB	0.0 KB/s	2.9 KB	23:59:53
8	news.hispagatos.org	1.0 MB	2.1 MB	3.1 MB	0.0 KB/s	5.1 KB	23:58:48
9	news.tnetconsulting.net	564.0 KB	1.9 MB	2.4 MB	0.0 KB/s	6.1 KB	23:59:49
10	usenet.goja.nl.eu.org	546.0 KB	2.4 MB	2.9 MB	0.0 KB/s	5.8 KB	23:59:53
11	news.corradoroberto.it	543.3 KB	0.0 KB	543.3 KB	0.0 KB/s	2.8 KB	23:37:31
12	news.bbs.nz	470.5 KB	3.6 MB	4.1 MB	0.0 KB/s	5.2 KB	23:59:53
13	news.nk.ca	452.3 KB	4.0 MB	4.4 MB	0.1 KB/s	5.3 KB	23:59:53
14	news.quux.org	393.9 KB	3.6 MB	4.0 MB	0.0 KB/s	6.1 KB	23:59:02
15	news.weretis.net	374.2 KB	1.8 MB	2.1 MB	0.0 KB/s	7.0 KB	23:59:53
16	nntp.comgw.net	336.8 KB	1.2 MB	1.6 MB	0.0 KB/s	6.8 KB	23:59:40
17	news.nntp4.net	334.5 KB	335.2 KB	669.7 KB	0.0 KB/s	4.8 KB	23:59:51
18	i2pn.org	315.7 KB	135.2 KB	450.9 KB	0.0 KB/s	3.6 KB	23:59:53
19	newsfeed.xs3.de	297.5 KB	1.5 MB	1.8 MB	0.0 KB/s	7.1 KB	23:59:53
20	news.chmurka.net	59.3 KB	6.2 KB	65.5 KB	0.0 KB/s	3.0 KB	23:59:53
21	news.samoylyk.net	28.5 KB	830.5 KB	859.0 KB	0.0 KB/s	4.5 KB	23:59:30
22	usenet.blueworldhosting.com	1.1 KB	0.0 KB	1.1 KB	0.0 KB/s	1.1 KB	23:59:53
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 23		35.5 MB	31.3 MB	66.8 MB	0.0 KB/s	4.9 KB	551:31:33

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2763	00:30:19
2	147.185.132.201	1	00:00:10
3	185.242.226.41	1	00:00:00
4	198.235.24.158	1	00:00:10
5	205.210.31.174	1	00:00:00
6	80.66.76.134	1	00:00:00
7	88.214.25.61	1	00:00:00
8	astounding.monitoring.internet-measurement.com	1	00:00:01
9	azpdsg58.stretchoid.com	1	00:00:00
10	localhost	1	00:11:04
TOTAL: 10		2772	00:41:47

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2763	00:30:19
2	unresolved	6	00:00:20
3	*.monitoring.internet-measurement.com	1	00:00:01
4	*.stretchoid.com	1	00:00:00
5	?	1	00:11:04
TOTAL: 5		2772	00:41:47

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.888	36.628	0.000	00:30:19
localhost	183.199	369.869	0.074	00:11:04
198.235.24.158	0.004	0.012	0.000	00:00:10
147.185.132.201	0.000	0.016	0.000	00:00:10
astounding.monitoring.internet-measurement.com	0.004	0.012	0.000	00:00:01
205.210.31.174	0.004	0.012	0.000	00:00:00
185.242.226.41	0.000	0.017	0.000	00:00:00
88.214.25.61	0.005	0.011	0.000	00:00:00
80.66.76.134	0.000	0.017	0.000	00:00:00
azpdsg58.stretchoid.com	0.003	0.017	0.000	00:00:00
TOTAL: 10	193.107	406.611	0.074	00:41:47

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

System	Conn
185.242.226.41	5
80.66.76.134	1
88.214.25.61	1
azpdsg58.stretchoid.com	1
TOTAL: 4	8

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

Command	Count
#003	2
Accept-Encoding: gzip	1
Accept: /	1
GET / HTTP/1.1	1
Host: 144.172.126.95:119	1
MGLNDD_144.172.126.95_119	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
TOTAL: 7	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.76.134	1	1
88.214.25.61	1	1
TOTAL: 2	2	3

Daily Usenet Report

Aug 29 00:01:01 -- Aug 30 00:01:02