Return-Path: Delivered-To: apmail-hadoop-hbase-dev-archive@minotaur.apache.org Received: (qmail 75207 invoked from network); 28 Jun 2009 07:35:48 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.3) by minotaur.apache.org with SMTP; 28 Jun 2009 07:35:48 -0000 Received: (qmail 71305 invoked by uid 500); 28 Jun 2009 07:35:58 -0000 Delivered-To: apmail-hadoop-hbase-dev-archive@hadoop.apache.org Received: (qmail 71238 invoked by uid 500); 28 Jun 2009 07:35:58 -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 71228 invoked by uid 99); 28 Jun 2009 07:35:58 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 28 Jun 2009 07:35:58 +0000 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; Sun, 28 Jun 2009 07:35:55 +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 n5S7ZXW7025900 for ; Sun, 28 Jun 2009 03:35:34 -0400 (EDT) Date: Sun, 28 Jun 2009 07:35:32 +0000 (UTC) From: Apache Hudson Server To: hbase-dev@hadoop.apache.org Message-ID: <25558721.1641246174533605.JavaMail.hudson@hudson.zones.apache.org> In-Reply-To: <27091669.1631246170966281.JavaMail.hudson@hudson.zones.apache.org> References: <27091669.1631246170966281.JavaMail.hudson@hudson.zones.apache.org> Subject: Build failed in Hudson: HBase-Patch #675 MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit X-Virus-Checked: Checked by ClamAV on apache.org See http://hudson.zones.apache.org/hudson/job/HBase-Patch/675/changes Changes: [stack] Part of HBASE-1586 Bring back transactions and indexing for 0.20.. plus hbase-1588 Rig our build to support our new contribs -- stargate and THBase/ITHBase [stack] Part of HBASE-1586 Bring back transactions and indexing for 0.20.. plus hbase-1588 Rig our build to support our new contribs -- stargate and THBase/ITHBase ------------------------------------------ [...truncated 14945 lines...] [junit] 2009-06-28 07:35:23,807 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 425.0 [junit] 2009-06-28 07:35:23,827 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0500,1246174514743 in 0sec [junit] 2009-06-28 07:35:23,827 DEBUG [main] regionserver.HRegion(2075): Files for region: TestMergeTool,row_0500,1246174514743 [junit] 2009-06-28 07:35:23,828 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1568571719/.regioninfo size=632 [junit] 2009-06-28 07:35:23,828 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:47270/user/hudson/TestMergeTool/1568571719/contents [junit] 2009-06-28 07:35:23,829 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1568571719/contents/1976045880307395177 size=425 [junit] 2009-06-28 07:35:23,830 INFO [main] regionserver.HRegion(2104): Creating new region REGION => {NAME => 'TestMergeTool,row_0100,1246174523829', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1551131491, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:23,847 INFO [main] regionserver.HRegion(2113): starting merge of regions: TestMergeTool,row_0100,1246174522470 and TestMergeTool,row_0500,1246174514743 into new region REGION => {NAME => 'TestMergeTool,row_0100,1246174523829', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1551131491, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key and end key [junit] 2009-06-28 07:35:23,847 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246174522470: compactions & flushes disabled [junit] 2009-06-28 07:35:23,847 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246174522470 [junit] 2009-06-28 07:35:23,847 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246174522470 [junit] 2009-06-28 07:35:23,848 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:23,848 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246174522470 [junit] 2009-06-28 07:35:23,848 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0500,1246174514743: compactions & flushes disabled [junit] 2009-06-28 07:35:23,848 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0500,1246174514743 [junit] 2009-06-28 07:35:23,848 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0500,1246174514743 [junit] 2009-06-28 07:35:23,849 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:23,849 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0500,1246174514743 [junit] 2009-06-28 07:35:23,907 DEBUG [main] regionserver.HRegion(2146): Files for new region [junit] 2009-06-28 07:35:23,907 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/contents [junit] 2009-06-28 07:35:23,908 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/contents/3340911445108346791 size=653 [junit] 2009-06-28 07:35:23,909 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/contents/5996098987357617519 size=425 [junit] 2009-06-28 07:35:23,909 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246174523829, encoded=1551131491 [junit] 2009-06-28 07:35:23,951 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1551131491/contents/3340911445108346791, isReference=false, sequence id=5, length=653, majorCompaction=true [junit] 2009-06-28 07:35:23,955 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1551131491/contents/5996098987357617519, isReference=false, sequence id=6, length=425, majorCompaction=true [junit] 2009-06-28 07:35:23,958 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246174523829/1551131491 available; sequence id is 7 [junit] 2009-06-28 07:35:23,958 INFO [main] regionserver.HRegion(764): Starting compaction on region TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:23,970 DEBUG [main] regionserver.Store(710): Compaction size of contents: 1.1k; Skipped 0 file(s), size: 0 [junit] 2009-06-28 07:35:23,990 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1551131491/3103798630952361117 [junit] 2009-06-28 07:35:24,174 DEBUG [main] regionserver.Store(734): Completed compaction of contents; store size is 745.0 [junit] 2009-06-28 07:35:24,194 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0100,1246174523829 in 0sec [junit] 2009-06-28 07:35:24,194 DEBUG [main] regionserver.HRegion(2153): Files for new region [junit] 2009-06-28 07:35:24,195 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/.regioninfo size=632 [junit] 2009-06-28 07:35:24,195 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/contents [junit] 2009-06-28 07:35:24,196 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/contents/3910872671710749367 size=745 [junit] 2009-06-28 07:35:24,196 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:47270/user/hudson/TestMergeTool/198459515 [junit] 2009-06-28 07:35:24,217 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:47270/user/hudson/TestMergeTool/1568571719 [junit] 2009-06-28 07:35:24,233 INFO [main] regionserver.HRegion(2159): merge completed. New region is TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,233 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1246174522470', STARTKEY => 'row_0100', ENDKEY => 'row_0400', ENCODED => 198459515, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1 [junit] 2009-06-28 07:35:24,234 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0500,1246174514743', STARTKEY => 'row_0500', ENDKEY => 'row_0600', ENCODED => 1568571719, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1 [junit] 2009-06-28 07:35:24,235 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,row_0100,1246174523829', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1551131491, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:24,235 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246174523829: compactions & flushes disabled [junit] 2009-06-28 07:35:24,235 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,236 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,236 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:24,236 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,236 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0: compactions & flushes disabled [junit] 2009-06-28 07:35:24,236 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on -ROOT-,,0 [junit] 2009-06-28 07:35:24,237 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region -ROOT-,,0 [junit] 2009-06-28 07:35:24,237 DEBUG [main] regionserver.Store(445): closed info [junit] 2009-06-28 07:35:24,237 INFO [main] regionserver.HRegion(485): Closed -ROOT-,,0 [junit] 2009-06-28 07:35:24,237 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1: compactions & flushes disabled [junit] 2009-06-28 07:35:24,237 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on .META.,,1 [junit] 2009-06-28 07:35:24,238 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region .META.,,1 [junit] 2009-06-28 07:35:24,238 DEBUG [main] regionserver.HRegion(889): Started memstore flush for region .META.,,1. Current region memstore size 1.2k [junit] 2009-06-28 07:35:24,293 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:47270/user/hudson/.META./1028785192/historian/8690474537523596398, entries=2, sequenceid=27, memsize=364.0, filesize=521.0 to .META.,,1 [junit] 2009-06-28 07:35:24,340 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:47270/user/hudson/.META./1028785192/info/6064062832376540610, entries=3, sequenceid=27, memsize=844.0, filesize=874.0 to .META.,,1 [junit] 2009-06-28 07:35:24,341 DEBUG [main] regionserver.HRegion(966): Finished memstore flush of ~1.2k for region .META.,,1 in 103ms, sequence id=27, compaction requested=true [junit] 2009-06-28 07:35:24,341 DEBUG [main] regionserver.Store(445): closed historian [junit] 2009-06-28 07:35:24,341 DEBUG [main] regionserver.Store(445): closed info [junit] 2009-06-28 07:35:24,341 INFO [main] regionserver.HRegion(485): Closed .META.,,1 [junit] 2009-06-28 07:35:24,378 INFO [main] regionserver.HLog(294): Roll /user/hudson/.logs_1246174523125/hlog.dat.1246174523134, entries=6, calcsize=1131, filesize=1151. New hlog /user/hudson/.logs_1246174523125/hlog.dat.1246174524357 [junit] 2009-06-28 07:35:24,378 DEBUG [main] regionserver.HLog(303): Last sequence written is empty. Deleting all old hlogs [junit] 2009-06-28 07:35:24,378 INFO [main] regionserver.HLog(428): removing old hlog file /user/hudson/.logs_1246174523125/hlog.dat.1246174523134 whose highest sequence/edit id is 26 [junit] 2009-06-28 07:35:24,397 DEBUG [main] regionserver.HLog(465): closing hlog writer in hdfs://localhost:47270/user/hudson/.logs_1246174523125 [junit] 2009-06-28 07:35:24,441 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246174523829', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1551131491, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:24,441 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246174523829, encoded=1551131491 [junit] 2009-06-28 07:35:24,448 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1551131491/contents/3910872671710749367, isReference=false, sequence id=6, length=745, majorCompaction=false [junit] 2009-06-28 07:35:24,450 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246174523829/1551131491 available; sequence id is 7 [junit] 2009-06-28 07:35:24,454 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246174523829: compactions & flushes disabled [junit] 2009-06-28 07:35:24,454 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,454 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,454 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:24,455 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,455 INFO [main] util.TestMergeTool(187): Verified merging regions 0+1+2 and 3 [junit] 2009-06-28 07:35:24,455 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4 [junit] 2009-06-28 07:35:24,455 INFO [main] util.Merge(82): Verifying that file system is available... [junit] 2009-06-28 07:35:24,456 INFO [main] util.Merge(91): Verifying that HBase is not running... [junit] 2009-06-28 07:35:24,579 INFO [main] util.Merge(197): Merging regions TestMergeTool,row_0100,1246174523829 and TestMergeTool,,1246174514744 in table TestMergeTool [junit] 2009-06-28 07:35:24,588 INFO [main] regionserver.HLog(209): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms [junit] 2009-06-28 07:35:24,604 INFO [main] regionserver.HLog(294): New hlog /user/hudson/.logs_1246174524579/hlog.dat.1246174524588 [junit] 2009-06-28 07:35:24,605 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:24,605 DEBUG [main] regionserver.HRegion(264): Opening region -ROOT-,,0, encoded=70236052 [junit] 2009-06-28 07:35:24,611 DEBUG [main] regionserver.Store(380): loaded /user/hudson/-ROOT-/70236052/info/3037166172217999822, isReference=false, sequence id=2, length=840, majorCompaction=false [junit] 2009-06-28 07:35:24,614 INFO [main] regionserver.HRegion(339): region -ROOT-,,0/70236052 available; sequence id is 3 [junit] 2009-06-28 07:35:24,614 INFO [main] regionserver.HRegion(764): Starting compaction on region -ROOT-,,0 [junit] 2009-06-28 07:35:24,616 INFO [main] regionserver.HRegion(779): compaction completed on region -ROOT-,,0 in 0sec [junit] 2009-06-28 07:35:24,616 INFO [main] util.Merge(211): Found meta for region1 .META.,,1, meta for region2 .META.,,1 [junit] 2009-06-28 07:35:24,617 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:24,617 DEBUG [main] regionserver.HRegion(264): Opening region .META.,,1, encoded=1028785192 [junit] 2009-06-28 07:35:24,624 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/663295109177012396, isReference=false, sequence id=20, length=651, majorCompaction=false [junit] 2009-06-28 07:35:24,627 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/8690474537523596398, isReference=false, sequence id=27, length=521, majorCompaction=false [junit] 2009-06-28 07:35:24,633 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/2619201151396191503, isReference=false, sequence id=6, length=2226, majorCompaction=false [junit] 2009-06-28 07:35:24,636 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/6064062832376540610, isReference=false, sequence id=27, length=874, majorCompaction=false [junit] 2009-06-28 07:35:24,640 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/841455267161768574, isReference=false, sequence id=20, length=994, majorCompaction=false [junit] 2009-06-28 07:35:24,642 INFO [main] regionserver.HRegion(339): region .META.,,1/1028785192 available; sequence id is 28 [junit] 2009-06-28 07:35:24,643 INFO [main] regionserver.HRegion(764): Starting compaction on region .META.,,1 [junit] 2009-06-28 07:35:24,652 DEBUG [main] regionserver.Store(710): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0 [junit] 2009-06-28 07:35:24,668 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/7078810493098912072 [junit] 2009-06-28 07:35:24,740 DEBUG [main] regionserver.Store(734): Completed compaction of historian; store size is 781.0 [junit] 2009-06-28 07:35:24,742 DEBUG [main] regionserver.Store(710): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226 [junit] 2009-06-28 07:35:24,760 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/6819748645884304416 [junit] 2009-06-28 07:35:24,828 DEBUG [main] regionserver.Store(734): Completed compaction of info; store size is 3.3k [junit] 2009-06-28 07:35:24,843 INFO [main] regionserver.HRegion(779): compaction completed on region .META.,,1 in 0sec [junit] 2009-06-28 07:35:24,845 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246174523829', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1551131491, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:24,846 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246174523829, encoded=1551131491 [junit] 2009-06-28 07:35:24,852 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1551131491/contents/3910872671710749367, isReference=false, sequence id=6, length=745, majorCompaction=false [junit] 2009-06-28 07:35:24,854 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246174523829/1551131491 available; sequence id is 7 [junit] 2009-06-28 07:35:24,854 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246174514744', STARTKEY => '', ENDKEY => '', ENCODED => 1533428394, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:24,855 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246174514744, encoded=1533428394 [junit] 2009-06-28 07:35:24,860 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1533428394/contents/5435101310539196224, isReference=false, sequence id=7, length=471, majorCompaction=false [junit] 2009-06-28 07:35:24,868 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246174514744/1533428394 available; sequence id is 8 [junit] 2009-06-28 07:35:24,869 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:24,891 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1551131491/874402929954027042 [junit] 2009-06-28 07:35:25,098 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 745.0 [junit] 2009-06-28 07:35:25,111 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0100,1246174523829 in 0sec [junit] 2009-06-28 07:35:25,111 DEBUG [main] regionserver.HRegion(2070): Files for region: TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:25,112 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/.regioninfo size=632 [junit] 2009-06-28 07:35:25,112 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/contents [junit] 2009-06-28 07:35:25,113 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491/contents/5063883629878997657 size=745 [junit] 2009-06-28 07:35:25,113 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,,1246174514744 [junit] 2009-06-28 07:35:25,151 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1533428394/385530643623966617 [junit] 2009-06-28 07:35:25,222 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 471.0 [junit] 2009-06-28 07:35:25,238 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246174514744 in 0sec [junit] 2009-06-28 07:35:25,238 DEBUG [main] regionserver.HRegion(2075): Files for region: TestMergeTool,,1246174514744 [junit] 2009-06-28 07:35:25,239 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1533428394/.regioninfo size=584 [junit] 2009-06-28 07:35:25,239 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:47270/user/hudson/TestMergeTool/1533428394/contents [junit] 2009-06-28 07:35:25,240 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/1533428394/contents/3495590575084981933 size=471 [junit] 2009-06-28 07:35:25,240 INFO [main] regionserver.HRegion(2104): Creating new region REGION => {NAME => 'TestMergeTool,,1246174525240', STARTKEY => '', ENDKEY => '', ENCODED => 158644262, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:25,258 INFO [main] regionserver.HRegion(2113): starting merge of regions: TestMergeTool,row_0100,1246174523829 and TestMergeTool,,1246174514744 into new region REGION => {NAME => 'TestMergeTool,,1246174525240', STARTKEY => '', ENDKEY => '', ENCODED => 158644262, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <> and end key <> [junit] 2009-06-28 07:35:25,258 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246174523829: compactions & flushes disabled [junit] 2009-06-28 07:35:25,258 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:25,259 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:25,259 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:25,259 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246174523829 [junit] 2009-06-28 07:35:25,259 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246174514744: compactions & flushes disabled [junit] 2009-06-28 07:35:25,259 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246174514744 [junit] 2009-06-28 07:35:25,260 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246174514744 [junit] 2009-06-28 07:35:25,260 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:25,260 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246174514744 [junit] 2009-06-28 07:35:25,306 DEBUG [main] regionserver.HRegion(2146): Files for new region [junit] 2009-06-28 07:35:25,307 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:47270/user/hudson/TestMergeTool/158644262/contents [junit] 2009-06-28 07:35:25,308 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/158644262/contents/1626885029889120970 size=471 [junit] 2009-06-28 07:35:25,308 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/158644262/contents/8970688236524677645 size=745 [junit] 2009-06-28 07:35:25,308 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246174525240, encoded=158644262 [junit] 2009-06-28 07:35:25,358 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/158644262/contents/1626885029889120970, isReference=false, sequence id=7, length=471, majorCompaction=true [junit] 2009-06-28 07:35:25,362 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/158644262/contents/8970688236524677645, isReference=false, sequence id=6, length=745, majorCompaction=true [junit] 2009-06-28 07:35:25,364 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246174525240/158644262 available; sequence id is 8 [junit] 2009-06-28 07:35:25,364 INFO [main] regionserver.HRegion(764): Starting compaction on region TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,374 DEBUG [main] regionserver.Store(710): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0 [junit] 2009-06-28 07:35:25,390 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/158644262/367779589367049280 [junit] 2009-06-28 07:35:25,461 DEBUG [main] regionserver.Store(734): Completed compaction of contents; store size is 883.0 [junit] 2009-06-28 07:35:25,478 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246174525240 in 0sec [junit] 2009-06-28 07:35:25,479 DEBUG [main] regionserver.HRegion(2153): Files for new region [junit] 2009-06-28 07:35:25,480 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/158644262/.regioninfo size=583 [junit] 2009-06-28 07:35:25,480 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:47270/user/hudson/TestMergeTool/158644262/contents [junit] 2009-06-28 07:35:25,480 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:47270/user/hudson/TestMergeTool/158644262/contents/3662258199362872562 size=883 [junit] 2009-06-28 07:35:25,481 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:47270/user/hudson/TestMergeTool/1551131491 [junit] 2009-06-28 07:35:25,497 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:47270/user/hudson/TestMergeTool/1533428394 [junit] 2009-06-28 07:35:25,513 INFO [main] regionserver.HRegion(2159): merge completed. New region is TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,513 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1246174523829', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1551131491, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1 [junit] 2009-06-28 07:35:25,514 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,,1246174514744', STARTKEY => '', ENDKEY => '', ENCODED => 1533428394, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1 [junit] 2009-06-28 07:35:25,515 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,,1246174525240', STARTKEY => '', ENDKEY => '', ENCODED => 158644262, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:25,516 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246174525240: compactions & flushes disabled [junit] 2009-06-28 07:35:25,516 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,516 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,516 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:25,517 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,517 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0: compactions & flushes disabled [junit] 2009-06-28 07:35:25,517 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on -ROOT-,,0 [junit] 2009-06-28 07:35:25,517 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region -ROOT-,,0 [junit] 2009-06-28 07:35:25,517 DEBUG [main] regionserver.Store(445): closed info [junit] 2009-06-28 07:35:25,518 INFO [main] regionserver.HRegion(485): Closed -ROOT-,,0 [junit] 2009-06-28 07:35:25,518 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1: compactions & flushes disabled [junit] 2009-06-28 07:35:25,518 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on .META.,,1 [junit] 2009-06-28 07:35:25,518 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region .META.,,1 [junit] 2009-06-28 07:35:25,518 DEBUG [main] regionserver.HRegion(889): Started memstore flush for region .META.,,1. Current region memstore size 1.1k [junit] 2009-06-28 07:35:25,560 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:47270/user/hudson/.META./1028785192/historian/5961334587916571255, entries=2, sequenceid=34, memsize=356.0, filesize=505.0 to .META.,,1 [junit] 2009-06-28 07:35:25,600 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:47270/user/hudson/.META./1028785192/info/294237310140121117, entries=3, sequenceid=34, memsize=804.0, filesize=826.0 to .META.,,1 [junit] 2009-06-28 07:35:25,600 DEBUG [main] regionserver.HRegion(966): Finished memstore flush of ~1.1k for region .META.,,1 in 82ms, sequence id=34, compaction requested=true [junit] 2009-06-28 07:35:25,600 DEBUG [main] regionserver.Store(445): closed historian [junit] 2009-06-28 07:35:25,601 DEBUG [main] regionserver.Store(445): closed info [junit] 2009-06-28 07:35:25,601 INFO [main] regionserver.HRegion(485): Closed .META.,,1 [junit] 2009-06-28 07:35:25,638 INFO [main] regionserver.HLog(294): Roll /user/hudson/.logs_1246174524579/hlog.dat.1246174524588, entries=6, calcsize=1083, filesize=1103. New hlog /user/hudson/.logs_1246174524579/hlog.dat.1246174525621 [junit] 2009-06-28 07:35:25,638 DEBUG [main] regionserver.HLog(303): Last sequence written is empty. Deleting all old hlogs [junit] 2009-06-28 07:35:25,638 INFO [main] regionserver.HLog(428): removing old hlog file /user/hudson/.logs_1246174524579/hlog.dat.1246174524588 whose highest sequence/edit id is 33 [junit] 2009-06-28 07:35:25,653 DEBUG [main] regionserver.HLog(465): closing hlog writer in hdfs://localhost:47270/user/hudson/.logs_1246174524579 [junit] 2009-06-28 07:35:25,689 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246174525240', STARTKEY => '', ENDKEY => '', ENCODED => 158644262, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} [junit] 2009-06-28 07:35:25,689 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246174525240, encoded=158644262 [junit] 2009-06-28 07:35:25,695 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/158644262/contents/3662258199362872562, isReference=false, sequence id=7, length=883, majorCompaction=false [junit] 2009-06-28 07:35:25,697 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246174525240/158644262 available; sequence id is 8 [junit] 2009-06-28 07:35:25,702 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246174525240: compactions & flushes disabled [junit] 2009-06-28 07:35:25,702 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,703 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,703 DEBUG [main] regionserver.Store(445): closed contents [junit] 2009-06-28 07:35:25,703 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246174525240 [junit] 2009-06-28 07:35:25,703 INFO [main] util.TestMergeTool(187): Verified merging regions 0+1+2+3 and 4 [junit] 2009-06-28 07:35:25,703 DEBUG [main] regionserver.HLog(465): closing hlog writer in /tmp/.logs_1246174520482 [junit] 2009-06-28 07:35:25,866 INFO [main] hbase.HBaseTestCase(627): Shutting down FileSystem [junit] 2009-06-28 07:35:25,866 INFO [main] hbase.HBaseTestCase(634): Shutting down Mini DFS [junit] Shutting down the Mini HDFS Cluster [junit] Shutting down DataNode 1 [junit] 2009-06-28 07:35:25,970 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@e14d81] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:42932, storageID=DS-1193674117-67.195.138.9-42932-1246174518099, infoPort=35173, ipcPort=50385):DataXceiveServer: java.nio.channels.AsynchronousCloseException [junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185) [junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152) [junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84) [junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130) [junit] at java.lang.Thread.run(Thread.java:619) [junit] [junit] Shutting down DataNode 0 [junit] 2009-06-28 07:35:27,071 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@dd75a4] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:57518, storageID=DS-959068288-67.195.138.9-57518-1246174517391, infoPort=54750, ipcPort=35749):DataXceiveServer: java.nio.channels.AsynchronousCloseException [junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185) [junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152) [junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84) [junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130) [junit] at java.lang.Thread.run(Thread.java:619) [junit] [junit] 2009-06-28 07:35:27,280 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@14d7745] namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 12.648 sec [junit] Running org.apache.hadoop.hbase.util.TestRootPath [junit] 2009-06-28 07:35:27,738 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path: [junit] java.io.IOException: Root directory does not contain a scheme [junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:212) [junit] at org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56) [junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [junit] at java.lang.reflect.Method.invoke(Method.java:597) [junit] at junit.framework.TestCase.runTest(TestCase.java:154) [junit] at junit.framework.TestCase.runBare(TestCase.java:127) [junit] at junit.framework.TestResult$1.protect(TestResult.java:106) [junit] at junit.framework.TestResult.runProtected(TestResult.java:124) [junit] at junit.framework.TestResult.run(TestResult.java:109) [junit] at junit.framework.TestCase.run(TestCase.java:118) [junit] at junit.framework.TestSuite.runTest(TestSuite.java:208) [junit] at junit.framework.TestSuite.run(TestSuite.java:203) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766) [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.084 sec BUILD FAILED http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :464: Tests failed! Total time: 18 minutes 41 seconds [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results Publishing Clover coverage report...