Daily Usenet Report: Nov 10 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Nov 10 04:40:02 newsfeed controlchan[3771926]: Unknown "retitle" control by robomod@news.nic.it
Nov 11 00:00:01 newsfeed nnrpd[3094879]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	13942	34.9%	1.9 MB	23.0%
inn	11932	29.9%	4.1 MB	49.1%
innd	8696	21.8%	1.3 MB	15.4%
innfeed	4431	11.1%	830.8 KB	9.8%
nocem	486	1.2%	156.7 KB	1.9%
cnfsstat	432	1.1%	73.4 KB	0.9%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 7	39921	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	127874	66.7%
Negative hits	54327	28.3%
Do not exist	9387	4.9%
Cache misses	127	0.1%
TOTAL: 4	191715	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.756	0.0%	2798	0.000	0.985	10.167
article cleanup	00:00:00.102	0.0%	9360	0.000	0.011	0.077
article logging	00:00:00.458	0.0%	9422	0.000	0.049	0.333
article parse	00:00:00.215	0.0%	13193	0.000	0.016	0.081
article write	00:00:09.121	0.0%	7737	0.000	1.179	266.083
artlog/artcncl	00:00:00.071	0.0%	2512	0.000	0.028	0.500
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.903	0.0%	193783	0.000	0.005	0.174
hisgrep/artcncl	00:00:01.627	0.0%	2719	0.000	0.598	7.286
hishave/artcncl	00:00:00.404	0.0%	2775	0.000	0.146	3.917
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:05:00.697	0.3%	189570	0.113	1.586	55.518
history sync	00:00:00.072	0.0%	396	0.000	0.182	1.000
history write	00:02:26.468	0.2%	9338	3.238	15.685	166.333
hiswrite/artcncl	00:00:00.298	0.0%	79	2.800	3.772	4.512
hiswrite/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:44:55.542	98.9%	192435	67.589	444.283	1424.311
nntp read	00:00:03.790	0.0%	195120	0.003	0.019	0.246
overview write	00:06:46.880	0.5%	7737	0.414	52.589	19702.917
perl filter	00:00:29.985	0.0%	9337	1.529	3.211	23.025
python filter	00:00:01.630	0.0%	9337	0.000	0.175	4.986
site send	00:00:01.299	0.0%	15739	0.000	0.083	0.226
TOTAL: 24:01:19.300	24:00:02.318	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.025	0.0%	8010	0.000	0.003	0.040
article prepare	00:00:00.003	0.0%	55328	0.000	0.000	0.005
article read	00:00:00.684	0.0%	4808	0.000	0.142	1.875
backlog stats	00:00:00.508	0.0%	296188	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.598	0.0%	200259	0.001	0.013	0.042
data write	00:00:03.628	0.0%	196182	0.009	0.018	0.034
idle	23:56:52.249	100.0%	296188	125.770	291.073	1082.113
status file	00:00:01.115	0.0%	396	1.000	2.816	129.200
TOTAL: 23:57:13.597	23:57:00.810	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:20:57.245	63.5%	8340	0.500	150.749	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.812	0.2%	95473	0.000	0.040	0.333
TOTAL: 00:32:59.864	00:21:01.057	63.7%	-	-	-	-

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	4	14033	1866	11864	303	13%	46:47:26
2	glockenspiel.complete.org	2	5872	1075	4795	2	18%	23:59:59
3	usenet.blueworldhosting.com	723	9122	1040	7437	645	11%	23:48:14
4	nntp.comgw.net	3	8413	691	7580	142	8%	24:02:33
5	i2pn.org	1	7433	415	6917	101	5%	23:59:53
6	usenet.goja.nl.eu.org	10	7990	406	7496	88	5%	22:24:37
7	news.corradoroberto.it	6	1146	378	768	0	32%	26:08:26
8	newsfeed.bofh.team	2	8744	365	8301	78	4%	23:59:48
9	newsfeed.xs3.de	2	6612	317	6260	35	4%	23:59:17
10	194.87.241.33	43	354	219	134	1	61%	16:42:13
11	endofthelinebbs.peers.news.panix.com	25	8127	206	7881	40	2%	23:55:36
12	news.nntp4.net	1	6886	141	6736	9	2%	23:59:54
13	usenet.network	1	5447	102	5326	19	1%	23:59:56
14	news.furie.org.uk	1	3503	85	3418	0	2%	23:59:56
15	endoftheline-out.news.chmurka.net	1	3703	50	3653	0	1%	21:27:40
16	news.netfront.net	1	7292	30	7162	100	0%	25:02:29
17	news.samoylyk.net	3	7185	21	7162	2	0%	23:59:29
18	news.bbs.nz	2	6861	12	6843	6	0%	23:58:42
19	news.hispagatos.org	5	8391	11	8342	38	0%	24:26:23
20	outgoing.news.lysator.liu.se	2	5512	7	5504	1	0%	23:59:47
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	27	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		868	132626	7437	123579	1610	5%	495:12:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.1 MB	3.8 KB	701.6 KB	6.7 MB	89%	3.2 KB
2	glockenspiel.complete.org	4.4 MB	0.0 KB	1.5 KB	4.4 MB	99%	4.2 KB
3	usenet.blueworldhosting.com	2.1 MB	0.0 KB	1.6 MB	3.7 MB	56%	2.2 KB
4	news.corradoroberto.it	2.0 MB	0.0 KB	0.0 KB	2.0 MB	100%	5.3 KB
5	nntp.comgw.net	1.7 MB	1.3 KB	265.6 KB	2.0 MB	86%	2.4 KB
6	usenet.goja.nl.eu.org	1.0 MB	3.0 KB	271.5 KB	1.3 MB	79%	2.7 KB
7	i2pn.org	1.0 MB	1.3 KB	131.4 KB	1.1 MB	88%	2.3 KB
8	newsfeed.bofh.team	893.5 KB	5.1 KB	97.8 KB	996.4 KB	89%	2.2 KB
9	usenet.network	889.2 KB	5.5 KB	15.5 KB	910.1 KB	97%	7.5 KB
10	newsfeed.xs3.de	713.1 KB	1.3 KB	58.4 KB	772.8 KB	92%	2.2 KB
11	194.87.241.33	599.1 KB	1.5 KB	0.0 KB	600.7 KB	99%	2.7 KB
12	endofthelinebbs.peers.news.panix.com	592.1 KB	2.9 KB	42.0 KB	637.0 KB	92%	2.6 KB
13	news.nntp4.net	365.3 KB	1.3 KB	7.6 KB	374.1 KB	97%	2.5 KB
14	news.furie.org.uk	353.2 KB	0.0 KB	0.0 KB	353.2 KB	100%	4.2 KB
15	endoftheline-out.news.chmurka.net	120.6 KB	0.0 KB	0.0 KB	120.6 KB	100%	2.4 KB
16	news.samoylyk.net	90.1 KB	1.3 KB	1.3 KB	92.7 KB	97%	4.0 KB
17	news.netfront.net	50.0 KB	1.3 KB	647.0 KB	698.3 KB	7%	5.4 KB
18	news.hispagatos.org	32.6 KB	1.3 KB	69.4 KB	103.3 KB	31%	2.1 KB
19	outgoing.news.lysator.liu.se	32.2 KB	1.3 KB	0.0 KB	33.5 KB	96%	4.2 KB
20	news.bbs.nz	31.5 KB	14.2 KB	0.0 KB	45.7 KB	69%	2.5 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		22.9 MB	46.1 KB	3.8 MB	26.8 MB	85%	3.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 10 00:01:01 - 00:59:59	111	1.4%	0.03	446.2 KB	1.7%	0.13
Nov 10 01:00:00 - 01:59:59	190	2.5%	0.05	570.2 KB	2.2%	0.16
Nov 10 02:00:00 - 02:59:59	355	4.6%	0.10	847.5 KB	3.3%	0.24
Nov 10 03:00:00 - 03:59:59	393	5.1%	0.11	914.4 KB	3.6%	0.25
Nov 10 04:00:00 - 04:59:59	412	5.3%	0.11	1006.1 KB	3.9%	0.28
Nov 10 05:00:00 - 05:59:59	429	5.5%	0.12	1.3 MB	5.1%	0.36
Nov 10 06:00:00 - 06:59:59	436	5.6%	0.12	1.2 MB	5.0%	0.35
Nov 10 07:00:00 - 07:59:59	400	5.2%	0.11	1.3 MB	5.1%	0.36
Nov 10 08:00:00 - 08:59:59	361	4.7%	0.10	1011.6 KB	4.0%	0.28
Nov 10 09:00:00 - 09:59:59	427	5.5%	0.12	1.2 MB	4.9%	0.35
Nov 10 10:00:00 - 10:59:59	425	5.5%	0.12	1.2 MB	4.9%	0.34
Nov 10 11:00:00 - 11:59:59	416	5.4%	0.12	1.2 MB	4.9%	0.34
Nov 10 12:00:00 - 12:59:59	435	5.6%	0.12	1.3 MB	5.2%	0.37
Nov 10 13:00:00 - 13:59:59	394	5.1%	0.11	1.1 MB	4.6%	0.32
Nov 10 14:00:00 - 14:59:59	539	7.0%	0.15	1.5 MB	6.0%	0.42
Nov 10 15:00:00 - 15:59:59	358	4.6%	0.10	1.2 MB	4.8%	0.34
Nov 10 16:00:00 - 16:59:59	309	4.0%	0.09	849.5 KB	3.3%	0.24
Nov 10 17:00:00 - 17:59:59	223	2.9%	0.06	2.0 MB	8.0%	0.57
Nov 10 18:00:00 - 18:59:59	195	2.5%	0.05	865.1 KB	3.4%	0.24
Nov 10 19:00:00 - 19:59:59	181	2.3%	0.05	1.2 MB	4.7%	0.33
Nov 10 20:00:00 - 20:59:59	179	2.3%	0.05	634.0 KB	2.5%	0.18
Nov 10 21:00:00 - 21:59:59	128	1.7%	0.04	668.2 KB	2.6%	0.19
Nov 10 22:00:00 - 22:59:59	229	3.0%	0.06	781.9 KB	3.1%	0.22
Nov 10 23:00:00 - 23:59:59	208	2.7%	0.06	812.3 KB	3.2%	0.23
Nov 11 00:00:00 - 00:01:01	4	0.1%	0.07	21.8 KB	0.1%	0.36
TOTAL: 24:00:00	7737	100.0%	0.09	24.9 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.7 GB	6.5%	0	0.48	39223.72
CYCTXT1	TEXT	5.0 GB	3.9 GB	78.5%	0	0.08	796.70
TOTAL: 3	-	3077.0 GB	103.7 GB	3.4%	1	0.39	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	659	29	0	630
2	endofthelinebbs-out.news.weretis.net	329	0	3	326
3	nntp.comgw.net	149	0	1	148
4	i2pn.org	103	0	1	102
5	news.netfront.net	102	0	1	101
6	usenet.goja.nl.eu.org	95	0	2	93
7	newsfeed.bofh.team	86	0	4	82
8	endofthelinebbs.peers.news.panix.com	44	28	2	14
9	newsfeed.xs3.de	42	0	1	41
10	news.hispagatos.org	38	27	1	10
11	usenet.network	19	0	2	17
12	news.nntp4.net	9	0	1	8
13	glockenspiel.complete.org	4	0	0	4
14	news.samoylyk.net	2	0	1	1
15	194.87.241.33	1	0	1	0
16	news.bbs.nz	1	0	1	0
17	news.furie.org.uk	1	0	0	1
18	outgoing.news.lysator.liu.se	1	0	1	0
TOTAL: 18		1685	84	23	1578

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.sci.announce	55
hispagatos.hacking.ctf	15
hispagatos.mastodon.tag.hacking	9
hispagatos.mastodon.tag.hispagatos	2
alt.apocalcbv	1
alt.apocalccc	1
hispagatos.mastodon.tag.hackers	1
TOTAL: 7	84

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	679
EMP (phr path)	335
EMP (md5)	265
Too many newsgroups	102
Too many newsgroups (meow)	72
User-issued cancel	35
Binary: misplaced binary	12
HTML Multipart	6
Binary Image: misplaced jpg	5
EMP (fsl)	4
TOTAL: 10	1515

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	138	2071	0	2071
nocem@eternal-september.org (bot)	85	702	0	702
robot@pasdenom.info (spam2)	18	0	20	20
i2pn2-nocem@i2pn2.org (multipost)	12	0	15	15
i2pn2-nocem@i2pn2.org (rmp)	4	0	8	8
robot@pasdenom.info (spam3)	4	0	7	7
robot@pasdenom.info (spam4)	1	0	1	1
TOTAL: 7	262	2773	51	2824

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	20
	usenet.blueworldhosting.com	14
	usenet.goja.nl.eu.org	8
	nntp.comgw.net	5
	newsfeed.xs3.de	5
	newsfeed.bofh.team	4
	endofthelinebbs.peers.news.panix.com	3
	glockenspiel.complete.org	1
	i2pn.org	1
	news.furie.org.uk	1
	TOTAL: 10	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	8165	2496	4958	276	0	30%	23:59:52
2	news.netfront.net	12800	1861	5828	1	8	14%	23:59:23
3	news.furie.org.uk	4441	708	2504	5	0	15%	23:57:47
4	photonic.trudheim.com	9346	635	5588	1510	0	6%	23:59:51
5	endofthelinebbs.peers.news.panix.com	8064	547	7178	7	0	6%	23:59:47
6	usenet.goja.nl.eu.org	8491	455	6641	92	1	5%	23:59:07
7	news.bbs.nz	14132	345	6873	511	63	2%	23:59:52
8	news.hispagatos.org	9452	289	7318	116	4	3%	23:59:52
9	news.nk.ca	10196	257	6832	429	19	2%	23:59:52
10	news.corradoroberto.it	1342	254	631	0	0	18%	23:45:13
11	usenet.network	9443	250	6934	55	4	2%	23:59:52
12	newsfeed.bofh.team	8498	221	7112	1	7	2%	23:59:52
13	nntp.comgw.net	7960	214	6744	43	6	2%	23:59:29
14	news.nntp4.net	8905	212	7340	29	2	2%	23:58:35
15	news.tnetconsulting.net	8497	202	7391	138	0	2%	23:59:24
16	news.weretis.net	8006	172	7489	75	12	2%	23:59:52
17	news.quux.org	6245	159	5313	434	0	2%	23:59:08
18	i2pn.org	8347	138	7118	39	30	1%	23:59:52
19	newsfeed.xs3.de	8954	92	7096	190	8	1%	23:59:52
20	news.samoylyk.net	10767	51	7403	260	112	0%	23:59:52
21	news.chmurka.net	5404	48	4031	1	4	0%	23:57:58
22	ddt.demos.su	106	18	73	0	0	16%	09:55:59
23	usenet.blueworldhosting.com	6887	1	6414	0	0	0%	23:59:52
24	feed.opticnetworks.net	0	0	0	0	7737	0%	23:59:52
TOTAL: 24		184448	9625	134809	4212	8017	5%	561:30:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	9.3 MB	2.1 MB	11.3 MB	0.1 KB/s	4.2 KB	23:59:52
2	news.netfront.net	7.6 MB	1.3 KB	7.6 MB	0.1 KB/s	4.2 KB	23:59:23
3	news.furie.org.uk	4.9 MB	54.4 KB	5.0 MB	0.1 KB/s	7.1 KB	23:57:47
4	photonic.trudheim.com	2.9 MB	4.5 MB	7.5 MB	0.1 KB/s	3.6 KB	23:59:51
5	endofthelinebbs.peers.news.panix.com	2.0 MB	9.4 KB	2.1 MB	0.0 KB/s	3.8 KB	23:59:47
6	news.hispagatos.org	1.4 MB	846.8 KB	2.2 MB	0.0 KB/s	5.6 KB	23:59:52
7	news.bbs.nz	1.3 MB	2.2 MB	3.5 MB	0.0 KB/s	4.1 KB	23:59:52
8	news.quux.org	1.2 MB	1.4 MB	2.6 MB	0.0 KB/s	4.5 KB	23:59:08
9	usenet.goja.nl.eu.org	1.1 MB	799.5 KB	1.9 MB	0.0 KB/s	3.5 KB	23:59:07
10	news.nntp4.net	1.1 MB	278.5 KB	1.4 MB	0.0 KB/s	5.8 KB	23:58:35
11	newsfeed.bofh.team	1.1 MB	1.3 KB	1.1 MB	0.0 KB/s	5.0 KB	23:59:52
12	news.tnetconsulting.net	989.3 KB	691.7 KB	1.6 MB	0.0 KB/s	4.9 KB	23:59:24
13	news.corradoroberto.it	900.8 KB	0.0 KB	900.8 KB	0.0 KB/s	3.5 KB	23:45:13
14	usenet.network	853.6 KB	73.4 KB	927.0 KB	0.0 KB/s	3.0 KB	23:59:52
15	news.weretis.net	830.6 KB	1.0 MB	1.8 MB	0.0 KB/s	7.5 KB	23:59:52
16	nntp.comgw.net	699.7 KB	847.3 KB	1.5 MB	0.0 KB/s	6.0 KB	23:59:29
17	news.samoylyk.net	686.4 KB	1.3 MB	1.9 MB	0.0 KB/s	6.4 KB	23:59:52
18	i2pn.org	579.4 KB	122.8 KB	702.3 KB	0.0 KB/s	4.0 KB	23:59:52
19	news.nk.ca	532.4 KB	2.1 MB	2.6 MB	0.0 KB/s	3.9 KB	23:59:52
20	newsfeed.xs3.de	471.1 KB	1.1 MB	1.5 MB	0.0 KB/s	5.5 KB	23:59:52
21	ddt.demos.su	456.5 KB	0.0 KB	456.5 KB	0.0 KB/s	25.4 KB	09:55:59
22	news.chmurka.net	115.9 KB	1.3 KB	117.2 KB	0.0 KB/s	2.4 KB	23:57:58
23	usenet.blueworldhosting.com	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	23:59:52
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 24		40.9 MB	19.3 MB	60.2 MB	0.0 KB/s	4.5 KB	561:30:05

NNRP readership statistics:

System		Conn	Elapsed
1	2001:470:28:a8:3ad5:47ff:fe02:c2fd	2779	00:21:21
2	167.94.145.96	3	00:00:03
3	198.235.24.238	1	00:00:10
4	205.210.31.159	1	00:00:10
5	azpdeg58.stretchoid.com	1	00:00:00
6	instinctive.monitoring.internet-measurement.com	1	00:00:01
7	localhost	1	00:11:07
8	server-0-3.survey.inspici.com	1	00:00:03
TOTAL: 8		2788	00:32:58

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	2780	00:32:29
2	unresolved	5	00:00:24
3	*.monitoring.internet-measurement.com	1	00:00:01
4	*.stretchoid.com	1	00:00:00
5	*.survey.inspici.com	1	00:00:03
TOTAL: 5		2788	00:32:58

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.963	37.102	0.000	00:21:21
localhost	178.946	372.306	0.068	00:11:07
198.235.24.238	0.009	0.009	0.000	00:00:10
205.210.31.159	0.003	0.013	0.000	00:00:10
167.94.145.96	0.022	0.037	0.000	00:00:03
server-0-3.survey.inspici.com	0.004	0.012	0.000	00:00:03
instinctive.monitoring.internet-measurement.com	0.003	0.014	0.000	00:00:01
azpdeg58.stretchoid.com	0.012	0.004	0.000	00:00:00
TOTAL: 8	188.962	409.497	0.068	00:32:58

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

System	Conn
167.94.145.96	1
azpdeg58.stretchoid.com	1
TOTAL: 2	2

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

Command	Count
EHLO www.censys.io	1
MGLNDD_144.172.126.95_119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.145.96	1	2
TOTAL: 1	1	2

Daily Usenet Report

Nov 10 00:01:01 -- Nov 11 00:01:01