Daily Usenet report for csiph.com: Mar 5 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19657	47.0%	5.0 MB	62.4%
innd	17485	41.8%	2.3 MB	28.6%
innfeed	3509	8.4%	616.9 KB	7.5%
nnrpd	1102	2.6%	122.4 KB	1.5%
nocem	51	0.1%	6.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41805	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	322380	57.1%
Negative hits	221697	39.2%
Do not exist	18986	3.4%
Cache misses	1856	0.3%
TOTAL: 4	564919	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.238	0.0%	26	0.000	9.154	27.400
article cleanup	00:00:00.120	0.0%	18530	0.000	0.006	0.043
article logging	00:00:00.306	0.0%	19120	0.000	0.016	0.304
article parse	00:00:01.492	0.0%	59279	0.000	0.025	0.421
article write	00:00:11.879	0.0%	15424	0.182	0.770	41.939
artlog/artcncl	00:00:00.000	0.0%	26	0.000	0.000	0.000
artlog/artparse	00:00:00.012	0.0%	534	0.000	0.022	0.400
data move	00:00:00.199	0.0%	527551	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.006	0.0%	23	0.000	0.261	0.500
hishave/artcncl	00:00:00.004	0.0%	26	0.000	0.154	1.000
hishave/artparse	00:00:00.002	0.0%	529	0.000	0.004	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:08.114	0.0%	565511	0.002	0.014	0.784
history sync	00:00:01.090	0.0%	363	0.000	3.003	57.000
history write	00:00:13.782	0.0%	18504	0.214	0.745	13.344
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hiswrite/artparse	00:00:00.915	0.0%	529	0.000	1.730	12.235
idle	23:58:46.436	99.8%	534752	92.187	161.433	444.454
nntp read	00:00:01.912	0.0%	536013	0.001	0.004	0.007
overview write	00:00:42.001	0.0%	15424	0.097	2.723	64.250
perl filter	00:00:24.127	0.0%	18499	1.054	1.304	1.963
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.244	0.0%	30899	0.000	0.008	0.028
TOTAL: 24:01:05.392	24:00:32.879	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	19768	0.000	0.002	0.024
article prepare	00:00:00.000	0.0%	12872	0.000	0.000	0.000
article read	00:00:00.265	0.0%	1150	0.000	0.230	16.333
backlog stats	00:00:00.173	0.0%	371781	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.362	0.0%	294223	0.001	0.005	0.009
data write	00:00:01.575	0.0%	273510	0.002	0.006	0.011
idle	23:54:17.395	100.0%	371781	124.567	231.473	801.042
status file	00:00:00.339	0.0%	276	0.000	1.228	28.500
TOTAL: 23:54:27.547	23:54:21.155	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	51:49:51.610	99.8%	756	64.500	246814.299	600138.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.760	0.0%	1769	0.000	0.995	13.462
readart	00:00:00.669	0.0%	72	0.000	9.292	43.000
TOTAL: 51:54:46.755	51:49:54.039	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
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	newsfeeds.xmission.com	2	14808	3853	10909	46	26%	22:54:56
2	news-out.aioe.org	2	15151	3021	12027	103	19%	24:09:56
3	feeder.eternal-september.org	1	17573	2209	15118	246	12%	24:17:08
4	csiph-out.feeder.erje.net	1440	76518	1125	74881	512	1%	118:51:50
5	newsfeed.fu-berlin.de	17	17556	1070	16403	83	6%	30:24:45
6	newsfeed.neva.ru	141	17025	830	15805	390	4%	72:05:44
7	news-out.mixmin.net	2	16135	819	15026	290	5%	24:02:33
8	csiph-out.news.xs4all.nl	861	17380	753	15414	1213	4%	71:29:13
9	csiph-out.news.weretis.net	3	22083	539	21406	138	2%	36:51:17
10	news.freedyn.net	2	18436	490	17796	150	2%	24:09:02
11	news.fcku.it	11	2140	239	1896	5	11%	22:05:37
12	csiph-out.feed.uzoreto.com	864	24890	143	24413	334	0%	71:16:38
13	news-out.neodome.net	2	16289	47	16235	7	0%	23:59:52
14	csiph-out.news.tnetconsulting.net	1	5450	40	5380	30	0%	11:56:06
15	news.swapon.de	10	1719	31	1688	0	1%	25:31:48
16	nntp.pasdenom.info	11	12027	26	11992	9	0%	19:32:19
17	localhost	41	15	15	0	0	100%	00:00:06
18	news.samoylyk.net	2	15269	7	15261	1	0%	23:59:51
19	news.bbs.nz	1	14901	6	14895	0	0%	23:59:52
20	xanadu-bbs.net	22	490	0	490	0	0%	22:22:33
TOTAL: 20		3436	325855	15263	307035	3557	4%	694:01:06

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.2 MB	0.0 KB	3.6 MB	14.8 MB	75%	3.9 KB
2	news-out.aioe.org	7.3 MB	3.5 KB	401.8 KB	7.7 MB	94%	2.5 KB
3	csiph-out.feeder.erje.net	6.6 MB	11.6 KB	9.9 MB	16.5 MB	39%	10.3 KB
4	feeder.eternal-september.org	5.5 MB	3.5 KB	1.4 MB	6.9 MB	79%	2.9 KB
5	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	375.1 KB	3.0 MB	87%	2.7 KB
6	csiph-out.news.xs4all.nl	2.5 MB	2.4 KB	12.3 MB	14.8 MB	16%	7.7 KB
7	news-out.mixmin.net	2.4 MB	3.5 KB	561.3 KB	2.9 MB	81%	2.7 KB
8	newsfeed.neva.ru	1.9 MB	3.5 KB	1.3 MB	3.2 MB	58%	2.7 KB
9	news.freedyn.net	1.9 MB	6.1 KB	1.9 MB	3.8 MB	49%	6.1 KB
10	csiph-out.news.weretis.net	1.7 MB	3.6 KB	2.5 MB	4.2 MB	40%	6.4 KB
11	csiph-out.feed.uzoreto.com	609.4 KB	4.6 KB	10.0 MB	10.6 MB	5%	22.8 KB
12	news.fcku.it	403.6 KB	2.4 KB	7.1 KB	413.1 KB	97%	1.7 KB
13	news-out.neodome.net	133.2 KB	1.2 KB	13.5 KB	147.9 KB	90%	2.7 KB
14	csiph-out.news.tnetconsulting.net	96.1 KB	0.0 KB	124.7 KB	220.8 KB	43%	3.2 KB
15	news.swapon.de	64.6 KB	0.0 KB	0.0 KB	64.6 KB	100%	2.1 KB
16	nntp.pasdenom.info	53.7 KB	0.0 KB	15.2 KB	69.0 KB	77%	2.0 KB
17	localhost	23.7 KB	0.0 KB	0.0 KB	23.7 KB	100%	1.6 KB
18	news.samoylyk.net	19.5 KB	2.4 KB	2.4 KB	24.2 KB	80%	3.0 KB
19	news.bbs.nz	10.1 KB	0.0 KB	0.0 KB	10.1 KB	100%	1.7 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.9 MB	48.2 KB	44.4 MB	89.4 MB	50%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 5 04:15:00 - 04:59:59	175	1.1%	0.06	545.6 KB	1.2%	0.20
Mar 5 05:00:00 - 05:59:59	285	1.8%	0.08	1.0 MB	2.3%	0.30
Mar 5 06:00:00 - 06:59:59	331	2.1%	0.09	1.1 MB	2.5%	0.32
Mar 5 07:00:00 - 07:59:59	448	2.9%	0.12	1.5 MB	3.3%	0.42
Mar 5 08:00:00 - 08:59:59	599	3.9%	0.17	1.5 MB	3.3%	0.43
Mar 5 09:00:00 - 09:59:59	709	4.6%	0.20	2.0 MB	4.4%	0.57
Mar 5 10:00:00 - 10:59:59	692	4.5%	0.19	1.7 MB	3.7%	0.48
Mar 5 11:00:00 - 11:59:59	791	5.1%	0.22	2.5 MB	5.6%	0.72
Mar 5 12:00:00 - 12:59:59	724	4.7%	0.20	1.8 MB	3.9%	0.50
Mar 5 13:00:00 - 13:59:59	815	5.3%	0.23	2.1 MB	4.5%	0.58
Mar 5 14:00:00 - 14:59:59	879	5.7%	0.24	2.4 MB	5.2%	0.67
Mar 5 15:00:00 - 15:59:59	952	6.2%	0.26	2.9 MB	6.4%	0.83
Mar 5 16:00:00 - 16:59:59	955	6.2%	0.27	2.6 MB	5.7%	0.74
Mar 5 17:00:00 - 17:59:59	973	6.3%	0.27	2.5 MB	5.5%	0.71
Mar 5 18:00:00 - 18:59:59	946	6.1%	0.26	2.7 MB	6.1%	0.78
Mar 5 19:00:00 - 19:59:59	836	5.4%	0.23	2.6 MB	5.8%	0.75
Mar 5 20:00:00 - 20:59:59	793	5.1%	0.22	2.1 MB	4.6%	0.60
Mar 5 21:00:00 - 21:59:59	744	4.8%	0.21	1.9 MB	4.3%	0.55
Mar 5 22:00:00 - 22:59:59	708	4.6%	0.20	2.3 MB	5.0%	0.64
Mar 5 23:00:00 - 23:59:59	550	3.6%	0.15	2.1 MB	4.7%	0.60
Mar 6 00:00:00 - 00:59:59	451	2.9%	0.13	1.4 MB	3.0%	0.39
Mar 6 01:00:00 - 01:59:59	412	2.7%	0.11	1.6 MB	3.5%	0.45
Mar 6 02:00:00 - 02:59:59	301	2.0%	0.08	937.5 KB	2.0%	0.26
Mar 6 03:00:00 - 03:59:59	275	1.8%	0.08	1.3 MB	2.8%	0.36
Mar 6 04:00:00 - 04:15:00	76	0.5%	0.08	196.0 KB	0.4%	0.22
TOTAL: 24:00:00	15420	100.0%	0.18	45.2 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1206	36	1	1169
2	newsfeed.fu-berlin.de	540	18	0	522
3	csiph-out.feeder.erje.net	515	41	7	467
4	newsfeed.neva.ru	387	82	3	302
5	csiph-out.feed.uzoreto.com	334	7	3	324
6	news-out.mixmin.net	299	2	2	295
7	feeder.eternal-september.org	244	19	2	223
8	csiph-out.news.weretis.net	198	81	3	114
9	news-out.aioe.org	196	81	2	113
10	news.freedyn.net	152	6	3	143
11	csiph-out.news.tnetconsulting.net	63	24	2	37
12	newsfeeds.xmission.com	52	1	0	51
13	nntp.pasdenom.info	9	0	0	9
14	news-out.neodome.net	7	0	1	6
15	news.fcku.it	5	0	1	4
16	news.swapon.de	3	0	0	3
17	news.samoylyk.net	1	0	1	0
TOTAL: 17		4211	398	31	3782

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	50
alt.computer.workshop	47
alt.bbs.synchronet	35
alt.survival	23
mozilla.support.thunderbird	22
lada.auto	21
mozilla.support.seamonkey	17
alt.binaries.pictures.purebeauty	14
alt.politics.usa	12
spline.eisfair	12
it-alt.sport.calcio.juventus	10
nyc.jobs	7
mozilla.dev.platform	7
cakewalk.audio	7
alt.music.pink-floyd.publius	6
mozilla.general	6
alt.windows7.general	6
mozilla.dev.security.policy	5
mozilla.dev.apps.thunderbird	4
lada.test	3
TOTAL: 78	398

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2085
EMP (phn path)	211
EMP (phl)	166
0	71
EMP (phn nph)	43
HTML Multipart	39
Scoring filter	17
Too many newsgroups	12
Binary Payload	9
Too many newsgroups (low_xpost)	3
Too many newsgroups (meow)	1
Binary: misplaced binary	1
TOTAL: 12	2658

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	55
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	2
	TOTAL: 3	59
Huge articles
	csiph-out.feeder.erje.net	172
	csiph-out.news.xs4all.nl	126
	csiph-out.feed.uzoreto.com	124
	newsfeeds.xmission.com	45
	csiph-out.news.weretis.net	28
	news.freedyn.net	16
	feeder.eternal-september.org	10
	news-out.mixmin.net	3
	newsfeed.fu-berlin.de	3
	csiph-out.news.tnetconsulting.net	1
	TOTAL: 10	528
Including strange strings
	newsfeed.fu-berlin.de	458
	news-out.aioe.org	89
	csiph-out.news.weretis.net	14
	news-out.mixmin.net	8
	newsfeeds.xmission.com	6
	news.freedyn.net	5
	csiph-out.feeder.erje.net	3
	news.swapon.de	3
	csiph-out.news.xs4all.nl	3
	csiph-out.feed.uzoreto.com	2
	TOTAL: 10	591
No colon-space in header
	csiph-out.news.xs4all.nl	5
	TOTAL: 1	5
TOTAL: 4		1183

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18134	793	14624	3	6	4%	23:59:55
2	news.bbs.nz	29330	414	14620	2	0	1%	23:59:56
3	news.freedyn.net	15236	383	14450	20	0	2%	23:59:41
4	news.samoylyk.net	16066	381	15028	5	69	2%	23:59:56
5	fu-berlin.de	14267	379	13887	0	0	2%	23:59:56
6	tnet	16138	172	14876	0	0	1%	23:59:52
7	erje	15483	97	14055	70	0	0%	23:59:56
8	newsfeed.xs4all.nl	15026	73	14463	9	72	0%	23:59:55
9	neva.ru	14200	68	13860	106	0	0%	23:59:50
10	aioe	11748	60	11227	1	0	0%	23:59:56
11	usenet.pasdenom.info	17829	29	17079	9	2133	0%	23:58:43
12	news.mixmin.net	14552	25	14014	0	0	0%	23:59:56
13	neodome.net	15897	25	15312	0	0	0%	23:59:45
14	xmission	13403	21	11090	0	0	0%	23:59:07
15	eternal-september	13308	18	12732	0	0	0%	23:59:55
16	news.swapon.de	1863	15	1804	0	0	0%	23:47:23
17	weretis.net	14541	15	14348	0	0	0%	23:59:48
18	news.uzoreto.com	15311	6	14952	22	0	0%	23:59:48
19	news.fcku.it	1719	0	1714	0	0	0%	21:20:04
TOTAL: 19		274051	2974	244135	247	2280	1%	453:03:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.5 MB	12.1 KB	5.5 MB	0.1 KB/s	7.1 KB	23:59:55
2	news.freedyn.net	4.3 MB	30.7 KB	4.4 MB	0.1 KB/s	11.1 KB	23:59:41
3	fu-berlin.de	4.3 MB	0.0 KB	4.3 MB	0.1 KB/s	11.6 KB	23:59:56
4	news.samoylyk.net	4.3 MB	5.7 KB	4.3 MB	0.1 KB/s	11.4 KB	23:59:56
5	news.bbs.nz	1.0 MB	4.9 KB	1.0 MB	0.0 KB/s	2.5 KB	23:59:56
6	tnet	403.7 KB	0.0 KB	403.7 KB	0.0 KB/s	2.3 KB	23:59:52
7	newsfeed.xs4all.nl	234.3 KB	31.4 KB	265.7 KB	0.0 KB/s	3.2 KB	23:59:55
8	erje	224.6 KB	489.8 KB	714.4 KB	0.0 KB/s	4.3 KB	23:59:56
9	neva.ru	160.2 KB	238.7 KB	398.9 KB	0.0 KB/s	2.3 KB	23:59:50
10	aioe	126.8 KB	1.7 KB	128.5 KB	0.0 KB/s	2.1 KB	23:59:56
11	usenet.pasdenom.info	92.0 KB	47.4 KB	139.5 KB	0.0 KB/s	3.7 KB	23:58:43
12	eternal-september	73.3 KB	0.0 KB	73.3 KB	0.0 KB/s	4.1 KB	23:59:55
13	news.mixmin.net	46.4 KB	0.0 KB	46.4 KB	0.0 KB/s	1.9 KB	23:59:56
14	neodome.net	45.0 KB	0.0 KB	45.0 KB	0.0 KB/s	1.8 KB	23:59:45
15	xmission	36.1 KB	0.0 KB	36.1 KB	0.0 KB/s	1.7 KB	23:59:07
16	news.swapon.de	23.7 KB	0.0 KB	23.7 KB	0.0 KB/s	1.6 KB	23:47:23
17	weretis.net	23.7 KB	0.0 KB	23.7 KB	0.0 KB/s	1.6 KB	23:59:48
18	news.uzoreto.com	11.1 KB	44.2 KB	55.2 KB	0.0 KB/s	2.0 KB	23:59:48
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:20:04
TOTAL: 19		20.9 MB	906.6 KB	21.8 MB	0.0 KB/s	6.9 KB	453:03:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	pd9e7374b.dip0.t-ipconnect.de	1	30	44.1 KB	1	0	0	00:35:12
2	68-114-222-137.dhcp.mdsn.wi.charter.com	38	23	36.2 KB	18	15	13	11:10:37
3	a131033.upc-a.chello.nl	62	19	29.1 KB	11	0	0	16:08:21
TOTAL: 3		101	72	109.4 KB	30	15	13	51:54:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dip0.t-ipconnect.de	1	30	44.1 KB	1	0	0	00:35:12
2	*.dhcp.mdsn.wi.charter.com	38	23	36.2 KB	18	15	13	11:10:37
3	*.upc-a.chello.nl	62	19	29.1 KB	11	0	0	16:08:21
4	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
5	unresolved	73	0	0.0 KB	0	0	0	24:00:25
6	*.dhcp.eucl.wi.charter.com	1	0	0.0 KB	0	0	0	00:00:07
TOTAL: 6		176	72	109.4 KB	30	15	13	51:54:45

NNRP auth users [Top 20]:

User	Conn
ohlandl	13
TOTAL: 1	13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	5.127	1.195	0.000	24:00:15
a131033.upc-a.chello.nl	5.034	0.863	0.000	16:08:21
68-114-222-137.dhcp.mdsn.wi.charter.com	3.328	0.526	0.034	11:10:37
pd9e7374b.dip0.t-ipconnect.de	0.123	0.041	0.000	00:35:12
192.241.230.45	0.061	0.015	0.000	00:00:09
68-190-164-53.dhcp.eucl.wi.charter.com	0.062	0.016	0.000	00:00:07
scan-141.security.ipip.net	0.041	0.033	0.000	00:00:00
TOTAL: 7	13.776	2.689	0.034	51:54:45

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
192.241.230.45	1
scan-141.security.ipip.net	1
68-190-164-53.dhcp.eucl.wi.charter.com	1
TOTAL: 4	75

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
Host: 69.160.37.58:119	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-114-222-137.dhcp.mdsn.wi.charter.com	11	0
pd9e7374b.dip0.t-ipconnect.de	1	0
TOTAL: 2	12	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Mar 5 04:15:00 -- Mar 6 04:15:00