Daily Usenet Report: Oct 29 00:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 30 00:00:01 newsfeed nnrpd[886394]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	14041	38.6%	1.9 MB	25.5%
innd	8806	24.2%	1.3 MB	17.2%
inn	8582	23.6%	3.3 MB	45.2%
innfeed	4510	12.4%	846.5 KB	11.2%
cnfsstat	432	1.2%	65.5 KB	0.9%
nocem	20	0.1%	2.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	36392	100.0%	7.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	108266	64.3%
Negative hits	49947	29.7%
Do not exist	8462	5.0%
Cache misses	1728	1.0%
TOTAL: 4	168403	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.165	0.0%	20	0.000	8.250	14.222
article cleanup	00:00:00.113	0.0%	8539	0.000	0.013	0.164
article logging	00:00:00.413	0.0%	8577	0.000	0.048	0.309
article parse	00:00:00.226	0.0%	14198	0.000	0.016	0.136
article write	00:00:02.786	0.0%	6708	0.000	0.415	11.000
artlog/artcncl	00:00:00.000	0.0%	1	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.543	0.0%	173864	0.000	0.009	0.424
hisgrep/artcncl	00:00:00.139	0.0%	21	0.000	6.619	12.556
hishave/artcncl	00:00:00.000	0.0%	1	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:32.762	0.2%	170290	0.104	0.897	5.138
history sync	00:00:00.083	0.0%	404	0.000	0.205	1.000
history write	00:02:05.387	0.1%	8540	3.004	14.682	125.353
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:51:06.972	99.3%	173238	70.571	495.659	1691.087
nntp read	00:00:03.700	0.0%	174763	0.000	0.021	0.303
overview write	00:03:19.189	0.2%	6708	0.613	29.694	2901.463
perl filter	00:00:43.835	0.1%	8539	1.457	5.134	177.982
python filter	00:00:02.126	0.0%	8539	0.000	0.249	7.123
site send	00:00:00.914	0.0%	13435	0.000	0.068	0.282
TOTAL: 24:01:01.588	24:00:00.353	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.816	0.0%	700516	0.000	0.001	0.048
article prepare	00:00:00.016	0.0%	54340	0.000	0.000	0.005
article read	00:00:00.808	0.0%	4391	0.000	0.184	1.818
backlog stats	00:00:00.930	0.0%	658568	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:05.383	0.0%	452476	0.002	0.012	0.023
data write	00:00:05.890	0.0%	437676	0.008	0.013	0.027
idle	23:54:34.346	99.9%	658568	48.013	130.699	1221.202
status file	00:00:00.529	0.0%	442	0.750	1.197	29.000
TOTAL: 23:55:34.701	23:54:48.718	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:32:44.571	72.9%	8354	0.250	235.165	303252.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.289	0.2%	95556	0.000	0.045	0.333
TOTAL: 00:44:56.269	00:32:48.860	73.0%	-	-	-	-

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	endofthelinebbs-out.news.weretis.net	3	11874	1776	9858	240	14%	44:54:25
2	usenet.blueworldhosting.com	718	8083	825	6619	639	10%	23:52:52
3	nntp.comgw.net	1	7511	726	6576	209	9%	23:59:56
4	endofthelinebbs.peers.news.panix.com	26	7161	596	6532	33	8%	23:56:16
5	usenet.goja.nl.eu.org	11	7335	553	6595	187	7%	24:26:02
6	glockenspiel.complete.org	2	5338	466	4869	3	8%	23:59:18
7	usenet.network	2	5874	439	5431	4	7%	23:59:24
8	i2pn.org	2	7282	335	6830	117	4%	23:57:31
9	news.corradoroberto.it	9	1093	305	788	0	27%	22:49:58
10	newsfeed.xs3.de	2	5567	270	5250	47	4%	23:59:39
11	news.samoylyk.net	2	6105	114	5989	2	1%	23:58:20
12	194.87.241.33	47	196	104	92	0	53%	13:54:37
13	endoftheline-out.news.chmurka.net	3	4047	51	3805	191	1%	12:38:08
14	news.netfront.net	1	6012	49	5900	63	0%	22:06:09
15	news.nntp4.net	2	5868	40	5827	1	0%	23:59:37
16	newsfeed.bofh.team	2	8055	39	8012	4	0%	23:59:43
17	news.hispagatos.org	5	7617	31	7540	46	0%	24:26:18
18	news.furie.org.uk	2	3726	16	3709	1	0%	23:58:33
19	news.bbs.nz	1	6763	3	6760	0	0%	23:59:40
20	outgoing.news.lysator.liu.se	2	4112	1	4111	0	0%	23:59:40
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:06
22	localhost	26	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:19
TOTAL: 23		872	119619	6739	111093	1787	5%	477:26:31

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.3 MB	394.6 KB	6.7 MB	94%	3.4 KB
2	usenet.network	5.3 MB	7.9 KB	5.3 MB	99%	12.2 KB
3	news.corradoroberto.it	2.7 MB	0.0 KB	2.7 MB	100%	9.1 KB
4	glockenspiel.complete.org	2.7 MB	2.3 KB	2.7 MB	99%	5.8 KB
5	usenet.blueworldhosting.com	2.6 MB	1.5 MB	4.1 MB	62%	2.9 KB
6	nntp.comgw.net	2.1 MB	298.6 KB	2.4 MB	87%	2.6 KB
7	usenet.goja.nl.eu.org	1.8 MB	354.8 KB	2.1 MB	83%	3.0 KB
8	endofthelinebbs.peers.news.panix.com	1.6 MB	50.4 KB	1.7 MB	97%	2.7 KB
9	i2pn.org	897.2 KB	153.5 KB	1.0 MB	85%	2.3 KB
10	news.samoylyk.net	861.4 KB	1.9 KB	863.3 KB	99%	7.4 KB
11	newsfeed.xs3.de	842.8 KB	78.2 KB	921.0 KB	91%	2.9 KB
12	194.87.241.33	332.3 KB	0.0 KB	332.3 KB	100%	3.2 KB
13	newsfeed.bofh.team	187.9 KB	4.1 KB	192.0 KB	97%	4.5 KB
14	endoftheline-out.news.chmurka.net	108.4 KB	146.6 KB	255.0 KB	42%	1.1 KB
15	news.netfront.net	93.7 KB	244.7 KB	338.4 KB	27%	3.0 KB
16	news.hispagatos.org	90.9 KB	61.6 KB	152.5 KB	59%	2.0 KB
17	news.nntp4.net	88.3 KB	0.8 KB	89.1 KB	99%	2.2 KB
18	news.furie.org.uk	66.9 KB	0.7 KB	67.6 KB	99%	4.0 KB
19	news.bbs.nz	4.1 KB	0.0 KB	4.1 KB	100%	1.4 KB
20	outgoing.news.lysator.liu.se	2.8 KB	0.0 KB	2.8 KB	100%	2.8 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		28.5 MB	3.3 MB	31.8 MB	89%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 29 00:01:02 - 00:59:59	149	2.2%	0.04	482.0 KB	1.7%	0.14
Oct 29 01:00:00 - 01:59:59	165	2.5%	0.05	461.3 KB	1.6%	0.13
Oct 29 02:00:00 - 02:59:59	214	3.2%	0.06	636.5 KB	2.2%	0.18
Oct 29 03:00:00 - 03:59:59	339	5.1%	0.09	1.0 MB	3.7%	0.29
Oct 29 04:00:00 - 04:59:59	341	5.1%	0.09	1009.6 KB	3.6%	0.28
Oct 29 05:00:00 - 05:59:59	349	5.2%	0.10	1.5 MB	5.4%	0.43
Oct 29 06:00:00 - 06:59:59	286	4.3%	0.08	1.0 MB	3.7%	0.29
Oct 29 07:00:00 - 07:59:59	293	4.4%	0.08	1.1 MB	4.1%	0.32
Oct 29 08:00:00 - 08:59:59	339	5.1%	0.09	1.1 MB	4.1%	0.32
Oct 29 09:00:00 - 09:59:59	316	4.7%	0.09	1.6 MB	5.6%	0.45
Oct 29 10:00:00 - 10:59:59	433	6.5%	0.12	1.9 MB	6.7%	0.53
Oct 29 11:00:00 - 11:59:59	412	6.1%	0.11	1.9 MB	6.8%	0.53
Oct 29 12:00:00 - 12:59:59	372	5.5%	0.10	1.3 MB	4.8%	0.38
Oct 29 13:00:00 - 13:59:59	473	7.0%	0.13	2.8 MB	9.9%	0.78
Oct 29 14:00:00 - 14:59:59	353	5.3%	0.10	1.9 MB	6.7%	0.53
Oct 29 15:00:00 - 15:59:59	402	6.0%	0.11	2.0 MB	7.3%	0.58
Oct 29 16:00:00 - 16:59:59	316	4.7%	0.09	1.3 MB	4.7%	0.37
Oct 29 17:00:00 - 17:59:59	270	4.0%	0.07	962.6 KB	3.4%	0.27
Oct 29 18:00:00 - 18:59:59	242	3.6%	0.07	1.4 MB	4.9%	0.38
Oct 29 19:00:00 - 19:59:59	174	2.6%	0.05	595.0 KB	2.1%	0.17
Oct 29 20:00:00 - 20:59:59	146	2.2%	0.04	601.0 KB	2.1%	0.17
Oct 29 21:00:00 - 21:59:59	106	1.6%	0.03	521.1 KB	1.8%	0.14
Oct 29 22:00:00 - 22:59:59	114	1.7%	0.03	444.2 KB	1.6%	0.12
Oct 29 23:00:00 - 23:59:59	101	1.5%	0.03	401.2 KB	1.4%	0.11
Oct 30 00:00:00 - 00:01:01	6	0.1%	0.10	18.5 KB	0.1%	0.30
TOTAL: 23:59:59	6711	100.0%	0.08	27.7 MB	100.0%	0.33

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.2 GB	6.5%	0	0.49	38133.99
CYCTXT1	TEXT	5.0 GB	3.9 GB	78.3%	0	0.03	2314.76
TOTAL: 3	-	3077.0 GB	103.2 GB	3.4%	1	0.34	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	usenet.blueworldhosting.com	645	1	1	643
2	endofthelinebbs-out.news.weretis.net	250	0	0	250
3	nntp.comgw.net	212	0	0	212
4	endoftheline-out.news.chmurka.net	191	0	0	191
5	usenet.goja.nl.eu.org	181	0	0	181
6	i2pn.org	117	0	0	117
7	news.netfront.net	115	0	0	115
8	newsfeed.xs3.de	57	0	0	57
9	news.hispagatos.org	46	36	0	10
10	endofthelinebbs.peers.news.panix.com	39	2	0	37
11	glockenspiel.complete.org	4	0	0	4
12	newsfeed.bofh.team	4	1	0	3
13	usenet.network	4	0	0	4
14	news.furie.org.uk	2	0	0	2
15	news.samoylyk.net	2	0	0	2
16	news.nntp4.net	1	0	0	1
TOTAL: 16		1870	40	1	1829

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	15
hispagatos.hacking.ctf	12
hispagatos.hacking.news	5
hispagatos.mastodon.tag.hackers	3
soc.culture.russia	2
hispagatos.hacking.exploits	1
rec.radio.amateur	1
tinasuthar2511gmail.com	1
TOTAL: 8	40

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
rec.radio.broadcasting	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	821
EMP (phr path)	548
EMP (md5)	299
Too many newsgroups	75
User-issued cancel	30
Binary: misplaced binary	5
EMP (phl)	5
Too many newsgroups (meow)	5
EMP (fsl)	1
HTML Multipart	1
TOTAL: 10	1790

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
mm+nocem@dorfdsl.de (spam)	1	376	376
i2pn2-nocem@i2pn2.org (rmp)	8	13	13
i2pn2-nocem@i2pn2.org (multipost)	6	6	6
robot@pasdenom.info (spam3)	3	4	4
robot@pasdenom.info (spam2)	1	1	1
TOTAL: 5	19	400	400

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.xs3.de	10
	endofthelinebbs-out.news.weretis.net	10
	usenet.blueworldhosting.com	6
	usenet.goja.nl.eu.org	5
	endofthelinebbs.peers.news.panix.com	4
	nntp.comgw.net	1
	glockenspiel.complete.org	1
	news.furie.org.uk	1
	TOTAL: 8	38
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		39

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7189	2781	3433	496	0	38%	23:59:47
2	news.netfront.net	10910	1627	5021	0	2	14%	23:59:35
3	news.furie.org.uk	4166	1128	2274	9	1	27%	23:57:31
4	photonic.trudheim.com	8041	663	4747	1300	1	8%	23:59:27
5	endofthelinebbs.peers.news.panix.com	6981	465	6236	5	0	6%	23:59:47
6	usenet.goja.nl.eu.org	7183	347	5537	241	0	4%	23:59:47
7	newsfeed.bofh.team	7546	203	6360	0	0	2%	23:59:35
8	news.corradoroberto.it	1026	192	531	0	0	18%	22:40:02
9	news.bbs.nz	12039	165	5849	690	66	1%	23:59:47
10	news.chmurka.net	729231	130	3944	3	692631	0%	23:59:47
11	news.nk.ca	8415	125	5863	621	7	1%	23:59:47
12	news.hispagatos.org	8016	116	6367	196	0	1%	23:59:47
13	usenet.network	7629	100	5782	5	0	1%	23:59:47
14	nntp.comgw.net	6783	98	5759	114	0	1%	23:59:47
15	news.nntp4.net	7892	93	6485	51	0	1%	23:59:17
16	i2pn.org	7314	80	6230	45	36	1%	23:59:47
17	news.weretis.net	6946	47	6478	182	0	0%	23:59:37
18	news.tnetconsulting.net	7527	35	6460	210	3	0%	23:58:58
19	news.quux.org	5374	31	4320	678	0	0%	23:59:47
20	ddt.demos.su	99	20	68	0	0	20%	09:59:08
21	newsfeed.xs3.de	7689	19	6168	240	0	0%	23:59:31
22	news.samoylyk.net	8200	11	6510	39	0	0%	23:59:47
23	usenet.blueworldhosting.com	5959	0	5683	0	0	0%	23:59:44
24	feed.opticnetworks.net	0	0	0	0	6711	0%	23:59:47
TOTAL: 24		882155	8476	116105	5125	699458	0%	560:29:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	10.6 MB	4.9 MB	15.5 MB	0.2 KB/s	4.8 KB	23:59:47
2	news.netfront.net	9.5 MB	0.0 KB	9.5 MB	0.1 KB/s	6.0 KB	23:59:35
3	news.furie.org.uk	7.5 MB	228.2 KB	7.7 MB	0.1 KB/s	7.0 KB	23:57:31
4	photonic.trudheim.com	5.0 MB	4.2 MB	9.1 MB	0.1 KB/s	4.8 KB	23:59:27
5	endofthelinebbs.peers.news.panix.com	2.4 MB	5.2 KB	2.4 MB	0.0 KB/s	5.2 KB	23:59:47
6	newsfeed.bofh.team	1.5 MB	0.0 KB	1.5 MB	0.0 KB/s	7.7 KB	23:59:35
7	usenet.goja.nl.eu.org	649.0 KB	2.5 MB	3.2 MB	0.0 KB/s	5.5 KB	23:59:47
8	news.quux.org	634.2 KB	4.1 MB	4.7 MB	0.1 KB/s	6.9 KB	23:59:47
9	news.bbs.nz	618.8 KB	4.8 MB	5.4 MB	0.1 KB/s	6.5 KB	23:59:47
10	news.chmurka.net	615.0 KB	13.0 KB	628.0 KB	0.0 KB/s	4.7 KB	23:59:47
11	news.samoylyk.net	592.7 KB	579.5 KB	1.1 MB	0.0 KB/s	23.4 KB	23:59:47
12	ddt.demos.su	513.9 KB	0.0 KB	513.9 KB	0.0 KB/s	25.7 KB	09:59:08
13	news.corradoroberto.it	464.6 KB	0.0 KB	464.6 KB	0.0 KB/s	2.4 KB	22:40:02
14	news.nk.ca	435.0 KB	4.6 MB	5.0 MB	0.1 KB/s	6.9 KB	23:59:47
15	news.nntp4.net	415.4 KB	639.6 KB	1.0 MB	0.0 KB/s	7.3 KB	23:59:17
16	usenet.network	383.8 KB	7.8 KB	391.6 KB	0.0 KB/s	3.7 KB	23:59:47
17	i2pn.org	352.7 KB	331.8 KB	684.4 KB	0.0 KB/s	5.5 KB	23:59:47
18	news.hispagatos.org	329.9 KB	2.0 MB	2.3 MB	0.0 KB/s	7.5 KB	23:59:47
19	news.weretis.net	325.5 KB	2.2 MB	2.5 MB	0.0 KB/s	11.3 KB	23:59:37
20	news.tnetconsulting.net	216.9 KB	1.7 MB	1.9 MB	0.0 KB/s	7.8 KB	23:58:58
21	nntp.comgw.net	186.5 KB	1.6 MB	1.8 MB	0.0 KB/s	8.6 KB	23:59:47
22	newsfeed.xs3.de	45.1 KB	1.9 MB	2.0 MB	0.0 KB/s	7.7 KB	23:59:31
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:47
24	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:44
TOTAL: 24		43.0 MB	36.2 MB	79.3 MB	0.0 KB/s	6.0 KB	560:29:36

NNRP readership statistics:

System		Conn	Elapsed
1	2001:470:28:a8:3ad5:47ff:fe02:c2fd	2780	00:22:59
2	167.94.145.108	3	00:00:03
3	199.45.154.140	3	00:00:05
4	147.185.132.28	1	00:00:10
5	198.235.24.108	1	00:00:00
6	205.210.31.134	1	00:00:10
7	205.210.31.26	1	00:00:00
8	91.238.181.33	1	00:00:00
9	freeq.furie.org.uk	1	00:10:06
10	localhost	1	00:11:12
11	lustrous.monitoring.internet-measurement.com	1	00:00:01
12	server-0-2.survey.inspici.com	1	00:00:03
13	venice.scan.bufferover.run	1	00:00:00
TOTAL: 13		2796	00:44:55

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	2781	00:34:12
2	unresolved	11	00:00:30
3	*.furie.org.uk	1	00:10:06
4	*.monitoring.internet-measurement.com	1	00:00:01
5	*.scan.bufferover.run	1	00:00:00
6	*.survey.inspici.com	1	00:00:03
TOTAL: 6		2796	00:44:55

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.885	37.747	0.000	00:22:59
localhost	180.471	369.692	0.076	00:11:12
freeq.furie.org.uk	0.006	0.011	0.000	00:10:06
147.185.132.28	0.004	0.012	0.000	00:00:10
205.210.31.134	0.008	0.008	0.000	00:00:10
199.45.154.140	0.018	0.046	0.000	00:00:05
167.94.145.108	0.032	0.029	0.000	00:00:03
server-0-2.survey.inspici.com	0.007	0.013	0.000	00:00:03
lustrous.monitoring.internet-measurement.com	0.008	0.008	0.000	00:00:01
91.238.181.33	0.004	0.012	0.000	00:00:00
205.210.31.26	0.004	0.012	0.000	00:00:00
198.235.24.108	0.000	0.020	0.000	00:00:00
venice.scan.bufferover.run	0.005	0.014	0.000	00:00:00
TOTAL: 13	190.452	407.624	0.076	00:44:55

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

System	Conn
venice.scan.bufferover.run	3
167.94.145.108	1
199.45.154.140	1
91.238.181.33	1
TOTAL: 4	6

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

Command	Count
EHLO www.censys.io	2
#003	1
#023#001#023#002#023#003#001	1
#026#003#001	1
À#024	1
TOTAL: 5	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.145.108	1	2
199.45.154.140	1	2
91.238.181.33	1	1
TOTAL: 3	3	7

Daily Usenet Report

Oct 29 00:01:02 -- Oct 30 00:01:01