Return-Path: Delivered-To: apmail-hadoop-hbase-dev-archive@minotaur.apache.org Received: (qmail 84766 invoked from network); 21 Mar 2009 03:03:18 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.2) by minotaur.apache.org with SMTP; 21 Mar 2009 03:03:18 -0000 Received: (qmail 70777 invoked by uid 500); 21 Mar 2009 03:03:18 -0000 Delivered-To: apmail-hadoop-hbase-dev-archive@hadoop.apache.org Received: (qmail 70763 invoked by uid 500); 21 Mar 2009 03:03:18 -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 70752 invoked by uid 99); 21 Mar 2009 03:03:18 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 20 Mar 2009 20:03:18 -0700 X-ASF-Spam-Status: No, hits=-2000.0 required=10.0 tests=ALL_TRUSTED X-Spam-Check-By: apache.org Received: from [140.211.11.106] (HELO hudson.zones.apache.org) (140.211.11.106) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 21 Mar 2009 03:03:09 +0000 Received: from hudson.zones.apache.org (localhost [127.0.0.1]) by hudson.zones.apache.org (8.13.8+Sun/8.13.8) with ESMTP id n2L32ltN019729 for ; Fri, 20 Mar 2009 23:02:48 -0400 (EDT) Date: Sat, 21 Mar 2009 03:02:47 +0000 (UTC) From: Apache Hudson Server To: hbase-dev@hadoop.apache.org Message-ID: <33506607.891237604567581.JavaMail.hudson@hudson.zones.apache.org> In-Reply-To: <6365321.761237564472291.JavaMail.hudson@hudson.zones.apache.org> References: <6365321.761237564472291.JavaMail.hudson@hudson.zones.apache.org> Subject: Build failed in Hudson: HBase-Patch #541 MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: quoted-printable X-Virus-Checked: Checked by ClamAV on apache.org See http://hudson.zones.apache.org/hudson/job/HBase-Patch/541/changes Changes: [stack] HBASE-1273 ZooKeeper WARN spits out lots of useless messages ------------------------------------------ [...truncated 24393 lines...] [junit] 2009-03-21 03:14:33,654 DEBUG [main] util.Merge(308): Removing = region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0100,1237605273239', STAR= TKEY =3D> 'row_0100', ENDKEY =3D> 'row_0400', ENCODED =3D> 1005975442, TABL= E =3D> {{NAME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'fal= se', FAMILIES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false', VERSIO= NS =3D> '3', COMPRESSION =3D> 'NONE', LENGTH =3D> '2147483647', TTL =3D> '-= 1', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'= }], INDEXES =3D> []}} from .META.,,1 [junit] 2009-03-21 03:14:33,657 DEBUG [main] util.Merge(308): Removing = region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0500,1237605239317', STAR= TKEY =3D> 'row_0500', ENDKEY =3D> 'row_0600', ENCODED =3D> 1336691590, TABL= E =3D> {{NAME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'fal= se', FAMILIES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false', VERSIO= NS =3D> '3', COMPRESSION =3D> 'NONE', LENGTH =3D> '2147483647', TTL =3D> '-= 1', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'= }], INDEXES =3D> []}} from .META.,,1 [junit] 2009-03-21 03:14:33,661 INFO [main] util.Merge(244): Adding RE= GION =3D> {NAME =3D> 'TestMergeTool,row_0100,1237605273604', STARTKEY =3D> = 'row_0100', ENDKEY =3D> 'row_0600', ENCODED =3D> 1303967493, TABLE =3D> {{N= AME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'false', FAMIL= IES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false', VERSIONS =3D> '3= ', COMPRESSION =3D> 'NONE', LENGTH =3D> '2147483647', TTL =3D> '-1', BLOCKS= IZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'}], INDEXE= S =3D> []}} to REGION =3D> {NAME =3D> '.META.,,1', STARTKEY =3D> '', ENDKEY= =3D> '', ENCODED =3D> 1028785192, TABLE =3D> {{NAME =3D> '.META.', IS_ROOT= =3D> 'false', IS_META =3D> 'true', FAMILIES =3D> [{NAME =3D> 'info', BLOOM= FILTER =3D> 'false', VERSIONS =3D> '10', COMPRESSION =3D> 'NONE', LENGTH = =3D> '2147483647', TTL =3D> '-1', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'fa= lse', BLOCKCACHE =3D> 'true'}, {NAME =3D> 'historian', BLOOMFILTER =3D> 'fa= lse', VERSIONS =3D> '2147483647', COMPRESSION =3D> 'NONE', LENGTH =3D> '214= 7483647', TTL =3D> '604800', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'false',= BLOCKCACHE =3D> 'false'}], INDEXES =3D> []}} [junit] 2009-03-21 03:14:33,662 DEBUG [main] regionserver.HRegion(385):= Closing TestMergeTool,row_0100,1237605273604: compactions & flushes disabl= ed=20 [junit] 2009-03-21 03:14:33,662 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on TestMergeTool,row_= 0100,1237605273604 [junit] 2009-03-21 03:14:33,662 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region TestMergeTool,row_0100,12376052736= 04 [junit] 2009-03-21 03:14:33,662 DEBUG [main] regionserver.Store(434): c= losed 1303967493/contents [junit] 2009-03-21 03:14:33,662 INFO [main] regionserver.HRegion(434):= Closed TestMergeTool,row_0100,1237605273604 [junit] 2009-03-21 03:14:33,663 DEBUG [main] regionserver.HRegion(385):= Closing -ROOT-,,0: compactions & flushes disabled=20 [junit] 2009-03-21 03:14:33,663 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on -ROOT-,,0 [junit] 2009-03-21 03:14:33,663 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region -ROOT-,,0 [junit] 2009-03-21 03:14:33,663 DEBUG [main] regionserver.Store(434): c= losed 70236052/info [junit] 2009-03-21 03:14:33,663 INFO [main] regionserver.HRegion(434):= Closed -ROOT-,,0 [junit] 2009-03-21 03:14:33,663 DEBUG [main] regionserver.HRegion(385):= Closing .META.,,1: compactions & flushes disabled=20 [junit] 2009-03-21 03:14:33,664 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on .META.,,1 [junit] 2009-03-21 03:14:33,664 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region .META.,,1 [junit] 2009-03-21 03:14:33,664 DEBUG [main] regionserver.HRegion(826):= Started memcache flush for region .META.,,1. Current region memcache size = 968.0 [junit] 2009-03-21 03:14:33,676 DEBUG [main] regionserver.Store(516): A= dded hdfs://localhost:52476/user/hudson/.META./1028785192/info/380520743808= 6958412, entries=3D3, sequenceid=3D17, memsize=3D968.0, filesize=3D968.0 to= .META.,,1 [junit] 2009-03-21 03:14:33,677 DEBUG [main] regionserver.HRegion(903):= Finished memcache flush of ~968.0 for region .META.,,1 in 13ms, sequence i= d=3D17, compaction requested=3Dtrue [junit] 2009-03-21 03:14:33,677 DEBUG [main] regionserver.Store(434): c= losed 1028785192/historian [junit] 2009-03-21 03:14:33,677 DEBUG [main] regionserver.Store(434): c= losed 1028785192/info [junit] 2009-03-21 03:14:33,677 INFO [main] regionserver.HRegion(434):= Closed .META.,,1 [junit] 2009-03-21 03:14:33,685 INFO [main] regionserver.HLog(273): Cl= osed hdfs://localhost:52476/user/hudson/log_1237605273471/hlog.dat.0, entri= es=3D4. New log writer: /user/hudson/log_1237605273471/hlog.dat.12376052736= 84 [junit] 2009-03-21 03:14:33,686 DEBUG [main] regionserver.HLog(280): La= st sequence written is empty. Deleting all old hlogs [junit] 2009-03-21 03:14:33,686 INFO [main] regionserver.HLog(391): re= moving old log file /user/hudson/log_1237605273471/hlog.dat.0 whose highest= sequence/edit id is 17 [junit] 2009-03-21 03:14:33,687 DEBUG [main] regionserver.HLog(427): cl= osing log writer in hdfs://localhost:52476/user/hudson/log_1237605273471 [junit] 2009-03-21 03:14:33,695 DEBUG [main] regionserver.HRegion(2200)= : Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0100,1237605273= 604', STARTKEY =3D> 'row_0100', ENDKEY =3D> 'row_0600', ENCODED =3D> 130396= 7493, TABLE =3D> {{NAME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META= =3D> 'false', FAMILIES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'fals= e', VERSIONS =3D> '3', COMPRESSION =3D> 'NONE', LENGTH =3D> '2147483647', T= TL =3D> '-1', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE = =3D> 'false'}], INDEXES =3D> []}} [junit] 2009-03-21 03:14:33,695 DEBUG [main] regionserver.HRegion(237):= Opening region TestMergeTool,row_0100,1237605273604/1303967493 [junit] 2009-03-21 03:14:33,700 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/TestMergeTool/1303967493/contents/1136725411393157603, i= sReference=3Dfalse, sequence id=3D5, length=3D804, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:33,701 DEBUG [main] regionserver.Store(213): L= oaded 1 file(s) in Store 1303967493/contents, max sequence id 5 [junit] 2009-03-21 03:14:33,702 DEBUG [main] regionserver.HRegion(297):= Next sequence id for region TestMergeTool,row_0100,1237605273604 is 6 [junit] 2009-03-21 03:14:33,703 INFO [main] regionserver.HRegion(314):= region TestMergeTool,row_0100,1237605273604/1303967493 available [junit] 2009-03-21 03:14:33,703 DEBUG [main] regionserver.HLog(217): ch= anging sequence number from 5 to 6 [junit] 2009-03-21 03:14:33,717 DEBUG [main] regionserver.HRegion(385):= Closing TestMergeTool,row_0100,1237605273604: compactions & flushes disabl= ed=20 [junit] 2009-03-21 03:14:33,717 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on TestMergeTool,row_= 0100,1237605273604 [junit] 2009-03-21 03:14:33,717 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region TestMergeTool,row_0100,12376052736= 04 [junit] 2009-03-21 03:14:33,718 DEBUG [main] regionserver.Store(434): c= losed 1303967493/contents [junit] 2009-03-21 03:14:33,718 INFO [main] regionserver.HRegion(434):= Closed TestMergeTool,row_0100,1237605273604 [junit] 2009-03-21 03:14:33,718 INFO [main] util.TestMergeTool(169): V= erified merging regions 0+1+2 and 3 [junit] 2009-03-21 03:14:33,718 INFO [main] util.TestMergeTool(156): m= erging regions 0+1+2+3 and 4 [junit] 2009-03-21 03:14:33,719 INFO [main] util.Merge(80): Verifying = that file system is available... [junit] 2009-03-21 03:14:33,719 INFO [main] util.Merge(89): Verifying = that HBase is not running... [junit] 2009-03-21 03:14:33,849 INFO [main] util.Merge(190): Merging r= egions TestMergeTool,row_0100,1237605273604 and TestMergeTool,,123760523931= 7 in table TestMergeTool [junit] 2009-03-21 03:14:33,853 INFO [main] regionserver.HLog(273): Ne= w log writer: /user/hudson/log_1237605273850/hlog.dat.1237605273851 [junit] 2009-03-21 03:14:33,853 DEBUG [main] regionserver.HRegion(2200)= : Opening region: REGION =3D> {NAME =3D> '-ROOT-,,0', STARTKEY =3D> '', END= KEY =3D> '', ENCODED =3D> 70236052, TABLE =3D> {{NAME =3D> '-ROOT-', IS_ROO= T =3D> 'true', IS_META =3D> 'true', FAMILIES =3D> [{NAME =3D> 'info', BLOOM= FILTER =3D> 'false', COMPRESSION =3D> 'NONE', VERSIONS =3D> '10', LENGTH = =3D> '2147483647', TTL =3D> '-1', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'fa= lse', BLOCKCACHE =3D> 'true'}], INDEXES =3D> []}} [junit] 2009-03-21 03:14:33,853 DEBUG [main] regionserver.HRegion(237):= Opening region -ROOT-,,0/70236052 [junit] 2009-03-21 03:14:33,858 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/-ROOT-/70236052/info/3693442419111546714, isReference=3D= false, sequence id=3D1, length=3D856, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:33,858 DEBUG [main] regionserver.Store(213): L= oaded 1 file(s) in Store 70236052/info, max sequence id 1 [junit] 2009-03-21 03:14:33,859 DEBUG [main] regionserver.HRegion(297):= Next sequence id for region -ROOT-,,0 is 2 [junit] 2009-03-21 03:14:33,860 INFO [main] regionserver.HRegion(314):= region -ROOT-,,0/70236052 available [junit] 2009-03-21 03:14:33,861 DEBUG [main] regionserver.HLog(217): ch= anging sequence number from 0 to 2 [junit] 2009-03-21 03:14:33,861 INFO [main] regionserver.HRegion(701):= starting compaction on region -ROOT-,,0 [junit] 2009-03-21 03:14:33,862 INFO [main] regionserver.HRegion(716):= compaction completed on region -ROOT-,,0 in 0sec [junit] 2009-03-21 03:14:33,864 INFO [main] util.Merge(204): Found met= a for region1 .META.,,1, meta for region2 .META.,,1 [junit] 2009-03-21 03:14:33,865 DEBUG [main] regionserver.HRegion(2200)= : Opening region: REGION =3D> {NAME =3D> '.META.,,1', STARTKEY =3D> '', END= KEY =3D> '', ENCODED =3D> 1028785192, TABLE =3D> {{NAME =3D> '.META.', IS_R= OOT =3D> 'false', IS_META =3D> 'true', FAMILIES =3D> [{NAME =3D> 'info', BL= OOMFILTER =3D> 'false', VERSIONS =3D> '10', COMPRESSION =3D> 'NONE', LENGTH= =3D> '2147483647', TTL =3D> '-1', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'f= alse', BLOCKCACHE =3D> 'true'}, {NAME =3D> 'historian', BLOOMFILTER =3D> 'f= alse', VERSIONS =3D> '2147483647', COMPRESSION =3D> 'NONE', LENGTH =3D> '21= 47483647', TTL =3D> '604800', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'false'= , BLOCKCACHE =3D> 'false'}], INDEXES =3D> []}} [junit] 2009-03-21 03:14:33,865 DEBUG [main] regionserver.HRegion(237):= Opening region .META.,,1/1028785192 [junit] 2009-03-21 03:14:33,870 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/.META./1028785192/info/245885823880984734, isReference= =3Dfalse, sequence id=3D13, length=3D1531, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:33,873 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/.META./1028785192/info/3330784844313168751, isReference= =3Dfalse, sequence id=3D5, length=3D2330, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:33,877 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/.META./1028785192/info/3805207438086958412, isReference= =3Dfalse, sequence id=3D17, length=3D968, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:33,877 DEBUG [main] regionserver.Store(213): L= oaded 3 file(s) in Store 1028785192/info, max sequence id 17 [junit] 2009-03-21 03:14:33,888 DEBUG [main] regionserver.HRegion(297):= Next sequence id for region .META.,,1 is 18 [junit] 2009-03-21 03:14:33,889 INFO [main] regionserver.HRegion(314):= region .META.,,1/1028785192 available [junit] 2009-03-21 03:14:33,889 DEBUG [main] regionserver.HLog(217): ch= anging sequence number from 2 to 18 [junit] 2009-03-21 03:14:33,889 INFO [main] regionserver.HRegion(701):= starting compaction on region .META.,,1 [junit] 2009-03-21 03:14:33,890 DEBUG [main] regionserver.Store(619): 1= 028785192/historian: no store files to compact [junit] 2009-03-21 03:14:33,891 DEBUG [main] regionserver.Store(683): C= ompaction size of 1028785192/info: 4.7k; Skipped 0 file(s), size: 0 [junit] 2009-03-21 03:14:33,895 DEBUG [main] regionserver.Store(692): S= tarted compaction of 3 file(s) into /user/hudson/.META./1028785192/info/80= 69999046440989056 [junit] 2009-03-21 03:14:33,916 DEBUG [main] regionserver.Store(707): C= ompleted compaction of 1028785192/info store size is 3.9k [junit] 2009-03-21 03:14:33,916 INFO [main] regionserver.HRegion(716):= compaction completed on region .META.,,1 in 0sec [junit] 2009-03-21 03:14:33,919 DEBUG [main] regionserver.HRegion(2200)= : Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0100,1237605273= 604', STARTKEY =3D> 'row_0100', ENDKEY =3D> 'row_0600', ENCODED =3D> 130396= 7493, TABLE =3D> {{NAME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META= =3D> 'false', FAMILIES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'fals= e', COMPRESSION =3D> 'NONE', VERSIONS =3D> '3', LENGTH =3D> '2147483647', T= TL =3D> '-1', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE = =3D> 'false'}], INDEXES =3D> []}} [junit] 2009-03-21 03:14:33,920 DEBUG [main] regionserver.HRegion(237):= Opening region TestMergeTool,row_0100,1237605273604/1303967493 [junit] 2009-03-21 03:14:33,924 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/TestMergeTool/1303967493/contents/1136725411393157603, i= sReference=3Dfalse, sequence id=3D5, length=3D804, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:33,925 DEBUG [main] regionserver.Store(213): L= oaded 1 file(s) in Store 1303967493/contents, max sequence id 5 [junit] 2009-03-21 03:14:33,925 DEBUG [main] regionserver.HRegion(297):= Next sequence id for region TestMergeTool,row_0100,1237605273604 is 6 [junit] 2009-03-21 03:14:33,927 INFO [main] regionserver.HRegion(314):= region TestMergeTool,row_0100,1237605273604/1303967493 available [junit] 2009-03-21 03:14:33,927 DEBUG [main] regionserver.HRegion(2200)= : Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,,1237605239317', ST= ARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 543356916, TABLE =3D> {{NAME = =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'false', FAMILIES = =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false', VERSIONS =3D> '3', C= OMPRESSION =3D> 'NONE', LENGTH =3D> '2147483647', TTL =3D> '-1', BLOCKSIZE = =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'}], INDEXES = =3D> []}} [junit] 2009-03-21 03:14:33,927 DEBUG [main] regionserver.HRegion(237):= Opening region TestMergeTool,,1237605239317/543356916 [junit] 2009-03-21 03:14:33,932 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/TestMergeTool/543356916/contents/124016612862438724, isR= eference=3Dfalse, sequence id=3D6, length=3D419, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:33,932 DEBUG [main] regionserver.Store(213): L= oaded 1 file(s) in Store 543356916/contents, max sequence id 6 [junit] 2009-03-21 03:14:33,933 DEBUG [main] regionserver.HRegion(297):= Next sequence id for region TestMergeTool,,1237605239317 is 7 [junit] 2009-03-21 03:14:33,934 INFO [main] regionserver.HRegion(314):= region TestMergeTool,,1237605239317/543356916 available [junit] 2009-03-21 03:14:33,934 INFO [main] regionserver.HRegion(701):= starting major compaction on region TestMergeTool,row_0100,1237605273604 [junit] 2009-03-21 03:14:33,939 DEBUG [main] regionserver.Store(692): S= tarted compaction of 1 file(s) into /user/hudson/TestMergeTool/1303967493/= contents/5208188366971552175 [junit] 2009-03-21 03:14:33,975 DEBUG [main] regionserver.Store(707): C= ompleted major compaction of 1303967493/contents store size is 804.0 [junit] 2009-03-21 03:14:33,975 INFO [main] regionserver.HRegion(716):= compaction completed on region TestMergeTool,row_0100,1237605273604 in 0se= c [junit] 2009-03-21 03:14:33,975 DEBUG [main] regionserver.HRegion(2440)= : Files for region: TestMergeTool,row_0100,1237605273604 [junit] 2009-03-21 03:14:33,976 DEBUG [main] regionserver.HRegion(2585)= : d hdfs://localhost:52476/user/hudson/TestMergeTool/1303967493/contents [junit] 2009-03-21 03:14:33,977 DEBUG [main] regionserver.HRegion(2588)= : f hdfs://localhost:52476/user/hudson/TestMergeTool/1303967493/contents/77= 91574484341008861 size=3D804 [junit] 2009-03-21 03:14:33,977 INFO [main] regionserver.HRegion(701):= starting major compaction on region TestMergeTool,,1237605239317 [junit] 2009-03-21 03:14:33,981 DEBUG [main] regionserver.Store(692): S= tarted compaction of 1 file(s) into /user/hudson/TestMergeTool/543356916/c= ontents/261534484213113778 [junit] 2009-03-21 03:14:33,994 DEBUG [main] regionserver.Store(707): C= ompleted major compaction of 543356916/contents store size is 419.0 [junit] 2009-03-21 03:14:33,995 INFO [main] regionserver.HRegion(716):= compaction completed on region TestMergeTool,,1237605239317 in 0sec [junit] 2009-03-21 03:14:33,995 DEBUG [main] regionserver.HRegion(2445)= : Files for region: TestMergeTool,,1237605239317 [junit] 2009-03-21 03:14:33,995 DEBUG [main] regionserver.HRegion(2585)= : d hdfs://localhost:52476/user/hudson/TestMergeTool/543356916/contents [junit] 2009-03-21 03:14:33,996 DEBUG [main] regionserver.HRegion(2588)= : f hdfs://localhost:52476/user/hudson/TestMergeTool/543356916/contents/563= 8007260606810157 size=3D419 [junit] 2009-03-21 03:14:33,996 INFO [main] regionserver.HRegion(2466)= : Creating new region REGION =3D> {NAME =3D> 'TestMergeTool,,1237605273996'= , STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 1245836152, TABLE =3D> {{N= AME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'false', FAMIL= IES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false', COMPRESSION =3D>= 'NONE', VERSIONS =3D> '3', LENGTH =3D> '2147483647', TTL =3D> '-1', BLOCKS= IZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'}], INDEXE= S =3D> []}} [junit] 2009-03-21 03:14:33,998 INFO [main] regionserver.HRegion(2475)= : starting merge of regions: TestMergeTool,row_0100,1237605273604 and TestM= ergeTool,,1237605239317 into new region REGION =3D> {NAME =3D> 'TestMergeTo= ol,,1237605273996', STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 12458361= 52, TABLE =3D> {{NAME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META = =3D> 'false', FAMILIES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false= ', COMPRESSION =3D> 'NONE', VERSIONS =3D> '3', LENGTH =3D> '2147483647', TT= L =3D> '-1', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D= > 'false'}], INDEXES =3D> []}} with start key <> and end key <> [junit] 2009-03-21 03:14:33,998 DEBUG [main] regionserver.HRegion(385):= Closing TestMergeTool,row_0100,1237605273604: compactions & flushes disabl= ed=20 [junit] 2009-03-21 03:14:33,998 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on TestMergeTool,row_= 0100,1237605273604 [junit] 2009-03-21 03:14:33,998 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region TestMergeTool,row_0100,12376052736= 04 [junit] 2009-03-21 03:14:33,999 DEBUG [main] regionserver.Store(434): c= losed 1303967493/contents [junit] 2009-03-21 03:14:33,999 INFO [main] regionserver.HRegion(434):= Closed TestMergeTool,row_0100,1237605273604 [junit] 2009-03-21 03:14:33,999 DEBUG [main] regionserver.HRegion(385):= Closing TestMergeTool,,1237605239317: compactions & flushes disabled=20 [junit] 2009-03-21 03:14:33,999 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on TestMergeTool,,123= 7605239317 [junit] 2009-03-21 03:14:34,000 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region TestMergeTool,,1237605239317 [junit] 2009-03-21 03:14:34,000 DEBUG [main] regionserver.Store(434): c= losed 543356916/contents [junit] 2009-03-21 03:14:34,000 INFO [main] regionserver.HRegion(434):= Closed TestMergeTool,,1237605239317 [junit] 2009-03-21 03:14:34,009 DEBUG [main] regionserver.HRegion(2510)= : Files for new region [junit] 2009-03-21 03:14:34,010 DEBUG [main] regionserver.HRegion(2585)= : d hdfs://localhost:52476/user/hudson/TestMergeTool/1245836152/contents [junit] 2009-03-21 03:14:34,011 DEBUG [main] regionserver.HRegion(2588)= : f hdfs://localhost:52476/user/hudson/TestMergeTool/1245836152/contents/32= 35560532777246106 size=3D804 [junit] 2009-03-21 03:14:34,011 DEBUG [main] regionserver.HRegion(2588)= : f hdfs://localhost:52476/user/hudson/TestMergeTool/1245836152/contents/76= 90365460247261377 size=3D419 [junit] 2009-03-21 03:14:34,011 DEBUG [main] regionserver.HRegion(237):= Opening region TestMergeTool,,1237605273996/1245836152 [junit] 2009-03-21 03:14:34,016 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/TestMergeTool/1245836152/contents/3235560532777246106, i= sReference=3Dfalse, sequence id=3D5, length=3D804, majorCompaction=3Dtrue [junit] 2009-03-21 03:14:34,020 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/TestMergeTool/1245836152/contents/7690365460247261377, i= sReference=3Dfalse, sequence id=3D6, length=3D419, majorCompaction=3Dtrue [junit] 2009-03-21 03:14:34,020 DEBUG [main] regionserver.Store(213): L= oaded 2 file(s) in Store 1245836152/contents, max sequence id 6 [junit] 2009-03-21 03:14:34,021 DEBUG [main] regionserver.HRegion(297):= Next sequence id for region TestMergeTool,,1237605273996 is 7 [junit] 2009-03-21 03:14:34,040 INFO [main] regionserver.HRegion(314):= region TestMergeTool,,1237605273996/1245836152 available [junit] 2009-03-21 03:14:34,040 INFO [main] regionserver.HRegion(701):= starting compaction on region TestMergeTool,,1237605273996 [junit] 2009-03-21 03:14:34,042 DEBUG [main] regionserver.Store(683): C= ompaction size of 1245836152/contents: 1.2k; Skipped 0 file(s), size: 0 [junit] 2009-03-21 03:14:34,045 DEBUG [main] regionserver.Store(692): S= tarted compaction of 2 file(s) into /user/hudson/TestMergeTool/1245836152/= contents/694636035773228276 [junit] 2009-03-21 03:14:34,060 DEBUG [main] regionserver.Store(707): C= ompleted compaction of 1245836152/contents store size is 890.0 [junit] 2009-03-21 03:14:34,060 INFO [main] regionserver.HRegion(716):= compaction completed on region TestMergeTool,,1237605273996 in 0sec [junit] 2009-03-21 03:14:34,061 DEBUG [main] regionserver.HRegion(2517)= : Files for new region [junit] 2009-03-21 03:14:34,061 DEBUG [main] regionserver.HRegion(2585)= : d hdfs://localhost:52476/user/hudson/TestMergeTool/1245836152/contents [junit] 2009-03-21 03:14:34,062 DEBUG [main] regionserver.HRegion(2588)= : f hdfs://localhost:52476/user/hudson/TestMergeTool/1245836152/contents/50= 01104343695015167 size=3D890 [junit] 2009-03-21 03:14:34,062 DEBUG [main] regionserver.HRegion(2318)= : DELETING region hdfs://localhost:52476/user/hudson/TestMergeTool/13039674= 93 [junit] 2009-03-21 03:14:34,063 DEBUG [main] regionserver.HRegion(2318)= : DELETING region hdfs://localhost:52476/user/hudson/TestMergeTool/54335691= 6 [junit] 2009-03-21 03:14:34,064 INFO [main] regionserver.HRegion(2523)= : merge completed. New region is TestMergeTool,,1237605273996 [junit] 2009-03-21 03:14:34,064 DEBUG [main] util.Merge(308): Removing = region: REGION =3D> {NAME =3D> 'TestMergeTool,row_0100,1237605273604', STAR= TKEY =3D> 'row_0100', ENDKEY =3D> 'row_0600', ENCODED =3D> 1303967493, TABL= E =3D> {{NAME =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'fal= se', FAMILIES =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false', COMPRE= SSION =3D> 'NONE', VERSIONS =3D> '3', LENGTH =3D> '2147483647', TTL =3D> '-= 1', BLOCKSIZE =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'= }], INDEXES =3D> []}} from .META.,,1 [junit] 2009-03-21 03:14:34,066 DEBUG [main] util.Merge(308): Removing = region: REGION =3D> {NAME =3D> 'TestMergeTool,,1237605239317', STARTKEY =3D= > '', ENDKEY =3D> '', ENCODED =3D> 543356916, TABLE =3D> {{NAME =3D> 'TestM= ergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'false', FAMILIES =3D> [{NAME= =3D> 'contents', BLOOMFILTER =3D> 'false', VERSIONS =3D> '3', COMPRESSION = =3D> 'NONE', LENGTH =3D> '2147483647', TTL =3D> '-1', BLOCKSIZE =3D> '65536= ', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'}], INDEXES =3D> []}} fro= m .META.,,1 [junit] 2009-03-21 03:14:34,069 INFO [main] util.Merge(244): Adding RE= GION =3D> {NAME =3D> 'TestMergeTool,,1237605273996', STARTKEY =3D> '', ENDK= EY =3D> '', ENCODED =3D> 1245836152, TABLE =3D> {{NAME =3D> 'TestMergeTool'= , IS_ROOT =3D> 'false', IS_META =3D> 'false', FAMILIES =3D> [{NAME =3D> 'co= ntents', BLOOMFILTER =3D> 'false', COMPRESSION =3D> 'NONE', VERSIONS =3D> '= 3', LENGTH =3D> '2147483647', TTL =3D> '-1', BLOCKSIZE =3D> '65536', IN_MEM= ORY =3D> 'false', BLOCKCACHE =3D> 'false'}], INDEXES =3D> []}} to REGION = =3D> {NAME =3D> '.META.,,1', STARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D>= 1028785192, TABLE =3D> {{NAME =3D> '.META.', IS_ROOT =3D> 'false', IS_META= =3D> 'true', FAMILIES =3D> [{NAME =3D> 'info', BLOOMFILTER =3D> 'false', V= ERSIONS =3D> '10', COMPRESSION =3D> 'NONE', LENGTH =3D> '2147483647', TTL = =3D> '-1', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> '= true'}, {NAME =3D> 'historian', BLOOMFILTER =3D> 'false', VERSIONS =3D> '21= 47483647', COMPRESSION =3D> 'NONE', LENGTH =3D> '2147483647', TTL =3D> '604= 800', BLOCKSIZE =3D> '8192', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false= '}], INDEXES =3D> []}} [junit] 2009-03-21 03:14:34,069 DEBUG [main] regionserver.HRegion(385):= Closing TestMergeTool,,1237605273996: compactions & flushes disabled=20 [junit] 2009-03-21 03:14:34,069 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on TestMergeTool,,123= 7605273996 [junit] 2009-03-21 03:14:34,070 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region TestMergeTool,,1237605273996 [junit] 2009-03-21 03:14:34,070 DEBUG [main] regionserver.Store(434): c= losed 1245836152/contents [junit] 2009-03-21 03:14:34,070 INFO [main] regionserver.HRegion(434):= Closed TestMergeTool,,1237605273996 [junit] 2009-03-21 03:14:34,070 DEBUG [main] regionserver.HRegion(385):= Closing -ROOT-,,0: compactions & flushes disabled=20 [junit] 2009-03-21 03:14:34,070 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on -ROOT-,,0 [junit] 2009-03-21 03:14:34,070 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region -ROOT-,,0 [junit] 2009-03-21 03:14:34,071 DEBUG [main] regionserver.Store(434): c= losed 70236052/info [junit] 2009-03-21 03:14:34,071 INFO [main] regionserver.HRegion(434):= Closed -ROOT-,,0 [junit] 2009-03-21 03:14:34,071 DEBUG [main] regionserver.HRegion(385):= Closing .META.,,1: compactions & flushes disabled=20 [junit] 2009-03-21 03:14:34,071 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on .META.,,1 [junit] 2009-03-21 03:14:34,071 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region .META.,,1 [junit] 2009-03-21 03:14:34,072 DEBUG [main] regionserver.HRegion(826):= Started memcache flush for region .META.,,1. Current region memcache size = 928.0 [junit] 2009-03-21 03:14:34,092 DEBUG [main] regionserver.Store(516): A= dded hdfs://localhost:52476/user/hudson/.META./1028785192/info/260419501019= 0926965, entries=3D3, sequenceid=3D21, memsize=3D928.0, filesize=3D920.0 to= .META.,,1 [junit] 2009-03-21 03:14:34,092 DEBUG [main] regionserver.HRegion(903):= Finished memcache flush of ~928.0 for region .META.,,1 in 20ms, sequence i= d=3D21, compaction requested=3Dtrue [junit] 2009-03-21 03:14:34,093 DEBUG [main] regionserver.Store(434): c= losed 1028785192/historian [junit] 2009-03-21 03:14:34,093 DEBUG [main] regionserver.Store(434): c= losed 1028785192/info [junit] 2009-03-21 03:14:34,093 INFO [main] regionserver.HRegion(434):= Closed .META.,,1 [junit] 2009-03-21 03:14:34,100 INFO [main] regionserver.HLog(273): Cl= osed hdfs://localhost:52476/user/hudson/log_1237605273850/hlog.dat.0, entri= es=3D4. New log writer: /user/hudson/log_1237605273850/hlog.dat.12376052740= 98 [junit] 2009-03-21 03:14:34,100 DEBUG [main] regionserver.HLog(280): La= st sequence written is empty. Deleting all old hlogs [junit] 2009-03-21 03:14:34,100 INFO [main] regionserver.HLog(391): re= moving old log file /user/hudson/log_1237605273850/hlog.dat.0 whose highest= sequence/edit id is 21 [junit] 2009-03-21 03:14:34,100 DEBUG [main] regionserver.HLog(427): cl= osing log writer in hdfs://localhost:52476/user/hudson/log_1237605273850 [junit] 2009-03-21 03:14:34,106 DEBUG [main] regionserver.HRegion(2200)= : Opening region: REGION =3D> {NAME =3D> 'TestMergeTool,,1237605273996', ST= ARTKEY =3D> '', ENDKEY =3D> '', ENCODED =3D> 1245836152, TABLE =3D> {{NAME = =3D> 'TestMergeTool', IS_ROOT =3D> 'false', IS_META =3D> 'false', FAMILIES = =3D> [{NAME =3D> 'contents', BLOOMFILTER =3D> 'false', COMPRESSION =3D> 'NO= NE', VERSIONS =3D> '3', LENGTH =3D> '2147483647', TTL =3D> '-1', BLOCKSIZE = =3D> '65536', IN_MEMORY =3D> 'false', BLOCKCACHE =3D> 'false'}], INDEXES = =3D> []}} [junit] 2009-03-21 03:14:34,106 DEBUG [main] regionserver.HRegion(237):= Opening region TestMergeTool,,1237605273996/1245836152 [junit] 2009-03-21 03:14:34,111 DEBUG [main] regionserver.Store(383): l= oaded /user/hudson/TestMergeTool/1245836152/contents/5001104343695015167, i= sReference=3Dfalse, sequence id=3D6, length=3D890, majorCompaction=3Dfalse [junit] 2009-03-21 03:14:34,111 DEBUG [main] regionserver.Store(213): L= oaded 1 file(s) in Store 1245836152/contents, max sequence id 6 [junit] 2009-03-21 03:14:34,112 DEBUG [main] regionserver.HRegion(297):= Next sequence id for region TestMergeTool,,1237605273996 is 7 [junit] 2009-03-21 03:14:34,113 INFO [main] regionserver.HRegion(314):= region TestMergeTool,,1237605273996/1245836152 available [junit] 2009-03-21 03:14:34,113 DEBUG [main] regionserver.HLog(217): ch= anging sequence number from 6 to 7 [junit] 2009-03-21 03:14:34,131 DEBUG [main] regionserver.HRegion(385):= Closing TestMergeTool,,1237605273996: compactions & flushes disabled=20 [junit] 2009-03-21 03:14:34,132 DEBUG [main] regionserver.HRegion(415):= Updates disabled for region, no outstanding scanners on TestMergeTool,,123= 7605273996 [junit] 2009-03-21 03:14:34,132 DEBUG [main] regionserver.HRegion(422):= No more row locks outstanding on region TestMergeTool,,1237605273996 [junit] 2009-03-21 03:14:34,132 DEBUG [main] regionserver.Store(434): c= losed 1245836152/contents [junit] 2009-03-21 03:14:34,132 INFO [main] regionserver.HRegion(434):= Closed TestMergeTool,,1237605273996 [junit] 2009-03-21 03:14:34,132 INFO [main] util.TestMergeTool(169): V= erified merging regions 0+1+2+3 and 4 [junit] 2009-03-21 03:14:34,132 DEBUG [main] regionserver.HLog(427): cl= osing log writer in /tmp/log_1237605272462 [junit] 2009-03-21 03:14:34,138 INFO [main] hbase.HBaseTestCase(587): = Shutting down FileSystem [junit] 2009-03-21 03:14:34,138 INFO [main] hbase.HBaseTestCase(594): = Shutting down Mini DFS=20 [junit] Shutting down the Mini HDFS Cluster [junit] Shutting down DataNode 1 [junit] 2009-03-21 03:14:34,138 INFO [Acceptor ServerSocket[addr=3Dloc= alhost/127.0.0.1,port=3D0,localport=3D52363]] util.ThreadedServer$Acceptor(= 656): Stopping Acceptor ServerSocket[addr=3Dlocalhost/127.0.0.1,port=3D0,lo= calport=3D52363] [junit] 2009-03-21 03:14:34,142 INFO [main] http.SocketListener(212): = Stopped SocketListener on 127.0.0.1:52363 [junit] 2009-03-21 03:14:34,142 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.servlet.WebApplicationHandler@1a5ec6c [junit] 2009-03-21 03:14:34,218 INFO [main] util.Container(156): Stopp= ed WebApplicationContext[/static,/static] [junit] 2009-03-21 03:14:34,219 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.servlet.WebApplicationHandler@c1186f [junit] 2009-03-21 03:14:34,277 INFO [main] util.Container(156): Stopp= ed WebApplicationContext[/,/] [junit] 2009-03-21 03:14:34,277 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.Server@11a4bd4 [junit] 2009-03-21 03:14:34,281 WARN [org.apache.hadoop.hdfs.server.da= tanode.DataXceiverServer@8e4805] datanode.DataXceiverServer(134): DatanodeR= egistration(127.0.0.1:40290, storageID=3DDS-1046087803-67.195.138.9-40290-1= 237605271956, infoPort=3D52363, ipcPort=3D42476):DataXceiveServer: java.nio= .channels.AsynchronousCloseException [junit] =09at java.nio.channels.spi.AbstractInterruptibleChannel.end(Ab= stractInterruptibleChannel.java:185) [junit] =09at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketCha= nnelImpl.java:152) [junit] =09at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor= .java:84) [junit] =09at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.= run(DataXceiverServer.java:129) [junit] =09at java.lang.Thread.run(Thread.java:619) [junit]=20 [junit] Shutting down DataNode 0 [junit] 2009-03-21 03:14:34,282 INFO [Acceptor ServerSocket[addr=3Dloc= alhost/127.0.0.1,port=3D0,localport=3D33810]] util.ThreadedServer$Acceptor(= 656): Stopping Acceptor ServerSocket[addr=3Dlocalhost/127.0.0.1,port=3D0,lo= calport=3D33810] [junit] 2009-03-21 03:14:34,283 INFO [main] http.SocketListener(212): = Stopped SocketListener on 127.0.0.1:33810 [junit] 2009-03-21 03:14:34,284 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.servlet.WebApplicationHandler@970c0e [junit] 2009-03-21 03:14:34,354 INFO [main] util.Container(156): Stopp= ed WebApplicationContext[/static,/static] [junit] 2009-03-21 03:14:34,354 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.servlet.WebApplicationHandler@103de90 [junit] 2009-03-21 03:14:34,406 INFO [main] util.Container(156): Stopp= ed WebApplicationContext[/,/] [junit] 2009-03-21 03:14:34,406 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.Server@1aa2c23 [junit] 2009-03-21 03:14:34,407 WARN [org.apache.hadoop.hdfs.server.da= tanode.DataXceiverServer@15d252d] datanode.DataXceiverServer(134): Datanode= Registration(127.0.0.1:42601, storageID=3DDS-1601566865-67.195.138.9-42601-= 1237605271698, infoPort=3D33810, ipcPort=3D45409):DataXceiveServer: java.ni= o.channels.AsynchronousCloseException [junit] =09at java.nio.channels.spi.AbstractInterruptibleChannel.end(Ab= stractInterruptibleChannel.java:185) [junit] =09at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketCha= nnelImpl.java:152) [junit] =09at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor= .java:84) [junit] =09at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.= run(DataXceiverServer.java:129) [junit] =09at java.lang.Thread.run(Thread.java:619) [junit]=20 [junit] 2009-03-21 03:14:35,407 INFO [Acceptor ServerSocket[addr=3Dloc= alhost/127.0.0.1,port=3D0,localport=3D36078]] util.ThreadedServer$Acceptor(= 656): Stopping Acceptor ServerSocket[addr=3Dlocalhost/127.0.0.1,port=3D0,lo= calport=3D36078] [junit] 2009-03-21 03:14:35,407 INFO [main] http.SocketListener(212): = Stopped SocketListener on 127.0.0.1:36078 [junit] 2009-03-21 03:14:35,408 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.servlet.WebApplicationHandler@3e96cf [junit] 2009-03-21 03:14:35,460 INFO [main] util.Container(156): Stopp= ed WebApplicationContext[/static,/static] [junit] 2009-03-21 03:14:35,460 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.servlet.WebApplicationHandler@e29820 [junit] 2009-03-21 03:14:35,507 INFO [main] util.Container(156): Stopp= ed WebApplicationContext[/,/] [junit] 2009-03-21 03:14:35,508 INFO [main] util.Container(156): Stopp= ed org.mortbay.jetty.Server@113beb5 [junit] 2009-03-21 03:14:35,508 WARN [org.apache.hadoop.hdfs.server.na= menode.FSNamesystem$ReplicationMonitor@e1eea8] namenode.FSNamesystem$Replic= ationMonitor(2300): ReplicationMonitor thread received InterruptedException= .java.lang.InterruptedException: sleep interrupted [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 36.297 sec [junit] Running org.apache.hadoop.hbase.util.TestRootPath [junit] 2009-03-21 03:14:35,862 INFO [main] util.TestRootPath(60): Got= expected exception when checking invalid path: [junit] java.io.IOException: Root directory does not contain a scheme [junit] =09at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSU= tils.java:199) [junit] =09at org.apache.hadoop.hbase.util.TestRootPath.testRootPath(Te= stRootPath.java:56) [junit] =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Metho= d) [junit] =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodA= ccessorImpl.java:39) [junit] =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(Delegatin= gMethodAccessorImpl.java:25) [junit] =09at java.lang.reflect.Method.invoke(Method.java:597) [junit] =09at junit.framework.TestCase.runTest(TestCase.java:154) [junit] =09at junit.framework.TestCase.runBare(TestCase.java:127) [junit] =09at junit.framework.TestResult$1.protect(TestResult.java:106) [junit] =09at junit.framework.TestResult.runProtected(TestResult.java:1= 24) [junit] =09at junit.framework.TestResult.run(TestResult.java:109) [junit] =09at junit.framework.TestCase.run(TestCase.java:118) [junit] =09at junit.framework.TestSuite.runTest(TestSuite.java:208) [junit] =09at junit.framework.TestSuite.run(TestSuite.java:203) [junit] =09at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRun= ner.run(JUnitTestRunner.java:421) [junit] =09at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRun= ner.launch(JUnitTestRunner.java:912) [junit] =09at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRun= ner.main(JUnitTestRunner.java:766) [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.077 sec [junit] Running org.onelab.test.TestFilter [junit] 2009-03-21 03:14:36,207 INFO [main] test.TestFilter(244): Chec= king for false negatives [junit] 2009-03-21 03:14:36,212 INFO [main] test.TestFilter(255): Chec= king for false positives [junit] 2009-03-21 03:14:36,213 INFO [main] test.TestFilter(262): Succ= ess! [junit] 2009-03-21 03:14:36,213 INFO [main] test.TestFilter(226): Chec= king serialization/deserialization [junit] 2009-03-21 03:14:36,215 INFO [main] test.TestFilter(244): Chec= king for false negatives [junit] 2009-03-21 03:14:36,218 INFO [main] test.TestFilter(255): Chec= king for false positives [junit] 2009-03-21 03:14:36,219 INFO [main] test.TestFilter(262): Succ= ess! [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.087 sec BUILD FAILED http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :4= 58: Tests failed! Total time: 28 minutes 15 seconds [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results Publishing Clover coverage report...