Daily Usenet report for csiph.com: Feb 28 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20592	48.1%	5.2 MB	63.3%
innd	17818	41.6%	2.3 MB	27.9%
innfeed	3479	8.1%	642.3 KB	7.6%
nnrpd	925	2.2%	101.2 KB	1.2%
nocem	16	0.0%	1.8 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	42831	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	339286	57.3%
Negative hits	232811	39.3%
Do not exist	19985	3.4%
Cache misses	245	0.0%
TOTAL: 4	592327	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.250	0.0%	16	0.000	15.625	59.000
article cleanup	00:00:00.108	0.0%	19602	0.000	0.006	0.025
article logging	00:00:00.683	0.0%	20111	0.000	0.034	3.000
article parse	00:00:02.254	0.0%	77464	0.000	0.029	0.626
article write	00:00:52.568	0.1%	16193	0.200	3.246	24.340
artlog/artcncl	00:00:00.002	0.0%	16	0.000	0.125	0.286
artlog/artparse	00:00:00.006	0.0%	477	0.000	0.013	0.500
data move	00:00:00.220	0.0%	568452	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.049	0.0%	14	0.000	3.500	23.000
hishave/artcncl	00:00:00.039	0.0%	16	0.000	2.438	19.000
hishave/artparse	00:00:00.001	0.0%	474	0.000	0.002	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:57.081	0.1%	592827	0.002	0.096	0.413
history sync	00:00:01.264	0.0%	384	0.000	3.292	15.000
history write	00:01:17.421	0.1%	19563	0.333	3.958	12.173
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:01.567	0.0%	456	0.000	3.436	54.000
idle	23:53:33.436	99.5%	566764	48.607	151.762	627.137
nntp read	00:00:02.338	0.0%	577334	0.002	0.004	0.007
overview write	00:03:09.880	0.2%	16193	0.361	11.726	4150.240
perl filter	00:00:27.193	0.0%	19560	1.056	1.390	5.914
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.266	0.0%	32401	0.000	0.008	0.037
TOTAL: 24:01:00.533	24:00:26.626	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	16916	0.000	0.002	0.020
article prepare	00:00:00.000	0.0%	15000	0.000	0.000	0.000
article read	00:00:00.245	0.0%	1258	0.000	0.195	22.000
backlog stats	00:00:00.215	0.0%	374217	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.449	0.0%	296235	0.001	0.005	0.009
data write	00:00:01.694	0.0%	283010	0.001	0.006	0.013
idle	23:53:21.740	100.0%	374217	143.469	229.818	933.696
status file	00:00:00.925	0.0%	255	0.000	3.627	102.714
TOTAL: 23:53:32.818	23:53:26.304	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	40:52:49.731	99.8%	607	55.000	242454.252	600078.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.016	0.0%	1411	0.000	0.011	0.286
readart	00:00:00.284	0.0%	35	0.000	8.114	43.000
TOTAL: 40:57:55.471	40:52:50.031	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	15383	4273	11070	40	27%	23:45:55
2	news-out.aioe.org	3	16239	3349	12654	236	20%	24:09:39
3	feeder.eternal-september.org	1	17046	2026	14783	237	11%	21:05:30
4	csiph-out.feeder.erje.net	1440	81739	1117	80123	499	1%	118:46:37
5	newsfeed.neva.ru	150	17548	931	16324	293	5%	71:13:36
6	csiph-out.news.weretis.net	2	33189	916	32083	190	2%	47:59:48
7	news-out.mixmin.net	1	17031	795	15869	367	4%	23:59:52
8	csiph-out.news.xs4all.nl	861	18241	784	16377	1080	4%	71:28:16
9	newsfeed.fu-berlin.de	8	16037	742	15046	249	4%	25:49:16
10	news.freedyn.net	2	19186	507	18477	202	2%	24:02:57
11	news.fcku.it	12	2100	215	1880	5	10%	22:25:38
12	csiph-out.feed.uzoreto.com	864	26465	167	26020	278	0%	71:11:41
13	csiph-out.news.tnetconsulting.net	2	16443	73	16274	96	0%	23:59:52
14	nntp.pasdenom.info	2	11175	73	11087	15	0%	23:58:19
15	news-out.neodome.net	2	16830	47	16756	27	0%	23:59:43
16	news.swapon.de	5	1356	21	1334	1	1%	23:01:53
17	news.bbs.nz	1	15656	16	15640	0	0%	23:59:53
18	news.samoylyk.net	2	16313	15	16279	19	0%	24:54:10
19	localhost	30	4	4	0	0	100%	00:00:06
20	xanadu-bbs.net	49	513	0	513	0	0%	40:43:31
TOTAL: 20		3440	358494	16071	338589	3834	4%	730:36:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	12.5 MB	0.0 KB	2.3 MB	14.9 MB	84%	3.5 KB
2	news-out.aioe.org	8.3 MB	3.7 KB	545.9 KB	8.9 MB	93%	2.5 KB
3	csiph-out.feeder.erje.net	7.4 MB	15.1 KB	10.8 MB	18.2 MB	40%	11.5 KB
4	feeder.eternal-september.org	5.3 MB	5.8 KB	7.8 MB	13.0 MB	40%	5.9 KB
5	csiph-out.news.weretis.net	2.5 MB	8.0 KB	2.6 MB	5.2 MB	49%	4.8 KB
6	csiph-out.news.xs4all.nl	2.5 MB	0.0 KB	13.0 MB	15.5 MB	16%	8.5 KB
7	newsfeed.neva.ru	2.1 MB	15.9 KB	1.0 MB	3.2 MB	66%	2.7 KB
8	news-out.mixmin.net	2.1 MB	5.8 KB	704.6 KB	2.8 MB	75%	2.5 KB
9	news.freedyn.net	1.9 MB	5.8 KB	1.3 MB	3.2 MB	57%	4.6 KB
10	newsfeed.fu-berlin.de	1.7 MB	0.0 KB	608.3 KB	2.3 MB	74%	2.4 KB
11	csiph-out.feed.uzoreto.com	955.3 KB	2.0 KB	18.2 MB	19.1 MB	4%	44.0 KB
12	news.fcku.it	324.6 KB	0.0 KB	5.9 KB	330.4 KB	98%	1.5 KB
13	csiph-out.news.tnetconsulting.net	278.5 KB	5.7 KB	269.0 KB	553.2 KB	50%	3.3 KB
14	nntp.pasdenom.info	151.3 KB	0.0 KB	21.9 KB	173.2 KB	87%	2.0 KB
15	news-out.neodome.net	150.6 KB	3.8 KB	1.1 MB	1.2 MB	11%	17.1 KB
16	news.swapon.de	73.0 KB	2.0 KB	2.0 KB	77.1 KB	94%	3.5 KB
17	news.samoylyk.net	32.5 KB	10.2 KB	11.6 MB	11.6 MB	0%	350.7 KB
18	news.bbs.nz	21.9 KB	0.0 KB	0.0 KB	21.9 KB	100%	1.4 KB
19	localhost	3.9 KB	0.0 KB	0.0 KB	3.9 KB	100%	1.0 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		48.3 MB	83.7 KB	71.9 MB	120.2 MB	40%	6.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 28 04:15:00 - 04:59:59	174	1.1%	0.06	579.0 KB	1.2%	0.21
Feb 28 05:00:00 - 05:59:59	368	2.3%	0.10	1.5 MB	3.1%	0.42
Feb 28 06:00:00 - 06:59:59	368	2.3%	0.10	1.1 MB	2.2%	0.31
Feb 28 07:00:00 - 07:59:59	637	3.9%	0.18	2.4 MB	4.9%	0.68
Feb 28 08:00:00 - 08:59:59	666	4.1%	0.18	2.0 MB	4.0%	0.56
Feb 28 09:00:00 - 09:59:59	823	5.1%	0.23	2.2 MB	4.5%	0.63
Feb 28 10:00:00 - 10:59:59	800	4.9%	0.22	2.3 MB	4.7%	0.64
Feb 28 11:00:00 - 11:59:59	760	4.7%	0.21	2.2 MB	4.4%	0.61
Feb 28 12:00:00 - 12:59:59	790	4.9%	0.22	2.2 MB	4.5%	0.62
Feb 28 13:00:00 - 13:59:59	827	5.1%	0.23	2.4 MB	5.0%	0.69
Feb 28 14:00:00 - 14:59:59	832	5.1%	0.23	2.3 MB	4.8%	0.67
Feb 28 15:00:00 - 15:59:59	885	5.5%	0.25	3.0 MB	6.1%	0.84
Feb 28 16:00:00 - 16:59:59	945	5.8%	0.26	2.9 MB	6.0%	0.83
Feb 28 17:00:00 - 17:59:59	861	5.3%	0.24	2.4 MB	5.0%	0.69
Feb 28 18:00:00 - 18:59:59	884	5.5%	0.25	2.3 MB	4.8%	0.66
Feb 28 19:00:00 - 19:59:59	914	5.6%	0.25	2.7 MB	5.5%	0.76
Feb 28 20:00:00 - 20:59:59	747	4.6%	0.21	2.1 MB	4.3%	0.59
Feb 28 21:00:00 - 21:59:59	792	4.9%	0.22	2.4 MB	4.8%	0.67
Feb 28 22:00:00 - 22:59:59	735	4.5%	0.20	2.1 MB	4.3%	0.59
Feb 28 23:00:00 - 23:59:59	645	4.0%	0.18	2.3 MB	4.7%	0.65
Feb 29 00:00:00 - 00:59:59	568	3.5%	0.16	1.7 MB	3.6%	0.49
Feb 29 01:00:00 - 01:59:59	393	2.4%	0.11	1.0 MB	2.2%	0.30
Feb 29 02:00:00 - 02:59:59	373	2.3%	0.10	1.2 MB	2.4%	0.33
Feb 29 03:00:00 - 03:59:59	340	2.1%	0.09	1.3 MB	2.7%	0.37
Feb 29 04:00:00 - 04:15:00	57	0.4%	0.06	172.1 KB	0.3%	0.19
TOTAL: 24:00:00	16184	100.0%	0.19	48.6 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1092	44	0	1048
2	newsfeed.fu-berlin.de	565	29	0	536
3	csiph-out.feeder.erje.net	502	56	8	438
4	news-out.mixmin.net	372	0	3	369
5	news-out.aioe.org	358	144	2	212
6	newsfeed.neva.ru	301	23	8	270
7	csiph-out.feed.uzoreto.com	283	7	1	275
8	feeder.eternal-september.org	261	38	3	220
9	csiph-out.news.weretis.net	248	98	4	146
10	news.freedyn.net	209	7	3	199
11	csiph-out.news.tnetconsulting.net	76	49	3	24
12	newsfeeds.xmission.com	54	7	0	47
13	news-out.neodome.net	28	0	2	26
14	news.samoylyk.net	19	0	4	15
15	nntp.pasdenom.info	15	0	0	15
16	news.fcku.it	5	3	0	2
17	news.swapon.de	4	0	1	3
TOTAL: 17		4392	505	42	3845

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bbs.synchronet	84
alt.computer.workshop	67
alt.politics.usa	52
mozilla.support.seamonkey	20
alt.survival	14
lada.talk	14
it-alt.sport.calcio.juventus	14
alt.music.pink-floyd.publius	10
mozilla.support.firefox	10
mozilla.dev.apps.seamonkey	9
harvard.forsale	9
alt.politics	8
mozilla.general	8
alt.windows7.general	7
att.jobs	7
at.jobs	6
cakewalk.audio	6
alt.eunuchs	6
it-alt.comp.software.mozilla	6
alt.forsale.seattle	5
TOTAL: 106	505

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1991
EMP (phn path)	451
EMP (phl)	211
EMP (phn nph)	100
0	52
HTML Multipart	19
Too many newsgroups	15
Scoring filter	13
Binary Payload	3
Too many newsgroups (meow)	2
Binary: misplaced binary	2
TOTAL: 11	2859

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	189
	csiph-out.news.xs4all.nl	104
	csiph-out.feed.uzoreto.com	59
	newsfeeds.xmission.com	33
	feeder.eternal-september.org	23
	news-out.neodome.net	19
	csiph-out.news.weretis.net	19
	news.samoylyk.net	15
	news.freedyn.net	8
	news-out.mixmin.net	3
	TOTAL: 11	474
Including strange strings
	newsfeed.fu-berlin.de	310
	news-out.aioe.org	129
	csiph-out.news.weretis.net	29
	newsfeeds.xmission.com	12
	news-out.mixmin.net	7
	news.freedyn.net	5
	newsfeed.neva.ru	4
	csiph-out.feeder.erje.net	3
	news.swapon.de	3
	csiph-out.feed.uzoreto.com	3
	TOTAL: 12	509
No colon-space in header
	csiph-out.news.xs4all.nl	2
	csiph-out.feeder.erje.net	1
	TOTAL: 2	3
TOTAL: 4		1031

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19087	914	15269	1	0	4%	23:59:56
2	news.samoylyk.net	16869	556	15586	11	635	3%	23:54:03
3	news.freedyn.net	16008	527	15013	48	0	3%	23:59:41
4	fu-berlin.de	15276	525	14751	0	0	3%	23:59:24
5	news.bbs.nz	30232	425	15181	7	0	1%	23:59:55
6	tnet	16748	186	15406	0	0	1%	23:50:08
7	usenet.pasdenom.info	16224	102	15339	104	14	0%	23:59:56
8	newsfeed.xs4all.nl	15757	46	15168	6	103	0%	23:55:21
9	neva.ru	14552	32	14261	161	0	0%	23:59:56
10	aioe	11926	23	11424	0	0	0%	23:57:07
11	xmission	12747	9	11391	1	0	0%	23:59:56
12	news.mixmin.net	15113	7	14540	0	0	0%	23:59:56
13	neodome.net	16616	6	16054	0	0	0%	23:52:16
14	weretis.net	14743	4	14640	1	0	0%	23:59:55
15	news.uzoreto.com	15815	4	15510	9	0	0%	23:50:07
16	news.swapon.de	1505	4	1480	0	0	0%	23:50:33
17	eternal-september	13911	3	13330	1	0	0%	23:59:04
18	erje	15376	1	14963	20	0	0%	23:59:52
19	news.fcku.it	1716	0	1714	0	0	0%	21:50:39
TOTAL: 19		280221	3374	251020	370	752	1%	452:57:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.8 MB	37.9 KB	6.8 MB	0.1 KB/s	7.6 KB	23:59:56
2	news.freedyn.net	5.9 MB	63.7 KB	6.0 MB	0.1 KB/s	10.6 KB	23:59:41
3	fu-berlin.de	5.8 MB	0.0 KB	5.8 MB	0.1 KB/s	11.3 KB	23:59:24
4	news.samoylyk.net	5.8 MB	66.1 KB	5.8 MB	0.1 KB/s	10.5 KB	23:54:03
5	news.bbs.nz	1.2 MB	51.9 KB	1.2 MB	0.0 KB/s	2.9 KB	23:59:55
6	tnet	436.7 KB	0.0 KB	436.7 KB	0.0 KB/s	2.3 KB	23:50:08
7	usenet.pasdenom.info	376.5 KB	320.7 KB	697.1 KB	0.0 KB/s	3.4 KB	23:59:56
8	newsfeed.xs4all.nl	155.8 KB	58.0 KB	213.8 KB	0.0 KB/s	4.1 KB	23:55:21
9	neva.ru	66.4 KB	351.8 KB	418.2 KB	0.0 KB/s	2.2 KB	23:59:56
10	aioe	51.1 KB	0.0 KB	51.1 KB	0.0 KB/s	2.2 KB	23:57:07
11	news.mixmin.net	40.7 KB	0.0 KB	40.7 KB	0.0 KB/s	5.8 KB	23:59:56
12	xmission	11.8 KB	2.2 KB	13.9 KB	0.0 KB/s	1.4 KB	23:59:56
13	neodome.net	6.5 KB	0.0 KB	6.5 KB	0.0 KB/s	1.1 KB	23:52:16
14	weretis.net	5.0 KB	1.2 KB	6.2 KB	0.0 KB/s	1.2 KB	23:59:55
15	news.uzoreto.com	4.1 KB	77.2 KB	81.3 KB	0.0 KB/s	6.3 KB	23:50:07
16	news.swapon.de	4.0 KB	0.0 KB	4.0 KB	0.0 KB/s	1.0 KB	23:50:33
17	eternal-september	3.0 KB	1.1 KB	4.1 KB	0.0 KB/s	1.0 KB	23:59:04
18	erje	1.1 KB	378.8 KB	380.0 KB	0.0 KB/s	18.1 KB	23:59:52
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:50:39
TOTAL: 19		26.6 MB	1.4 MB	27.9 MB	0.0 KB/s	7.6 KB	452:57:45

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	89-24-44-58.nat.epc.tmcz.cz	6	18	37.3 KB	3	0	0	00:52:01
2	a131033.upc-a.chello.nl	25	12	17.7 KB	4	0	0	06:16:22
3	68-190-164-53.dhcp.eucl.wi.charter.com	25	3	3.1 KB	3	4	4	07:45:03
4	ip72-222-200-117.ph.ph.cox.net	4	2	2.6 KB	1	0	0	02:00:37
TOTAL: 4		60	35	60.7 KB	11	4	4	40:57:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.nat.epc.tmcz.cz	6	18	37.3 KB	3	0	0	00:52:01
2	*.upc-a.chello.nl	25	12	17.7 KB	4	0	0	06:16:22
3	*.dhcp.eucl.wi.charter.com	25	3	3.1 KB	3	4	4	07:45:03
4	*.ph.ph.cox.net	4	2	2.6 KB	1	0	0	02:00:37
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
6	unresolved	75	0	0.0 KB	0	0	0	24:03:47
TOTAL: 6		136	35	60.7 KB	11	4	4	40:57:54

NNRP auth users [Top 20]:

User	Conn
ctomczyk	6
ohlandl	4
TOTAL: 2	10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.769	1.129	0.000	24:03:36
68-190-164-53.dhcp.eucl.wi.charter.com	1.656	0.455	0.012	07:45:03
a131033.upc-a.chello.nl	1.840	0.427	0.000	06:16:22
ip72-222-200-117.ph.ph.cox.net	0.424	0.108	0.000	02:00:37
89-24-44-58.nat.epc.tmcz.cz	0.488	0.118	0.014	00:52:01
192.241.231.243	0.069	0.008	0.000	00:00:09
scan-141.security.ipip.net	0.057	0.024	0.000	00:00:01
92.53.90.43	0.072	0.008	0.000	00:00:00
TOTAL: 8	9.375	2.277	0.026	40:57:54

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	73
92.53.90.43	1
192.241.231.243	1
scan-141.security.ipip.net	1
TOTAL: 4	76

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-190-164-53.dhcp.eucl.wi.charter.com	8	0
ip72-222-200-117.ph.ph.cox.net	4	0
89-24-44-58.nat.epc.tmcz.cz	1	1
91.187.33.87	1	1
92.53.90.43	1	1
TOTAL: 5	15	3

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.lang.javascript	18
2	comp.sys.ibm.ps2.hardware	17
TOTAL: 2		35

Daily Usenet report for csiph.com

Feb 28 04:15:00 -- Feb 29 04:15:00