Daily Usenet report: Sep 21 15:00:04

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 21 15:01:26 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Sep 22 06:30:01 colo-sc-1 pgpverify[32222]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	16269	79.4%	2.0 MB	77.1%
innd	2490	12.1%	356.4 KB	13.6%
innfeed	1149	5.6%	163.1 KB	6.2%
cnfsstat	576	2.8%	76.8 KB	2.9%
controlchan	13	0.1%	2.5 KB	0.1%
overchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	20499	100.0%	2.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	44939	43.7%
Positive hits	42016	40.8%
Do not exist	15864	15.4%
Cache misses	69	0.1%
TOTAL: 4	102888	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.735	0.0%	78	0.000	9.423	70.667
article cleanup	00:00:00.122	0.0%	16014	0.000	0.008	0.058
article logging	00:00:00.509	0.0%	16322	0.000	0.031	0.100
article parse	00:00:00.312	0.0%	31554	0.000	0.010	0.045
article write	00:00:00.973	0.0%	15103	0.000	0.064	0.932
artlog/artcncl	00:00:00.001	0.0%	67	0.000	0.015	0.100
data move	00:00:00.076	0.0%	115339	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.000	0.0%	73	0.000	0.000	0.000
hishave/artcncl	00:00:00.478	0.0%	78	0.000	6.128	70.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:56.436	0.2%	103808	0.001	1.700	40.620
history sync	00:00:00.031	0.0%	399	0.000	0.078	6.000
history write	00:00:01.723	0.0%	16014	0.000	0.108	3.896
hiswrite/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
idle	23:57:17.889	99.7%	115609	326.293	745.944	3281.842
nntp read	00:00:00.992	0.0%	116087	0.000	0.009	0.017
overview write	00:00:00.039	0.0%	15103	0.000	0.003	0.043
perl filter	00:00:20.425	0.0%	16014	0.867	1.275	11.975
python filter	00:00:00.504	0.0%	16014	0.000	0.031	0.117
site send	00:00:02.850	0.0%	45310	0.024	0.063	0.101
TOTAL: 24:00:59.874	24:00:44.095	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.033	0.0%	14906	0.000	0.002	0.031
article prepare	00:00:00.001	0.0%	879	0.000	0.001	0.045
article read	00:00:00.106	0.0%	559	0.000	0.190	13.333
backlog stats	00:00:00.060	0.0%	82890	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.419	0.0%	50896	0.000	0.008	0.024
data write	00:00:01.070	0.0%	36250	0.004	0.030	0.075
idle	23:47:36.741	100.0%	82890	581.771	1033.378	2659.473
status file	00:00:00.816	0.0%	202	0.000	4.040	769.000
TOTAL: 23:47:41.733	23:47:39.246	100.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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
ba-mod@panix.com	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	10	27396	9920	17237	239	36%	47:22:02
2	news-out.aioe.org	5	14644	4154	10489	1	28%	24:34:40
3	news.nntp4.net	3	15054	612	13747	695	4%	23:15:21
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		19	57094	14686	41473	935	25%	95:12:03

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	32.0 MB	692.2 KB	32.7 MB	97%	3.3 KB
2	news-out.aioe.org	10.6 MB	1.2 KB	10.6 MB	99%	2.6 KB
3	news.nntp4.net	2.5 MB	8.1 MB	10.6 MB	23%	8.3 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		45.0 MB	8.7 MB	53.8 MB	83%	3.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 21 15:00:04 - 15:59:59	834	5.6%	0.23	2.5 MB	5.5%	0.72
Sep 21 16:00:00 - 16:59:59	795	5.3%	0.22	2.3 MB	5.0%	0.65
Sep 21 17:00:00 - 17:59:59	775	5.2%	0.22	2.4 MB	5.2%	0.67
Sep 21 18:00:00 - 18:59:59	651	4.3%	0.18	2.0 MB	4.3%	0.56
Sep 21 19:00:00 - 19:59:59	758	5.1%	0.21	2.2 MB	4.7%	0.62
Sep 21 20:00:00 - 20:59:59	620	4.1%	0.17	1.7 MB	3.8%	0.50
Sep 21 21:00:00 - 21:59:59	666	4.4%	0.18	1.9 MB	4.0%	0.53
Sep 21 22:00:00 - 22:59:59	663	4.4%	0.18	2.3 MB	4.9%	0.64
Sep 21 23:00:00 - 23:59:59	488	3.3%	0.14	1.7 MB	3.7%	0.49
Sep 22 00:00:00 - 00:59:59	360	2.4%	0.10	1.3 MB	2.8%	0.36
Sep 22 01:00:00 - 01:59:59	384	2.6%	0.11	1.7 MB	3.7%	0.48
Sep 22 02:00:00 - 02:59:59	337	2.2%	0.09	1.7 MB	3.8%	0.50
Sep 22 03:00:00 - 03:59:59	255	1.7%	0.07	1.0 MB	2.2%	0.29
Sep 22 04:00:00 - 04:59:59	290	1.9%	0.08	1009.3 KB	2.1%	0.28
Sep 22 05:00:00 - 05:59:59	367	2.4%	0.10	1.4 MB	3.0%	0.39
Sep 22 06:00:00 - 06:59:59	499	3.3%	0.14	1.9 MB	4.2%	0.54
Sep 22 07:00:00 - 07:59:59	591	3.9%	0.16	1.9 MB	4.1%	0.54
Sep 22 08:00:00 - 08:59:59	783	5.2%	0.22	2.0 MB	4.3%	0.56
Sep 22 09:00:00 - 09:59:59	849	5.7%	0.24	2.5 MB	5.4%	0.70
Sep 22 10:00:00 - 10:59:59	742	4.9%	0.21	1.9 MB	4.1%	0.54
Sep 22 11:00:00 - 11:59:59	695	4.6%	0.19	1.7 MB	3.8%	0.50
Sep 22 12:00:00 - 12:59:59	790	5.3%	0.22	2.2 MB	4.8%	0.63
Sep 22 13:00:00 - 13:59:59	782	5.2%	0.22	2.0 MB	4.4%	0.57
Sep 22 14:00:00 - 15:00:01	1017	6.8%	0.28	2.8 MB	6.1%	0.80
TOTAL: 23:59:57	14991	100.0%	0.17	45.9 MB	100.0%	0.54

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	96.5 MB	9.4%	3	0.22	54.28
ONE	DEFAULT	1024.0 MB	258.6 MB	25.3%	5	0.23	53.51
THREE	DEFAULT	1024.0 MB	99.6 MB	9.7%	3	0.23	53.62
TWO	DEFAULT	1024.0 MB	95.5 MB	9.3%	3	0.23	52.75
TOTAL: 4	-	4.0 GB	550.2 MB	13.4%	14	0.23	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	655	49	1	605
2	hasname-out.news.weretis.net	485	8	0	477
3	news-out.aioe.org	71	0	0	71
TOTAL: 3		1211	57	1	1153

Unwanted newsgroups [Top 20]:

Newsgroup	Count
panix.chat.politics	36
alt.bestjobsusa.jobs.offered	4
alt.bestjobsusa.philly.jobs	3
agency.chat	3
wolfsburg.test	2
alt.gayteen.personals	1
agency.announce	1
alt.bestjobsusa.computers.jobs	1
alt.bestjobsusa.houston.jobs	1
alt.business.ads	1
alt.bestjobsusa.california.jobs	1
alt.penthouse.sex.femdom	1
alt.bestjobsusa.portland.jobs	1
alt.bestjobsusa.dallas.jobs	1
TOTAL: 14	57

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 (md5)	478
EMP (phn path)	311
User-issued spam cancel	29
HTML Multipart	15
HTML post	9
EMP (phl)	8
Scoring filter	1
TOTAL: 7	851

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	227
	news-out.aioe.org	70
	news.nntp4.net	9
	TOTAL: 3	306
TOTAL: 1		306

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	456	456	0	0	0	100%	21:59:47
2	aioe	13472	51	10746	0	2	0%	23:53:55
3	nntp4	16988	23	14213	0	0	0%	23:53:55
4	weretis	4940	1	4702	31	0	0%	23:53:55
TOTAL: 4		35856	531	29661	31	2	1%	93:41:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.7 MB	0.0 KB	1.7 MB	3.7 KB	21:59:47
2	aioe	501.5 KB	0.0 KB	501.5 KB	9.8 KB	23:53:55
3	nntp4	82.8 KB	0.0 KB	82.8 KB	3.6 KB	23:53:55
4	weretis	42.0 KB	193.4 KB	235.4 KB	7.4 KB	23:53:55
TOTAL: 4		2.3 MB	193.4 KB	2.5 MB	4.5 KB	93:41:32

Daily Usenet report

Sep 21 15:00:04 -- Sep 22 15:00:01