Daily Usenet report: Feb 28 15:00:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Feb 28 15:01:30 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Mar 1 00:04:03 colo-sc-1 pgpverify[27974]: pgpverify: verification failed
Mar 1 06:45:03 colo-sc-1 pgpverify[18856]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18399	75.7%	2.2 MB	73.8%
innd	4100	16.9%	557.8 KB	18.1%
innfeed	1205	5.0%	165.5 KB	5.4%
cnfsstat	576	2.4%	78.4 KB	2.5%
controlchan	23	0.1%	5.0 KB	0.2%
nnrpd	15	0.1%	1.5 KB	0.0%
pgpverify	2	0.0%	0.4 KB	0.0%
TOTAL: 7	24320	100.0%	3.0 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	51444	45.1%
Positive hits	44643	39.2%
Do not exist	17748	15.6%
Cache misses	192	0.2%
TOTAL: 4	114027	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.198	0.0%	582	0.000	8.931	413.250
article cleanup	00:00:00.109	0.0%	17438	0.000	0.006	0.033
article logging	00:00:00.561	0.0%	17728	0.000	0.032	0.103
article parse	00:00:00.274	0.0%	31142	0.000	0.009	0.034
article write	00:00:01.950	0.0%	14919	0.000	0.131	11.780
artlog/artcncl	00:00:00.005	0.0%	574	0.000	0.009	0.500
data move	00:00:00.068	0.0%	123979	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.002	0.0%	213	0.000	0.009	0.333
hishave/artcncl	00:00:02.533	0.0%	582	0.000	4.352	351.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:30.044	0.3%	113768	0.000	2.374	66.168
history sync	00:00:00.018	0.0%	388	0.000	0.046	0.667
history write	00:00:03.045	0.0%	17425	0.000	0.175	8.130
hiswrite/artcncl	00:00:00.003	0.0%	369	0.000	0.008	0.011
idle	23:45:31.899	99.6%	123162	181.728	694.467	2344.523
nntp read	00:00:00.859	0.0%	123880	0.000	0.007	0.020
overview write	00:00:00.068	0.0%	14919	0.000	0.005	0.036
perl filter	00:00:28.484	0.0%	17425	0.571	1.635	38.836
python filter	00:00:00.577	0.0%	17425	0.000	0.033	0.093
site send	00:00:02.754	0.0%	44758	0.032	0.062	0.124
TOTAL: 23:51:07.158	23:50:48.451	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.029	0.0%	14972	0.000	0.002	0.022
article prepare	00:00:00.001	0.0%	940	0.000	0.001	0.022
article read	00:00:00.027	0.0%	731	0.000	0.037	0.500
backlog stats	00:00:00.060	0.0%	75122	0.000	0.001	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.400	0.0%	48667	0.000	0.008	0.025
data write	00:00:00.921	0.0%	33852	0.000	0.027	0.074
idle	23:58:07.029	100.0%	75122	231.653	1148.625	2837.184
status file	00:00:00.620	0.0%	231	0.000	2.684	110.200
TOTAL: 23:58:10.855	23:58:09.087	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.001	0.0%	3	0.000	0.333	1.000
TOTAL: 00:00:03.494	00:00:00.001	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	10	28954	8557	19200	1197	29%	46:22:30
2	news-out.aioe.org	3	14844	6182	8643	19	41%	24:14:01
3	news.nntp4.net	2	17484	220	15927	1337	1%	24:00:18
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		16	61282	14959	43770	2553	24%	94:36:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	27.8 MB	42.7 KB	2.1 MB	29.9 MB	92%	3.1 KB
2	news-out.aioe.org	15.8 MB	6.6 KB	30.2 KB	15.9 MB	99%	2.6 KB
3	news.nntp4.net	895.6 KB	18.0 KB	2.7 MB	3.6 MB	24%	2.4 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		44.5 MB	67.3 KB	4.8 MB	49.4 MB	90%	2.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 28 15:00:01 - 15:59:59	765	5.1%	0.21	2.1 MB	4.7%	0.60
Feb 28 16:00:00 - 16:59:59	855	5.7%	0.24	2.3 MB	5.2%	0.66
Feb 28 17:00:00 - 17:59:59	893	6.0%	0.25	2.9 MB	6.4%	0.82
Feb 28 18:00:00 - 18:59:59	1346	9.0%	0.37	2.9 MB	6.5%	0.83
Feb 28 19:00:00 - 19:59:59	894	6.0%	0.25	2.4 MB	5.5%	0.70
Feb 28 20:00:00 - 20:59:59	682	4.5%	0.19	1.9 MB	4.3%	0.54
Feb 28 21:00:00 - 21:59:59	697	4.6%	0.19	1.9 MB	4.3%	0.55
Feb 28 22:00:00 - 22:59:59	738	4.9%	0.20	2.0 MB	4.5%	0.57
Feb 28 23:00:00 - 23:59:59	543	3.6%	0.15	1.5 MB	3.4%	0.44
Mar 1 00:00:00 - 00:59:59	487	3.2%	0.14	1.7 MB	3.8%	0.49
Mar 1 01:00:00 - 01:59:59	374	2.5%	0.10	1.6 MB	3.5%	0.45
Mar 1 02:00:00 - 02:59:59	339	2.3%	0.09	1.2 MB	2.6%	0.33
Mar 1 03:00:00 - 03:59:59	319	2.1%	0.09	1.1 MB	2.4%	0.31
Mar 1 04:00:00 - 04:59:59	274	1.8%	0.08	811.3 KB	1.8%	0.23
Mar 1 05:00:00 - 05:59:59	288	1.9%	0.08	1.1 MB	2.4%	0.31
Mar 1 06:00:00 - 06:59:59	312	2.1%	0.09	1.1 MB	2.5%	0.32
Mar 1 07:00:00 - 07:59:59	507	3.4%	0.14	2.2 MB	5.0%	0.63
Mar 1 08:00:00 - 08:59:59	614	4.1%	0.17	2.0 MB	4.3%	0.56
Mar 1 09:00:00 - 09:59:59	674	4.5%	0.19	1.9 MB	4.3%	0.55
Mar 1 10:00:00 - 10:59:59	672	4.5%	0.19	1.9 MB	4.3%	0.55
Mar 1 11:00:00 - 11:59:59	726	4.8%	0.20	2.0 MB	4.5%	0.57
Mar 1 12:00:00 - 12:59:59	631	4.2%	0.18	1.7 MB	3.8%	0.48
Mar 1 13:00:00 - 13:59:59	660	4.4%	0.18	1.7 MB	3.9%	0.50
Mar 1 14:00:00 - 14:59:59	694	4.6%	0.19	2.8 MB	6.2%	0.79
Mar 1 15:00:00 - 15:00:09	6	0.0%	0.67	18.6 KB	0.0%	2.06
TOTAL: 24:00:08	14990	100.0%	0.17	44.9 MB	100.0%	0.53

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	797.9 MB	77.9%	5	0.23	53.72
ONE	DEFAULT	1024.0 MB	967.0 MB	94.4%	7	0.22	54.03
THREE	DEFAULT	1024.0 MB	806.3 MB	78.7%	5	0.23	53.63
TWO	DEFAULT	1024.0 MB	797.6 MB	77.9%	5	0.22	54.79
TOTAL: 4	-	4.0 GB	3.3 GB	82.2%	22	0.22	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	hasname-out.news.weretis.net	1403	2	7	0	1394
2	news.nntp4.net	1339	12	4	1	1322
3	news-out.aioe.org	93	0	2	0	91
TOTAL: 3		2835	14	13	1	2807

Unwanted newsgroups [Top 20]:

Newsgroup	Count
panix.test	3
mozilla.dev.ports.os2	2
clari.local.texas.briefs	1
jobs.misc.jobs	1
lucky.freebsd.jobs	1
panix.chat.politics	1
alt.soc.germans	1
soc.culture.germany	1
alt.french	1
alt.games.quake	1
alt.religion.jewish	1
TOTAL: 11	14

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)	1977
EMP (md5)	307
EMP (phn nph)	102
EMP (phl)	43
Excessive Supersedes (88.165.5.23)	34
Too many newsgroups	28
User-issued spam cancel	16
HTML Multipart	5
Scoring filter	3
Binary: misplaced binary	1
TOTAL: 10	2516

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	218
	news-out.aioe.org	73
	TOTAL: 2	291
TOTAL: 1		291

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	bbsgslincom	664	664	0	0	100%	20:56:58
2	aioe	9898	25	8657	1	0%	24:00:03
3	nntp4	15988	0	14772	0	0%	24:00:03
4	weretis	6555	0	6279	43	0%	23:59:50
TOTAL: 4		33105	689	29708	44	2%	92:56:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	2.5 MB	0.0 KB	2.5 MB	3.9 KB	20:56:58
2	aioe	82.1 KB	4.1 KB	86.2 KB	3.3 KB	24:00:03
3	nntp4	0.0 KB	0.0 KB	0.0 KB	0	24:00:03
4	weretis	0.0 KB	212.6 KB	212.6 KB	4.9 KB	23:59:50
TOTAL: 4		2.6 MB	216.6 KB	2.8 MB	3.9 KB	92:56:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
27.124.5.26	0.000	0.006	00:00:03
210.118.26.167	0.001	0.001	00:00:00
TOTAL: 2	0.001	0.007	00:00:03

NNRP no permission clients:

System	Conn
27.124.5.26	2
210.118.26.167	1
TOTAL: 2	3

Daily Usenet report

Feb 28 15:00:01 -- Mar 1 15:00:09