Daily Usenet report: Nov 21 15:00:04

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Nov 21 15:01:23 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Nov 22 00:49:22 colo-sc-1 innd: message repeated 3 times: [ 68.183.48.220 rejected 502]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	16470	74.4%	2.0 MB	71.9%
innd	3832	17.3%	521.8 KB	18.7%
innfeed	1254	5.7%	172.7 KB	6.2%
cnfsstat	576	2.6%	87.1 KB	3.1%
nnrpd	13	0.1%	1.2 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	22148	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	46136	45.1%
Positive hits	40247	39.3%
Do not exist	15929	15.6%
Cache misses	21	0.0%
TOTAL: 4	102333	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.057	0.0%	44	0.000	1.295	3.100
article cleanup	00:00:00.121	0.0%	16056	0.000	0.008	0.037
article logging	00:00:00.496	0.0%	16411	0.000	0.030	0.092
article parse	00:00:00.280	0.0%	26624	0.000	0.011	0.036
article write	00:00:04.291	0.0%	13752	0.000	0.312	13.204
artlog/artcncl	00:00:00.002	0.0%	44	0.000	0.045	0.200
data move	00:00:00.063	0.0%	110056	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.000	0.0%	41	0.000	0.000	0.000
hishave/artcncl	00:00:00.024	0.0%	44	0.000	0.545	1.400
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:27.660	0.3%	103105	0.002	2.596	46.899
history sync	00:00:00.168	0.0%	388	0.000	0.433	73.000
history write	00:00:04.503	0.0%	16043	0.000	0.281	13.105
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
idle	23:55:43.451	99.6%	109074	238.984	789.771	2945.245
nntp read	00:00:00.881	0.0%	109836	0.000	0.008	0.023
overview write	00:00:00.052	0.0%	13752	0.000	0.004	0.037
perl filter	00:00:17.681	0.0%	16043	0.786	1.102	5.240
python filter	00:00:00.409	0.0%	16043	0.000	0.025	0.091
site send	00:00:02.344	0.0%	41254	0.024	0.057	0.095
TOTAL: 24:00:59.165	24:00:42.483	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	13732	0.000	0.002	0.022
article prepare	00:00:00.000	0.0%	872	0.000	0.000	0.000
article read	00:00:00.023	0.0%	498	0.000	0.046	1.000
backlog stats	00:00:00.042	0.0%	73215	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.382	0.0%	45675	0.000	0.008	0.021
data write	00:00:00.956	0.0%	32228	0.005	0.030	0.113
idle	23:57:52.282	100.0%	73215	353.737	1178.342	2662.199
status file	00:00:00.437	0.0%	251	0.000	1.741	357.000
TOTAL: 23:57:56.210	23:57:54.153	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%	3	0.000	0.000	0.000
TOTAL: 00:00:00.035	00:00:00.000	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
reserve	2
TOTAL: 10	167

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	5	27647	8092	18577	978	29%	48:33:42
2	news-out.aioe.org	3	13522	5326	8177	19	39%	24:01:55
3	news.nntp4.net	1	14739	387	13029	1323	2%	23:03:05
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		10	55908	13805	39783	2320	24%	95:38:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	26.1 MB	1.6 KB	1.7 MB	27.8 MB	93%	3.1 KB
2	news-out.aioe.org	13.9 MB	23.5 KB	37.5 KB	13.9 MB	99%	2.7 KB
3	news.nntp4.net	1.3 MB	13.8 KB	2.4 MB	3.7 MB	34%	2.2 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		41.2 MB	38.9 KB	4.1 MB	45.3 MB	90%	2.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 21 15:00:04 - 15:59:59	678	4.9%	0.19	1.9 MB	4.5%	0.53
Nov 21 16:00:00 - 16:59:59	682	5.0%	0.19	2.1 MB	5.3%	0.61
Nov 21 17:00:00 - 17:59:59	713	5.2%	0.20	2.3 MB	5.6%	0.65
Nov 21 18:00:00 - 18:59:59	723	5.3%	0.20	2.0 MB	4.9%	0.58
Nov 21 19:00:00 - 19:59:59	774	5.6%	0.21	2.2 MB	5.4%	0.63
Nov 21 20:00:00 - 20:59:59	669	4.9%	0.19	2.0 MB	5.0%	0.58
Nov 21 21:00:00 - 21:59:59	690	5.0%	0.19	2.3 MB	5.6%	0.65
Nov 21 22:00:00 - 22:59:59	901	6.5%	0.25	2.6 MB	6.5%	0.75
Nov 21 23:00:00 - 23:59:59	496	3.6%	0.14	1.5 MB	3.7%	0.43
Nov 22 00:00:00 - 00:59:59	418	3.0%	0.12	1.3 MB	3.2%	0.37
Nov 22 01:00:00 - 01:59:59	401	2.9%	0.11	1.2 MB	3.0%	0.35
Nov 22 02:00:00 - 02:59:59	279	2.0%	0.08	918.3 KB	2.2%	0.26
Nov 22 03:00:00 - 03:59:59	263	1.9%	0.07	864.8 KB	2.1%	0.24
Nov 22 04:00:00 - 04:59:59	255	1.9%	0.07	1.1 MB	2.6%	0.30
Nov 22 05:00:00 - 05:59:59	347	2.5%	0.10	1.1 MB	2.7%	0.32
Nov 22 06:00:00 - 06:59:59	304	2.2%	0.08	845.3 KB	2.0%	0.23
Nov 22 07:00:00 - 07:59:59	396	2.9%	0.11	1.2 MB	3.0%	0.35
Nov 22 08:00:00 - 08:59:59	570	4.1%	0.16	1.5 MB	3.7%	0.43
Nov 22 09:00:00 - 09:59:59	671	4.9%	0.19	1.7 MB	4.3%	0.50
Nov 22 10:00:00 - 10:59:59	728	5.3%	0.20	1.9 MB	4.6%	0.54
Nov 22 11:00:00 - 11:59:59	711	5.2%	0.20	2.2 MB	5.3%	0.62
Nov 22 12:00:00 - 12:59:59	637	4.6%	0.18	1.8 MB	4.5%	0.52
Nov 22 13:00:00 - 13:59:59	655	4.8%	0.18	1.8 MB	4.4%	0.51
Nov 22 14:00:00 - 14:59:59	797	5.8%	0.22	2.4 MB	6.0%	0.69
Nov 22 15:00:00 - 15:00:02	2	0.0%	1.00	19.2 KB	0.0%	9.61
TOTAL: 23:59:58	13760	100.0%	0.16	40.9 MB	100.0%	0.49

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	103.8 MB	10.1%	4	0.20	59.43
ONE	DEFAULT	1024.0 MB	266.1 MB	26.0%	6	0.20	59.43
THREE	DEFAULT	1024.0 MB	106.2 MB	10.4%	4	0.21	58.92
TWO	DEFAULT	1024.0 MB	101.0 MB	9.9%	4	0.20	60.46
TOTAL: 4	-	4.0 GB	577.1 MB	14.1%	18	0.20	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nntp4.net	1351	4	5	1	1341
2	hasname-out.news.weretis.net	1245	4	1	0	1240
3	news-out.aioe.org	73	0	7	0	66
TOTAL: 3		2669	8	13	1	2647

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bestjobsusa.jobs.offered	4
alt.penthouse.sex.femdom	1
biz.marketplace	1
alt.bestjobsusa.philly.jobs	1
alt.fetish.feet	1
TOTAL: 5	8

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1715
EMP (md5)	388
EMP (phn nph)	107
EMP (phl)	36
User-issued spam cancel	24
Too many newsgroups	7
Scoring filter	6
HTML Multipart	4
Binary: misplaced binary	1
TOTAL: 9	2288

INND no permission servers:

System	Conn
68.183.48.220	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	303
	news-out.aioe.org	54
	news.nntp4.net	2
	TOTAL: 3	359
TOTAL: 1		359

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	381	381	0	0	0	100%	20:03:50
2	aioe	10197	42	8230	16	3	0%	24:01:32
3	nntp4	15480	2	13468	0	0	0%	24:09:50
4	weretis	5809	0	5668	63	0	0%	24:06:12
TOTAL: 4		31867	425	27366	79	3	1%	92:21:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.5 MB	0.0 KB	1.5 MB	4.0 KB	20:03:50
2	aioe	213.5 KB	52.1 KB	265.6 KB	4.6 KB	24:01:32
3	nntp4	4.2 KB	0.0 KB	4.2 KB	2.1 KB	24:09:50
4	weretis	0.0 KB	204.0 KB	204.0 KB	3.2 KB	24:06:12
TOTAL: 4		1.7 MB	256.1 KB	2.0 MB	4.0 KB	92:21:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
94.232.42.105	0.001	0.005	00:00:00
?	0.000	0.002	00:00:00
TOTAL: 2	0.001	0.007	00:00:00

NNRP no permission clients:

System	Conn
94.232.42.105	2
TOTAL: 1	2

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

Daily Usenet report

Nov 21 15:00:04 -- Nov 22 15:00:02