Return-Path: Delivered-To: apmail-hadoop-hbase-dev-archive@minotaur.apache.org Received: (qmail 44901 invoked from network); 30 Oct 2009 22:51:36 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.3) by minotaur.apache.org with SMTP; 30 Oct 2009 22:51:36 -0000 Received: (qmail 58281 invoked by uid 500); 30 Oct 2009 22:51:36 -0000 Delivered-To: apmail-hadoop-hbase-dev-archive@hadoop.apache.org Received: (qmail 58253 invoked by uid 500); 30 Oct 2009 22:51:36 -0000 Mailing-List: contact hbase-dev-help@hadoop.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: hbase-dev@hadoop.apache.org Delivered-To: mailing list hbase-dev@hadoop.apache.org Received: (qmail 58243 invoked by uid 99); 30 Oct 2009 22:51:36 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 30 Oct 2009 22:51:36 +0000 X-ASF-Spam-Status: No, hits=-2.6 required=5.0 tests=BAYES_00 X-Spam-Check-By: apache.org Received-SPF: pass (athena.apache.org: domain of jdcryans@gmail.com designates 72.14.220.159 as permitted sender) Received: from [72.14.220.159] (HELO fg-out-1718.google.com) (72.14.220.159) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 30 Oct 2009 22:51:32 +0000 Received: by fg-out-1718.google.com with SMTP id d23so1576921fga.11 for ; Fri, 30 Oct 2009 15:51:11 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=gamma; h=domainkey-signature:mime-version:sender:received:in-reply-to :references:date:x-google-sender-auth:message-id:subject:from:to :content-type:content-transfer-encoding; bh=u4CzlL+r1gQZYhkwSq0N8oQ+dlNjHGm+Kj8xRif8aoI=; b=OHYgt+url0SgNS7SOFZR14Gar7HnTXcc4Q7W05CLLCmdb2Qm9CMg3ydNEfMrD+mHTT by+PiX5JZKLERkW60dXQdNLiXPKh1PG7FKCM2lEExe1BN8xbwWqMAtCqDocyCjmlKyga UO61YGyi9Lwl7wiL2SBt6gA029U40e2xHCkUQ= DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=mime-version:sender:in-reply-to:references:date :x-google-sender-auth:message-id:subject:from:to:content-type :content-transfer-encoding; b=JQHF0gCZVTqqLIrG6X+wOVhwGqoFkozzp1iQbcwgit6GdJWLJwxvGPrbnoboiy/myA ovBqjqGtJnsrE3hTAQF2MvT87Qx+y7tCSRfk8mJVN/EE5D5OP72U8+189C6nrMyLxYCw vaNvEDm80Woy3tGGjOPC86dpnGk7gyDm3SaVQ= MIME-Version: 1.0 Sender: jdcryans@gmail.com Received: by 10.204.35.12 with SMTP id n12mr1536380bkd.147.1256943070795; Fri, 30 Oct 2009 15:51:10 -0700 (PDT) In-Reply-To: <26782402.7201256942413997.JavaMail.hudson@hudson.zones.apache.org> References: <12315178.7151256938797312.JavaMail.hudson@hudson.zones.apache.org> <26782402.7201256942413997.JavaMail.hudson@hudson.zones.apache.org> Date: Fri, 30 Oct 2009 15:51:10 -0700 X-Google-Sender-Auth: 749087d4c3e568cc Message-ID: <31a243e70910301551w11ab799fo45733ebea1a49e2f@mail.gmail.com> Subject: Re: Build failed in Hudson: HBase-Patch #971 From: Jean-Daniel Cryans To: hbase-dev@hadoop.apache.org Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: quoted-printable I have another fix in line. J-D On Fri, Oct 30, 2009 at 3:40 PM, Apache Hudson Server wrote: > See > > Changes: > > [jdcryans] Trying to fix Hudson, 1 update missing in TestHLog > > ------------------------------------------ > [...truncated 22992 lines...] > =A0 =A0[junit] 2009-10-30 22:42:55,592 DEBUG [main] regionserver.HRegion(= 895): Started memstore flush for region .META.,,1. Current region memstore = size 1016 > =A0 =A0[junit] 2009-10-30 22:42:55,627 DEBUG [main] regionserver.Store(56= 5): Added hdfs://localhost:43785/user/hudson/.META./1028785192/historian/18= 95672545031026572, entries=3D2, sequenceid=3D27, memsize=3D288, filesize=3D= 521 to .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:55,667 DEBUG [main] regionserver.Store(56= 5): Added hdfs://localhost:43785/user/hudson/.META./1028785192/info/4665371= 487457657112, entries=3D3, sequenceid=3D27, memsize=3D728, filesize=3D874 t= o .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:55,667 DEBUG [main] regionserver.HRegion(= 971): Finished memstore flush of ~1016 for region .META.,,1 in 75ms, sequen= ce id=3D27, compaction requested=3Dtrue > =A0 =A0[junit] 2009-10-30 22:42:55,667 DEBUG [main] regionserver.Store(48= 3): closed historian > =A0 =A0[junit] 2009-10-30 22:42:55,668 DEBUG [main] regionserver.Store(48= 3): closed info > =A0 =A0[junit] 2009-10-30 22:42:55,668 INFO =A0[main] regionserver.HRegio= n(490): Closed .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:55,696 INFO =A0[main] wal.HLog(322): Roll= /user/hudson/.logs_1256942571596/hlog.dat.1256942571610, entries=3D6, calc= size=3D1131, filesize=3D1095. New hlog /user/hudson/.logs_1256942571596/hlo= g.dat.1256942575683 > =A0 =A0[junit] 2009-10-30 22:42:55,696 DEBUG [main] wal.HLog(331): Last s= equence written is empty. Deleting all old hlogs > =A0 =A0[junit] 2009-10-30 22:42:55,696 INFO =A0[main] wal.HLog(550): remo= ving old hlog file /user/hudson/.logs_1256942571596/hlog.dat.1256942571610 = whose highest sequence/edit id is 26 > =A0 =A0[junit] 2009-10-30 22:42:55,711 DEBUG [main.logSyncer] wal.HLog$Lo= gSyncer(761): main.logSyncerinterrupted while waiting for sync requests > =A0 =A0[junit] 2009-10-30 22:42:55,712 INFO =A0[main.logSyncer] wal.HLog$= LogSyncer(765): main.logSyncer exiting > =A0 =A0[junit] 2009-10-30 22:42:55,712 DEBUG [main] wal.HLog(595): closin= g hlog writer in hdfs://localhost:43785/user/hudson/.logs_1256942571596 > =A0 =A0[junit] 2009-10-30 22:42:55,743 DEBUG [main] regionserver.HRegion(= 1887): Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0100,12569= 42574320', STARTKEY =3D> 'row_0100', ENDKEY =3D> 'row_0600', ENCODED =3D> 1= 677776407, TABLE =3D> {{NAME =3D> 'TestMergeTool', FAMILIES =3D> [{NAME =3D= > 'contents', VERSIONS =3D> '3', COMPRESSION =3D> 'NONE', TTL =3D> '2147483= 647', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true= '}]}} > =A0 =A0[junit] 2009-10-30 22:42:55,743 DEBUG [main] regionserver.HRegion(= 265): Opening region TestMergeTool,row_0100,1256942574320, encoded=3D167777= 6407 > =A0 =A0[junit] 2009-10-30 22:42:55,751 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/TestMergeTool/1677776407/contents/23908295655570415= 25, isReference=3Dfalse, sequence id=3D6, length=3D883, majorCompaction=3Df= alse > =A0 =A0[junit] 2009-10-30 22:42:55,753 INFO =A0[main] regionserver.HRegio= n(343): region TestMergeTool,row_0100,1256942574320/1677776407 available; s= equence id is 7 > =A0 =A0[junit] 2009-10-30 22:42:55,758 DEBUG [main] regionserver.HRegion(= 454): Closing TestMergeTool,row_0100,1256942574320: compactions & flushes d= isabled > =A0 =A0[junit] 2009-10-30 22:42:55,758 DEBUG [main] regionserver.HRegion(= 471): Updates disabled for region, no outstanding scanners on TestMergeTool= ,row_0100,1256942574320 > =A0 =A0[junit] 2009-10-30 22:42:55,758 DEBUG [main] regionserver.HRegion(= 478): No more row locks outstanding on region TestMergeTool,row_0100,125694= 2574320 > =A0 =A0[junit] 2009-10-30 22:42:55,759 DEBUG [main] regionserver.Store(48= 3): closed contents > =A0 =A0[junit] 2009-10-30 22:42:55,759 INFO =A0[main] regionserver.HRegio= n(490): Closed TestMergeTool,row_0100,1256942574320 > =A0 =A0[junit] 2009-10-30 22:42:55,759 INFO =A0[main] util.TestMergeTool(= 186): Verified merging regions 0+1+2 and 3 > =A0 =A0[junit] 2009-10-30 22:42:55,760 INFO =A0[main] util.TestMergeTool(= 173): merging regions 0+1+2+3 and 4 > =A0 =A0[junit] 2009-10-30 22:42:55,760 WARN =A0[main] conf.Configuration(= 616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduc= e.client.genericoptionsparser.used > =A0 =A0[junit] 2009-10-30 22:42:55,760 WARN =A0[main] conf.Configuration(= 616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduc= e.client.genericoptionsparser.used > =A0 =A0[junit] 2009-10-30 22:42:55,761 INFO =A0[main] util.Merge(82): Ver= ifying that file system is available... > =A0 =A0[junit] 2009-10-30 22:42:55,761 INFO =A0[main] util.Merge(91): Ver= ifying that HBase is not running... > =A0 =A0[junit] 2009-10-30 22:42:55,784 WARN =A0[main] conf.Configuration(= 616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoin= t.size > =A0 =A0[junit] 2009-10-30 22:42:55,785 WARN =A0[main] conf.Configuration(= 616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint= .dir > =A0 =A0[junit] 2009-10-30 22:42:55,785 WARN =A0[main] conf.Configuration(= 616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.chec= kpoint.edits.dir > =A0 =A0[junit] 2009-10-30 22:42:55,786 WARN =A0[main] conf.Configuration(= 616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpo= int.period > =A0 =A0[junit] 2009-10-30 22:42:56,323 INFO =A0[main] client.HConnectionM= anager$TableServers(333): getMaster attempt 0 of 1 failed; no more retrying= . > =A0 =A0[junit] java.io.IOException: org.apache.zookeeper.KeeperException$= ConnectionLossException: KeeperErrorCode =3D ConnectionLoss for /hbase/mast= er > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.zookeeper.ZooKeeperWrap= per.readAddressOrThrow(ZooKeeperWrapper.java:326) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.zookeeper.ZooKeeperWrap= per.readMasterAddressOrThrow(ZooKeeperWrapper.java:235) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.client.HConnectionManag= er$TableServers.getMaster(HConnectionManager.java:318) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.client.HBaseAdmin.(HBaseAdmin.java:72) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.client.HBaseAdmin.check= HBaseAvailable(HBaseAdmin.java:840) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.util.Merge.run(Merge.ja= va:93) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.util.ToolRunner.run(ToolRunne= r.java:65) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.util.ToolRunner.run(ToolRunne= r.java:79) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.util.TestMergeTool.merg= eAndVerify(TestMergeTool.java:174) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.util.TestMergeTool.test= MergeTool(TestMergeTool.java:267) > =A0 =A0[junit] =A0 =A0 at sun.reflect.NativeMethodAccessorImpl.invoke0(Na= tive Method) > =A0 =A0[junit] =A0 =A0 at sun.reflect.NativeMethodAccessorImpl.invoke(Nat= iveMethodAccessorImpl.java:39) > =A0 =A0[junit] =A0 =A0 at sun.reflect.DelegatingMethodAccessorImpl.invoke= (DelegatingMethodAccessorImpl.java:25) > =A0 =A0[junit] =A0 =A0 at java.lang.reflect.Method.invoke(Method.java:597= ) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestCase.runTest(TestCase.java:= 168) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestCase.runBare(TestCase.java:= 134) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestResult$1.protect(TestResult= .java:110) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestResult.runProtected(TestRes= ult.java:128) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestResult.run(TestResult.java:= 113) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestCase.run(TestCase.java:124) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestSuite.runTest(TestSuite.jav= a:232) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestSuite.run(TestSuite.java:22= 7) > =A0 =A0[junit] =A0 =A0 at org.junit.internal.runners.JUnit38ClassRunner.r= un(JUnit38ClassRunner.java:83) > =A0 =A0[junit] =A0 =A0 at junit.framework.JUnit4TestAdapter.run(JUnit4Tes= tAdapter.java:39) > =A0 =A0[junit] =A0 =A0 at org.apache.tools.ant.taskdefs.optional.junit.JU= nitTestRunner.run(JUnitTestRunner.java:421) > =A0 =A0[junit] =A0 =A0 at org.apache.tools.ant.taskdefs.optional.junit.JU= nitTestRunner.launch(JUnitTestRunner.java:912) > =A0 =A0[junit] =A0 =A0 at org.apache.tools.ant.taskdefs.optional.junit.JU= nitTestRunner.main(JUnitTestRunner.java:766) > =A0 =A0[junit] Caused by: org.apache.zookeeper.KeeperException$Connection= LossException: KeeperErrorCode =3D ConnectionLoss for /hbase/master > =A0 =A0[junit] =A0 =A0 at org.apache.zookeeper.KeeperException.create(Kee= perException.java:90) > =A0 =A0[junit] =A0 =A0 at org.apache.zookeeper.KeeperException.create(Kee= perException.java:42) > =A0 =A0[junit] =A0 =A0 at org.apache.zookeeper.ZooKeeper.getData(ZooKeepe= r.java:892) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.zookeeper.ZooKeeperWrap= per.readAddressOrThrow(ZooKeeperWrapper.java:322) > =A0 =A0[junit] =A0 =A0 ... 26 more > =A0 =A0[junit] 2009-10-30 22:42:56,328 INFO =A0[main] util.Merge(197): Me= rging regions TestMergeTool,row_0100,1256942574320 and TestMergeTool,,12569= 42555142 in table TestMergeTool > =A0 =A0[junit] 2009-10-30 22:42:56,348 INFO =A0[main] wal.HLog(234): HLog= configuration: blocksize=3D67108864, rollsize=3D63753420, enabled=3Dtrue, = flushlogentries=3D1, optionallogflushinternal=3D10000ms > =A0 =A0[junit] 2009-10-30 22:42:56,364 INFO =A0[main] wal.HLog(322): New = hlog /user/hudson/.logs_1256942576328/hlog.dat.1256942576348 > =A0 =A0[junit] 2009-10-30 22:42:56,365 DEBUG [main] regionserver.HRegion(= 1887): Opening region: REGION =3D> {NAME =3D> '-ROOT-,,0', STARTKEY =3D> ''= , ENDKEY =3D> '', ENCODED =3D> 70236052, TABLE =3D> {{NAME =3D> '-ROOT-', I= S_ROOT =3D> 'true', IS_META =3D> 'true', FAMILIES =3D> [{NAME =3D> 'info', = COMPRESSION =3D> 'NONE', VERSIONS =3D> '10', TTL =3D> '2147483647', BLOCKSI= ZE =3D> '8192', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true'}]}} > =A0 =A0[junit] 2009-10-30 22:42:56,365 DEBUG [main] regionserver.HRegion(= 265): Opening region -ROOT-,,0, encoded=3D70236052 > =A0 =A0[junit] 2009-10-30 22:42:56,376 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/-ROOT-/70236052/info/70611751126905087, isReference= =3Dfalse, sequence id=3D2, length=3D809, majorCompaction=3Dfalse > =A0 =A0[junit] 2009-10-30 22:42:56,378 INFO =A0[main] regionserver.HRegio= n(343): region -ROOT-,,0/70236052 available; sequence id is 3 > =A0 =A0[junit] 2009-10-30 22:42:56,379 INFO =A0[main] regionserver.HRegio= n(771): Starting compaction on region -ROOT-,,0 > =A0 =A0[junit] 2009-10-30 22:42:56,384 INFO =A0[main] regionserver.HRegio= n(786): compaction completed on region -ROOT-,,0 in 0sec > =A0 =A0[junit] 2009-10-30 22:42:56,384 INFO =A0[main] util.Merge(211): Fo= und meta for region1 .META.,,1, meta for region2 .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:56,385 DEBUG [main] regionserver.HRegion(= 1887): Opening region: REGION =3D> {NAME =3D> '.META.,,1', STARTKEY =3D> ''= , ENDKEY =3D> '', ENCODED =3D> 1028785192, TABLE =3D> {{NAME =3D> '.META.',= IS_META =3D> 'true', FAMILIES =3D> [{NAME =3D> 'historian', VERSIONS =3D> = '2147483647', COMPRESSION =3D> 'NONE', TTL =3D> '604800', BLOCKSIZE =3D> '8= 192', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'}, {NAME =3D> 'info', = VERSIONS =3D> '10', COMPRESSION =3D> 'NONE', TTL =3D> '2147483647', BLOCKSI= ZE =3D> '8192', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true'}]}} > =A0 =A0[junit] 2009-10-30 22:42:56,385 DEBUG [main] regionserver.HRegion(= 265): Opening region .META.,,1, encoded=3D1028785192 > =A0 =A0[junit] 2009-10-30 22:42:56,392 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/.META./1028785192/historian/1895672545031026572, is= Reference=3Dfalse, sequence id=3D27, length=3D521, majorCompaction=3Dfalse > =A0 =A0[junit] 2009-10-30 22:42:56,396 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/.META./1028785192/historian/2306956041153776265, is= Reference=3Dfalse, sequence id=3D20, length=3D651, majorCompaction=3Dfalse > =A0 =A0[junit] 2009-10-30 22:42:56,403 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/.META./1028785192/info/3848158811438287066, isRefer= ence=3Dfalse, sequence id=3D20, length=3D994, majorCompaction=3Dfalse > =A0 =A0[junit] 2009-10-30 22:42:56,407 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/.META./1028785192/info/4665371487457657112, isRefer= ence=3Dfalse, sequence id=3D27, length=3D874, majorCompaction=3Dfalse > =A0 =A0[junit] 2009-10-30 22:42:56,411 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/.META./1028785192/info/9005483223799296465, isRefer= ence=3Dfalse, sequence id=3D6, length=3D2226, majorCompaction=3Dfalse > =A0 =A0[junit] 2009-10-30 22:42:56,413 INFO =A0[main] regionserver.HRegio= n(343): region .META.,,1/1028785192 available; sequence id is 28 > =A0 =A0[junit] 2009-10-30 22:42:56,413 INFO =A0[main] regionserver.HRegio= n(771): Starting compaction on region .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:56,424 DEBUG [main] regionserver.Store(75= 1): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0 > =A0 =A0[junit] 2009-10-30 22:42:56,424 DEBUG [main] regionserver.Store(75= 8): Started compaction of 2 file(s) =A0into /user/hudson/.META./compaction.= dir/1028785192, seqid=3D27 > =A0 =A0[junit] 2009-10-30 22:42:56,635 DEBUG [main] regionserver.Store(76= 5): Completed compaction of historian; new storefile is hdfs://localhost:43= 785/user/hudson/.META./1028785192/historian/825894279214784888; store size = is 781 > =A0 =A0[junit] 2009-10-30 22:42:56,637 DEBUG [main] regionserver.Store(75= 1): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226 > =A0 =A0[junit] 2009-10-30 22:42:56,637 DEBUG [main] regionserver.Store(75= 8): Started compaction of 2 file(s) =A0into /user/hudson/.META./compaction.= dir/1028785192, seqid=3D27 > =A0 =A0[junit] 2009-10-30 22:42:56,694 DEBUG [main] regionserver.Store(76= 5): Completed compaction of info; new storefile is hdfs://localhost:43785/u= ser/hudson/.META./1028785192/info/4475199317543238642; store size is 3.3k > =A0 =A0[junit] 2009-10-30 22:42:56,702 INFO =A0[main] regionserver.HRegio= n(786): compaction completed on region .META.,,1 in 0sec > =A0 =A0[junit] 2009-10-30 22:42:56,703 INFO =A0[main] util.MetaUtils(125)= : OPENING META .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:56,705 DEBUG [main] regionserver.HRegion(= 1887): Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0100,12569= 42574320', STARTKEY =3D> 'row_0100', ENDKEY =3D> 'row_0600', ENCODED =3D> 1= 677776407, TABLE =3D> {{NAME =3D> 'TestMergeTool', FAMILIES =3D> [{NAME =3D= > 'contents', COMPRESSION =3D> 'NONE', VERSIONS =3D> '3', TTL =3D> '2147483= 647', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true= '}]}} > =A0 =A0[junit] 2009-10-30 22:42:56,705 DEBUG [main] regionserver.HRegion(= 265): Opening region TestMergeTool,row_0100,1256942574320, encoded=3D167777= 6407 > =A0 =A0[junit] 2009-10-30 22:42:56,712 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/TestMergeTool/1677776407/contents/23908295655570415= 25, isReference=3Dfalse, sequence id=3D6, length=3D883, majorCompaction=3Df= alse > =A0 =A0[junit] 2009-10-30 22:42:56,714 INFO =A0[main] regionserver.HRegio= n(343): region TestMergeTool,row_0100,1256942574320/1677776407 available; s= equence id is 7 > =A0 =A0[junit] 2009-10-30 22:42:56,715 DEBUG [main] regionserver.HRegion(= 1887): Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,,1256942555142= ', STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 78342473, TABLE =3D> {{NA= ME =3D> 'TestMergeTool', FAMILIES =3D> [{NAME =3D> 'contents', VERSIONS =3D= > '3', COMPRESSION =3D> 'NONE', TTL =3D> '2147483647', BLOCKSIZE =3D> '6553= 6', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true'}]}} > =A0 =A0[junit] 2009-10-30 22:42:56,715 DEBUG [main] regionserver.HRegion(= 265): Opening region TestMergeTool,,1256942555142, encoded=3D78342473 > =A0 =A0[junit] 2009-10-30 22:42:56,722 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/TestMergeTool/78342473/contents/7978269299131899874= , isReference=3Dfalse, sequence id=3D7, length=3D517, majorCompaction=3Dfal= se > =A0 =A0[junit] 2009-10-30 22:42:56,724 INFO =A0[main] regionserver.HRegio= n(343): region TestMergeTool,,1256942555142/78342473 available; sequence id= is 8 > =A0 =A0[junit] 2009-10-30 22:42:56,725 INFO =A0[main] regionserver.HRegio= n(771): Starting major compaction on region TestMergeTool,row_0100,12569425= 74320 > =A0 =A0[junit] 2009-10-30 22:42:56,734 DEBUG [main] regionserver.Store(75= 8): Started compaction of 1 file(s) =A0into /user/hudson/TestMergeTool/comp= action.dir/1677776407, seqid=3D6 > =A0 =A0[junit] 2009-10-30 22:42:56,778 DEBUG [main] regionserver.Store(76= 5): Completed major compaction of contents; new storefile is hdfs://localho= st:43785/user/hudson/TestMergeTool/1677776407/contents/5042117736930384897;= store size is 883 > =A0 =A0[junit] 2009-10-30 22:42:56,786 INFO =A0[main] regionserver.HRegio= n(786): compaction completed on region TestMergeTool,row_0100,1256942574320= in 0sec > =A0 =A0[junit] 2009-10-30 22:42:56,786 DEBUG [main] regionserver.HRegion(= 2126): Files for region: TestMergeTool,row_0100,1256942574320 > =A0 =A0[junit] 2009-10-30 22:42:56,787 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/1677776407/.regio= ninfo size=3D632 > =A0 =A0[junit] 2009-10-30 22:42:56,787 DEBUG [main] regionserver.HRegion(= 2270): d hdfs://localhost:43785/user/hudson/TestMergeTool/1677776407/conten= ts > =A0 =A0[junit] 2009-10-30 22:42:56,788 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/1677776407/conten= ts/5042117736930384897 size=3D883 > =A0 =A0[junit] 2009-10-30 22:42:56,788 INFO =A0[main] regionserver.HRegio= n(771): Starting major compaction on region TestMergeTool,,1256942555142 > =A0 =A0[junit] 2009-10-30 22:42:56,798 DEBUG [main] regionserver.Store(75= 8): Started compaction of 1 file(s) =A0into /user/hudson/TestMergeTool/comp= action.dir/78342473, seqid=3D7 > =A0 =A0[junit] 2009-10-30 22:42:57,861 DEBUG [main] regionserver.Store(76= 5): Completed major compaction of contents; new storefile is hdfs://localho= st:43785/user/hudson/TestMergeTool/78342473/contents/3462988106158093510; s= tore size is 517 > =A0 =A0[junit] 2009-10-30 22:42:57,869 INFO =A0[main] regionserver.HRegio= n(786): compaction completed on region TestMergeTool,,1256942555142 in 1sec > =A0 =A0[junit] 2009-10-30 22:42:57,869 DEBUG [main] regionserver.HRegion(= 2131): Files for region: TestMergeTool,,1256942555142 > =A0 =A0[junit] 2009-10-30 22:42:57,870 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/78342473/.regioni= nfo size=3D582 > =A0 =A0[junit] 2009-10-30 22:42:57,871 DEBUG [main] regionserver.HRegion(= 2270): d hdfs://localhost:43785/user/hudson/TestMergeTool/78342473/contents > =A0 =A0[junit] 2009-10-30 22:42:57,871 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/78342473/contents= /3462988106158093510 size=3D517 > =A0 =A0[junit] 2009-10-30 22:42:57,872 INFO =A0[main] regionserver.HRegio= n(2160): Creating new region REGION =3D> {NAME =3D> 'TestMergeTool,,1256942= 577872', STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 1962079055, TABLE = =3D> {{NAME =3D> 'TestMergeTool', FAMILIES =3D> [{NAME =3D> 'contents', COM= PRESSION =3D> 'NONE', VERSIONS =3D> '3', TTL =3D> '2147483647', BLOCKSIZE = =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true'}]}} > =A0 =A0[junit] 2009-10-30 22:42:57,881 INFO =A0[main] regionserver.HRegio= n(2169): starting merge of regions: TestMergeTool,row_0100,1256942574320 an= d TestMergeTool,,1256942555142 into new region REGION =3D> {NAME =3D> 'Test= MergeTool,,1256942577872', STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 1= 962079055, TABLE =3D> {{NAME =3D> 'TestMergeTool', FAMILIES =3D> [{NAME =3D= > 'contents', COMPRESSION =3D> 'NONE', VERSIONS =3D> '3', TTL =3D> '2147483= 647', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true= '}]}} with start key <> and end key <> > =A0 =A0[junit] 2009-10-30 22:42:57,881 DEBUG [main] regionserver.HRegion(= 454): Closing TestMergeTool,row_0100,1256942574320: compactions & flushes d= isabled > =A0 =A0[junit] 2009-10-30 22:42:57,882 DEBUG [main] regionserver.HRegion(= 471): Updates disabled for region, no outstanding scanners on TestMergeTool= ,row_0100,1256942574320 > =A0 =A0[junit] 2009-10-30 22:42:57,882 DEBUG [main] regionserver.HRegion(= 478): No more row locks outstanding on region TestMergeTool,row_0100,125694= 2574320 > =A0 =A0[junit] 2009-10-30 22:42:57,882 DEBUG [main] regionserver.Store(48= 3): closed contents > =A0 =A0[junit] 2009-10-30 22:42:57,882 INFO =A0[main] regionserver.HRegio= n(490): Closed TestMergeTool,row_0100,1256942574320 > =A0 =A0[junit] 2009-10-30 22:42:57,883 DEBUG [main] regionserver.HRegion(= 454): Closing TestMergeTool,,1256942555142: compactions & flushes disabled > =A0 =A0[junit] 2009-10-30 22:42:57,883 DEBUG [main] regionserver.HRegion(= 471): Updates disabled for region, no outstanding scanners on TestMergeTool= ,,1256942555142 > =A0 =A0[junit] 2009-10-30 22:42:57,883 DEBUG [main] regionserver.HRegion(= 478): No more row locks outstanding on region TestMergeTool,,1256942555142 > =A0 =A0[junit] 2009-10-30 22:42:57,883 DEBUG [main] regionserver.Store(48= 3): closed contents > =A0 =A0[junit] 2009-10-30 22:42:57,883 INFO =A0[main] regionserver.HRegio= n(490): Closed TestMergeTool,,1256942555142 > =A0 =A0[junit] 2009-10-30 22:42:57,917 DEBUG [main] regionserver.HRegion(= 2202): Files for new region > =A0 =A0[junit] 2009-10-30 22:42:57,918 DEBUG [main] regionserver.HRegion(= 2270): d hdfs://localhost:43785/user/hudson/TestMergeTool/1962079055/conten= ts > =A0 =A0[junit] 2009-10-30 22:42:57,919 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/1962079055/conten= ts/6202673648315084005 size=3D517 > =A0 =A0[junit] 2009-10-30 22:42:57,919 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/1962079055/conten= ts/8569908871789881415 size=3D883 > =A0 =A0[junit] 2009-10-30 22:42:57,919 DEBUG [main] regionserver.HRegion(= 265): Opening region TestMergeTool,,1256942577872, encoded=3D1962079055 > =A0 =A0[junit] 2009-10-30 22:42:57,950 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/TestMergeTool/1962079055/contents/62026736483150840= 05, isReference=3Dfalse, sequence id=3D7, length=3D517, majorCompaction=3Dt= rue > =A0 =A0[junit] 2009-10-30 22:42:57,954 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/TestMergeTool/1962079055/contents/85699088717898814= 15, isReference=3Dfalse, sequence id=3D6, length=3D883, majorCompaction=3Dt= rue > =A0 =A0[junit] 2009-10-30 22:42:57,956 INFO =A0[main] regionserver.HRegio= n(343): region TestMergeTool,,1256942577872/1962079055 available; sequence = id is 8 > =A0 =A0[junit] 2009-10-30 22:42:57,956 INFO =A0[main] regionserver.HRegio= n(771): Starting compaction on region TestMergeTool,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:42:57,969 DEBUG [main] regionserver.Store(75= 1): Compaction size of contents: 1.4k; Skipped 0 file(s), size: 0 > =A0 =A0[junit] 2009-10-30 22:42:57,969 DEBUG [main] regionserver.Store(75= 8): Started compaction of 2 file(s) =A0into /user/hudson/TestMergeTool/comp= action.dir/1962079055, seqid=3D7 > =A0 =A0[junit] 2009-10-30 22:42:58,112 DEBUG [main] regionserver.Store(76= 5): Completed compaction of contents; new storefile is hdfs://localhost:437= 85/user/hudson/TestMergeTool/1962079055/contents/3326024334183207796; store= size is 1.0k > =A0 =A0[junit] 2009-10-30 22:42:58,128 INFO =A0[main] regionserver.HRegio= n(786): compaction completed on region TestMergeTool,,1256942577872 in 0sec > =A0 =A0[junit] 2009-10-30 22:42:58,129 DEBUG [main] regionserver.HRegion(= 2209): Files for new region > =A0 =A0[junit] 2009-10-30 22:42:58,130 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/1962079055/.regio= ninfo size=3D584 > =A0 =A0[junit] 2009-10-30 22:42:58,130 DEBUG [main] regionserver.HRegion(= 2270): d hdfs://localhost:43785/user/hudson/TestMergeTool/1962079055/conten= ts > =A0 =A0[junit] 2009-10-30 22:42:58,131 DEBUG [main] regionserver.HRegion(= 2273): f hdfs://localhost:43785/user/hudson/TestMergeTool/1962079055/conten= ts/3326024334183207796 size=3D1067 > =A0 =A0[junit] 2009-10-30 22:42:58,131 DEBUG [main] regionserver.HRegion(= 2005): DELETING region hdfs://localhost:43785/user/hudson/TestMergeTool/167= 7776407 > =A0 =A0[junit] 2009-10-30 22:42:58,148 DEBUG [main] regionserver.HRegion(= 2005): DELETING region hdfs://localhost:43785/user/hudson/TestMergeTool/783= 42473 > =A0 =A0[junit] 2009-10-30 22:42:58,164 INFO =A0[main] regionserver.HRegio= n(2215): merge completed. New region is TestMergeTool,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:42:58,164 DEBUG [main] util.Merge(319): Remo= ving region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0100,1256942574320',= STARTKEY =3D> 'row_0100', ENDKEY =3D> 'row_0600', ENCODED =3D> 1677776407,= TABLE =3D> {{NAME =3D> 'TestMergeTool', FAMILIES =3D> [{NAME =3D> 'content= s', COMPRESSION =3D> 'NONE', VERSIONS =3D> '3', TTL =3D> '2147483647', BLOC= KSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true'}]}} from= .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:58,165 DEBUG [main] util.Merge(319): Remo= ving region: REGION =3D> {NAME =3D> 'TestMergeTool,,1256942555142', STARTKE= Y =3D> '', ENDKEY =3D> '', ENCODED =3D> 78342473, TABLE =3D> {{NAME =3D> 'T= estMergeTool', FAMILIES =3D> [{NAME =3D> 'contents', VERSIONS =3D> '3', COM= PRESSION =3D> 'NONE', TTL =3D> '2147483647', BLOCKSIZE =3D> '65536', IN_MEM= ORY =3D> 'false', BLOCKCACHE =3D> 'true'}]}} from .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:58,173 INFO =A0[main] util.Merge(255): Ad= ding REGION =3D> {NAME =3D> 'TestMergeTool,,1256942577872', STARTKEY =3D> '= ', ENDKEY =3D> '', ENCODED =3D> 1962079055, TABLE =3D> {{NAME =3D> 'TestMer= geTool', FAMILIES =3D> [{NAME =3D> 'contents', COMPRESSION =3D> 'NONE', VER= SIONS =3D> '3', TTL =3D> '2147483647', BLOCKSIZE =3D> '65536', IN_MEMORY = =3D> 'false', BLOCKCACHE =3D> 'true'}]}} to REGION =3D> {NAME =3D> '.META.,= ,1', STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 1028785192, TABLE =3D> = {{NAME =3D> '.META.', IS_META =3D> 'true', FAMILIES =3D> [{NAME =3D> 'histo= rian', VERSIONS =3D> '2147483647', COMPRESSION =3D> 'NONE', TTL =3D> '60480= 0', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'}= , {NAME =3D> 'info', VERSIONS =3D> '10', COMPRESSION =3D> 'NONE', TTL =3D> = '2147483647', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'false', BLOCKCACHE =3D= > 'true'}]}} > =A0 =A0[junit] 2009-10-30 22:42:58,173 DEBUG [main] regionserver.HRegion(= 454): Closing TestMergeTool,,1256942577872: compactions & flushes disabled > =A0 =A0[junit] 2009-10-30 22:42:58,173 DEBUG [main] regionserver.HRegion(= 471): Updates disabled for region, no outstanding scanners on TestMergeTool= ,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:42:58,174 DEBUG [main] regionserver.HRegion(= 478): No more row locks outstanding on region TestMergeTool,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:42:58,174 DEBUG [main] regionserver.Store(48= 3): closed contents > =A0 =A0[junit] 2009-10-30 22:42:58,174 INFO =A0[main] regionserver.HRegio= n(490): Closed TestMergeTool,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:42:58,174 DEBUG [main] regionserver.HRegion(= 454): Closing -ROOT-,,0: compactions & flushes disabled > =A0 =A0[junit] 2009-10-30 22:42:58,174 DEBUG [main] regionserver.HRegion(= 471): Updates disabled for region, no outstanding scanners on -ROOT-,,0 > =A0 =A0[junit] 2009-10-30 22:42:58,175 DEBUG [main] regionserver.HRegion(= 478): No more row locks outstanding on region -ROOT-,,0 > =A0 =A0[junit] 2009-10-30 22:42:58,175 DEBUG [main] regionserver.Store(48= 3): closed info > =A0 =A0[junit] 2009-10-30 22:42:58,175 INFO =A0[main] regionserver.HRegio= n(490): Closed -ROOT-,,0 > =A0 =A0[junit] 2009-10-30 22:42:58,175 INFO =A0[main] util.MetaUtils(148)= : CLOSING META .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:58,176 DEBUG [main] regionserver.HRegion(= 454): Closing .META.,,1: compactions & flushes disabled > =A0 =A0[junit] 2009-10-30 22:42:58,176 DEBUG [main] regionserver.HRegion(= 471): Updates disabled for region, no outstanding scanners on .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:58,176 DEBUG [main] regionserver.HRegion(= 478): No more row locks outstanding on region .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:58,176 DEBUG [main] regionserver.HRegion(= 895): Started memstore flush for region .META.,,1. Current region memstore = size 968 > =A0 =A0[junit] 2009-10-30 22:42:59,247 DEBUG [main] regionserver.Store(56= 5): Added hdfs://localhost:43785/user/hudson/.META./1028785192/historian/67= 54698127872509198, entries=3D2, sequenceid=3D34, memsize=3D280, filesize=3D= 505 to .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:59,283 DEBUG [main] regionserver.Store(56= 5): Added hdfs://localhost:43785/user/hudson/.META./1028785192/info/9467838= 40010499771, entries=3D3, sequenceid=3D34, memsize=3D688, filesize=3D826 to= .META.,,1 > =A0 =A0[junit] 2009-10-30 22:42:59,283 DEBUG [main] regionserver.HRegion(= 971): Finished memstore flush of ~968 for region .META.,,1 in 1107ms, seque= nce id=3D34, compaction requested=3Dtrue > =A0 =A0[junit] 2009-10-30 22:42:59,284 DEBUG [main] regionserver.Store(48= 3): closed historian > =A0 =A0[junit] 2009-10-30 22:42:59,284 DEBUG [main] regionserver.Store(48= 3): closed info > =A0 =A0[junit] 2009-10-30 22:42:59,284 INFO =A0[main] regionserver.HRegio= n(490): Closed .META.,,1 > =A0 =A0[junit] 2009-10-30 22:43:00,349 INFO =A0[main] wal.HLog(322): Roll= /user/hudson/.logs_1256942576328/hlog.dat.1256942576348, entries=3D6, calc= size=3D1083, filesize=3D1047. New hlog /user/hudson/.logs_1256942576328/hlo= g.dat.1256942580336 > =A0 =A0[junit] 2009-10-30 22:43:00,350 DEBUG [main] wal.HLog(331): Last s= equence written is empty. Deleting all old hlogs > =A0 =A0[junit] 2009-10-30 22:43:00,350 INFO =A0[main] wal.HLog(550): remo= ving old hlog file /user/hudson/.logs_1256942576328/hlog.dat.1256942576348 = whose highest sequence/edit id is 33 > =A0 =A0[junit] 2009-10-30 22:43:00,364 DEBUG [main.logSyncer] wal.HLog$Lo= gSyncer(761): main.logSyncerinterrupted while waiting for sync requests > =A0 =A0[junit] 2009-10-30 22:43:00,366 INFO =A0[main.logSyncer] wal.HLog$= LogSyncer(765): main.logSyncer exiting > =A0 =A0[junit] 2009-10-30 22:43:00,366 DEBUG [main] wal.HLog(595): closin= g hlog writer in hdfs://localhost:43785/user/hudson/.logs_1256942576328 > =A0 =A0[junit] 2009-10-30 22:43:00,404 DEBUG [main] regionserver.HRegion(= 1887): Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,,1256942577872= ', STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 1962079055, TABLE =3D> {{= NAME =3D> 'TestMergeTool', FAMILIES =3D> [{NAME =3D> 'contents', COMPRESSIO= N =3D> 'NONE', VERSIONS =3D> '3', TTL =3D> '2147483647', BLOCKSIZE =3D> '65= 536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'true'}]}} > =A0 =A0[junit] 2009-10-30 22:43:00,404 DEBUG [main] regionserver.HRegion(= 265): Opening region TestMergeTool,,1256942577872, encoded=3D1962079055 > =A0 =A0[junit] 2009-10-30 22:43:00,411 DEBUG [main] regionserver.Store(41= 8): loaded /user/hudson/TestMergeTool/1962079055/contents/33260243341832077= 96, isReference=3Dfalse, sequence id=3D7, length=3D1067, majorCompaction=3D= false > =A0 =A0[junit] 2009-10-30 22:43:00,418 INFO =A0[main] regionserver.HRegio= n(343): region TestMergeTool,,1256942577872/1962079055 available; sequence = id is 8 > =A0 =A0[junit] 2009-10-30 22:43:00,423 DEBUG [main] regionserver.HRegion(= 454): Closing TestMergeTool,,1256942577872: compactions & flushes disabled > =A0 =A0[junit] 2009-10-30 22:43:00,423 DEBUG [main] regionserver.HRegion(= 471): Updates disabled for region, no outstanding scanners on TestMergeTool= ,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:43:00,424 DEBUG [main] regionserver.HRegion(= 478): No more row locks outstanding on region TestMergeTool,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:43:00,424 DEBUG [main] regionserver.Store(48= 3): closed contents > =A0 =A0[junit] 2009-10-30 22:43:00,424 INFO =A0[main] regionserver.HRegio= n(490): Closed TestMergeTool,,1256942577872 > =A0 =A0[junit] 2009-10-30 22:43:00,424 INFO =A0[main] util.TestMergeTool(= 186): Verified merging regions 0+1+2+3 and 4 > =A0 =A0[junit] 2009-10-30 22:43:00,424 DEBUG [main.logSyncer] wal.HLog$Lo= gSyncer(761): main.logSyncerinterrupted while waiting for sync requests > =A0 =A0[junit] 2009-10-30 22:43:00,425 INFO =A0[main.logSyncer] wal.HLog$= LogSyncer(765): main.logSyncer exiting > =A0 =A0[junit] 2009-10-30 22:43:00,425 DEBUG [main] wal.HLog(595): closin= g hlog writer in /tmp/.logs_1256942568205 > =A0 =A0[junit] 2009-10-30 22:43:01,511 INFO =A0[main] hbase.HBaseTestCase= (627): Shutting down Mini DFS > =A0 =A0[junit] Shutting down the Mini HDFS Cluster > =A0 =A0[junit] Shutting down DataNode 1 > =A0 =A0[junit] 2009-10-30 22:43:01,614 WARN =A0[org.apache.hadoop.hdfs.se= rver.datanode.DataXceiverServer@523df] datanode.DataXceiverServer(140): Dat= anodeRegistration(127.0.0.1:56417, storageID=3DDS-1412043641-67.195.138.9-5= 6417-1256942557976, infoPort=3D49864, ipcPort=3D60736):DataXceiveServer: ja= va.nio.channels.AsynchronousCloseException > =A0 =A0[junit] =A0 =A0 at java.nio.channels.spi.AbstractInterruptibleChan= nel.end(AbstractInterruptibleChannel.java:185) > =A0 =A0[junit] =A0 =A0 at sun.nio.ch.ServerSocketChannelImpl.accept(Serve= rSocketChannelImpl.java:152) > =A0 =A0[junit] =A0 =A0 at sun.nio.ch.ServerSocketAdaptor.accept(ServerSoc= ketAdaptor.java:84) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hdfs.server.datanode.DataXcei= verServer.run(DataXceiverServer.java:133) > =A0 =A0[junit] =A0 =A0 at java.lang.Thread.run(Thread.java:619) > =A0 =A0[junit] > =A0 =A0[junit] Shutting down DataNode 0 > =A0 =A0[junit] 2009-10-30 22:43:01,618 WARN =A0[org.apache.hadoop.hdfs.se= rver.datanode.DataXceiverServer@1e2afb2] datanode.DataXceiverServer(140): D= atanodeRegistration(127.0.0.1:38955, storageID=3DDS-153273490-67.195.138.9-= 38955-1256942557248, infoPort=3D35692, ipcPort=3D34391):DataXceiveServer: j= ava.nio.channels.AsynchronousCloseException > =A0 =A0[junit] =A0 =A0 at java.nio.channels.spi.AbstractInterruptibleChan= nel.end(AbstractInterruptibleChannel.java:185) > =A0 =A0[junit] =A0 =A0 at sun.nio.ch.ServerSocketChannelImpl.accept(Serve= rSocketChannelImpl.java:152) > =A0 =A0[junit] =A0 =A0 at sun.nio.ch.ServerSocketAdaptor.accept(ServerSoc= ketAdaptor.java:84) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hdfs.server.datanode.DataXcei= verServer.run(DataXceiverServer.java:133) > =A0 =A0[junit] =A0 =A0 at java.lang.Thread.run(Thread.java:619) > =A0 =A0[junit] > =A0 =A0[junit] 2009-10-30 22:43:01,746 WARN =A0[org.apache.hadoop.hdfs.se= rver.namenode.FSNamesystem$ReplicationMonitor@9444d1] namenode.FSNamesystem= $ReplicationMonitor(2333): ReplicationMonitor thread received InterruptedEx= ception.java.lang.InterruptedException: sleep interrupted > =A0 =A0[junit] 2009-10-30 22:43:01,748 WARN =A0[org.apache.hadoop.hdfs.se= rver.namenode.DecommissionManager$Monitor@77eaf8] namenode.DecommissionMana= ger$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep= interrupted > =A0 =A0[junit] 2009-10-30 22:43:01,757 INFO =A0[main] hbase.HBaseTestCase= (638): Shutting down FileSystem > =A0 =A0[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 26.791= sec > =A0 =A0[junit] Running org.apache.hadoop.hbase.util.TestRootPath > =A0 =A0[junit] 2009-10-30 22:43:02,277 INFO =A0[main] util.TestRootPath(6= 0): Got expected exception when checking invalid path: > =A0 =A0[junit] java.io.IOException: Root directory does not have a scheme > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.util.FSUtils.validateRo= otPath(FSUtils.java:229) > =A0 =A0[junit] =A0 =A0 at org.apache.hadoop.hbase.util.TestRootPath.testR= ootPath(TestRootPath.java:56) > =A0 =A0[junit] =A0 =A0 at sun.reflect.NativeMethodAccessorImpl.invoke0(Na= tive Method) > =A0 =A0[junit] =A0 =A0 at sun.reflect.NativeMethodAccessorImpl.invoke(Nat= iveMethodAccessorImpl.java:39) > =A0 =A0[junit] =A0 =A0 at sun.reflect.DelegatingMethodAccessorImpl.invoke= (DelegatingMethodAccessorImpl.java:25) > =A0 =A0[junit] =A0 =A0 at java.lang.reflect.Method.invoke(Method.java:597= ) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestCase.runTest(TestCase.java:= 168) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestCase.runBare(TestCase.java:= 134) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestResult$1.protect(TestResult= .java:110) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestResult.runProtected(TestRes= ult.java:128) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestResult.run(TestResult.java:= 113) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestCase.run(TestCase.java:124) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestSuite.runTest(TestSuite.jav= a:232) > =A0 =A0[junit] =A0 =A0 at junit.framework.TestSuite.run(TestSuite.java:22= 7) > =A0 =A0[junit] =A0 =A0 at org.junit.internal.runners.JUnit38ClassRunner.r= un(JUnit38ClassRunner.java:83) > =A0 =A0[junit] =A0 =A0 at junit.framework.JUnit4TestAdapter.run(JUnit4Tes= tAdapter.java:39) > =A0 =A0[junit] =A0 =A0 at org.apache.tools.ant.taskdefs.optional.junit.JU= nitTestRunner.run(JUnitTestRunner.java:421) > =A0 =A0[junit] =A0 =A0 at org.apache.tools.ant.taskdefs.optional.junit.JU= nitTestRunner.launch(JUnitTestRunner.java:912) > =A0 =A0[junit] =A0 =A0 at org.apache.tools.ant.taskdefs.optional.junit.JU= nitTestRunner.main(JUnitTestRunner.java:766) > =A0 =A0[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.078 = sec > > BUILD FAILED > :510: Tests failed! > > Total time: 24 minutes 12 seconds > [locks-and-latches] Releasing all the locks > [locks-and-latches] All the locks released > Archiving artifacts > Recording test results > Publishing Clover coverage report... > No Clover report will be published due to a Build Failure > >