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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	50963	47.0%	7.1 MB	57.8%
nnrpd	31781	29.3%	2.6 MB	21.3%
innd	23791	22.0%	2.3 MB	18.5%
innfeed	1833	1.7%	310.2 KB	2.5%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	108369	100.0%	12.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	260967	46.0%
Negative hits	250517	44.2%
Do not exist	50261	8.9%
Cache misses	5011	0.9%
TOTAL: 4	566756	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.230	0.0%	251	0.000	0.916	17.000
article cleanup	00:00:00.267	0.0%	49678	0.000	0.005	0.022
article logging	00:00:00.573	0.0%	50154	0.000	0.011	0.028
article parse	00:00:02.114	0.0%	248852	0.003	0.008	0.016
article write	00:00:05.892	0.0%	25548	0.142	0.231	0.331
artlog/artcncl	00:00:00.002	0.0%	170	0.000	0.012	1.000
artlog/artparse	00:00:00.008	0.0%	651	0.000	0.012	1.000
data move	00:00:00.318	0.0%	698542	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	244	0.000	0.008	0.333
hishave/artcncl	00:00:00.000	0.0%	251	0.000	0.000	0.000
hishave/artparse	00:00:00.001	0.0%	651	0.000	0.002	0.004
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.373	0.0%	566727	0.000	0.002	0.007
history sync	00:00:00.587	0.0%	360	0.000	1.631	3.500
history write	00:00:13.071	0.0%	49671	0.069	0.263	0.343
hiswrite/artcncl	00:00:00.002	0.0%	7	0.000	0.286	1.000
hiswrite/artparse	00:00:00.201	0.0%	649	0.000	0.310	3.000
idle	23:58:01.247	99.8%	688886	27.865	125.247	379.575
nntp read	00:00:02.344	0.0%	688011	0.001	0.003	0.007
overview write	00:00:05.896	0.0%	25548	0.088	0.231	1.939
perl filter	00:01:48.002	0.1%	49671	0.925	2.174	4.385
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.503	0.0%	51096	0.000	0.010	0.029
TOTAL: 24:00:58.084	24:00:22.633	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	25488	0.000	0.003	0.022
article prepare	00:00:00.002	0.0%	6752	0.000	0.000	0.014
article read	00:00:00.196	0.0%	1290	0.000	0.152	23.000
backlog stats	00:00:00.092	0.0%	287847	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.243	0.0%	220815	0.000	0.006	0.011
data write	00:00:01.718	0.0%	196875	0.003	0.009	0.016
idle	23:54:33.309	100.0%	287847	178.796	299.025	708.254
status file	00:00:00.090	0.0%	168	0.000	0.536	2.000
TOTAL: 23:54:40.566	23:54:36.715	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.372	0.0%	13395	0.000	0.028	0.062
idle	32:40:11.266	99.9%	44714	0.000	2630.301	600095.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:18.031	0.0%	173787	0.000	0.104	0.209
readart	00:00:00.009	0.0%	60	0.000	0.150	1.000
TOTAL: 32:41:32.430	32:40:29.678	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	5	46384	5975	37723	2686	12%	24:49:01
2	news-out.mixmin.net	5	32101	5107	23740	3254	15%	24:21:25
3	xanadu-bbs.net	1	50012	4119	36786	9107	8%	24:00:37
4	au2pb.net	1	26832	3922	22293	617	14%	23:35:02
5	newsfeed.neva.ru	146	62383	2193	51502	8688	3%	72:02:32
6	newsfeeds.xmission.com	1	24708	1622	22916	170	6%	22:50:01
7	newsfeed.fu-berlin.de	40	30966	1607	29086	273	5%	32:20:33
8	nntp.pasdenom.info	4	23572	616	22924	32	2%	23:39:59
9	news.swapon.de	1	2372	237	2126	9	9%	21:16:21
10	localhost	48	15016	0	15016	0	0%	00:00:56
TOTAL: 10		252	314346	25398	264112	24836	8%	268:56:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	16.6 MB	0.0 KB	17.7 MB	34.2 MB	48%	4.0 KB
2	news-out.mixmin.net	15.0 MB	10.9 KB	8.4 MB	23.4 MB	64%	2.9 KB
3	xanadu-bbs.net	11.2 MB	2.4 KB	113.2 MB	124.4 MB	9%	9.6 KB
4	au2pb.net	10.6 MB	2.4 KB	3.4 MB	14.0 MB	75%	3.2 KB
5	newsfeed.neva.ru	6.7 MB	2.4 KB	77.7 MB	84.4 MB	7%	7.9 KB
6	newsfeeds.xmission.com	5.0 MB	0.0 KB	1.2 MB	6.2 MB	80%	3.5 KB
7	newsfeed.fu-berlin.de	3.4 MB	0.0 KB	862.7 KB	4.2 MB	80%	2.3 KB
8	nntp.pasdenom.info	1.6 MB	0.0 KB	363.2 KB	1.9 MB	81%	3.0 KB
9	news.swapon.de	629.5 KB	0.0 KB	26.2 KB	655.7 KB	96%	2.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		70.6 MB	18.1 KB	222.7 MB	293.3 MB	24%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 13 04:15:00 - 04:59:59	332	1.3%	0.12	1.2 MB	1.7%	0.46
Sep 13 05:00:00 - 05:59:59	489	1.9%	0.14	1.3 MB	1.9%	0.38
Sep 13 06:00:00 - 06:59:59	788	3.1%	0.22	2.6 MB	3.6%	0.74
Sep 13 07:00:00 - 07:59:59	926	3.6%	0.26	2.3 MB	3.3%	0.66
Sep 13 08:00:00 - 08:59:59	1064	4.2%	0.30	2.6 MB	3.6%	0.73
Sep 13 09:00:00 - 09:59:59	1080	4.2%	0.30	2.7 MB	3.8%	0.77
Sep 13 10:00:00 - 10:59:59	1147	4.5%	0.32	3.1 MB	4.3%	0.88
Sep 13 11:00:00 - 11:59:59	1257	4.9%	0.35	3.4 MB	4.8%	0.96
Sep 13 12:00:00 - 12:59:59	1363	5.3%	0.38	3.7 MB	5.1%	1.04
Sep 13 13:00:00 - 13:59:59	1334	5.2%	0.37	3.6 MB	5.1%	1.03
Sep 13 14:00:00 - 14:59:59	1350	5.3%	0.38	3.5 MB	4.9%	0.99
Sep 13 15:00:00 - 15:59:59	1342	5.3%	0.37	3.9 MB	5.4%	1.10
Sep 13 16:00:00 - 16:59:59	1467	5.7%	0.41	3.9 MB	5.6%	1.12
Sep 13 17:00:00 - 17:59:59	1521	6.0%	0.42	4.0 MB	5.7%	1.15
Sep 13 18:00:00 - 18:59:59	1575	6.2%	0.44	4.1 MB	5.8%	1.16
Sep 13 19:00:00 - 19:59:59	1481	5.8%	0.41	3.9 MB	5.4%	1.10
Sep 13 20:00:00 - 20:59:59	1403	5.5%	0.39	3.6 MB	5.1%	1.03
Sep 13 21:00:00 - 21:59:59	1279	5.0%	0.36	4.3 MB	6.0%	1.22
Sep 13 22:00:00 - 22:59:59	1015	4.0%	0.28	2.6 MB	3.7%	0.75
Sep 13 23:00:00 - 23:59:59	668	2.6%	0.19	1.9 MB	2.7%	0.54
Sep 14 00:00:00 - 00:59:59	702	2.7%	0.20	2.3 MB	3.2%	0.64
Sep 14 01:00:00 - 01:59:59	582	2.3%	0.16	2.0 MB	2.8%	0.56
Sep 14 02:00:00 - 02:59:59	557	2.2%	0.15	2.0 MB	2.8%	0.57
Sep 14 03:00:00 - 03:59:59	627	2.5%	0.17	2.0 MB	2.8%	0.58
Sep 14 04:00:00 - 04:15:00	192	0.8%	0.21	697.7 KB	1.0%	0.78
TOTAL: 24:00:00	25541	100.0%	0.30	71.1 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	9132	572	1	8559
2	newsfeed.neva.ru	8602	978	1	7623
3	news-out.mixmin.net	3284	15	1	3268
4	feeder.eternal-september.org	2699	268	0	2431
5	newsfeed.fu-berlin.de	650	32	1	617
6	au2pb.net	634	588	1	45
7	newsfeeds.xmission.com	185	160	1	24
8	nntp.pasdenom.info	37	24	1	12
9	news.swapon.de	30	0	0	30
TOTAL: 9		25253	2637	7	22609

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	287
pld.cvs.commit	159
mailing.openbsd.source-changes	125
ut.test	121
alt.politics	113
lada.talk	113
it-alt.sport.calcio.juventus	95
mailing.freebsd.svn-ports-head	86
mailing.freebsd.svn-ports-all	86
mailing.freebsd.ports-bugs	84
alt.politics.scorched-earth	83
alt.windows7.general	69
mailing.freebsd.pkg-fallout	64
mozilla.general	55
fa.openbsd.source-changes	53
alt.survival	51
mpc.lists.freebsd.ports.bugs	45
lada.auto	32
mailing.freebsd.questions	30
mailing.freebsd.svn-src-all	28
TOTAL: 202	2637

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16971
EMP (phn path)	2989
EMP (phl)	1030
PFM bot misc	221
EMP (phn nph)	139
Too many newsgroups	54
HTML Multipart	35
Binary Image: misplaced jpg	24
HTML post	12
HTML Image Tags	4
Too many newsgroups (meow)	2
Too many test groups in crosspost	1
TOTAL: 12	21482

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	524
	news-out.mixmin.net	53
	feeder.eternal-september.org	38
	au2pb.net	17
	newsfeeds.xmission.com	12
	nntp.pasdenom.info	6
	newsfeed.fu-berlin.de	1
	TOTAL: 7	651
Including strange strings
	newsfeed.fu-berlin.de	373
	news-out.mixmin.net	28
	feeder.eternal-september.org	24
	news.swapon.de	20
	au2pb.net	15
	newsfeeds.xmission.com	9
	xanadu-bbs.net	5
	nntp.pasdenom.info	1
	newsfeed.neva.ru	1
	TOTAL: 9	476
TOTAL: 2		1127

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	31250	320	23985	625	18	1%	23:59:57
2	au2pb	22606	251	21139	154	0	1%	23:59:57
3	xanadu-bbs	22826	109	21297	20	0	0%	23:59:57
4	fu-berlin.de	23602	49	23523	30	0	0%	23:59:57
5	xmission	28898	12	23678	4	0	0%	23:59:57
6	news.mixmin.net	20959	12	20241	14	0	0%	23:59:57
7	eternal-september	20179	10	19193	5	0	0%	23:59:55
8	neva.ru	24336	6	23140	67	0	0%	23:59:57
9	news.swapon.de	2220	1	2208	2	0	0%	23:58:39
TOTAL: 9		196876	770	178404	921	18	0%	215:58:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.1 MB	389.7 KB	1.5 MB	3.7 KB	23:59:57
2	usenet.pasdenom.info	1.0 MB	1.3 MB	2.3 MB	2.5 KB	23:59:57
3	xanadu-bbs	684.6 KB	34.5 KB	719.1 KB	5.6 KB	23:59:57
4	fu-berlin.de	299.8 KB	40.9 KB	340.7 KB	4.3 KB	23:59:57
5	eternal-september	66.8 KB	5.9 KB	72.7 KB	4.8 KB	23:59:55
6	news.mixmin.net	63.1 KB	21.9 KB	85.0 KB	3.3 KB	23:59:57
7	xmission	29.0 KB	7.7 KB	36.7 KB	2.3 KB	23:59:57
8	neva.ru	10.8 KB	293.4 KB	304.2 KB	4.2 KB	23:59:57
9	news.swapon.de	2.3 KB	3.8 KB	6.1 KB	2.0 KB	23:58:39
TOTAL: 9		3.2 MB	2.0 MB	5.3 MB	3.2 KB	215:58:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	50	133.6 KB	47	05:37:49
2	207.59.237.131	1	7	12.1 KB	1	00:02:16
3	ip70-186-189-240.br.br.cox.net	4	1	2.6 KB	1	00:04:40
4	challenger.nielsenmedia.com	2	1	1.5 KB	1	00:30:12
5	pool-71-98-160-84.tampfl.dsl-w.verizon.net	14	1	1.5 KB	1	02:07:51
TOTAL: 5		309	60	151.4 KB	51	32:41:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	322	57	145.8 KB	48	29:28:37
2	*.tampfl.dsl-w.verizon.net	14	1	1.5 KB	1	02:07:51
3	*.nielsenmedia.com	2	1	1.5 KB	1	00:30:12
4	*.br.br.cox.net	4	1	2.6 KB	1	00:04:40
5	*.strace.net	1	0	0.0 KB	0	00:00:02
6	*.tu.ok.cox.net	1	0	0.0 KB	0	00:30:04
TOTAL: 6		344	60	151.4 KB	51	32:41:28

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
71.94.21.184	0.873	0.837	0.000	23:48:29
202.89.176.220	26.447	5.979	0.702	05:37:49
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.149	0.220	0.000	02:07:51
challenger.nielsenmedia.com	0.213	0.046	0.000	00:30:12
ip72-222-107-224.tu.ok.cox.net	0.093	0.015	0.000	00:30:04
ip70-186-189-240.br.br.cox.net	0.477	0.038	0.000	00:04:40
207.59.237.131	0.351	0.261	0.000	00:02:16
69.160.37.54	1.991	0.413	0.000	00:00:02
21vek.strace.net	0.082	0.016	0.000	00:00:02
TOTAL: 9	31.676	7.825	0.702	32:41:28

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
162.212.88.198	4
71.94.21.184	4
ip72-222-107-224.tu.ok.cox.net	1
21vek.strace.net	1
TOTAL: 5	35

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	4
TOTAL: 1	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	0
challenger.nielsenmedia.com	1	0
71.94.21.184	1	0
ip72-222-107-224.tu.ok.cox.net	1	0
TOTAL: 4	7	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	22
2	comp.arch.fpga	10
3	comp.sys.ibm.ps2.hardware	7
4	comp.os.vms	5
5	comp.os.msdos.misc	3
6	comp.ai.philosophy	2
7	comp.ai.neural-nets	2
8	comp.lang.awk	2
9	comp.os.os2.programmer.misc	1
10	comp.sys.ibm.pc.classic	1
11	comp.sys.apple2	1
12	comp.sys.ibm.pc.games.war-historical	1
13	comp.sys.ibm.pc.games.action	1
14	comp.os.os2.advocacy	1
15	comp.os.os2.misc	1
TOTAL: 15		60

Daily Usenet report for csiph.com

Sep 13 04:15:00 -- Sep 14 04:15:00