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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	47454	48.5%	7.1 MB	61.0%
nnrpd	32579	33.3%	2.7 MB	23.3%
innd	15932	16.3%	1.6 MB	13.5%
innfeed	1898	1.9%	266.6 KB	2.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	97864	100.0%	11.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	267432	48.5%
Negative hits	231313	42.0%
Do not exist	46659	8.5%
Cache misses	5911	1.1%
TOTAL: 4	551315	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.405	0.0%	204	0.000	1.985	25.000
article cleanup	00:00:00.312	0.0%	46038	0.000	0.007	0.027
article logging	00:00:00.558	0.0%	46525	0.000	0.012	0.046
article parse	00:00:02.064	0.0%	235217	0.002	0.009	0.017
article write	00:00:06.768	0.0%	29267	0.136	0.231	0.348
artlog/artcncl	00:00:00.002	0.0%	197	0.000	0.010	1.000
artlog/artparse	00:00:00.012	0.0%	759	0.000	0.016	0.250
data move	00:00:00.272	0.0%	704892	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.001	0.0%	199	0.000	0.005	0.500
hishave/artcncl	00:00:00.001	0.0%	204	0.000	0.005	0.053
hishave/artparse	00:00:00.004	0.0%	759	0.000	0.005	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.312	0.0%	551965	0.001	0.002	0.010
history sync	00:00:00.532	0.0%	354	0.000	1.503	3.500
history write	00:00:11.739	0.0%	46037	0.056	0.255	0.331
hiswrite/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hiswrite/artparse	00:00:00.213	0.0%	759	0.000	0.281	1.250
idle	23:58:11.722	99.8%	689800	27.187	125.097	394.115
nntp read	00:00:02.309	0.0%	688041	0.001	0.003	0.007
overview write	00:00:05.127	0.0%	29267	0.071	0.175	1.400
perl filter	00:01:24.771	0.1%	46037	1.031	1.841	3.159
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.526	0.0%	58534	0.000	0.009	0.050
TOTAL: 24:00:46.767	24:00:08.650	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.064	0.0%	29216	0.000	0.002	0.022
article prepare	00:00:00.005	0.0%	6400	0.000	0.001	0.042
article read	00:00:00.210	0.0%	1256	0.000	0.167	11.250
backlog stats	00:00:00.101	0.0%	326207	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.322	0.0%	251014	0.001	0.005	0.013
data write	00:00:01.915	0.0%	224181	0.002	0.009	0.020
idle	23:54:06.488	100.0%	326207	157.126	263.779	715.315
status file	00:00:00.080	0.0%	204	0.000	0.392	1.000
TOTAL: 23:54:15.520	23:54:10.185	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.834	0.0%	37560	0.000	0.022	0.100
idle	85:09:25.623	100.0%	69042	0.000	4440.277	600096.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.775	0.0%	139153	0.000	0.013	8.336
readart	00:00:04.960	0.0%	15775	0.000	0.314	1.000
TOTAL: 85:10:57.803	85:09:33.192	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	3	42471	7027	34015	1429	16%	24:13:27
2	au2pb.net	1	28927	5421	22813	693	18%	23:31:05
3	xanadu-bbs.net	1	46147	5347	32340	8460	11%	23:34:48
4	news-out.mixmin.net	5	32435	4034	26688	1713	12%	24:14:14
5	newsfeed.neva.ru	156	62204	3388	54025	4791	5%	72:41:12
6	newsfeed.fu-berlin.de	51	30632	1487	29008	137	4%	32:42:24
7	newsfeeds.xmission.com	1	27774	1450	26158	166	5%	24:34:24
8	nntp.pasdenom.info	2	28488	633	27795	60	2%	24:57:55
9	news.swapon.de	1	2496	325	2167	4	13%	22:37:26
10	localhost	50	18240	0	18240	0	0%	00:01:03
TOTAL: 10		271	319814	29112	273249	17453	9%	273:07:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	18.8 MB	0.0 KB	8.0 MB	26.8 MB	70%	3.2 KB
2	xanadu-bbs.net	15.2 MB	35.0 KB	129.9 MB	145.1 MB	10%	10.8 KB
3	au2pb.net	13.6 MB	0.0 KB	6.4 MB	20.0 MB	68%	3.3 KB
4	news-out.mixmin.net	11.8 MB	0.0 KB	5.0 MB	16.7 MB	70%	3.0 KB
5	newsfeed.neva.ru	10.6 MB	0.0 KB	28.1 MB	38.8 MB	27%	4.9 KB
6	newsfeeds.xmission.com	4.4 MB	0.0 KB	2.2 MB	6.6 MB	66%	4.2 KB
7	newsfeed.fu-berlin.de	3.4 MB	0.0 KB	757.6 KB	4.1 MB	82%	2.6 KB
8	nntp.pasdenom.info	1.5 MB	0.0 KB	287.2 KB	1.7 MB	83%	2.6 KB
9	news.swapon.de	811.1 KB	0.0 KB	92.0 KB	903.0 KB	89%	2.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		80.0 MB	35.0 KB	180.7 MB	260.7 MB	30%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 7 04:15:00 - 04:59:59	446	1.5%	0.17	1.4 MB	1.8%	0.55
Sep 7 05:00:00 - 05:59:59	591	2.0%	0.16	2.5 MB	3.1%	0.71
Sep 7 06:00:00 - 06:59:59	949	3.2%	0.26	3.6 MB	4.5%	1.03
Sep 7 07:00:00 - 07:59:59	1173	4.0%	0.33	2.8 MB	3.5%	0.81
Sep 7 08:00:00 - 08:59:59	1264	4.3%	0.35	3.1 MB	3.8%	0.88
Sep 7 09:00:00 - 09:59:59	1346	4.6%	0.37	3.6 MB	4.5%	1.02
Sep 7 10:00:00 - 10:59:59	1459	5.0%	0.41	3.3 MB	4.1%	0.93
Sep 7 11:00:00 - 11:59:59	1380	4.7%	0.38	3.8 MB	4.7%	1.08
Sep 7 12:00:00 - 12:59:59	1596	5.5%	0.44	4.0 MB	5.0%	1.15
Sep 7 13:00:00 - 13:59:59	1659	5.7%	0.46	4.0 MB	5.0%	1.14
Sep 7 14:00:00 - 14:59:59	1751	6.0%	0.49	4.6 MB	5.7%	1.31
Sep 7 15:00:00 - 15:59:59	1893	6.5%	0.53	5.4 MB	6.7%	1.53
Sep 7 16:00:00 - 16:59:59	1722	5.9%	0.48	4.5 MB	5.6%	1.29
Sep 7 17:00:00 - 17:59:59	1921	6.6%	0.53	6.3 MB	7.8%	1.79
Sep 7 18:00:00 - 18:59:59	1592	5.4%	0.44	3.9 MB	4.9%	1.12
Sep 7 19:00:00 - 19:59:59	1535	5.2%	0.43	4.0 MB	5.0%	1.14
Sep 7 20:00:00 - 20:59:59	1408	4.8%	0.39	3.6 MB	4.4%	1.01
Sep 7 21:00:00 - 21:59:59	1379	4.7%	0.38	3.6 MB	4.4%	1.01
Sep 7 22:00:00 - 22:59:59	1014	3.5%	0.28	2.9 MB	3.6%	0.83
Sep 7 23:00:00 - 23:59:59	737	2.5%	0.20	2.1 MB	2.6%	0.59
Sep 8 00:00:00 - 00:59:59	663	2.3%	0.18	1.9 MB	2.4%	0.55
Sep 8 01:00:00 - 01:59:59	610	2.1%	0.17	1.8 MB	2.3%	0.51
Sep 8 02:00:00 - 02:59:59	500	1.7%	0.14	1.6 MB	1.9%	0.44
Sep 8 03:00:00 - 03:59:59	513	1.8%	0.14	1.5 MB	1.9%	0.43
Sep 8 04:00:00 - 04:15:00	155	0.5%	0.17	455.3 KB	0.6%	0.51
TOTAL: 24:00:00	29256	100.0%	0.34	80.3 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8558	837	1	7720
2	newsfeed.neva.ru	4829	1349	0	3480
3	news-out.mixmin.net	1734	6	0	1728
4	feeder.eternal-september.org	1457	216	0	1241
5	au2pb.net	706	658	0	48
6	newsfeed.fu-berlin.de	469	51	0	418
7	newsfeeds.xmission.com	171	141	0	30
8	nntp.pasdenom.info	55	52	0	3
9	news.swapon.de	23	0	0	23
TOTAL: 9		18002	3310	1	14691

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	539
mailing.freebsd.pkg-fallout	178
lada.talk	168
lada.auto	165
alt.politics	138
ut.test	136
mailing.freebsd.svn-ports-all	122
mailing.freebsd.svn-ports-head	114
alt.survival	93
it-alt.sport.calcio.juventus	91
alt.job	84
pld.cvs.commit	69
mailing.freebsd.ports-bugs	60
mpc.lists.freebsd.ports.bugs	60
alt.politics.scorched-earth	57
mozilla.dev.tree-alerts	48
alt.windows7.general	40
mailing.unix.dovecot	39
lada.photo	37
mailing.unix.dovecot-cvs	34
TOTAL: 242	3310

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8664
EMP (phn path)	3737
EMP (phl)	669
PFM bot misc	219
Binary Image: misplaced jpg	48
HTML post	37
HTML Multipart	30
Too many newsgroups	25
EMP (phn nph)	5
Too many newsgroups (low_xpost)	4
HTML Image Tags	3
Binary Payload	1
TOTAL: 12	13442

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	625
	news-out.mixmin.net	42
	au2pb.net	35
	feeder.eternal-september.org	30
	newsfeeds.xmission.com	20
	newsfeed.fu-berlin.de	7
	nntp.pasdenom.info	2
	news.swapon.de	1
	TOTAL: 8	762
Including strange strings
	newsfeed.fu-berlin.de	380
	feeder.eternal-september.org	37
	news-out.mixmin.net	23
	news.swapon.de	19
	au2pb.net	10
	xanadu-bbs.net	8
	newsfeeds.xmission.com	5
	newsfeed.neva.ru	4
	nntp.pasdenom.info	1
	TOTAL: 9	487
TOTAL: 2		1249

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	35264	317	27657	653	6	0%	23:59:43
2	au2pb	24667	170	23453	142	0	0%	23:59:42
3	xanadu-bbs	25195	101	23719	26	10	0%	23:59:44
4	xmission	34329	67	27491	16	0	0%	23:59:57
5	fu-berlin.de	27304	28	27266	10	0	0%	23:59:44
6	news.mixmin.net	26028	8	25090	6	0	0%	23:59:55
7	eternal-september	22833	5	21672	1	0	0%	23:59:54
8	neva.ru	26810	1	25588	47	0	0%	23:59:55
9	news.swapon.de	2308	0	2296	2	0	0%	23:53:38
TOTAL: 9		224738	697	204232	903	16	0%	215:52:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	999.6 KB	1.2 MB	2.2 MB	2.3 KB	23:59:43
2	au2pb	782.6 KB	526.8 KB	1.3 MB	4.2 KB	23:59:42
3	xanadu-bbs	652.0 KB	47.8 KB	699.8 KB	5.5 KB	23:59:44
4	xmission	212.2 KB	55.2 KB	267.4 KB	3.2 KB	23:59:57
5	fu-berlin.de	165.3 KB	14.4 KB	179.6 KB	4.7 KB	23:59:44
6	news.mixmin.net	52.8 KB	8.4 KB	61.2 KB	4.4 KB	23:59:55
7	eternal-september	23.1 KB	1.0 KB	24.1 KB	4.0 KB	23:59:54
8	neva.ru	6.9 KB	220.2 KB	227.1 KB	4.7 KB	23:59:55
9	news.swapon.de	0.0 KB	6.0 KB	6.0 KB	3.0 KB	23:53:38
TOTAL: 9		2.8 MB	2.1 MB	4.9 MB	3.1 KB	215:52:12

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	51	174.8 KB	49	04:26:05
2	71.94.21.184	5	1	2.4 KB	1	23:44:57
TOTAL: 2		293	52	177.1 KB	50	85:10:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	472	52	177.1 KB	50	81:19:35
2	*.nielsenmedia.com	14	0	0.0 KB	0	01:19:40
3	*.bbox.io	9	0	0.0 KB	0	02:00:29
4	*.br.br.cox.net	2	0	0.0 KB	0	00:00:48
5	*.tampfl.dsl-w.verizon.net	3	0	0.0 KB	0	00:30:17
TOTAL: 5		500	52	177.1 KB	50	85:10:52

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	10.911	3.114	0.000	37:40:15
71.94.21.184	1.431	1.412	0.000	23:44:57
162.212.88.198	0.779	0.362	0.000	15:28:15
202.89.176.220	26.600	5.650	0.697	04:26:05
tempe0.bbox.io	0.661	0.116	0.000	02:00:29
challenger.nielsenmedia.com	1.124	0.217	0.000	01:19:40
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.277	0.032	0.000	00:30:17
ip70-186-189-146.br.br.cox.net	0.222	0.016	0.000	00:00:48
69.160.37.54	1.720	0.434	0.000	00:00:01
TOTAL: 9	43.725	11.353	0.697	85:10:52

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	150
69.160.37.54	25
challenger.nielsenmedia.com	14
tempe0.bbox.io	9
162.212.88.198	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3
ip70-186-189-146.br.br.cox.net	2
TOTAL: 7	207

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	4	0
challenger.nielsenmedia.com	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
71.94.21.184	1	1
TOTAL: 4	8	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	51	98.1%
2	alt	1	1.9%
TOTAL: 2		52	100.0%

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

Newsgroup		Count
1	comp.os.vms	30
2	comp.arch	7
3	comp.sys.ibm.pc.games.action	6
4	comp.sys.ibm.pc.games.strategic	3
5	comp.arch.fpga	3
6	comp.ai.neural-nets	1
7	alt.test	1
8	comp.answers	1
TOTAL: 8		52

Daily Usenet report for csiph.com

Sep 7 04:15:00 -- Sep 8 04:15:00