Daily Usenet Report: Oct 19 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 20 00:00:01 newsfeed nnrpd[3082011]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	8456	39.0%	1.2 MB	22.7%
inn	8367	38.6%	3.3 MB	60.9%
innfeed	4318	19.9%	840.7 KB	14.9%
cnfsstat	432	2.0%	67.9 KB	1.2%
nnrpd	84	0.4%	8.7 KB	0.2%
nocem	19	0.1%	2.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	21677	100.0%	5.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	113492	60.8%
Negative hits	65091	34.8%
Do not exist	8224	4.4%
Cache misses	10	0.0%
TOTAL: 4	186817	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.123	0.0%	31	0.000	3.968	19.000
article cleanup	00:00:00.079	0.0%	8277	0.000	0.010	0.215
article logging	00:00:00.367	0.0%	8360	0.000	0.044	0.153
article parse	00:00:00.196	0.0%	11508	0.000	0.017	0.065
article write	00:00:02.049	0.0%	6893	0.022	0.297	2.852
artlog/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.977	0.0%	189599	0.000	0.005	0.302
hisgrep/artcncl	00:00:00.080	0.0%	34	0.000	2.353	10.000
hishave/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
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:36.290	0.2%	188128	0.105	0.831	5.692
history sync	00:00:00.068	0.0%	409	0.000	0.166	1.667
history write	00:01:56.125	0.1%	8278	3.189	14.028	115.519
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:52:22.518	99.4%	184287	84.157	466.351	1910.029
nntp read	00:00:04.052	0.0%	190667	0.003	0.021	0.295
overview write	00:03:02.914	0.2%	6893	0.515	26.536	3480.739
perl filter	00:00:23.943	0.0%	8277	1.342	2.893	22.657
python filter	00:00:01.431	0.0%	8277	0.000	0.173	12.148
site send	00:00:01.043	0.0%	13803	0.000	0.076	6.886
TOTAL: 24:01:09.926	24:00:32.255	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.016	0.0%	7026	0.000	0.002	0.038
article prepare	00:00:00.007	0.0%	42756	0.000	0.000	0.005
article read	00:00:00.532	0.0%	3781	0.000	0.141	0.789
backlog stats	00:00:00.398	0.0%	245156	0.000	0.002	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.112	0.0%	167085	0.002	0.013	0.044
data write	00:00:02.859	0.0%	162643	0.005	0.018	0.049
idle	23:58:39.576	100.0%	245156	154.175	352.101	1297.186
status file	00:00:00.464	0.0%	353	0.500	1.314	34.000
TOTAL: 23:58:58.358	23:58:45.964	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:20:56.075	64.6%	28	0.750	44859.821	302534.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.333	0.2%	78906	0.000	0.055	0.500
TOTAL: 00:32:24.721	00:21:00.408	64.8%	-	-	-	-

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	5750	1286	4461	3	22%	23:59:57
2	usenet.blueworldhosting.com	716	8090	883	6645	562	10%	23:55:26
3	nntp.comgw.net	2	7550	875	6460	215	11%	23:59:55
4	usenet.goja.nl.eu.org	10	7421	793	6431	197	10%	23:43:45
5	endofthelinebbs-out.news.weretis.net	3	12989	718	12147	124	5%	49:54:22
6	news.hispagatos.org	6	7590	425	7146	19	5%	24:25:50
7	news.corradoroberto.it	9	1079	365	714	0	33%	24:15:34
8	i2pn.org	1	7439	351	6983	105	4%	23:59:54
9	newsfeed.xs3.de	1	5774	270	5478	26	4%	23:59:55
10	194.87.241.33	44	347	210	136	1	60%	16:00:07
11	endofthelinebbs.peers.news.panix.com	26	7235	134	7084	17	1%	23:53:47
12	news.furie.org.uk	1	3852	131	3720	1	3%	25:53:35
13	usenet.network	2	5516	129	5378	9	2%	23:59:55
14	endoftheline-out.news.chmurka.net	1	3707	104	3603	0	2%	23:59:54
15	news.netfront.net	2	5997	69	5843	85	1%	24:11:08
16	newsfeed.bofh.team	1	8010	49	7956	5	0%	23:59:54
17	news.samoylyk.net	2	6387	35	6350	2	0%	23:59:34
18	news.nntp4.net	1	6220	28	6192	0	0%	23:59:55
19	glockenspiel.complete.org	2	5708	14	5691	3	0%	23:59:10
20	news.bbs.nz	1	6818	5	6813	0	0%	23:59:30
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:04
22	localhost	27	0	0	0	0	0%	00:00:01
23	news.nk.ca	2	0	0	0	0	0%	00:20:16
TOTAL: 23		862	123479	6874	115231	1374	5%	500:41:28

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	3.6 MB	3.3 KB	3.6 MB	99%	2.8 KB
2	endofthelinebbs-out.news.weretis.net	2.7 MB	269.9 KB	3.0 MB	91%	3.6 KB
3	usenet.goja.nl.eu.org	2.5 MB	308.5 KB	2.8 MB	89%	2.9 KB
4	nntp.comgw.net	2.4 MB	365.1 KB	2.8 MB	87%	2.6 KB
5	usenet.blueworldhosting.com	2.4 MB	1.5 MB	3.8 MB	62%	2.7 KB
6	news.corradoroberto.it	2.0 MB	0.0 KB	2.0 MB	100%	5.7 KB
7	usenet.network	1.8 MB	8.2 KB	1.8 MB	99%	13.2 KB
8	news.hispagatos.org	1.4 MB	24.8 KB	1.4 MB	98%	3.2 KB
9	i2pn.org	1.0 MB	138.4 KB	1.1 MB	88%	2.6 KB
10	newsfeed.xs3.de	723.9 KB	48.8 KB	772.8 KB	93%	2.6 KB
11	194.87.241.33	547.0 KB	0.9 KB	548.0 KB	99%	2.6 KB
12	news.furie.org.uk	399.5 KB	0.9 KB	400.4 KB	99%	3.0 KB
13	endofthelinebbs.peers.news.panix.com	365.5 KB	54.1 KB	419.6 KB	87%	2.8 KB
14	endoftheline-out.news.chmurka.net	323.6 KB	0.0 KB	323.6 KB	100%	3.1 KB
15	news.samoylyk.net	147.9 KB	2.4 KB	150.3 KB	98%	4.1 KB
16	news.netfront.net	141.5 KB	337.2 KB	478.7 KB	29%	3.1 KB
17	newsfeed.bofh.team	134.5 KB	5.7 KB	140.2 KB	95%	2.6 KB
18	news.nntp4.net	62.1 KB	0.0 KB	62.1 KB	100%	2.2 KB
19	glockenspiel.complete.org	53.0 KB	4.3 KB	57.4 KB	92%	3.4 KB
20	news.bbs.nz	11.6 KB	0.0 KB	11.6 KB	100%	2.3 KB
21	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0%	0
22	localhost	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
TOTAL: 23		22.6 MB	3.0 MB	25.6 MB	88%	3.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 19 00:01:01 - 00:59:59	135	2.0%	0.04	571.5 KB	2.5%	0.16
Oct 19 01:00:00 - 01:59:59	139	2.0%	0.04	378.8 KB	1.6%	0.11
Oct 19 02:00:00 - 02:59:59	294	4.3%	0.08	828.9 KB	3.6%	0.23
Oct 19 03:00:00 - 03:59:59	363	5.3%	0.10	1.1 MB	4.6%	0.30
Oct 19 04:00:00 - 04:59:59	369	5.4%	0.10	1.2 MB	5.1%	0.33
Oct 19 05:00:00 - 05:59:59	292	4.2%	0.08	726.4 KB	3.1%	0.20
Oct 19 06:00:00 - 06:59:59	342	5.0%	0.10	1.0 MB	4.6%	0.30
Oct 19 07:00:00 - 07:59:59	335	4.9%	0.09	1.1 MB	4.9%	0.32
Oct 19 08:00:00 - 08:59:59	368	5.3%	0.10	1.1 MB	4.9%	0.31
Oct 19 09:00:00 - 09:59:59	436	6.3%	0.12	1.8 MB	7.8%	0.50
Oct 19 10:00:00 - 10:59:59	336	4.9%	0.09	1.3 MB	5.7%	0.36
Oct 19 11:00:00 - 11:59:59	465	6.7%	0.13	1.2 MB	5.3%	0.34
Oct 19 12:00:00 - 12:59:59	364	5.3%	0.10	1.2 MB	5.5%	0.35
Oct 19 13:00:00 - 13:59:59	372	5.4%	0.10	1.1 MB	4.8%	0.31
Oct 19 14:00:00 - 14:59:59	294	4.3%	0.08	1.1 MB	4.9%	0.31
Oct 19 15:00:00 - 15:59:59	399	5.8%	0.11	1.4 MB	6.4%	0.41
Oct 19 16:00:00 - 16:59:59	320	4.6%	0.09	1.1 MB	4.8%	0.31
Oct 19 17:00:00 - 17:59:59	326	4.7%	0.09	1.3 MB	5.5%	0.36
Oct 19 18:00:00 - 18:59:59	181	2.6%	0.05	612.7 KB	2.6%	0.17
Oct 19 19:00:00 - 19:59:59	177	2.6%	0.05	727.9 KB	3.1%	0.20
Oct 19 20:00:00 - 20:59:59	162	2.3%	0.04	592.1 KB	2.6%	0.16
Oct 19 21:00:00 - 21:59:59	119	1.7%	0.03	433.9 KB	1.9%	0.12
Oct 19 22:00:00 - 22:59:59	139	2.0%	0.04	457.7 KB	2.0%	0.13
Oct 19 23:00:00 - 23:59:59	163	2.4%	0.05	492.6 KB	2.1%	0.14
Oct 20 00:00:00 - 00:01:01	6	0.1%	0.10	11.1 KB	0.0%	0.18
TOTAL: 24:00:00	6896	100.0%	0.08	22.6 MB	100.0%	0.27

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.8 GB	6.4%	0	0.42	44412.89
CYCTXT1	TEXT	5.0 GB	3.9 GB	78.0%	0	0.03	1847.26
TOTAL: 3	-	3077.0 GB	102.7 GB	3.3%	1	0.28	-

Sites sending bad articles:

Server		Total	Group	Other
1	usenet.blueworldhosting.com	575	6	569
2	nntp.comgw.net	219	0	219
3	usenet.goja.nl.eu.org	213	0	213
4	endofthelinebbs-out.news.weretis.net	173	0	173
5	i2pn.org	107	0	107
6	news.netfront.net	86	0	86
7	newsfeed.xs3.de	32	0	32
8	endofthelinebbs.peers.news.panix.com	21	2	19
9	news.hispagatos.org	16	7	9
10	usenet.network	10	0	10
11	newsfeed.bofh.team	6	1	5
12	outgoing.news.lysator.liu.se	3	0	3
13	news.samoylyk.net	2	0	2
14	194.87.241.33	1	0	1
15	glockenspiel.complete.org	1	0	1
16	news.furie.org.uk	1	0	1
17	news.nntp4.net	1	0	1
TOTAL: 17		1467	16	1451

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	5
alt.alt.anonymous	3
news.test_messages	3
hispagatos.hacking.news	2
soc.culture.russia	2
unspecified	1
TOTAL: 6	16

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	749
EMP (phr path)	352
EMP (md5)	153
Too many newsgroups	92
User-issued cancel	19
HTML Multipart	1
Too many newsgroups (meow)	1
TOTAL: 7	1367

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	1	1	0	1
i2pn2-nocem@i2pn2.org (multipost)	9	0	13	13
i2pn2-nocem@i2pn2.org (rmp)	2	0	6	6
robot@pasdenom.info (spam3)	3	0	4	4
robot@pasdenom.info (spam2)	2	0	3	3
TOTAL: 5	17	1	26	27

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	39
	usenet.blueworldhosting.com	13
	nntp.comgw.net	11
	usenet.goja.nl.eu.org	8
	newsfeed.xs3.de	6
	endofthelinebbs.peers.news.panix.com	4
	news.nntp4.net	1
	newsfeed.bofh.team	1
	TOTAL: 8	83
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		84

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10797	2241	4587	0	10	20%	23:59:33
2	nyheter.lysator.liu.se	5775	1679	3715	215	0	29%	23:59:50
3	news.furie.org.uk	3833	972	2353	16	5	25%	23:57:47
4	usenet.goja.nl.eu.org	7115	436	5483	72	2	6%	23:59:50
5	endofthelinebbs.peers.news.panix.com	7214	396	6475	22	0	5%	23:59:02
6	news.hispagatos.org	7740	385	5949	100	0	4%	23:59:50
7	usenet.network	7377	365	5944	32	0	4%	23:59:50
8	news.bbs.nz	12667	277	6211	398	44	2%	23:59:50
9	i2pn.org	7420	197	6249	79	14	2%	23:59:50
10	newsfeed.bofh.team	7906	190	6543	0	0	2%	23:59:35
11	news.tnetconsulting.net	7822	185	6580	123	6	2%	23:59:50
12	news.nk.ca	10391	163	6247	426	9	1%	23:59:50
13	nntp.comgw.net	7112	163	5802	46	2	2%	23:59:07
14	news.nntp4.net	7905	159	6671	20	0	2%	23:59:33
15	news.quux.org	7306	138	6327	418	0	1%	23:59:50
16	news.weretis.net	7210	135	6691	65	0	1%	23:59:50
17	news.corradoroberto.it	946	124	587	0	3	13%	22:56:39
18	newsfeed.xs3.de	7970	77	6362	187	2	0%	23:59:50
19	news.chmurka.net	4347	66	3421	1	2	1%	23:59:50
20	news.samoylyk.net	8450	27	6736	90	30	0%	23:59:18
21	usenet.blueworldhosting.com	6059	5	5769	0	7	0%	23:59:12
22	ddt.demos.su	39	3	35	0	0	7%	05:16:44
23	feed.opticnetworks.net	0	0	0	0	6896	0%	23:59:50
24	photonic.trudheim.com	0	0	0	0	6895	0%	23:59:50
TOTAL: 24		153401	8383	114737	2310	13927	5%	556:04:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	8.5 MB	0.0 KB	8.5 MB	0.1 KB/s	3.9 KB	23:59:33
2	nyheter.lysator.liu.se	6.1 MB	1.3 MB	7.4 MB	0.1 KB/s	4.0 KB	23:59:50
3	news.furie.org.uk	5.4 MB	197.1 KB	5.6 MB	0.1 KB/s	5.8 KB	23:57:47
4	news.quux.org	1.8 MB	1.3 MB	3.1 MB	0.0 KB/s	5.7 KB	23:59:50
5	endofthelinebbs.peers.news.panix.com	1.8 MB	59.7 KB	1.8 MB	0.0 KB/s	4.5 KB	23:59:02
6	news.hispagatos.org	1.7 MB	892.5 KB	2.5 MB	0.0 KB/s	5.4 KB	23:59:50
7	news.bbs.nz	1.5 MB	2.0 MB	3.6 MB	0.0 KB/s	5.4 KB	23:59:50
8	newsfeed.bofh.team	1.4 MB	0.0 KB	1.4 MB	0.0 KB/s	7.5 KB	23:59:35
9	usenet.network	1.4 MB	75.8 KB	1.4 MB	0.0 KB/s	3.7 KB	23:59:50
10	news.nntp4.net	1.3 MB	193.4 KB	1.5 MB	0.0 KB/s	8.3 KB	23:59:33
11	usenet.goja.nl.eu.org	1.2 MB	745.8 KB	2.0 MB	0.0 KB/s	4.0 KB	23:59:50
12	news.tnetconsulting.net	1.0 MB	858.1 KB	1.9 MB	0.0 KB/s	6.3 KB	23:59:50
13	nntp.comgw.net	916.9 KB	1.0 MB	1.9 MB	0.0 KB/s	9.4 KB	23:59:07
14	news.samoylyk.net	848.1 KB	346.8 KB	1.2 MB	0.0 KB/s	10.2 KB	23:59:18
15	news.weretis.net	832.9 KB	1.2 MB	2.0 MB	0.0 KB/s	10.1 KB	23:59:50
16	i2pn.org	779.6 KB	199.9 KB	979.6 KB	0.0 KB/s	3.5 KB	23:59:50
17	ddt.demos.su	729.2 KB	0.0 KB	729.2 KB	0.0 KB/s	243.1 KB	05:16:44
18	newsfeed.xs3.de	721.0 KB	1.2 MB	1.9 MB	0.0 KB/s	7.5 KB	23:59:50
19	news.nk.ca	531.2 KB	2.1 MB	2.6 MB	0.0 KB/s	4.6 KB	23:59:50
20	news.corradoroberto.it	403.8 KB	0.0 KB	403.8 KB	0.0 KB/s	3.3 KB	22:56:39
21	news.chmurka.net	276.4 KB	1.6 KB	278.0 KB	0.0 KB/s	4.1 KB	23:59:50
22	usenet.blueworldhosting.com	20.5 KB	0.0 KB	20.5 KB	0.0 KB/s	4.1 KB	23:59:12
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:50
24	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:50
TOTAL: 24		39.1 MB	13.6 MB	52.7 MB	0.0 KB/s	5.0 KB	556:04:10

NNRP readership statistics:

System		Conn	Elapsed
1	167.94.145.100	3	00:00:03
2	167.94.146.56	3	00:00:04
3	198.235.24.196	1	00:00:10
4	2001:470:28:a8:3ad5:47ff:fe02:c2fd	1	00:10:02
5	205.210.31.24	1	00:00:10
6	47.237.99.162	1	00:00:08
7	80.66.76.130	1	00:00:00
8	attentive.monitoring.internet-measurement.com	1	00:00:01
9	azpdsgb38.stretchoid.com	1	00:00:00
10	azpdssc0.stretchoid.com	1	00:00:09
11	freeq.furie.org.uk	1	00:10:05
12	localhost	1	00:11:25
13	server-0-2.survey.inspici.com	1	00:00:03
TOTAL: 13		17	00:32:24

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	10	00:00:36
2	*.stretchoid.com	2	00:00:09
3	?	2	00:21:28
4	*.furie.org.uk	1	00:10:05
5	*.monitoring.internet-measurement.com	1	00:00:01
6	*.survey.inspici.com	1	00:00:03
TOTAL: 6		17	00:32:24

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
localhost	188.641	372.268	0.081	00:11:25
freeq.furie.org.uk	0.003	0.013	0.000	00:10:05
2001:470:28:a8:3ad5:47ff:fe02:c2fd	0.000	0.019	0.000	00:10:02
198.235.24.196	0.000	0.016	0.000	00:00:10
205.210.31.24	0.000	0.016	0.000	00:00:10
azpdssc0.stretchoid.com	0.004	0.025	0.000	00:00:09
47.237.99.162	0.005	0.011	0.000	00:00:08
167.94.146.56	0.012	0.052	0.000	00:00:04
167.94.145.100	0.032	0.048	0.000	00:00:03
server-0-2.survey.inspici.com	0.003	0.013	0.000	00:00:03
attentive.monitoring.internet-measurement.com	0.011	0.021	0.000	00:00:01
80.66.76.130	0.005	0.023	0.000	00:00:00
azpdsgb38.stretchoid.com	0.000	0.021	0.000	00:00:00
TOTAL: 13	188.716	372.546	0.081	00:32:24

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

System	Conn
167.94.145.100	1
167.94.146.56	1
80.66.76.130	1
azpdsgb38.stretchoid.com	1
TOTAL: 4	4

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.145.100	1	2
167.94.146.56	1	2
80.66.76.130	1	1
TOTAL: 3	3	6

Daily Usenet Report

Oct 19 00:01:01 -- Oct 20 00:01:01