Daily Usenet report: Mar 29 15:00:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Mar 29 15:01:15 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Mar 29 20:00:04 colo-sc-1 pgpverify[19167]: pgpverify: verification failed
Mar 29 20:00:11 colo-sc-1 pgpverify[19191]: pgpverify: verification failed
Mar 29 20:01:05 colo-sc-1 pgpverify[19309]: pgpverify: verification failed
Mar 29 20:01:07 colo-sc-1 pgpverify[19323]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	15527	72.5%	1.8 MB	70.4%
innd	4046	18.9%	529.7 KB	19.8%
innfeed	1267	5.9%	176.3 KB	6.6%
cnfsstat	576	2.7%	82.4 KB	3.1%
controlchan	5	0.0%	0.4 KB	0.0%
pgpverify	4	0.0%	0.8 KB	0.0%
nnrpd	4	0.0%	0.4 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 8	21430	100.0%	2.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	41280	43.6%
Positive hits	38117	40.3%
Do not exist	15172	16.0%
Cache misses	26	0.0%
TOTAL: 4	94595	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.732	0.0%	59	0.000	63.254	2823.000
article cleanup	00:00:00.083	0.0%	15257	0.000	0.005	0.056
article logging	00:00:00.519	0.0%	15474	0.000	0.034	0.132
article parse	00:00:00.231	0.0%	26055	0.000	0.009	0.059
article write	00:00:02.834	0.0%	12846	0.000	0.221	3.741
artlog/artcncl	00:00:00.001	0.0%	59	0.000	0.017	1.000
data move	00:00:00.057	0.0%	101925	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.000	0.0%	54	0.000	0.000	0.000
hishave/artcncl	00:00:01.944	0.0%	59	0.000	32.949	1865.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:25.914	0.0%	95076	0.001	0.273	5.189
history sync	00:00:00.015	0.0%	404	0.000	0.037	1.000
history write	00:00:05.312	0.0%	15251	0.000	0.348	8.429
hiswrite/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
idle	23:59:44.972	99.9%	102563	192.757	842.263	4654.233
nntp read	00:00:00.754	0.0%	102496	0.000	0.007	0.029
overview write	00:00:00.053	0.0%	12846	0.000	0.004	0.040
perl filter	00:00:22.581	0.0%	15251	0.889	1.481	29.822
python filter	00:00:00.455	0.0%	15251	0.000	0.030	0.111
site send	00:00:02.402	0.0%	38541	0.022	0.062	0.105
TOTAL: 24:01:10.287	24:00:51.859	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.026	0.0%	12836	0.000	0.002	0.062
article prepare	00:00:00.000	0.0%	837	0.000	0.000	0.000
article read	00:00:00.033	0.0%	570	0.000	0.058	0.667
backlog stats	00:00:00.047	0.0%	68780	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.356	0.0%	42694	0.000	0.008	0.027
data write	00:00:00.858	0.0%	30186	0.000	0.028	0.074
idle	23:59:27.663	100.0%	68780	446.693	1255.709	3746.809
status file	00:00:00.148	0.0%	261	0.000	0.567	23.667
TOTAL: 23:59:35.126	23:59:29.131	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.8%	1	1.000	1.000	1.000
TOTAL: 00:00:00.119	00:00:00.001	0.8%	-	-	-	-

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
group-admin@isc.org	0	0	0	4	0	0
TOTAL	0	0	0	4	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	12	25359	8523	15976	860	33%	48:22:45
2	news-out.aioe.org	5	13037	4264	8758	15	32%	25:02:03
3	news.nntp4.net	2	14075	214	12454	1407	1%	23:03:40
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		20	52471	13001	37188	2282	24%	96:28:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	27.2 MB	38.1 KB	1.6 MB	28.8 MB	94%	3.1 KB
2	news-out.aioe.org	10.1 MB	17.0 KB	31.1 KB	10.2 MB	99%	2.4 KB
3	news.nntp4.net	760.5 KB	3.6 KB	3.1 MB	3.9 MB	19%	2.5 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		38.0 MB	58.7 KB	4.7 MB	42.8 MB	88%	2.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 29 15:00:02 - 15:59:59	765	6.0%	0.21	2.2 MB	5.9%	0.63
Mar 29 16:00:00 - 16:59:59	708	5.5%	0.20	2.0 MB	5.4%	0.58
Mar 29 17:00:00 - 17:59:59	744	5.8%	0.21	2.1 MB	5.6%	0.60
Mar 29 18:00:00 - 18:59:59	616	4.8%	0.17	1.8 MB	4.7%	0.50
Mar 29 19:00:00 - 19:59:59	670	5.2%	0.19	1.9 MB	5.1%	0.54
Mar 29 20:00:00 - 20:59:59	654	5.1%	0.18	2.2 MB	5.8%	0.62
Mar 29 21:00:00 - 21:59:59	563	4.4%	0.16	1.8 MB	4.8%	0.51
Mar 29 22:00:00 - 22:59:59	483	3.8%	0.13	1.8 MB	4.9%	0.52
Mar 29 23:00:00 - 23:59:59	361	2.8%	0.10	1.3 MB	3.5%	0.38
Mar 30 00:00:00 - 00:59:59	249	1.9%	0.07	915.2 KB	2.4%	0.25
Mar 30 01:00:00 - 01:59:59	247	1.9%	0.07	807.4 KB	2.1%	0.22
Mar 30 02:00:00 - 02:59:59	186	1.4%	0.05	640.6 KB	1.7%	0.18
Mar 30 03:00:00 - 03:59:59	213	1.7%	0.06	668.3 KB	1.7%	0.19
Mar 30 04:00:00 - 04:59:59	305	2.4%	0.08	1.1 MB	2.9%	0.32
Mar 30 05:00:00 - 05:59:59	270	2.1%	0.07	796.1 KB	2.1%	0.22
Mar 30 06:00:00 - 06:59:59	421	3.3%	0.12	1.2 MB	3.2%	0.34
Mar 30 07:00:00 - 07:59:59	535	4.2%	0.15	1.4 MB	3.7%	0.39
Mar 30 08:00:00 - 08:59:59	658	5.1%	0.18	2.0 MB	5.3%	0.57
Mar 30 09:00:00 - 09:59:59	606	4.7%	0.17	1.6 MB	4.2%	0.45
Mar 30 10:00:00 - 10:59:59	644	5.0%	0.18	1.6 MB	4.3%	0.46
Mar 30 11:00:00 - 11:59:59	663	5.2%	0.18	1.8 MB	4.8%	0.51
Mar 30 12:00:00 - 12:59:59	609	4.7%	0.17	1.5 MB	4.1%	0.44
Mar 30 13:00:00 - 13:59:59	754	5.9%	0.21	2.1 MB	5.5%	0.59
Mar 30 14:00:00 - 14:59:59	911	7.1%	0.25	2.4 MB	6.5%	0.69
Mar 30 15:00:00 - 15:00:01	2	0.0%	2.00	5.6 KB	0.0%	5.64
TOTAL: 23:59:59	12837	100.0%	0.15	37.7 MB	100.0%	0.45

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	256.2 MB	25.0%	6	0.19	64.16
ONE	DEFAULT	1024.0 MB	427.5 MB	41.7%	8	0.19	63.92
THREE	DEFAULT	1024.0 MB	264.8 MB	25.9%	6	0.19	63.92
TWO	DEFAULT	1024.0 MB	255.9 MB	25.0%	6	0.19	64.85
TOTAL: 4	-	4.0 GB	1.2 GB	29.4%	26	0.19	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nntp4.net	1538	12	1	1	1524
2	hasname-out.news.weretis.net	1036	0	1	0	1035
3	news-out.aioe.org	58	0	4	0	54
TOTAL: 3		2632	12	6	1	2613

Unwanted newsgroups [Top 20]:

Newsgroup	Count
newsreader.test	4
alt.sex.female	2
alt.0000.test	2
bigweek.general	1
fa.music.j-m-jarre	1
alt.bestjobsusa.philly.jobs	1
panix.chat.politics	1
TOTAL: 7	12

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)	1466
EMP (md5)	541
Too many newsgroups	203
EMP (phn nph)	163
EMP (phl)	15
User-issued spam cancel	3
HTML Multipart	3
Too many newsgroups (meow)	2
Binary: misplaced binary	1
TOTAL: 9	2397

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	173
	news-out.aioe.org	43
	TOTAL: 2	216
TOTAL: 1		216

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	481	481	0	0	0	100%	19:48:53
2	aioe	10266	64	8540	2	0	0%	23:59:50
3	nntp4	14385	1	12606	0	5	0%	23:59:52
4	weretis	4447	0	4252	22	0	0%	23:59:24
TOTAL: 4		29579	546	25398	24	5	1%	91:47:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.9 MB	0.0 KB	1.9 MB	4.1 KB	19:48:53
2	aioe	198.9 KB	3.8 KB	202.7 KB	3.1 KB	23:59:50
3	nntp4	2.4 KB	0.0 KB	2.4 KB	2.4 KB	23:59:52
4	weretis	0.0 KB	76.9 KB	76.9 KB	3.5 KB	23:59:24
TOTAL: 4		2.1 MB	80.7 KB	2.2 MB	4.0 KB	91:47:59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
tor.exit.lv1.plithismos.net	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
tor.exit.lv1.plithismos.net	1
TOTAL: 1	1

Daily Usenet report

Mar 29 15:00:02 -- Mar 30 15:00:01