You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@hbase.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2008/10/29 22:52:46 UTC
Build failed in Hudson: HBase-Patch #374
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/374/changes
Changes:
[stack] HBASE-625 Metrics support for cluster load history: emissions and graphs
[jdcryans] HBASE-966 HBASE-748 misses some writes
------------------------------------------
[...truncated 20648 lines...]
[junit] 2008-10-29 21:52:37,590 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 625273694/contents, max sequence id 2
[junit] 2008-10-29 21:52:37,591 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:37,599 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,row_0100,1225317155998 is 3
[junit] 2008-10-29 21:52:37,600 INFO [main] regionserver.HRegion(514): region TestMergeTool,row_0100,1225317155998/625273694 available
[junit] 2008-10-29 21:52:37,601 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:37,601 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:37,601 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:37,601 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:37,601 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:37,602 DEBUG [main] regionserver.HStore(552): closed 625273694/contents
[junit] 2008-10-29 21:52:37,602 INFO [main] regionserver.HRegion(628): closed TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:37,602 INFO [main] util.TestMergeTool(171): Verified merging regions 0+1+2 and 3
[junit] 2008-10-29 21:52:37,602 INFO [main] util.TestMergeTool(158): merging regions 0+1+2+3 and 4
[junit] 2008-10-29 21:52:37,602 INFO [main] util.Merge(80): Verifying that file system is available...
[junit] 2008-10-29 21:52:37,603 INFO [main] util.Merge(89): Verifying that HBase is not running...
[junit] 2008-10-29 21:52:37,615 INFO [main] util.Merge(190): Merging regions [B@1bde392 and [B@1a7905e in table [B@1f71773
[junit] 2008-10-29 21:52:37,686 INFO [main] regionserver.HLog(272): New log writer created at /user/hudson/log_1225317157615/hlog.dat.1225317157652
[junit] 2008-10-29 21:52:37,686 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '1', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}
[junit] 2008-10-29 21:52:37,687 DEBUG [main] regionserver.HRegion(431): Opening region -ROOT-,,0/70236052
[junit] 2008-10-29 21:52:37,704 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/-ROOT-/70236052/info/info/1104232898735887759, isReference=false, sequence id=1
[junit] 2008-10-29 21:52:37,705 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 70236052/info, max sequence id 1
[junit] 2008-10-29 21:52:37,711 DEBUG [main] regionserver.HRegion(492): Next sequence id for region -ROOT-,,0 is 2
[junit] 2008-10-29 21:52:37,712 INFO [main] regionserver.HRegion(514): region -ROOT-,,0/70236052 available
[junit] 2008-10-29 21:52:37,713 DEBUG [main] regionserver.HLog(205): changing sequence number from 0 to 2
[junit] 2008-10-29 21:52:37,713 INFO [main] regionserver.HRegion(897): starting compaction on region -ROOT-,,0
[junit] 2008-10-29 21:52:37,715 INFO [main] regionserver.HRegion(911): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2008-10-29 21:52:37,722 INFO [main] util.Merge(204): Found meta for region1 [B@2b2cf8, meta for region2 [B@2b2cf8
[junit] 2008-10-29 21:52:37,723 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_ROOT => 'false', IS_META => 'true', FAMILIES => [{NAME => 'info', BLOOMFILTER => 'false', VERSIONS => '1', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'historian', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}
[junit] 2008-10-29 21:52:37,723 DEBUG [main] regionserver.HRegion(431): Opening region .META.,,1/1028785192
[junit] 2008-10-29 21:52:37,734 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/.META./1028785192/info/info/1250258123925068101, isReference=false, sequence id=13
[junit] 2008-10-29 21:52:37,740 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/.META./1028785192/info/info/5786174343119365586, isReference=false, sequence id=17
[junit] 2008-10-29 21:52:37,746 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/.META./1028785192/info/info/8733123097858893209, isReference=false, sequence id=5
[junit] 2008-10-29 21:52:37,747 DEBUG [main] regionserver.HStore(231): Loaded 3 file(s) in hstore 1028785192/info, max sequence id 17
[junit] 2008-10-29 21:52:37,768 DEBUG [main] regionserver.HRegion(492): Next sequence id for region .META.,,1 is 18
[junit] 2008-10-29 21:52:37,769 INFO [main] regionserver.HRegion(514): region .META.,,1/1028785192 available
[junit] 2008-10-29 21:52:37,769 DEBUG [main] regionserver.HLog(205): changing sequence number from 2 to 18
[junit] 2008-10-29 21:52:37,769 INFO [main] regionserver.HRegion(897): starting compaction on region .META.,,1
[junit] 2008-10-29 21:52:37,773 DEBUG [main] regionserver.HStore(857): Compaction size of 1028785192/info: 4.5k; Skipped 0 files , size: 0
[junit] 2008-10-29 21:52:37,794 DEBUG [main] regionserver.HStore(893): started compaction of 3 files into /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/2638586321210944186
[junit] 2008-10-29 21:52:38,090 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/2638586321210944186 to /user/hudson/.META./1028785192/info/mapfiles/2709776743092379659
[junit] 2008-10-29 21:52:38,371 DEBUG [main] regionserver.HStore(911): Completed compaction of 1028785192/info store size is 3.5k; time since last major compaction: 24 seconds
[junit] 2008-10-29 21:52:38,405 INFO [main] regionserver.HRegion(911): compaction completed on region .META.,,1 in 0sec
[junit] 2008-10-29 21:52:38,411 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1225317155998', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 625273694, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-29 21:52:38,411 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,row_0100,1225317155998/625273694
[junit] 2008-10-29 21:52:38,423 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/625273694/contents/info/3954083656430599910, isReference=false, sequence id=2
[junit] 2008-10-29 21:52:38,424 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 625273694/contents, max sequence id 2
[junit] 2008-10-29 21:52:38,425 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:38,431 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,row_0100,1225317155998 is 3
[junit] 2008-10-29 21:52:38,432 INFO [main] regionserver.HRegion(514): region TestMergeTool,row_0100,1225317155998/625273694 available
[junit] 2008-10-29 21:52:38,433 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => 'TestMergeTool,,1225317126959', STARTKEY => '', ENDKEY => '', ENCODED => 675411580, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-29 21:52:38,433 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,,1225317126959/675411580
[junit] 2008-10-29 21:52:38,444 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/675411580/contents/info/211391013831129784, isReference=false, sequence id=2
[junit] 2008-10-29 21:52:38,444 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 675411580/contents, max sequence id 2
[junit] 2008-10-29 21:52:38,445 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:38,452 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,,1225317126959 is 3
[junit] 2008-10-29 21:52:38,453 INFO [main] regionserver.HRegion(514): region TestMergeTool,,1225317126959/675411580 available
[junit] 2008-10-29 21:52:38,453 INFO [main] regionserver.HRegion(897): starting compaction on region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:38,465 DEBUG [main] regionserver.HStore(893): started compaction of 1 files into /user/hudson/TestMergeTool/compaction.dir/625273694/contents/mapfiles/5876631973617316519
[junit] 2008-10-29 21:52:38,747 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/TestMergeTool/compaction.dir/625273694/contents/mapfiles/5876631973617316519 to /user/hudson/TestMergeTool/625273694/contents/mapfiles/2306587202311321167
[junit] 2008-10-29 21:52:38,847 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:38,926 DEBUG [main] regionserver.HStore(911): Completed compaction of 625273694/contents store size is 486.0
[junit] 2008-10-29 21:52:38,962 INFO [main] regionserver.HRegion(911): compaction completed on region TestMergeTool,row_0100,1225317155998 in 0sec
[junit] 2008-10-29 21:52:38,963 DEBUG [main] regionserver.HRegion(173): Files for region: TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:38,963 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/625273694/contents
[junit] 2008-10-29 21:52:38,965 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/625273694/contents/info
[junit] 2008-10-29 21:52:38,965 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/625273694/contents/info/2306587202311321167 size=9
[junit] 2008-10-29 21:52:38,965 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/625273694/contents/mapfiles
[junit] 2008-10-29 21:52:38,966 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/625273694/contents/mapfiles/2306587202311321167
[junit] 2008-10-29 21:52:38,967 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/625273694/contents/mapfiles/2306587202311321167/data size=486
[junit] 2008-10-29 21:52:38,967 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/625273694/contents/mapfiles/2306587202311321167/index size=227
[junit] 2008-10-29 21:52:38,967 INFO [main] regionserver.HRegion(897): starting compaction on region TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:38,982 DEBUG [main] regionserver.HStore(893): started compaction of 1 files into /user/hudson/TestMergeTool/compaction.dir/675411580/contents/mapfiles/3359521185058220429
[junit] 2008-10-29 21:52:39,266 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/TestMergeTool/compaction.dir/675411580/contents/mapfiles/3359521185058220429 to /user/hudson/TestMergeTool/675411580/contents/mapfiles/7612978265261763525
[junit] 2008-10-29 21:52:39,341 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:39,402 DEBUG [main] regionserver.HStore(911): Completed compaction of 675411580/contents store size is 204.0
[junit] 2008-10-29 21:52:39,428 INFO [main] regionserver.HRegion(911): compaction completed on region TestMergeTool,,1225317126959 in 0sec
[junit] 2008-10-29 21:52:39,428 DEBUG [main] regionserver.HRegion(178): Files for region: TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:39,429 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/675411580/contents
[junit] 2008-10-29 21:52:39,430 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/675411580/contents/info
[junit] 2008-10-29 21:52:39,430 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/675411580/contents/info/7612978265261763525 size=9
[junit] 2008-10-29 21:52:39,431 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/675411580/contents/mapfiles
[junit] 2008-10-29 21:52:39,432 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/675411580/contents/mapfiles/7612978265261763525
[junit] 2008-10-29 21:52:39,433 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/675411580/contents/mapfiles/7612978265261763525/data size=204
[junit] 2008-10-29 21:52:39,433 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/675411580/contents/mapfiles/7612978265261763525/index size=227
[junit] 2008-10-29 21:52:39,434 INFO [main] regionserver.HRegion(202): Creating new region REGION => {NAME => 'TestMergeTool,,1225317159434', STARTKEY => '', ENDKEY => '', ENCODED => 399741436, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-29 21:52:39,469 INFO [main] regionserver.HRegion(211): starting merge of regions: TestMergeTool,row_0100,1225317155998 and TestMergeTool,,1225317126959 into new region REGION => {NAME => 'TestMergeTool,,1225317159434', STARTKEY => '', ENDKEY => '', ENCODED => 399741436, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <[B...@4f80d6> and end key <[B...@4f80d6>
[junit] 2008-10-29 21:52:39,469 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:39,469 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:39,469 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:39,470 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:39,470 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:39,470 DEBUG [main] regionserver.HStore(552): closed 625273694/contents
[junit] 2008-10-29 21:52:39,470 INFO [main] regionserver.HRegion(628): closed TestMergeTool,row_0100,1225317155998
[junit] 2008-10-29 21:52:39,470 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:39,470 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:39,471 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:39,471 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:39,471 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:39,471 DEBUG [main] regionserver.HStore(552): closed 675411580/contents
[junit] 2008-10-29 21:52:39,471 INFO [main] regionserver.HRegion(628): closed TestMergeTool,,1225317126959
[junit] 2008-10-29 21:52:39,552 DEBUG [main] regionserver.HRegion(234): Adjusting sequence id of storeFile 675411580/contents/7612978265261763525 down by one; sequence id A=2, sequence id B=2
[junit] 2008-10-29 21:52:40,157 DEBUG [main] regionserver.HRegion(245): Renaming 625273694/contents/2306587202311321167 to 399741436/contents/4821907998026131932
[junit] 2008-10-29 21:52:40,516 DEBUG [main] regionserver.HRegion(245): Renaming 675411580/contents/7612978265261763525 to 399741436/contents/3151346060175195848
[junit] 2008-10-29 21:52:40,567 DEBUG [main] regionserver.HRegion(251): Files for new region
[junit] 2008-10-29 21:52:40,568 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents
[junit] 2008-10-29 21:52:40,569 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/info
[junit] 2008-10-29 21:52:40,569 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/info/3151346060175195848 size=9
[junit] 2008-10-29 21:52:40,570 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/info/4821907998026131932 size=9
[junit] 2008-10-29 21:52:40,570 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles
[junit] 2008-10-29 21:52:40,571 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/3151346060175195848
[junit] 2008-10-29 21:52:40,572 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/3151346060175195848/data size=204
[junit] 2008-10-29 21:52:40,572 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/3151346060175195848/index size=227
[junit] 2008-10-29 21:52:40,572 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/4821907998026131932
[junit] 2008-10-29 21:52:40,573 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/4821907998026131932/data size=486
[junit] 2008-10-29 21:52:40,574 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/4821907998026131932/index size=227
[junit] 2008-10-29 21:52:40,574 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,,1225317159434/399741436
[junit] 2008-10-29 21:52:40,584 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/399741436/contents/info/3151346060175195848, isReference=false, sequence id=1
[junit] 2008-10-29 21:52:40,590 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/399741436/contents/info/4821907998026131932, isReference=false, sequence id=2
[junit] 2008-10-29 21:52:40,591 DEBUG [main] regionserver.HStore(231): Loaded 2 file(s) in hstore 399741436/contents, max sequence id 2
[junit] 2008-10-29 21:52:40,592 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:40,610 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,,1225317159434 is 3
[junit] 2008-10-29 21:52:40,611 INFO [main] regionserver.HRegion(514): region TestMergeTool,,1225317159434/399741436 available
[junit] 2008-10-29 21:52:40,611 INFO [main] regionserver.HRegion(897): starting compaction on region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:40,615 DEBUG [main] regionserver.HStore(857): Compaction size of 399741436/contents: 1.1k; Skipped 0 files , size: 0
[junit] 2008-10-29 21:52:40,630 DEBUG [main] regionserver.HStore(893): started compaction of 2 files into /user/hudson/TestMergeTool/compaction.dir/399741436/contents/mapfiles/7196814045027086893
[junit] 2008-10-29 21:52:40,860 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/TestMergeTool/compaction.dir/399741436/contents/mapfiles/7196814045027086893 to /user/hudson/TestMergeTool/399741436/contents/mapfiles/2561629027892525429
[junit] 2008-10-29 21:52:40,931 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:41,049 DEBUG [main] regionserver.HStore(911): Completed compaction of 399741436/contents store size is 580.0; time since last major compaction: 2 seconds
[junit] 2008-10-29 21:52:41,087 INFO [main] regionserver.HRegion(911): compaction completed on region TestMergeTool,,1225317159434 in 0sec
[junit] 2008-10-29 21:52:41,088 DEBUG [main] regionserver.HRegion(258): Files for new region
[junit] 2008-10-29 21:52:41,089 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents
[junit] 2008-10-29 21:52:41,090 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/info
[junit] 2008-10-29 21:52:41,091 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/info/2561629027892525429 size=9
[junit] 2008-10-29 21:52:41,091 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles
[junit] 2008-10-29 21:52:41,092 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/2561629027892525429
[junit] 2008-10-29 21:52:41,094 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/2561629027892525429/data size=580
[junit] 2008-10-29 21:52:41,094 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:55227/user/hudson/TestMergeTool/399741436/contents/mapfiles/2561629027892525429/index size=227
[junit] 2008-10-29 21:52:41,094 DEBUG [main] regionserver.HRegion(2280): DELETING region hdfs://localhost:55227/user/hudson/TestMergeTool/625273694
[junit] 2008-10-29 21:52:41,126 DEBUG [main] regionserver.HRegion(2280): DELETING region hdfs://localhost:55227/user/hudson/TestMergeTool/675411580
[junit] 2008-10-29 21:52:41,152 INFO [main] regionserver.HRegion(264): merge completed. New region is TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,152 DEBUG [main] util.Merge(308): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1225317155998', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 625273694, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2008-10-29 21:52:41,161 DEBUG [main] util.Merge(308): Removing region: REGION => {NAME => 'TestMergeTool,,1225317126959', STARTKEY => '', ENDKEY => '', ENCODED => 675411580, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2008-10-29 21:52:41,169 INFO [main] util.Merge(244): Adding REGION => {NAME => 'TestMergeTool,,1225317159434', STARTKEY => '', ENDKEY => '', ENCODED => 399741436, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_ROOT => 'false', IS_META => 'true', FAMILIES => [{NAME => 'info', BLOOMFILTER => 'false', VERSIONS => '1', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'historian', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}
[junit] 2008-10-29 21:52:41,169 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,170 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,170 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,170 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,170 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,170 DEBUG [main] regionserver.HStore(552): closed 399741436/contents
[junit] 2008-10-29 21:52:41,170 INFO [main] regionserver.HRegion(628): closed TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,170 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region -ROOT-,,0
[junit] 2008-10-29 21:52:41,171 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region -ROOT-,,0
[junit] 2008-10-29 21:52:41,171 DEBUG [main] regionserver.HRegion(607): No more active scanners for region -ROOT-,,0
[junit] 2008-10-29 21:52:41,171 DEBUG [main] regionserver.HRegion(609): Updates disabled for region -ROOT-,,0
[junit] 2008-10-29 21:52:41,171 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region -ROOT-,,0
[junit] 2008-10-29 21:52:41,171 DEBUG [main] regionserver.HStore(552): closed 70236052/info
[junit] 2008-10-29 21:52:41,171 INFO [main] regionserver.HRegion(628): closed -ROOT-,,0
[junit] 2008-10-29 21:52:41,172 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region .META.,,1
[junit] 2008-10-29 21:52:41,172 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region .META.,,1
[junit] 2008-10-29 21:52:41,172 DEBUG [main] regionserver.HRegion(607): No more active scanners for region .META.,,1
[junit] 2008-10-29 21:52:41,172 DEBUG [main] regionserver.HRegion(609): Updates disabled for region .META.,,1
[junit] 2008-10-29 21:52:41,172 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region .META.,,1
[junit] 2008-10-29 21:52:41,172 DEBUG [main] regionserver.HRegion(1022): Started memcache flush for region .META.,,1. Current region memcache size 466.0
[junit] 2008-10-29 21:52:41,444 DEBUG [main] regionserver.HStore(647): Added /user/hudson/.META./1028785192/info/mapfiles/4669332007492128006 with 3 entries, sequence id 21, data size 466.0, file size 618.0
[junit] 2008-10-29 21:52:41,444 DEBUG [main] regionserver.HRegion(1096): Finished memcache flush for region .META.,,1 in 272ms, sequence id=21, compaction requested=true
[junit] 2008-10-29 21:52:41,444 DEBUG [main] regionserver.HStore(552): closed 1028785192/historian
[junit] 2008-10-29 21:52:41,444 DEBUG [main] regionserver.HStore(552): closed 1028785192/info
[junit] 2008-10-29 21:52:41,445 INFO [main] regionserver.HRegion(628): closed .META.,,1
[junit] 2008-10-29 21:52:41,490 DEBUG [main] regionserver.HLog(259): Closing current log writer /user/hudson/log_1225317157615/hlog.dat.0
[junit] 2008-10-29 21:52:41,527 INFO [main] regionserver.HLog(272): New log writer created at /user/hudson/log_1225317157615/hlog.dat.1225317161491
[junit] 2008-10-29 21:52:41,528 DEBUG [main] regionserver.HLog(277): Last sequence written is empty. Deleting all old hlogs
[junit] 2008-10-29 21:52:41,528 INFO [main] regionserver.HLog(329): removing old log file /user/hudson/log_1225317157615/hlog.dat.0 whose highest sequence/edit id is 21
[junit] 2008-10-29 21:52:41,528 DEBUG [main] regionserver.HLog(365): closing log writer in hdfs://localhost:55227/user/hudson/log_1225317157615
[junit] 2008-10-29 21:52:41,585 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => 'TestMergeTool,,1225317159434', STARTKEY => '', ENDKEY => '', ENCODED => 399741436, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-29 21:52:41,585 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,,1225317159434/399741436
[junit] 2008-10-29 21:52:41,594 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/399741436/contents/info/2561629027892525429, isReference=false, sequence id=2
[junit] 2008-10-29 21:52:41,594 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 399741436/contents, max sequence id 2
[junit] 2008-10-29 21:52:41,595 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-29 21:52:41,604 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,,1225317159434 is 3
[junit] 2008-10-29 21:52:41,605 INFO [main] regionserver.HRegion(514): region TestMergeTool,,1225317159434/399741436 available
[junit] 2008-10-29 21:52:41,606 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,607 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,607 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,607 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,607 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,607 DEBUG [main] regionserver.HStore(552): closed 399741436/contents
[junit] 2008-10-29 21:52:41,607 INFO [main] regionserver.HRegion(628): closed TestMergeTool,,1225317159434
[junit] 2008-10-29 21:52:41,607 INFO [main] util.TestMergeTool(171): Verified merging regions 0+1+2+3 and 4
[junit] 2008-10-29 21:52:41,608 DEBUG [main] regionserver.HLog(365): closing log writer in /tmp/log_1225317136436
[junit] 2008-10-29 21:52:41,682 INFO [main] hbase.HBaseTestCase(600): Shutting down FileSystem
[junit] 2008-10-29 21:52:42,000 INFO [main] hbase.HBaseTestCase(607): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2008-10-29 21:52:42,005 INFO [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:55235
[junit] 2008-10-29 21:52:42,117 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-10-29 21:52:42,117 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@14627a
[junit] 2008-10-29 21:52:42,191 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-10-29 21:52:42,192 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@16d8196
[junit] Shutting down DataNode 0
[junit] 2008-10-29 21:52:43,201 INFO [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:55232
[junit] 2008-10-29 21:52:43,297 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-10-29 21:52:43,297 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@afae4a
[junit] 2008-10-29 21:52:43,368 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-10-29 21:52:43,368 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@121b59a
[junit] 2008-10-29 21:52:44,481 INFO [main] http.SocketListener(212): Stopped SocketListener on 0.0.0.0:55228
[junit] 2008-10-29 21:52:44,550 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-10-29 21:52:44,550 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1493102
[junit] 2008-10-29 21:52:44,617 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-10-29 21:52:44,617 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@349471
[junit] 2008-10-29 21:52:44,618 WARN [org.apache.hadoop.dfs.FSNamesystem$ReplicationMonitor@1372656] dfs.FSNamesystem$ReplicationMonitor(2193): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 37.786 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2008-10-29 21:52:45,195 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:158)
[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.058 sec
[junit] Running org.onelab.test.TestFilter
[junit] 2008-10-29 21:52:45,713 INFO [main] test.TestFilter(244): Checking for false negatives
[junit] 2008-10-29 21:52:45,718 INFO [main] test.TestFilter(255): Checking for false positives
[junit] 2008-10-29 21:52:45,718 INFO [main] test.TestFilter(262): Success!
[junit] 2008-10-29 21:52:45,719 INFO [main] test.TestFilter(226): Checking serialization/deserialization
[junit] 2008-10-29 21:52:45,721 INFO [main] test.TestFilter(244): Checking for false negatives
[junit] 2008-10-29 21:52:45,723 INFO [main] test.TestFilter(255): Checking for false positives
[junit] 2008-10-29 21:52:45,723 INFO [main] test.TestFilter(262): Success!
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.064 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:447: Tests failed!
Total time: 35 minutes 36 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Hudson build is back to normal: HBase-Patch #376
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/376/changes
Build failed in Hudson: HBase-Patch #375
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/375/changes
Changes:
[stack] HBASE-949 Add an HBase Manual
------------------------------------------
[...truncated 20505 lines...]
[junit] 2008-10-30 02:20:37,251 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,row_0100,1225333235666 is 3
[junit] 2008-10-30 02:20:37,252 INFO [main] regionserver.HRegion(514): region TestMergeTool,row_0100,1225333235666/272495894 available
[junit] 2008-10-30 02:20:37,253 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:37,254 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:37,254 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:37,254 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:37,254 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:37,254 DEBUG [main] regionserver.HStore(552): closed 272495894/contents
[junit] 2008-10-30 02:20:37,254 INFO [main] regionserver.HRegion(628): closed TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:37,254 INFO [main] util.TestMergeTool(171): Verified merging regions 0+1+2 and 3
[junit] 2008-10-30 02:20:37,255 INFO [main] util.TestMergeTool(158): merging regions 0+1+2+3 and 4
[junit] 2008-10-30 02:20:37,255 INFO [main] util.Merge(80): Verifying that file system is available...
[junit] 2008-10-30 02:20:37,256 INFO [main] util.Merge(89): Verifying that HBase is not running...
[junit] 2008-10-30 02:20:37,285 INFO [main] util.Merge(190): Merging regions [B@34b350 and [B@1e881b6 in table [B@19bf795
[junit] 2008-10-30 02:20:37,366 INFO [main] regionserver.HLog(272): New log writer created at /user/hudson/log_1225333237285/hlog.dat.1225333237318
[junit] 2008-10-30 02:20:37,366 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '1', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}
[junit] 2008-10-30 02:20:37,367 DEBUG [main] regionserver.HRegion(431): Opening region -ROOT-,,0/70236052
[junit] 2008-10-30 02:20:37,375 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/-ROOT-/70236052/info/info/3692115888026982638, isReference=false, sequence id=1
[junit] 2008-10-30 02:20:37,376 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 70236052/info, max sequence id 1
[junit] 2008-10-30 02:20:37,386 DEBUG [main] regionserver.HRegion(492): Next sequence id for region -ROOT-,,0 is 2
[junit] 2008-10-30 02:20:37,387 INFO [main] regionserver.HRegion(514): region -ROOT-,,0/70236052 available
[junit] 2008-10-30 02:20:37,387 DEBUG [main] regionserver.HLog(205): changing sequence number from 0 to 2
[junit] 2008-10-30 02:20:37,387 INFO [main] regionserver.HRegion(897): starting compaction on region -ROOT-,,0
[junit] 2008-10-30 02:20:37,389 INFO [main] regionserver.HRegion(911): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2008-10-30 02:20:37,396 INFO [main] util.Merge(204): Found meta for region1 [B@1e565bd, meta for region2 [B@1e565bd
[junit] 2008-10-30 02:20:37,397 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_ROOT => 'false', IS_META => 'true', FAMILIES => [{NAME => 'info', BLOOMFILTER => 'false', VERSIONS => '1', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'historian', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}
[junit] 2008-10-30 02:20:37,398 DEBUG [main] regionserver.HRegion(431): Opening region .META.,,1/1028785192
[junit] 2008-10-30 02:20:37,407 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/.META./1028785192/info/info/3802458803512339883, isReference=false, sequence id=13
[junit] 2008-10-30 02:20:37,413 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/.META./1028785192/info/info/5459443495152343005, isReference=false, sequence id=17
[junit] 2008-10-30 02:20:37,438 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/.META./1028785192/info/info/8178398700693942254, isReference=false, sequence id=5
[junit] 2008-10-30 02:20:37,439 DEBUG [main] regionserver.HStore(231): Loaded 3 file(s) in hstore 1028785192/info, max sequence id 17
[junit] 2008-10-30 02:20:37,463 DEBUG [main] regionserver.HRegion(492): Next sequence id for region .META.,,1 is 18
[junit] 2008-10-30 02:20:37,464 INFO [main] regionserver.HRegion(514): region .META.,,1/1028785192 available
[junit] 2008-10-30 02:20:37,464 DEBUG [main] regionserver.HLog(205): changing sequence number from 2 to 18
[junit] 2008-10-30 02:20:37,464 INFO [main] regionserver.HRegion(897): starting compaction on region .META.,,1
[junit] 2008-10-30 02:20:37,468 DEBUG [main] regionserver.HStore(857): Compaction size of 1028785192/info: 4.5k; Skipped 0 files , size: 0
[junit] 2008-10-30 02:20:37,486 DEBUG [main] regionserver.HStore(893): started compaction of 3 files into /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/1772197285361741783
[junit] 2008-10-30 02:20:37,783 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/1772197285361741783 to /user/hudson/.META./1028785192/info/mapfiles/3105995736832598842
[junit] 2008-10-30 02:20:39,019 DEBUG [main] regionserver.HStore(911): Completed compaction of 1028785192/info store size is 3.5k; time since last major compaction: 31 seconds
[junit] 2008-10-30 02:20:39,051 INFO [main] regionserver.HRegion(911): compaction completed on region .META.,,1 in 1sec
[junit] 2008-10-30 02:20:39,062 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1225333235666', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 272495894, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-30 02:20:39,063 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,row_0100,1225333235666/272495894
[junit] 2008-10-30 02:20:39,076 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/272495894/contents/info/7485999916515871605, isReference=false, sequence id=2
[junit] 2008-10-30 02:20:39,076 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 272495894/contents, max sequence id 2
[junit] 2008-10-30 02:20:39,077 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-30 02:20:39,083 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,row_0100,1225333235666 is 3
[junit] 2008-10-30 02:20:39,085 INFO [main] regionserver.HRegion(514): region TestMergeTool,row_0100,1225333235666/272495894 available
[junit] 2008-10-30 02:20:39,085 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => 'TestMergeTool,,1225333192426', STARTKEY => '', ENDKEY => '', ENCODED => 105657654, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-30 02:20:39,085 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,,1225333192426/105657654
[junit] 2008-10-30 02:20:39,095 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/105657654/contents/info/8153557530982383492, isReference=false, sequence id=2
[junit] 2008-10-30 02:20:39,096 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 105657654/contents, max sequence id 2
[junit] 2008-10-30 02:20:39,097 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-30 02:20:39,105 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,,1225333192426 is 3
[junit] 2008-10-30 02:20:39,107 INFO [main] regionserver.HRegion(514): region TestMergeTool,,1225333192426/105657654 available
[junit] 2008-10-30 02:20:39,107 INFO [main] regionserver.HRegion(897): starting compaction on region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:39,115 DEBUG [main] regionserver.HStore(893): started compaction of 1 files into /user/hudson/TestMergeTool/compaction.dir/272495894/contents/mapfiles/4308572063732617512
[junit] 2008-10-30 02:20:39,352 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/TestMergeTool/compaction.dir/272495894/contents/mapfiles/4308572063732617512 to /user/hudson/TestMergeTool/272495894/contents/mapfiles/4385880880880393382
[junit] 2008-10-30 02:20:39,512 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-30 02:20:39,584 DEBUG [main] regionserver.HStore(911): Completed compaction of 272495894/contents store size is 486.0
[junit] 2008-10-30 02:20:39,612 INFO [main] regionserver.HRegion(911): compaction completed on region TestMergeTool,row_0100,1225333235666 in 0sec
[junit] 2008-10-30 02:20:39,612 DEBUG [main] regionserver.HRegion(173): Files for region: TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:39,613 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/272495894/contents
[junit] 2008-10-30 02:20:39,613 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/272495894/contents/info
[junit] 2008-10-30 02:20:39,614 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/272495894/contents/info/4385880880880393382 size=9
[junit] 2008-10-30 02:20:39,615 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/272495894/contents/mapfiles
[junit] 2008-10-30 02:20:39,616 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/272495894/contents/mapfiles/4385880880880393382
[junit] 2008-10-30 02:20:39,617 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/272495894/contents/mapfiles/4385880880880393382/data size=486
[junit] 2008-10-30 02:20:39,617 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/272495894/contents/mapfiles/4385880880880393382/index size=227
[junit] 2008-10-30 02:20:39,617 INFO [main] regionserver.HRegion(897): starting compaction on region TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:39,626 DEBUG [main] regionserver.HStore(893): started compaction of 1 files into /user/hudson/TestMergeTool/compaction.dir/105657654/contents/mapfiles/5937543761752100082
[junit] 2008-10-30 02:20:40,265 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/TestMergeTool/compaction.dir/105657654/contents/mapfiles/5937543761752100082 to /user/hudson/TestMergeTool/105657654/contents/mapfiles/7564240403140724975
[junit] 2008-10-30 02:20:40,332 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-30 02:20:40,392 DEBUG [main] regionserver.HStore(911): Completed compaction of 105657654/contents store size is 204.0
[junit] 2008-10-30 02:20:40,416 INFO [main] regionserver.HRegion(911): compaction completed on region TestMergeTool,,1225333192426 in 0sec
[junit] 2008-10-30 02:20:40,417 DEBUG [main] regionserver.HRegion(178): Files for region: TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:40,417 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/105657654/contents
[junit] 2008-10-30 02:20:40,418 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/105657654/contents/info
[junit] 2008-10-30 02:20:40,419 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/105657654/contents/info/7564240403140724975 size=9
[junit] 2008-10-30 02:20:40,419 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/105657654/contents/mapfiles
[junit] 2008-10-30 02:20:40,420 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/105657654/contents/mapfiles/7564240403140724975
[junit] 2008-10-30 02:20:40,421 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/105657654/contents/mapfiles/7564240403140724975/data size=204
[junit] 2008-10-30 02:20:40,421 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/105657654/contents/mapfiles/7564240403140724975/index size=227
[junit] 2008-10-30 02:20:40,421 INFO [main] regionserver.HRegion(202): Creating new region REGION => {NAME => 'TestMergeTool,,1225333240421', STARTKEY => '', ENDKEY => '', ENCODED => 883433574, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-30 02:20:40,453 INFO [main] regionserver.HRegion(211): starting merge of regions: TestMergeTool,row_0100,1225333235666 and TestMergeTool,,1225333192426 into new region REGION => {NAME => 'TestMergeTool,,1225333240421', STARTKEY => '', ENDKEY => '', ENCODED => 883433574, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <[B...@4f80d6> and end key <[B...@4f80d6>
[junit] 2008-10-30 02:20:40,454 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:40,454 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:40,454 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:40,454 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:40,454 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:40,454 DEBUG [main] regionserver.HStore(552): closed 272495894/contents
[junit] 2008-10-30 02:20:40,455 INFO [main] regionserver.HRegion(628): closed TestMergeTool,row_0100,1225333235666
[junit] 2008-10-30 02:20:40,455 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:40,455 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:40,455 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:40,455 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:40,455 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:40,456 DEBUG [main] regionserver.HStore(552): closed 105657654/contents
[junit] 2008-10-30 02:20:40,456 INFO [main] regionserver.HRegion(628): closed TestMergeTool,,1225333192426
[junit] 2008-10-30 02:20:40,533 DEBUG [main] regionserver.HRegion(234): Adjusting sequence id of storeFile 105657654/contents/7564240403140724975 down by one; sequence id A=2, sequence id B=2
[junit] 2008-10-30 02:20:40,618 DEBUG [main] regionserver.HRegion(245): Renaming 272495894/contents/4385880880880393382 to 883433574/contents/288847300247397501
[junit] 2008-10-30 02:20:40,669 DEBUG [main] regionserver.HRegion(245): Renaming 105657654/contents/7564240403140724975 to 883433574/contents/7550515463163255910
[junit] 2008-10-30 02:20:40,719 DEBUG [main] regionserver.HRegion(251): Files for new region
[junit] 2008-10-30 02:20:40,720 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents
[junit] 2008-10-30 02:20:40,721 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/info
[junit] 2008-10-30 02:20:40,722 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/info/288847300247397501 size=9
[junit] 2008-10-30 02:20:40,722 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/info/7550515463163255910 size=9
[junit] 2008-10-30 02:20:40,722 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles
[junit] 2008-10-30 02:20:40,723 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/288847300247397501
[junit] 2008-10-30 02:20:40,724 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/288847300247397501/data size=486
[junit] 2008-10-30 02:20:40,724 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/288847300247397501/index size=227
[junit] 2008-10-30 02:20:40,724 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/7550515463163255910
[junit] 2008-10-30 02:20:40,725 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/7550515463163255910/data size=204
[junit] 2008-10-30 02:20:40,725 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/7550515463163255910/index size=227
[junit] 2008-10-30 02:20:40,726 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,,1225333240421/883433574
[junit] 2008-10-30 02:20:40,735 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/883433574/contents/info/288847300247397501, isReference=false, sequence id=2
[junit] 2008-10-30 02:20:40,745 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/883433574/contents/info/7550515463163255910, isReference=false, sequence id=1
[junit] 2008-10-30 02:20:40,746 DEBUG [main] regionserver.HStore(231): Loaded 2 file(s) in hstore 883433574/contents, max sequence id 2
[junit] 2008-10-30 02:20:40,747 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-30 02:20:40,760 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,,1225333240421 is 3
[junit] 2008-10-30 02:20:40,761 INFO [main] regionserver.HRegion(514): region TestMergeTool,,1225333240421/883433574 available
[junit] 2008-10-30 02:20:40,761 INFO [main] regionserver.HRegion(897): starting compaction on region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:40,764 DEBUG [main] regionserver.HStore(857): Compaction size of 883433574/contents: 1.1k; Skipped 0 files , size: 0
[junit] 2008-10-30 02:20:40,781 DEBUG [main] regionserver.HStore(893): started compaction of 2 files into /user/hudson/TestMergeTool/compaction.dir/883433574/contents/mapfiles/8474402128623575512
[junit] 2008-10-30 02:20:41,077 DEBUG [main] regionserver.HStore(1064): moving /user/hudson/TestMergeTool/compaction.dir/883433574/contents/mapfiles/8474402128623575512 to /user/hudson/TestMergeTool/883433574/contents/mapfiles/5866468671843865860
[junit] 2008-10-30 02:20:41,152 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-30 02:20:41,306 DEBUG [main] regionserver.HStore(911): Completed compaction of 883433574/contents store size is 580.0; time since last major compaction: 1 seconds
[junit] 2008-10-30 02:20:41,342 INFO [main] regionserver.HRegion(911): compaction completed on region TestMergeTool,,1225333240421 in 0sec
[junit] 2008-10-30 02:20:41,344 DEBUG [main] regionserver.HRegion(258): Files for new region
[junit] 2008-10-30 02:20:41,345 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents
[junit] 2008-10-30 02:20:41,346 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/info
[junit] 2008-10-30 02:20:41,347 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/info/5866468671843865860 size=9
[junit] 2008-10-30 02:20:41,347 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles
[junit] 2008-10-30 02:20:41,350 DEBUG [main] regionserver.HRegion(311): d hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/5866468671843865860
[junit] 2008-10-30 02:20:41,351 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/5866468671843865860/data size=580
[junit] 2008-10-30 02:20:41,351 DEBUG [main] regionserver.HRegion(314): f hdfs://localhost:54496/user/hudson/TestMergeTool/883433574/contents/mapfiles/5866468671843865860/index size=227
[junit] 2008-10-30 02:20:41,352 DEBUG [main] regionserver.HRegion(2280): DELETING region hdfs://localhost:54496/user/hudson/TestMergeTool/272495894
[junit] 2008-10-30 02:20:41,382 DEBUG [main] regionserver.HRegion(2280): DELETING region hdfs://localhost:54496/user/hudson/TestMergeTool/105657654
[junit] 2008-10-30 02:20:41,425 INFO [main] regionserver.HRegion(264): merge completed. New region is TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,425 DEBUG [main] util.Merge(308): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1225333235666', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 272495894, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2008-10-30 02:20:41,433 DEBUG [main] util.Merge(308): Removing region: REGION => {NAME => 'TestMergeTool,,1225333192426', STARTKEY => '', ENDKEY => '', ENCODED => 105657654, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2008-10-30 02:20:41,440 INFO [main] util.Merge(244): Adding REGION => {NAME => 'TestMergeTool,,1225333240421', STARTKEY => '', ENDKEY => '', ENCODED => 883433574, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_ROOT => 'false', IS_META => 'true', FAMILIES => [{NAME => 'info', BLOOMFILTER => 'false', VERSIONS => '1', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'historian', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'false'}]}}
[junit] 2008-10-30 02:20:41,468 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,468 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,470 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,470 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,470 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,470 DEBUG [main] regionserver.HStore(552): closed 883433574/contents
[junit] 2008-10-30 02:20:41,470 INFO [main] regionserver.HRegion(628): closed TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,470 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region -ROOT-,,0
[junit] 2008-10-30 02:20:41,471 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region -ROOT-,,0
[junit] 2008-10-30 02:20:41,471 DEBUG [main] regionserver.HRegion(607): No more active scanners for region -ROOT-,,0
[junit] 2008-10-30 02:20:41,471 DEBUG [main] regionserver.HRegion(609): Updates disabled for region -ROOT-,,0
[junit] 2008-10-30 02:20:41,471 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region -ROOT-,,0
[junit] 2008-10-30 02:20:41,472 DEBUG [main] regionserver.HStore(552): closed 70236052/info
[junit] 2008-10-30 02:20:41,472 INFO [main] regionserver.HRegion(628): closed -ROOT-,,0
[junit] 2008-10-30 02:20:41,472 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region .META.,,1
[junit] 2008-10-30 02:20:41,472 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region .META.,,1
[junit] 2008-10-30 02:20:41,472 DEBUG [main] regionserver.HRegion(607): No more active scanners for region .META.,,1
[junit] 2008-10-30 02:20:41,472 DEBUG [main] regionserver.HRegion(609): Updates disabled for region .META.,,1
[junit] 2008-10-30 02:20:41,473 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region .META.,,1
[junit] 2008-10-30 02:20:41,473 DEBUG [main] regionserver.HRegion(1022): Started memcache flush for region .META.,,1. Current region memcache size 466.0
[junit] 2008-10-30 02:20:41,752 DEBUG [main] regionserver.HStore(647): Added /user/hudson/.META./1028785192/info/mapfiles/4866907926906075568 with 3 entries, sequence id 21, data size 466.0, file size 618.0
[junit] 2008-10-30 02:20:41,752 DEBUG [main] regionserver.HRegion(1096): Finished memcache flush for region .META.,,1 in 279ms, sequence id=21, compaction requested=true
[junit] 2008-10-30 02:20:41,753 DEBUG [main] regionserver.HStore(552): closed 1028785192/historian
[junit] 2008-10-30 02:20:41,753 DEBUG [main] regionserver.HStore(552): closed 1028785192/info
[junit] 2008-10-30 02:20:41,753 INFO [main] regionserver.HRegion(628): closed .META.,,1
[junit] 2008-10-30 02:20:41,798 DEBUG [main] regionserver.HLog(259): Closing current log writer /user/hudson/log_1225333237285/hlog.dat.0
[junit] 2008-10-30 02:20:41,833 INFO [main] regionserver.HLog(272): New log writer created at /user/hudson/log_1225333237285/hlog.dat.1225333241798
[junit] 2008-10-30 02:20:41,833 DEBUG [main] regionserver.HLog(277): Last sequence written is empty. Deleting all old hlogs
[junit] 2008-10-30 02:20:41,833 INFO [main] regionserver.HLog(329): removing old log file /user/hudson/log_1225333237285/hlog.dat.0 whose highest sequence/edit id is 21
[junit] 2008-10-30 02:20:41,834 DEBUG [main] regionserver.HLog(365): closing log writer in hdfs://localhost:54496/user/hudson/log_1225333237285
[junit] 2008-10-30 02:20:41,908 DEBUG [main] regionserver.HRegion(2183): Opening region: REGION => {NAME => 'TestMergeTool,,1225333240421', STARTKEY => '', ENDKEY => '', ENCODED => 883433574, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2008-10-30 02:20:41,909 DEBUG [main] regionserver.HRegion(431): Opening region TestMergeTool,,1225333240421/883433574
[junit] 2008-10-30 02:20:41,925 DEBUG [main] regionserver.HStore(457): loaded /user/hudson/TestMergeTool/883433574/contents/info/5866468671843865860, isReference=false, sequence id=2
[junit] 2008-10-30 02:20:41,926 DEBUG [main] regionserver.HStore(231): Loaded 1 file(s) in hstore 883433574/contents, max sequence id 2
[junit] 2008-10-30 02:20:41,926 INFO [main] regionserver.HStoreFile$HbaseMapFile$HbaseReader(652): Block Cache enabled
[junit] 2008-10-30 02:20:41,936 DEBUG [main] regionserver.HRegion(492): Next sequence id for region TestMergeTool,,1225333240421 is 3
[junit] 2008-10-30 02:20:41,937 INFO [main] regionserver.HRegion(514): region TestMergeTool,,1225333240421/883433574 available
[junit] 2008-10-30 02:20:41,938 DEBUG [main] regionserver.HRegion(577): Compactions and cache flushes disabled for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,938 DEBUG [main] regionserver.HRegion(592): Scanners disabled for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,938 DEBUG [main] regionserver.HRegion(607): No more active scanners for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,938 DEBUG [main] regionserver.HRegion(609): Updates disabled for region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,939 DEBUG [main] regionserver.HRegion(615): No more row locks outstanding on region TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,939 DEBUG [main] regionserver.HStore(552): closed 883433574/contents
[junit] 2008-10-30 02:20:41,939 INFO [main] regionserver.HRegion(628): closed TestMergeTool,,1225333240421
[junit] 2008-10-30 02:20:41,939 INFO [main] util.TestMergeTool(171): Verified merging regions 0+1+2+3 and 4
[junit] 2008-10-30 02:20:41,939 DEBUG [main] regionserver.HLog(365): closing log writer in /tmp/log_1225333212781
[junit] 2008-10-30 02:20:42,024 INFO [main] hbase.HBaseTestCase(600): Shutting down FileSystem
[junit] 2008-10-30 02:20:42,911 INFO [main] hbase.HBaseTestCase(607): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2008-10-30 02:20:42,913 INFO [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=54504]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=54504]
[junit] 2008-10-30 02:20:43,122 INFO [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:54504
[junit] 2008-10-30 02:20:43,258 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-10-30 02:20:43,258 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@687ea9
[junit] 2008-10-30 02:20:43,329 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-10-30 02:20:43,330 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@12462b3
[junit] Shutting down DataNode 0
[junit] 2008-10-30 02:20:44,344 INFO [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=54501]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=54501]
[junit] 2008-10-30 02:20:44,344 INFO [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:54501
[junit] 2008-10-30 02:20:44,442 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-10-30 02:20:44,442 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@15d252d
[junit] 2008-10-30 02:20:44,510 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-10-30 02:20:44,510 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@186dda3
[junit] 2008-10-30 02:20:45,644 INFO [main] http.SocketListener(212): Stopped SocketListener on 0.0.0.0:54497
[junit] 2008-10-30 02:20:45,710 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-10-30 02:20:45,711 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1493102
[junit] 2008-10-30 02:20:45,777 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-10-30 02:20:45,777 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@349471
[junit] 2008-10-30 02:20:45,777 WARN [org.apache.hadoop.dfs.FSNamesystem$ReplicationMonitor@1372656] dfs.FSNamesystem$ReplicationMonitor(2193): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 53.585 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2008-10-30 02:20:47,442 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:158)
[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.238 sec
[junit] Running org.onelab.test.TestFilter
[junit] 2008-10-30 02:20:48,276 INFO [main] test.TestFilter(244): Checking for false negatives
[junit] 2008-10-30 02:20:48,280 INFO [main] test.TestFilter(255): Checking for false positives
[junit] 2008-10-30 02:20:48,281 INFO [main] test.TestFilter(262): Success!
[junit] 2008-10-30 02:20:48,282 INFO [main] test.TestFilter(226): Checking serialization/deserialization
[junit] 2008-10-30 02:20:48,295 INFO [main] test.TestFilter(244): Checking for false negatives
[junit] 2008-10-30 02:20:48,297 INFO [main] test.TestFilter(255): Checking for false positives
[junit] 2008-10-30 02:20:48,298 INFO [main] test.TestFilter(262): Success!
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.099 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:453: Tests failed!
Total time: 39 minutes 34 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results