Daily Usenet report for csiph.com: Sep 15 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	54259	48.6%	7.7 MB	59.5%
nnrpd	32362	29.0%	2.7 MB	20.9%
innd	23211	20.8%	2.3 MB	17.5%
innfeed	1862	1.7%	274.2 KB	2.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	111695	100.0%	12.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	284132	46.5%
Negative hits	268016	43.9%
Do not exist	53417	8.7%
Cache misses	5484	0.9%
TOTAL: 4	611049	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.543	0.0%	222	0.000	2.446	67.000
article cleanup	00:00:00.302	0.0%	53129	0.000	0.006	0.019
article logging	00:00:00.634	0.0%	53579	0.000	0.012	0.032
article parse	00:00:02.189	0.0%	260882	0.002	0.008	0.018
article write	00:00:06.653	0.0%	28289	0.150	0.235	0.338
artlog/artcncl	00:00:00.002	0.0%	205	0.000	0.010	1.000
artlog/artparse	00:00:00.013	0.0%	641	0.000	0.020	1.000
data move	00:00:00.286	0.0%	746113	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	220	0.000	0.009	0.500
hishave/artcncl	00:00:00.001	0.0%	222	0.000	0.005	0.053
hishave/artparse	00:00:00.003	0.0%	641	0.000	0.005	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.505	0.0%	613789	0.000	0.002	0.013
history sync	00:00:00.598	0.0%	349	0.000	1.713	3.500
history write	00:00:14.499	0.0%	53126	0.055	0.273	0.343
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.197	0.0%	639	0.000	0.308	3.000
idle	23:57:44.782	99.8%	736471	28.721	117.133	385.823
nntp read	00:00:02.470	0.0%	735316	0.001	0.003	0.006
overview write	00:00:04.734	0.0%	28289	0.110	0.167	0.409
perl filter	00:01:50.446	0.1%	53126	1.064	2.079	3.590
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.479	0.0%	56578	0.000	0.008	0.025
TOTAL: 24:00:48.350	24:00:10.338	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.059	0.0%	28217	0.000	0.002	0.014
article prepare	00:00:00.001	0.0%	6450	0.000	0.000	0.011
article read	00:00:00.212	0.0%	1294	0.000	0.164	4.000
backlog stats	00:00:00.106	0.0%	316075	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.356	0.0%	241675	0.003	0.006	0.012
data write	00:00:01.855	0.0%	216102	0.001	0.009	0.022
idle	23:53:31.405	100.0%	316075	143.476	272.123	754.531
status file	00:00:00.078	0.0%	178	0.000	0.438	1.000
TOTAL: 23:53:41.543	23:53:35.072	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.173	0.0%	8046	0.000	0.022	0.078
idle	26:47:05.438	99.9%	39503	0.000	2440.965	600239.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.305	0.0%	80027	0.000	0.016	10.551
readart	00:00:00.058	0.0%	91	0.000	0.637	3.294
TOTAL: 26:48:24.533	26:47:06.974	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	147
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	166

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	4	48591	7263	38577	2751	14%	23:56:45
2	au2pb.net	1	31786	5009	26065	712	15%	24:52:00
3	xanadu-bbs.net	1	53083	4719	39120	9244	8%	23:38:39
4	news-out.mixmin.net	4	33856	4062	27252	2542	11%	24:19:24
5	newsfeed.neva.ru	152	67594	3020	55200	9374	4%	71:53:38
6	newsfeeds.xmission.com	1	28915	1829	26819	267	6%	23:04:05
7	newsfeed.fu-berlin.de	64	32362	1317	30731	314	4%	35:12:26
8	nntp.pasdenom.info	4	27502	722	26706	74	2%	22:57:56
9	news.swapon.de	2	2450	286	2152	12	11%	24:36:21
10	localhost	50	14415	0	14415	0	0%	00:01:04
TOTAL: 10		283	340554	28227	287037	25290	8%	274:32:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.7 MB	11.7 KB	17.7 MB	38.4 MB	53%	3.9 KB
2	au2pb.net	12.9 MB	11.7 KB	3.4 MB	16.3 MB	78%	2.9 KB
3	xanadu-bbs.net	12.9 MB	11.3 KB	114.4 MB	127.3 MB	10%	9.3 KB
4	news-out.mixmin.net	12.2 MB	0.0 KB	7.2 MB	19.4 MB	62%	3.0 KB
5	newsfeed.neva.ru	10.0 MB	0.0 KB	76.6 MB	86.6 MB	11%	7.2 KB
6	newsfeeds.xmission.com	5.4 MB	0.0 KB	1.7 MB	7.0 MB	76%	3.4 KB
7	newsfeed.fu-berlin.de	2.9 MB	2.1 KB	822.3 KB	3.7 MB	78%	2.3 KB
8	nntp.pasdenom.info	1.6 MB	2.2 KB	783.0 KB	2.4 MB	67%	3.1 KB
9	news.swapon.de	769.7 KB	0.0 KB	36.5 KB	806.2 KB	95%	2.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		79.2 MB	39.0 KB	222.7 MB	301.9 MB	26%	5.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 15 04:15:00 - 04:59:59	497	1.8%	0.18	1.7 MB	2.2%	0.65
Sep 15 05:00:00 - 05:59:59	628	2.2%	0.17	2.1 MB	2.6%	0.59
Sep 15 06:00:00 - 06:59:59	866	3.1%	0.24	2.3 MB	2.9%	0.66
Sep 15 07:00:00 - 07:59:59	1272	4.5%	0.35	3.5 MB	4.4%	1.00
Sep 15 08:00:00 - 08:59:59	1180	4.2%	0.33	3.3 MB	4.1%	0.93
Sep 15 09:00:00 - 09:59:59	1499	5.3%	0.42	4.2 MB	5.3%	1.19
Sep 15 10:00:00 - 10:59:59	1385	4.9%	0.38	4.1 MB	5.2%	1.16
Sep 15 11:00:00 - 11:59:59	1306	4.6%	0.36	3.5 MB	4.5%	1.01
Sep 15 12:00:00 - 12:59:59	1467	5.2%	0.41	4.1 MB	5.1%	1.15
Sep 15 13:00:00 - 13:59:59	1526	5.4%	0.42	4.0 MB	5.0%	1.13
Sep 15 14:00:00 - 14:59:59	1634	5.8%	0.45	4.5 MB	5.7%	1.29
Sep 15 15:00:00 - 15:59:59	1791	6.3%	0.50	5.0 MB	6.3%	1.41
Sep 15 16:00:00 - 16:59:59	1679	5.9%	0.47	4.7 MB	5.9%	1.33
Sep 15 17:00:00 - 17:59:59	1572	5.6%	0.44	4.3 MB	5.5%	1.23
Sep 15 18:00:00 - 18:59:59	1400	5.0%	0.39	3.7 MB	4.7%	1.05
Sep 15 19:00:00 - 19:59:59	1452	5.1%	0.40	4.0 MB	5.0%	1.13
Sep 15 20:00:00 - 20:59:59	1548	5.5%	0.43	4.0 MB	5.0%	1.13
Sep 15 21:00:00 - 21:59:59	1275	4.5%	0.35	3.4 MB	4.2%	0.95
Sep 15 22:00:00 - 22:59:59	955	3.4%	0.27	3.4 MB	4.3%	0.97
Sep 15 23:00:00 - 23:59:59	797	2.8%	0.22	2.2 MB	2.8%	0.63
Sep 16 00:00:00 - 00:59:59	728	2.6%	0.20	2.2 MB	2.8%	0.62
Sep 16 01:00:00 - 01:59:59	649	2.3%	0.18	1.9 MB	2.4%	0.54
Sep 16 02:00:00 - 02:59:59	511	1.8%	0.14	1.5 MB	1.8%	0.42
Sep 16 03:00:00 - 03:59:59	502	1.8%	0.14	1.4 MB	1.8%	0.40
Sep 16 04:00:00 - 04:15:00	150	0.5%	0.17	454.2 KB	0.6%	0.50
TOTAL: 24:00:00	28269	100.0%	0.33	79.2 MB	100.0%	0.94

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	9365	722	1	8642
2	newsfeed.neva.ru	9294	1819	0	7475
3	feeder.eternal-september.org	2809	342	1	2466
4	news-out.mixmin.net	2574	7	0	2567
5	au2pb.net	714	666	1	47
6	newsfeed.fu-berlin.de	635	43	0	592
7	newsfeeds.xmission.com	275	250	0	25
8	nntp.pasdenom.info	79	64	0	15
9	news.swapon.de	37	0	0	37
TOTAL: 9		25782	3913	3	21866

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1015
lada.talk	234
it-alt.sport.calcio.juventus	189
ut.test	180
alt.politics	126
mailing.freebsd.ports-bugs	121
mailing.freebsd.svn-ports-all	101
mailing.freebsd.svn-ports-head	98
lada.auto	91
pld.cvs.commit	83
free.it.calcio.juventus	62
mailing.freebsd.pkg-fallout	53
alt.survival	50
mailing.freebsd.svn-src-all	49
fa.freebsd.cvs-all	47
mailing.gcc.gcc-cvs	40
mailing.freebsd.svn-src-head	39
alt.windows7.general	38
alt.job	33
mailing.freebsd.questions	33
TOTAL: 258	3913

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16485
EMP (phn path)	2801
EMP (phl)	942
EMP (phn nph)	285
PFM bot misc	127
HTML Multipart	61
Too many newsgroups	47
Binary Image: misplaced jpg	15
HTML post	7
Binary: misplaced zip	2
Binary Payload	2
HTML Image Tags	1
Too many newsgroups (low_xpost)	1
TOTAL: 13	20776

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	508
	news-out.mixmin.net	49
	feeder.eternal-september.org	46
	au2pb.net	14
	newsfeeds.xmission.com	14
	nntp.pasdenom.info	9
	newsfeed.fu-berlin.de	1
	TOTAL: 7	641
Including strange strings
	newsfeed.fu-berlin.de	315
	feeder.eternal-september.org	32
	news-out.mixmin.net	28
	news.swapon.de	25
	xanadu-bbs.net	19
	au2pb.net	16
	newsfeeds.xmission.com	8
	newsfeed.neva.ru	5
	nntp.pasdenom.info	1
	TOTAL: 9	449
TOTAL: 2		1090

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	usenet.pasdenom.info	34182	316	26687	528	0	6	0%	23:59:55
2	au2pb	24323	234	22894	262	1	0	0%	23:59:55
3	xanadu-bbs	25087	66	23415	12	0	9	0%	23:59:55
4	fu-berlin.de	26531	46	26457	28	0	0	0%	23:59:55
5	xmission	32760	17	26232	1	0	1	0%	23:59:55
6	news.mixmin.net	24904	11	24066	15	0	0	0%	23:59:55
7	eternal-september	21528	8	20459	11	0	0	0%	23:59:32
8	neva.ru	26243	4	24996	56	0	0	0%	23:59:55
9	news.swapon.de	2189	0	2175	1	0	0	0%	23:53:33
TOTAL: 9		217747	702	197381	914	1	16	0%	215:52:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	985.1 KB	1.4 MB	2.3 MB	2.8 KB	23:59:55
2	au2pb	962.2 KB	790.7 KB	1.7 MB	3.5 KB	23:59:55
3	xanadu-bbs	487.1 KB	54.5 KB	541.6 KB	6.9 KB	23:59:55
4	fu-berlin.de	354.1 KB	35.6 KB	389.7 KB	5.3 KB	23:59:55
5	news.mixmin.net	51.7 KB	32.0 KB	83.7 KB	3.2 KB	23:59:55
6	xmission	47.9 KB	1.5 KB	49.4 KB	2.7 KB	23:59:55
7	eternal-september	38.4 KB	13.5 KB	51.9 KB	2.7 KB	23:59:32
8	neva.ru	12.7 KB	291.8 KB	304.4 KB	5.1 KB	23:59:55
9	news.swapon.de	0.0 KB	1.9 KB	1.9 KB	1.9 KB	23:53:33
TOTAL: 9		2.9 MB	2.6 MB	5.4 MB	3.4 KB	215:52:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	72	185.0 KB	59	04:39:55
2	x5ce25d86.dyn.telefonica.de	2	17	29.3 KB	1	00:38:21
3	challenger.nielsenmedia.com	13	2	4.0 KB	2	01:45:55
TOTAL: 3		303	91	218.3 KB	62	26:48:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	423	72	185.0 KB	59	18:02:04
2	*.dyn.telefonica.de	2	17	29.3 KB	1	00:38:21
3	*.nielsenmedia.com	13	2	4.0 KB	2	01:45:55
4	*.160.61.188.dynamic.wline.res.cust.swisscom.ch	1	0	0.0 KB	0	00:30:02
5	*.tu.ok.cox.net	8	0	0.0 KB	0	04:01:57
6	*.tampfl.dsl-w.verizon.net	11	0	0.0 KB	0	01:49:57
TOTAL: 6		458	91	218.3 KB	62	26:48:19

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
108.175.241.207	7.594	2.104	0.000	13:22:07
202.89.176.220	26.896	6.165	0.686	04:39:55
ip72-222-107-224.tu.ok.cox.net	0.714	0.182	0.000	04:01:57
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.966	0.162	0.000	01:49:57
challenger.nielsenmedia.com	1.102	0.209	0.000	01:45:55
x5ce25d86.dyn.telefonica.de	0.236	0.053	0.000	00:38:21
113.160.61.188.dynamic.wline.res.cust.swisscom.ch	0.058	0.017	0.000	00:30:02
69.160.37.54	2.050	0.305	0.000	00:00:02
TOTAL: 8	39.616	9.197	0.686	26:48:19

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	106
69.160.37.54	25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	11
ip72-222-107-224.tu.ok.cox.net	8
162.212.88.198	4
113.160.61.188.dynamic.wline.res.cust.swisscom.ch	1
TOTAL: 6	155

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-107-224.tu.ok.cox.net	8	0
challenger.nielsenmedia.com	3	1
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
113.160.61.188.dynamic.wline.res.cust.swisscom.ch	1	0
x5ce25d86.dyn.telefonica.de	1	0
TOTAL: 5	15	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	31
2	comp.sys.ibm.ps2.hardware	18
3	comp.os.vms	17
4	comp.sys.ibm.pc.hardware.storage	6
5	comp.sys.ibm.pc.games.action	5
6	comp.sys.ibm.as400.misc	4
7	comp.os.os2.misc	4
8	comp.lang.awk	2
9	comp.arch.fpga	2
10	comp.os.os2.programmer.misc	1
11	comp.sys.ibm.pc.games.war-historical	1
TOTAL: 11		91

Daily Usenet report for csiph.com

Sep 15 04:15:00 -- Sep 16 04:15:00