Daily Usenet Report: Jan 8 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jan 8 14:00:03 newsfeed controlchan[2249910]: Unknown "tags" control by robomod@news.nic.it
Jan 9 00:00:01 newsfeed nnrpd[2827856]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	10443	44.1%	3.8 MB	64.2%
innd	8290	35.0%	1.2 MB	20.3%
innfeed	4390	18.6%	849.3 KB	14.0%
cnfsstat	432	1.8%	75.5 KB	1.2%
nnrpd	56	0.2%	6.1 KB	0.1%
nocem	49	0.2%	5.5 KB	0.1%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 7	23662	100.0%	5.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	116464	60.2%
Negative hits	52334	27.0%
Cache misses	14378	7.4%
Do not exist	10336	5.3%
TOTAL: 4	193512	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.107	0.0%	24	0.000	4.458	8.000
article cleanup	00:00:00.806	0.0%	10382	0.000	0.078	3.505
article logging	00:00:00.772	0.0%	10421	0.000	0.074	3.088
article parse	00:00:00.278	0.0%	17865	0.000	0.016	0.303
article write	00:00:02.524	0.0%	7379	0.000	0.342	6.373
artlog/artcncl	00:00:00.000	0.0%	2	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:00.632	0.0%	183679	0.000	0.003	0.046
hisgrep/artcncl	00:00:00.084	0.0%	26	0.000	3.231	3.833
hishave/artcncl	00:00:00.000	0.0%	2	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:05:00.946	0.4%	194205	0.107	1.550	10.755
history sync	00:00:00.053	0.0%	395	0.000	0.134	1.667
history write	00:02:35.005	0.2%	10375	3.293	14.940	112.091
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:38:28.270	99.1%	176936	92.436	481.012	1692.475
nntp read	00:00:03.828	0.0%	184148	0.000	0.021	0.295
overview write	00:03:32.084	0.2%	7379	0.524	28.742	1160.197
perl filter	00:00:38.189	0.0%	10375	1.115	3.681	26.051
python filter	00:00:01.897	0.0%	10375	0.000	0.183	4.173
site send	00:00:01.142	0.0%	14807	0.000	0.077	0.273
TOTAL: 23:51:07.373	23:50:26.617	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	27384	0.000	0.001	0.062
article prepare	00:00:00.006	0.0%	70568	0.000	0.000	0.004
article read	00:00:02.644	0.0%	6664	0.000	0.397	1.562
backlog stats	00:00:00.420	0.0%	278587	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.374	0.0%	186798	0.000	0.013	0.051
data write	00:00:03.363	0.0%	181891	0.005	0.018	0.034
idle	23:57:36.025	100.0%	278587	64.523	309.620	1260.658
status file	00:00:00.474	0.0%	392	1.000	1.209	8.000
TOTAL: 23:57:56.311	23:57:45.341	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:35.779	4.7%	19	0.000	1883.105	10059.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.525	0.5%	78837	0.000	0.045	0.500
TOTAL: 00:12:36.657	00:00:39.304	5.2%	-	-	-	-

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	13977	1873	11714	390	13%	49:51:56
2	glockenspiel.complete.org	2	5531	1743	3783	5	31%	23:45:54
3	usenet.blueworldhosting.com	303	9078	1074	7072	932	11%	23:43:38
4	nntp.comgw.net	1	8309	537	7449	323	6%	23:59:57
5	news.corradoroberto.it	7	1384	534	850	0	38%	23:35:10
6	usenet.network	2	6034	411	5623	0	6%	23:59:15
7	endoftheline-out.news.chmurka.net	1	3477	262	3215	0	7%	23:59:57
8	i2pn.org	2	8030	213	7754	63	2%	23:59:46
9	newsfeed.bofh.team	2	8811	194	8592	25	2%	23:59:35
10	194.87.241.33	47	261	164	97	0	62%	15:15:51
11	usenet.goja.nl.eu.org	10	8012	156	7813	43	1%	23:55:10
12	news.samoylyk.net	2	18736	78	18316	342	0%	08:48:14
13	news.nntp4.net	2	6433	68	6351	14	1%	23:59:52
14	endofthelinebbs.peers.news.panix.com	22	8092	55	8034	3	0%	23:55:12
15	newsfeed.xs3.de	2	5930	48	5862	20	0%	23:58:43
16	outgoing.news.lysator.liu.se	1	4043	37	3281	725	0%	23:59:57
17	news.hispagatos.org	6	8279	32	8200	47	0%	24:26:06
18	news.netfront.net	1	6685	30	6606	49	0%	23:49:07
19	news.bbs.nz	2	6574	12	6562	0	0%	23:59:30
20	news.furie.org.uk	2	3778	9	3767	2	0%	23:59:53
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:03
22	localhost	26	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		449	141454	7530	130941	2983	5%	481:32:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.2 MB	0.0 KB	722.2 KB	6.9 MB	89%	3.1 KB
2	glockenspiel.complete.org	5.2 MB	0.0 KB	5.3 KB	5.2 MB	99%	3.0 KB
3	usenet.network	5.0 MB	0.0 KB	0.0 KB	5.0 MB	100%	12.5 KB
4	news.corradoroberto.it	4.4 MB	0.0 KB	0.0 KB	4.4 MB	100%	8.5 KB
5	usenet.blueworldhosting.com	2.6 MB	0.0 KB	2.0 MB	4.6 MB	55%	2.3 KB
6	nntp.comgw.net	2.5 MB	291.9 KB	637.0 KB	3.4 MB	72%	4.0 KB
7	endoftheline-out.news.chmurka.net	1.1 MB	0.0 KB	0.0 KB	1.1 MB	100%	4.3 KB
8	news.samoylyk.net	1.0 MB	290.4 KB	616.8 KB	1.9 MB	53%	4.6 KB
9	newsfeed.bofh.team	636.9 KB	290.4 KB	51.1 KB	978.5 KB	65%	4.5 KB
10	i2pn.org	525.6 KB	0.0 KB	89.5 KB	615.1 KB	85%	2.2 KB
11	194.87.241.33	517.4 KB	0.0 KB	0.0 KB	517.4 KB	100%	3.2 KB
12	newsfeed.xs3.de	493.8 KB	290.4 KB	88.7 KB	873.0 KB	56%	12.8 KB
13	usenet.goja.nl.eu.org	463.1 KB	0.0 KB	84.9 KB	548.0 KB	84%	2.8 KB
14	endofthelinebbs.peers.news.panix.com	210.7 KB	0.0 KB	3.3 KB	214.0 KB	98%	3.7 KB
15	news.nntp4.net	182.6 KB	0.0 KB	53.3 KB	235.8 KB	77%	2.9 KB
16	news.hispagatos.org	122.0 KB	0.0 KB	60.3 KB	182.3 KB	66%	2.3 KB
17	outgoing.news.lysator.liu.se	103.6 KB	290.5 KB	696.8 KB	1.1 MB	9%	1.4 KB
18	news.netfront.net	84.3 KB	0.0 KB	194.6 KB	278.9 KB	30%	3.5 KB
19	news.furie.org.uk	38.1 KB	290.5 KB	0.7 KB	329.2 KB	11%	29.9 KB
20	news.bbs.nz	18.8 KB	0.0 KB	0.0 KB	18.8 KB	100%	1.6 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		31.2 MB	1.7 MB	5.2 MB	38.1 MB	81%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 8 00:01:01 - 00:59:59	157	2.1%	0.04	615.1 KB	2.0%	0.17
Jan 8 01:00:00 - 01:59:59	214	2.9%	0.06	708.5 KB	2.3%	0.20
Jan 8 02:00:00 - 02:59:59	307	4.2%	0.09	1.7 MB	5.7%	0.49
Jan 8 03:00:00 - 03:59:59	422	5.7%	0.12	1.4 MB	4.4%	0.38
Jan 8 04:00:00 - 04:59:59	406	5.5%	0.11	1.3 MB	4.2%	0.36
Jan 8 05:00:00 - 05:59:59	378	5.1%	0.10	1.2 MB	3.8%	0.33
Jan 8 06:00:00 - 06:59:59	362	4.9%	0.10	1.3 MB	4.2%	0.37
Jan 8 07:00:00 - 07:59:59	436	5.9%	0.12	1.5 MB	4.9%	0.42
Jan 8 08:00:00 - 08:59:59	402	5.4%	0.11	1.3 MB	4.2%	0.37
Jan 8 09:00:00 - 09:59:59	416	5.6%	0.12	1.5 MB	4.9%	0.43
Jan 8 10:00:00 - 10:59:59	459	6.2%	0.13	1.9 MB	6.3%	0.55
Jan 8 11:00:00 - 11:59:59	385	5.2%	0.11	1.2 MB	3.9%	0.34
Jan 8 12:00:00 - 12:59:59	418	5.7%	0.12	1.8 MB	5.7%	0.50
Jan 8 13:00:00 - 13:59:59	463	6.3%	0.13	4.2 MB	13.7%	1.19
Jan 8 14:00:00 - 14:59:59	379	5.1%	0.11	1.2 MB	3.8%	0.33
Jan 8 15:00:00 - 15:59:59	330	4.5%	0.09	1.3 MB	4.4%	0.38
Jan 8 16:00:00 - 16:59:59	338	4.6%	0.09	1.6 MB	5.4%	0.47
Jan 8 17:00:00 - 17:59:59	276	3.7%	0.08	1.5 MB	4.8%	0.42
Jan 8 18:00:00 - 18:59:59	168	2.3%	0.05	858.2 KB	2.7%	0.24
Jan 8 19:00:00 - 19:59:59	153	2.1%	0.04	904.5 KB	2.9%	0.25
Jan 8 20:00:00 - 20:59:59	146	2.0%	0.04	443.7 KB	1.4%	0.12
Jan 8 21:00:00 - 21:59:59	124	1.7%	0.03	613.3 KB	2.0%	0.17
Jan 8 22:00:00 - 22:59:59	113	1.5%	0.03	348.0 KB	1.1%	0.10
Jan 8 23:00:00 - 23:59:59	134	1.8%	0.04	371.2 KB	1.2%	0.10
Jan 9 00:00:00 - 00:01:01	4	0.1%	0.07	9.2 KB	0.0%	0.15
TOTAL: 24:00:00	7390	100.0%	0.09	30.5 MB	100.0%	0.36

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	102.0 GB	6.6%	0	0.53	35040.11
CYCTXT1	TEXT	5.0 GB	4.0 GB	80.0%	0	0.03	2052.50
TOTAL: 3	-	3077.0 GB	106.1 GB	3.4%	1	0.37	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	951	1	0	950
2	outgoing.news.lysator.liu.se	726	0	1	725
3	endofthelinebbs-out.news.weretis.net	427	0	0	427
4	news.samoylyk.net	342	0	1	341
5	nntp.comgw.net	331	0	2	329
6	i2pn.org	63	0	0	63
7	news.hispagatos.org	48	37	0	11
8	news.netfront.net	47	0	0	47
9	usenet.goja.nl.eu.org	44	0	0	44
10	newsfeed.bofh.team	25	0	1	24
11	newsfeed.xs3.de	20	0	1	19
12	news.nntp4.net	14	0	0	14
13	glockenspiel.complete.org	8	0	0	8
14	endofthelinebbs.peers.news.panix.com	3	0	0	3
15	news.furie.org.uk	2	0	1	1
TOTAL: 15		3051	38	7	3006

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	16
hispagatos.hacking.ctf	11
hispagatos.hacking.news	7
hispagatos.mastodon.tag.hackers	3
soc.culture.russia	1
TOTAL: 5	38

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1378
EMP (phr path)	1018
EMP (md5)	215
Too many newsgroups	177
EMP (phn nph)	127
Too many newsgroups (meow)	27
EMP (fsl)	12
User-issued cancel	7
HTML Multipart	4
Angle-bracket bot	2
TOTAL: 10	2967

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
i2pn2-nocem@i2pn2.org (multipost)	39	44	44
i2pn2-nocem@i2pn2.org (rmp)	2	7	7
robot@pasdenom.info (spam3)	4	6	6
robot@pasdenom.info (spam2)	3	3	3
TOTAL: 4	48	60	60

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	19
	endofthelinebbs-out.news.weretis.net	9
	usenet.goja.nl.eu.org	4
	glockenspiel.complete.org	3
	nntp.comgw.net	3
	outgoing.news.lysator.liu.se	1
	TOTAL: 6	39
TOTAL: 1		39

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7838	4205	2664	484	0	53%	23:59:52
2	news.netfront.net	11709	2340	5019	1	11	19%	23:58:39
3	endofthelinebbs.peers.news.panix.com	7653	1369	6010	2	0	17%	23:59:53
4	news.furie.org.uk	4637	995	2443	17	2	21%	23:59:53
5	usenet.goja.nl.eu.org	8506	503	6287	248	0	5%	23:59:53
6	newsfeed.bofh.team	8314	350	6836	0	0	4%	23:59:12
7	news.hispagatos.org	8801	234	6728	380	1	2%	23:59:53
8	news.bbs.nz	13339	213	6480	685	103	1%	23:59:53
9	news.nntp4.net	8521	211	7088	7	6	2%	23:58:46
10	news.corradoroberto.it	1346	209	658	0	0	15%	22:51:55
11	news.tnetconsulting.net	8238	207	6798	374	14	2%	23:59:53
12	usenet.network	9091	204	6473	65	0	2%	23:58:08
13	news.weretis.net	7749	182	6873	333	1	2%	23:59:53
14	news.quux.org	5926	179	4653	644	3	3%	23:57:20
15	nntp.comgw.net	7785	143	6545	176	0	1%	23:59:40
16	newsfeed.xs3.de	9193	135	6865	330	0	1%	23:59:37
17	i2pn.org	8028	125	6956	68	69	1%	23:59:53
18	news.nk.ca	9782	101	6477	721	58	1%	23:59:53
19	news.samoylyk.net	23164	20	21018	1218	4736	0%	23:59:53
20	ddt.demos.su	49	6	42	0	0	12%	06:14:41
21	usenet.blueworldhosting.com	6787	4	6171	1	0	0%	23:57:23
22	news.chmurka.net	3850	4	2921	0	0	0%	23:59:53
23	feed.opticnetworks.net	0	0	0	0	7390	0%	23:59:53
24	photonic.trudheim.com	0	0	0	0	7383	0%	23:59:53
TOTAL: 24		180306	11939	132005	5754	19777	6%	556:53:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	14.7 MB	4.3 MB	19.0 MB	0.2 KB/s	4.2 KB	23:59:52
2	news.netfront.net	14.4 MB	13.5 KB	14.4 MB	0.2 KB/s	6.3 KB	23:58:39
3	news.furie.org.uk	7.5 MB	99.1 KB	7.6 MB	0.1 KB/s	7.7 KB	23:59:53
4	endofthelinebbs.peers.news.panix.com	7.3 MB	3.5 KB	7.3 MB	0.1 KB/s	5.5 KB	23:59:53
5	newsfeed.bofh.team	3.4 MB	0.0 KB	3.4 MB	0.0 KB/s	10.0 KB	23:59:12
6	news.quux.org	2.2 MB	3.6 MB	5.9 MB	0.1 KB/s	7.3 KB	23:57:20
7	news.hispagatos.org	1.8 MB	3.4 MB	5.2 MB	0.1 KB/s	8.7 KB	23:59:53
8	usenet.goja.nl.eu.org	1.8 MB	2.4 MB	4.2 MB	0.1 KB/s	5.8 KB	23:59:53
9	news.tnetconsulting.net	1.7 MB	3.4 MB	5.0 MB	0.1 KB/s	8.9 KB	23:59:53
10	news.nntp4.net	1.6 MB	57.5 KB	1.7 MB	0.0 KB/s	7.8 KB	23:58:46
11	usenet.network	1.6 MB	125.6 KB	1.7 MB	0.0 KB/s	6.5 KB	23:58:08
12	nntp.comgw.net	1.3 MB	2.5 MB	3.7 MB	0.0 KB/s	11.9 KB	23:59:40
13	newsfeed.xs3.de	1.2 MB	2.7 MB	4.0 MB	0.0 KB/s	8.7 KB	23:59:37
14	news.weretis.net	1.1 MB	4.8 MB	5.9 MB	0.1 KB/s	11.8 KB	23:59:53
15	news.bbs.nz	956.3 KB	5.6 MB	6.5 MB	0.1 KB/s	7.4 KB	23:59:53
16	i2pn.org	826.4 KB	308.6 KB	1.1 MB	0.0 KB/s	5.9 KB	23:59:53
17	ddt.demos.su	732.6 KB	0.0 KB	732.6 KB	0.0 KB/s	122.1 KB	06:14:41
18	news.corradoroberto.it	486.2 KB	0.0 KB	486.2 KB	0.0 KB/s	2.3 KB	22:51:55
19	news.samoylyk.net	225.1 KB	10.6 MB	10.8 MB	0.1 KB/s	9.0 KB	23:59:53
20	news.nk.ca	203.4 KB	4.8 MB	5.0 MB	0.1 KB/s	6.2 KB	23:59:53
21	usenet.blueworldhosting.com	19.0 KB	26.4 KB	45.4 KB	0.0 KB/s	9.1 KB	23:57:23
22	news.chmurka.net	13.3 KB	0.0 KB	13.3 KB	0.0 KB/s	3.3 KB	23:59:53
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
24	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 24		65.0 MB	48.7 MB	113.7 MB	0.1 KB/s	6.6 KB	556:53:42

NNRP readership statistics:

System		Conn	Elapsed
1	206.168.34.86	5	00:00:52
2	198.235.24.31	1	00:00:10
3	198.235.24.82	1	00:00:10
4	78.150.216.162.bc.googleusercontent.com	1	00:00:00
5	azpdegb29.stretchoid.com	1	00:00:00
6	azpdssc25.stretchoid.com	1	00:00:09
7	localhost	1	00:11:14
TOTAL: 7		11	00:12:36

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	7	00:01:12
2	*.stretchoid.com	2	00:00:09
3	*.150.216.162.bc.googleusercontent.com	1	00:00:00
4	?	1	00:11:14
TOTAL: 4		11	00:12:36

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	182.036	369.325	0.074	00:11:14
206.168.34.86	0.043	0.069	0.000	00:00:52
198.235.24.82	0.000	0.043	0.000	00:00:10
198.235.24.31	0.013	0.004	0.000	00:00:10
azpdssc25.stretchoid.com	0.011	0.014	0.000	00:00:09
azpdegb29.stretchoid.com	0.000	0.026	0.000	00:00:00
78.150.216.162.bc.googleusercontent.com	0.000	0.023	0.000	00:00:00
TOTAL: 7	182.103	369.504	0.074	00:12:36

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

System	Conn
78.150.216.162.bc.googleusercontent.com	4
206.168.34.86	3
azpdegb29.stretchoid.com	1
TOTAL: 3	8

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

Command	Count
GET / HTTP/1.1	2
Host: 144.172.126.95:119	2
Accept-Encoding: gzip	1
EHLO www.censys.io	1
MGLNDD_144.172.126.95_119	1
User-Agent: Expanse, a Palo Alto Network...	1
TOTAL: 6	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
206.168.34.86	2	2
TOTAL: 1	2	2

Daily Usenet Report

Jan 8 00:01:01 -- Jan 9 00:01:01