Daily Usenet Report: Feb 25 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 25 13:47:17 newsfeed nnrpd[3107708]: 47.186.35.85 auth: program exited with status 8
Feb 26 00:00:02 newsfeed nnrpd[3268660]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	10162	38.9%	3.7 MB	60.8%
innd	8205	31.4%	1.2 MB	19.6%
innfeed	4429	17.0%	808.4 KB	12.8%
nnrpd	2836	10.9%	347.0 KB	5.5%
cnfsstat	432	1.7%	75.4 KB	1.2%
nocem	53	0.2%	6.9 KB	0.1%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 7	26118	100.0%	6.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	141536	64.5%
Negative hits	67819	30.9%
Do not exist	10042	4.6%
Cache misses	64	0.0%
TOTAL: 4	219461	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.085	0.0%	31	0.000	2.742	9.000
article cleanup	00:00:00.775	0.0%	10098	0.000	0.077	10.275
article logging	00:00:00.542	0.0%	10154	0.000	0.053	0.475
article parse	00:00:00.255	0.0%	15184	0.000	0.017	0.069
article write	00:00:02.455	0.0%	7392	0.000	0.332	3.256
artlog/artcncl	00:00:00.001	0.0%	11	0.000	0.091	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:02.130	0.0%	211661	0.000	0.010	0.914
hisgrep/artcncl	00:00:00.057	0.0%	38	0.000	1.500	9.000
hishave/artcncl	00:00:00.000	0.0%	11	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:03:05.677	0.2%	220685	0.105	0.841	19.121
history sync	00:00:00.066	0.0%	372	0.000	0.177	1.000
history write	00:02:33.630	0.2%	10083	3.200	15.237	142.909
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:47:05.336	99.0%	203840	115.755	420.061	1104.764
nntp read	00:00:07.969	0.0%	212561	0.003	0.037	4.118
overview write	00:06:40.712	0.5%	7392	0.444	54.209	2351.707
perl filter	00:00:29.943	0.0%	10083	1.207	2.970	15.435
python filter	00:00:01.787	0.0%	10083	0.000	0.177	5.232
site send	00:00:01.231	0.0%	14832	0.008	0.083	0.322
TOTAL: 24:00:56.841	24:00:12.651	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.019	0.0%	8118	0.000	0.002	0.038
article prepare	00:00:00.004	0.0%	46559	0.000	0.000	0.006
article read	00:00:00.670	0.0%	4605	0.000	0.145	1.500
backlog stats	00:00:00.444	0.0%	284358	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.659	0.0%	192287	0.003	0.014	0.050
data write	00:00:03.469	0.0%	186571	0.005	0.019	0.042
idle	23:57:54.736	100.0%	284358	147.807	303.402	856.796
status file	00:00:00.503	0.0%	407	0.333	1.236	21.500
TOTAL: 23:58:15.821	23:58:02.504	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:06:08.626	34.4%	2728	0.750	135.127	10052.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.229	0.4%	84244	0.000	0.050	0.333
TOTAL: 00:17:52.903	00:06:12.855	34.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	4	16001	1790	13761	450	11%	46:31:32
2	glockenspiel.complete.org	3	6376	1545	4809	22	24%	22:15:44
3	usenet.blueworldhosting.com	288	9947	914	8151	882	9%	23:46:03
4	usenet.goja.nl.eu.org	11	9204	526	8460	218	5%	24:23:26
5	usenet.network	2	6848	435	6394	19	6%	23:22:22
6	newsfeed.bofh.team	2	9970	350	9473	147	3%	23:59:29
7	news.corradoroberto.it	9	1315	326	989	0	24%	23:07:22
8	i2pn.org	1	8882	300	8523	59	3%	23:59:54
9	news.nntp4.net	9	8510	220	7996	294	2%	23:58:18
10	news.samoylyk.net	2	7940	177	7708	55	2%	23:59:48
11	endoftheline-out.news.chmurka.net	2	4160	176	3983	1	4%	23:59:34
12	nntp.comgw.net	1	9374	121	9150	103	1%	23:59:57
13	endofthelinebbs.peers.news.panix.com	24	9016	81	8924	11	0%	24:06:25
14	194.87.241.33	48	694	81	392	221	11%	14:41:21
15	newsfeed.xs3.de	1	7019	65	6933	21	0%	23:59:55
16	news.hispagatos.org	6	9442	60	9263	119	0%	24:25:38
17	news.furie.org.uk	2	4297	41	4256	0	0%	23:59:45
18	news.netfront.net	1	7803	24	7727	52	0%	23:19:34
19	news.bbs.nz	2	8295	13	8279	3	0%	23:59:25
20	photonic.trudheim.com	3	1918	5	1912	1	0%	23:48:58
21	outgoing.news.lysator.liu.se	1	5566	0	5563	3	0%	23:59:57
22	localhost	30	0	0	0	0	0%	00:00:01
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		454	152577	7250	142646	2681	4%	514:04:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.1 MB	1.0 KB	937.4 KB	6.0 MB	84%	2.7 KB
2	glockenspiel.complete.org	4.8 MB	0.9 KB	30.8 KB	4.8 MB	99%	3.1 KB
3	usenet.network	4.5 MB	0.9 KB	34.8 KB	4.5 MB	99%	10.2 KB
4	usenet.blueworldhosting.com	2.6 MB	0.0 KB	2.1 MB	4.7 MB	54%	2.7 KB
5	news.corradoroberto.it	1.7 MB	0.0 KB	0.0 KB	1.7 MB	100%	5.5 KB
6	usenet.goja.nl.eu.org	1.2 MB	0.9 KB	401.9 KB	1.6 MB	75%	2.2 KB
7	newsfeed.bofh.team	1.2 MB	0.9 KB	247.4 KB	1.4 MB	83%	2.9 KB
8	i2pn.org	812.2 KB	0.9 KB	114.7 KB	927.7 KB	87%	2.6 KB
9	nntp.comgw.net	736.0 KB	0.0 KB	543.4 KB	1.2 MB	57%	5.7 KB
10	news.nntp4.net	711.0 KB	0.0 KB	855.1 KB	1.5 MB	45%	3.0 KB
11	news.samoylyk.net	632.8 KB	0.9 KB	140.9 KB	774.7 KB	81%	3.3 KB
12	endoftheline-out.news.chmurka.net	512.6 KB	0.0 KB	0.8 KB	513.4 KB	99%	2.9 KB
13	194.87.241.33	475.0 KB	0.0 KB	582.3 KB	1.0 MB	44%	3.5 KB
14	newsfeed.xs3.de	259.9 KB	0.9 KB	50.3 KB	311.1 KB	83%	3.6 KB
15	news.hispagatos.org	213.4 KB	0.0 KB	311.3 KB	524.7 KB	40%	2.9 KB
16	news.furie.org.uk	183.8 KB	0.0 KB	0.0 KB	183.8 KB	100%	4.5 KB
17	endofthelinebbs.peers.news.panix.com	145.4 KB	0.0 KB	12.1 KB	157.5 KB	92%	1.7 KB
18	news.netfront.net	74.6 KB	5.9 KB	475.2 KB	555.7 KB	13%	7.3 KB
19	photonic.trudheim.com	32.6 KB	0.9 KB	0.0 KB	33.5 KB	97%	5.6 KB
20	news.bbs.nz	23.9 KB	3.3 KB	3.9 KB	31.1 KB	76%	1.9 KB
21	localhost	0.0 KB	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 KB	0%	0
23	outgoing.news.lysator.liu.se	0.0 KB	0.9 KB	6.0 KB	6.9 KB	0%	2.3 KB
TOTAL: 23		25.8 MB	18.4 KB	6.8 MB	32.6 MB	79%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 25 00:01:01 - 00:59:59	157	2.1%	0.04	486.4 KB	1.8%	0.14
Feb 25 01:00:00 - 01:59:59	205	2.8%	0.06	750.4 KB	2.8%	0.21
Feb 25 02:00:00 - 02:59:59	370	5.0%	0.10	1.2 MB	4.5%	0.34
Feb 25 03:00:00 - 03:59:59	400	5.4%	0.11	1.5 MB	5.8%	0.43
Feb 25 04:00:00 - 04:59:59	407	5.5%	0.11	1.4 MB	5.4%	0.41
Feb 25 05:00:00 - 05:59:59	358	4.8%	0.10	1.1 MB	4.1%	0.31
Feb 25 06:00:00 - 06:59:59	341	4.6%	0.09	1.1 MB	4.2%	0.31
Feb 25 07:00:00 - 07:59:59	401	5.4%	0.11	1.3 MB	5.1%	0.38
Feb 25 08:00:00 - 08:59:59	440	6.0%	0.12	1.5 MB	5.7%	0.43
Feb 25 09:00:00 - 09:59:59	437	5.9%	0.12	1.7 MB	6.5%	0.49
Feb 25 10:00:00 - 10:59:59	453	6.1%	0.13	1.9 MB	7.4%	0.55
Feb 25 11:00:00 - 11:59:59	362	4.9%	0.10	1.3 MB	4.9%	0.36
Feb 25 12:00:00 - 12:59:59	446	6.0%	0.12	1.4 MB	5.3%	0.40
Feb 25 13:00:00 - 13:59:59	281	3.8%	0.08	875.4 KB	3.2%	0.24
Feb 25 14:00:00 - 14:59:59	390	5.3%	0.11	1.3 MB	5.0%	0.38
Feb 25 15:00:00 - 15:59:59	370	5.0%	0.10	1.3 MB	4.9%	0.37
Feb 25 16:00:00 - 16:59:59	410	5.5%	0.11	1.6 MB	6.2%	0.47
Feb 25 17:00:00 - 17:59:59	214	2.9%	0.06	868.2 KB	3.2%	0.24
Feb 25 18:00:00 - 18:59:59	172	2.3%	0.05	577.8 KB	2.1%	0.16
Feb 25 19:00:00 - 19:59:59	155	2.1%	0.04	616.5 KB	2.3%	0.17
Feb 25 20:00:00 - 20:59:59	167	2.3%	0.05	633.1 KB	2.3%	0.18
Feb 25 21:00:00 - 21:59:59	125	1.7%	0.03	484.1 KB	1.8%	0.13
Feb 25 22:00:00 - 22:59:59	158	2.1%	0.04	587.7 KB	2.2%	0.16
Feb 25 23:00:00 - 23:59:59	163	2.2%	0.05	863.4 KB	3.2%	0.24
Feb 26 00:00:00 - 00:01:01	9	0.1%	0.15	34.5 KB	0.1%	0.57
TOTAL: 24:00:00	7391	100.0%	0.09	26.4 MB	100.0%	0.31

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	104.2 GB	6.8%	0	0.49	38394.14
CYCTXT1	TEXT	5.0 GB	4.1 GB	82.4%	0	0.03	2158.82
TOTAL: 3	-	3077.0 GB	108.4 GB	3.5%	1	0.36	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	897	0	0	897
2	endofthelinebbs-out.news.weretis.net	517	0	1	516
3	news.nntp4.net	285	0	0	285
4	194.87.241.33	221	0	0	221
5	usenet.goja.nl.eu.org	185	0	1	184
6	newsfeed.bofh.team	146	0	1	145
7	news.hispagatos.org	130	22	0	108
8	nntp.comgw.net	113	0	0	113
9	news.netfront.net	63	0	2	61
10	i2pn.org	55	0	1	54
11	news.samoylyk.net	55	0	2	53
12	newsfeed.xs3.de	30	0	2	28
13	usenet.network	23	0	1	22
14	glockenspiel.complete.org	22	0	1	21
15	endofthelinebbs.peers.news.panix.com	11	0	0	11
16	outgoing.news.lysator.liu.se	3	0	1	2
17	news.bbs.nz	2	0	1	1
18	endoftheline-out.news.chmurka.net	1	0	0	1
19	photonic.trudheim.com	1	0	1	0
TOTAL: 19		2760	22	15	2723

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	16
hispagatos.hacking.news	4
hispagatos.hacking.ctf	1
hispagatos.mastodon.tag.hackers	1
TOTAL: 4	22

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1390
EMP (phr path)	677
Too many newsgroups	239
EMP (md5)	189
EMP (phl)	76
EMP (fsl)	46
User-issued cancel	20
Too many newsgroups (meow)	13
Angle-bracket bot	12
HTML Multipart	3
HTML post	2
TOTAL: 11	2667

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
robot@pasdenom.info (spam)	4	4	0	4
robot@pasdenom.info (spam2)	20	0	21	21
i2pn2-nocem@i2pn2.org (multipost)	11	0	14	14
robot@pasdenom.info (spam3)	5	0	13	13
robot@pasdenom.info (spam4)	4	0	9	9
i2pn2-nocem@i2pn2.org (rmp)	4	0	7	7
TOTAL: 6	48	4	64	68

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	22
	usenet.blueworldhosting.com	15
	nntp.comgw.net	8
	usenet.network	5
	usenet.goja.nl.eu.org	2
	news.samoylyk.net	2
	news.nntp4.net	2
	TOTAL: 7	56
TOTAL: 1		56

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7872	2921	4002	468	0	37%	23:59:45
2	news.netfront.net	12043	2034	5335	1	23	16%	23:59:51
3	news.furie.org.uk	5028	797	2866	15	0	15%	23:59:51
4	photonic.trudheim.com	9094	446	5626	1317	4	4%	23:59:49
5	usenet.goja.nl.eu.org	7701	324	6426	42	0	4%	23:59:51
6	news.nntp4.net	7942	322	6800	1	40	4%	23:59:51
7	endofthelinebbs.peers.news.panix.com	7855	309	7075	4	0	3%	23:59:51
8	news.corradoroberto.it	1269	126	718	0	0	9%	22:33:44
9	news.nk.ca	10124	101	6638	484	32	0%	23:59:51
10	news.bbs.nz	13675	97	6771	506	66	0%	23:59:40
11	newsfeed.bofh.team	8058	78	6947	1	1	0%	23:59:51
12	i2pn.org	8105	62	6996	22	31	0%	23:58:40
13	usenet.network	8239	51	6606	24	501	0%	23:59:51
14	news.weretis.net	7810	50	7186	153	0	0%	23:59:51
15	news.hispagatos.org	8468	48	7219	61	0	0%	23:58:16
16	nntp.comgw.net	7881	38	7193	37	0	0%	23:59:25
17	news.tnetconsulting.net	9520	23	7098	265	11	0%	23:59:48
18	news.quux.org	5976	23	4974	473	0	0%	23:58:07
19	newsfeed.xs3.de	8569	11	7164	127	0	0%	23:59:51
20	news.chmurka.net	4581	9	3498	1	2	0%	23:59:51
21	news.samoylyk.net	9383	7	7109	9	26	0%	23:59:17
22	ddt.demos.su	59	4	55	0	0	6%	05:57:20
23	usenet.blueworldhosting.com	7072	3	6376	0	0	0%	23:58:41
24	feed.opticnetworks.net	0	0	0	0	7391	0%	23:59:51
TOTAL: 24		176324	7884	130678	4011	8128	4%	556:20:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	9.8 MB	3.2 KB	9.8 MB	0.1 KB/s	4.9 KB	23:59:51
2	nyheter.lysator.liu.se	9.1 MB	4.6 MB	13.7 MB	0.2 KB/s	4.1 KB	23:59:45
3	news.furie.org.uk	5.5 MB	68.6 KB	5.6 MB	0.1 KB/s	7.0 KB	23:59:51
4	endofthelinebbs.peers.news.panix.com	2.5 MB	7.2 KB	2.5 MB	0.0 KB/s	8.2 KB	23:59:51
5	photonic.trudheim.com	2.4 MB	4.1 MB	6.5 MB	0.1 KB/s	3.8 KB	23:59:49
6	newsfeed.bofh.team	559.9 KB	3.2 KB	563.2 KB	0.0 KB/s	7.1 KB	23:59:51
7	news.nntp4.net	550.7 KB	3.2 KB	553.9 KB	0.0 KB/s	1.7 KB	23:59:51
8	usenet.goja.nl.eu.org	540.4 KB	520.8 KB	1.0 MB	0.0 KB/s	2.9 KB	23:59:51
9	news.corradoroberto.it	361.6 KB	0.0 KB	361.6 KB	0.0 KB/s	2.9 KB	22:33:44
10	news.nk.ca	359.4 KB	2.6 MB	2.9 MB	0.0 KB/s	5.1 KB	23:59:51
11	usenet.network	314.2 KB	74.5 KB	388.7 KB	0.0 KB/s	5.2 KB	23:59:51
12	news.bbs.nz	307.7 KB	2.9 MB	3.2 MB	0.0 KB/s	5.4 KB	23:59:40
13	i2pn.org	193.0 KB	74.2 KB	267.2 KB	0.0 KB/s	3.2 KB	23:58:40
14	news.weretis.net	185.6 KB	1.6 MB	1.8 MB	0.0 KB/s	9.1 KB	23:59:51
15	news.hispagatos.org	124.4 KB	594.5 KB	718.9 KB	0.0 KB/s	6.6 KB	23:58:16
16	news.quux.org	83.6 KB	2.6 MB	2.7 MB	0.0 KB/s	5.6 KB	23:58:07
17	nntp.comgw.net	72.2 KB	478.8 KB	551.1 KB	0.0 KB/s	7.3 KB	23:59:25
18	news.tnetconsulting.net	68.0 KB	2.4 MB	2.5 MB	0.0 KB/s	8.7 KB	23:59:48
19	news.samoylyk.net	53.7 KB	17.8 KB	71.5 KB	0.0 KB/s	4.5 KB	23:59:17
20	newsfeed.xs3.de	40.9 KB	994.8 KB	1.0 MB	0.0 KB/s	7.5 KB	23:59:51
21	news.chmurka.net	22.3 KB	3.2 KB	25.6 KB	0.0 KB/s	2.6 KB	23:59:51
22	ddt.demos.su	9.9 KB	0.0 KB	9.9 KB	0.0 KB/s	2.5 KB	05:57:20
23	usenet.blueworldhosting.com	4.0 KB	0.0 KB	4.0 KB	0.0 KB/s	1.3 KB	23:58:41
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
TOTAL: 24		33.0 MB	23.5 MB	56.6 MB	0.0 KB/s	4.9 KB	556:20:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	47.186.35.85	1	0	0.0 KB	6	0	0	00:00:00
TOTAL: 1		1	0	0.0 KB	6	0	0	00:17:52

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	*.vps.ovh.net	903	0	00:05:55
2	unresolved	9	6	00:00:22
3	*.stretchoid.com	2	0	00:00:10
4	?	1	0	00:11:24
TOTAL: 4		915	6	00:17:52

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
localhost	184.674	368.458	0.075	00:11:24
vps-b8a0d561.vps.ovh.net	3.103	11.913	0.000	00:05:55
198.235.24.110	0.008	0.008	0.000	00:00:10
azpdesb80.stretchoid.com	0.003	0.014	0.000	00:00:09
205.210.31.192	0.004	0.043	0.000	00:00:06
167.94.146.48	0.024	0.054	0.000	00:00:05
147.45.112.183	0.004	0.012	0.000	00:00:00
198.235.24.88	0.008	0.008	0.000	00:00:00
2.57.122.208	0.000	0.021	0.000	00:00:00
azpdeg35.stretchoid.com	0.004	0.014	0.000	00:00:00
TOTAL: 10	187.832	380.545	0.075	00:17:52

Curious NNRP server explorers [Top 20]:

System	Conn
vps-b8a0d561.vps.ovh.net	903
167.94.146.48	3
147.45.112.183	1
198.235.24.110	1
198.235.24.88	1
2.57.122.208	1
205.210.31.192	1
azpdeg35.stretchoid.com	1
azpdesb80.stretchoid.com	1
localhost	1
TOTAL: 10	914

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

System	Conn
147.45.112.183	1
167.94.146.48	1
azpdeg35.stretchoid.com	1
TOTAL: 3	3

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.183	1	1
167.94.146.48	1	2
TOTAL: 2	2	5

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	news	5	50.0%
2	alt	3	30.0%
3	comp	2	20.0%
TOTAL: 3		10	100.0%

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

Newsgroup		Count
1	news.admin.peering	3
2	alt.music.lyrics	2
3	news.admin.net-abuse.usenet	2
4	alt.genealogy	1
5	comp.sys.acorn.advocacy	1
6	comp.sys.acorn.programmer	1
TOTAL: 6		10

Daily Usenet Report

Feb 25 00:01:01 -- Feb 26 00:01:01