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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19195	37.9%	4.9 MB	54.9%
innd	17739	35.0%	2.3 MB	26.1%
innfeed	13168	26.0%	1.6 MB	18.3%
nnrpd	502	1.0%	56.6 KB	0.6%
nocem	26	0.1%	2.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	50631	100.0%	8.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	272833	56.9%
Negative hits	187795	39.2%
Do not exist	18563	3.9%
Cache misses	219	0.0%
TOTAL: 4	479410	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.258	0.0%	61	0.000	4.230	26.000
article cleanup	00:00:00.118	0.0%	17854	0.000	0.007	0.050
article logging	00:00:00.277	0.0%	18304	0.000	0.015	0.061
article parse	00:00:01.624	0.0%	71557	0.000	0.023	0.127
article write	00:00:04.873	0.0%	14898	0.141	0.327	4.615
artlog/artcncl	00:00:00.000	0.0%	44	0.000	0.000	0.000
artlog/artparse	00:00:00.012	0.0%	812	0.000	0.015	0.333
data move	00:00:00.125	0.0%	467438	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.048	0.0%	61	0.000	0.787	4.100
hishave/artcncl	00:00:00.006	0.0%	61	0.000	0.098	1.000
hishave/artparse	00:00:00.002	0.0%	809	0.000	0.002	0.040
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:09.080	0.0%	481235	0.001	0.019	0.234
history sync	00:00:00.743	0.0%	374	0.000	1.987	10.333
history write	00:00:16.648	0.0%	17857	0.065	0.932	8.423
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.958	0.0%	809	0.000	1.184	9.750
idle	23:58:58.577	99.9%	475325	110.458	181.641	630.148
nntp read	00:00:01.828	0.0%	474272	0.001	0.004	0.009
overview write	00:00:20.173	0.0%	14898	0.133	1.354	177.474
perl filter	00:00:29.366	0.0%	17853	1.199	1.645	2.500
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.243	0.0%	29820	0.000	0.008	0.040
TOTAL: 24:00:58.803	24:00:24.959	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	16393	0.000	0.003	0.024
article prepare	00:00:00.000	0.0%	13816	0.000	0.000	0.000
article read	00:00:00.262	0.0%	1097	0.000	0.239	22.000
backlog stats	00:00:00.166	0.0%	310029	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.133	0.0%	232898	0.000	0.005	0.013
data write	00:00:01.388	0.0%	220334	0.001	0.006	0.011
idle	23:54:46.161	100.0%	310029	160.213	277.671	787.609
status file	00:00:02.800	0.0%	4401	0.000	0.636	2.000
TOTAL: 23:54:59.810	23:54:51.957	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	18:29:43.727	100.0%	394	334.000	168994.231	600082.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.003	0.0%	896	0.000	0.003	0.167
readart	00:00:00.469	0.0%	37	0.000	12.676	47.000
TOTAL: 18:29:55.986	18:29:44.199	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	28893	2270	26455	168	7%	47:26:18
2	news.freedyn.net	3	17880	2261	14283	1336	12%	24:08:50
3	newsfeeds.xmission.com	2	12981	2051	10914	16	15%	21:02:15
4	feeder.eternal-september.org	3	16460	1845	14511	104	11%	25:00:12
5	csiph-out.feeder.erje.net	1439	73183	1288	71529	366	1%	118:53:48
6	csiph-out.news.xs4all.nl	861	16936	1277	14760	899	7%	71:26:37
7	news-out.aioe.org	3	14108	1242	12855	11	8%	24:02:54
8	newsfeed.fu-berlin.de	4	15296	864	14372	60	5%	26:35:35
9	news-out.mixmin.net	2	15363	767	14424	172	4%	23:59:26
10	newsfeed.neva.ru	150	17210	583	16177	450	3%	71:48:49
11	csiph-out.feed.uzoreto.com	864	23638	190	23182	266	0%	71:21:47
12	news.fcku.it	14	1373	103	1270	0	7%	22:20:23
13	nntp.pasdenom.info	1	9514	47	9458	9	0%	23:59:54
14	news.swapon.de	8	1332	12	1320	0	0%	23:08:27
15	news.samoylyk.net	3	14305	9	14285	11	0%	24:55:06
16	localhost	37	8	8	0	0	100%	00:00:05
17	news.bbs.nz	1	12827	4	12823	0	0%	22:59:51
18	xanadu-bbs.net	1	0	0	0	0	0%	00:10:17
TOTAL: 18		3399	291307	14821	272618	3868	5%	643:20:34

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	9.5 MB	12.2 MB	21.7 MB	43%	13.4 KB
2	csiph-out.news.weretis.net	6.9 MB	4.0 MB	10.9 MB	63%	4.6 KB
3	newsfeeds.xmission.com	6.0 MB	1001.2 KB	7.0 MB	85%	3.4 KB
4	news.freedyn.net	5.7 MB	50.6 MB	56.3 MB	10%	16.0 KB
5	feeder.eternal-september.org	5.1 MB	272.8 KB	5.3 MB	95%	2.8 KB
6	csiph-out.news.xs4all.nl	3.5 MB	10.7 MB	14.2 MB	24%	6.7 KB
7	news-out.aioe.org	2.7 MB	44.1 KB	2.7 MB	98%	2.2 KB
8	news-out.mixmin.net	2.5 MB	417.4 KB	2.9 MB	85%	3.1 KB
9	newsfeed.fu-berlin.de	2.0 MB	138.1 KB	2.1 MB	93%	2.3 KB
10	newsfeed.neva.ru	1.7 MB	1.5 MB	3.3 MB	53%	3.2 KB
11	csiph-out.feed.uzoreto.com	743.2 KB	5.4 MB	6.1 MB	11%	13.7 KB
12	news.fcku.it	194.5 KB	0.0 KB	194.5 KB	100%	1.9 KB
13	nntp.pasdenom.info	104.9 KB	15.8 KB	120.7 KB	86%	2.2 KB
14	news.samoylyk.net	29.9 KB	1.4 MB	1.4 MB	2%	71.6 KB
15	news.swapon.de	29.7 KB	0.0 KB	29.7 KB	100%	2.5 KB
16	localhost	10.6 KB	0.0 KB	10.6 KB	100%	1.3 KB
17	news.bbs.nz	8.1 KB	0.0 KB	8.1 KB	100%	2.0 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		46.5 MB	87.6 MB	134.2 MB	34%	7.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 29 04:15:00 - 04:59:59	180	1.2%	0.07	503.1 KB	1.0%	0.19
Sep 29 05:00:00 - 05:59:59	358	2.4%	0.10	1.1 MB	2.4%	0.32
Sep 29 06:00:00 - 06:59:59	481	3.2%	0.13	1.8 MB	3.7%	0.50
Sep 29 07:00:00 - 07:59:59	558	3.7%	0.15	2.2 MB	4.6%	0.62
Sep 29 08:00:00 - 08:59:59	649	4.4%	0.18	1.9 MB	4.0%	0.54
Sep 29 09:00:00 - 09:59:59	670	4.5%	0.19	1.9 MB	4.0%	0.53
Sep 29 10:00:00 - 10:59:59	783	5.3%	0.22	4.0 MB	8.4%	1.13
Sep 29 11:00:00 - 11:59:59	745	5.0%	0.21	3.1 MB	6.6%	0.89
Sep 29 12:00:00 - 12:59:59	743	5.0%	0.21	1.8 MB	3.9%	0.53
Sep 29 13:00:00 - 13:59:59	704	4.7%	0.20	1.8 MB	3.9%	0.52
Sep 29 14:00:00 - 14:59:59	797	5.3%	0.22	2.4 MB	5.1%	0.69
Sep 29 15:00:00 - 15:59:59	700	4.7%	0.19	1.9 MB	4.1%	0.55
Sep 29 16:00:00 - 16:59:59	838	5.6%	0.23	2.3 MB	4.9%	0.65
Sep 29 17:00:00 - 17:59:59	784	5.3%	0.22	2.1 MB	4.5%	0.60
Sep 29 18:00:00 - 18:59:59	799	5.4%	0.22	2.5 MB	5.3%	0.71
Sep 29 19:00:00 - 19:59:59	784	5.3%	0.22	2.1 MB	4.4%	0.59
Sep 29 20:00:00 - 20:59:59	887	5.9%	0.25	2.5 MB	5.2%	0.70
Sep 29 21:00:00 - 21:59:59	913	6.1%	0.25	2.8 MB	5.9%	0.79
Sep 29 22:00:00 - 22:59:59	600	4.0%	0.17	1.9 MB	4.1%	0.55
Sep 29 23:00:00 - 23:59:59	483	3.2%	0.13	1.3 MB	2.8%	0.38
Sep 30 00:00:00 - 00:59:59	356	2.4%	0.10	1.1 MB	2.2%	0.30
Sep 30 01:00:00 - 01:59:59	363	2.4%	0.10	1.1 MB	2.4%	0.32
Sep 30 02:00:00 - 02:59:59	356	2.4%	0.10	1.5 MB	3.2%	0.43
Sep 30 03:00:00 - 03:59:59	291	2.0%	0.08	1.0 MB	2.2%	0.30
Sep 30 04:00:00 - 04:15:00	92	0.6%	0.10	478.7 KB	1.0%	0.53
TOTAL: 24:00:00	14914	100.0%	0.17	47.1 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Other
1	news.freedyn.net	1262	11	1251
2	csiph-out.news.xs4all.nl	946	18	928
3	newsfeed.neva.ru	450	26	424
4	newsfeed.fu-berlin.de	428	16	412
5	csiph-out.feeder.erje.net	372	39	333
6	csiph-out.feed.uzoreto.com	266	7	259
7	csiph-out.news.weretis.net	191	69	122
8	news-out.mixmin.net	172	0	172
9	feeder.eternal-september.org	100	16	84
10	news-out.aioe.org	19	9	10
11	newsfeeds.xmission.com	17	2	15
12	nntp.pasdenom.info	9	0	9
13	news.swapon.de	4	0	4
14	news.samoylyk.net	2	0	2
TOTAL: 14		4238	213	4025

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.windows7.general	22
mozilla.support.seamonkey	21
alt.binaries.pictures.purebeauty	14
lada.talk	14
alt.survival	13
mozilla.support.firefox	12
alt.pl.nieruchomosci.gielda	11
alt.politics	7
alt.tv.star-trek	6
mozilla.general	6
alt.math.undergrad	5
oesterreich.tratsch	5
spline.fli4l	4
alt.religion	4
mozilla.support.thunderbird	4
alt.aol.hell	4
alt.binaries.ebooks	3
cakewalk.audio	3
spline.eisfair	2
alt.biz.accounting	2
TOTAL: 66	213

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2366
EMP (phn path)	102
Too many newsgroups	70
EMP (phn nph)	55
EMP (phl)	50
HTML Multipart	23
User-issued cancel	17
Scoring filter (8)	14
Scoring filter (13)	10
Scoring filter (10)	10
Binary Payload	9
Scoring filter (12)	8
Binary: misplaced rar	7
Scoring filter (9)	3
Scoring filter (20)	3
Scoring filter (14)	3
Too many newsgroups (meow)	2
Scoring filter (15)	2
Scoring filter (11)	1
Binary: misplaced par2	1
TOTAL: 24	2760

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	17	0	17	17
TOTAL: 1	17	7	17	17

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	351
	csiph-out.feeder.erje.net	226
	csiph-out.news.xs4all.nl	110
	csiph-out.news.weretis.net	54
	csiph-out.feed.uzoreto.com	51
	newsfeeds.xmission.com	14
	news-out.mixmin.net	2
	news.samoylyk.net	2
	feeder.eternal-september.org	1
	TOTAL: 9	811
Including strange strings
	newsfeed.fu-berlin.de	375
	csiph-out.news.xs4all.nl	42
	csiph-out.news.weretis.net	12
	news-out.aioe.org	8
	news.swapon.de	4
	csiph-out.feeder.erje.net	4
	news.freedyn.net	2
	news-out.mixmin.net	2
	newsfeed.neva.ru	1
	TOTAL: 9	450
No colon-space in header
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	3
TOTAL: 4		1309

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.samoylyk.net	15710	657	14242	6	96	4%	23:59:54
2	news.freedyn.net	13009	648	11931	23	0	4%	23:59:32
3	fu-berlin.de	13947	641	13306	0	0	4%	23:59:32
4	news.bbs.nz	27665	356	13895	6	0	1%	23:59:54
5	usenet.pasdenom.info	15303	122	14073	671	0	0%	23:59:42
6	newsfeed.xs4all.nl	14125	51	13088	9	32	0%	23:59:53
7	neva.ru	13986	50	13347	144	0	0%	23:59:54
8	aioe	12889	43	12291	0	0	0%	23:59:54
9	weretis.net	12039	13	11784	6	0	0%	23:59:33
10	xmission	15910	12	12027	1	0	0%	23:59:52
11	erje	14056	12	13544	4	0	0%	23:59:54
12	news.mixmin.net	13881	9	13324	0	0	0%	23:59:39
13	news.swapon.de	1511	8	1462	0	0	0%	23:54:33
14	eternal-september	12936	8	12355	0	0	0%	23:59:46
15	news.uzoreto.com	14598	6	14241	14	0	0%	23:59:53
16	news.redatomik.org	0	0	0	0	14259	0%	23:59:54
17	news.fcku.it	1247	0	1240	0	0	0%	21:41:32
18	xanadu-bbs	1399	0	0	0	16313	0%	23:58:43
TOTAL: 18		214211	2636	186150	884	30700	1%	429:31:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.8 MB	37.1 KB	7.8 MB	0.1 KB/s	11.9 KB	23:59:32
2	news.samoylyk.net	7.8 MB	6.9 KB	7.8 MB	0.1 KB/s	12.0 KB	23:59:54
3	fu-berlin.de	7.7 MB	0.0 KB	7.7 MB	0.1 KB/s	12.3 KB	23:59:32
4	news.bbs.nz	1.0 MB	16.0 KB	1.0 MB	0.0 KB/s	2.9 KB	23:59:54
5	usenet.pasdenom.info	403.9 KB	7.8 MB	8.2 MB	0.1 KB/s	10.6 KB	23:59:42
6	newsfeed.xs4all.nl	192.8 KB	63.8 KB	256.6 KB	0.0 KB/s	4.3 KB	23:59:53
7	aioe	148.9 KB	0.0 KB	148.9 KB	0.0 KB/s	3.5 KB	23:59:54
8	neva.ru	140.4 KB	489.8 KB	630.2 KB	0.0 KB/s	3.2 KB	23:59:54
9	erje	48.7 KB	13.7 KB	62.4 KB	0.0 KB/s	3.9 KB	23:59:54
10	news.uzoreto.com	26.5 KB	113.8 KB	140.2 KB	0.0 KB/s	7.0 KB	23:59:53
11	xmission	22.2 KB	2.9 KB	25.1 KB	0.0 KB/s	1.9 KB	23:59:52
12	weretis.net	20.5 KB	10.1 KB	30.6 KB	0.0 KB/s	1.6 KB	23:59:33
13	news.mixmin.net	12.7 KB	0.0 KB	12.7 KB	0.0 KB/s	1.4 KB	23:59:39
14	news.swapon.de	10.6 KB	0.0 KB	10.6 KB	0.0 KB/s	1.3 KB	23:54:33
15	eternal-september	10.6 KB	0.0 KB	10.6 KB	0.0 KB/s	1.3 KB	23:59:46
16	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:41:32
18	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:43
TOTAL: 18		25.3 MB	8.5 MB	33.8 MB	0.0 KB/s	9.8 KB	429:31:34

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	66-188-82-154.dhcp.mdsn.wi.charter.com	25	17	27.2 KB	10	7	7	08:28:13
2	a131033.upc-a.chello.nl	27	16	24.6 KB	4	0	0	07:08:45
3	ip72-222-200-117.ph.ph.cox.net	12	4	5.5 KB	4	1	1	02:52:55
TOTAL: 3		64	37	57.4 KB	18	8	8	18:29:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.mdsn.wi.charter.com	25	17	27.2 KB	10	7	7	08:28:13
2	*.upc-a.chello.nl	27	16	24.6 KB	4	0	0	07:08:45
3	*.ph.ph.cox.net	12	4	5.5 KB	4	1	1	02:52:55
4	unresolved	1	0	0.0 KB	0	0	0	00:00:01
TOTAL: 4		65	37	57.4 KB	18	8	8	18:29:55

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
kev009	1
TOTAL: 2	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
66-188-82-154.dhcp.mdsn.wi.charter.com	2.192	0.351	0.014	08:28:13
a131033.upc-a.chello.nl	2.066	0.328	0.000	07:08:45
ip72-222-200-117.ph.ph.cox.net	1.218	0.156	0.003	02:52:55
94.162.147.221	0.072	0.008	0.000	00:00:01
TOTAL: 4	5.548	0.843	0.017	18:29:55

Curious NNRP server explorers [Top 20]:

System	Conn
94.162.147.221	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
66-188-82-154.dhcp.mdsn.wi.charter.com	12	0
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	16	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 29 04:15:00 -- Sep 30 04:15:00