Daily Usenet report: Sep 15 06:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 15 06:01:51 kennel innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	489973	98.0%	60.3 MB	97.6%
innd	8336	1.7%	1.2 MB	2.0%
innfeed	1392	0.3%	194.6 KB	0.3%
cnfsstat	144	0.0%	25.8 KB	0.0%
nnrpd	30	0.0%	3.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	499877	100.0%	61.8 MB	100.0%

History cache:

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.006	0.0%	39	0.000	0.154	1.000
article cleanup	00:00:04.524	0.0%	413103	0.007	0.011	0.020
article logging	00:00:16.616	0.0%	413446	0.027	0.040	0.058
article parse	00:00:45.184	0.1%	1634231	0.011	0.028	0.040
article write	00:00:26.110	0.0%	284304	0.051	0.092	0.138
artlog/artparse	00:00:03.824	0.0%	75323	0.020	0.051	0.094
data move	00:00:04.890	0.0%	1817104	0.000	0.003	0.007
hisgrep/artcncl	00:00:00.001	0.0%	39	0.000	0.026	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:25.072	0.0%	1726215	0.002	0.015	0.802
history sync	00:00:00.028	0.0%	305	0.000	0.092	1.000
history write	00:00:31.454	0.0%	478551	0.045	0.066	0.090
idle	23:50:44.268	99.3%	1399868	27.427	61.323	104.452
nntp read	00:00:56.144	0.1%	1401718	0.018	0.040	0.058
overview write	00:00:01.529	0.0%	284304	0.001	0.005	0.012
perl filter	00:00:21.303	0.0%	404424	0.037	0.053	0.079
python filter	00:00:21.469	0.0%	404424	0.037	0.053	0.081
site send	00:00:11.054	0.0%	645365	0.011	0.017	0.102
TOTAL: 24:00:26.731	23:55:13.476	99.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.273	0.0%	76710	0.000	0.004	0.020
article prepare	00:00:00.004	0.0%	4702	0.000	0.001	0.031
article read	00:00:00.174	0.0%	1474	0.000	0.118	1.000
backlog stats	00:00:00.231	0.0%	238648	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.150	0.0%	153808	0.010	0.027	0.050
data write	00:00:03.663	0.0%	95017	0.018	0.039	0.096
idle	23:56:19.560	100.0%	238648	86.817	361.116	1955.935
status file	00:00:00.146	0.0%	252	0.000	0.579	1.000
TOTAL: 23:56:39.417	23:56:28.201	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:00.000	0.0%	0	0.000	0.000	0.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.000	0.0%	6	0.000	0.000	0.000
TOTAL: 00:00:00.758	00:00:00.000	0.0%	-	-	-	-

Control commands to INND:

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.feeder.erje.net	576	927777	277041	446654	204082	29%	48:25:06
2	hasname-out.news.weretis.net	4	13128	4629	8394	105	35%	49:29:39
3	news.nntp4.net	3	9494	1890	7337	267	19%	27:10:29
4	usenet.blueworldhosting.com	718	8198	1539	6348	311	18%	23:42:10
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		1302	958597	285099	468733	204765	29%	148:47:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.feeder.erje.net	2.8 GB	96.4 MB	3.0 GB	5.9 GB	48%	12.8 KB
2	news.nntp4.net	31.3 MB	34.2 KB	2.4 MB	33.8 MB	92%	16.0 KB
3	hasname-out.news.weretis.net	14.3 MB	41.2 KB	203.4 KB	14.5 MB	98%	3.1 KB
4	usenet.blueworldhosting.com	3.8 MB	1.9 KB	2.5 MB	6.3 MB	59%	3.5 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		2.9 GB	96.4 MB	3.0 GB	5.9 GB	48%	12.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 15 06:01:02 - 06:59:59	7364	2.6%	2.08	83.3 MB	2.8%	24.10
Sep 15 07:00:00 - 07:59:59	18103	6.4%	5.03	115.2 MB	3.9%	32.78
Sep 15 08:00:00 - 08:59:59	9040	3.2%	2.51	107.8 MB	3.7%	30.66
Sep 15 09:00:00 - 09:59:59	9289	3.3%	2.58	105.1 MB	3.6%	29.89
Sep 15 10:00:00 - 10:59:59	11547	4.1%	3.21	120.4 MB	4.1%	34.24
Sep 15 11:00:00 - 11:59:59	8662	3.0%	2.41	90.9 MB	3.1%	25.86
Sep 15 12:00:00 - 12:59:59	8509	3.0%	2.36	107.4 MB	3.7%	30.55
Sep 15 13:00:00 - 13:59:59	12162	4.3%	3.38	127.4 MB	4.3%	36.24
Sep 15 14:00:00 - 14:59:59	12595	4.4%	3.50	116.1 MB	4.0%	33.01
Sep 15 15:00:00 - 15:59:59	15074	5.3%	4.19	143.2 MB	4.9%	40.74
Sep 15 16:00:00 - 16:59:59	13671	4.8%	3.80	124.1 MB	4.2%	35.31
Sep 15 17:00:00 - 17:59:59	15777	5.5%	4.38	132.1 MB	4.5%	37.57
Sep 15 18:00:00 - 18:59:59	13479	4.7%	3.74	119.5 MB	4.1%	34.00
Sep 15 19:00:00 - 19:59:59	10346	3.6%	2.87	95.5 MB	3.3%	27.17
Sep 15 20:00:00 - 20:59:59	11788	4.1%	3.27	117.7 MB	4.0%	33.48
Sep 15 21:00:00 - 21:59:59	8607	3.0%	2.39	100.4 MB	3.4%	28.55
Sep 15 22:00:00 - 22:59:59	10036	3.5%	2.79	110.7 MB	3.8%	31.49
Sep 15 23:00:00 - 23:59:59	13589	4.8%	3.77	174.0 MB	5.9%	49.49
Sep 16 00:00:00 - 00:59:59	16444	5.8%	4.57	184.8 MB	6.3%	52.56
Sep 16 01:00:00 - 01:59:59	12381	4.3%	3.44	165.8 MB	5.6%	47.17
Sep 16 02:00:00 - 02:59:59	12062	4.2%	3.35	156.7 MB	5.3%	44.57
Sep 16 03:00:00 - 03:59:59	10697	3.8%	2.97	123.6 MB	4.2%	35.17
Sep 16 04:00:00 - 04:59:59	10477	3.7%	2.91	102.5 MB	3.5%	29.17
Sep 16 05:00:00 - 05:59:59	12468	4.4%	3.46	109.7 MB	3.7%	31.20
Sep 16 06:00:00 - 06:01:01	756	0.3%	12.39	2.3 MB	0.1%	38.23
TOTAL: 23:59:59	284923	100.0%	3.30	2.9 GB	100.0%	34.80

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
C1	DEFAULT	10.0 GB	1.4 GB	13.8%	81	41.83	2.90
TOTAL: 1	-	10.0 GB	1.4 GB	13.8%	81	41.83	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	hasname-out.feeder.erje.net	204264	119709	8630	7	75918
2	news.nntp4.net	353	257	7	0	89
3	usenet.blueworldhosting.com	333	304	1	0	28
4	hasname-out.news.weretis.net	100	84	9	0	7
TOTAL: 4		205050	120354	8647	7	76042

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.friends	37305
alt.binaries.pwp	3799
alt.binaries.encryptnzb.bravo	2654
alt.binaries.encryptnzb.alpha	2635
alt.binaries.encryptnzb.hotel	2613
alt.binaries.encryptnzb.foxtrot	2605
alt.binaries.encryptnzb.mike	2602
alt.binaries.encryptnzb.delta	2598
alt.binaries.encryptnzb.kilo	2596
alt.binaries.encryptnzb.india	2595
alt.binaries.encryptnzb.lima	2586
alt.binaries.encryptnzb.charlie	2578
alt.binaries.encryptnzb.novemeber	2577
alt.binaries.encryptnzb.golf	2570
alt.binaries.encryptnzb.beta	2550
alt.binaries.encryptnzb.echo	2547
alt.binaries.encryptnzb.juliet	2483
alt.binaries.encrypted	2247
alt.binaries.ath	1815
alt.binaries.wtfnzb.delta	1235
TOTAL: 199	120354

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
alt.binaries.whitburn	7
TOTAL: 1	7

INND no permission servers:

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	hasname-out.feeder.erje.net	30
	TOTAL: 1	30
Including strange strings
	hasname-out.feeder.erje.net	225
	news.nntp4.net	89
	usenet.blueworldhosting.com	22
	hasname-out.news.weretis.net	7
	TOTAL: 4	343
No colon-space in header field
	hasname-out.feeder.erje.net	2096
	TOTAL: 1	2096
TOTAL: 3		2469

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	398	398	0	0	0	100%	20:57:41
2	blueworldhosting	75791	8	75389	0	1	0%	23:59:52
3	erje	7334	0	7317	1	1	0%	23:59:51
4	nntp4	6705	0	6701	0	5	0%	23:59:52
5	weretis	3932	0	2860	1067	3	0%	23:59:51
TOTAL: 5		94160	406	92267	1068	10	0%	116:57:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	bbsgslincom	1.2 MB	0.0 KB	1.2 MB	0.0 KB/s	3.1 KB	20:57:41
2	blueworldhosting	95.6 KB	0.0 KB	95.6 KB	0.0 KB/s	11.9 KB	23:59:52
3	erje	0.0 KB	14.3 KB	14.3 KB	0.0 KB/s	14.3 KB	23:59:51
4	nntp4	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
5	weretis	0.0 KB	29.5 MB	29.5 MB	0.3 KB/s	28.3 KB	23:59:51
TOTAL: 5		1.3 MB	29.5 MB	30.8 MB	0.1 KB/s	21.4 KB	116:57:07

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
194.165.16.161	0.002	0.002	00:00:00
167.94.146.48	0.003	0.008	00:00:00
205.210.31.177	0.000	0.004	00:00:00
205.210.31.21	0.002	0.002	00:00:00
TOTAL: 4	0.007	0.016	00:00:00

NNRP no permission clients [Top 20]:

Daily Usenet report