Daily Usenet Report: Dec 29 00:01:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 29 08:14:52 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Dec 30 00:00:02 newsfeed nnrpd[1262060]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	10155	41.4%	1.5 MB	25.6%
inn	9482	38.6%	3.4 MB	58.9%
innfeed	4385	17.9%	839.5 KB	14.3%
cnfsstat	432	1.8%	66.1 KB	1.1%
nnrpd	65	0.3%	7.0 KB	0.1%
nocem	27	0.1%	3.6 KB	0.1%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 7	24547	100.0%	5.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	116671	63.8%
Negative hits	56828	31.1%
Do not exist	9319	5.1%
Cache misses	12	0.0%
TOTAL: 4	182830	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.134	0.0%	40	0.000	3.350	11.000
article cleanup	00:00:00.132	0.0%	9422	0.000	0.014	0.373
article logging	00:00:00.631	0.0%	9477	0.000	0.067	3.169
article parse	00:00:00.252	0.0%	17576	0.000	0.014	0.079
article write	00:00:02.348	0.0%	6510	0.000	0.361	5.562
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:02.911	0.0%	188567	0.000	0.015	1.112
hisgrep/artcncl	00:00:00.095	0.0%	41	0.000	2.317	8.000
hishave/artcncl	00:00:00.002	0.0%	8	0.000	0.250	0.667
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:51.546	0.2%	184470	0.121	0.930	6.075
history sync	00:00:00.076	0.0%	397	0.000	0.191	1.000
history write	00:02:21.727	0.2%	9416	3.422	15.052	123.765
hiswrite/artcncl	00:00:00.003	0.0%	1	3.000	3.000	3.000
hiswrite/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:51:30.178	99.3%	183191	74.781	468.856	2654.867
nntp read	00:00:05.072	0.0%	190703	0.001	0.027	0.758
overview write	00:02:43.728	0.2%	6510	0.773	25.150	1559.250
perl filter	00:00:38.763	0.0%	9416	1.286	4.117	54.457
python filter	00:00:02.601	0.0%	9416	0.000	0.276	7.268
site send	00:00:00.938	0.0%	13042	0.000	0.072	0.259
TOTAL: 24:01:09.372	24:00:21.137	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.025	0.0%	6606	0.000	0.004	0.100
article prepare	00:00:00.006	0.0%	35324	0.000	0.000	0.007
article read	00:00:00.604	0.0%	3949	0.000	0.153	1.150
backlog stats	00:00:00.345	0.0%	237594	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.182	0.0%	159782	0.000	0.014	0.045
data write	00:00:02.888	0.0%	155030	0.004	0.019	0.038
idle	23:58:43.034	100.0%	237594	194.446	363.322	1991.182
status file	00:00:00.480	0.0%	380	0.000	1.263	29.000
TOTAL: 23:59:00.184	23:58:49.564	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:17.580	2.3%	20	0.500	879.000	6231.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.098	0.5%	78878	0.000	0.052	0.052
TOTAL: 00:12:35.091	00:00:21.678	2.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	12267	2314	9520	433	18%	44:42:30
2	glockenspiel.complete.org	2	5525	1159	4365	1	20%	26:39:11
3	usenet.blueworldhosting.com	759	8382	956	6486	940	11%	24:06:54
4	nntp.comgw.net	1	8930	347	7935	648	3%	23:59:58
5	194.87.241.33	38	431	268	163	0	62%	17:36:15
6	news.corradoroberto.it	10	1021	266	755	0	26%	23:49:25
7	newsfeed.bofh.team	2	9275	262	8846	167	2%	23:59:43
8	i2pn.org	2	7835	222	7477	136	2%	23:59:09
9	news.samoylyk.net	1	6319	149	6166	4	2%	23:59:52
10	newsfeed.xs3.de	1	6115	101	5691	323	1%	23:59:56
11	usenet.network	2	5476	97	5376	3	1%	23:59:47
12	usenet.goja.nl.eu.org	11	8768	83	8651	34	0%	23:51:49
13	news.nntp4.net	1	6058	59	5999	0	0%	23:59:54
14	news.hispagatos.org	6	8022	56	7921	45	0%	24:25:53
15	news.netfront.net	1	7714	48	7498	168	0%	25:14:43
16	endofthelinebbs.peers.news.panix.com	27	7263	42	7217	4	0%	23:53:47
17	news.furie.org.uk	2	3209	41	3168	0	1%	23:59:39
18	endoftheline-out.news.chmurka.net	2	3172	37	3133	2	1%	23:59:41
19	news.bbs.nz	2	6109	6	6103	0	0%	23:59:32
20	outgoing.news.lysator.liu.se	2	4330	4	4325	1	0%	23:58:48
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:06
22	localhost	28	0	0	0	0	0%	00:00:01
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		906	126221	6517	116795	2909	5%	498:46:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.6 MB	0.0 KB	718.9 KB	7.3 MB	90%	2.7 KB
2	glockenspiel.complete.org	4.1 MB	0.0 KB	0.7 KB	4.1 MB	99%	3.7 KB
3	usenet.blueworldhosting.com	2.3 MB	0.0 KB	2.0 MB	4.3 MB	54%	2.3 KB
4	usenet.network	1.7 MB	429.8 KB	0.9 KB	2.1 MB	80%	22.0 KB
5	news.samoylyk.net	1.5 MB	0.0 KB	3.7 KB	1.5 MB	99%	9.9 KB
6	news.corradoroberto.it	1.3 MB	0.0 KB	0.0 KB	1.3 MB	100%	5.0 KB
7	194.87.241.33	1.1 MB	0.0 KB	0.0 KB	1.1 MB	100%	4.1 KB
8	newsfeed.bofh.team	1.0 MB	513.8 KB	1.7 MB	3.3 MB	31%	7.8 KB
9	nntp.comgw.net	996.6 KB	0.0 KB	9.6 MB	10.6 MB	9%	10.9 KB
10	i2pn.org	489.6 KB	0.0 KB	179.3 KB	669.0 KB	73%	1.9 KB
11	newsfeed.xs3.de	411.4 KB	513.8 KB	523.7 KB	1.4 MB	28%	3.4 KB
12	usenet.goja.nl.eu.org	310.3 KB	0.0 KB	79.2 KB	389.5 KB	79%	3.3 KB
13	news.hispagatos.org	203.0 KB	513.8 KB	83.2 KB	800.0 KB	25%	7.9 KB
14	news.furie.org.uk	188.4 KB	0.0 KB	0.0 KB	188.4 KB	100%	4.6 KB
15	news.nntp4.net	146.8 KB	0.0 KB	0.0 KB	146.8 KB	100%	2.5 KB
16	endofthelinebbs.peers.news.panix.com	129.9 KB	0.0 KB	4.8 KB	134.7 KB	96%	2.9 KB
17	endoftheline-out.news.chmurka.net	104.1 KB	0.0 KB	1.3 KB	105.4 KB	98%	2.7 KB
18	news.netfront.net	91.9 KB	0.0 KB	3.7 MB	3.8 MB	2%	17.9 KB
19	news.bbs.nz	13.4 KB	0.0 KB	0.0 KB	13.4 KB	100%	2.2 KB
20	outgoing.news.lysator.liu.se	11.0 KB	513.8 KB	0.0 KB	524.7 KB	2%	104.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		22.7 MB	2.4 MB	18.5 MB	43.7 MB	52%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 29 00:01:02 - 00:59:59	104	1.6%	0.03	329.6 KB	1.4%	0.09
Dec 29 01:00:00 - 01:59:59	171	2.6%	0.05	460.8 KB	2.0%	0.13
Dec 29 02:00:00 - 02:59:59	257	3.9%	0.07	831.2 KB	3.6%	0.23
Dec 29 03:00:00 - 03:59:59	243	3.7%	0.07	607.5 KB	2.6%	0.17
Dec 29 04:00:00 - 04:59:59	377	5.8%	0.10	953.2 KB	4.1%	0.26
Dec 29 05:00:00 - 05:59:59	348	5.3%	0.10	1.6 MB	7.3%	0.47
Dec 29 06:00:00 - 06:59:59	346	5.3%	0.10	870.1 KB	3.8%	0.24
Dec 29 07:00:00 - 07:59:59	334	5.1%	0.09	1.6 MB	7.2%	0.46
Dec 29 08:00:00 - 08:59:59	335	5.1%	0.09	969.6 KB	4.2%	0.27
Dec 29 09:00:00 - 09:59:59	352	5.4%	0.10	1.1 MB	4.7%	0.30
Dec 29 10:00:00 - 10:59:59	386	5.9%	0.11	1.7 MB	7.7%	0.50
Dec 29 11:00:00 - 11:59:59	415	6.4%	0.12	1.2 MB	5.2%	0.33
Dec 29 12:00:00 - 12:59:59	365	5.6%	0.10	1.1 MB	4.7%	0.30
Dec 29 13:00:00 - 13:59:59	360	5.5%	0.10	1.4 MB	6.2%	0.40
Dec 29 14:00:00 - 14:59:59	333	5.1%	0.09	1.3 MB	5.7%	0.37
Dec 29 15:00:00 - 15:59:59	330	5.1%	0.09	1.0 MB	4.5%	0.29
Dec 29 16:00:00 - 16:59:59	312	4.8%	0.09	997.1 KB	4.3%	0.28
Dec 29 17:00:00 - 17:59:59	221	3.4%	0.06	801.4 KB	3.5%	0.22
Dec 29 18:00:00 - 18:59:59	212	3.3%	0.06	1.0 MB	4.5%	0.29
Dec 29 19:00:00 - 19:59:59	178	2.7%	0.05	811.9 KB	3.5%	0.23
Dec 29 20:00:00 - 20:59:59	181	2.8%	0.05	1004.3 KB	4.3%	0.28
Dec 29 21:00:00 - 21:59:59	141	2.2%	0.04	488.1 KB	2.1%	0.14
Dec 29 22:00:00 - 22:59:59	95	1.5%	0.03	280.1 KB	1.2%	0.08
Dec 29 23:00:00 - 23:59:59	110	1.7%	0.03	359.2 KB	1.6%	0.10
Dec 30 00:00:00 - 00:01:01	1	0.0%	0.02	5.6 KB	0.0%	0.09
TOTAL: 23:59:59	6507	100.0%	0.08	22.6 MB	100.0%	0.27

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	101.6 GB	6.6%	0	0.41	45142.40
CYCTXT1	TEXT	5.0 GB	4.0 GB	79.8%	0	0.04	1666.74
TOTAL: 3	-	3077.0 GB	105.7 GB	3.4%	1	0.28	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	959	1	0	958
2	nntp.comgw.net	648	0	0	648
3	endofthelinebbs-out.news.weretis.net	467	0	0	467
4	newsfeed.xs3.de	326	0	1	325
5	news.netfront.net	167	0	0	167
6	newsfeed.bofh.team	166	1	1	164
7	i2pn.org	138	0	0	138
8	news.hispagatos.org	43	25	1	17
9	usenet.goja.nl.eu.org	36	0	0	36
10	news.samoylyk.net	5	0	0	5
11	endofthelinebbs.peers.news.panix.com	4	0	0	4
12	usenet.network	3	0	2	1
13	endoftheline-out.news.chmurka.net	2	0	0	2
14	glockenspiel.complete.org	2	0	0	2
15	outgoing.news.lysator.liu.se	1	0	1	0
TOTAL: 15		2967	27	6	2934

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	13
hispagatos.hacking.ctf	9
hispagatos.mastodon.tag.hackers	3
hispagatos.hacking.news	1
soc.culture.turkey	1
TOTAL: 5	27

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1336
Too many newsgroups	650
User-issued cancel	371
EMP (phr path)	288
EMP (md5)	220
HTML Multipart	8
Too many newsgroups (meow)	6
TOTAL: 7	2879

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	4	6	0	6
i2pn2-nocem@i2pn2.org (multipost)	9	0	13	13
i2pn2-nocem@i2pn2.org (rmp)	5	0	8	8
robot@pasdenom.info (spam3)	3	0	6	6
robot@pasdenom.info (spam2)	1	0	1	1
TOTAL: 5	22	6	28	34

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	32
	usenet.blueworldhosting.com	19
	glockenspiel.complete.org	1
	newsfeed.xs3.de	1
	usenet.goja.nl.eu.org	1
	i2pn.org	1
	TOTAL: 6	55
TOTAL: 1		55

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	6862	2717	3612	174	0	39%	23:59:52
2	news.netfront.net	10478	1671	4790	0	4	15%	23:59:19
3	endofthelinebbs.peers.news.panix.com	6685	803	5671	28	0	12%	23:59:41
4	news.furie.org.uk	3817	632	2266	9	0	16%	23:59:38
5	usenet.goja.nl.eu.org	7620	272	6040	30	0	3%	23:59:52
6	news.corradoroberto.it	946	226	465	0	0	23%	22:42:33
7	news.nntp4.net	7694	120	6302	6	0	1%	23:59:52
8	news.nk.ca	8450	103	5951	357	6	1%	23:59:52
9	usenet.network	8285	87	5946	14	0	1%	23:59:52
10	newsfeed.bofh.team	7293	86	6151	0	0	1%	23:59:40
11	news.hispagatos.org	7541	66	6258	120	0	0%	23:59:52
12	nntp.comgw.net	6792	59	6048	27	0	0%	23:59:09
13	i2pn.org	7130	57	6165	46	28	0%	23:59:52
14	news.bbs.nz	12107	54	6146	292	43	0%	23:58:51
15	news.weretis.net	6736	30	6419	56	0	0%	23:59:52
16	news.tnetconsulting.net	7293	29	6375	97	2	0%	23:59:52
17	news.quux.org	5190	27	4586	298	0	0%	23:59:40
18	newsfeed.xs3.de	7895	21	6218	133	0	0%	23:57:27
19	news.samoylyk.net	8026	16	6205	36	0	0%	23:59:52
20	news.chmurka.net	4111	15	3044	0	0	0%	23:58:23
21	ddt.demos.su	105	15	87	0	0	14%	09:21:35
22	usenet.blueworldhosting.com	5746	1	5313	1	0	0%	23:59:52
23	feed.opticnetworks.net	0	0	0	0	6507	0%	23:59:52
24	photonic.trudheim.com	0	0	0	0	6496	0%	23:59:52
TOTAL: 24		146802	7107	110058	1724	13086	4%	559:54:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	9.4 MB	797.6 KB	10.1 MB	0.1 KB/s	3.6 KB	23:59:52
2	news.netfront.net	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	4.0 KB	23:59:19
3	news.furie.org.uk	3.0 MB	34.2 KB	3.0 MB	0.0 KB/s	4.8 KB	23:59:38
4	endofthelinebbs.peers.news.panix.com	2.0 MB	157.5 KB	2.1 MB	0.0 KB/s	2.6 KB	23:59:41
5	news.quux.org	1.4 MB	534.3 KB	1.9 MB	0.0 KB/s	6.1 KB	23:59:40
6	news.samoylyk.net	1.4 MB	66.8 KB	1.4 MB	0.0 KB/s	28.1 KB	23:59:52
7	ddt.demos.su	974.0 KB	0.0 KB	974.0 KB	0.0 KB/s	64.9 KB	09:21:35
8	news.corradoroberto.it	650.0 KB	0.0 KB	650.0 KB	0.0 KB/s	2.9 KB	22:42:33
9	news.nntp4.net	563.6 KB	5.7 KB	569.4 KB	0.0 KB/s	4.5 KB	23:59:52
10	newsfeed.bofh.team	525.3 KB	0.0 KB	525.3 KB	0.0 KB/s	6.1 KB	23:59:40
11	usenet.goja.nl.eu.org	437.3 KB	181.8 KB	619.1 KB	0.0 KB/s	2.1 KB	23:59:52
12	usenet.network	213.2 KB	32.3 KB	245.4 KB	0.0 KB/s	2.4 KB	23:59:52
13	news.nk.ca	210.3 KB	2.0 MB	2.2 MB	0.0 KB/s	5.0 KB	23:59:52
14	news.bbs.nz	168.5 KB	2.2 MB	2.4 MB	0.0 KB/s	7.1 KB	23:58:51
15	news.hispagatos.org	153.0 KB	687.6 KB	840.6 KB	0.0 KB/s	4.5 KB	23:59:52
16	i2pn.org	109.7 KB	135.9 KB	245.5 KB	0.0 KB/s	2.4 KB	23:59:52
17	news.tnetconsulting.net	107.8 KB	324.8 KB	432.6 KB	0.0 KB/s	3.4 KB	23:59:52
18	news.weretis.net	106.2 KB	1.8 MB	1.9 MB	0.0 KB/s	23.2 KB	23:59:52
19	nntp.comgw.net	100.8 KB	1.4 MB	1.5 MB	0.0 KB/s	17.9 KB	23:59:09
20	newsfeed.xs3.de	59.5 KB	911.1 KB	970.5 KB	0.0 KB/s	6.3 KB	23:57:27
21	news.chmurka.net	36.0 KB	0.0 KB	36.0 KB	0.0 KB/s	2.4 KB	23:58:23
22	usenet.blueworldhosting.com	1.4 KB	4.8 KB	6.2 KB	0.0 KB/s	3.1 KB	23:59:52
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
24	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 24		28.0 MB	11.3 MB	39.3 MB	0.0 KB/s	4.6 KB	559:54:12

NNRP readership statistics:

System		Conn	Elapsed
1	167.94.145.111	3	00:00:04
2	199.45.154.137	3	00:00:05
3	106.75.66.161	1	00:00:01
4	147.185.132.112	1	00:00:00
5	147.45.112.158	1	00:00:00
6	205.210.31.12	1	00:00:06
7	localhost	1	00:12:14
8	thoughtful.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 8		12	00:12:35

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	10	00:00:18
2	*.monitoring.internet-measurement.com	1	00:00:01
3	?	1	00:12:14
TOTAL: 3		12	00:12:35

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
localhost	180.677	373.911	0.075	00:12:14
205.210.31.12	0.000	0.039	0.000	00:00:06
199.45.154.137	0.022	0.058	0.000	00:00:05
167.94.145.111	0.020	0.046	0.000	00:00:04
thoughtful.monitoring.internet-measurement.com	0.000	0.017	0.000	00:00:01
106.75.66.161	0.000	0.017	0.000	00:00:01
147.185.132.112	0.003	0.013	0.000	00:00:00
147.45.112.158	0.000	0.025	0.000	00:00:00
TOTAL: 8	180.722	374.126	0.075	00:12:35

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

System	Conn
147.45.112.158	1
167.94.145.111	1
199.45.154.137	1
TOTAL: 3	3

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

Command	Count
EHLO www.censys.io	2
#003	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.158	1	1
167.94.145.111	1	2
199.45.154.137	1	2
TOTAL: 3	3	7

Daily Usenet Report

Dec 29 00:01:02 -- Dec 30 00:01:01