Daily Usenet report: Feb 26 15:00:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 26 15:00:03 colo-sc-1 pgpverify[12733]: pgpverify: verification failed
Feb 26 15:00:07 colo-sc-1 pgpverify[12885]: pgpverify: verification failed
Feb 26 15:00:07 colo-sc-1 pgpverify[12893]: pgpverify: verification failed
Feb 26 15:01:39 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	16726	73.3%	2.0 MB	71.2%
innd	4307	18.9%	574.4 KB	20.1%
innfeed	1188	5.2%	163.1 KB	5.7%
cnfsstat	576	2.5%	83.3 KB	2.9%
controlchan	4	0.0%	0.3 KB	0.0%
pgpverify	3	0.0%	0.4 KB	0.0%
TOTAL: 6	22804	100.0%	2.8 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	45328	43.9%
Positive hits	41470	40.2%
Do not exist	16274	15.8%
Cache misses	85	0.1%
TOTAL: 4	103157	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.189	0.0%	131	0.000	16.710	191.000
article cleanup	00:00:00.089	0.0%	16352	0.000	0.005	0.037
article logging	00:00:00.511	0.0%	16606	0.000	0.031	0.078
article parse	00:00:00.260	0.0%	29407	0.000	0.009	0.037
article write	00:00:01.478	0.0%	13680	0.000	0.108	3.410
artlog/artcncl	00:00:00.002	0.0%	130	0.000	0.015	0.100
data move	00:00:00.058	0.0%	114275	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.018	0.0%	130	0.000	0.138	0.586
hishave/artcncl	00:00:00.058	0.0%	131	0.000	0.443	1.828
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:17.704	0.0%	103498	0.000	0.171	5.383
history sync	00:00:00.016	0.0%	405	0.000	0.040	0.667
history write	00:00:04.673	0.0%	16352	0.000	0.286	13.832
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:59:57.865	99.9%	114647	385.496	753.599	2326.465
nntp read	00:00:00.773	0.0%	114576	0.002	0.007	0.016
overview write	00:00:00.045	0.0%	13680	0.000	0.003	0.027
perl filter	00:00:24.321	0.0%	16352	0.802	1.487	15.765
python filter	00:00:00.493	0.0%	16352	0.000	0.030	0.125
site send	00:00:02.330	0.0%	41043	0.027	0.057	0.092
TOTAL: 24:01:10.692	24:00:52.883	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.018	0.0%	13652	0.000	0.001	0.018
article prepare	00:00:00.000	0.0%	676	0.000	0.000	0.000
article read	00:00:00.025	0.0%	546	0.000	0.046	0.500
backlog stats	00:00:00.025	0.0%	69193	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.309	0.0%	44361	0.000	0.007	0.032
data write	00:00:00.841	0.0%	30839	0.000	0.027	0.061
idle	23:58:05.167	100.0%	69193	606.776	1247.022	2752.619
status file	00:00:00.061	0.0%	222	0.000	0.275	1.500
TOTAL: 23:58:12.320	23:58:06.446	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
moderator@dana.de	0	0	0	3	0	0
TOTAL	0	0	0	3	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	10	27148	8124	17889	1135	29%	47:17:49
2	news-out.aioe.org	3	14045	5288	8741	16	37%	24:45:43
3	news.nntp4.net	2	15348	126	13818	1404	0%	22:57:20
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		16	56541	13538	40448	2555	23%	95:00:52

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	25.2 MB	1.9 MB	27.1 MB	92%	3.0 KB
2	news-out.aioe.org	13.6 MB	22.9 KB	13.7 MB	99%	2.6 KB
3	news.nntp4.net	334.3 KB	2.8 MB	3.1 MB	10%	2.1 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		39.2 MB	4.7 MB	43.9 MB	89%	2.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 26 15:00:02 - 15:59:59	665	4.9%	0.18	2.0 MB	5.0%	0.56
Feb 26 16:00:00 - 16:59:59	795	5.8%	0.22	2.1 MB	5.3%	0.59
Feb 26 17:00:00 - 17:59:59	934	6.8%	0.26	2.6 MB	6.5%	0.73
Feb 26 18:00:00 - 18:59:59	757	5.5%	0.21	2.2 MB	5.6%	0.62
Feb 26 19:00:00 - 19:59:59	864	6.3%	0.24	2.7 MB	7.0%	0.78
Feb 26 20:00:00 - 20:59:59	791	5.8%	0.22	2.2 MB	5.6%	0.63
Feb 26 21:00:00 - 21:59:59	913	6.7%	0.25	2.6 MB	6.6%	0.74
Feb 26 22:00:00 - 22:59:59	686	5.0%	0.19	2.0 MB	4.9%	0.56
Feb 26 23:00:00 - 23:59:59	538	3.9%	0.15	1.7 MB	4.3%	0.49
Feb 27 00:00:00 - 00:59:59	394	2.9%	0.11	1.2 MB	3.0%	0.34
Feb 27 01:00:00 - 01:59:59	352	2.6%	0.10	1.2 MB	2.9%	0.33
Feb 27 02:00:00 - 02:59:59	330	2.4%	0.09	1.1 MB	2.8%	0.31
Feb 27 03:00:00 - 03:59:59	336	2.5%	0.09	1.0 MB	2.6%	0.29
Feb 27 04:00:00 - 04:59:59	276	2.0%	0.08	863.0 KB	2.1%	0.24
Feb 27 05:00:00 - 05:59:59	240	1.8%	0.07	764.7 KB	1.9%	0.21
Feb 27 06:00:00 - 06:59:59	316	2.3%	0.09	950.8 KB	2.4%	0.26
Feb 27 07:00:00 - 07:59:59	399	2.9%	0.11	1.5 MB	3.7%	0.42
Feb 27 08:00:00 - 08:59:59	508	3.7%	0.14	1.3 MB	3.4%	0.38
Feb 27 09:00:00 - 09:59:59	565	4.1%	0.16	1.5 MB	3.7%	0.42
Feb 27 10:00:00 - 10:59:59	534	3.9%	0.15	1.2 MB	3.1%	0.35
Feb 27 11:00:00 - 11:59:59	535	3.9%	0.15	1.5 MB	3.7%	0.42
Feb 27 12:00:00 - 12:59:59	595	4.4%	0.17	1.8 MB	4.6%	0.52
Feb 27 13:00:00 - 13:59:59	549	4.0%	0.15	1.4 MB	3.7%	0.41
Feb 27 14:00:00 - 14:59:59	793	5.8%	0.22	2.1 MB	5.3%	0.60
Feb 27 15:00:00 - 15:00:08	5	0.0%	0.62	20.0 KB	0.0%	2.50
TOTAL: 24:00:06	13670	100.0%	0.16	39.5 MB	100.0%	0.47

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	761.2 MB	74.3%	5	0.20	61.41
ONE	DEFAULT	1024.0 MB	930.4 MB	90.9%	7	0.20	59.85
THREE	DEFAULT	1024.0 MB	769.5 MB	75.1%	5	0.20	61.48
TWO	DEFAULT	1024.0 MB	761.0 MB	74.3%	5	0.20	60.03
TOTAL: 4	-	4.0 GB	3.1 GB	78.7%	22	0.20	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	1495	2	1	1492
2	hasname-out.news.weretis.net	1363	1	0	1362
3	news-out.aioe.org	68	3	0	65
TOTAL: 3		2926	6	1	2919

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.infosystems.gemini	3
hk.test	1
ukc.test	1
alt.exploding.barney	1
TOTAL: 4	6

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)	2123
EMP (md5)	280
EMP (phn nph)	195
Excessive Supersedes (88.165.5.23)	37
HTML Multipart	7
Too many newsgroups	7
User-issued spam cancel	7
Scoring filter	5
Binary: misplaced binary	2
EMP (phl)	1
TOTAL: 10	2664

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	203
	news-out.aioe.org	52
	TOTAL: 2	255
TOTAL: 1		255

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	506	506	0	0	0	100%	20:45:29
2	aioe	9486	20	8315	1	0	0%	23:59:57
3	nntp4	14824	0	13540	0	0	0%	23:59:57
4	weretis	5482	0	5249	22	4	0%	23:59:34
TOTAL: 4		30298	526	27104	23	4	1%	92:44:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.8 MB	0.0 KB	1.8 MB	3.6 KB	20:45:29
2	aioe	67.4 KB	2.5 KB	69.9 KB	3.3 KB	23:59:57
3	nntp4	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
4	weretis	0.0 KB	78.3 KB	78.3 KB	3.6 KB	23:59:34
TOTAL: 4		1.8 MB	80.8 KB	1.9 MB	3.6 KB	92:44:57

Daily Usenet report

Feb 26 15:00:02 -- Feb 27 15:00:08