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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19247	46.7%	5.0 MB	62.6%
innd	17742	43.0%	2.3 MB	28.7%
innfeed	3461	8.4%	629.6 KB	7.7%
nnrpd	755	1.8%	82.6 KB	1.0%
nocem	22	0.1%	2.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41228	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	322419	57.9%
Negative hits	215720	38.7%
Do not exist	18636	3.3%
Cache misses	226	0.0%
TOTAL: 4	557001	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.089	0.0%	31	0.000	2.871	14.400
article cleanup	00:00:00.107	0.0%	18205	0.000	0.006	0.034
article logging	00:00:00.259	0.0%	18692	0.000	0.014	0.250
article parse	00:00:00.862	0.0%	62379	0.000	0.014	0.117
article write	00:00:06.594	0.0%	15571	0.142	0.423	10.923
artlog/artcncl	00:00:00.002	0.0%	31	0.000	0.065	1.000
artlog/artparse	00:00:00.008	0.0%	582	0.000	0.014	0.125
data move	00:00:00.172	0.0%	527875	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.004	0.0%	30	0.000	0.133	1.000
hishave/artcncl	00:00:00.002	0.0%	31	0.000	0.065	0.200
hishave/artparse	00:00:00.003	0.0%	578	0.000	0.005	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.629	0.0%	559689	0.002	0.006	0.132
history sync	00:00:00.733	0.0%	367	0.000	1.997	6.000
history write	00:00:08.602	0.0%	18181	0.017	0.473	3.268
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.323	0.0%	578	0.000	0.559	6.000
idle	23:59:26.240	99.9%	536215	84.825	161.066	614.212
nntp read	00:00:02.011	0.0%	536396	0.001	0.004	0.007
overview write	00:00:23.009	0.0%	15571	0.105	1.478	18.462
perl filter	00:00:24.172	0.0%	18175	1.081	1.330	1.808
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.256	0.0%	31164	0.000	0.008	0.056
TOTAL: 24:01:09.455	24:00:37.077	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	15947	0.000	0.002	0.024
article prepare	00:00:00.002	0.0%	16652	0.000	0.000	0.004
article read	00:00:00.309	0.0%	2074	0.000	0.149	15.000
backlog stats	00:00:00.180	0.0%	368872	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.296	0.0%	290250	0.001	0.004	0.011
data write	00:00:01.556	0.0%	277567	0.000	0.006	0.014
idle	23:54:27.250	100.0%	368872	145.639	233.326	734.377
status file	00:00:00.159	0.0%	247	0.000	0.644	2.000
TOTAL: 23:54:38.315	23:54:30.787	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	24:06:38.008	100.0%	625	327.667	138876.813	600155.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	1377	0.000	0.005	0.182
readart	00:00:00.559	0.0%	46	0.000	12.152	42.000
TOTAL: 24:06:58.085	24:06:38.574	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	15202	4106	11067	29	27%	24:32:13
2	news-out.aioe.org	3	15356	3117	12171	68	20%	24:09:54
3	feeder.eternal-september.org	8	16257	1722	14317	218	10%	22:40:32
4	csiph-out.news.weretis.net	2	35731	1047	34439	245	2%	54:36:33
5	csiph-out.feeder.erje.net	1440	76542	934	75213	395	1%	118:51:32
6	csiph-out.news.xs4all.nl	861	17025	910	15141	974	5%	71:27:16
7	newsfeed.neva.ru	146	17818	866	16671	281	4%	72:04:28
8	news-out.mixmin.net	2	16527	818	15350	359	4%	23:30:03
9	newsfeed.fu-berlin.de	10	15263	773	14337	153	5%	25:05:48
10	csiph-out.news.tnetconsulting.net	1	23346	412	22899	35	1%	33:50:17
11	news.freedyn.net	2	18103	283	17631	189	1%	23:59:32
12	news.fcku.it	5	2102	271	1829	2	12%	22:36:59
13	csiph-out.feed.uzoreto.com	859	23654	174	23256	224	0%	71:49:45
14	news-out.neodome.net	2	17262	112	17106	44	0%	25:26:35
15	nntp.pasdenom.info	2	11175	40	11113	22	0%	23:58:32
16	news.swapon.de	3	1621	31	1584	6	1%	23:53:06
17	localhost	46	20	20	0	0	100%	00:00:04
18	news.samoylyk.net	3	15700	5	15688	7	0%	25:43:24
19	news.bbs.nz	2	15307	2	15305	0	0%	23:59:53
20	xanadu-bbs.net	100	435	0	435	0	0%	78:24:43
TOTAL: 20		3500	354446	15643	335552	3251	4%	790:41:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.7 MB	0.0 KB	1.6 MB	13.3 MB	88%	3.3 KB
2	news-out.aioe.org	8.0 MB	4.3 KB	287.7 KB	8.3 MB	96%	2.7 KB
3	csiph-out.feeder.erje.net	7.1 MB	11.7 KB	10.9 MB	18.0 MB	39%	13.8 KB
4	feeder.eternal-september.org	4.4 MB	2.9 KB	1.4 MB	5.8 MB	75%	3.1 KB
5	csiph-out.news.weretis.net	3.1 MB	8.9 KB	2.3 MB	5.4 MB	57%	4.3 KB
6	csiph-out.news.xs4all.nl	2.7 MB	4.4 KB	15.3 MB	18.0 MB	14%	9.8 KB
7	news-out.mixmin.net	2.2 MB	4.4 KB	725.5 KB	2.9 MB	75%	2.5 KB
8	newsfeed.neva.ru	2.0 MB	4.4 KB	972.4 KB	3.0 MB	67%	2.6 KB
9	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	437.0 KB	2.3 MB	81%	2.6 KB
10	csiph-out.news.tnetconsulting.net	1.2 MB	3.0 KB	403.5 KB	1.6 MB	75%	3.8 KB
11	news.freedyn.net	1.1 MB	4.4 KB	1.3 MB	2.4 MB	46%	5.2 KB
12	csiph-out.feed.uzoreto.com	556.2 KB	4.3 KB	6.2 MB	6.8 MB	8%	17.4 KB
13	news.fcku.it	443.2 KB	0.0 KB	2.2 KB	445.4 KB	99%	1.6 KB
14	news-out.neodome.net	228.2 KB	0.0 KB	1.7 MB	1.9 MB	11%	12.7 KB
15	nntp.pasdenom.info	89.7 KB	0.0 KB	36.5 KB	126.2 KB	71%	2.0 KB
16	news.swapon.de	81.8 KB	2.9 KB	2.8 MB	2.8 MB	2%	78.7 KB
17	localhost	32.2 KB	0.0 KB	0.0 KB	32.2 KB	100%	1.6 KB
18	news.samoylyk.net	12.8 KB	1.5 KB	601.0 KB	615.4 KB	2%	51.3 KB
19	news.bbs.nz	3.4 KB	0.0 KB	0.0 KB	3.4 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		46.9 MB	57.1 KB	46.9 MB	93.8 MB	49%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 22 04:15:00 - 04:59:59	231	1.5%	0.09	653.6 KB	1.4%	0.24
Jan 22 05:00:00 - 05:59:59	287	1.8%	0.08	1008.3 KB	2.1%	0.28
Jan 22 06:00:00 - 06:59:59	354	2.3%	0.10	1.0 MB	2.2%	0.29
Jan 22 07:00:00 - 07:59:59	531	3.4%	0.15	2.0 MB	4.3%	0.57
Jan 22 08:00:00 - 08:59:59	615	4.0%	0.17	1.8 MB	3.9%	0.52
Jan 22 09:00:00 - 09:59:59	590	3.8%	0.16	1.6 MB	3.4%	0.45
Jan 22 10:00:00 - 10:59:59	732	4.7%	0.20	2.1 MB	4.5%	0.59
Jan 22 11:00:00 - 11:59:59	780	5.0%	0.22	2.2 MB	4.8%	0.64
Jan 22 12:00:00 - 12:59:59	800	5.1%	0.22	2.5 MB	5.3%	0.70
Jan 22 13:00:00 - 13:59:59	854	5.5%	0.24	2.5 MB	5.3%	0.70
Jan 22 14:00:00 - 14:59:59	784	5.0%	0.22	2.3 MB	5.0%	0.67
Jan 22 15:00:00 - 15:59:59	913	5.9%	0.25	2.7 MB	5.8%	0.77
Jan 22 16:00:00 - 16:59:59	993	6.4%	0.28	2.6 MB	5.5%	0.73
Jan 22 17:00:00 - 17:59:59	890	5.7%	0.25	2.3 MB	5.0%	0.67
Jan 22 18:00:00 - 18:59:59	815	5.2%	0.23	2.3 MB	5.0%	0.66
Jan 22 19:00:00 - 19:59:59	850	5.5%	0.24	2.7 MB	5.8%	0.77
Jan 22 20:00:00 - 20:59:59	909	5.8%	0.25	2.5 MB	5.4%	0.72
Jan 22 21:00:00 - 21:59:59	829	5.3%	0.23	2.3 MB	5.0%	0.66
Jan 22 22:00:00 - 22:59:59	677	4.4%	0.19	1.9 MB	4.2%	0.55
Jan 22 23:00:00 - 23:59:59	599	3.9%	0.17	2.6 MB	5.5%	0.73
Jan 23 00:00:00 - 00:59:59	451	2.9%	0.13	1.5 MB	3.2%	0.43
Jan 23 01:00:00 - 01:59:59	402	2.6%	0.11	1.2 MB	2.5%	0.33
Jan 23 02:00:00 - 02:59:59	320	2.1%	0.09	957.4 KB	2.0%	0.27
Jan 23 03:00:00 - 03:59:59	264	1.7%	0.07	1.0 MB	2.3%	0.30
Jan 23 04:00:00 - 04:15:00	80	0.5%	0.09	250.0 KB	0.5%	0.28
TOTAL: 24:00:00	15550	100.0%	0.18	46.6 MB	100.0%	0.55

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	977	47	2	928
2	newsfeed.fu-berlin.de	492	46	0	446
3	csiph-out.feeder.erje.net	402	98	5	299
4	news-out.mixmin.net	372	9	2	361
5	newsfeed.neva.ru	277	73	3	201
6	feeder.eternal-september.org	233	21	2	210
7	csiph-out.feed.uzoreto.com	223	9	2	212
8	csiph-out.news.weretis.net	213	101	4	108
9	news.freedyn.net	169	3	2	164
10	news-out.aioe.org	165	52	2	111
11	news-out.neodome.net	47	4	1	42
12	newsfeeds.xmission.com	31	2	0	29
13	csiph-out.news.tnetconsulting.net	22	18	1	3
14	nntp.pasdenom.info	22	0	0	22
15	news.swapon.de	12	0	1	11
16	news.samoylyk.net	7	0	1	6
17	news.fcku.it	2	2	0	0
TOTAL: 17		3666	485	28	3153

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	138
lada.talk	32
mozilla.support.thunderbird	29
spline.eisfair	22
lada.auto	19
mozilla.support.seamonkey	19
it-alt.sport.calcio.juventus	17
mozilla.support.firefox	15
oesterreich.tratsch	12
alt.windows7.general	12
alt.binaries.ebooks	11
mozilla.general	11
alt.survival	10
alt.politics.usa	10
alt.binaries.pictures.purebeauty	8
alt.religion	6
mozilla.support.bugzilla	5
alt.tv.star-trek	5
alt.net.dn42.users	5
mozilla.dev.platform	4
TOTAL: 82	485

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1452
EMP (phn path)	308
EMP (phn nph)	137
EMP (phl)	68
0	60
HTML Multipart	41
Too many newsgroups	13
Scoring filter	2
HTML file attachment	2
Binary: misplaced binary	2
TOTAL: 10	2085

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	2
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	198
	csiph-out.news.xs4all.nl	184
	csiph-out.feed.uzoreto.com	81
	news-out.neodome.net	31
	newsfeeds.xmission.com	27
	csiph-out.news.weretis.net	20
	news.freedyn.net	10
	feeder.eternal-september.org	9
	news.samoylyk.net	6
	news.swapon.de	5
	TOTAL: 13	578
Including strange strings
	newsfeed.fu-berlin.de	345
	news-out.aioe.org	98
	csiph-out.news.weretis.net	11
	news-out.mixmin.net	8
	news.swapon.de	6
	csiph-out.news.xs4all.nl	5
	csiph-out.feeder.erje.net	4
	newsfeed.neva.ru	3
	newsfeeds.xmission.com	2
	news.freedyn.net	1
	TOTAL: 11	484
No colon-space in header
	csiph-out.news.xs4all.nl	4
	TOTAL: 1	4
TOTAL: 5		1113

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19947	1754	13794	2	0	8%	23:59:55
2	news.samoylyk.net	16013	426	15119	0	285	2%	23:59:54
3	fu-berlin.de	14694	420	14274	0	0	2%	23:59:54
4	news.freedyn.net	15548	419	14817	11	0	2%	23:59:54
5	news.bbs.nz	29494	395	14738	5	0	1%	23:59:54
6	usenet.pasdenom.info	15603	130	14950	23	16	0%	23:59:54
7	tnet	16297	105	15036	2	0	0%	23:59:55
8	aioe	11826	58	11454	0	0	0%	23:59:54
9	neva.ru	14164	54	13906	99	0	0%	23:59:50
10	newsfeed.xs4all.nl	14965	37	14466	6	98	0%	23:59:54
11	neodome.net	15788	28	15327	0	0	0%	23:59:54
12	news.mixmin.net	14565	24	14127	0	0	0%	23:59:46
13	xmission	14444	24	11031	0	0	0%	23:59:48
14	weretis.net	14140	21	13974	1	0	0%	23:59:53
15	news.swapon.de	1619	21	1577	0	0	1%	23:54:04
16	eternal-september	13585	20	13148	0	19	0%	23:59:54
17	erje	14740	19	14485	24	0	0%	23:59:53
18	news.uzoreto.com	15167	5	14913	32	0	0%	23:59:54
19	news.fcku.it	1690	0	1683	0	0	0%	22:04:26
TOTAL: 19		274289	3960	242819	205	418	1%	453:56:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.2 MB	12.7 KB	9.2 MB	0.1 KB/s	5.4 KB	23:59:55
2	news.samoylyk.net	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	13.2 KB	23:59:54
3	news.freedyn.net	5.5 MB	17.2 KB	5.5 MB	0.1 KB/s	13.1 KB	23:59:54
4	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	13.4 KB	23:59:54
5	news.bbs.nz	1.1 MB	8.2 KB	1.1 MB	0.0 KB/s	2.8 KB	23:59:54
6	usenet.pasdenom.info	432.2 KB	74.0 KB	506.2 KB	0.0 KB/s	3.3 KB	23:59:54
7	tnet	304.4 KB	9.8 KB	314.2 KB	0.0 KB/s	2.9 KB	23:59:55
8	aioe	140.8 KB	0.0 KB	140.8 KB	0.0 KB/s	2.4 KB	23:59:54
9	neva.ru	125.1 KB	282.1 KB	407.2 KB	0.0 KB/s	2.7 KB	23:59:50
10	newsfeed.xs4all.nl	104.7 KB	31.7 KB	136.4 KB	0.0 KB/s	3.2 KB	23:59:54
11	neodome.net	99.6 KB	0.0 KB	99.6 KB	0.0 KB/s	3.6 KB	23:59:54
12	news.mixmin.net	41.9 KB	0.0 KB	41.9 KB	0.0 KB/s	1.7 KB	23:59:46
13	xmission	38.4 KB	0.0 KB	38.4 KB	0.0 KB/s	1.6 KB	23:59:48
14	eternal-september	35.9 KB	0.0 KB	35.9 KB	0.0 KB/s	1.8 KB	23:59:54
15	erje	34.9 KB	46.6 KB	81.5 KB	0.0 KB/s	1.9 KB	23:59:53
16	weretis.net	34.7 KB	23.5 KB	58.2 KB	0.0 KB/s	2.6 KB	23:59:53
17	news.swapon.de	33.9 KB	0.0 KB	33.9 KB	0.0 KB/s	1.6 KB	23:54:04
18	news.uzoreto.com	23.6 KB	109.8 KB	133.4 KB	0.0 KB/s	3.6 KB	23:59:54
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:04:26
TOTAL: 19		28.1 MB	615.6 KB	28.7 MB	0.0 KB/s	7.1 KB	453:56:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	71-90-73-253.dhcp.ftbg.wi.charter.com	59	31	58.2 KB	27	20	16	13:53:50
2	a131033.upc-a.chello.nl	29	8	17.2 KB	3	0	0	07:45:58
3	208-71-239-109.cust.centrio.cz	2	5	14.7 KB	2	0	0	00:26:12
4	ip72-222-200-117.ph.ph.cox.net	4	2	1.9 KB	1	0	0	02:00:49
TOTAL: 4		94	46	92.0 KB	33	20	16	24:06:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.ftbg.wi.charter.com	59	31	58.2 KB	27	20	16	13:53:50
2	*.upc-a.chello.nl	29	8	17.2 KB	3	0	0	07:45:58
3	*.cust.centrio.cz	2	5	14.7 KB	2	0	0	00:26:12
4	*.ph.ph.cox.net	4	2	1.9 KB	1	0	0	02:00:49
5	unresolved	5	0	0.0 KB	0	0	0	00:00:06
TOTAL: 5		99	46	92.0 KB	33	20	16	24:06:57

NNRP auth users [Top 20]:

User	Conn
ohlandl	16
ctomczyk	2
TOTAL: 2	18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-90-73-253.dhcp.ftbg.wi.charter.com	4.979	0.982	0.037	13:53:50
a131033.upc-a.chello.nl	2.092	0.307	0.000	07:45:58
ip72-222-200-117.ph.ph.cox.net	0.394	0.100	0.000	02:00:49
208-71-239-109.cust.centrio.cz	0.220	0.024	0.004	00:26:12
94.165.95.218	0.138	0.016	0.000	00:00:02
94.165.14.136	0.070	0.008	0.000	00:00:01
94.162.182.195	0.068	0.007	0.000	00:00:01
94.162.159.64	0.075	0.000	0.000	00:00:01
TOTAL: 8	8.036	1.444	0.041	24:06:57

Curious NNRP server explorers [Top 20]:

System	Conn
94.165.95.218	2
94.162.159.64	1
94.162.182.195	1
94.165.14.136	1
TOTAL: 4	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-90-73-253.dhcp.ftbg.wi.charter.com	12	0
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	16	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	44	95.7%
2	news	2	4.3%
TOTAL: 2		46	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	39
2	comp.lang.javascript	5
3	news.admin.peering	2
TOTAL: 3		46

Daily Usenet report for csiph.com

Jan 22 04:15:00 -- Jan 23 04:15:00