Daily Usenet Report: Oct 7 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 7 08:12:15 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Oct 8 00:00:01 newsfeed nnrpd[1283325]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	22374	50.9%	2.6 MB	31.1%
inn	9168	20.9%	3.9 MB	45.4%
innd	7758	17.7%	1.1 MB	13.1%
innfeed	4127	9.4%	838.8 KB	9.6%
cnfsstat	432	1.0%	64.3 KB	0.7%
nocem	55	0.1%	5.5 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	43915	100.0%	8.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	127451	58.8%
Negative hits	80380	37.1%
Do not exist	8950	4.1%
Cache misses	5	0.0%
TOTAL: 4	216786	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.227	0.0%	107	0.000	2.121	11.000
article cleanup	00:00:00.090	0.0%	9132	0.000	0.010	0.077
article logging	00:00:00.452	0.0%	9162	0.000	0.049	0.333
article parse	00:00:00.268	0.0%	15912	0.000	0.017	0.061
article write	00:00:02.306	0.0%	8083	0.000	0.285	3.032
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.844	0.0%	219661	0.000	0.008	0.180
hisgrep/artcncl	00:00:00.197	0.0%	112	0.000	1.759	9.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:36.042	0.2%	219222	0.092	0.712	4.320
history sync	00:00:00.065	0.0%	376	0.000	0.173	1.000
history write	00:02:16.816	0.2%	9057	3.292	15.106	116.480
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:13.239	99.3%	222876	70.837	385.296	1329.042
nntp read	00:00:04.631	0.0%	219695	0.003	0.021	0.410
overview write	00:02:58.702	0.2%	8083	0.481	22.108	1925.327
perl filter	00:00:29.147	0.0%	9057	1.571	3.218	19.913
python filter	00:00:01.929	0.0%	9057	0.000	0.213	4.487
site send	00:00:01.429	0.0%	16216	0.000	0.088	1.391
TOTAL: 24:00:57.131	23:59:47.384	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	8771	0.000	0.003	0.053
article prepare	00:00:00.011	0.0%	62760	0.000	0.000	0.007
article read	00:00:00.813	0.0%	5721	0.000	0.142	0.404
backlog stats	00:00:00.434	0.0%	278877	0.000	0.002	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.554	0.0%	198374	0.000	0.013	0.048
data write	00:00:03.569	0.0%	194753	0.006	0.018	0.036
idle	23:56:46.254	100.0%	278877	118.615	309.119	1144.202
status file	00:00:00.324	0.0%	278	0.800	1.165	5.000
TOTAL: 23:57:03.739	23:56:53.987	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:42:30.132	68.3%	16683	0.250	152.858	34449.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.583	0.1%	112124	0.000	0.041	1.000
TOTAL: 01:02:13.183	00:42:34.715	68.4%	-	-	-	-

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	2	13317	1929	11069	319	14%	45:27:00
2	outgoing.news.lysator.liu.se	1	6573	1509	5056	8	22%	23:59:57
3	usenet.blueworldhosting.com	719	8798	691	7822	285	7%	23:55:46
4	nntp.comgw.net	2	8031	568	7316	147	7%	23:59:51
5	news.corradoroberto.it	9	1664	546	1111	7	32%	23:06:33
6	usenet.network	2	6325	476	5844	5	7%	23:57:54
7	news.hispagatos.org	6	8018	464	7537	17	5%	24:25:59
8	usenet.goja.nl.eu.org	11	8258	446	7754	58	5%	25:40:01
9	i2pn.org	2	7829	428	7346	55	5%	23:59:24
10	endoftheline-out.news.chmurka.net	1	3754	288	3464	2	7%	23:59:56
11	newsfeed.bofh.team	2	8728	150	8575	3	1%	23:59:00
12	endofthelinebbs.peers.news.panix.com	28	8314	144	8156	14	1%	24:19:27
13	newsfeed.xs3.de	2	6727	103	6618	6	1%	23:58:55
14	news.samoylyk.net	2	7038	87	6947	4	1%	23:59:41
15	news.netfront.net	1	4060	49	3910	101	1%	23:22:00
16	news.nntp4.net	2	6931	31	6893	7	0%	23:59:44
17	glockenspiel.complete.org	2	5502	20	5482	0	0%	24:00:34
18	news.bbs.nz	2	6111	9	6102	0	0%	23:58:44
19	news.furie.org.uk	4	8426	8	8406	12	0%	47:59:32
20	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
21	localhost	28	0	0	0	0	0%	00:00:01
22	news.nk.ca	2	0	0	0	0	0%	00:20:13
TOTAL: 22		831	134404	7946	125408	1050	5%	502:40:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.9 MB	35.2 KB	400.0 KB	6.4 MB	93%	2.9 KB
2	news.corradoroberto.it	5.1 MB	30.1 KB	0.0 KB	5.1 MB	99%	9.4 KB
3	outgoing.news.lysator.liu.se	4.8 MB	6.2 KB	7.9 KB	4.8 MB	99%	3.2 KB
4	usenet.network	3.9 MB	42.5 KB	0.0 KB	4.0 MB	98%	8.5 KB
5	nntp.comgw.net	2.5 MB	6.1 KB	162.2 KB	2.6 MB	93%	3.8 KB
6	usenet.blueworldhosting.com	2.1 MB	1.6 KB	761.4 KB	2.9 MB	74%	3.0 KB
7	news.hispagatos.org	1.8 MB	1.3 KB	25.2 KB	1.8 MB	98%	3.8 KB
8	i2pn.org	1.7 MB	0.0 KB	65.0 KB	1.8 MB	96%	3.8 KB
9	usenet.goja.nl.eu.org	1.3 MB	0.0 KB	81.8 KB	1.4 MB	94%	2.8 KB
10	news.samoylyk.net	1.2 MB	1.3 KB	2.7 KB	1.2 MB	99%	13.5 KB
11	endoftheline-out.news.chmurka.net	1.1 MB	3.7 KB	0.8 KB	1.1 MB	99%	3.8 KB
12	endofthelinebbs.peers.news.panix.com	890.6 KB	54.2 KB	16.0 KB	960.9 KB	92%	6.1 KB
13	newsfeed.xs3.de	699.4 KB	10.0 KB	3.8 KB	713.2 KB	98%	6.5 KB
14	news.netfront.net	494.8 KB	28.3 KB	355.0 KB	878.1 KB	56%	5.9 KB
15	newsfeed.bofh.team	465.4 KB	1.3 KB	1.9 KB	468.6 KB	99%	3.1 KB
16	glockenspiel.complete.org	419.6 KB	0.0 KB	0.0 KB	419.6 KB	100%	21.0 KB
17	news.nntp4.net	63.6 KB	28.6 KB	0.8 KB	92.9 KB	68%	2.4 KB
18	news.furie.org.uk	39.4 KB	74.7 KB	0.7 KB	114.8 KB	34%	5.7 KB
19	news.bbs.nz	21.1 KB	0.0 KB	0.0 KB	21.1 KB	100%	2.3 KB
20	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
22	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 22		34.4 MB	325.0 KB	1.8 MB	36.5 MB	94%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 7 00:01:01 - 00:59:59	148	1.8%	0.04	580.6 KB	1.6%	0.16
Oct 7 01:00:00 - 01:59:59	215	2.7%	0.06	777.7 KB	2.2%	0.22
Oct 7 02:00:00 - 02:59:59	248	3.1%	0.07	1.2 MB	3.4%	0.33
Oct 7 03:00:00 - 03:59:59	514	6.4%	0.14	6.7 MB	19.3%	1.91
Oct 7 04:00:00 - 04:59:59	368	4.6%	0.10	1.1 MB	3.1%	0.30
Oct 7 05:00:00 - 05:59:59	395	4.9%	0.11	1.4 MB	4.0%	0.39
Oct 7 06:00:00 - 06:59:59	502	6.2%	0.14	1.7 MB	4.8%	0.47
Oct 7 07:00:00 - 07:59:59	439	5.4%	0.12	1.9 MB	5.6%	0.55
Oct 7 08:00:00 - 08:59:59	549	6.8%	0.15	1.8 MB	5.2%	0.51
Oct 7 09:00:00 - 09:59:59	529	6.5%	0.15	1.9 MB	5.6%	0.55
Oct 7 10:00:00 - 10:59:59	411	5.1%	0.11	1.7 MB	4.8%	0.48
Oct 7 11:00:00 - 11:59:59	490	6.1%	0.14	1.8 MB	5.1%	0.50
Oct 7 12:00:00 - 12:59:59	476	5.9%	0.13	1.4 MB	4.0%	0.39
Oct 7 13:00:00 - 13:59:59	372	4.6%	0.10	1.5 MB	4.4%	0.43
Oct 7 14:00:00 - 14:59:59	439	5.4%	0.12	1.4 MB	4.0%	0.40
Oct 7 15:00:00 - 15:59:59	417	5.2%	0.12	1.4 MB	4.0%	0.40
Oct 7 16:00:00 - 16:59:59	283	3.5%	0.08	887.1 KB	2.5%	0.25
Oct 7 17:00:00 - 17:59:59	238	2.9%	0.07	1.3 MB	3.7%	0.37
Oct 7 18:00:00 - 18:59:59	205	2.5%	0.06	1.3 MB	3.7%	0.36
Oct 7 19:00:00 - 19:59:59	213	2.6%	0.06	800.9 KB	2.3%	0.22
Oct 7 20:00:00 - 20:59:59	167	2.1%	0.05	677.8 KB	1.9%	0.19
Oct 7 21:00:00 - 21:59:59	123	1.5%	0.03	476.3 KB	1.3%	0.13
Oct 7 22:00:00 - 22:59:59	116	1.4%	0.03	533.2 KB	1.5%	0.15
Oct 7 23:00:00 - 23:59:59	209	2.6%	0.06	721.8 KB	2.0%	0.20
Oct 8 00:00:00 - 00:01:01	17	0.2%	0.28	47.6 KB	0.1%	0.78
TOTAL: 24:00:00	8083	100.0%	0.09	34.7 MB	100.0%	0.41

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	98.3 GB	6.4%	0	0.60	31171.38
CYCTXT1	TEXT	5.0 GB	3.9 GB	77.7%	0	0.04	1465.61
TOTAL: 3	-	3077.0 GB	102.2 GB	3.3%	1	0.43	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	endofthelinebbs-out.news.weretis.net	328	0	8	320
2	usenet.blueworldhosting.com	295	0	1	294
3	nntp.comgw.net	147	0	3	144
4	news.netfront.net	91	0	8	83
5	usenet.goja.nl.eu.org	59	0	0	59
6	i2pn.org	53	0	0	53
7	news.hispagatos.org	21	13	1	7
8	endofthelinebbs.peers.news.panix.com	16	4	8	4
9	news.furie.org.uk	12	0	11	1
10	outgoing.news.lysator.liu.se	12	0	2	10
11	news.corradoroberto.it	7	0	7	0
12	news.nntp4.net	7	0	6	1
13	glockenspiel.complete.org	6	0	6	0
14	newsfeed.xs3.de	6	0	3	3
15	usenet.network	5	0	5	0
16	news.bbs.nz	4	0	3	1
17	news.samoylyk.net	3	0	1	2
18	newsfeed.bofh.team	3	0	1	2
19	endoftheline-out.news.chmurka.net	2	0	1	1
TOTAL: 19		1077	17	75	985

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	6
hispagatos.hacking.news	6
alt.privacy.anon-server.narkive.com	4
hispagatos.hacking.exploits	1
TOTAL: 4	17

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	387
EMP (phr path)	288
EMP (md5)	135
Too many newsgroups	112
User-issued cancel	14
Too many newsgroups (meow)	10
Too many newsgroups (low_xpost)	8
HTML Multipart	1
TOTAL: 8	955

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	1	1	0	1
nocem@eternal-september.org (bot)	1	1	0	1
robot@pasdenom.info (spam)	1	1	0	1
i2pn2-nocem@i2pn2.org (multipost)	38	0	154	154
robot@pasdenom.info (spam3)	6	0	9	9
i2pn2-nocem@i2pn2.org (rmp)	4	0	8	8
TOTAL: 6	51	3	171	174

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	12
	usenet.blueworldhosting.com	10
	outgoing.news.lysator.liu.se	4
	endofthelinebbs.peers.news.panix.com	2
	news.hispagatos.org	1
	usenet.goja.nl.eu.org	1
	TOTAL: 6	30
TOTAL: 1		30

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10734	4663	3369	1	35	43%	23:59:54
2	nyheter.lysator.liu.se	6820	1313	4673	587	10	19%	23:59:54
3	news.furie.org.uk	4643	1241	2728	16	19	26%	23:59:54
4	photonic.trudheim.com	8487	616	6550	903	10	7%	23:59:50
5	endofthelinebbs.peers.news.panix.com	8458	534	7534	0	0	6%	23:59:50
6	usenet.goja.nl.eu.org	8391	346	6943	217	11	4%	23:59:54
7	news.hispagatos.org	8680	340	6916	289	5	3%	23:59:54
8	usenet.network	7960	288	6845	26	13	3%	23:59:05
9	newsfeed.bofh.team	8753	230	7492	1	12	2%	23:59:26
10	news.tnetconsulting.net	9215	188	7608	218	23	2%	23:59:54
11	i2pn.org	9079	171	7374	85	175	1%	23:59:05
12	news.bbs.nz	14748	168	7300	554	232	1%	23:59:05
13	news.weretis.net	8425	160	7713	155	3	1%	23:59:54
14	nntp.comgw.net	8237	140	7276	92	8	1%	23:59:54
15	news.nntp4.net	8822	137	7782	96	9	1%	23:59:54
16	news.quux.org	8616	127	7339	595	2	1%	23:59:54
17	news.nk.ca	11864	122	7234	636	105	1%	23:59:54
18	newsfeed.xs3.de	9111	55	7611	253	4	0%	23:59:54
19	news.chmurka.net	4026	49	3355	1	0	1%	23:59:54
20	news.corradoroberto.it	933	38	781	16	0	4%	22:25:31
21	news.samoylyk.net	8942	36	7855	68	44	0%	23:59:53
22	usenet.blueworldhosting.com	7515	4	7174	0	0	0%	23:58:59
23	feed.opticnetworks.net	0	0	0	0	8082	0%	23:59:54
TOTAL: 23		182459	10966	139452	4809	8802	6%	550:19:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	19.3 MB	1.7 KB	19.3 MB	0.2 KB/s	4.2 KB	23:59:54
2	news.furie.org.uk	8.3 MB	84.1 KB	8.4 MB	0.1 KB/s	6.8 KB	23:59:54
3	nyheter.lysator.liu.se	4.7 MB	4.5 MB	9.1 MB	0.1 KB/s	4.9 KB	23:59:54
4	photonic.trudheim.com	3.6 MB	2.6 MB	6.2 MB	0.1 KB/s	4.2 KB	23:59:50
5	endofthelinebbs.peers.news.panix.com	2.8 MB	0.0 KB	2.8 MB	0.0 KB/s	5.4 KB	23:59:50
6	newsfeed.bofh.team	1.4 MB	1.7 KB	1.4 MB	0.0 KB/s	6.2 KB	23:59:26
7	news.hispagatos.org	1.4 MB	1.9 MB	3.3 MB	0.0 KB/s	5.4 KB	23:59:54
8	usenet.network	1.3 MB	50.4 KB	1.4 MB	0.0 KB/s	4.5 KB	23:59:05
9	news.quux.org	1.1 MB	3.2 MB	4.4 MB	0.1 KB/s	6.2 KB	23:59:54
10	news.tnetconsulting.net	1.0 MB	1.8 MB	2.8 MB	0.0 KB/s	7.1 KB	23:59:54
11	i2pn.org	907.8 KB	788.3 KB	1.7 MB	0.0 KB/s	6.6 KB	23:59:05
12	usenet.goja.nl.eu.org	899.7 KB	1.8 MB	2.7 MB	0.0 KB/s	5.0 KB	23:59:54
13	nntp.comgw.net	892.6 KB	936.6 KB	1.8 MB	0.0 KB/s	7.9 KB	23:59:54
14	news.weretis.net	882.9 KB	2.1 MB	2.9 MB	0.0 KB/s	9.5 KB	23:59:54
15	newsfeed.xs3.de	768.9 KB	1.2 MB	2.0 MB	0.0 KB/s	6.7 KB	23:59:54
16	news.nk.ca	713.1 KB	3.7 MB	4.4 MB	0.1 KB/s	5.9 KB	23:59:54
17	news.bbs.nz	694.9 KB	3.5 MB	4.2 MB	0.0 KB/s	5.9 KB	23:59:05
18	news.samoylyk.net	633.4 KB	190.1 KB	823.6 KB	0.0 KB/s	7.9 KB	23:59:53
19	news.nntp4.net	518.0 KB	620.4 KB	1.1 MB	0.0 KB/s	4.9 KB	23:59:54
20	news.chmurka.net	206.4 KB	1.7 KB	208.1 KB	0.0 KB/s	4.2 KB	23:59:54
21	news.corradoroberto.it	134.8 KB	48.6 KB	183.4 KB	0.0 KB/s	3.4 KB	22:25:31
22	usenet.blueworldhosting.com	8.7 KB	0.0 KB	8.7 KB	0.0 KB/s	2.2 KB	23:58:59
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 23		52.1 MB	29.0 MB	81.1 MB	0.0 KB/s	5.3 KB	550:19:20

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	?	2788	0	00:33:07
2	*.demos.su	2777	0	00:28:38
3	unresolved	4	1	00:00:17
4	*.stretchoid.com	2	0	00:00:00
5	*.monitoring.internet-measurement.com	1	0	00:00:01
6	*.survey.inspici.com	1	0	00:00:03
TOTAL: 6		5573	1	01:02:10

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.924	36.770	0.000	00:28:38
2001:470:28:a8:ca7f:54ff:fe03:41e0	9.742	37.386	0.000	00:21:55
localhost	179.995	369.431	0.074	00:11:12
198.235.24.183	0.008	0.008	0.000	00:00:10
205.210.31.102	0.005	0.010	0.000	00:00:07
server-0-3.survey.inspici.com	0.003	0.017	0.000	00:00:03
truthful.monitoring.internet-measurement.com	0.011	0.005	0.000	00:00:01
198.235.24.118	0.008	0.008	0.000	00:00:00
147.45.112.147	0.008	0.008	0.000	00:00:00
azpdwgc69.stretchoid.com	0.004	0.015	0.000	00:00:00
azpdsg49.stretchoid.com	0.005	0.011	0.000	00:00:00
TOTAL: 11	199.713	443.669	0.074	01:02:10

Curious NNRP server explorers [Top 20]:

System	Conn
2001:470:28:a8:ca7f:54ff:fe03:41e0	2787
ddt.demos.su	2777
147.45.112.147	1
198.235.24.118	1
198.235.24.183	1
205.210.31.102	1
azpdsg49.stretchoid.com	1
azpdwgc69.stretchoid.com	1
localhost	1
server-0-3.survey.inspici.com	1
truthful.monitoring.internet-measurement.com	1
TOTAL: 11	5573

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

System	Conn
147.45.112.147	1
azpdsg49.stretchoid.com	1
azpdwgc69.stretchoid.com	1
TOTAL: 3	3

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

Command	Count
MGLNDD_144.172.126.95_119	2
#003	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.147	1	1
TOTAL: 1	1	3

Newsgroup request counts (by hierarchy):

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

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

Newsgroup		Count
1	news.admin.net-abuse.usenet	2
TOTAL: 1		2

Daily Usenet Report

Oct 7 00:01:01 -- Oct 8 00:01:01