Daily Usenet report for csiph.com: Sep 21 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18413	45.8%	4.6 MB	61.5%
innd	18055	45.0%	2.3 MB	30.3%
innfeed	3378	8.4%	588.0 KB	7.7%
nnrpd	277	0.7%	30.2 KB	0.4%
nocem	39	0.1%	4.3 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40163	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	255870	57.0%
Negative hits	175070	39.0%
Do not exist	17896	4.0%
Cache misses	287	0.1%
TOTAL: 4	449123	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.941	0.0%	82	0.000	11.476	262.000
article cleanup	00:00:00.107	0.0%	16806	0.000	0.006	0.043
article logging	00:00:00.321	0.0%	17172	0.000	0.019	0.275
article parse	00:00:04.983	0.0%	79232	0.000	0.063	0.502
article write	00:02:23.916	0.2%	13791	0.326	10.436	41.298
artlog/artcncl	00:00:00.000	0.0%	63	0.000	0.000	0.000
artlog/artparse	00:00:00.010	0.0%	1219	0.000	0.008	0.167
data move	00:00:00.144	0.0%	448079	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.108	0.0%	78	0.000	1.385	12.000
hishave/artcncl	00:00:00.019	0.0%	82	0.000	0.232	1.556
hishave/artparse	00:00:00.005	0.0%	1216	0.000	0.004	0.083
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:44.469	0.1%	450822	0.001	0.099	0.548
history sync	00:00:02.192	0.0%	384	0.000	5.708	67.667
history write	00:00:52.353	0.1%	16807	0.260	3.115	12.696
hiswrite/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hiswrite/artparse	00:00:04.213	0.0%	1215	0.000	3.467	76.000
idle	23:51:45.037	99.4%	443822	103.217	193.557	616.792
nntp read	00:00:02.090	0.0%	454314	0.001	0.005	0.018
overview write	00:03:51.671	0.3%	13791	1.712	16.799	1082.447
perl filter	00:00:31.279	0.0%	16801	1.292	1.862	8.150
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.243	0.0%	27619	0.000	0.009	0.031
TOTAL: 24:00:57.585	24:00:24.101	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.041	0.0%	13821	0.000	0.003	0.027
article prepare	00:00:00.003	0.0%	14744	0.000	0.000	0.015
article read	00:00:07.850	0.0%	1297	0.000	6.052	79.000
backlog stats	00:00:00.204	0.0%	295282	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.294	0.0%	225955	0.002	0.006	0.013
data write	00:00:01.397	0.0%	214656	0.002	0.007	0.013
idle	23:55:44.173	100.0%	295282	169.282	291.735	818.761
status file	00:00:01.755	0.0%	288	0.000	6.094	114.000
TOTAL: 23:56:04.873	23:55:56.717	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	09:32:01.301	100.0%	215	99.000	159633.958	600079.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.003	0.0%	468	0.000	0.006	0.024
readart	00:00:00.161	0.0%	12	0.000	13.417	47.000
TOTAL: 09:32:12.699	09:32:01.465	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	19
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	39

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	27212	2763	24197	252	10%	47:55:11
2	feeder.eternal-september.org	1	15266	1625	13576	65	10%	23:44:42
3	csiph-out.news.xs4all.nl	864	15888	1526	13082	1280	9%	71:12:30
4	newsfeeds.xmission.com	2	14190	1370	12811	9	9%	27:21:16
5	news-out.mixmin.net	2	14325	1221	12951	153	8%	23:59:53
6	csiph-out.feeder.erje.net	1440	67444	1199	65730	515	1%	118:42:04
7	news.freedyn.net	2	17219	1173	14747	1299	6%	23:59:46
8	news-out.aioe.org	2	13441	1168	12250	23	8%	23:59:53
9	newsfeed.fu-berlin.de	10	12531	684	11804	43	5%	24:33:15
10	newsfeed.neva.ru	152	18072	654	17071	347	3%	72:13:25
11	csiph-out.feed.uzoreto.com	864	22285	198	21861	226	0%	71:17:07
12	news.fcku.it	8	1333	121	1207	5	9%	20:48:49
13	nntp.pasdenom.info	2	9150	76	9057	17	0%	23:58:20
14	news.swapon.de	8	1144	19	1125	0	1%	23:26:54
15	news.samoylyk.net	1	13315	19	13296	0	0%	23:59:54
16	news.bbs.nz	2	13446	10	13436	0	0%	23:59:44
17	localhost	32	4	4	0	0	100%	00:00:05
18	xanadu-bbs.net	32	321	0	321	0	0%	20:33:47
TOTAL: 18		3427	276586	13830	258522	4234	5%	665:46:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	9.2 MB	15.4 KB	20.1 MB	29.3 MB	31%	17.5 KB
2	csiph-out.news.weretis.net	9.2 MB	0.0 KB	3.4 MB	12.6 MB	72%	4.3 KB
3	feeder.eternal-september.org	4.6 MB	0.0 KB	203.1 KB	4.8 MB	95%	2.9 KB
4	csiph-out.news.xs4all.nl	4.2 MB	0.0 KB	8.7 MB	12.9 MB	32%	4.7 KB
5	news-out.mixmin.net	4.0 MB	0.0 KB	370.1 KB	4.4 MB	91%	3.3 KB
6	newsfeeds.xmission.com	3.9 MB	0.0 KB	504.8 KB	4.4 MB	88%	3.3 KB
7	news.freedyn.net	3.0 MB	3.7 KB	72.6 MB	75.5 MB	3%	31.3 KB
8	news-out.aioe.org	2.6 MB	0.0 KB	43.3 KB	2.6 MB	98%	2.2 KB
9	newsfeed.fu-berlin.de	1.6 MB	0.0 KB	90.0 KB	1.7 MB	94%	2.4 KB
10	newsfeed.neva.ru	1.5 MB	0.0 KB	1.1 MB	2.6 MB	58%	2.6 KB
11	csiph-out.feed.uzoreto.com	568.0 KB	0.0 KB	3.5 MB	4.1 MB	13%	9.8 KB
12	news.fcku.it	236.9 KB	0.0 KB	71.7 KB	308.6 KB	76%	2.4 KB
13	nntp.pasdenom.info	177.3 KB	0.0 KB	26.7 KB	204.0 KB	86%	2.2 KB
14	news.samoylyk.net	72.3 KB	0.0 KB	0.0 KB	72.3 KB	100%	3.8 KB
15	news.swapon.de	50.7 KB	0.0 KB	0.0 KB	50.7 KB	100%	2.7 KB
16	news.bbs.nz	14.8 KB	0.0 KB	0.0 KB	14.8 KB	100%	1.5 KB
17	localhost	10.6 KB	0.0 KB	0.0 KB	10.6 KB	100%	2.6 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		45.0 MB	19.1 KB	110.6 MB	155.6 MB	28%	8.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 21 04:15:00 - 04:59:59	204	1.5%	0.08	637.6 KB	1.4%	0.24
Sep 21 05:00:00 - 05:59:59	339	2.5%	0.09	1.2 MB	2.6%	0.34
Sep 21 06:00:00 - 06:59:59	405	2.9%	0.11	1.6 MB	3.7%	0.47
Sep 21 07:00:00 - 07:59:59	698	5.1%	0.19	3.1 MB	6.8%	0.87
Sep 21 08:00:00 - 08:59:59	789	5.7%	0.22	4.0 MB	9.0%	1.14
Sep 21 09:00:00 - 09:59:59	630	4.6%	0.17	1.8 MB	3.9%	0.50
Sep 21 10:00:00 - 10:59:59	637	4.6%	0.18	2.1 MB	4.6%	0.59
Sep 21 11:00:00 - 11:59:59	601	4.4%	0.17	1.7 MB	3.8%	0.48
Sep 21 12:00:00 - 12:59:59	601	4.4%	0.17	1.5 MB	3.3%	0.42
Sep 21 13:00:00 - 13:59:59	728	5.3%	0.20	2.3 MB	5.2%	0.66
Sep 21 14:00:00 - 14:59:59	805	5.8%	0.22	2.8 MB	6.3%	0.80
Sep 21 15:00:00 - 15:59:59	667	4.8%	0.19	1.7 MB	3.8%	0.49
Sep 21 16:00:00 - 16:59:59	759	5.5%	0.21	2.2 MB	5.0%	0.64
Sep 21 17:00:00 - 17:59:59	731	5.3%	0.20	2.1 MB	4.6%	0.59
Sep 21 18:00:00 - 18:59:59	722	5.2%	0.20	2.2 MB	4.8%	0.62
Sep 21 19:00:00 - 19:59:59	800	5.8%	0.22	2.1 MB	4.6%	0.59
Sep 21 20:00:00 - 20:59:59	753	5.5%	0.21	2.2 MB	4.9%	0.63
Sep 21 21:00:00 - 21:59:59	656	4.8%	0.18	1.9 MB	4.1%	0.53
Sep 21 22:00:00 - 22:59:59	521	3.8%	0.14	1.9 MB	4.3%	0.55
Sep 21 23:00:00 - 23:59:59	438	3.2%	0.12	1.6 MB	3.5%	0.44
Sep 22 00:00:00 - 00:59:59	401	2.9%	0.11	1.4 MB	3.0%	0.38
Sep 22 01:00:00 - 01:59:59	316	2.3%	0.09	1009.9 KB	2.2%	0.28
Sep 22 02:00:00 - 02:59:59	230	1.7%	0.06	937.9 KB	2.0%	0.26
Sep 22 03:00:00 - 03:59:59	276	2.0%	0.08	817.9 KB	1.8%	0.23
Sep 22 04:00:00 - 04:15:00	66	0.5%	0.07	249.9 KB	0.5%	0.28
TOTAL: 24:00:00	13773	100.0%	0.16	44.8 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.freedyn.net	1319	19	1	1299
2	csiph-out.news.xs4all.nl	1315	23	0	1292
3	csiph-out.feeder.erje.net	518	60	1	457
4	newsfeed.fu-berlin.de	341	7	0	334
5	newsfeed.neva.ru	341	35	0	306
6	csiph-out.news.weretis.net	255	112	0	143
7	csiph-out.feed.uzoreto.com	227	3	0	224
8	news-out.mixmin.net	145	0	0	145
9	feeder.eternal-september.org	63	10	0	53
10	news-out.aioe.org	21	10	0	11
11	nntp.pasdenom.info	16	0	0	16
12	newsfeeds.xmission.com	8	0	0	8
13	news.fcku.it	5	4	0	1
14	news.swapon.de	4	0	0	4
TOTAL: 14		4578	283	2	4293

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bbs.synchronet	28
alt.binaries.pictures.purebeauty	23
lada.talk	22
rocksolid.shared.test	20
mozilla.support.firefox	19
mozilla.general	15
it-alt.sport.calcio.juventus	14
mozilla.support.seamonkey	12
alt.windows7.general	10
mozilla.support.thunderbird	9
alt.etc.passwd	6
att.jobs	6
it-alt.comp.software.mozilla	5
rocksolid.nodes.announce	4
alt.job	4
oesterreich.tratsch	4
lada.commerce	4
rocksolid.shared.linux	4
cakewalk.audio	4
alt.christian.bible	4
TOTAL: 65	283

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2264
EMP (phn path)	100
EMP (phl)	93
EMP (phn nph)	69
Too many newsgroups	67
Binary Payload	32
HTML Multipart	23
User-issued cancel	20
Binary: misplaced rar	14
User-issued spam cancel	9
Scoring filter (10)	5
Scoring filter (15)	2
Scoring filter (8)	2
Scoring filter (14)	2
Scoring filter (13)	2
Scoring filter (9)	1
U2 violation - invalid distribution	1
Scoring filter (12)	1
TOTAL: 18	2707

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	29	0	29	29
TOTAL: 1	29	8	29	29

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	635
	csiph-out.feeder.erje.net	354
	csiph-out.news.xs4all.nl	122
	csiph-out.feed.uzoreto.com	54
	csiph-out.news.weretis.net	39
	newsfeeds.xmission.com	8
	news-out.mixmin.net	1
	feeder.eternal-september.org	1
	news.fcku.it	1
	TOTAL: 9	1215
Including strange strings
	newsfeed.fu-berlin.de	298
	csiph-out.news.xs4all.nl	39
	news-out.mixmin.net	6
	csiph-out.news.weretis.net	5
	news.swapon.de	4
	news.freedyn.net	4
	news-out.aioe.org	3
	csiph-out.feeder.erje.net	3
	csiph-out.feed.uzoreto.com	3
	TOTAL: 9	365
No colon-space in header
	csiph-out.news.xs4all.nl	3
	TOTAL: 1	3
TOTAL: 4		1628

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.nz	24569	664	12588	5	0	2%	23:59:38
2	xanadu-bbs	15458	619	13153	0	0	4%	23:59:52
3	news.samoylyk.net	14117	512	13240	3	0	3%	23:59:52
4	news.freedyn.net	12761	500	12064	23	0	3%	23:59:46
5	fu-berlin.de	12937	494	12443	0	0	3%	23:59:41
6	usenet.pasdenom.info	13922	54	13126	516	13	0%	23:59:54
7	erje	15495	43	12325	105	0	0%	23:59:40
8	neva.ru	12771	19	12352	59	0	0%	23:59:11
9	newsfeed.xs4all.nl	12320	17	11672	6	50	0%	23:59:53
10	weretis.net	10534	8	10332	1	0	0%	23:59:54
11	aioe	11736	7	11385	0	0	0%	23:59:54
12	xmission	14819	6	11975	1	0	0%	23:59:54
13	news.mixmin.net	12157	5	11920	0	1	0%	23:59:53
14	news.swapon.de	1177	4	1165	0	0	0%	23:44:50
15	eternal-september	11783	4	11506	0	0	0%	23:59:39
16	news.uzoreto.com	13326	2	13165	10	0	0%	23:59:45
17	news.fcku.it	1151	0	1147	0	0	0%	20:20:32
18	news.redatomik.org	0	0	0	0	13261	0%	23:59:54
TOTAL: 18		211033	2958	185558	729	13325	1%	428:01:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.1 MB	0.0 KB	7.1 MB	0.1 KB/s	11.8 KB	23:59:52
2	news.freedyn.net	6.9 MB	37.2 KB	7.0 MB	0.1 KB/s	13.6 KB	23:59:46
3	fu-berlin.de	6.9 MB	0.0 KB	6.9 MB	0.1 KB/s	14.3 KB	23:59:41
4	news.samoylyk.net	6.8 MB	108.0 KB	6.9 MB	0.1 KB/s	13.8 KB	23:59:52
5	news.bbs.nz	1.8 MB	11.9 KB	1.8 MB	0.0 KB/s	2.7 KB	23:59:38
6	usenet.pasdenom.info	124.6 KB	6.9 MB	7.0 MB	0.1 KB/s	12.7 KB	23:59:54
7	newsfeed.xs4all.nl	119.7 KB	19.6 KB	139.3 KB	0.0 KB/s	6.1 KB	23:59:53
8	erje	93.5 KB	319.9 KB	413.4 KB	0.0 KB/s	2.8 KB	23:59:40
9	neva.ru	43.2 KB	113.2 KB	156.4 KB	0.0 KB/s	2.0 KB	23:59:11
10	weretis.net	20.8 KB	1.1 KB	22.0 KB	0.0 KB/s	2.4 KB	23:59:54
11	aioe	16.2 KB	0.0 KB	16.2 KB	0.0 KB/s	2.3 KB	23:59:54
12	xmission	13.8 KB	2.8 KB	16.6 KB	0.0 KB/s	2.4 KB	23:59:54
13	news.mixmin.net	11.8 KB	0.0 KB	11.8 KB	0.0 KB/s	2.4 KB	23:59:53
14	news.swapon.de	10.6 KB	0.0 KB	10.6 KB	0.0 KB/s	2.6 KB	23:44:50
15	eternal-september	10.6 KB	0.0 KB	10.6 KB	0.0 KB/s	2.6 KB	23:59:39
16	news.uzoreto.com	3.8 KB	44.4 KB	48.2 KB	0.0 KB/s	4.0 KB	23:59:45
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:20:32
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 18		30.0 MB	7.6 MB	37.5 MB	0.0 KB/s	10.4 KB	428:01:42

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	71-82-166-98.dhcp.mdsn.wi.charter.com	21	9	26.6 KB	7	4	3	06:48:01
2	a131033.upc-a.chello.nl	3	3	5.3 KB	2	0	0	00:22:49
TOTAL: 2		24	12	31.9 KB	9	4	3	09:32:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.mdsn.wi.charter.com	21	9	26.6 KB	7	4	3	06:48:01
2	*.upc-a.chello.nl	3	3	5.3 KB	2	0	0	00:22:49
3	*.ph.ph.cox.net	6	0	0.0 KB	0	0	0	02:21:07
4	unresolved	3	0	0.0 KB	0	0	0	00:00:13
TOTAL: 4		33	12	31.9 KB	9	4	3	09:32:12

NNRP auth users [Top 20]:

User	Conn
ohlandl	3
TOTAL: 1	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-82-166-98.dhcp.mdsn.wi.charter.com	1.825	0.293	0.008	06:48:01
ip72-222-200-117.ph.ph.cox.net	0.535	0.100	0.000	02:21:07
a131033.upc-a.chello.nl	0.297	0.048	0.000	00:22:49
159.203.197.12	0.065	0.016	0.000	00:00:09
122.228.19.80	0.072	0.008	0.000	00:00:03
170.106.38.8	0.073	0.008	0.000	00:00:00
TOTAL: 6	2.867	0.473	0.008	09:32:12

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	6
122.228.19.80	1
159.203.197.12	1
170.106.38.8	1
TOTAL: 4	9

NNRP unrecognized commands (by host):

System	Conn
122.228.19.80	5
170.106.38.8	2
TOTAL: 2	7

NNRP unrecognized commands (by command):

Command	Count
Accept: /	1
Connection: keep-alive	1
GET / HTTP/1.1	1
Host: 69.160.37.58:119	1
Host: 69.160.37.58	1
GET / HTTP/1.0	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 7	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-166-98.dhcp.mdsn.wi.charter.com	9	0
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	13	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	12	100.0%
TOTAL: 1		12	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	12
TOTAL: 1		12

Daily Usenet report for csiph.com

Sep 21 04:15:00 -- Sep 22 04:15:00