Daily Usenet Report: Nov 2 00:01:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Nov 2 08:13:50 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Nov 3 00:00:01 newsfeed nnrpd[3453725]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13938	33.3%	4.4 MB	50.8%
nnrpd	13677	32.7%	1.9 MB	21.5%
innd	8858	21.2%	1.3 MB	15.0%
innfeed	4431	10.6%	821.8 KB	9.3%
nocem	541	1.3%	233.8 KB	2.6%
cnfsstat	432	1.0%	66.2 KB	0.7%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	41878	100.0%	8.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	132565	65.7%
Negative hits	59003	29.3%
Do not exist	9772	4.8%
Cache misses	281	0.1%
TOTAL: 4	201621	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.788	0.0%	4560	0.000	0.611	11.833
article cleanup	00:00:00.126	0.0%	9733	0.000	0.013	0.071
article logging	00:00:00.547	0.0%	9799	0.000	0.056	0.855
article parse	00:00:00.189	0.0%	14169	0.000	0.013	0.061
article write	00:00:02.405	0.0%	7966	0.000	0.302	2.885
artlog/artcncl	00:00:00.101	0.0%	4124	0.000	0.024	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.576	0.0%	200820	0.000	0.003	0.200
hisgrep/artcncl	00:00:01.178	0.0%	4492	0.000	0.262	11.833
hishave/artcncl	00:00:00.635	0.0%	4543	0.000	0.140	3.621
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.381	0.2%	197951	0.103	0.790	6.009
history sync	00:00:00.081	0.0%	404	0.000	0.200	1.000
history write	00:02:11.066	0.2%	9730	3.244	13.470	102.262
hiswrite/artcncl	00:00:00.321	0.0%	82	2.800	3.915	4.246
hiswrite/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:44:06.345	98.8%	199591	76.398	428.107	1398.650
nntp read	00:00:04.509	0.0%	202006	0.003	0.022	0.603
overview write	00:10:27.032	0.7%	7966	0.513	78.714	9983.800
perl filter	00:00:30.567	0.0%	9730	1.507	3.142	24.828
python filter	00:00:01.956	0.0%	9730	0.000	0.201	5.178
site send	00:00:01.236	0.0%	16217	0.000	0.076	0.748
TOTAL: 24:01:07.628	24:00:08.039	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	8489	0.000	0.003	0.061
article prepare	00:00:00.015	0.0%	61298	0.000	0.000	0.011
article read	00:00:00.785	0.0%	5660	0.000	0.139	0.455
backlog stats	00:00:00.486	0.0%	312372	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.768	0.0%	212520	0.003	0.013	0.046
data write	00:00:03.545	0.0%	204720	0.006	0.017	0.030
idle	23:57:31.916	100.0%	312372	96.061	276.119	1114.811
status file	00:00:00.461	0.0%	389	0.000	1.185	13.000
TOTAL: 23:57:53.010	23:57:40.000	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:58.647	72.0%	8183	0.250	227.135	303340.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.052	0.2%	95111	0.000	0.043	0.333
TOTAL: 00:43:00.148	00:31:02.699	72.2%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	2	14929	3056	11300	573	20%	47:04:30
2	glockenspiel.complete.org	2	10642	1252	9387	3	11%	39:34:34
3	usenet.blueworldhosting.com	718	9494	1165	7745	584	12%	23:56:14
4	news.corradoroberto.it	3	1231	446	785	0	36%	24:48:49
5	i2pn.org	2	7838	411	7278	149	5%	23:59:45
6	newsfeed.bofh.team	2	9338	386	8875	77	4%	24:02:57
7	nntp.comgw.net	1	8960	289	8560	111	3%	23:59:57
8	endofthelinebbs.peers.news.panix.com	26	8389	198	8133	58	2%	24:04:32
9	usenet.goja.nl.eu.org	11	8702	148	8515	39	1%	24:02:41
10	usenet.network	2	6152	133	6017	2	2%	23:59:30
11	194.87.241.33	48	247	123	124	0	49%	14:51:09
12	news.netfront.net	2	11738	91	11450	197	0%	35:21:32
13	endoftheline-out.news.chmurka.net	1	4233	83	4150	0	1%	23:59:56
14	news.nntp4.net	2	7132	75	7056	1	1%	23:59:37
15	newsfeed.xs3.de	2	6800	56	6744	0	0%	23:59:41
16	news.samoylyk.net	1	7380	41	7338	1	0%	23:39:04
17	news.hispagatos.org	10	8935	39	8865	31	0%	24:50:38
18	outgoing.news.lysator.liu.se	2	4152	18	4131	3	0%	23:59:55
19	news.furie.org.uk	2	3599	18	3579	2	0%	23:59:56
20	news.bbs.nz	1	7191	9	7182	0	0%	23:59:38
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:03
22	localhost	27	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:13
TOTAL: 23		870	147082	8037	137214	1831	5%	522:44:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	9.3 MB	5.0 KB	920.2 KB	10.2 MB	91%	2.9 KB
2	glockenspiel.complete.org	4.1 MB	0.0 KB	2.2 KB	4.1 MB	99%	3.4 KB
3	news.corradoroberto.it	3.2 MB	0.0 KB	0.0 KB	3.2 MB	100%	7.3 KB
4	usenet.blueworldhosting.com	2.4 MB	0.0 KB	1012.7 KB	3.4 MB	70%	2.0 KB
5	usenet.network	1.3 MB	0.0 KB	1.5 KB	1.3 MB	99%	9.6 KB
6	i2pn.org	1.1 MB	0.0 KB	217.0 KB	1.3 MB	83%	2.3 KB
7	newsfeed.bofh.team	884.4 KB	0.0 KB	123.7 KB	1008.1 KB	87%	2.2 KB
8	nntp.comgw.net	824.3 KB	0.0 KB	192.7 KB	1017.0 KB	81%	2.5 KB
9	endofthelinebbs.peers.news.panix.com	596.8 KB	0.0 KB	64.4 KB	661.2 KB	90%	2.6 KB
10	194.87.241.33	398.9 KB	0.0 KB	0.0 KB	398.9 KB	100%	3.2 KB
11	usenet.goja.nl.eu.org	382.7 KB	0.0 KB	53.1 KB	435.9 KB	87%	2.3 KB
12	endoftheline-out.news.chmurka.net	271.5 KB	0.0 KB	0.0 KB	271.5 KB	100%	3.3 KB
13	news.nntp4.net	198.3 KB	0.0 KB	1.3 KB	199.6 KB	99%	2.6 KB
14	news.samoylyk.net	170.3 KB	0.0 KB	1.1 KB	171.5 KB	99%	4.1 KB
15	news.netfront.net	170.3 KB	0.0 KB	601.4 KB	771.7 KB	22%	2.7 KB
16	newsfeed.xs3.de	147.5 KB	0.0 KB	0.0 KB	147.5 KB	100%	2.6 KB
17	news.hispagatos.org	84.3 KB	5.0 KB	50.9 KB	140.2 KB	60%	2.0 KB
18	news.furie.org.uk	70.7 KB	5.0 KB	0.7 KB	76.4 KB	92%	3.8 KB
19	outgoing.news.lysator.liu.se	67.3 KB	0.0 KB	2.6 KB	69.9 KB	96%	3.3 KB
20	news.bbs.nz	26.2 KB	0.0 KB	0.0 KB	26.2 KB	100%	2.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		25.6 MB	15.0 KB	3.2 MB	28.7 MB	88%	3.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 2 00:01:02 - 00:59:59	174	2.2%	0.05	999.3 KB	3.9%	0.28
Nov 2 01:00:00 - 01:59:59	237	3.0%	0.07	828.9 KB	3.2%	0.23
Nov 2 02:00:00 - 02:59:59	282	3.5%	0.08	936.5 KB	3.6%	0.26
Nov 2 03:00:00 - 03:59:59	313	3.9%	0.09	817.2 KB	3.2%	0.23
Nov 2 04:00:00 - 04:59:59	450	5.6%	0.12	1.0 MB	4.1%	0.29
Nov 2 05:00:00 - 05:59:59	511	6.4%	0.14	1.3 MB	5.0%	0.36
Nov 2 06:00:00 - 06:59:59	354	4.4%	0.10	1.0 MB	4.1%	0.29
Nov 2 07:00:00 - 07:59:59	287	3.6%	0.08	1.4 MB	5.4%	0.39
Nov 2 08:00:00 - 08:59:59	354	4.4%	0.10	1.2 MB	4.8%	0.34
Nov 2 09:00:00 - 09:59:59	424	5.3%	0.12	1.1 MB	4.6%	0.33
Nov 2 10:00:00 - 10:59:59	366	4.6%	0.10	1.2 MB	4.9%	0.35
Nov 2 11:00:00 - 11:59:59	445	5.6%	0.12	1.2 MB	4.9%	0.35
Nov 2 12:00:00 - 12:59:59	319	4.0%	0.09	1.1 MB	4.2%	0.30
Nov 2 13:00:00 - 13:59:59	515	6.5%	0.14	1.5 MB	5.9%	0.42
Nov 2 14:00:00 - 14:59:59	370	4.6%	0.10	1.2 MB	4.8%	0.34
Nov 2 15:00:00 - 15:59:59	465	5.8%	0.13	1.3 MB	5.4%	0.38
Nov 2 16:00:00 - 16:59:59	480	6.0%	0.13	1.6 MB	6.5%	0.46
Nov 2 17:00:00 - 17:59:59	401	5.0%	0.11	1.2 MB	4.7%	0.33
Nov 2 18:00:00 - 18:59:59	177	2.2%	0.05	657.1 KB	2.6%	0.18
Nov 2 19:00:00 - 19:59:59	263	3.3%	0.07	1.0 MB	4.0%	0.28
Nov 2 20:00:00 - 20:59:59	162	2.0%	0.04	1.0 MB	4.1%	0.29
Nov 2 21:00:00 - 21:59:59	190	2.4%	0.05	468.5 KB	1.8%	0.13
Nov 2 22:00:00 - 22:59:59	177	2.2%	0.05	510.9 KB	2.0%	0.14
Nov 2 23:00:00 - 23:59:59	249	3.1%	0.07	608.7 KB	2.4%	0.17
Nov 3 00:00:00 - 00:01:02	6	0.1%	0.10	13.5 KB	0.1%	0.22
TOTAL: 24:00:00	7971	100.0%	0.09	25.1 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	99.4 GB	6.5%	0	0.49	37693.93
CYCTXT1	TEXT	5.0 GB	3.9 GB	78.3%	0	0.03	2178.24
TOTAL: 3	-	3077.0 GB	103.3 GB	3.4%	1	0.34	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	endofthelinebbs-out.news.weretis.net	606	0	1	605
2	usenet.blueworldhosting.com	600	47	0	553
3	i2pn.org	148	0	0	148
4	news.netfront.net	134	0	0	134
5	nntp.comgw.net	109	0	0	109
6	endofthelinebbs.peers.news.panix.com	78	64	0	14
7	newsfeed.bofh.team	78	1	0	77
8	usenet.goja.nl.eu.org	36	0	0	36
9	news.hispagatos.org	30	20	1	9
10	glockenspiel.complete.org	4	0	0	4
11	outgoing.news.lysator.liu.se	3	0	0	3
12	usenet.network	2	0	0	2
13	news.furie.org.uk	1	0	1	0
14	news.nntp4.net	1	0	0	1
15	news.samoylyk.net	1	0	0	1
TOTAL: 15		1831	132	3	1696

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.sci.announce	109
hispagatos.mastodon.tag.hacking	10
hispagatos.hacking.ctf	5
hispagatos.mastodon.tag.hackers	3
news.test_messages	2
alive.music.jazz	1
hispagatos.hacking.news	1
hispagatos.mastodon.tag.hackthebox	1
TOTAL: 8	132

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1038
EMP (phr path)	322
Too many newsgroups	138
EMP (md5)	93
User-issued cancel	25
Too many newsgroups (meow)	8
HTML Multipart	3
EMP (fsl)	2
Too many newsgroups (low_xpost)	1
TOTAL: 9	1630

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	158	3371	0	3371
nocem@eternal-september.org (bot)	94	1169	0	1169
nocem@usenet.ovh (faqfr)	1	1	0	1
robot@pasdenom.info (spam)	1	1	0	1
i2pn2-nocem@i2pn2.org (multipost)	14	0	16	16
robot@pasdenom.info (spam3)	7	0	16	16
i2pn2-nocem@i2pn2.org (rmp)	6	0	10	10
robot@pasdenom.info (spam2)	5	0	5	5
TOTAL: 8	286	4542	47	4589

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	36
	usenet.blueworldhosting.com	16
	endofthelinebbs.peers.news.panix.com	7
	usenet.goja.nl.eu.org	4
	nntp.comgw.net	1
	newsfeed.bofh.team	1
	glockenspiel.complete.org	1
	TOTAL: 7	66
TOTAL: 1		66

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	8510	4147	3509	297	0	0	48%	23:59:25
2	news.netfront.net	12919	2174	5724	14	0	47	16%	23:59:41
3	photonic.trudheim.com	9462	719	5465	1786	1	63	7%	23:59:38
4	news.furie.org.uk	4534	634	2631	5	0	3	13%	23:56:56
5	endofthelinebbs.peers.news.panix.com	8286	476	7491	2	0	4	5%	23:59:50
6	usenet.goja.nl.eu.org	9265	408	7212	123	0	3	4%	23:59:50
7	news.bbs.nz	14408	363	7072	519	0	105	2%	23:57:36
8	news.nk.ca	10449	221	7079	464	0	18	2%	23:59:50
9	news.hispagatos.org	9609	209	7581	142	0	22	2%	23:59:51
10	newsfeed.bofh.team	8945	203	7352	0	0	5	2%	23:58:58
11	news.corradoroberto.it	1189	155	648	0	0	6	13%	23:47:17
12	news.nntp4.net	9466	152	7686	35	0	6	1%	23:59:51
13	usenet.network	10081	140	7337	59	0	11	1%	23:59:45
14	nntp.comgw.net	8760	128	7496	56	0	8	1%	23:59:30
15	news.tnetconsulting.net	9050	125	7711	130	0	38	1%	23:59:44
16	i2pn.org	8807	97	7339	79	0	51	1%	23:59:12
17	news.weretis.net	8267	91	7782	95	0	30	1%	23:59:51
18	news.quux.org	6584	73	5572	534	0	33	1%	23:59:03
19	newsfeed.xs3.de	9714	58	7677	169	0	3	0%	23:59:50
20	news.chmurka.net	5478	32	4177	0	0	3	0%	23:59:51
21	ddt.demos.su	130	24	83	0	0	0	18%	09:54:40
22	news.samoylyk.net	10997	14	7699	187	0	66	0%	23:59:50
23	usenet.blueworldhosting.com	7035	2	6509	0	0	0	0%	23:59:29
24	feed.opticnetworks.net	0	0	0	0	0	7971	0%	23:59:51
TOTAL: 24		191945	10645	138832	4696	1	8496	5%	561:29:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	12.7 MB	1.7 MB	14.4 MB	0.2 KB/s	3.3 KB	23:59:25
2	news.netfront.net	7.5 MB	120.8 KB	7.6 MB	0.1 KB/s	3.6 KB	23:59:41
3	photonic.trudheim.com	4.2 MB	4.3 MB	8.5 MB	0.1 KB/s	3.5 KB	23:59:38
4	news.furie.org.uk	3.9 MB	87.0 KB	4.0 MB	0.0 KB/s	6.4 KB	23:56:56
5	endofthelinebbs.peers.news.panix.com	1.8 MB	2.4 KB	1.8 MB	0.0 KB/s	3.9 KB	23:59:50
6	newsfeed.bofh.team	1.4 MB	0.0 KB	1.4 MB	0.0 KB/s	7.2 KB	23:58:58
7	news.hispagatos.org	1.1 MB	875.7 KB	2.0 MB	0.0 KB/s	5.8 KB	23:59:51
8	news.bbs.nz	1.1 MB	2.1 MB	3.2 MB	0.0 KB/s	3.7 KB	23:57:36
9	usenet.goja.nl.eu.org	1004.5 KB	881.4 KB	1.8 MB	0.0 KB/s	3.6 KB	23:59:50
10	news.nntp4.net	976.1 KB	181.7 KB	1.1 MB	0.0 KB/s	6.2 KB	23:59:51
11	nntp.comgw.net	937.3 KB	409.6 KB	1.3 MB	0.0 KB/s	7.3 KB	23:59:30
12	news.quux.org	878.8 KB	1.7 MB	2.6 MB	0.0 KB/s	4.3 KB	23:59:03
13	news.tnetconsulting.net	810.5 KB	682.5 KB	1.5 MB	0.0 KB/s	5.9 KB	23:59:44
14	newsfeed.xs3.de	733.1 KB	627.2 KB	1.3 MB	0.0 KB/s	6.0 KB	23:59:50
15	usenet.network	633.7 KB	75.2 KB	708.9 KB	0.0 KB/s	3.6 KB	23:59:45
16	news.weretis.net	525.5 KB	1.1 MB	1.6 MB	0.0 KB/s	8.9 KB	23:59:51
17	news.corradoroberto.it	472.2 KB	0.0 KB	472.2 KB	0.0 KB/s	3.0 KB	23:47:17
18	news.nk.ca	445.2 KB	1.7 MB	2.2 MB	0.0 KB/s	3.2 KB	23:59:50
19	i2pn.org	318.6 KB	235.8 KB	554.3 KB	0.0 KB/s	3.1 KB	23:59:12
20	news.chmurka.net	52.4 KB	0.0 KB	52.4 KB	0.0 KB/s	1.6 KB	23:59:51
21	ddt.demos.su	51.8 KB	0.0 KB	51.8 KB	0.0 KB/s	2.2 KB	09:54:40
22	news.samoylyk.net	29.8 KB	767.4 KB	797.2 KB	0.0 KB/s	4.0 KB	23:59:50
23	usenet.blueworldhosting.com	10.0 KB	0.0 KB	10.0 KB	0.0 KB/s	5.0 KB	23:59:29
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
TOTAL: 24		41.4 MB	17.5 MB	58.9 MB	0.0 KB/s	3.9 KB	561:29:19

NNRP readership statistics:

System		Conn	Elapsed
1	2001:470:28:a8:3ad5:47ff:fe02:c2fd	2727	00:21:19
2	147.45.112.158	1	00:00:00
3	198.235.24.215	1	00:00:09
4	205.210.31.102	1	00:00:08
5	azpdsg12.stretchoid.com	1	00:00:00
6	freeq.furie.org.uk	1	00:10:06
7	localhost	1	00:11:08
8	merciful.monitoring.internet-measurement.com	1	00:00:01
9	server-0-2.survey.inspici.com	1	00:00:03
TOTAL: 9		2735	00:42:59

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	2728	00:32:28
2	unresolved	3	00:00:18
3	*.furie.org.uk	1	00:10:06
4	*.monitoring.internet-measurement.com	1	00:00:01
5	*.stretchoid.com	1	00:00:00
6	*.survey.inspici.com	1	00:00:03
TOTAL: 6		2735	00:42:59

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
2001:470:28:a8:3ad5:47ff:fe02:c2fd	9.787	36.026	0.000	00:21:19
localhost	180.021	371.478	0.074	00:11:08
freeq.furie.org.uk	0.003	0.014	0.000	00:10:06
198.235.24.215	0.011	0.005	0.000	00:00:09
205.210.31.102	0.004	0.012	0.000	00:00:08
server-0-2.survey.inspici.com	0.004	0.012	0.000	00:00:03
merciful.monitoring.internet-measurement.com	0.004	0.012	0.000	00:00:01
147.45.112.158	0.004	0.012	0.000	00:00:00
azpdsg12.stretchoid.com	0.009	0.009	0.000	00:00:00
TOTAL: 9	189.847	407.580	0.074	00:42:59

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

System	Conn
147.45.112.158	1
azpdsg12.stretchoid.com	1
TOTAL: 2	2

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

Command	Count
#003	1
MGLNDD_144.172.126.95_119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.158	1	1
TOTAL: 1	1	3

Daily Usenet Report

Nov 2 00:01:02 -- Nov 3 00:01:02