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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22415	49.3%	5.6 MB	64.9%
innd	19064	41.9%	2.4 MB	27.8%
innfeed	3375	7.4%	570.5 KB	6.5%
nnrpd	562	1.2%	62.8 KB	0.7%
nocem	56	0.1%	6.3 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	45473	100.0%	8.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	309165	56.4%
Negative hits	216390	39.5%
Do not exist	21829	4.0%
Cache misses	392	0.1%
TOTAL: 4	547776	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.507	0.0%	130	0.000	3.900	31.000
article cleanup	00:00:00.107	0.0%	20517	0.000	0.005	0.037
article logging	00:00:00.414	0.0%	20974	0.000	0.020	0.301
article parse	00:00:03.633	0.0%	96723	0.000	0.038	0.530
article write	00:00:41.176	0.0%	16653	0.340	2.473	67.788
artlog/artcncl	00:00:00.002	0.0%	105	0.000	0.019	0.167
artlog/artparse	00:00:00.030	0.0%	1397	0.000	0.021	0.500
data move	00:00:00.164	0.0%	545461	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.031	0.0%	121	0.000	0.256	18.000
hishave/artcncl	00:00:00.006	0.0%	130	0.000	0.046	0.500
hishave/artparse	00:00:00.001	0.0%	1395	0.000	0.001	0.029
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:22.126	0.0%	548419	0.002	0.040	0.180
history sync	00:00:00.880	0.0%	373	0.000	2.359	27.000
history write	00:00:30.403	0.0%	20513	0.204	1.482	5.854
hiswrite/artcncl	00:00:00.004	0.0%	9	0.000	0.444	1.000
hiswrite/artparse	00:00:02.678	0.0%	1390	0.000	1.927	39.500
idle	23:56:43.124	99.7%	547197	87.457	157.536	684.033
nntp read	00:00:02.465	0.0%	550078	0.001	0.004	0.039
overview write	00:01:16.889	0.1%	16653	0.172	4.617	136.636
perl filter	00:00:34.402	0.0%	20511	1.167	1.677	2.765
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.300	0.0%	33357	0.000	0.009	0.052
TOTAL: 24:00:57.762	24:00:19.342	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.050	0.0%	16839	0.000	0.003	0.048
article prepare	00:00:00.001	0.0%	17296	0.000	0.000	0.004
article read	00:00:02.602	0.0%	1239	0.000	2.100	130.667
backlog stats	00:00:00.212	0.0%	356510	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.505	0.0%	275373	0.001	0.005	0.063
data write	00:00:01.643	0.0%	259787	0.001	0.006	0.012
idle	23:54:11.059	100.0%	356510	129.732	241.371	995.907
status file	00:00:00.440	0.0%	282	0.000	1.560	17.750
TOTAL: 23:54:27.297	23:54:17.512	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	23:19:50.703	100.0%	394	102.000	213174.373	600164.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	892	0.000	0.007	0.500
readart	00:00:00.301	0.0%	13	0.000	23.154	48.000
TOTAL: 23:20:10.306	23:19:51.010	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	33684	2215	31071	398	6%	48:16:27
2	newsfeeds.xmission.com	2	15626	2065	13553	8	13%	23:34:35
3	feeder.eternal-september.org	2	18137	2002	16042	93	11%	22:39:04
4	news.freedyn.net	2	21110	1892	17274	1944	8%	24:09:02
5	csiph-out.feeder.erje.net	1440	80090	1691	78049	350	2%	118:41:53
6	news-out.aioe.org	3	16124	1440	14669	15	8%	24:09:02
7	csiph-out.news.xs4all.nl	861	18911	1327	16346	1238	7%	71:25:36
8	news-out.mixmin.net	3	17275	1184	15877	214	6%	24:08:48
9	newsfeed.neva.ru	139	19627	1073	17930	624	5%	71:25:54
10	newsfeed.fu-berlin.de	9	13187	745	12409	33	5%	21:36:01
11	news.fcku.it	12	2213	404	1806	3	18%	22:09:41
12	csiph-out.feed.uzoreto.com	864	26158	270	25474	414	1%	71:06:31
13	nntp.pasdenom.info	4	11602	44	11550	8	0%	23:51:25
14	news.swapon.de	6	1469	15	1452	2	1%	24:12:23
15	news.samoylyk.net	4	16099	13	16085	1	0%	24:54:56
16	news.bbs.nz	1	16014	10	16004	0	0%	23:59:50
17	localhost	34	2	2	0	0	100%	00:00:07
18	xanadu-bbs.net	20	552	0	551	1	0%	23:06:29
TOTAL: 18		3411	327880	16392	306142	5346	4%	663:27:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	11.6 MB	0.0 KB	11.9 MB	23.5 MB	49%	11.8 KB
2	csiph-out.news.weretis.net	6.8 MB	35.0 KB	5.6 MB	12.4 MB	54%	4.9 KB
3	newsfeeds.xmission.com	5.9 MB	0.0 KB	538.7 KB	6.5 MB	91%	3.2 KB
4	feeder.eternal-september.org	5.3 MB	1.7 KB	393.5 KB	5.7 MB	93%	2.8 KB
5	news.freedyn.net	5.1 MB	0.0 KB	116.8 MB	121.9 MB	4%	32.5 KB
6	csiph-out.news.xs4all.nl	3.9 MB	5.5 KB	8.4 MB	12.3 MB	31%	4.9 KB
7	news-out.mixmin.net	3.5 MB	0.0 KB	858.7 KB	4.4 MB	80%	3.2 KB
8	news-out.aioe.org	3.4 MB	26.6 KB	60.8 KB	3.5 MB	97%	2.5 KB
9	newsfeed.neva.ru	2.3 MB	0.0 KB	1.9 MB	4.2 MB	54%	2.6 KB
10	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	101.1 KB	2.0 MB	94%	2.6 KB
11	csiph-out.feed.uzoreto.com	1006.1 KB	0.0 KB	5.2 MB	6.2 MB	15%	9.3 KB
12	news.fcku.it	726.6 KB	0.0 KB	2.8 KB	729.3 KB	99%	1.8 KB
13	nntp.pasdenom.info	96.5 KB	0.0 KB	12.4 KB	108.8 KB	88%	2.1 KB
14	news.samoylyk.net	43.2 KB	0.0 KB	116.3 KB	159.5 KB	27%	11.4 KB
15	news.swapon.de	33.6 KB	0.0 KB	97.1 KB	130.7 KB	25%	7.7 KB
16	news.bbs.nz	25.9 KB	0.0 KB	0.0 KB	25.9 KB	100%	2.6 KB
17	localhost	2.2 KB	0.0 KB	0.0 KB	2.2 KB	100%	1.1 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	96.4 KB	96.4 KB	0%	96.4 KB
TOTAL: 18		51.5 MB	68.8 KB	152.1 MB	203.7 MB	25%	9.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 25 04:15:00 - 04:59:59	190	1.1%	0.07	760.2 KB	1.4%	0.28
Sep 25 05:00:00 - 05:59:59	458	2.8%	0.13	2.0 MB	3.8%	0.56
Sep 25 06:00:00 - 06:59:59	485	2.9%	0.13	1.7 MB	3.2%	0.47
Sep 25 07:00:00 - 07:59:59	733	4.4%	0.20	2.2 MB	4.1%	0.61
Sep 25 08:00:00 - 08:59:59	960	5.8%	0.27	2.2 MB	4.3%	0.64
Sep 25 09:00:00 - 09:59:59	904	5.4%	0.25	2.5 MB	4.8%	0.72
Sep 25 10:00:00 - 10:59:59	773	4.7%	0.21	2.4 MB	4.7%	0.69
Sep 25 11:00:00 - 11:59:59	764	4.6%	0.21	2.2 MB	4.3%	0.64
Sep 25 12:00:00 - 12:59:59	907	5.5%	0.25	2.2 MB	4.2%	0.62
Sep 25 13:00:00 - 13:59:59	890	5.4%	0.25	2.3 MB	4.4%	0.65
Sep 25 14:00:00 - 14:59:59	966	5.8%	0.27	3.3 MB	6.3%	0.93
Sep 25 15:00:00 - 15:59:59	1117	6.7%	0.31	5.2 MB	9.9%	1.47
Sep 25 16:00:00 - 16:59:59	910	5.5%	0.25	2.8 MB	5.4%	0.80
Sep 25 17:00:00 - 17:59:59	875	5.3%	0.24	2.2 MB	4.3%	0.63
Sep 25 18:00:00 - 18:59:59	804	4.8%	0.22	2.4 MB	4.6%	0.69
Sep 25 19:00:00 - 19:59:59	887	5.3%	0.25	2.5 MB	4.7%	0.70
Sep 25 20:00:00 - 20:59:59	780	4.7%	0.22	2.3 MB	4.4%	0.65
Sep 25 21:00:00 - 21:59:59	689	4.1%	0.19	2.1 MB	4.0%	0.59
Sep 25 22:00:00 - 22:59:59	675	4.1%	0.19	3.0 MB	5.7%	0.84
Sep 25 23:00:00 - 23:59:59	432	2.6%	0.12	1.3 MB	2.6%	0.38
Sep 26 00:00:00 - 00:59:59	371	2.2%	0.10	1.0 MB	1.9%	0.29
Sep 26 01:00:00 - 01:59:59	353	2.1%	0.10	1.1 MB	2.0%	0.30
Sep 26 02:00:00 - 02:59:59	308	1.9%	0.09	1.2 MB	2.3%	0.34
Sep 26 03:00:00 - 03:59:59	320	1.9%	0.09	1.1 MB	2.0%	0.30
Sep 26 04:00:00 - 04:15:00	70	0.4%	0.08	315.0 KB	0.6%	0.35
TOTAL: 24:00:00	16621	100.0%	0.19	52.0 MB	100.0%	0.62

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.freedyn.net	1837	32	0	1805
2	csiph-out.news.xs4all.nl	1257	30	1	1226
3	newsfeed.neva.ru	634	84	0	550
4	newsfeed.fu-berlin.de	424	18	0	406
5	csiph-out.feed.uzoreto.com	418	13	0	405
6	csiph-out.news.weretis.net	412	126	2	284
7	csiph-out.feeder.erje.net	357	56	0	301
8	news-out.mixmin.net	217	1	0	216
9	feeder.eternal-september.org	94	11	1	82
10	news-out.aioe.org	16	12	2	2
11	newsfeeds.xmission.com	10	0	0	10
12	nntp.pasdenom.info	7	0	0	7
13	news.swapon.de	3	0	0	3
14	news.fcku.it	3	2	0	1
15	xanadu-bbs.net	1	0	0	1
TOTAL: 15		5690	385	6	5299

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	68
alt.bbs.synchronet	39
it-alt.sport.calcio.juventus	21
alt.music.pink-floyd.publius	21
alt.binaries.pictures.purebeauty	18
mozilla.support.firefox	15
mozilla.support.seamonkey	11
mozilla.general	10
spline.eisfair	10
mozilla.support.thunderbird	10
alt.survival	9
alt.windows7.general	8
alt.religion	8
oesterreich.tratsch	7
alt.binaries.mp3.abooks	7
lada.auto	6
mozilla.support.bugzilla	6
mozilla.dev.platform	6
mozilla.support.calendar	5
it-alt.comp.software.mozilla	5
TOTAL: 74	385

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3036
EMP (phn path)	154
EMP (phn nph)	77
Too many newsgroups	72
EMP (phl)	28
Binary: misplaced rar	25
HTML Multipart	18
User-issued cancel	11
Scoring filter (8)	9
Binary Payload	8
Scoring filter (10)	7
User-issued spam cancel	4
Scoring filter (9)	2
HTML post	2
Too many newsgroups (meow)	2
HTML Image Tags	1
Binary Image: misplaced jpg	1
TOTAL: 17	3457

NoCeM on Spool:

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

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	882
	csiph-out.feeder.erje.net	213
	csiph-out.news.xs4all.nl	123
	csiph-out.feed.uzoreto.com	81
	csiph-out.news.weretis.net	63
	news-out.mixmin.net	8
	newsfeeds.xmission.com	7
	news.swapon.de	2
	feeder.eternal-september.org	2
	newsfeed.fu-berlin.de	2
	TOTAL: 11	1384
Including strange strings
	newsfeed.fu-berlin.de	387
	csiph-out.news.xs4all.nl	17
	csiph-out.news.weretis.net	16
	csiph-out.feeder.erje.net	12
	news-out.mixmin.net	10
	csiph-out.feed.uzoreto.com	8
	newsfeeds.xmission.com	2
	news-out.aioe.org	1
	news.swapon.de	1
	feeder.eternal-september.org	1
	TOTAL: 11	456
No colon-space in header
	csiph-out.feeder.erje.net	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	2
TOTAL: 4		1887

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19873	977	15644	0	0	4%	23:59:48
2	news.samoylyk.net	17452	623	15982	3	77	3%	23:59:53
3	news.freedyn.net	15047	613	14066	17	0	4%	23:59:53
4	fu-berlin.de	15628	602	15026	0	0	3%	23:59:51
5	news.bbs.nz	30789	269	15723	5	0	0%	23:59:53
6	usenet.pasdenom.info	16990	131	15817	629	124	0%	23:59:53
7	newsfeed.xs4all.nl	15671	48	14627	5	44	0%	23:59:52
8	aioe	14453	33	13866	0	0	0%	23:59:53
9	neva.ru	14950	17	14532	164	0	0%	23:59:46
10	news.uzoreto.com	15985	15	15653	95	0	0%	23:59:53
11	news.mixmin.net	15115	9	14672	0	0	0%	23:59:53
12	erje	15025	7	14779	44	0	0%	23:59:06
13	eternal-september	14296	4	13810	0	0	0%	23:59:53
14	news.swapon.de	1569	4	1534	0	0	0%	23:47:45
15	xmission	17723	3	13839	1	0	0%	23:59:38
16	weretis.net	14144	3	13644	3	3	0%	23:59:53
17	news.fcku.it	1642	0	1636	0	0	0%	20:53:47
18	news.redatomik.org	0	0	0	0	16006	0%	23:59:53
TOTAL: 18		256352	3358	224850	966	16254	1%	428:38:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.3 MB	0.0 KB	9.3 MB	0.1 KB/s	9.7 KB	23:59:48
2	news.freedyn.net	8.4 MB	21.7 KB	8.5 MB	0.1 KB/s	13.8 KB	23:59:53
3	news.samoylyk.net	8.3 MB	40.6 KB	8.4 MB	0.1 KB/s	13.7 KB	23:59:53
4	fu-berlin.de	8.3 MB	0.0 KB	8.3 MB	0.1 KB/s	14.1 KB	23:59:51
5	news.bbs.nz	782.5 KB	20.2 KB	802.8 KB	0.0 KB/s	2.9 KB	23:59:53
6	usenet.pasdenom.info	389.2 KB	8.4 MB	8.8 MB	0.1 KB/s	11.8 KB	23:59:53
7	newsfeed.xs4all.nl	167.0 KB	44.1 KB	211.0 KB	0.0 KB/s	4.0 KB	23:59:52
8	neva.ru	76.2 KB	401.6 KB	477.9 KB	0.0 KB/s	2.6 KB	23:59:46
9	aioe	75.2 KB	0.0 KB	75.2 KB	0.0 KB/s	2.3 KB	23:59:53
10	news.uzoreto.com	67.9 KB	300.0 KB	368.0 KB	0.0 KB/s	3.3 KB	23:59:53
11	erje	17.8 KB	103.6 KB	121.4 KB	0.0 KB/s	2.4 KB	23:59:06
12	news.mixmin.net	13.0 KB	0.0 KB	13.0 KB	0.0 KB/s	1.4 KB	23:59:53
13	xmission	10.9 KB	8.1 KB	19.0 KB	0.0 KB/s	4.8 KB	23:59:38
14	eternal-september	10.6 KB	0.0 KB	10.6 KB	0.0 KB/s	2.6 KB	23:59:53
15	news.swapon.de	10.5 KB	0.0 KB	10.5 KB	0.0 KB/s	2.6 KB	23:47:45
16	weretis.net	3.7 KB	9.3 KB	13.1 KB	0.0 KB/s	2.2 KB	23:59:53
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:53:47
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 18		35.9 MB	9.3 MB	45.2 MB	0.0 KB/s	10.7 KB	428:38:23

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	41	6	8.2 KB	5	0	0	11:10:37
2	66-188-82-154.dhcp.mdsn.wi.charter.com	25	5	7.2 KB	4	2	2	08:12:14
3	ip72-222-200-117.ph.ph.cox.net	10	2	2.0 KB	1	0	0	03:57:00
TOTAL: 3		76	13	17.4 KB	10	2	2	23:20:07

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	41	6	8.2 KB	5	0	0	11:10:37
2	*.dhcp.mdsn.wi.charter.com	25	5	7.2 KB	4	2	2	08:12:14
3	*.ph.ph.cox.net	10	2	2.0 KB	1	0	0	03:57:00
4	unresolved	6	0	0.0 KB	0	0	0	00:00:15
TOTAL: 4		82	13	17.4 KB	10	2	2	23:20:07

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.146	0.591	0.000	11:10:37
66-188-82-154.dhcp.mdsn.wi.charter.com	1.964	0.388	0.005	08:12:14
ip72-222-200-117.ph.ph.cox.net	0.980	0.138	0.000	03:57:00
159.203.201.178	0.073	0.008	0.000	00:00:09
94.167.188.104	0.134	0.024	0.000	00:00:02
94.167.245.5	0.075	0.008	0.000	00:00:01
94.167.143.202	0.072	0.008	0.000	00:00:01
122.228.19.80	0.071	0.008	0.000	00:00:00
TOTAL: 8	6.515	1.173	0.005	23:20:07

Curious NNRP server explorers [Top 20]:

System	Conn
94.167.188.104	2
94.167.143.202	1
94.167.245.5	1
159.203.201.178	1
122.228.19.80	1
TOTAL: 5	6

NNRP unrecognized commands (by host):

System	Conn
122.228.19.80	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 25 04:15:00 -- Sep 26 04:15:00