Daily Usenet Report: Sep 22 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 22 08:13:22 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 23 00:00:01 newsfeed nnrpd[96553]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	8438	29.3%	857.3 KB	13.8%
innd	8007	27.8%	1.1 MB	18.9%
inn	7834	27.2%	3.2 MB	53.1%
innfeed	4082	14.2%	813.7 KB	13.1%
cnfsstat	432	1.5%	63.9 KB	1.0%
nocem	29	0.1%	4.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	28823	100.0%	6.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	113951	60.3%
Negative hits	67331	35.6%
Do not exist	7751	4.1%
Cache misses	1	0.0%
TOTAL: 4	189034	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.176	0.0%	65	0.000	2.708	12.000
article cleanup	00:00:00.094	0.0%	7779	0.000	0.012	0.111
article logging	00:00:00.328	0.0%	7805	0.000	0.042	0.222
article parse	00:00:00.171	0.0%	10653	0.000	0.016	0.093
article write	00:00:02.270	0.0%	6722	0.000	0.338	3.667
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:01.270	0.0%	193012	0.000	0.007	0.231
hisgrep/artcncl	00:00:00.128	0.0%	67	0.000	1.910	8.000
hishave/artcncl	00:00:00.000	0.0%	8	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:26.861	0.2%	189774	0.103	0.774	5.946
history sync	00:00:00.075	0.0%	405	0.000	0.185	1.000
history write	00:01:49.359	0.1%	7778	3.375	14.060	122.536
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:52:00.791	99.4%	192522	62.006	446.291	2273.750
nntp read	00:00:03.235	0.0%	194135	0.000	0.017	0.482
overview write	00:03:11.306	0.2%	6722	0.562	28.460	1638.259
perl filter	00:00:30.350	0.0%	7777	1.642	3.903	28.556
python filter	00:00:02.757	0.0%	7778	0.000	0.354	7.340
site send	00:00:01.017	0.0%	13470	0.000	0.076	0.262
TOTAL: 24:01:05.458	24:00:10.188	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.016	0.0%	6960	0.000	0.002	0.050
article prepare	00:00:00.005	0.0%	38924	0.000	0.000	0.004
article read	00:00:00.425	0.0%	3157	0.000	0.135	1.000
backlog stats	00:00:00.323	0.0%	241049	0.000	0.001	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.227	0.0%	167619	0.002	0.013	0.045
data write	00:00:02.756	0.0%	163020	0.007	0.017	0.036
idle	23:57:58.480	100.0%	241049	165.906	357.929	1766.126
status file	00:00:00.339	0.0%	255	1.000	1.329	32.000
TOTAL: 23:58:16.358	23:58:04.571	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:21:46.169	52.5%	8328	0.500	156.841	10038.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.202	0.2%	95459	0.000	0.044	0.333
TOTAL: 00:41:28.968	00:21:50.371	52.6%	-	-	-	-

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	outgoing.news.lysator.liu.se	1	5743	1180	4553	10	20%	23:59:56
2	endofthelinebbs-out.news.weretis.net	3	13982	1003	12784	195	7%	54:12:38
3	nntp.comgw.net	2	7180	895	6145	140	12%	23:57:52
4	usenet.blueworldhosting.com	718	7521	747	6418	356	9%	23:55:44
5	newsfeed.xs3.de	1	5756	726	4991	39	12%	23:59:56
6	i2pn.org	1	7131	449	6629	53	6%	23:59:52
7	news.hispagatos.org	5	6970	399	6552	19	5%	24:26:22
8	news.corradoroberto.it	11	1290	356	934	0	27%	23:19:57
9	usenet.goja.nl.eu.org	10	6988	327	6600	61	4%	23:59:47
10	endoftheline-out.news.chmurka.net	1	3558	309	3248	1	8%	23:59:56
11	usenet.network	1	5310	120	5168	22	2%	23:59:57
12	endofthelinebbs.peers.news.panix.com	25	7131	101	7021	9	1%	23:50:10
13	news.samoylyk.net	1	6347	77	6270	0	1%	23:59:48
14	news.netfront.net	1	5843	63	5621	159	1%	24:58:30
15	newsfeed.bofh.team	1	7510	59	7446	5	0%	23:59:55
16	news.nntp4.net	2	6176	47	6128	1	0%	23:59:41
17	glockenspiel.complete.org	2	5058	14	5044	0	0%	24:02:32
18	news.furie.org.uk	3	6085	12	6073	0	0%	47:59:35
19	news.bbs.nz	2	5848	11	5837	0	0%	23:59:31
20	photonic.trudheim.com	5	1708	11	1697	0	0%	29:00:55
21	localhost	28	1	1	0	0	100%	00:00:01
22	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
23	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 23		827	123136	6907	115159	1070	5%	540:12:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	3.5 MB	0.0 KB	355.1 KB	3.8 MB	90%	3.3 KB
2	outgoing.news.lysator.liu.se	3.4 MB	0.0 KB	9.7 KB	3.4 MB	99%	2.9 KB
3	nntp.comgw.net	2.3 MB	0.0 KB	281.6 KB	2.6 MB	89%	2.6 KB
4	newsfeed.xs3.de	2.0 MB	0.0 KB	44.9 KB	2.0 MB	97%	2.7 KB
5	news.corradoroberto.it	1.9 MB	0.0 KB	0.0 KB	1.9 MB	100%	5.4 KB
6	usenet.blueworldhosting.com	1.7 MB	0.0 KB	689.6 KB	2.4 MB	71%	2.2 KB
7	i2pn.org	1.3 MB	0.0 KB	81.8 KB	1.3 MB	94%	2.8 KB
8	news.hispagatos.org	1.2 MB	0.0 KB	20.2 KB	1.2 MB	98%	3.1 KB
9	usenet.network	1.1 MB	0.0 KB	20.4 KB	1.1 MB	98%	7.8 KB
10	usenet.goja.nl.eu.org	1.0 MB	3.3 KB	76.2 KB	1.1 MB	93%	2.9 KB
11	endoftheline-out.news.chmurka.net	742.4 KB	0.0 KB	0.7 KB	743.1 KB	99%	2.4 KB
12	news.samoylyk.net	333.5 KB	0.0 KB	0.0 KB	333.5 KB	100%	4.3 KB
13	endofthelinebbs.peers.news.panix.com	254.7 KB	0.0 KB	17.2 KB	271.9 KB	93%	2.5 KB
14	news.netfront.net	182.6 KB	0.0 KB	1.0 MB	1.2 MB	14%	5.6 KB
15	newsfeed.bofh.team	156.1 KB	0.0 KB	7.4 KB	163.5 KB	95%	2.6 KB
16	news.nntp4.net	98.4 KB	0.0 KB	0.7 KB	99.1 KB	99%	2.1 KB
17	news.furie.org.uk	80.0 KB	0.0 KB	0.0 KB	80.0 KB	100%	6.7 KB
18	photonic.trudheim.com	49.3 KB	0.0 KB	0.0 KB	49.3 KB	100%	4.5 KB
19	glockenspiel.complete.org	41.7 KB	0.0 KB	0.0 KB	41.7 KB	100%	3.0 KB
20	news.bbs.nz	27.2 KB	0.0 KB	0.0 KB	27.2 KB	100%	2.5 KB
21	localhost	1.2 KB	0.0 KB	0.0 KB	1.2 KB	100%	1.2 KB
22	eotl-out.news.tnetconsulting.net	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		21.3 MB	3.3 KB	2.6 MB	23.9 MB	89%	3.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 22 00:01:01 - 00:59:59	121	1.8%	0.03	386.0 KB	1.8%	0.11
Sep 22 01:00:00 - 01:59:59	231	3.4%	0.06	813.7 KB	3.8%	0.23
Sep 22 02:00:00 - 02:59:59	273	4.1%	0.08	687.5 KB	3.2%	0.19
Sep 22 03:00:00 - 03:59:59	336	5.0%	0.09	1005.9 KB	4.7%	0.28
Sep 22 04:00:00 - 04:59:59	355	5.3%	0.10	965.0 KB	4.5%	0.27
Sep 22 05:00:00 - 05:59:59	336	5.0%	0.09	1019.2 KB	4.8%	0.28
Sep 22 06:00:00 - 06:59:59	347	5.2%	0.10	986.3 KB	4.6%	0.27
Sep 22 07:00:00 - 07:59:59	299	4.4%	0.08	1.5 MB	7.2%	0.42
Sep 22 08:00:00 - 08:59:59	407	6.0%	0.11	1.0 MB	5.0%	0.30
Sep 22 09:00:00 - 09:59:59	457	6.8%	0.13	1.3 MB	6.1%	0.36
Sep 22 10:00:00 - 10:59:59	306	4.5%	0.09	1010.9 KB	4.7%	0.28
Sep 22 11:00:00 - 11:59:59	371	5.5%	0.10	1.1 MB	5.4%	0.32
Sep 22 12:00:00 - 12:59:59	333	4.9%	0.09	1.2 MB	5.7%	0.33
Sep 22 13:00:00 - 13:59:59	385	5.7%	0.11	1.1 MB	5.3%	0.31
Sep 22 14:00:00 - 14:59:59	421	6.3%	0.12	1.1 MB	5.4%	0.32
Sep 22 15:00:00 - 15:59:59	411	6.1%	0.11	1.2 MB	5.6%	0.33
Sep 22 16:00:00 - 16:59:59	390	5.8%	0.11	1.4 MB	6.7%	0.40
Sep 22 17:00:00 - 17:59:59	222	3.3%	0.06	855.0 KB	4.0%	0.24
Sep 22 18:00:00 - 18:59:59	165	2.5%	0.05	544.8 KB	2.6%	0.15
Sep 22 19:00:00 - 19:59:59	136	2.0%	0.04	365.0 KB	1.7%	0.10
Sep 22 20:00:00 - 20:59:59	116	1.7%	0.03	402.1 KB	1.9%	0.11
Sep 22 21:00:00 - 21:59:59	90	1.3%	0.03	453.8 KB	2.1%	0.13
Sep 22 22:00:00 - 22:59:59	98	1.5%	0.03	322.3 KB	1.5%	0.09
Sep 22 23:00:00 - 23:59:59	121	1.8%	0.03	327.1 KB	1.5%	0.09
Sep 23 00:00:00 - 00:01:01	6	0.1%	0.10	10.6 KB	0.0%	0.17
TOTAL: 24:00:00	6733	100.0%	0.08	20.8 MB	100.0%	0.25

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	97.7 GB	6.4%	0	0.40	46202.02
CYCTXT1	TEXT	5.0 GB	3.9 GB	77.4%	0	0.03	2230.51
TOTAL: 3	-	3077.0 GB	101.6 GB	3.3%	1	0.28	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	367	0	0	367
2	endofthelinebbs-out.news.weretis.net	186	0	0	186
3	news.netfront.net	162	0	0	162
4	nntp.comgw.net	158	0	0	158
5	i2pn.org	56	0	0	56
6	usenet.goja.nl.eu.org	49	0	1	48
7	newsfeed.xs3.de	43	0	0	43
8	news.hispagatos.org	24	9	0	15
9	usenet.network	21	0	0	21
10	outgoing.news.lysator.liu.se	11	0	0	11
11	endofthelinebbs.peers.news.panix.com	9	0	0	9
12	newsfeed.bofh.team	5	0	0	5
13	endoftheline-out.news.chmurka.net	1	0	0	1
14	news.nntp4.net	1	0	0	1
TOTAL: 14		1093	9	1	1083

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	9
TOTAL: 1	9

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	355
EMP (phr path)	323
EMP (md5)	215
Too many newsgroups	92
User-issued cancel	47
Binary: misplaced binary	15
HTML Multipart	7
Too many newsgroups (meow)	2
TOTAL: 8	1056

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
nocem@eternal-september.org (bot)	2	6	0	6
i2pn2-nocem@i2pn2.org (multipost)	9	0	47	47
i2pn2-nocem@i2pn2.org (rmp)	10	0	41	41
robot@pasdenom.info (spam2)	3	0	3	3
robot@pasdenom.info (spam3)	2	0	2	2
TOTAL: 5	26	6	93	99

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	11
	endofthelinebbs-out.news.weretis.net	9
	newsfeed.xs3.de	4
	outgoing.news.lysator.liu.se	1
	nntp.comgw.net	1
	TOTAL: 5	26
TOTAL: 1		26

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10868	1878	4792	0	3	17%	23:59:51
2	nyheter.lysator.liu.se	5757	1432	4007	115	0	24%	23:59:51
3	news.furie.org.uk	3140	658	2067	8	1	20%	23:59:51
4	photonic.trudheim.com	7205	358	5460	903	2	4%	23:59:51
5	endofthelinebbs.peers.news.panix.com	7075	341	6380	9	0	4%	23:59:51
6	news.hispagatos.org	7260	331	5966	48	2	4%	23:59:10
7	usenet.goja.nl.eu.org	7362	326	6064	27	13	4%	23:59:50
8	usenet.network	7359	310	6053	21	8	4%	23:58:36
9	news.bbs.nz	12375	216	6107	402	48	1%	23:59:29
10	newsfeed.bofh.team	7398	171	6366	0	14	2%	23:59:51
11	news.tnetconsulting.net	7827	147	6469	113	2	1%	23:59:51
12	news.nntp4.net	7599	145	6523	13	18	1%	23:59:51
13	news.nk.ca	9460	142	6139	374	27	1%	23:59:51
14	nntp.comgw.net	6578	141	5638	22	11	2%	23:59:19
15	news.weretis.net	6883	114	6556	60	0	1%	23:59:29
16	news.quux.org	7284	100	6304	323	0	1%	23:59:51
17	i2pn.org	7185	91	6174	4	27	1%	23:59:50
18	news.corradoroberto.it	1019	77	758	0	0	7%	23:00:47
19	newsfeed.xs3.de	7151	75	5817	114	13	1%	23:59:08
20	news.chmurka.net	3648	36	2963	0	12	0%	23:59:51
21	news.samoylyk.net	7942	20	6566	64	32	0%	23:59:48
22	usenet.blueworldhosting.com	6208	3	5803	0	0	0%	23:59:45
23	feed.opticnetworks.net	0	0	0	0	6733	0%	23:59:51
TOTAL: 23		154583	7112	118972	2620	6966	4%	550:53:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	6.9 MB	0.0 KB	6.9 MB	0.1 KB/s	3.8 KB	23:59:51
2	nyheter.lysator.liu.se	4.5 MB	1018.9 KB	5.5 MB	0.1 KB/s	3.7 KB	23:59:51
3	news.furie.org.uk	3.6 MB	192.0 KB	3.8 MB	0.0 KB/s	5.8 KB	23:59:51
4	photonic.trudheim.com	1.8 MB	2.6 MB	4.4 MB	0.1 KB/s	3.6 KB	23:59:51
5	endofthelinebbs.peers.news.panix.com	1.3 MB	14.8 KB	1.3 MB	0.0 KB/s	3.9 KB	23:59:51
6	news.hispagatos.org	1.2 MB	578.4 KB	1.8 MB	0.0 KB/s	4.8 KB	23:59:10
7	usenet.network	1.1 MB	55.5 KB	1.1 MB	0.0 KB/s	3.5 KB	23:58:36
8	newsfeed.bofh.team	1.0 MB	0.0 KB	1.0 MB	0.0 KB/s	6.1 KB	23:59:51
9	usenet.goja.nl.eu.org	819.3 KB	363.8 KB	1.2 MB	0.0 KB/s	3.4 KB	23:59:50
10	news.nntp4.net	770.2 KB	153.0 KB	923.1 KB	0.0 KB/s	5.8 KB	23:59:51
11	news.tnetconsulting.net	735.0 KB	532.7 KB	1.2 MB	0.0 KB/s	4.9 KB	23:59:51
12	news.quux.org	715.3 KB	1.0 MB	1.7 MB	0.0 KB/s	4.2 KB	23:59:51
13	news.bbs.nz	676.9 KB	1.4 MB	2.1 MB	0.0 KB/s	3.5 KB	23:59:29
14	news.weretis.net	524.7 KB	565.2 KB	1.1 MB	0.0 KB/s	6.3 KB	23:59:29
15	nntp.comgw.net	520.7 KB	438.9 KB	959.6 KB	0.0 KB/s	5.9 KB	23:59:19
16	newsfeed.xs3.de	415.5 KB	614.7 KB	1.0 MB	0.0 KB/s	5.5 KB	23:59:08
17	i2pn.org	387.6 KB	42.3 KB	429.9 KB	0.0 KB/s	4.5 KB	23:59:50
18	news.nk.ca	279.7 KB	1.4 MB	1.7 MB	0.0 KB/s	3.4 KB	23:59:51
19	news.corradoroberto.it	267.2 KB	0.0 KB	267.2 KB	0.0 KB/s	3.5 KB	23:00:47
20	news.samoylyk.net	244.5 KB	323.6 KB	568.1 KB	0.0 KB/s	6.8 KB	23:59:48
21	news.chmurka.net	121.9 KB	0.0 KB	121.9 KB	0.0 KB/s	3.4 KB	23:59:51
22	usenet.blueworldhosting.com	8.3 KB	0.0 KB	8.3 KB	0.0 KB/s	2.8 KB	23:59:45
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
TOTAL: 23		27.8 MB	11.3 MB	39.1 MB	0.0 KB/s	4.1 KB	550:53:23

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	*.demos.su	2775	0	00:28:40
2	unresolved	6	1	00:00:14
3	*.stretchoid.com	2	0	00:00:09
4	*.monitoring.internet-measurement.com	1	0	00:00:01
5	*.survey.inspici.com	1	0	00:00:04
6	?	1	0	00:12:16
TOTAL: 6		2786	1	00:41:27

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
ddt.demos.su	9.904	36.383	0.000	00:28:40
localhost	178.507	368.215	0.073	00:12:16
198.235.24.153	0.004	0.012	0.000	00:00:10
azpdesb57.stretchoid.com	0.004	0.012	0.000	00:00:09
server-0-3.survey.inspici.com	0.000	0.016	0.000	00:00:04
162.142.125.214	0.026	0.035	0.000	00:00:03
upright.monitoring.internet-measurement.com	0.006	0.011	0.000	00:00:01
198.235.24.162	0.000	0.016	0.000	00:00:00
91.238.181.22	0.003	0.013	0.000	00:00:00
azpdcgc10.stretchoid.com	0.000	0.016	0.000	00:00:00
TOTAL: 10	188.454	404.729	0.073	00:41:27

Curious NNRP server explorers [Top 20]:

System	Conn
ddt.demos.su	2775
162.142.125.214	3
198.235.24.153	1
198.235.24.162	1
91.238.181.22	1
azpdcgc10.stretchoid.com	1
azpdesb57.stretchoid.com	1
localhost	1
server-0-3.survey.inspici.com	1
upright.monitoring.internet-measurement.com	1
TOTAL: 10	2786

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

System	Conn
162.142.125.214	1
91.238.181.22	1
azpdcgc10.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
162.142.125.214	1	2
91.238.181.22	1	1
TOTAL: 2	2	4

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	news	1	100.0%
TOTAL: 1		1	100.0%

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

Newsgroup		Count
1	news.software.nntp	1
TOTAL: 1		1

Daily Usenet Report

Sep 22 00:01:01 -- Sep 23 00:01:01