Daily Usenet report: Oct 20 06:01:01

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Oct 20 06:01:59 kennel innd: message repeated 2 times: [ ctlinnd command s]
Oct 21 02:19:53 kennel innd: message repeated 2 times: [ 165.154.173.74 rejected 502]
Oct 21 02:20:24 kennel innd: message repeated 5 times: [ 165.154.173.74 rejected 502]
Oct 21 02:20:49 kennel innd: message repeated 8 times: [ 165.154.173.74 rejected 502]
Oct 21 02:21:00 kennel innd: message repeated 2 times: [ 165.154.173.74 rejected 502]
Oct 21 02:21:20 kennel innd: message repeated 4 times: [ 165.154.173.74 rejected 502]
Oct 21 02:21:37 kennel innd: message repeated 5 times: [ 165.154.173.74 rejected 502]
Oct 21 02:21:56 kennel innd: message repeated 6 times: [ 165.154.173.74 rejected 502]
Oct 21 02:22:19 kennel innd: message repeated 2 times: [ 165.154.173.74 rejected 502]
Oct 21 02:22:30 kennel innd: message repeated 3 times: [ 165.154.173.74 rejected 502]
Oct 21 02:23:01 kennel innd: message repeated 10 times: [ 165.154.173.74 rejected 502]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	447765	97.9%	58.7 MB	97.6%
innd	7961	1.7%	1.2 MB	2.0%
innfeed	1424	0.3%	206.5 KB	0.3%
cnfsstat	144	0.0%	20.1 KB	0.0%
nnrpd	66	0.0%	7.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	457361	100.0%	60.1 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	700660	44.2%
Positive hits	439164	27.7%
Do not exist	438997	27.7%
Cache misses	6178	0.4%
TOTAL: 4	1584999	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.004	0.0%	29	0.000	0.138	1.000
article cleanup	00:00:04.924	0.0%	443175	0.005	0.011	0.017
article logging	00:00:17.513	0.0%	443445	0.020	0.039	0.054
article parse	00:00:27.576	0.0%	1288311	0.008	0.021	0.030
article write	00:00:26.376	0.0%	296483	0.063	0.089	0.115
artlog/artparse	00:00:00.212	0.0%	4434	0.000	0.048	0.500
data move	00:00:02.222	0.0%	1331958	0.001	0.002	0.005
hisgrep/artcncl	00:00:00.001	0.0%	29	0.000	0.034	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:15.908	0.0%	1588657	0.002	0.010	0.216
history sync	00:00:00.025	0.0%	334	0.000	0.075	0.500
history write	00:00:25.424	0.0%	439191	0.035	0.058	0.080
idle	23:54:01.073	99.5%	1061803	29.074	81.033	154.707
nntp read	00:00:41.104	0.0%	1062797	0.018	0.039	0.053
overview write	00:00:01.550	0.0%	296483	0.001	0.005	0.010
perl filter	00:00:22.181	0.0%	434851	0.032	0.051	0.069
python filter	00:00:21.675	0.0%	434851	0.032	0.050	0.068
site send	00:00:12.818	0.0%	830657	0.009	0.015	0.140
TOTAL: 24:00:35.017	23:57:40.586	99.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.760	0.0%	238868	0.000	0.003	0.025
article prepare	00:00:00.000	0.0%	1004	0.000	0.000	0.000
article read	00:00:00.059	0.0%	639	0.000	0.092	1.000
backlog stats	00:00:00.639	0.0%	654589	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:10.565	0.0%	411235	0.012	0.026	0.061
data write	00:00:06.896	0.0%	258441	0.016	0.027	0.058
idle	23:51:41.663	100.0%	654589	35.876	131.230	1378.759
status file	00:00:00.174	0.0%	261	0.000	0.667	1.000
TOTAL: 23:52:15.931	23:52:00.756	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.003	0.0%	14	0.000	0.214	1.000
TOTAL: 00:00:37.747	00:00:00.003	0.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	147
name	2
pause	2
paused	2
reload	48
reserve	2
TOTAL: 11	215

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.feeder.erje.net	576	843542	286420	406488	150634	33%	47:50:59
2	hasname-out.news.weretis.net	5	20132	6502	13528	102	32%	49:22:15
3	usenet.blueworldhosting.com	583	12304	2103	9911	290	17%	23:41:28
4	news.nntp4.net	1	9396	1229	7921	246	13%	23:59:53
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		1166	885374	296254	437848	151272	33%	144:54:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.feeder.erje.net	2.5 GB	87.8 MB	1.7 GB	4.3 GB	58%	10.3 KB
2	hasname-out.news.weretis.net	14.3 MB	21.7 KB	153.1 KB	14.5 MB	98%	2.3 KB
3	news.nntp4.net	8.2 MB	8.9 KB	1.8 MB	10.0 MB	81%	6.9 KB
4	usenet.blueworldhosting.com	3.7 MB	0.0 KB	1.6 MB	5.3 MB	69%	2.3 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		2.5 GB	87.9 MB	1.7 GB	4.3 GB	59%	10.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 20 06:01:01 - 06:59:59	12743	4.3%	3.60	118.9 MB	4.6%	34.40
Oct 20 07:00:00 - 07:59:59	14490	4.9%	4.03	142.1 MB	5.5%	40.41
Oct 20 08:00:00 - 08:59:59	19781	6.7%	5.49	185.3 MB	7.1%	52.71
Oct 20 09:00:00 - 09:59:59	11491	3.9%	3.19	99.7 MB	3.8%	28.35
Oct 20 10:00:00 - 10:59:59	12983	4.4%	3.61	131.7 MB	5.1%	37.47
Oct 20 11:00:00 - 11:59:59	12506	4.2%	3.47	109.1 MB	4.2%	31.02
Oct 20 12:00:00 - 12:59:59	10143	3.4%	2.82	104.4 MB	4.0%	29.70
Oct 20 13:00:00 - 13:59:59	19217	6.5%	5.34	135.2 MB	5.2%	38.45
Oct 20 14:00:00 - 14:59:59	16653	5.6%	4.63	152.1 MB	5.9%	43.27
Oct 20 15:00:00 - 15:59:59	15035	5.1%	4.18	140.5 MB	5.4%	39.96
Oct 20 16:00:00 - 16:59:59	13472	4.5%	3.74	113.5 MB	4.4%	32.28
Oct 20 17:00:00 - 17:59:59	10254	3.5%	2.85	87.9 MB	3.4%	24.99
Oct 20 18:00:00 - 18:59:59	14448	4.9%	4.01	129.8 MB	5.0%	36.92
Oct 20 19:00:00 - 19:59:59	14143	4.8%	3.93	149.8 MB	5.8%	42.60
Oct 20 20:00:00 - 20:59:59	9354	3.2%	2.60	82.5 MB	3.2%	23.48
Oct 20 21:00:00 - 21:59:59	10361	3.5%	2.88	93.3 MB	3.6%	26.53
Oct 20 22:00:00 - 22:59:59	9933	3.4%	2.76	99.5 MB	3.8%	28.30
Oct 20 23:00:00 - 23:59:59	8576	2.9%	2.38	87.2 MB	3.4%	24.79
Oct 21 00:00:00 - 00:59:59	12731	4.3%	3.54	93.9 MB	3.6%	26.70
Oct 21 01:00:00 - 01:59:59	11250	3.8%	3.12	82.6 MB	3.2%	23.50
Oct 21 02:00:00 - 02:59:59	8701	2.9%	2.42	58.4 MB	2.2%	16.63
Oct 21 03:00:00 - 03:59:59	8266	2.8%	2.30	58.9 MB	2.3%	16.76
Oct 21 04:00:00 - 04:59:59	9094	3.1%	2.53	59.8 MB	2.3%	17.00
Oct 21 05:00:00 - 05:59:59	10394	3.5%	2.89	82.8 MB	3.2%	23.55
Oct 21 06:00:00 - 06:01:01	137	0.0%	2.25	1.0 MB	0.0%	17.29
TOTAL: 24:00:00	296156	100.0%	3.43	2.5 GB	100.0%	30.81

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
C1	DEFAULT	10.0 GB	8.8 GB	88.0%	90	37.90	3.20
TOTAL: 1	-	10.0 GB	8.8 GB	88.0%	90	37.90	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	hasname-out.feeder.erje.net	150826	137961	8317	5	4543
2	news.nntp4.net	353	232	2	0	119
3	usenet.blueworldhosting.com	305	282	0	0	23
4	hasname-out.news.weretis.net	125	86	17	0	22
TOTAL: 4		151609	138561	8336	5	4707

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.friends	57361
alt.binaries.pwp	5368
alt.binaries.encrypted	5033
alt.binaries.encryptnzb.kilo	2108
alt.binaries.encryptnzb.lima	2077
alt.binaries.encryptnzb.india	2076
alt.binaries.encryptnzb.hotel	2074
alt.binaries.encryptnzb.echo	2065
alt.binaries.encryptnzb.mike	2056
alt.binaries.encryptnzb.charlie	2037
alt.binaries.encryptnzb.alpha	2034
alt.binaries.encryptnzb.golf	2034
alt.binaries.encryptnzb.delta	1998
alt.binaries.encryptnzb.novemeber	1992
alt.binaries.encryptnzb.foxtrot	1986
alt.binaries.encryptnzb.juliet	1976
alt.binaries.encryptnzb.bravo	1968
alt.binaries.encryptnzb.beta	1935
alt.binaries.wtfnzb.novemeber	1426
alt.binaries.wtfnzb.bravo	1403
TOTAL: 222	138561

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

Groups	Count
alt.binaries.sounds.mp3.jazz.vocals	5
TOTAL: 1	5

INND no permission servers:

System	Conn
103.193.138.76	30
165.154.173.74	24
162.216.149.95	2
162.216.150.210	2
162.142.125.196	1
TOTAL: 5	59

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	hasname-out.feeder.erje.net	2
	TOTAL: 1	2
Including strange strings
	news.nntp4.net	119
	hasname-out.feeder.erje.net	109
	hasname-out.news.weretis.net	22
	usenet.blueworldhosting.com	20
	TOTAL: 4	270
No colon-space in header field
	hasname-out.feeder.erje.net	1538
	usenet.blueworldhosting.com	3
	TOTAL: 2	1541
TOTAL: 3		1813

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	521	521	0	0	0	100%	21:14:43
2	blueworldhosting	235909	5	234613	100	1781	0%	23:59:55
3	erje	9094	2	9083	0	32	0%	23:59:55
4	nntp4	10435	1	10430	0	37	0%	23:58:57
5	weretis	4161	1	4134	12	0	0%	23:57:55
TOTAL: 5		260120	530	258260	112	1850	0%	117:11:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.6 MB	0.0 KB	1.6 MB	3.1 KB	21:14:43
2	blueworldhosting	53.0 KB	1.0 MB	1.1 MB	10.5 KB	23:59:55
3	erje	6.9 KB	0.0 KB	6.9 KB	3.5 KB	23:59:55
4	nntp4	1.3 KB	0.0 KB	1.3 KB	1.3 KB	23:58:57
5	weretis	1.3 KB	143.1 KB	144.3 KB	11.1 KB	23:57:55
TOTAL: 5		1.6 MB	1.2 MB	2.8 MB	4.5 KB	117:11:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
165.154.10.250	0.005	0.008	00:00:35
45.82.78.105	0.006	0.010	00:00:01
45.227.254.152	0.001	0.003	00:00:00
divine.monitoring.internet-measurement.com	0.001	0.003	00:00:00
45-79-207-71.ip.linodeusercontent.com	0.002	0.002	00:00:00
azpdcgewr0bm.stretchoid.com	0.001	0.007	00:00:00
147.185.132.231	0.002	0.001	00:00:00
205.210.31.40	0.002	0.002	00:00:00
TOTAL: 8	0.020	0.036	00:00:37

NNRP no permission clients [Top 20]:

System	Conn
45.82.78.105	4
165.154.10.250	3
azpdcgewr0bm.stretchoid.com	2
147.185.132.231	1
205.210.31.40	1
45-79-207-71.ip.linodeusercontent.com	1
45.227.254.152	1
divine.monitoring.internet-measurement.com	1
TOTAL: 8	14

Daily Usenet report

Oct 20 06:01:01 -- Oct 21 06:01:01