Daily Usenet report for csiph.com: Nov 29 08:36:14

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	16066	48.9%	4.2 MB	64.1%
innd	13407	40.8%	1.7 MB	26.9%
innfeed	2680	8.2%	516.6 KB	7.8%
nnrpd	699	2.1%	76.9 KB	1.2%
nocem	20	0.1%	2.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	32873	100.0%	6.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	256887	58.1%
Negative hits	169318	38.3%
Do not exist	15686	3.5%
Cache misses	235	0.1%
TOTAL: 4	442126	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.091	0.0%	9	0.000	10.111	65.000
article cleanup	00:00:00.088	0.0%	16019	0.000	0.005	0.029
article logging	00:00:00.230	0.0%	16382	0.000	0.014	0.042
article parse	00:00:00.606	0.0%	48938	0.000	0.012	0.050
article write	00:01:43.676	0.1%	13787	0.322	7.520	52.957
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artparse	00:00:00.004	0.0%	379	0.000	0.011	0.333
data move	00:00:00.139	0.0%	435056	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hishave/artparse	00:00:00.000	0.0%	374	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:22.028	0.0%	466464	0.002	0.047	0.708
history sync	00:00:00.461	0.0%	315	0.000	1.463	3.500
history write	00:00:11.817	0.0%	16023	0.167	0.738	7.193
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.162	0.0%	374	0.000	0.433	2.000
idle	19:35:50.721	99.6%	440019	21.635	160.336	461.111
nntp read	00:00:01.668	0.0%	443593	0.001	0.004	0.006
overview write	00:01:51.410	0.2%	13787	0.127	8.081	921.385
perl filter	00:00:22.016	0.0%	15361	1.184	1.433	1.868
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.266	0.0%	27593	0.000	0.010	0.029
TOTAL: 19:40:53.579	19:40:25.383	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	13843	0.000	0.003	0.026
article prepare	00:00:00.002	0.0%	17424	0.000	0.000	0.003
article read	00:00:00.474	0.0%	1672	0.000	0.283	15.800
backlog stats	00:00:00.175	0.0%	316153	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.193	0.0%	243920	0.000	0.005	0.009
data write	00:00:01.381	0.0%	232797	0.003	0.006	0.013
idle	19:32:58.461	100.0%	316153	130.387	222.609	596.487
status file	00:00:00.135	0.0%	193	0.000	0.699	6.000
TOTAL: 19:33:05.640	19:33:01.856	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	23:54:41.087	99.8%	671	328.333	128287.760	600111.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:16.257	0.0%	200393	0.000	0.081	406.556
readart	00:00:00.917	0.0%	107	0.000	8.570	60.000
TOTAL: 23:57:17.081	23:54:58.261	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	4
flushlogs	2
go	2
logmode	2
lowmark	1
mode	98
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	117

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	12238	2581	9622	35	21%	17:47:03
2	csiph-out.feeder.erje.net	1178	63732	2036	61468	228	3%	97:18:21
3	news-out.aioe.org	3	12681	1946	10706	29	15%	18:58:44
4	feeder.eternal-september.org	0	14429	1725	12607	97	11%	19:27:34
5	csiph-out.news.xs4all.nl	702	14422	1002	12768	652	6%	58:13:43
6	news.freedyn.net	2	14959	958	13611	390	6%	19:00:01
7	newsfeed.neva.ru	115	15202	839	14200	163	5%	58:19:31
8	news-out.mixmin.net	1	13560	655	12825	80	4%	18:35:58
9	newsfeed.fu-berlin.de	4	8466	580	7866	20	6%	11:43:57
10	csiph-out.news.weretis.net	2	13736	446	13261	29	3%	19:41:53
11	news.fcku.it	9	1510	233	1275	2	15%	18:31:56
12	csiph-out.feed.uzoreto.com	706	20438	136	20126	176	0%	58:03:09
13	nntp.pasdenom.info	3	8690	76	8600	14	0%	18:52:27
14	news.samoylyk.net	2	12783	63	12716	4	0%	18:52:36
15	localhost	47	23	23	0	0	100%	00:00:07
16	news.swapon.de	6	1229	15	1213	1	1%	19:11:25
17	news.bbs.nz	1	1805	10	1795	0	0%	02:34:04
18	csiph-out.news.tnetconsulting.net	1	1358	3	1354	1	0%	01:51:00
19	xanadu-bbs.net	32	288	1	287	0	0%	17:23:32
TOTAL: 19		2816	231549	13328	216300	1921	5%	494:27:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	9.7 MB	0.0 KB	7.6 MB	17.3 MB	56%	7.8 KB
2	newsfeeds.xmission.com	7.8 MB	0.0 KB	1.6 MB	9.5 MB	82%	3.7 KB
3	news-out.aioe.org	5.0 MB	0.0 KB	49.7 KB	5.1 MB	99%	2.6 KB
4	feeder.eternal-september.org	4.8 MB	1.5 KB	2.0 MB	6.7 MB	70%	3.8 KB
5	csiph-out.news.xs4all.nl	3.0 MB	0.0 KB	8.6 MB	11.6 MB	26%	7.2 KB
6	news.freedyn.net	2.4 MB	0.0 KB	1.7 MB	4.1 MB	59%	3.1 KB
7	newsfeed.neva.ru	2.0 MB	0.0 KB	801.2 KB	2.7 MB	71%	2.8 KB
8	news-out.mixmin.net	1.8 MB	0.0 KB	289.7 KB	2.1 MB	86%	3.0 KB
9	csiph-out.news.weretis.net	1.5 MB	0.0 KB	612.6 KB	2.1 MB	71%	4.6 KB
10	newsfeed.fu-berlin.de	1.3 MB	0.0 KB	42.5 KB	1.3 MB	96%	2.2 KB
11	csiph-out.feed.uzoreto.com	502.7 KB	0.0 KB	4.3 MB	4.8 MB	10%	15.6 KB
12	news.fcku.it	414.7 KB	0.0 KB	2.0 KB	416.7 KB	99%	1.8 KB
13	nntp.pasdenom.info	179.4 KB	0.0 KB	17.6 KB	197.0 KB	91%	2.2 KB
14	news.samoylyk.net	175.1 KB	0.0 KB	40.5 KB	215.7 KB	81%	3.2 KB
15	news.swapon.de	41.1 KB	0.0 KB	88.0 KB	129.1 KB	31%	8.1 KB
16	localhost	29.9 KB	0.0 KB	0.0 KB	29.9 KB	100%	1.3 KB
17	news.bbs.nz	20.9 KB	0.0 KB	0.0 KB	20.9 KB	100%	2.1 KB
18	xanadu-bbs.net	16.6 KB	0.0 KB	0.0 KB	16.6 KB	100%	16.6 KB
19	csiph-out.news.tnetconsulting.net	4.9 KB	0.0 KB	1.8 KB	6.6 KB	73%	1.7 KB
TOTAL: 19		40.8 MB	1.5 KB	27.6 MB	68.4 MB	59%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 29 08:36:14 - 08:59:59	266	1.9%	0.19	726.0 KB	1.7%	0.51
Nov 29 09:00:00 - 09:59:59	794	5.8%	0.22	2.8 MB	6.6%	0.79
Nov 29 10:00:00 - 10:59:59	755	5.5%	0.21	1.9 MB	4.5%	0.53
Nov 29 11:00:00 - 11:59:59	768	5.6%	0.21	2.3 MB	5.4%	0.64
Nov 29 12:00:00 - 12:59:59	698	5.1%	0.19	1.9 MB	4.6%	0.55
Nov 29 13:00:00 - 13:59:59	874	6.4%	0.24	2.6 MB	6.3%	0.75
Nov 29 14:00:00 - 14:59:59	910	6.6%	0.25	2.4 MB	5.8%	0.69
Nov 29 15:00:00 - 15:59:59	950	6.9%	0.26	3.1 MB	7.5%	0.89
Nov 29 16:00:00 - 16:59:59	861	6.3%	0.24	2.6 MB	6.1%	0.73
Nov 29 17:00:00 - 17:59:59	843	6.1%	0.23	2.2 MB	5.4%	0.64
Nov 29 18:00:00 - 18:59:59	884	6.4%	0.25	2.7 MB	6.4%	0.76
Nov 29 19:00:00 - 19:59:59	743	5.4%	0.21	2.5 MB	5.9%	0.70
Nov 29 20:00:00 - 20:59:59	712	5.2%	0.20	2.0 MB	4.7%	0.56
Nov 29 21:00:00 - 21:59:59	735	5.3%	0.20	2.4 MB	5.7%	0.68
Nov 29 22:00:00 - 22:59:59	688	5.0%	0.19	2.2 MB	5.2%	0.62
Nov 29 23:00:00 - 23:59:59	673	4.9%	0.19	2.3 MB	5.6%	0.66
Nov 30 00:00:00 - 00:59:59	437	3.2%	0.12	1.1 MB	2.7%	0.32
Nov 30 01:00:00 - 01:59:59	340	2.5%	0.09	1.1 MB	2.7%	0.33
Nov 30 02:00:00 - 02:59:59	359	2.6%	0.10	1.3 MB	3.0%	0.36
Nov 30 03:00:00 - 03:59:59	367	2.7%	0.10	1.6 MB	3.8%	0.45
Nov 30 04:00:00 - 04:15:00	84	0.6%	0.09	286.1 KB	0.7%	0.32
TOTAL: 19:38:46	13741	100.0%	0.19	42.0 MB	100.0%	0.61

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	665	31	0	634
2	news.freedyn.net	392	4	0	388
3	newsfeed.fu-berlin.de	326	22	0	304
4	csiph-out.feeder.erje.net	231	53	0	178
5	csiph-out.feed.uzoreto.com	176	7	0	169
6	newsfeed.neva.ru	164	34	0	130
7	feeder.eternal-september.org	101	21	1	79
8	news-out.mixmin.net	85	1	0	84
9	news-out.aioe.org	56	16	0	40
10	csiph-out.news.weretis.net	51	32	0	19
11	newsfeeds.xmission.com	43	2	0	41
12	nntp.pasdenom.info	14	0	0	14
13	csiph-out.news.tnetconsulting.net	8	8	0	0
14	news.fcku.it	2	1	0	1
15	news.swapon.de	2	0	0	2
16	news.samoylyk.net	1	1	0	0
TOTAL: 16		2317	233	1	2083

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mozilla.support.seamonkey	30
lada.talk	20
alt.survival	20
mozilla.support.thunderbird	12
alt.windows7.general	11
alt.binaries.ctb	10
it-alt.comp.software.openoffice	9
alt.binaries.pictures.purebeauty	7
it-alt.sport.calcio.juventus	7
spline.eisfair	6
alt.magick.folk	5
alt.politics	5
at.jobs	4
alt.tv.star-trek	4
alt.binaries.ebooks	4
spline.eisfair.dev	4
oesterreich.tratsch	4
al.christnet.bible	3
spline.fli4l.dev	3
rocksolid.shared.test	3
TOTAL: 64	233

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1250
0	45
Too many newsgroups	16
Scoring filter	10
EMP (phl)	10
HTML Multipart	4
Too many newsgroups (low_xpost)	4
Binary: misplaced binary	2
Too many test groups in crosspost	1
TOTAL: 9	1342

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	138
	csiph-out.news.xs4all.nl	94
	csiph-out.feed.uzoreto.com	56
	newsfeeds.xmission.com	38
	feeder.eternal-september.org	22
	news.freedyn.net	18
	csiph-out.news.weretis.net	6
	news-out.mixmin.net	1
	news.swapon.de	1
	TOTAL: 9	374
Including strange strings
	newsfeed.fu-berlin.de	300
	news-out.aioe.org	28
	csiph-out.news.xs4all.nl	13
	csiph-out.news.weretis.net	10
	news-out.mixmin.net	5
	csiph-out.feeder.erje.net	2
	newsfeeds.xmission.com	2
	news.freedyn.net	1
	news.swapon.de	1
	TOTAL: 9	362
No colon-space in header
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	3
TOTAL: 4		784

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17043	1420	12277	2	0	8%	19:30:05
2	news.bbs.nz	25898	398	12944	29	0	1%	19:30:05
3	news.freedyn.net	13047	366	12362	8	0	2%	19:30:05
4	news.samoylyk.net	14174	341	13353	5	61	2%	19:32:00
5	fu-berlin.de	12830	338	12492	0	0	2%	19:30:05
6	tnet	15553	221	13159	0	0	1%	19:30:05
7	usenet.pasdenom.info	14095	178	13135	350	21	1%	19:38:43
8	neva.ru	12500	48	12151	182	0	0%	19:30:05
9	newsfeed.xs4all.nl	13004	46	12550	5	42	0%	19:35:47
10	news.uzoreto.com	13328	32	13002	187	0	0%	19:30:04
11	aioe	11124	30	10688	0	0	0%	19:30:05
12	erje	12205	29	11552	3	0	0%	19:30:05
13	news.mixmin.net	13002	28	12543	0	0	0%	19:30:05
14	xmission	12618	23	10719	1	0	0%	19:30:05
15	weretis.net	13484	23	12630	7	0	0%	19:30:05
16	eternal-september	11973	23	11469	0	0	0%	19:30:05
17	news.swapon.de	1281	19	1242	0	0	1%	19:35:05
18	news.fcku.it	1193	1	1190	0	0	0%	18:05:05
TOTAL: 18		228352	3564	199458	779	124	1%	349:57:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.5 MB	9.9 KB	7.5 MB	0.1 KB/s	5.4 KB	19:30:05
2	news.freedyn.net	4.5 MB	12.0 KB	4.5 MB	0.1 KB/s	12.4 KB	19:30:05
3	news.samoylyk.net	4.4 MB	5.8 KB	4.4 MB	0.1 KB/s	12.9 KB	19:32:00
4	fu-berlin.de	4.3 MB	0.0 KB	4.3 MB	0.1 KB/s	13.2 KB	19:30:05
5	news.bbs.nz	951.1 KB	127.3 KB	1.1 MB	0.0 KB/s	2.5 KB	19:30:05
6	usenet.pasdenom.info	464.8 KB	4.4 MB	4.9 MB	0.1 KB/s	9.4 KB	19:38:43
7	tnet	455.8 KB	0.0 KB	455.8 KB	0.0 KB/s	2.1 KB	19:30:05
8	newsfeed.xs4all.nl	197.7 KB	11.8 KB	209.5 KB	0.0 KB/s	4.1 KB	19:35:47
9	news.uzoreto.com	97.4 KB	558.9 KB	656.3 KB	0.0 KB/s	3.0 KB	19:30:04
10	neva.ru	83.7 KB	384.8 KB	468.5 KB	0.0 KB/s	2.0 KB	19:30:05
11	erje	76.9 KB	5.9 KB	82.8 KB	0.0 KB/s	2.6 KB	19:30:05
12	aioe	65.5 KB	0.0 KB	65.5 KB	0.0 KB/s	2.2 KB	19:30:05
13	news.mixmin.net	41.3 KB	0.0 KB	41.3 KB	0.0 KB/s	1.5 KB	19:30:05
14	news.swapon.de	39.2 KB	0.0 KB	39.2 KB	0.0 KB/s	2.1 KB	19:35:05
15	eternal-september	34.5 KB	0.0 KB	34.5 KB	0.0 KB/s	1.5 KB	19:30:05
16	xmission	31.6 KB	1.9 KB	33.6 KB	0.0 KB/s	1.4 KB	19:30:05
17	weretis.net	31.6 KB	14.0 KB	45.6 KB	0.0 KB/s	1.5 KB	19:30:05
18	news.fcku.it	2.8 KB	0.0 KB	2.8 KB	0.0 KB/s	2.8 KB	18:05:05
TOTAL: 18		23.3 MB	5.5 MB	28.8 MB	0.0 KB/s	6.8 KB	349:57:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	9	43	73.3 KB	10	4	2	03:55:38
2	66-188-80-128.dhcp.mdsn.wi.charter.com	42	39	60.0 KB	25	19	17	11:26:08
3	a131033.upc-a.chello.nl	25	18	26.4 KB	4	0	0	06:59:15
4	69.160.37.57	2	6	9.1 KB	2	0	0	01:35:46
5	p3ee23238.dip0.t-ipconnect.de	1	1	1.1 KB	1	0	0	00:00:22
TOTAL: 5		79	107	169.8 KB	42	23	19	23:57:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	9	43	73.3 KB	10	4	2	03:55:38
2	*.dhcp.mdsn.wi.charter.com	42	39	60.0 KB	25	19	17	11:26:08
3	*.upc-a.chello.nl	25	18	26.4 KB	4	0	0	06:59:15
4	unresolved	5	6	9.1 KB	2	0	0	01:35:50
5	*.dip0.t-ipconnect.de	1	1	1.1 KB	1	0	0	00:00:22
TOTAL: 5		82	107	169.8 KB	42	23	19	23:57:16

NNRP auth users [Top 20]:

User	Conn
ohlandl	17
kev009	2
TOTAL: 2	19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
66-188-80-128.dhcp.mdsn.wi.charter.com	3.798	0.568	0.074	11:26:08
a131033.upc-a.chello.nl	1.994	0.328	0.000	06:59:15
ip72-222-200-117.ph.ph.cox.net	0.901	0.181	0.004	03:55:38
69.160.37.57	0.925	2.484	0.000	01:35:46
p3ee23238.dip0.t-ipconnect.de	0.125	0.108	0.000	00:00:22
94.161.7.26	0.052	0.022	0.000	00:00:01
94.163.233.104	0.056	0.016	0.000	00:00:01
94.161.47.160	0.057	0.016	0.000	00:00:01
TOTAL: 8	7.908	3.723	0.078	23:57:16

Curious NNRP server explorers [Top 20]:

System	Conn
94.163.233.104	1
94.161.7.26	1
94.161.47.160	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
66-188-80-128.dhcp.mdsn.wi.charter.com	13	0
ip72-222-200-117.ph.ph.cox.net	6	0
69.160.37.57	3	0
TOTAL: 3	22	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	97	90.7%
2	news	9	8.4%
3	alt	1	0.9%
TOTAL: 3		107	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	97
2	news.software.nntp	6
3	news.admin.peering	3
4	alt.free.newsservers	1
TOTAL: 4		107

Daily Usenet report for csiph.com

Nov 29 08:36:14 -- Nov 30 04:15:00