Daily Usenet report: Jan 31 15:00:03

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jan 31 15:01:18 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Feb 1 00:04:03 colo-sc-1 pgpverify[17167]: pgpverify: verification failed
Feb 1 06:45:03 colo-sc-1 pgpverify[6340]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	17040	74.3%	2.0 MB	72.0%
innd	4116	17.9%	556.0 KB	19.3%
innfeed	1176	5.1%	160.6 KB	5.6%
cnfsstat	576	2.5%	84.6 KB	2.9%
controlchan	23	0.1%	5.1 KB	0.2%
nnrpd	5	0.0%	0.5 KB	0.0%
pgpverify	2	0.0%	0.4 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 8	22939	100.0%	2.8 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	43070	42.4%
Positive hits	41876	41.2%
Do not exist	16584	16.3%
Cache misses	52	0.1%
TOTAL: 4	101582	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.638	0.0%	83	0.000	31.783	614.000
article cleanup	00:00:00.093	0.0%	16657	0.000	0.006	0.052
article logging	00:00:03.333	0.0%	16981	0.000	0.196	57.796
article parse	00:00:00.297	0.0%	34432	0.000	0.009	0.035
article write	00:00:01.185	0.0%	14037	0.000	0.084	3.716
artlog/artcncl	00:00:00.000	0.0%	83	0.000	0.000	0.000
data move	00:00:00.040	0.0%	115865	0.000	0.000	0.007
hisgrep/artcncl	00:00:00.002	0.0%	81	0.000	0.025	0.333
hishave/artcncl	00:00:01.742	0.0%	83	0.000	20.988	319.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:18.107	0.3%	101927	0.001	2.532	71.332
history sync	00:00:00.016	0.0%	382	0.000	0.042	0.667
history write	00:00:03.792	0.0%	16657	0.000	0.228	12.633
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	23:55:41.393	99.6%	116255	321.545	740.969	2093.035
nntp read	00:00:00.759	0.0%	116902	0.000	0.006	0.028
overview write	00:00:00.040	0.0%	14037	0.000	0.003	0.028
perl filter	00:00:27.827	0.0%	16657	0.879	1.671	65.345
python filter	00:00:00.484	0.0%	16657	0.000	0.029	0.101
site send	00:00:02.670	0.0%	42111	0.029	0.063	0.154
TOTAL: 24:00:58.534	24:00:44.418	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.033	0.0%	14019	0.000	0.002	0.033
article prepare	00:00:00.000	0.0%	854	0.000	0.000	0.000
article read	00:00:00.024	0.0%	576	0.000	0.042	0.500
backlog stats	00:00:00.060	0.0%	81667	0.000	0.001	0.012
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.396	0.0%	49013	0.000	0.008	0.026
data write	00:00:00.920	0.0%	35221	0.004	0.026	0.089
idle	23:58:12.502	100.0%	81667	573.119	1056.639	2593.320
status file	00:00:01.068	0.0%	221	0.000	4.833	982.000
TOTAL: 23:58:21.259	23:58:15.003	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%	1	0.000	0.000	0.000
TOTAL: 00:00:00.113	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

Control Channel:

Sender	Other	Bad PGP	OK
moderator@dana.de	1	1	1
us-control@lists.killfile.org	0	1	0
TOTAL	1	2	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	7	28041	7767	19326	948	27%	47:35:57
2	news-out.aioe.org	2	13930	5903	8024	3	42%	24:19:25
3	news.nntp4.net	2	16250	255	14430	1565	1%	23:37:21
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		12	58221	13925	41780	2516	23%	95:32:43

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	26.3 MB	1.7 MB	28.0 MB	94%	3.3 KB
2	news-out.aioe.org	15.1 MB	4.8 KB	15.1 MB	99%	2.6 KB
3	news.nntp4.net	1.2 MB	8.0 MB	9.2 MB	13%	5.2 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		42.7 MB	9.7 MB	52.4 MB	81%	3.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 31 15:00:03 - 15:59:59	733	5.2%	0.20	2.0 MB	4.6%	0.57
Jan 31 16:00:00 - 16:59:59	758	5.4%	0.21	2.2 MB	5.1%	0.63
Jan 31 17:00:00 - 17:59:59	889	6.3%	0.25	2.8 MB	6.5%	0.80
Jan 31 18:00:00 - 18:59:59	833	5.9%	0.23	2.3 MB	5.4%	0.67
Jan 31 19:00:00 - 19:59:59	775	5.5%	0.22	2.4 MB	5.5%	0.68
Jan 31 20:00:00 - 20:59:59	864	6.2%	0.24	2.3 MB	5.4%	0.66
Jan 31 21:00:00 - 21:59:59	683	4.9%	0.19	2.3 MB	5.2%	0.64
Jan 31 22:00:00 - 22:59:59	681	4.9%	0.19	2.1 MB	4.8%	0.58
Jan 31 23:00:00 - 23:59:59	534	3.8%	0.15	1.5 MB	3.5%	0.43
Feb 1 00:00:00 - 00:59:59	374	2.7%	0.10	1.2 MB	2.8%	0.34
Feb 1 01:00:00 - 01:59:59	431	3.1%	0.12	1.6 MB	3.6%	0.45
Feb 1 02:00:00 - 02:59:59	264	1.9%	0.07	917.7 KB	2.1%	0.25
Feb 1 03:00:00 - 03:59:59	274	2.0%	0.08	980.8 KB	2.2%	0.27
Feb 1 04:00:00 - 04:59:59	332	2.4%	0.09	1.5 MB	3.5%	0.43
Feb 1 05:00:00 - 05:59:59	280	2.0%	0.08	832.1 KB	1.9%	0.23
Feb 1 06:00:00 - 06:59:59	370	2.6%	0.10	1.6 MB	3.7%	0.46
Feb 1 07:00:00 - 07:59:59	471	3.4%	0.13	1.7 MB	4.0%	0.49
Feb 1 08:00:00 - 08:59:59	481	3.4%	0.13	1.3 MB	3.1%	0.38
Feb 1 09:00:00 - 09:59:59	636	4.5%	0.18	1.8 MB	4.1%	0.50
Feb 1 10:00:00 - 10:59:59	650	4.6%	0.18	1.7 MB	3.9%	0.47
Feb 1 11:00:00 - 11:59:59	681	4.9%	0.19	2.3 MB	5.3%	0.65
Feb 1 12:00:00 - 12:59:59	625	4.5%	0.17	1.6 MB	3.8%	0.47
Feb 1 13:00:00 - 13:59:59	689	4.9%	0.19	2.1 MB	4.8%	0.58
Feb 1 14:00:00 - 14:59:59	711	5.1%	0.20	2.3 MB	5.3%	0.65
Feb 1 15:00:00 - 15:00:02	1	0.0%	0.50	2.5 KB	0.0%	1.26
TOTAL: 23:59:59	14020	100.0%	0.16	43.3 MB	100.0%	0.51

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	290.8 MB	28.4%	5	0.21	57.42
ONE	DEFAULT	1024.0 MB	457.1 MB	44.6%	7	0.21	56.56
THREE	DEFAULT	1024.0 MB	299.2 MB	29.2%	5	0.21	57.81
TWO	DEFAULT	1024.0 MB	290.7 MB	28.4%	5	0.21	57.39
TOTAL: 4	-	4.0 GB	1.3 GB	32.7%	22	0.21	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	1624	18	1	1605
2	hasname-out.news.weretis.net	1237	7	0	1230
3	news-out.aioe.org	76	2	0	74
TOTAL: 3		2937	27	1	2909

Unwanted newsgroups [Top 20]:

Newsgroup	Count
panix.chat.politics	10
comp.infosystems.gemini	6
lucky.freebsd.jobs	4
alt.biz.misc	1
alt.soc.germans	1
alt.bestjobsusa.jobs.offered	1
wolfsburg.test	1
alt.french	1
alt.religion.jewish	1
soc.culture.germany	1
TOTAL: 10	27

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)	1774
EMP (md5)	582
EMP (phn nph)	182
EMP (phl)	24
User-issued spam cancel	16
HTML Multipart	3
Scoring filter	2
Too many newsgroups	2
TOTAL: 8	2585

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	249
	news-out.aioe.org	73
	news.nntp4.net	2
	TOTAL: 3	324
TOTAL: 1		324

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	491	491	0	0	0	100%	20:03:27
2	aioe	10967	60	7951	2	1	0%	23:59:49
3	nntp4	16833	5	13759	0	9	0%	23:59:46
4	weretis	6360	0	5986	24	0	0%	23:59:53
TOTAL: 4		34651	556	27696	26	10	1%	92:02:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.7 MB	0.0 KB	1.7 MB	3.4 KB	20:03:27
2	aioe	205.8 KB	10.6 KB	216.4 KB	3.5 KB	23:59:49
3	nntp4	25.0 KB	0.0 KB	25.0 KB	5.0 KB	23:59:46
4	weretis	0.0 KB	93.1 KB	93.1 KB	3.9 KB	23:59:53
TOTAL: 4		1.9 MB	103.7 KB	2.0 MB	3.5 KB	92:02:55

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
68.183.42.89	0.001	0.002	0.000	00:00:00
TOTAL: 1	0.001	0.002	0.000	00:00:00

NNRP no permission clients:

System	Conn
68.183.42.89	1
TOTAL: 1	1

Daily Usenet report

Jan 31 15:00:03 -- Feb 1 15:00:02