Daily Usenet report: Jun 15 15:00:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 15 15:01:27 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Jun 16 08:45:02 colo-sc-1 pgpverify[7505]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	12998	73.5%	1.6 MB	70.7%
innd	2770	15.7%	388.1 KB	17.2%
innfeed	1278	7.2%	179.9 KB	8.0%
cnfsstat	576	3.3%	86.0 KB	3.8%
nnrpd	50	0.3%	5.1 KB	0.2%
controlchan	3	0.0%	0.3 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 8	17677	100.0%	2.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	38217	45.6%
Positive hits	33011	39.4%
Do not exist	12589	15.0%
Cache misses	22	0.0%
TOTAL: 4	83839	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.064	0.0%	77	0.000	0.831	4.111
article cleanup	00:00:00.079	0.0%	12651	0.000	0.006	0.034
article logging	00:00:00.378	0.0%	12920	0.000	0.029	0.083
article parse	00:00:00.236	0.0%	24010	0.000	0.010	0.050
article write	00:00:00.579	0.0%	11240	0.000	0.052	0.452
artlog/artcncl	00:00:00.002	0.0%	77	0.000	0.026	0.333
data move	00:00:00.048	0.0%	93630	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.000	0.0%	75	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	77	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.508	0.0%	84186	0.000	0.042	0.811
history sync	00:00:00.012	0.0%	337	0.000	0.036	0.500
history write	00:00:03.337	0.0%	12651	0.000	0.264	13.037
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	24:00:16.445	100.0%	93506	544.285	924.181	3370.562
nntp read	00:00:00.703	0.0%	93383	0.000	0.008	0.024
overview write	00:00:00.044	0.0%	11240	0.000	0.004	0.034
perl filter	00:00:14.498	0.0%	12651	0.810	1.146	5.609
python filter	00:00:00.371	0.0%	12651	0.000	0.029	0.120
site send	00:00:02.034	0.0%	33721	0.024	0.060	0.096
TOTAL: 24:00:52.603	24:00:42.338	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.029	0.0%	11228	0.000	0.003	0.029
article prepare	00:00:00.001	0.0%	680	0.000	0.001	0.500
article read	00:00:00.023	0.0%	380	0.000	0.061	1.000
backlog stats	00:00:00.052	0.0%	58806	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.310	0.0%	36525	0.000	0.008	0.025
data write	00:00:00.682	0.0%	25426	0.000	0.027	0.090
idle	23:58:52.865	100.0%	58806	815.421	1468.096	3594.787
status file	00:00:00.868	0.0%	274	0.000	3.168	197.500
TOTAL: 23:58:56.904	23:58:54.830	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.5%	10	0.000	0.100	1.000
TOTAL: 00:00:00.218	00:00:00.001	0.5%	-	-	-	-

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	2	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	8	22020	7282	14451	287	33%	48:24:07
2	news-out.aioe.org	2	11796	3749	7894	153	31%	24:48:50
3	news.nntp4.net	1	10561	365	9155	1041	3%	20:31:04
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		12	44377	11396	31500	1481	25%	93:44:01

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	23.4 MB	529.3 KB	23.9 MB	97%	3.2 KB
2	news-out.aioe.org	9.9 MB	217.6 KB	10.1 MB	97%	2.7 KB
3	news.nntp4.net	1.2 MB	4.1 MB	5.4 MB	23%	3.9 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		34.6 MB	4.8 MB	39.4 MB	87%	3.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 15 15:00:01 - 15:59:59	677	6.0%	0.19	2.0 MB	5.9%	0.57
Jun 15 16:00:00 - 16:59:59	624	5.6%	0.17	2.0 MB	5.9%	0.57
Jun 15 17:00:00 - 17:59:59	598	5.3%	0.17	1.6 MB	4.6%	0.45
Jun 15 18:00:00 - 18:59:59	518	4.6%	0.14	1.6 MB	4.6%	0.45
Jun 15 19:00:00 - 19:59:59	585	5.2%	0.16	1.9 MB	5.6%	0.55
Jun 15 20:00:00 - 20:59:59	590	5.3%	0.16	1.5 MB	4.5%	0.44
Jun 15 21:00:00 - 21:59:59	475	4.2%	0.13	1.5 MB	4.3%	0.42
Jun 15 22:00:00 - 22:59:59	405	3.6%	0.11	1.4 MB	4.1%	0.39
Jun 15 23:00:00 - 23:59:59	316	2.8%	0.09	1.3 MB	3.7%	0.36
Jun 16 00:00:00 - 00:59:59	306	2.7%	0.09	990.0 KB	2.8%	0.27
Jun 16 01:00:00 - 01:59:59	274	2.4%	0.08	1.5 MB	4.4%	0.43
Jun 16 02:00:00 - 02:59:59	199	1.8%	0.06	591.7 KB	1.7%	0.16
Jun 16 03:00:00 - 03:59:59	196	1.7%	0.05	582.0 KB	1.7%	0.16
Jun 16 04:00:00 - 04:59:59	248	2.2%	0.07	703.2 KB	2.0%	0.20
Jun 16 05:00:00 - 05:59:59	312	2.8%	0.09	945.8 KB	2.7%	0.26
Jun 16 06:00:00 - 06:59:59	386	3.4%	0.11	1.3 MB	3.7%	0.36
Jun 16 07:00:00 - 07:59:59	447	4.0%	0.12	1.5 MB	4.4%	0.42
Jun 16 08:00:00 - 08:59:59	474	4.2%	0.13	1.2 MB	3.6%	0.35
Jun 16 09:00:00 - 09:59:59	574	5.1%	0.16	1.6 MB	4.7%	0.45
Jun 16 10:00:00 - 10:59:59	577	5.1%	0.16	1.6 MB	4.6%	0.45
Jun 16 11:00:00 - 11:59:59	539	4.8%	0.15	1.5 MB	4.4%	0.42
Jun 16 12:00:00 - 12:59:59	661	5.9%	0.18	1.7 MB	4.9%	0.48
Jun 16 13:00:00 - 13:59:59	652	5.8%	0.18	2.1 MB	6.1%	0.59
Jun 16 14:00:00 - 14:59:59	603	5.4%	0.17	1.7 MB	5.1%	0.49
Jun 16 15:00:00 - 15:00:02	1	0.0%	0.50	3.9 KB	0.0%	1.95
TOTAL: 24:00:01	11237	100.0%	0.13	34.1 MB	100.0%	0.40

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	410.1 MB	40.0%	7	0.17	72.53
ONE	DEFAULT	1024.0 MB	585.4 MB	57.2%	9	0.17	70.91
THREE	DEFAULT	1024.0 MB	421.7 MB	41.2%	7	0.17	71.87
TWO	DEFAULT	1024.0 MB	414.2 MB	40.4%	7	0.17	71.87
TOTAL: 4	-	4.0 GB	1.8 GB	44.7%	30	0.17	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	993	12	1	980
2	hasname-out.news.weretis.net	514	12	0	502
3	news-out.aioe.org	177	0	0	177
TOTAL: 3		1684	24	1	1659

Unwanted newsgroups [Top 20]:

Newsgroup	Count
wolfsburg.test	7
gun.general	4
alt.buttered.scones	3
houston.swingers	2
houston.jobs	2
panix.test	1
3dfx.game.support	1
panix.chat.politics	1
alt.ads	1
alt.sex.gay	1
intel.motherboards.pentium_ii	1
TOTAL: 11	24

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)	598
EMP (md5)	560
EMP (phn nph)	112
EMP (phl)	105
HTML Multipart	9
Binary: misplaced binary	4
Scoring filter	3
HTML post	1
TOTAL: 8	1392

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	231
	news-out.aioe.org	36
	TOTAL: 2	267
TOTAL: 1		267

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	285	285	0	0	1	100%	17:08:10
2	aioe	8467	75	7208	12	0	0%	23:59:57
3	nntp4	12132	3	10862	2	0	0%	23:59:53
4	weretis	4058	0	3935	7	0	0%	23:59:26
TOTAL: 4		24942	363	22005	21	1	1%	89:07:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.1 MB	0.0 KB	1.1 MB	4.0 KB	17:08:10
2	aioe	258.8 KB	46.5 KB	305.3 KB	3.5 KB	23:59:57
3	nntp4	10.2 KB	5.1 KB	15.3 KB	3.1 KB	23:59:53
4	weretis	0.0 KB	37.1 KB	37.1 KB	5.3 KB	23:59:26
TOTAL: 4		1.4 MB	88.7 KB	1.5 MB	3.9 KB	89:07:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
192.241.219.100	0.002	0.001	00:00:00
46.101.130.147	0.005	0.014	00:00:00
192.241.221.74	0.003	0.000	00:00:00
TOTAL: 3	0.010	0.015	00:00:00

NNRP no permission clients:

System	Conn
46.101.130.147	8
192.241.221.74	1
192.241.219.100	1
TOTAL: 3	10

Daily Usenet report

Jun 15 15:00:01 -- Jun 16 15:00:02