Daily Usenet Report: Nov 29 00:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Nov 30 00:00:01 newsfeed nnrpd[1970415]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	8947	38.5%	1.3 MB	22.8%
inn	8874	38.2%	3.4 MB	60.2%
innfeed	4477	19.3%	877.7 KB	15.0%
cnfsstat	432	1.9%	65.6 KB	1.1%
nnrpd	429	1.8%	45.8 KB	0.8%
nocem	61	0.3%	8.3 KB	0.1%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 7	23221	100.0%	5.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	115803	65.2%
Negative hits	52875	29.8%
Do not exist	8808	5.0%
Cache misses	8	0.0%
TOTAL: 4	177494	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.109	0.0%	26	0.000	4.192	9.000
article cleanup	00:00:00.090	0.0%	8824	0.000	0.010	0.460
article logging	00:00:00.358	0.0%	8852	0.000	0.040	0.176
article parse	00:00:00.197	0.0%	14133	0.000	0.014	0.070
article write	00:00:01.717	0.0%	6969	0.000	0.246	5.000
artlog/artcncl	00:00:00.000	0.0%	7	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:00.525	0.0%	170810	0.000	0.003	0.287
hisgrep/artcncl	00:00:00.080	0.0%	27	0.000	2.963	7.000
hishave/artcncl	00:00:00.002	0.0%	8	0.000	0.250	0.667
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:03:03.322	0.2%	177666	0.111	1.032	9.526
history sync	00:00:00.074	0.0%	393	0.000	0.188	1.000
history write	00:01:57.922	0.1%	8816	2.304	13.376	168.185
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:32:45.472	98.5%	164939	243.632	513.920	1780.347
nntp read	00:00:02.901	0.0%	171907	0.002	0.017	0.207
overview write	00:15:59.971	1.1%	6969	0.600	137.749	14366.870
perl filter	00:00:19.321	0.0%	8814	1.248	2.192	12.333
python filter	00:00:00.763	0.0%	8814	0.000	0.087	4.149
site send	00:00:00.942	0.0%	13993	0.000	0.067	2.472
TOTAL: 23:54:47.390	23:54:13.766	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.011	0.0%	7319	0.000	0.002	0.048
article prepare	00:00:00.012	0.0%	58612	0.000	0.000	0.004
article read	00:00:01.026	0.0%	5105	0.000	0.201	7.245
backlog stats	00:00:00.386	0.0%	272537	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.383	0.0%	178516	0.002	0.013	0.339
data write	00:00:03.117	0.0%	174102	0.008	0.018	0.090
idle	23:56:16.410	100.0%	272537	163.128	316.201	1315.155
status file	00:00:00.473	0.0%	418	0.667	1.132	5.000
TOTAL: 23:56:36.718	23:56:23.818	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:00.350	7.3%	113	0.500	534.071	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.073	0.5%	79088	0.000	0.051	0.500
TOTAL: 00:13:50.705	00:01:04.423	7.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	3	13757	1951	11696	110	14%	48:49:08
2	usenet.blueworldhosting.com	714	8528	922	6842	764	10%	23:50:46
3	usenet.goja.nl.eu.org	10	7784	686	6904	194	8%	23:43:52
4	nntp.comgw.net	2	8153	577	7321	255	7%	23:59:57
5	news.corradoroberto.it	11	1242	526	716	0	42%	22:25:06
6	glockenspiel.complete.org	2	2129	333	1794	2	15%	11:41:48
7	newsfeed.bofh.team	2	8429	324	7945	160	3%	23:59:48
8	endoftheline-out.news.chmurka.net	1	3463	301	3161	1	8%	23:59:54
9	194.87.241.33	46	323	252	71	0	78%	16:24:15
10	i2pn.org	1	7862	242	7581	39	3%	23:59:52
11	newsfeed.xs3.de	2	6059	229	5745	85	3%	23:59:00
12	usenet.network	2	5481	176	5298	7	3%	23:59:51
13	endofthelinebbs.peers.news.panix.com	26	7473	141	7316	16	1%	23:53:30
14	news.samoylyk.net	4	6661	93	6563	5	1%	24:42:19
15	news.nntp4.net	2	6344	80	6232	32	1%	23:59:12
16	news.netfront.net	1	6461	75	6230	156	1%	24:29:12
17	news.hispagatos.org	5	8196	46	8113	37	0%	24:26:20
18	news.bbs.nz	1	6538	12	6523	3	0%	23:59:38
19	news.furie.org.uk	2	3638	9	3628	1	0%	23:58:38
20	outgoing.news.lysator.liu.se	1	3803	1	3801	1	0%	23:59:57
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:03
22	localhost	30	0	0	0	0	0%	00:00:01
23	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 23		871	122324	6976	113480	1868	5%	484:52:19

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.0 MB	2.5 KB	457.3 KB	6.5 MB	93%	3.2 KB
2	news.corradoroberto.it	3.1 MB	0.0 KB	0.0 KB	3.1 MB	100%	6.1 KB
3	nntp.comgw.net	2.0 MB	0.0 KB	1.1 MB	3.1 MB	65%	3.9 KB
4	usenet.blueworldhosting.com	2.0 MB	0.0 KB	1.5 MB	3.4 MB	56%	2.1 KB
5	usenet.goja.nl.eu.org	1.9 MB	0.0 KB	354.3 KB	2.3 MB	84%	2.7 KB
6	usenet.network	1.8 MB	0.0 KB	13.2 KB	1.9 MB	99%	10.4 KB
7	glockenspiel.complete.org	1.5 MB	0.0 KB	1.5 KB	1.5 MB	99%	4.5 KB
8	endoftheline-out.news.chmurka.net	1.1 MB	0.0 KB	1.0 KB	1.1 MB	99%	3.6 KB
9	newsfeed.bofh.team	872.4 KB	0.0 KB	223.0 KB	1.1 MB	79%	2.3 KB
10	194.87.241.33	798.8 KB	0.0 KB	0.0 KB	798.8 KB	100%	3.2 KB
11	i2pn.org	778.6 KB	2.5 KB	51.2 KB	832.3 KB	93%	3.0 KB
12	newsfeed.xs3.de	596.3 KB	2.4 KB	284.4 KB	883.2 KB	67%	2.8 KB
13	news.samoylyk.net	451.2 KB	0.0 KB	10.3 KB	461.5 KB	97%	4.7 KB
14	endofthelinebbs.peers.news.panix.com	372.4 KB	0.0 KB	20.0 KB	392.4 KB	94%	2.5 KB
15	news.nntp4.net	201.6 KB	2.4 KB	82.1 KB	286.1 KB	70%	2.6 KB
16	news.hispagatos.org	157.9 KB	0.0 KB	63.4 KB	221.3 KB	71%	2.7 KB
17	news.netfront.net	130.2 KB	4.6 KB	5.3 MB	5.5 MB	2%	24.3 KB
18	news.bbs.nz	45.7 KB	4.4 KB	6.3 KB	56.5 KB	80%	3.8 KB
19	news.furie.org.uk	31.0 KB	2.4 KB	0.0 KB	33.4 KB	92%	3.3 KB
20	outgoing.news.lysator.liu.se	1.2 KB	2.5 KB	0.0 KB	3.7 KB	33%	1.9 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		23.8 MB	23.7 KB	9.4 MB	33.3 MB	71%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 29 00:01:02 - 00:59:59	140	2.0%	0.04	429.9 KB	1.8%	0.12
Nov 29 01:00:00 - 01:59:59	220	3.2%	0.06	586.5 KB	2.4%	0.16
Nov 29 02:00:00 - 02:59:59	284	4.1%	0.08	970.5 KB	4.0%	0.27
Nov 29 03:00:00 - 03:59:59	389	5.6%	0.11	1.2 MB	5.2%	0.35
Nov 29 04:00:00 - 04:59:59	393	5.6%	0.11	1.2 MB	5.1%	0.34
Nov 29 05:00:00 - 05:59:59	284	4.1%	0.08	947.0 KB	3.9%	0.26
Nov 29 06:00:00 - 06:59:59	371	5.3%	0.10	1.6 MB	6.8%	0.46
Nov 29 07:00:00 - 07:59:59	362	5.2%	0.10	1.1 MB	4.8%	0.32
Nov 29 08:00:00 - 08:59:59	402	5.8%	0.11	1.2 MB	5.2%	0.35
Nov 29 09:00:00 - 09:59:59	392	5.6%	0.11	1.5 MB	6.4%	0.43
Nov 29 10:00:00 - 10:59:59	427	6.1%	0.12	1.2 MB	5.3%	0.35
Nov 29 11:00:00 - 11:59:59	377	5.4%	0.10	1.2 MB	4.9%	0.33
Nov 29 12:00:00 - 12:59:59	424	6.1%	0.12	1.4 MB	6.1%	0.41
Nov 29 13:00:00 - 13:59:59	439	6.3%	0.12	1.4 MB	6.0%	0.40
Nov 29 14:00:00 - 14:59:59	301	4.3%	0.08	890.0 KB	3.7%	0.25
Nov 29 15:00:00 - 15:59:59	406	5.8%	0.11	1.5 MB	6.5%	0.44
Nov 29 16:00:00 - 16:59:59	285	4.1%	0.08	1.4 MB	6.1%	0.41
Nov 29 17:00:00 - 17:59:59	279	4.0%	0.08	923.2 KB	3.8%	0.26
Nov 29 18:00:00 - 18:59:59	179	2.6%	0.05	590.4 KB	2.4%	0.16
Nov 29 19:00:00 - 19:59:59	165	2.4%	0.05	510.9 KB	2.1%	0.14
Nov 29 20:00:00 - 20:59:59	122	1.7%	0.03	417.7 KB	1.7%	0.12
Nov 29 21:00:00 - 21:59:59	87	1.2%	0.02	361.0 KB	1.5%	0.10
Nov 29 22:00:00 - 22:59:59	118	1.7%	0.03	589.5 KB	2.4%	0.16
Nov 29 23:00:00 - 23:59:59	124	1.8%	0.03	443.7 KB	1.8%	0.12
Nov 30 00:00:00 - 00:01:02	8	0.1%	0.13	21.6 KB	0.1%	0.35
TOTAL: 24:00:00	6978	100.0%	0.08	23.7 MB	100.0%	0.28

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	100.5 GB	6.5%	0	0.44	41955.86
CYCTXT1	TEXT	5.0 GB	4.0 GB	79.0%	0	0.03	2283.20
TOTAL: 3	-	3077.0 GB	104.5 GB	3.4%	1	0.32	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	767	1	0	766
2	nntp.comgw.net	260	0	0	260
3	usenet.goja.nl.eu.org	198	0	0	198
4	newsfeed.bofh.team	162	0	0	162
5	news.netfront.net	149	0	2	147
6	endofthelinebbs-out.news.weretis.net	119	0	1	118
7	newsfeed.xs3.de	87	0	1	86
8	i2pn.org	40	0	1	39
9	news.hispagatos.org	36	24	0	12
10	news.nntp4.net	32	0	1	31
11	endofthelinebbs.peers.news.panix.com	17	0	0	17
12	usenet.network	7	0	0	7
13	news.samoylyk.net	5	0	0	5
14	news.bbs.nz	3	0	2	1
15	glockenspiel.complete.org	2	0	0	2
16	news.furie.org.uk	2	0	1	1
17	endoftheline-out.news.chmurka.net	1	0	0	1
18	news.corradoroberto.it	1	0	0	1
19	outgoing.news.lysator.liu.se	1	0	1	0
TOTAL: 19		1889	25	10	1854

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	16
hispagatos.hacking.ctf	5
hispagatos.hacking.news	4
TOTAL: 3	25

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	962
EMP (phr path)	327
Too many newsgroups	135
EMP (md5)	132
Binary: misplaced binary	100
EMP (phn nph)	70
User-issued cancel	48
Too many newsgroups (meow)	45
HTML Multipart	4
Subject (lSdk-v)	1
TOTAL: 10	1824

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	5	7	0	7
i2pn2-nocem@i2pn2.org (multipost)	25	0	27	27
nocem@eternal-september.org (2001::1/128)	14	0	24	24
robot@pasdenom.info (spam3)	3	0	9	9
i2pn2-nocem@i2pn2.org (rmp)	5	0	8	8
robot@pasdenom.info (spam2)	3	0	3	3
TOTAL: 6	55	7	71	78

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.goja.nl.eu.org	6
	nntp.comgw.net	5
	endofthelinebbs-out.news.weretis.net	5
	usenet.blueworldhosting.com	3
	newsfeed.bofh.team	3
	newsfeed.xs3.de	2
	news.furie.org.uk	1
	news.corradoroberto.it	1
	i2pn.org	1
	endofthelinebbs.peers.news.panix.com	1
	TOTAL: 10	28
No colon-space in header field
	usenet.blueworldhosting.com	2
	TOTAL: 1	2
TOTAL: 2		30

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7301	3634	3123	220	0	49%	23:59:51
2	news.netfront.net	10878	2402	4513	0	0	22%	23:59:16
3	news.furie.org.uk	4462	717	2536	13	0	16%	23:59:51
4	usenet.goja.nl.eu.org	7971	585	5410	204	0	7%	23:59:05
5	news.nntp4.net	8267	500	6177	202	0	6%	23:59:51
6	usenet.network	10973	486	5976	39	3	4%	23:59:51
7	endofthelinebbs.peers.news.panix.com	7273	481	6492	1	0	6%	23:59:47
8	newsfeed.bofh.team	8100	316	6326	0	0	3%	23:59:51
9	news.bbs.nz	12813	249	6181	537	86	1%	23:59:50
10	news.hispagatos.org	8271	208	6583	141	0	2%	23:59:51
11	news.nk.ca	9547	207	5985	663	11	2%	23:59:51
12	nntp.comgw.net	7351	196	6072	132	0	2%	23:59:44
13	news.corradoroberto.it	990	182	512	0	0	18%	22:39:38
14	i2pn.org	7770	170	6456	96	47	2%	23:59:43
15	news.tnetconsulting.net	7867	115	6543	314	2	1%	23:59:51
16	news.weretis.net	7383	113	6693	170	0	1%	23:59:51
17	news.quux.org	5494	93	4374	660	0	1%	23:59:16
18	newsfeed.xs3.de	8655	74	6418	248	0	0%	23:59:03
19	news.samoylyk.net	9765	40	6609	203	204	0%	23:59:51
20	usenet.blueworldhosting.com	5896	36	5735	0	0	0%	23:59:15
21	ddt.demos.su	127	33	76	0	0	25%	10:16:49
22	news.chmurka.net	4200	18	2874	0	0	0%	23:59:46
23	feed.opticnetworks.net	0	0	0	0	6978	0%	23:59:51
24	photonic.trudheim.com	0	0	0	0	6977	0%	23:59:51
TOTAL: 24		161354	10855	111664	3843	14308	6%	560:49:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	11.5 MB	1.5 MB	13.0 MB	0.2 KB/s	3.5 KB	23:59:51
2	news.netfront.net	8.8 MB	0.0 KB	8.8 MB	0.1 KB/s	3.8 KB	23:59:16
3	news.furie.org.uk	3.4 MB	56.8 KB	3.5 MB	0.0 KB/s	4.9 KB	23:59:51
4	usenet.network	2.0 MB	103.0 KB	2.1 MB	0.0 KB/s	4.1 KB	23:59:51
5	endofthelinebbs.peers.news.panix.com	1.8 MB	2.3 KB	1.8 MB	0.0 KB/s	3.9 KB	23:59:47
6	newsfeed.bofh.team	1.7 MB	0.0 KB	1.7 MB	0.0 KB/s	5.4 KB	23:59:51
7	news.nntp4.net	1.6 MB	1.4 MB	2.9 MB	0.0 KB/s	4.3 KB	23:59:51
8	usenet.goja.nl.eu.org	1.2 MB	1.3 MB	2.5 MB	0.0 KB/s	3.3 KB	23:59:05
9	news.quux.org	1.0 MB	2.1 MB	3.1 MB	0.0 KB/s	4.2 KB	23:59:16
10	news.bbs.nz	823.6 KB	2.2 MB	3.0 MB	0.0 KB/s	3.9 KB	23:59:50
11	news.hispagatos.org	713.5 KB	1.2 MB	1.9 MB	0.0 KB/s	5.6 KB	23:59:51
12	news.tnetconsulting.net	628.8 KB	1.1 MB	1.7 MB	0.0 KB/s	4.1 KB	23:59:51
13	ddt.demos.su	613.8 KB	0.0 KB	613.8 KB	0.0 KB/s	18.6 KB	10:16:49
14	nntp.comgw.net	537.1 KB	1.2 MB	1.7 MB	0.0 KB/s	5.3 KB	23:59:44
15	news.weretis.net	524.1 KB	1.3 MB	1.8 MB	0.0 KB/s	6.5 KB	23:59:51
16	news.corradoroberto.it	471.1 KB	0.0 KB	471.1 KB	0.0 KB/s	2.6 KB	22:39:38
17	news.nk.ca	445.1 KB	2.6 MB	3.1 MB	0.0 KB/s	3.6 KB	23:59:51
18	news.samoylyk.net	434.0 KB	818.8 KB	1.2 MB	0.0 KB/s	5.2 KB	23:59:51
19	i2pn.org	419.7 KB	379.2 KB	798.9 KB	0.0 KB/s	3.0 KB	23:59:43
20	newsfeed.xs3.de	352.1 KB	1.4 MB	1.7 MB	0.0 KB/s	5.4 KB	23:59:03
21	usenet.blueworldhosting.com	87.9 KB	0.0 KB	87.9 KB	0.0 KB/s	2.4 KB	23:59:15
22	news.chmurka.net	48.3 KB	0.0 KB	48.3 KB	0.0 KB/s	2.7 KB	23:59:46
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
24	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
TOTAL: 24		38.9 MB	18.6 MB	57.5 MB	0.0 KB/s	4.0 KB	560:49:24

NNRP readership statistics:

System		Conn	Elapsed
1	104.156.155.30	95	00:00:41
2	152.32.226.102	3	00:00:10
3	198.235.24.36	1	00:00:10
4	205.210.31.221	1	00:00:00
5	205.210.31.57	1	00:00:10
6	91.238.181.35	1	00:00:00
7	champion.monitoring.internet-measurement.com	1	00:00:01
8	localhost	1	00:12:35
TOTAL: 8		104	00:13:50

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	102	00:01:12
2	*.monitoring.internet-measurement.com	1	00:00:01
3	?	1	00:12:35
TOTAL: 3		104	00:13: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
localhost	180.209	371.291	0.075	00:12:35
104.156.155.30	1.333	1.521	0.000	00:00:41
152.32.226.102	0.019	0.039	0.000	00:00:10
198.235.24.36	0.009	0.022	0.000	00:00:10
205.210.31.57	0.006	0.011	0.000	00:00:10
champion.monitoring.internet-measurement.com	0.006	0.011	0.000	00:00:01
205.210.31.221	0.003	0.014	0.000	00:00:00
91.238.181.35	0.007	0.014	0.000	00:00:00
TOTAL: 8	181.592	372.923	0.075	00:13:50

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

System	Conn
152.32.226.102	2
91.238.181.35	1
TOTAL: 2	3

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

Command	Count
#003	1
#026#003#001#001#027#001	1
À#024	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
104.156.155.30	1	1
91.238.181.35	1	1
TOTAL: 2	2	3

Daily Usenet Report

Nov 29 00:01:02 -- Nov 30 00:01:02