Daily Usenet report for csiph.com: Jul 19 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18721	45.7%	4.7 MB	60.7%
innd	17853	43.6%	2.3 MB	29.9%
innfeed	3639	8.9%	663.9 KB	8.3%
nnrpd	694	1.7%	74.8 KB	0.9%
nocem	53	0.1%	6.4 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40961	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	284980	57.2%
Negative hits	195187	39.2%
Do not exist	18111	3.6%
Cache misses	267	0.1%
TOTAL: 4	498545	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.267	0.0%	23	0.000	11.609	23.000
article cleanup	00:00:00.103	0.0%	17604	0.000	0.006	0.033
article logging	00:00:00.253	0.0%	18147	0.000	0.014	0.159
article parse	00:00:00.786	0.0%	56842	0.000	0.014	0.046
article write	00:00:05.779	0.0%	14328	0.158	0.403	10.328
artlog/artcncl	00:00:00.000	0.0%	23	0.000	0.000	0.000
artlog/artparse	00:00:00.010	0.0%	536	0.000	0.019	0.333
data move	00:00:00.187	0.0%	473132	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.026	0.0%	23	0.000	1.130	2.125
hishave/artcncl	00:00:00.005	0.0%	23	0.000	0.217	0.400
hishave/artparse	00:00:00.002	0.0%	530	0.000	0.004	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.880	0.0%	498926	0.001	0.008	0.188
history sync	00:00:00.746	0.0%	381	0.000	1.958	6.500
history write	00:00:08.375	0.0%	17605	0.120	0.476	1.573
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.269	0.0%	530	0.000	0.508	6.000
idle	23:59:19.813	99.9%	480484	105.688	179.735	447.269
nntp read	00:00:01.849	0.0%	479942	0.001	0.004	0.007
overview write	00:00:25.319	0.0%	14328	0.098	1.767	28.953
perl filter	00:00:22.954	0.0%	17599	0.895	1.304	2.109
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.217	0.0%	28709	0.000	0.008	0.033
TOTAL: 24:01:03.978	24:00:30.840	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	14559	0.000	0.003	0.033
article prepare	00:00:00.004	0.0%	16264	0.000	0.000	0.017
article read	00:00:00.504	0.0%	2334	0.000	0.216	15.667
backlog stats	00:00:00.168	0.0%	329564	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.191	0.0%	263402	0.000	0.005	0.011
data write	00:00:01.443	0.0%	249926	0.000	0.006	0.012
idle	23:54:53.963	100.0%	329564	159.877	261.236	625.248
status file	00:00:00.196	0.0%	319	0.000	0.614	2.000
TOTAL: 23:55:04.688	23:54:57.507	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	36:55:05.715	99.8%	394	226.333	337324.150	600178.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	901	0.000	0.004	0.143
readart	00:00:00.250	0.0%	16	0.000	15.625	42.000
TOTAL: 37:00:24.611	36:55:05.969	99.8%	-	-	-	-

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	13853	3705	10073	75	26%	24:26:56
2	news-out.aioe.org	3	13674	3353	10234	87	24%	24:08:38
3	feeder.eternal-september.org	1	17123	1908	14983	232	11%	25:26:20
4	newsfeed.fu-berlin.de	5	16514	947	15392	175	5%	29:13:24
5	news-out.mixmin.net	1	15572	845	14079	648	5%	23:59:49
6	csiph-out.news.weretis.net	5	25924	799	24780	345	3%	43:15:37
7	csiph-out.feeder.erje.net	1440	73535	777	72267	491	1%	118:41:25
8	csiph-out.news.xs4all.nl	861	16647	567	15299	781	3%	71:27:12
9	newsfeed.neva.ru	142	15761	539	14952	270	3%	71:48:06
10	csiph-out.feed.uzoreto.com	864	23571	373	22452	746	1%	71:12:23
11	news-out.neodome.net	2	15945	206	15706	33	1%	23:59:52
12	nntp.pasdenom.info	2	8837	138	8685	14	1%	23:59:43
13	news.fcku.it	12	1219	90	1129	0	7%	21:54:26
14	csiph-out.news.tnetconsulting.net	2	13503	45	13419	39	0%	23:59:57
15	news.swapon.de	6	1334	35	1293	6	2%	24:00:22
16	news.samoylyk.net	3	13794	11	13783	0	0%	24:54:25
17	news.bbs.nz	1	12926	5	12921	0	0%	23:59:52
18	localhost	28	2	2	0	0	100%	00:00:04
19	xanadu-bbs.net	32	365	0	365	0	0%	20:31:00
TOTAL: 19		3412	300099	14345	281812	3942	4%	690:59:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	10.7 MB	2.4 KB	4.7 MB	15.4 MB	69%	4.2 KB
2	news-out.aioe.org	8.0 MB	0.0 KB	261.0 KB	8.2 MB	96%	2.4 KB
3	csiph-out.feeder.erje.net	5.5 MB	0.0 KB	7.3 MB	12.8 MB	42%	10.3 KB
4	feeder.eternal-september.org	5.2 MB	8.6 KB	1.5 MB	6.7 MB	77%	3.2 KB
5	news-out.mixmin.net	2.9 MB	0.0 KB	1.2 MB	4.1 MB	70%	2.8 KB
6	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	502.1 KB	2.8 MB	82%	2.5 KB
7	csiph-out.news.weretis.net	2.0 MB	2.5 KB	3.2 MB	5.2 MB	39%	4.7 KB
8	csiph-out.news.xs4all.nl	1.6 MB	0.0 KB	7.1 MB	8.6 MB	18%	6.5 KB
9	newsfeed.neva.ru	1.5 MB	0.0 KB	747.2 KB	2.3 MB	67%	2.9 KB
10	csiph-out.feed.uzoreto.com	1.5 MB	0.0 KB	18.2 MB	19.8 MB	7%	18.1 KB
11	news-out.neodome.net	534.3 KB	0.0 KB	285.9 KB	820.2 KB	65%	3.4 KB
12	nntp.pasdenom.info	316.5 KB	0.0 KB	20.3 KB	336.8 KB	93%	2.2 KB
13	news.fcku.it	134.7 KB	0.0 KB	0.0 KB	134.7 KB	100%	1.5 KB
14	csiph-out.news.tnetconsulting.net	131.6 KB	0.0 KB	263.9 KB	395.5 KB	33%	4.7 KB
15	news.swapon.de	75.7 KB	0.0 KB	94.3 KB	169.9 KB	44%	4.1 KB
16	news.samoylyk.net	31.1 KB	0.0 KB	0.0 KB	31.1 KB	100%	2.8 KB
17	news.bbs.nz	12.3 KB	0.0 KB	0.0 KB	12.3 KB	100%	2.5 KB
18	localhost	3.7 KB	0.0 KB	0.0 KB	3.7 KB	100%	1.9 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		42.4 MB	13.5 KB	45.3 MB	87.7 MB	48%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 19 04:15:00 - 04:59:59	232	1.6%	0.09	683.5 KB	1.6%	0.25
Jul 19 05:00:00 - 05:59:59	337	2.4%	0.09	1.3 MB	3.1%	0.37
Jul 19 06:00:00 - 06:59:59	480	3.4%	0.13	2.2 MB	5.1%	0.61
Jul 19 07:00:00 - 07:59:59	496	3.5%	0.14	1.7 MB	3.9%	0.47
Jul 19 08:00:00 - 08:59:59	641	4.5%	0.18	1.7 MB	4.1%	0.49
Jul 19 09:00:00 - 09:59:59	622	4.3%	0.17	1.9 MB	4.4%	0.53
Jul 19 10:00:00 - 10:59:59	651	4.5%	0.18	1.8 MB	4.3%	0.52
Jul 19 11:00:00 - 11:59:59	582	4.1%	0.16	1.7 MB	4.0%	0.48
Jul 19 12:00:00 - 12:59:59	758	5.3%	0.21	2.3 MB	5.4%	0.65
Jul 19 13:00:00 - 13:59:59	755	5.3%	0.21	2.0 MB	4.6%	0.56
Jul 19 14:00:00 - 14:59:59	731	5.1%	0.20	2.0 MB	4.7%	0.57
Jul 19 15:00:00 - 15:59:59	760	5.3%	0.21	2.1 MB	4.9%	0.59
Jul 19 16:00:00 - 16:59:59	734	5.1%	0.20	2.3 MB	5.5%	0.66
Jul 19 17:00:00 - 17:59:59	793	5.5%	0.22	2.1 MB	5.0%	0.60
Jul 19 18:00:00 - 18:59:59	826	5.8%	0.23	2.5 MB	5.8%	0.70
Jul 19 19:00:00 - 19:59:59	764	5.3%	0.21	1.9 MB	4.6%	0.55
Jul 19 20:00:00 - 20:59:59	796	5.6%	0.22	2.1 MB	4.9%	0.59
Jul 19 21:00:00 - 21:59:59	710	5.0%	0.20	2.2 MB	5.2%	0.63
Jul 19 22:00:00 - 22:59:59	564	3.9%	0.16	1.6 MB	3.8%	0.45
Jul 19 23:00:00 - 23:59:59	502	3.5%	0.14	1.6 MB	3.7%	0.45
Jul 20 00:00:00 - 00:59:59	477	3.3%	0.13	1.6 MB	3.9%	0.47
Jul 20 01:00:00 - 01:59:59	338	2.4%	0.09	880.7 KB	2.0%	0.24
Jul 20 02:00:00 - 02:59:59	346	2.4%	0.10	1.1 MB	2.6%	0.31
Jul 20 03:00:00 - 03:59:59	325	2.3%	0.09	912.6 KB	2.1%	0.25
Jul 20 04:00:00 - 04:15:00	103	0.7%	0.11	414.4 KB	1.0%	0.46
TOTAL: 24:00:00	14323	100.0%	0.17	42.4 MB	100.0%	0.50

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	791	27	0	764
2	csiph-out.feed.uzoreto.com	760	9	0	751
3	news-out.mixmin.net	666	1	0	665
4	newsfeed.fu-berlin.de	577	29	0	548
5	csiph-out.news.weretis.net	376	74	2	300
6	csiph-out.feeder.erje.net	358	48	0	310
7	newsfeed.neva.ru	279	21	0	258
8	feeder.eternal-september.org	218	48	2	168
9	news-out.aioe.org	175	57	0	118
10	newsfeeds.xmission.com	81	8	1	72
11	csiph-out.news.tnetconsulting.net	34	26	0	8
12	news-out.neodome.net	33	3	0	30
13	news.swapon.de	16	0	0	16
14	nntp.pasdenom.info	13	0	0	13
15	news.samoylyk.net	1	0	0	1
TOTAL: 15		4378	351	5	4022

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	49
scinet.stats	26
alt.survival	26
mozilla.support.thunderbird	24
alt.bbs.synchronet	23
spline.eisfair	20
alt.politics	13
alt.politics.usa	11
lada.talk	10
alt.binaries.pictures.purebeauty	9
mozilla.support.seamonkey	9
mozilla.general	8
alt.hack.nl	7
alt.paranormal.spells	7
mozilla.dev.ports.os2	6
it-alt.sport.calcio.juventus	5
alt.magick.folk	5
spline.eisfair.dev	5
rocksolid.russian.religion	4
trollnet.cinema	4
TOTAL: 71	351

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2286
EMP (phn path)	275
EMP (phl)	120
0	82
Too many newsgroups	65
EMP (phn nph)	29
Binary Payload	26
HTML Multipart	25
Scoring filter	24
Binary: misplaced binary	6
Too many newsgroups (low_xpost)	1
TOTAL: 11	2939

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	160
	csiph-out.feed.uzoreto.com	27
	csiph-out.news.xs4all.nl	24
	TOTAL: 3	211
Huge articles
	csiph-out.feed.uzoreto.com	222
	csiph-out.feeder.erje.net	124
	csiph-out.news.xs4all.nl	72
	newsfeeds.xmission.com	66
	csiph-out.news.weretis.net	30
	feeder.eternal-september.org	12
	news-out.neodome.net	3
	news-out.mixmin.net	2
	csiph-out.news.tnetconsulting.net	1
	news.swapon.de	1
	TOTAL: 11	534
Including strange strings
	newsfeed.fu-berlin.de	420
	news-out.aioe.org	95
	news.swapon.de	10
	csiph-out.news.weretis.net	8
	csiph-out.feed.uzoreto.com	5
	news-out.mixmin.net	3
	newsfeeds.xmission.com	2
	csiph-out.feeder.erje.net	1
	TOTAL: 8	544
No colon-space in header
	csiph-out.news.xs4all.nl	5
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	6
TOTAL: 4		1295

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	16390	1845	12476	3	0	11%	23:59:49
2	news.bbs.nz	26885	618	13223	14	0	2%	23:59:49
3	fu-berlin.de	13313	455	12858	0	0	3%	23:59:51
4	news.samoylyk.net	14696	451	13828	3	29	3%	23:59:54
5	news.freedyn.net	14617	123	14199	1	0	0%	23:59:54
6	tnet	14666	96	13762	1	0	0%	23:59:43
7	newsfeed.xs4all.nl	14234	78	13574	33	263	0%	23:59:54
8	neva.ru	13367	72	13096	60	0	0%	23:59:54
9	usenet.pasdenom.info	14252	20	13691	4	0	0%	23:59:18
10	xmission	11041	20	10071	2	0	0%	23:59:54
11	neodome.net	14445	11	14019	0	0	0%	23:59:49
12	erje	13682	11	13352	57	0	0%	23:59:54
13	news.mixmin.net	13146	9	12750	2	0	0%	23:59:54
14	news.uzoreto.com	13920	8	13611	30	0	0%	23:59:54
15	aioe	10294	6	9890	0	0	0%	23:59:44
16	weretis.net	13067	3	12916	3	12	0%	23:59:54
17	eternal-september	12490	2	11933	0	0	0%	23:59:54
18	news.swapon.de	1344	2	1328	0	0	0%	23:36:01
19	news.fcku.it	1068	0	1068	0	0	0%	21:26:38
TOTAL: 19		246917	3830	221645	213	304	1%	452:59:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	8.1 MB	21.5 KB	8.1 MB	0.1 KB/s	4.5 KB	23:59:49
2	fu-berlin.de	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	9.9 KB	23:59:51
3	news.samoylyk.net	4.3 MB	3.9 KB	4.3 MB	0.1 KB/s	9.8 KB	23:59:54
4	news.bbs.nz	1.6 MB	68.3 KB	1.6 MB	0.0 KB/s	2.7 KB	23:59:49
5	news.freedyn.net	845.3 KB	1.8 KB	847.1 KB	0.0 KB/s	6.8 KB	23:59:54
6	newsfeed.xs4all.nl	309.0 KB	178.7 KB	487.7 KB	0.0 KB/s	4.4 KB	23:59:54
7	tnet	214.0 KB	0.6 KB	214.6 KB	0.0 KB/s	2.2 KB	23:59:43
8	neva.ru	150.3 KB	178.9 KB	329.1 KB	0.0 KB/s	2.5 KB	23:59:54
9	usenet.pasdenom.info	69.0 KB	4.6 KB	73.6 KB	0.0 KB/s	3.1 KB	23:59:18
10	xmission	49.7 KB	4.7 KB	54.4 KB	0.0 KB/s	2.5 KB	23:59:54
11	neodome.net	24.7 KB	0.0 KB	24.7 KB	0.0 KB/s	2.2 KB	23:59:49
12	erje	20.6 KB	137.7 KB	158.3 KB	0.0 KB/s	2.3 KB	23:59:54
13	news.mixmin.net	18.7 KB	5.5 KB	24.3 KB	0.0 KB/s	2.2 KB	23:59:54
14	aioe	18.1 KB	0.0 KB	18.1 KB	0.0 KB/s	3.0 KB	23:59:44
15	news.uzoreto.com	16.5 KB	159.9 KB	176.4 KB	0.0 KB/s	4.6 KB	23:59:54
16	weretis.net	15.0 KB	3.4 KB	18.4 KB	0.0 KB/s	3.1 KB	23:59:54
17	eternal-september	3.7 KB	0.0 KB	3.7 KB	0.0 KB/s	1.9 KB	23:59:54
18	news.swapon.de	3.7 KB	0.0 KB	3.7 KB	0.0 KB/s	1.9 KB	23:36:01
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:26:38
TOTAL: 19		20.1 MB	769.3 KB	20.8 MB	0.0 KB/s	5.3 KB	452:59:42

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	071-013-248-209.res.spectrum.com	15	8	16.1 KB	6	2	2	05:22:11
2	a131033.upc-a.chello.nl	24	4	6.3 KB	4	0	0	07:07:50
3	athedsl-152405.home.otenet.gr	2	2	1.8 KB	1	0	0	00:00:03
4	109-178-155-107.pat.ren.cosmote.net	1	2	3.0 KB	1	0	0	00:00:02
TOTAL: 4		42	16	27.2 KB	12	2	2	37:00:23

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	15	8	16.1 KB	6	2	2	05:22:11
2	*.upc-a.chello.nl	24	4	6.3 KB	4	0	0	07:07:50
3	*.pat.ren.cosmote.net	1	2	3.0 KB	1	0	0	00:00:02
4	*.home.otenet.gr	2	2	1.8 KB	1	0	0	00:00:03
5	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:02
6	unresolved	72	0	0.0 KB	0	0	0	24:00:13
TOTAL: 6		115	16	27.2 KB	12	2	2	37:00:23

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.772	1.172	0.000	24:00:13
a131033.upc-a.chello.nl	1.638	0.380	0.000	07:07:50
071-013-248-209.res.spectrum.com	1.250	0.227	0.005	05:22:11
c-73-140-175-55.hsd1.wa.comcast.net	0.067	0.008	0.000	00:30:02
athedsl-152405.home.otenet.gr	0.163	0.031	0.000	00:00:03
109-178-155-107.pat.ren.cosmote.net	0.100	0.017	0.000	00:00:02
TOTAL: 6	7.990	1.835	0.005	37:00:23

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
c-73-140-175-55.hsd1.wa.comcast.net	1
TOTAL: 2	73

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-248-209.res.spectrum.com	8	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 2	9	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Jul 19 04:15:00 -- Jul 20 04:15:00