Daily Usenet Report: Oct 26 00:01:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Oct 26 08:11:22 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Oct 26 14:13:52 newsfeed nnrpd[3099970]: 47.186.47.228 auth: program exited with status 8
Oct 27 00:00:01 newsfeed nnrpd[3253238]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	14024	39.2%	1.9 MB	25.8%
innd	8685	24.3%	1.2 MB	17.1%
inn	8198	22.9%	3.3 MB	44.8%
innfeed	4406	12.3%	850.1 KB	11.4%
cnfsstat	432	1.2%	65.0 KB	0.9%
nocem	22	0.1%	2.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	35768	100.0%	7.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	107372	65.6%
Negative hits	48152	29.4%
Do not exist	8064	4.9%
Cache misses	16	0.0%
TOTAL: 4	163604	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.024	0.0%	11	0.000	2.182	9.000
article cleanup	00:00:00.087	0.0%	8140	0.000	0.011	0.250
article logging	00:00:00.444	0.0%	8202	0.000	0.054	0.911
article parse	00:00:00.175	0.0%	11217	0.000	0.016	0.065
article write	00:00:02.150	0.0%	6615	0.000	0.325	3.283
artlog/artcncl	00:00:00.000	0.0%	3	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:01.032	0.0%	168165	0.000	0.006	0.157
hisgrep/artcncl	00:00:00.020	0.0%	13	0.000	1.538	3.500
hishave/artcncl	00:00:00.000	0.0%	3	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.128	0.2%	165210	0.112	0.945	5.470
history sync	00:00:00.079	0.0%	407	0.000	0.194	1.000
history write	00:01:54.845	0.1%	8141	3.216	14.107	145.417
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:48:57.569	99.0%	168357	85.035	509.260	1926.597
nntp read	00:00:02.985	0.0%	169266	0.000	0.018	0.098
overview write	00:08:17.069	0.6%	6615	0.727	75.143	8675.966
perl filter	00:00:28.125	0.0%	8140	1.581	3.455	23.118
python filter	00:00:01.325	0.0%	8140	0.000	0.163	3.510
site send	00:00:01.033	0.0%	13250	0.000	0.078	0.233
TOTAL: 24:03:21.261	24:02:23.090	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.018	0.0%	7327	0.000	0.002	0.036
article prepare	00:00:00.008	0.0%	56996	0.000	0.000	0.003
article read	00:00:00.698	0.0%	5350	0.000	0.130	0.600
backlog stats	00:00:00.399	0.0%	263064	0.000	0.002	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.165	0.0%	175648	0.003	0.012	0.038
data write	00:00:03.108	0.0%	170717	0.005	0.018	0.041
idle	23:58:07.029	100.0%	263064	132.657	328.008	1551.297
status file	00:00:00.462	0.0%	390	0.909	1.185	8.000
TOTAL: 23:58:27.472	23:58:13.887	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:31:18.018	70.7%	8338	0.250	225.236	302786.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.776	0.2%	95568	0.000	0.050	0.500
TOTAL: 00:44:16.409	00:31:22.794	70.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	3	12344	2814	9209	321	22%	45:42:52
2	usenet.blueworldhosting.com	721	7913	875	6479	559	11%	23:51:04
3	usenet.goja.nl.eu.org	10	7496	649	6707	140	8%	24:02:13
4	nntp.comgw.net	2	7839	492	7175	172	6%	24:59:35
5	i2pn.org	1	7486	419	6918	149	5%	23:59:52
6	news.corradoroberto.it	12	905	310	595	0	34%	23:51:17
7	endoftheline-out.news.chmurka.net	1	3636	129	3506	1	3%	24:59:55
8	194.87.241.33	40	226	124	102	0	54%	13:59:16
9	endofthelinebbs.peers.news.panix.com	26	7008	115	6887	6	1%	23:56:54
10	glockenspiel.complete.org	2	5217	113	5103	1	2%	24:02:31
11	newsfeed.xs3.de	1	5636	104	5521	11	1%	23:59:56
12	usenet.network	2	5420	89	5329	2	1%	23:59:54
13	news.netfront.net	2	8853	78	8591	184	0%	36:26:15
14	news.samoylyk.net	2	6355	66	6288	1	1%	24:33:18
15	news.hispagatos.org	30	7927	39	7864	24	0%	26:19:40
16	news.nntp4.net	2	6037	34	6003	0	0%	24:59:33
17	newsfeed.bofh.team	2	7921	33	7875	13	0%	23:59:32
18	news.furie.org.uk	2	3265	16	3247	2	0%	23:59:45
19	news.bbs.nz	1	6268	4	6264	0	0%	23:59:33
20	outgoing.news.lysator.liu.se	1	3642	0	3642	0	0%	24:59:57
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:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:19
TOTAL: 23		893	121394	6503	113305	1586	5%	511:13:15

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	8.7 MB	453.9 KB	9.1 MB	95%	3.0 KB
2	usenet.blueworldhosting.com	2.3 MB	1.1 MB	3.3 MB	67%	2.4 KB
3	news.corradoroberto.it	1.8 MB	0.0 KB	1.8 MB	100%	5.9 KB
4	usenet.goja.nl.eu.org	1.6 MB	184.5 KB	1.8 MB	90%	2.4 KB
5	usenet.network	1.6 MB	5.5 KB	1.6 MB	99%	17.9 KB
6	nntp.comgw.net	1.5 MB	388.8 KB	1.9 MB	79%	2.9 KB
7	i2pn.org	1.1 MB	196.2 KB	1.3 MB	85%	2.4 KB
8	glockenspiel.complete.org	816.9 KB	0.9 KB	817.8 KB	99%	7.2 KB
9	endoftheline-out.news.chmurka.net	471.0 KB	0.6 KB	471.6 KB	99%	3.6 KB
10	194.87.241.33	362.0 KB	0.0 KB	362.0 KB	100%	2.9 KB
11	newsfeed.xs3.de	270.2 KB	32.1 KB	302.3 KB	89%	2.6 KB
12	endofthelinebbs.peers.news.panix.com	237.5 KB	19.0 KB	256.5 KB	92%	2.1 KB
13	news.netfront.net	237.3 KB	691.6 KB	928.9 KB	25%	3.5 KB
14	news.samoylyk.net	219.3 KB	1.5 KB	220.8 KB	99%	3.3 KB
15	news.nntp4.net	124.6 KB	0.0 KB	124.6 KB	100%	3.7 KB
16	news.hispagatos.org	100.9 KB	36.8 KB	137.7 KB	73%	2.2 KB
17	news.furie.org.uk	85.3 KB	1.6 KB	86.9 KB	98%	4.8 KB
18	newsfeed.bofh.team	74.9 KB	34.1 KB	109.0 KB	68%	2.4 KB
19	news.bbs.nz	7.5 KB	0.0 KB	7.5 KB	100%	1.9 KB
20	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0%	0
21	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
22	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0%	0
23	outgoing.news.lysator.liu.se	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 23		21.5 MB	3.1 MB	24.6 MB	87%	3.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 26 00:01:02 - 00:59:59	154	2.3%	0.04	458.6 KB	2.0%	0.13
Oct 26 01:00:00 - 01:59:59	245	3.7%	0.07	668.5 KB	3.0%	0.19
Oct 26 02:00:00 - 02:59:59	273	4.1%	0.08	1018.1 KB	4.5%	0.28
Oct 26 03:00:00 - 03:59:59	364	5.5%	0.10	972.7 KB	4.3%	0.27
Oct 26 04:00:00 - 04:59:59	336	5.1%	0.09	1008.4 KB	4.5%	0.28
Oct 26 05:00:00 - 05:59:59	364	5.5%	0.10	1.4 MB	6.2%	0.38
Oct 26 06:00:00 - 06:59:59	302	4.6%	0.08	847.1 KB	3.8%	0.24
Oct 26 07:00:00 - 07:59:59	318	4.8%	0.09	1015.3 KB	4.5%	0.28
Oct 26 08:00:00 - 08:59:59	355	5.4%	0.10	1014.9 KB	4.5%	0.28
Oct 26 09:00:00 - 09:59:59	456	6.9%	0.13	1.2 MB	5.5%	0.34
Oct 26 10:00:00 - 10:59:59	365	5.5%	0.10	1.4 MB	6.5%	0.41
Oct 26 11:00:00 - 11:59:59	373	5.6%	0.10	1.5 MB	6.8%	0.42
Oct 26 12:00:00 - 12:59:59	298	4.5%	0.08	1.1 MB	4.9%	0.31
Oct 26 13:00:00 - 13:59:59	333	5.0%	0.09	1.0 MB	4.7%	0.29
Oct 26 14:00:00 - 14:59:59	320	4.8%	0.09	1006.9 KB	4.5%	0.28
Oct 26 15:00:00 - 15:59:59	258	3.9%	0.07	939.0 KB	4.2%	0.26
Oct 26 16:00:00 - 16:59:59	260	3.9%	0.07	849.7 KB	3.8%	0.24
Oct 26 17:00:00 - 17:59:59	341	5.2%	0.09	988.8 KB	4.4%	0.27
Oct 26 18:00:00 - 18:59:59	187	2.8%	0.05	747.6 KB	3.3%	0.21
Oct 26 19:00:00 - 19:59:59	173	2.6%	0.05	925.6 KB	4.1%	0.26
Oct 26 20:00:00 - 20:59:59	169	2.6%	0.05	690.7 KB	3.1%	0.19
Oct 26 21:00:00 - 21:59:59	128	1.9%	0.04	415.9 KB	1.9%	0.12
Oct 26 22:00:00 - 22:59:59	92	1.4%	0.03	511.3 KB	2.3%	0.14
Oct 26 23:00:00 - 23:59:59	135	2.0%	0.04	566.6 KB	2.5%	0.16
Oct 27 00:00:00 - 00:01:02	8	0.1%	0.13	16.9 KB	0.1%	0.27
TOTAL: 24:00:00	6607	100.0%	0.08	21.9 MB	100.0%	0.26

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.1 GB	6.5%	0	0.41	45064.19
CYCTXT1	TEXT	5.0 GB	3.9 GB	78.2%	0	0.03	2321.43
TOTAL: 3	-	3077.0 GB	103.0 GB	3.3%	1	0.28	-

Sites sending bad articles:

Server		Total	Group	Other
1	usenet.blueworldhosting.com	575	3	572
2	endofthelinebbs-out.news.weretis.net	378	0	378
3	nntp.comgw.net	170	0	170
4	i2pn.org	150	0	150
5	usenet.goja.nl.eu.org	141	0	141
6	news.netfront.net	107	0	107
7	news.hispagatos.org	28	23	5
8	newsfeed.bofh.team	13	0	13
9	newsfeed.xs3.de	10	0	10
10	endofthelinebbs.peers.news.panix.com	7	1	6
11	endoftheline-out.news.chmurka.net	2	0	2
12	news.furie.org.uk	2	0	2
13	news.samoylyk.net	2	0	2
14	usenet.network	2	0	2
15	news.bbs.nz	1	0	1
TOTAL: 15		1588	27	1561

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	16
hispagatos.mastodon.tag.hacking	5
soc.culture.russia	3
hispagatos.hacking.news	2
betsytomasso3gmail.com	1
TOTAL: 5	27

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	859
EMP (phr path)	342
EMP (md5)	139
Too many newsgroups	129
EMP (fsl)	16
User-issued cancel	12
Subject (LSDRhdGD0ZHQsiDQsiDQttC+0L/R)	1
TOTAL: 7	1498

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
robot@pasdenom.info (spam)	1	1	0	1
robot@pasdenom.info (spam3)	9	0	19	19
i2pn2-nocem@i2pn2.org (rmp)	8	0	16	16
robot@pasdenom.info (spam2)	2	0	2	2
TOTAL: 4	20	1	37	38

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	37
	usenet.blueworldhosting.com	16
	usenet.goja.nl.eu.org	4
	news.samoylyk.net	1
	nntp.comgw.net	1
	newsfeed.xs3.de	1
	news.netfront.net	1
	endofthelinebbs.peers.news.panix.com	1
	TOTAL: 8	62
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		63

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	6909	3507	2940	160	0	50%	23:59:50
2	news.netfront.net	9718	2992	3580	2	3	30%	23:59:36
3	news.furie.org.uk	3738	1060	1893	8	4	28%	23:57:47
4	photonic.trudheim.com	7883	638	4526	1439	0	8%	23:59:50
5	endofthelinebbs.peers.news.panix.com	6967	436	6161	7	0	6%	23:59:50
6	usenet.goja.nl.eu.org	7597	373	5471	84	0	4%	23:59:33
7	news.bbs.nz	12055	268	5941	392	15	2%	23:59:29
8	news.corradoroberto.it	925	178	459	1	9	19%	22:46:40
9	news.nk.ca	9093	163	6017	376	23	1%	23:59:50
10	newsfeed.bofh.team	7532	156	6289	0	0	2%	23:59:50
11	news.hispagatos.org	7860	149	6334	81	0	1%	23:58:19
12	usenet.network	8420	141	5949	3	0	1%	23:59:50
13	news.nntp4.net	8124	115	6396	11	0	1%	23:59:09
14	nntp.comgw.net	6893	105	5966	56	0	1%	23:59:50
15	news.tnetconsulting.net	7321	96	6388	119	0	1%	23:59:19
16	i2pn.org	6957	96	6043	32	4	1%	23:58:13
17	news.quux.org	5193	83	4416	435	0	1%	23:59:50
18	news.weretis.net	6826	82	6461	63	0	1%	23:59:50
19	news.chmurka.net	4919	47	3126	0	0	0%	23:59:50
20	newsfeed.xs3.de	8210	41	6293	175	0	0%	23:58:52
21	news.samoylyk.net	8719	22	6460	57	671	0%	23:59:50
22	ddt.demos.su	64	13	45	0	0	20%	07:06:24
23	usenet.blueworldhosting.com	5832	3	5487	0	0	0%	23:59:51
24	feed.opticnetworks.net	0	0	0	0	6607	0%	23:59:50
TOTAL: 24		157755	10764	112641	3501	7336	6%	557:41:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	11.9 MB	1.2 MB	13.2 MB	0.2 KB/s	3.7 KB	23:59:50
2	news.netfront.net	10.3 MB	6.9 KB	10.3 MB	0.1 KB/s	3.5 KB	23:59:36
3	news.furie.org.uk	5.2 MB	59.9 KB	5.3 MB	0.1 KB/s	5.1 KB	23:57:47
4	photonic.trudheim.com	3.2 MB	4.3 MB	7.5 MB	0.1 KB/s	3.7 KB	23:59:50
5	endofthelinebbs.peers.news.panix.com	1.9 MB	8.3 KB	1.9 MB	0.0 KB/s	4.4 KB	23:59:50
6	newsfeed.bofh.team	1.3 MB	0.0 KB	1.3 MB	0.0 KB/s	8.4 KB	23:59:50
7	news.quux.org	1.2 MB	1.5 MB	2.6 MB	0.0 KB/s	5.2 KB	23:59:50
8	news.bbs.nz	1.0 MB	2.1 MB	3.1 MB	0.0 KB/s	4.8 KB	23:59:29
9	news.nntp4.net	911.4 KB	166.1 KB	1.1 MB	0.0 KB/s	8.6 KB	23:59:09
10	usenet.goja.nl.eu.org	840.4 KB	1.1 MB	2.0 MB	0.0 KB/s	4.4 KB	23:59:33
11	news.samoylyk.net	798.2 KB	198.1 KB	996.3 KB	0.0 KB/s	12.6 KB	23:59:50
12	ddt.demos.su	772.7 KB	0.0 KB	772.7 KB	0.0 KB/s	59.4 KB	07:06:24
13	news.hispagatos.org	542.2 KB	1.0 MB	1.6 MB	0.0 KB/s	6.9 KB	23:58:19
14	news.corradoroberto.it	527.5 KB	1.0 KB	528.5 KB	0.0 KB/s	3.0 KB	22:46:40
15	usenet.network	503.3 KB	6.9 KB	510.2 KB	0.0 KB/s	3.5 KB	23:59:50
16	news.tnetconsulting.net	462.4 KB	1.0 MB	1.5 MB	0.0 KB/s	7.1 KB	23:59:19
17	news.weretis.net	421.1 KB	1.2 MB	1.6 MB	0.0 KB/s	11.6 KB	23:59:50
18	news.nk.ca	421.1 KB	2.2 MB	2.6 MB	0.0 KB/s	4.9 KB	23:59:50
19	nntp.comgw.net	374.0 KB	1.2 MB	1.6 MB	0.0 KB/s	10.1 KB	23:59:50
20	i2pn.org	331.4 KB	182.7 KB	514.1 KB	0.0 KB/s	4.0 KB	23:58:13
21	newsfeed.xs3.de	270.7 KB	1.4 MB	1.6 MB	0.0 KB/s	7.7 KB	23:58:52
22	news.chmurka.net	154.1 KB	0.0 KB	154.1 KB	0.0 KB/s	3.3 KB	23:59:50
23	usenet.blueworldhosting.com	14.9 KB	0.0 KB	14.9 KB	0.0 KB/s	5.0 KB	23:59:51
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:50
TOTAL: 24		43.2 MB	18.9 MB	62.1 MB	0.0 KB/s	4.5 KB	557:41:12

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	47.186.47.228	1	0	0.0 KB	3	0	0	00:00:00
TOTAL: 1		1	0	0.0 KB	3	0	0	00:44:15

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	?	2776	0	00:33:30
2	unresolved	12	3	00:00:34
3	*.furie.org.uk	1	0	00:10:05
4	*.monitoring.internet-measurement.com	1	0	00:00:01
5	*.survey.inspici.com	1	0	00:00:03
TOTAL: 5		2791	3	00:44:15

NNRP auth users [Top 20]:

User	Conn
getlist	1
nelgin	1
TOTAL: 2	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
2001:470:28:a8:3ad5:47ff:fe02:c2fd	9.859	36.864	0.000	00:21:25
localhost	181.973	369.810	0.083	00:12:04
freeq.furie.org.uk	0.000	0.017	0.000	00:10:05
198.235.24.152	0.008	0.008	0.000	00:00:10
167.94.138.50	0.015	0.050	0.000	00:00:08
147.185.132.141	0.004	0.012	0.000	00:00:07
167.94.138.127	0.024	0.046	0.000	00:00:04
206.168.34.49	0.012	0.050	0.000	00:00:03
server-0-2.survey.inspici.com	0.004	0.013	0.000	00:00:03
unforgettable.monitoring.internet-measurement.com	0.008	0.008	0.000	00:00:01
TOTAL: 10	191.907	406.878	0.083	00:44:15

Curious NNRP server explorers [Top 20]:

System	Conn
2001:470:28:a8:3ad5:47ff:fe02:c2fd	2775
167.94.138.127	3
167.94.138.50	3
206.168.34.49	3
147.185.132.141	1
198.235.24.152	1
freeq.furie.org.uk	1
localhost	1
server-0-2.survey.inspici.com	1
unforgettable.monitoring.internet-measurement.com	1
TOTAL: 10	2790

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

System	Conn
167.94.138.127	1
167.94.138.50	1
206.168.34.49	1
TOTAL: 3	3

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

Command	Count
EHLO www.censys.io	3
TOTAL: 1	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.127	1	2
167.94.138.50	1	2
206.168.34.49	1	2
TOTAL: 3	3	7

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	comp	5	71.4%
2	alt	2	28.6%
TOTAL: 2		7	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	comp.sys.acorn.apps	5
2	alt.fan.states.texas	1
3	alt.music.lyrics	1
TOTAL: 3		7

Daily Usenet Report

Oct 26 00:01:02 -- Oct 27 00:01:02