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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20826	44.9%	5.4 MB	60.9%
innd	17376	37.5%	2.3 MB	26.5%
innfeed	7504	16.2%	1.0 MB	11.9%
nnrpd	619	1.3%	68.9 KB	0.8%
nocem	15	0.0%	1.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	46341	100.0%	8.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	341547	57.6%
Negative hits	229868	38.8%
Do not exist	20135	3.4%
Cache misses	1484	0.3%
TOTAL: 4	593034	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.146	0.0%	30	0.000	4.867	17.000
article cleanup	00:00:00.097	0.0%	19928	0.000	0.005	0.027
article logging	00:00:00.259	0.0%	20490	0.000	0.013	0.049
article parse	00:00:00.663	0.0%	58243	0.000	0.011	0.070
article write	00:00:07.275	0.0%	16716	0.167	0.435	11.631
artlog/artcncl	00:00:00.000	0.0%	30	0.000	0.000	0.000
artlog/artparse	00:00:00.007	0.0%	319	0.000	0.022	1.000
data move	00:00:00.161	0.0%	553440	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.007	0.0%	30	0.000	0.233	1.000
hishave/artcncl	00:00:00.001	0.0%	30	0.000	0.033	0.143
hishave/artparse	00:00:00.001	0.0%	316	0.000	0.003	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.023	0.0%	594733	0.002	0.005	0.011
history sync	00:00:00.727	0.0%	341	0.000	2.132	8.333
history write	00:00:08.731	0.0%	19899	0.030	0.439	0.929
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.133	0.0%	316	0.000	0.421	5.000
idle	23:57:07.244	99.8%	561154	84.172	153.661	488.902
nntp read	00:00:01.990	0.0%	562173	0.001	0.004	0.007
overview write	00:02:13.850	0.2%	16716	0.180	8.007	973.225
perl filter	00:00:25.443	0.0%	19894	0.990	1.279	1.920
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.259	0.0%	33447	0.000	0.008	0.031
TOTAL: 24:00:43.061	24:00:10.017	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.123	0.0%	163844	0.000	0.001	0.046
article prepare	00:00:00.006	0.0%	35752	0.000	0.000	0.011
article read	00:00:00.973	0.0%	6563	0.000	0.148	44.000
backlog stats	00:00:00.190	0.0%	455638	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.541	0.0%	353886	0.001	0.004	0.009
data write	00:00:01.888	0.0%	336590	0.001	0.006	0.012
idle	23:53:34.492	100.0%	455638	15.656	188.778	576.080
status file	00:00:01.189	0.0%	1972	0.000	0.603	2.000
TOTAL: 23:53:49.431	23:53:40.402	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	22:46:16.258	100.0%	534	326.500	153513.592	600216.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	1287	0.000	0.005	0.167
readart	00:00:00.630	0.0%	90	0.000	7.000	42.000
TOTAL: 22:46:34.304	22:46:16.894	100.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	141
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	161

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	15611	3956	11608	47	25%	23:01:16
2	news-out.aioe.org	3	16269	3224	12994	51	19%	24:09:55
3	feeder.eternal-september.org	2	18266	2148	15976	142	11%	22:48:34
4	csiph-out.feeder.erje.net	1440	79929	1274	78390	265	1%	118:42:47
5	newsfeed.neva.ru	146	17858	943	16508	407	5%	72:02:56
6	csiph-out.news.xs4all.nl	861	18477	919	16375	1183	4%	71:25:39
7	csiph-out.news.weretis.net	2	23342	910	22278	154	3%	36:07:54
8	newsfeed.fu-berlin.de	14	16619	892	15564	163	5%	26:07:18
9	news-out.mixmin.net	2	17479	816	16183	480	4%	24:02:53
10	news.freedyn.net	3	19778	492	19088	198	2%	24:59:43
11	csiph-out.feed.uzoreto.com	864	26190	241	25597	352	0%	71:10:18
12	news.fcku.it	14	2536	234	2296	6	9%	21:38:04
13	csiph-out.news.tnetconsulting.net	2	16080	173	15868	39	1%	23:59:52
14	news-out.neodome.net	2	17641	145	17476	20	0%	23:59:52
15	nntp.pasdenom.info	2	11767	67	11673	27	0%	23:58:22
16	news.swapon.de	2	1525	36	1481	8	2%	22:46:39
17	localhost	37	11	11	0	0	100%	00:00:04
18	news.samoylyk.net	4	17056	8	17045	3	0%	19:14:24
19	news.bbs.nz	1	15796	7	15789	0	0%	23:59:52
20	xanadu-bbs.net	27	588	0	588	0	0%	24:36:29
TOTAL: 20		3430	352818	16496	332777	3545	4%	698:52:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.3 MB	1.8 KB	2.6 MB	13.9 MB	81%	3.5 KB
2	news-out.aioe.org	7.9 MB	6.8 KB	237.5 KB	8.2 MB	97%	2.6 KB
3	csiph-out.feeder.erje.net	7.8 MB	20.3 KB	481.0 KB	8.2 MB	94%	5.5 KB
4	feeder.eternal-september.org	5.4 MB	45.2 KB	689.0 KB	6.1 MB	88%	2.7 KB
5	csiph-out.news.xs4all.nl	2.8 MB	0.0 KB	9.3 MB	12.1 MB	22%	5.9 KB
6	csiph-out.news.weretis.net	2.7 MB	6.9 KB	2.1 MB	4.8 MB	55%	4.6 KB
7	newsfeed.neva.ru	2.4 MB	5.5 KB	1.6 MB	4.1 MB	60%	3.1 KB
8	news-out.mixmin.net	2.3 MB	6.8 KB	826.3 KB	3.1 MB	74%	2.5 KB
9	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	603.7 KB	2.7 MB	78%	2.6 KB
10	news.freedyn.net	1.4 MB	44.1 KB	1.2 MB	2.7 MB	52%	3.9 KB
11	csiph-out.feed.uzoreto.com	802.2 KB	1.8 KB	4.2 MB	4.9 MB	15%	8.5 KB
12	csiph-out.news.tnetconsulting.net	518.7 KB	0.0 KB	269.3 KB	788.0 KB	65%	3.7 KB
13	news-out.neodome.net	397.5 KB	40.5 KB	134.7 KB	572.7 KB	69%	3.5 KB
14	news.fcku.it	384.3 KB	0.0 KB	103.2 KB	487.5 KB	78%	2.0 KB
15	nntp.pasdenom.info	138.5 KB	0.0 KB	41.8 KB	180.3 KB	76%	1.9 KB
16	news.swapon.de	85.2 KB	5.0 KB	3.5 MB	3.6 MB	2%	83.9 KB
17	news.samoylyk.net	28.2 KB	37.3 KB	41.6 KB	107.1 KB	26%	9.7 KB
18	news.bbs.nz	26.0 KB	0.0 KB	0.0 KB	26.0 KB	100%	3.7 KB
19	localhost	16.7 KB	0.0 KB	0.0 KB	16.7 KB	100%	1.5 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		48.4 MB	222.0 KB	27.8 MB	76.4 MB	63%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 29 04:15:00 - 04:59:59	323	1.9%	0.12	883.6 KB	1.8%	0.33
Jan 29 05:00:00 - 05:59:59	398	2.4%	0.11	1.4 MB	2.8%	0.40
Jan 29 06:00:00 - 06:59:59	468	2.8%	0.13	1.7 MB	3.6%	0.50
Jan 29 07:00:00 - 07:59:59	494	3.0%	0.14	1.6 MB	3.3%	0.46
Jan 29 08:00:00 - 08:59:59	612	3.7%	0.17	1.5 MB	3.0%	0.42
Jan 29 09:00:00 - 09:59:59	788	4.7%	0.22	2.3 MB	4.7%	0.65
Jan 29 10:00:00 - 10:59:59	738	4.4%	0.20	1.8 MB	3.7%	0.51
Jan 29 11:00:00 - 11:59:59	754	4.5%	0.21	2.0 MB	4.1%	0.57
Jan 29 12:00:00 - 12:59:59	679	4.1%	0.19	1.6 MB	3.3%	0.46
Jan 29 13:00:00 - 13:59:59	815	4.9%	0.23	2.0 MB	4.1%	0.58
Jan 29 14:00:00 - 14:59:59	929	5.6%	0.26	2.2 MB	4.5%	0.63
Jan 29 15:00:00 - 15:59:59	986	5.9%	0.27	3.4 MB	7.0%	0.98
Jan 29 16:00:00 - 16:59:59	1050	6.3%	0.29	4.3 MB	8.8%	1.22
Jan 29 17:00:00 - 17:59:59	1037	6.2%	0.29	2.9 MB	5.9%	0.82
Jan 29 18:00:00 - 18:59:59	891	5.3%	0.25	2.5 MB	5.2%	0.72
Jan 29 19:00:00 - 19:59:59	956	5.7%	0.27	2.8 MB	5.7%	0.79
Jan 29 20:00:00 - 20:59:59	911	5.5%	0.25	2.4 MB	4.9%	0.69
Jan 29 21:00:00 - 21:59:59	815	4.9%	0.23	2.3 MB	4.7%	0.66
Jan 29 22:00:00 - 22:59:59	721	4.3%	0.20	2.0 MB	4.1%	0.57
Jan 29 23:00:00 - 23:59:59	645	3.9%	0.18	2.2 MB	4.5%	0.63
Jan 30 00:00:00 - 00:59:59	545	3.3%	0.15	1.6 MB	3.2%	0.45
Jan 30 01:00:00 - 01:59:59	398	2.4%	0.11	1.1 MB	2.3%	0.33
Jan 30 02:00:00 - 02:59:59	337	2.0%	0.09	925.7 KB	1.8%	0.26
Jan 30 03:00:00 - 03:59:59	329	2.0%	0.09	1.2 MB	2.4%	0.34
Jan 30 04:00:00 - 04:15:00	87	0.5%	0.10	228.7 KB	0.5%	0.25
TOTAL: 24:00:00	16706	100.0%	0.19	49.0 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1180	35	0	1145
2	newsfeed.fu-berlin.de	591	34	0	557
3	news-out.mixmin.net	484	2	2	480
4	newsfeed.neva.ru	404	115	3	286
5	csiph-out.feed.uzoreto.com	352	5	1	346
6	csiph-out.feeder.erje.net	266	44	6	216
7	news.freedyn.net	195	2	3	190
8	csiph-out.news.weretis.net	180	63	3	114
9	news-out.aioe.org	152	43	2	107
10	feeder.eternal-september.org	137	19	4	114
11	newsfeeds.xmission.com	48	4	1	43
12	csiph-out.news.tnetconsulting.net	29	23	2	4
13	nntp.pasdenom.info	27	0	0	27
14	news-out.neodome.net	21	0	3	18
15	news.swapon.de	14	0	1	13
16	news.fcku.it	7	5	0	2
17	news.samoylyk.net	3	0	1	2
TOTAL: 17		4090	394	32	3664

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	68
it-alt.sport.calcio.juventus	31
alt.computer.workshop	27
mozilla.general	21
lada.commerce	19
lada.auto	15
mozilla.support.thunderbird	11
alt.politics	11
mozilla.support.seamonkey	10
spline.eisfair	10
mozilla.dev.platform	9
alt.binaries.pictures.purebeauty	9
alt.politics.usa	8
spline.eisfair.dev	8
alt.bbs.synchronet	8
mozilla.support.firefox	8
alt.windows7.general	5
oesterreich.tratsch	5
free.it.religioni.scientology	5
alt.hack.nl	5
TOTAL: 84	394

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1878
EMP (phn nph)	325
EMP (phn path)	305
EMP (phl)	117
0	96
HTML Multipart	31
Too many newsgroups	14
Binary: misplaced binary	12
Too many newsgroups (meow)	2
Too many newsgroups (low_xpost)	1
TOTAL: 10	2781

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	40
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	43
Huge articles
	csiph-out.news.xs4all.nl	138
	csiph-out.feed.uzoreto.com	78
	newsfeeds.xmission.com	40
	csiph-out.news.weretis.net	26
	news.freedyn.net	13
	news.swapon.de	7
	feeder.eternal-september.org	6
	newsfeed.fu-berlin.de	4
	csiph-out.news.tnetconsulting.net	1
	news-out.neodome.net	1
	TOTAL: 12	316
Including strange strings
	newsfeed.fu-berlin.de	430
	news-out.aioe.org	101
	csiph-out.news.weretis.net	10
	news.swapon.de	6
	news.freedyn.net	5
	news-out.mixmin.net	4
	csiph-out.news.xs4all.nl	2
	news.fcku.it	1
	feeder.eternal-september.org	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 11	562
No colon-space in header
	csiph-out.news.xs4all.nl	3
	TOTAL: 1	3
TOTAL: 4		924

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	72793	3256	65254	3	12	2927	4%	23:59:55
2	news.samoylyk.net	106721	2921	102939	408	0	2753	2%	23:58:40
3	news.freedyn.net	16525	554	15618	16	0	42	3%	23:59:55
4	fu-berlin.de	15678	550	15128	0	0	0	3%	23:59:54
5	news.bbs.nz	31428	364	15771	7	0	1	1%	23:59:50
6	usenet.pasdenom.info	16656	117	15951	17	0	17	0%	23:59:55
7	tnet	17534	103	16050	0	0	0	0%	23:59:55
8	newsfeed.xs4all.nl	16076	43	15579	3	0	46	0%	23:59:54
9	aioe	12435	42	12028	1	0	0	0%	23:59:55
10	news.uzoreto.com	16201	42	15826	153	0	0	0%	23:59:54
11	neva.ru	15073	38	14789	134	0	0	0%	23:59:55
12	neodome.net	17006	35	16474	0	0	0	0%	23:59:50
13	erje	15636	32	15330	17	0	0	0%	23:59:46
14	xmission	13648	21	12016	0	0	0	0%	23:59:51
15	weretis.net	15225	17	15028	0	0	0	0%	23:59:33
16	news.mixmin.net	15605	16	15149	0	0	0	0%	23:59:51
17	eternal-september	14236	15	13753	0	0	0	0%	23:59:48
18	news.swapon.de	1656	14	1596	0	0	0	0%	23:56:34
19	news.fcku.it	2099	0	2092	0	0	0	0%	21:26:26
TOTAL: 19		432231	8180	396371	759	12	5786	1%	453:19:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.samoylyk.net	27.6 MB	10.2 MB	37.8 MB	0.4 KB/s	11.6 KB	23:58:40
2	xanadu-bbs	27.3 MB	16.4 KB	27.4 MB	0.3 KB/s	8.6 KB	23:59:55
3	news.freedyn.net	5.7 MB	25.4 KB	5.7 MB	0.1 KB/s	10.3 KB	23:59:55
4	fu-berlin.de	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	10.5 KB	23:59:54
5	news.bbs.nz	861.5 KB	61.8 KB	923.3 KB	0.0 KB/s	2.5 KB	23:59:50
6	usenet.pasdenom.info	319.3 KB	65.3 KB	384.6 KB	0.0 KB/s	2.9 KB	23:59:55
7	neodome.net	273.1 KB	0.0 KB	273.1 KB	0.0 KB/s	7.8 KB	23:59:50
8	tnet	253.3 KB	0.0 KB	253.3 KB	0.0 KB/s	2.5 KB	23:59:55
9	newsfeed.xs4all.nl	204.3 KB	11.1 KB	215.4 KB	0.0 KB/s	4.7 KB	23:59:54
10	neva.ru	96.2 KB	271.7 KB	367.9 KB	0.0 KB/s	2.1 KB	23:59:55
11	aioe	82.8 KB	1.5 KB	84.2 KB	0.0 KB/s	2.0 KB	23:59:55
12	news.uzoreto.com	81.0 KB	565.4 KB	646.4 KB	0.0 KB/s	3.3 KB	23:59:54
13	erje	69.6 KB	80.5 KB	150.1 KB	0.0 KB/s	3.1 KB	23:59:46
14	xmission	39.1 KB	0.0 KB	39.1 KB	0.0 KB/s	1.9 KB	23:59:51
15	weretis.net	34.9 KB	0.0 KB	34.9 KB	0.0 KB/s	2.1 KB	23:59:33
16	eternal-september	27.0 KB	0.0 KB	27.0 KB	0.0 KB/s	1.8 KB	23:59:48
17	news.mixmin.net	26.8 KB	0.0 KB	26.8 KB	0.0 KB/s	1.7 KB	23:59:51
18	news.swapon.de	22.2 KB	0.0 KB	22.2 KB	0.0 KB/s	1.6 KB	23:56:34
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:26:26
TOTAL: 19		68.6 MB	11.3 MB	79.9 MB	0.1 KB/s	9.2 KB	453:19:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	4	31	63.2 KB	2	0	0	01:41:24
2	71-13-20-4.dhcp.dlth.mn.charter.com	43	19	35.6 KB	15	11	11	11:20:49
3	a131033.upc-a.chello.nl	33	17	37.7 KB	6	0	0	08:27:58
4	208-71-239-109.cust.centrio.cz	2	14	30.1 KB	2	0	0	00:14:10
5	96-42-30-247.dhcp.mdsn.wi.charter.com	1	9	22.7 KB	2	1	0	00:32:04
TOTAL: 5		83	90	189.3 KB	27	12	11	22:46:33

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	4	31	63.2 KB	2	0	0	01:41:24
2	*.dhcp.dlth.mn.charter.com	43	19	35.6 KB	15	11	11	11:20:49
3	*.upc-a.chello.nl	33	17	37.7 KB	6	0	0	08:27:58
4	*.cust.centrio.cz	2	14	30.1 KB	2	0	0	00:14:10
5	*.dhcp.mdsn.wi.charter.com	1	9	22.7 KB	2	1	0	00:32:04
6	*.6-2.cable.virginm.net	2	0	0.0 KB	0	0	0	00:30:05
TOTAL: 6		85	90	189.3 KB	27	12	11	22:46:33

NNRP auth users [Top 20]:

User	Conn
ohlandl	11
timc	2
ctomczyk	2
TOTAL: 3	15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-13-20-4.dhcp.dlth.mn.charter.com	3.526	0.720	0.026	11:20:49
a131033.upc-a.chello.nl	2.553	0.478	0.000	08:27:58
ip72-222-200-117.ph.ph.cox.net	0.416	0.073	0.000	01:41:24
96-42-30-247.dhcp.mdsn.wi.charter.com	0.219	0.032	0.003	00:32:04
cpc78679-glfd7-2-0-cust204.6-2.cable.virginm.net	0.147	0.051	0.005	00:30:05
208-71-239-109.cust.centrio.cz	0.178	0.070	0.005	00:14:10
TOTAL: 6	7.039	1.424	0.039	22:46:33

Curious NNRP server explorers [Top 20]:

System	Conn
cpc78679-glfd7-2-0-cust204.6-2.cable.virginm.net	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-13-20-4.dhcp.dlth.mn.charter.com	10	0
ip72-222-200-117.ph.ph.cox.net	3	0
96-42-30-247.dhcp.mdsn.wi.charter.com	1	0
cpc78679-glfd7-2-0-cust204.6-2.cable.virginm.net	1	0
TOTAL: 4	15	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	71
2	comp.lang.javascript	14
TOTAL: 2		85

Daily Usenet report for csiph.com

Jan 29 04:15:00 -- Jan 30 04:15:00