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/11/06 21:27:45 UTC
Build failed in Hudson: HBase-Patch #391
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/391/changes
Changes:
[apurtell] HBASE-981 hbase.io.index.interval doesn't seem to have an effect
------------------------------------------
[...truncated 21304 lines...]
[junit] 2008-11-06 20:27:35,265 INFO [main] regionserver.HLog(272): New log writer created at /user/hudson/log_1226003250956/hlog.dat.1226003255228
[junit] 2008-11-06 20:27:35,266 DEBUG [main] regionserver.HLog(277): Last sequence written is empty. Deleting all old hlogs
[junit] 2008-11-06 20:27:35,266 INFO [main] regionserver.HLog(329): removing old log file /user/hudson/log_1226003250956/hlog.dat.0 whose highest sequence/edit id is 17
[junit] 2008-11-06 20:27:35,267 DEBUG [main] regionserver.HLog(365): closing log writer in hdfs://localhost:42234/user/hudson/log_1226003250956
[junit] 2008-11-06 20:27:35,358 DEBUG [main] regionserver.HRegion(2184): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1226003253332', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1303204373, 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-11-06 20:27:35,358 DEBUG [main] regionserver.HRegion(432): Opening region TestMergeTool,row_0100,1226003253332/1303204373
[junit] 2008-11-06 20:27:35,367 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/TestMergeTool/1303204373/contents/info/4066640136817504354, isReference=false, sequence id=2, length=486
[junit] 2008-11-06 20:27:35,368 DEBUG [main] regionserver.HStore(229): Loaded 1 file(s) in hstore 1303204373/contents, max sequence id 2
[junit] 2008-11-06 20:27:35,374 DEBUG [main] regionserver.HRegion(493): Next sequence id for region TestMergeTool,row_0100,1226003253332 is 3
[junit] 2008-11-06 20:27:35,375 INFO [main] regionserver.HRegion(515): region TestMergeTool,row_0100,1226003253332/1303204373 available
[junit] 2008-11-06 20:27:35,376 DEBUG [main] regionserver.HRegion(578): Compactions and cache flushes disabled for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:35,376 DEBUG [main] regionserver.HRegion(593): Scanners disabled for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:35,376 DEBUG [main] regionserver.HRegion(608): No more active scanners for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:35,376 DEBUG [main] regionserver.HRegion(610): Updates disabled for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:35,377 DEBUG [main] regionserver.HRegion(616): No more row locks outstanding on region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:35,377 DEBUG [main] regionserver.HStore(595): closed 1303204373/contents
[junit] 2008-11-06 20:27:35,377 INFO [main] regionserver.HRegion(629): closed TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:35,377 INFO [main] util.TestMergeTool(171): Verified merging regions 0+1+2 and 3
[junit] 2008-11-06 20:27:35,377 INFO [main] util.TestMergeTool(158): merging regions 0+1+2+3 and 4
[junit] 2008-11-06 20:27:35,377 INFO [main] util.Merge(80): Verifying that file system is available...
[junit] 2008-11-06 20:27:35,378 INFO [main] util.Merge(89): Verifying that HBase is not running...
[junit] 2008-11-06 20:27:35,390 INFO [main] util.Merge(190): Merging regions [B@1ca3cb and [B@4577f9 in table [B@c78e02
[junit] 2008-11-06 20:27:35,456 INFO [main] regionserver.HLog(272): New log writer created at /user/hudson/log_1226003255391/hlog.dat.1226003255413
[junit] 2008-11-06 20:27:35,457 DEBUG [main] regionserver.HRegion(2184): 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-11-06 20:27:35,459 DEBUG [main] regionserver.HRegion(432): Opening region -ROOT-,,0/70236052
[junit] 2008-11-06 20:27:35,471 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/-ROOT-/70236052/info/info/932505270467494489, isReference=false, sequence id=1, length=569
[junit] 2008-11-06 20:27:35,473 DEBUG [main] regionserver.HStore(229): Loaded 1 file(s) in hstore 70236052/info, max sequence id 1
[junit] 2008-11-06 20:27:35,480 DEBUG [main] regionserver.HRegion(493): Next sequence id for region -ROOT-,,0 is 2
[junit] 2008-11-06 20:27:35,481 INFO [main] regionserver.HRegion(515): region -ROOT-,,0/70236052 available
[junit] 2008-11-06 20:27:35,481 DEBUG [main] regionserver.HLog(205): changing sequence number from 0 to 2
[junit] 2008-11-06 20:27:35,482 INFO [main] regionserver.HRegion(898): starting compaction on region -ROOT-,,0
[junit] 2008-11-06 20:27:35,484 INFO [main] regionserver.HRegion(912): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2008-11-06 20:27:35,490 INFO [main] util.Merge(204): Found meta for region1 [B@10e468f, meta for region2 [B@10e468f
[junit] 2008-11-06 20:27:35,490 DEBUG [main] regionserver.HRegion(2184): 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-11-06 20:27:35,490 DEBUG [main] regionserver.HRegion(432): Opening region .META.,,1/1028785192
[junit] 2008-11-06 20:27:35,499 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/.META./1028785192/info/info/6238399713985357904, isReference=false, sequence id=5, length=1928
[junit] 2008-11-06 20:27:35,505 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/.META./1028785192/info/info/6496600197375491971, isReference=false, sequence id=17, length=658
[junit] 2008-11-06 20:27:35,537 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/.META./1028785192/info/info/8037543892230871542, isReference=false, sequence id=13, length=1206
[junit] 2008-11-06 20:27:35,538 DEBUG [main] regionserver.HStore(229): Loaded 3 file(s) in hstore 1028785192/info, max sequence id 17
[junit] 2008-11-06 20:27:35,565 DEBUG [main] regionserver.HRegion(493): Next sequence id for region .META.,,1 is 18
[junit] 2008-11-06 20:27:35,568 INFO [main] regionserver.HRegion(515): region .META.,,1/1028785192 available
[junit] 2008-11-06 20:27:35,569 DEBUG [main] regionserver.HLog(205): changing sequence number from 2 to 18
[junit] 2008-11-06 20:27:35,569 INFO [main] regionserver.HRegion(898): starting compaction on region .META.,,1
[junit] 2008-11-06 20:27:35,573 DEBUG [main] regionserver.HStore(889): Compaction size of 1028785192/info: 4.5k; Skipped 0 files , size: 0
[junit] 2008-11-06 20:27:35,613 DEBUG [main] regionserver.HStore(907): started compaction of 3 files into /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/695442272597599478
[junit] 2008-11-06 20:27:35,910 DEBUG [main] regionserver.HStore(1165): moving /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/695442272597599478 to /user/hudson/.META./1028785192/info/mapfiles/4041881657389062481
[junit] 2008-11-06 20:27:36,213 DEBUG [main] regionserver.HStore(925): Completed compaction of 1028785192/info store size is 3.5k; time since last major compaction: 0 seconds
[junit] 2008-11-06 20:27:36,257 INFO [main] regionserver.HRegion(912): compaction completed on region .META.,,1 in 0sec
[junit] 2008-11-06 20:27:36,259 DEBUG [main] regionserver.HRegion(2184): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1226003253332', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1303204373, 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-11-06 20:27:36,260 DEBUG [main] regionserver.HRegion(432): Opening region TestMergeTool,row_0100,1226003253332/1303204373
[junit] 2008-11-06 20:27:36,267 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/TestMergeTool/1303204373/contents/info/4066640136817504354, isReference=false, sequence id=2, length=486
[junit] 2008-11-06 20:27:36,268 DEBUG [main] regionserver.HStore(229): Loaded 1 file(s) in hstore 1303204373/contents, max sequence id 2
[junit] 2008-11-06 20:27:36,274 DEBUG [main] regionserver.HRegion(493): Next sequence id for region TestMergeTool,row_0100,1226003253332 is 3
[junit] 2008-11-06 20:27:36,275 INFO [main] regionserver.HRegion(515): region TestMergeTool,row_0100,1226003253332/1303204373 available
[junit] 2008-11-06 20:27:36,275 DEBUG [main] regionserver.HRegion(2184): Opening region: REGION => {NAME => 'TestMergeTool,,1226003224721', STARTKEY => '', ENDKEY => '', ENCODED => 944282181, 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-11-06 20:27:36,276 DEBUG [main] regionserver.HRegion(432): Opening region TestMergeTool,,1226003224721/944282181
[junit] 2008-11-06 20:27:36,366 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/TestMergeTool/944282181/contents/info/6933377788073175383, isReference=false, sequence id=2, length=204
[junit] 2008-11-06 20:27:36,367 DEBUG [main] regionserver.HStore(229): Loaded 1 file(s) in hstore 944282181/contents, max sequence id 2
[junit] 2008-11-06 20:27:36,379 DEBUG [main] regionserver.HRegion(493): Next sequence id for region TestMergeTool,,1226003224721 is 3
[junit] 2008-11-06 20:27:36,380 INFO [main] regionserver.HRegion(515): region TestMergeTool,,1226003224721/944282181 available
[junit] 2008-11-06 20:27:36,381 INFO [main] regionserver.HRegion(898): starting compaction on region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:36,394 DEBUG [main] regionserver.HStore(907): started compaction of 1 files into /user/hudson/TestMergeTool/compaction.dir/1303204373/contents/mapfiles/2868426775794350852
[junit] 2008-11-06 20:27:37,325 DEBUG [main] regionserver.HStore(1165): moving /user/hudson/TestMergeTool/compaction.dir/1303204373/contents/mapfiles/2868426775794350852 to /user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570
[junit] 2008-11-06 20:27:37,476 DEBUG [main] regionserver.HStore(925): Completed compaction of 1303204373/contents store size is 486.0
[junit] 2008-11-06 20:27:37,506 INFO [main] regionserver.HRegion(912): compaction completed on region TestMergeTool,row_0100,1226003253332 in 1sec
[junit] 2008-11-06 20:27:37,506 DEBUG [main] regionserver.HRegion(174): Files for region: TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:37,507 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents
[junit] 2008-11-06 20:27:37,508 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/info
[junit] 2008-11-06 20:27:37,509 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/info/5616567245829338570 size=9
[junit] 2008-11-06 20:27:37,509 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles
[junit] 2008-11-06 20:27:37,510 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570
[junit] 2008-11-06 20:27:37,511 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570/data size=486
[junit] 2008-11-06 20:27:37,511 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570/index size=227
[junit] 2008-11-06 20:27:37,512 INFO [main] regionserver.HRegion(898): starting compaction on region TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:37,521 DEBUG [main] regionserver.HStore(907): started compaction of 1 files into /user/hudson/TestMergeTool/compaction.dir/944282181/contents/mapfiles/5908293202449466261
[junit] 2008-11-06 20:27:37,796 DEBUG [main] regionserver.HStore(1165): moving /user/hudson/TestMergeTool/compaction.dir/944282181/contents/mapfiles/5908293202449466261 to /user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171
[junit] 2008-11-06 20:27:37,955 DEBUG [main] regionserver.HStore(925): Completed compaction of 944282181/contents store size is 204.0
[junit] 2008-11-06 20:27:37,996 INFO [main] regionserver.HRegion(912): compaction completed on region TestMergeTool,,1226003224721 in 0sec
[junit] 2008-11-06 20:27:37,996 DEBUG [main] regionserver.HRegion(179): Files for region: TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:37,998 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents
[junit] 2008-11-06 20:27:37,999 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/info
[junit] 2008-11-06 20:27:38,000 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/info/1442289836149478171 size=9
[junit] 2008-11-06 20:27:38,000 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles
[junit] 2008-11-06 20:27:38,001 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171
[junit] 2008-11-06 20:27:38,002 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171/data size=204
[junit] 2008-11-06 20:27:38,002 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171/index size=227
[junit] 2008-11-06 20:27:38,003 INFO [main] regionserver.HRegion(203): Creating new region REGION => {NAME => 'TestMergeTool,,1226003258003', STARTKEY => '', ENDKEY => '', ENCODED => 1931386174, 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-11-06 20:27:38,034 INFO [main] regionserver.HRegion(212): starting merge of regions: TestMergeTool,row_0100,1226003253332 and TestMergeTool,,1226003224721 into new region REGION => {NAME => 'TestMergeTool,,1226003258003', STARTKEY => '', ENDKEY => '', ENCODED => 1931386174, 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...@1ea0252> and end key <[B...@1ea0252>
[junit] 2008-11-06 20:27:38,034 DEBUG [main] regionserver.HRegion(578): Compactions and cache flushes disabled for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:38,034 DEBUG [main] regionserver.HRegion(593): Scanners disabled for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:38,034 DEBUG [main] regionserver.HRegion(608): No more active scanners for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:38,035 DEBUG [main] regionserver.HRegion(610): Updates disabled for region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:38,035 DEBUG [main] regionserver.HRegion(616): No more row locks outstanding on region TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:38,035 DEBUG [main] regionserver.HStore(595): closed 1303204373/contents
[junit] 2008-11-06 20:27:38,035 INFO [main] regionserver.HRegion(629): closed TestMergeTool,row_0100,1226003253332
[junit] 2008-11-06 20:27:38,035 DEBUG [main] regionserver.HRegion(578): Compactions and cache flushes disabled for region TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:38,036 DEBUG [main] regionserver.HRegion(593): Scanners disabled for region TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:38,036 DEBUG [main] regionserver.HRegion(608): No more active scanners for region TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:38,036 DEBUG [main] regionserver.HRegion(610): Updates disabled for region TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:38,036 DEBUG [main] regionserver.HRegion(616): No more row locks outstanding on region TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:38,036 DEBUG [main] regionserver.HStore(595): closed 944282181/contents
[junit] 2008-11-06 20:27:38,037 INFO [main] regionserver.HRegion(629): closed TestMergeTool,,1226003224721
[junit] 2008-11-06 20:27:38,097 DEBUG [main] regionserver.HRegion(235): Adjusting sequence id of storeFile 944282181/contents/1442289836149478171 down by one; sequence id A=2, sequence id B=2
[junit] 2008-11-06 20:27:38,204 DEBUG [main] regionserver.HRegion(246): Renaming 1303204373/contents/5616567245829338570 to 1931386174/contents/1777032553035286939
[junit] 2008-11-06 20:27:38,264 DEBUG [main] regionserver.HRegion(246): Renaming 944282181/contents/1442289836149478171 to 1931386174/contents/4071795926526351466
[junit] 2008-11-06 20:27:38,331 DEBUG [main] regionserver.HRegion(252): Files for new region
[junit] 2008-11-06 20:27:38,332 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents
[junit] 2008-11-06 20:27:38,333 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info
[junit] 2008-11-06 20:27:38,333 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/1777032553035286939 size=9
[junit] 2008-11-06 20:27:38,334 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/4071795926526351466 size=9
[junit] 2008-11-06 20:27:38,334 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles
[junit] 2008-11-06 20:27:38,334 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939
[junit] 2008-11-06 20:27:38,335 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939/data size=486
[junit] 2008-11-06 20:27:38,335 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939/index size=227
[junit] 2008-11-06 20:27:38,335 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466
[junit] 2008-11-06 20:27:38,336 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466/data size=204
[junit] 2008-11-06 20:27:38,336 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466/index size=227
[junit] 2008-11-06 20:27:38,337 DEBUG [main] regionserver.HRegion(432): Opening region TestMergeTool,,1226003258003/1931386174
[junit] 2008-11-06 20:27:38,344 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/TestMergeTool/1931386174/contents/info/1777032553035286939, isReference=false, sequence id=2, length=486
[junit] 2008-11-06 20:27:38,349 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/TestMergeTool/1931386174/contents/info/4071795926526351466, isReference=false, sequence id=1, length=204
[junit] 2008-11-06 20:27:38,350 DEBUG [main] regionserver.HStore(229): Loaded 2 file(s) in hstore 1931386174/contents, max sequence id 2
[junit] 2008-11-06 20:27:38,361 DEBUG [main] regionserver.HRegion(493): Next sequence id for region TestMergeTool,,1226003258003 is 3
[junit] 2008-11-06 20:27:38,362 INFO [main] regionserver.HRegion(515): region TestMergeTool,,1226003258003/1931386174 available
[junit] 2008-11-06 20:27:38,363 INFO [main] regionserver.HRegion(898): starting compaction on region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:38,366 DEBUG [main] regionserver.HStore(889): Compaction size of 1931386174/contents: 1.1k; Skipped 0 files , size: 0
[junit] 2008-11-06 20:27:38,377 DEBUG [main] regionserver.HStore(907): started compaction of 2 files into /user/hudson/TestMergeTool/compaction.dir/1931386174/contents/mapfiles/3853862809414353089
[junit] 2008-11-06 20:27:38,704 DEBUG [main] regionserver.HStore(1165): moving /user/hudson/TestMergeTool/compaction.dir/1931386174/contents/mapfiles/3853862809414353089 to /user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479
[junit] 2008-11-06 20:27:38,916 DEBUG [main] regionserver.HStore(925): Completed compaction of 1931386174/contents store size is 580.0; time since last major compaction: 0 seconds
[junit] 2008-11-06 20:27:38,951 INFO [main] regionserver.HRegion(912): compaction completed on region TestMergeTool,,1226003258003 in 0sec
[junit] 2008-11-06 20:27:38,951 DEBUG [main] regionserver.HRegion(259): Files for new region
[junit] 2008-11-06 20:27:38,952 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents
[junit] 2008-11-06 20:27:38,953 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info
[junit] 2008-11-06 20:27:38,954 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/3550934524445237479 size=9
[junit] 2008-11-06 20:27:38,954 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles
[junit] 2008-11-06 20:27:38,955 DEBUG [main] regionserver.HRegion(312): d hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479
[junit] 2008-11-06 20:27:38,955 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479/data size=580
[junit] 2008-11-06 20:27:38,956 DEBUG [main] regionserver.HRegion(315): f hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479/index size=227
[junit] 2008-11-06 20:27:38,956 DEBUG [main] regionserver.HRegion(2281): DELETING region hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373
[junit] 2008-11-06 20:27:38,994 DEBUG [main] regionserver.HRegion(2281): DELETING region hdfs://localhost:42234/user/hudson/TestMergeTool/944282181
[junit] 2008-11-06 20:27:39,029 INFO [main] regionserver.HRegion(265): merge completed. New region is TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,029 DEBUG [main] util.Merge(308): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1226003253332', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1303204373, 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-11-06 20:27:39,031 DEBUG [main] util.Merge(308): Removing region: REGION => {NAME => 'TestMergeTool,,1226003224721', STARTKEY => '', ENDKEY => '', ENCODED => 944282181, 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-11-06 20:27:39,038 INFO [main] util.Merge(244): Adding REGION => {NAME => 'TestMergeTool,,1226003258003', STARTKEY => '', ENDKEY => '', ENCODED => 1931386174, 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-11-06 20:27:39,039 DEBUG [main] regionserver.HRegion(578): Compactions and cache flushes disabled for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,039 DEBUG [main] regionserver.HRegion(593): Scanners disabled for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,039 DEBUG [main] regionserver.HRegion(608): No more active scanners for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,039 DEBUG [main] regionserver.HRegion(610): Updates disabled for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,039 DEBUG [main] regionserver.HRegion(616): No more row locks outstanding on region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,039 DEBUG [main] regionserver.HStore(595): closed 1931386174/contents
[junit] 2008-11-06 20:27:39,040 INFO [main] regionserver.HRegion(629): closed TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,040 DEBUG [main] regionserver.HRegion(578): Compactions and cache flushes disabled for region -ROOT-,,0
[junit] 2008-11-06 20:27:39,040 DEBUG [main] regionserver.HRegion(593): Scanners disabled for region -ROOT-,,0
[junit] 2008-11-06 20:27:39,040 DEBUG [main] regionserver.HRegion(608): No more active scanners for region -ROOT-,,0
[junit] 2008-11-06 20:27:39,040 DEBUG [main] regionserver.HRegion(610): Updates disabled for region -ROOT-,,0
[junit] 2008-11-06 20:27:39,040 DEBUG [main] regionserver.HRegion(616): No more row locks outstanding on region -ROOT-,,0
[junit] 2008-11-06 20:27:39,041 DEBUG [main] regionserver.HStore(595): closed 70236052/info
[junit] 2008-11-06 20:27:39,041 INFO [main] regionserver.HRegion(629): closed -ROOT-,,0
[junit] 2008-11-06 20:27:39,041 DEBUG [main] regionserver.HRegion(578): Compactions and cache flushes disabled for region .META.,,1
[junit] 2008-11-06 20:27:39,041 DEBUG [main] regionserver.HRegion(593): Scanners disabled for region .META.,,1
[junit] 2008-11-06 20:27:39,041 DEBUG [main] regionserver.HRegion(608): No more active scanners for region .META.,,1
[junit] 2008-11-06 20:27:39,041 DEBUG [main] regionserver.HRegion(610): Updates disabled for region .META.,,1
[junit] 2008-11-06 20:27:39,042 DEBUG [main] regionserver.HRegion(616): No more row locks outstanding on region .META.,,1
[junit] 2008-11-06 20:27:39,042 DEBUG [main] regionserver.HRegion(1023): Started memcache flush for region .META.,,1. Current region memcache size 466.0
[junit] 2008-11-06 20:27:39,340 DEBUG [main] regionserver.HStore(690): Added /user/hudson/.META./1028785192/info/mapfiles/2677182741781292076 with 3 entries, sequence id 21, data size 466.0, file size 618.0
[junit] 2008-11-06 20:27:39,341 DEBUG [main] regionserver.HRegion(1097): Finished memcache flush for region .META.,,1 in 299ms, sequence id=21, compaction requested=true
[junit] 2008-11-06 20:27:39,341 DEBUG [main] regionserver.HStore(595): closed 1028785192/historian
[junit] 2008-11-06 20:27:39,342 DEBUG [main] regionserver.HStore(595): closed 1028785192/info
[junit] 2008-11-06 20:27:39,342 INFO [main] regionserver.HRegion(629): closed .META.,,1
[junit] 2008-11-06 20:27:39,385 DEBUG [main] regionserver.HLog(259): Closing current log writer /user/hudson/log_1226003255391/hlog.dat.0
[junit] 2008-11-06 20:27:39,428 INFO [main] regionserver.HLog(272): New log writer created at /user/hudson/log_1226003255391/hlog.dat.1226003259386
[junit] 2008-11-06 20:27:39,429 DEBUG [main] regionserver.HLog(277): Last sequence written is empty. Deleting all old hlogs
[junit] 2008-11-06 20:27:39,429 INFO [main] regionserver.HLog(329): removing old log file /user/hudson/log_1226003255391/hlog.dat.0 whose highest sequence/edit id is 21
[junit] 2008-11-06 20:27:39,429 DEBUG [main] regionserver.HLog(365): closing log writer in hdfs://localhost:42234/user/hudson/log_1226003255391
[junit] 2008-11-06 20:27:39,515 DEBUG [main] regionserver.HRegion(2184): Opening region: REGION => {NAME => 'TestMergeTool,,1226003258003', STARTKEY => '', ENDKEY => '', ENCODED => 1931386174, 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-11-06 20:27:39,515 DEBUG [main] regionserver.HRegion(432): Opening region TestMergeTool,,1226003258003/1931386174
[junit] 2008-11-06 20:27:39,523 DEBUG [main] regionserver.HStore(490): loaded /user/hudson/TestMergeTool/1931386174/contents/info/3550934524445237479, isReference=false, sequence id=2, length=580
[junit] 2008-11-06 20:27:39,523 DEBUG [main] regionserver.HStore(229): Loaded 1 file(s) in hstore 1931386174/contents, max sequence id 2
[junit] 2008-11-06 20:27:39,530 DEBUG [main] regionserver.HRegion(493): Next sequence id for region TestMergeTool,,1226003258003 is 3
[junit] 2008-11-06 20:27:39,532 INFO [main] regionserver.HRegion(515): region TestMergeTool,,1226003258003/1931386174 available
[junit] 2008-11-06 20:27:39,533 DEBUG [main] regionserver.HRegion(578): Compactions and cache flushes disabled for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,533 DEBUG [main] regionserver.HRegion(593): Scanners disabled for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,533 DEBUG [main] regionserver.HRegion(608): No more active scanners for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,533 DEBUG [main] regionserver.HRegion(610): Updates disabled for region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,534 DEBUG [main] regionserver.HRegion(616): No more row locks outstanding on region TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,534 DEBUG [main] regionserver.HStore(595): closed 1931386174/contents
[junit] 2008-11-06 20:27:39,534 INFO [main] regionserver.HRegion(629): closed TestMergeTool,,1226003258003
[junit] 2008-11-06 20:27:39,534 INFO [main] util.TestMergeTool(171): Verified merging regions 0+1+2+3 and 4
[junit] 2008-11-06 20:27:39,534 DEBUG [main] regionserver.HLog(365): closing log writer in /tmp/log_1226003234747
[junit] 2008-11-06 20:27:39,617 INFO [main] hbase.HBaseTestCase(600): Shutting down FileSystem
[junit] 2008-11-06 20:27:39,617 INFO [main] hbase.HBaseTestCase(607): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2008-11-06 20:27:39,624 INFO [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42252
[junit] 2008-11-06 20:27:39,753 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-11-06 20:27:39,753 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@135f44e
[junit] 2008-11-06 20:27:39,827 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-11-06 20:27:39,828 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@17c2891
[junit] Shutting down DataNode 0
[junit] 2008-11-06 20:27:40,837 INFO [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42244
[junit] 2008-11-06 20:27:40,837 INFO [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42244]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42244]
[junit] 2008-11-06 20:27:40,947 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-11-06 20:27:40,948 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@4d2af2
[junit] 2008-11-06 20:27:41,049 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-11-06 20:27:41,049 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@1579a30
[junit] 2008-11-06 20:27:42,066 INFO [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42240
[junit] 2008-11-06 20:27:42,137 INFO [main] util.Container(156): Stopped HttpContext[/static,/static]
[junit] 2008-11-06 20:27:42,137 INFO [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1c54796
[junit] 2008-11-06 20:27:42,248 INFO [main] util.Container(156): Stopped WebApplicationContext[/,/]
[junit] 2008-11-06 20:27:42,248 INFO [main] util.Container(156): Stopped org.mortbay.jetty.Server@19113f8
[junit] 2008-11-06 20:27:42,249 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@401369] namenode.FSNamesystem$ReplicationMonitor(2280): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 37.746 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2008-11-06 20:27:42,947 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.081 sec
[junit] Running org.onelab.test.TestFilter
[junit] 2008-11-06 20:27:43,570 INFO [main] test.TestFilter(244): Checking for false negatives
[junit] 2008-11-06 20:27:43,575 INFO [main] test.TestFilter(255): Checking for false positives
[junit] 2008-11-06 20:27:43,576 INFO [main] test.TestFilter(262): Success!
[junit] 2008-11-06 20:27:43,576 INFO [main] test.TestFilter(226): Checking serialization/deserialization
[junit] 2008-11-06 20:27:43,578 INFO [main] test.TestFilter(244): Checking for false negatives
[junit] 2008-11-06 20:27:43,580 INFO [main] test.TestFilter(255): Checking for false positives
[junit] 2008-11-06 20:27:43,581 INFO [main] test.TestFilter(262): Success!
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.068 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:453: Tests failed!
Total time: 45 minutes 35 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 #392
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/392/changes
Re: Build failed in Hudson: HBase-Patch #391
Posted by stack <st...@duboce.net>.
I took a look at your patch; no way that it can be related IMHO (Its TTMR
that failed). One of us will dig in...
St.Ack
On Thu, Nov 6, 2008 at 1:43 PM, Andrew Purtell <ap...@yahoo.com> wrote:
> This doesn't make any sense to me. I only added ~10 lines to HStore.
>
> What's this about "root path does not have a scheme"?
>
> - Andy
>
>
> > From: Apache Hudson Server <hu...@hudson.zones.apache.org>
> > Subject: Build failed in Hudson: HBase-Patch #391
> > To: hbase-dev@hadoop.apache.org
> > Date: Thursday, November 6, 2008, 12:27 PM
> > See
> > http://hudson.zones.apache.org/hudson/job/HBase-Patch/391/changes
> >
> > Changes:
> >
> > [apurtell] HBASE-981 hbase.io.index.interval doesn't
> > seem to have an effect
> >
> > ------------------------------------------
> > [...truncated 21304 lines...]
> > [junit] 2008-11-06 20:27:35,265 INFO [main]
> > regionserver.HLog(272): New log writer created at
> > /user/hudson/log_1226003250956/hlog.dat.1226003255228
> > [junit] 2008-11-06 20:27:35,266 DEBUG [main]
> > regionserver.HLog(277): Last sequence written is empty.
> > Deleting all old hlogs
> > [junit] 2008-11-06 20:27:35,266 INFO [main]
> > regionserver.HLog(329): removing old log file
> > /user/hudson/log_1226003250956/hlog.dat.0 whose highest
> > sequence/edit id is 17
> > [junit] 2008-11-06 20:27:35,267 DEBUG [main]
> > regionserver.HLog(365): closing log writer in
> > hdfs://localhost:42234/user/hudson/log_1226003250956
> > [junit] 2008-11-06 20:27:35,358 DEBUG [main]
> > regionserver.HRegion(2184): Opening region: REGION =>
> > {NAME => 'TestMergeTool,row_0100,1226003253332',
> > STARTKEY => 'row_0100', ENDKEY =>
> > 'row_0600', ENCODED => 1303204373, 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-11-06 20:27:35,358 DEBUG [main]
> > regionserver.HRegion(432): Opening region
> > TestMergeTool,row_0100,1226003253332/1303204373
> > [junit] 2008-11-06 20:27:35,367 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/TestMergeTool/1303204373/contents/info/4066640136817504354,
> > isReference=false, sequence id=2, length=486
> > [junit] 2008-11-06 20:27:35,368 DEBUG [main]
> > regionserver.HStore(229): Loaded 1 file(s) in hstore
> > 1303204373/contents, max sequence id 2
> > [junit] 2008-11-06 20:27:35,374 DEBUG [main]
> > regionserver.HRegion(493): Next sequence id for region
> > TestMergeTool,row_0100,1226003253332 is 3
> > [junit] 2008-11-06 20:27:35,375 INFO [main]
> > regionserver.HRegion(515): region
> > TestMergeTool,row_0100,1226003253332/1303204373 available
> > [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> > regionserver.HRegion(578): Compactions and cache flushes
> > disabled for region TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> > regionserver.HRegion(593): Scanners disabled for region
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> > regionserver.HRegion(608): No more active scanners for
> > region TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> > regionserver.HRegion(610): Updates disabled for region
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:35,377 DEBUG [main]
> > regionserver.HRegion(616): No more row locks outstanding on
> > region TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:35,377 DEBUG [main]
> > regionserver.HStore(595): closed 1303204373/contents
> > [junit] 2008-11-06 20:27:35,377 INFO [main]
> > regionserver.HRegion(629): closed
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:35,377 INFO [main]
> > util.TestMergeTool(171): Verified merging regions 0+1+2 and
> > 3
> > [junit] 2008-11-06 20:27:35,377 INFO [main]
> > util.TestMergeTool(158): merging regions 0+1+2+3 and 4
> > [junit] 2008-11-06 20:27:35,377 INFO [main]
> > util.Merge(80): Verifying that file system is available...
> > [junit] 2008-11-06 20:27:35,378 INFO [main]
> > util.Merge(89): Verifying that HBase is not running...
> > [junit] 2008-11-06 20:27:35,390 INFO [main]
> > util.Merge(190): Merging regions [B@1ca3cb and [B@4577f9 in
> > table [B@c78e02
> > [junit] 2008-11-06 20:27:35,456 INFO [main]
> > regionserver.HLog(272): New log writer created at
> > /user/hudson/log_1226003255391/hlog.dat.1226003255413
> > [junit] 2008-11-06 20:27:35,457 DEBUG [main]
> > regionserver.HRegion(2184): 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-11-06 20:27:35,459 DEBUG [main]
> > regionserver.HRegion(432): Opening region -ROOT-,,0/70236052
> > [junit] 2008-11-06 20:27:35,471 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/-ROOT-/70236052/info/info/932505270467494489,
> > isReference=false, sequence id=1, length=569
> > [junit] 2008-11-06 20:27:35,473 DEBUG [main]
> > regionserver.HStore(229): Loaded 1 file(s) in hstore
> > 70236052/info, max sequence id 1
> > [junit] 2008-11-06 20:27:35,480 DEBUG [main]
> > regionserver.HRegion(493): Next sequence id for region
> > -ROOT-,,0 is 2
> > [junit] 2008-11-06 20:27:35,481 INFO [main]
> > regionserver.HRegion(515): region -ROOT-,,0/70236052
> > available
> > [junit] 2008-11-06 20:27:35,481 DEBUG [main]
> > regionserver.HLog(205): changing sequence number from 0 to 2
> > [junit] 2008-11-06 20:27:35,482 INFO [main]
> > regionserver.HRegion(898): starting compaction on region
> > -ROOT-,,0
> > [junit] 2008-11-06 20:27:35,484 INFO [main]
> > regionserver.HRegion(912): compaction completed on region
> > -ROOT-,,0 in 0sec
> > [junit] 2008-11-06 20:27:35,490 INFO [main]
> > util.Merge(204): Found meta for region1 [B@10e468f, meta for
> > region2 [B@10e468f
> > [junit] 2008-11-06 20:27:35,490 DEBUG [main]
> > regionserver.HRegion(2184): 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-11-06 20:27:35,490 DEBUG [main]
> > regionserver.HRegion(432): Opening region
> > .META.,,1/1028785192
> > [junit] 2008-11-06 20:27:35,499 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/.META./1028785192/info/info/6238399713985357904,
> > isReference=false, sequence id=5, length=1928
> > [junit] 2008-11-06 20:27:35,505 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/.META./1028785192/info/info/6496600197375491971,
> > isReference=false, sequence id=17, length=658
> > [junit] 2008-11-06 20:27:35,537 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/.META./1028785192/info/info/8037543892230871542,
> > isReference=false, sequence id=13, length=1206
> > [junit] 2008-11-06 20:27:35,538 DEBUG [main]
> > regionserver.HStore(229): Loaded 3 file(s) in hstore
> > 1028785192/info, max sequence id 17
> > [junit] 2008-11-06 20:27:35,565 DEBUG [main]
> > regionserver.HRegion(493): Next sequence id for region
> > .META.,,1 is 18
> > [junit] 2008-11-06 20:27:35,568 INFO [main]
> > regionserver.HRegion(515): region .META.,,1/1028785192
> > available
> > [junit] 2008-11-06 20:27:35,569 DEBUG [main]
> > regionserver.HLog(205): changing sequence number from 2 to
> > 18
> > [junit] 2008-11-06 20:27:35,569 INFO [main]
> > regionserver.HRegion(898): starting compaction on region
> > .META.,,1
> > [junit] 2008-11-06 20:27:35,573 DEBUG [main]
> > regionserver.HStore(889): Compaction size of
> > 1028785192/info: 4.5k; Skipped 0 files , size: 0
> > [junit] 2008-11-06 20:27:35,613 DEBUG [main]
> > regionserver.HStore(907): started compaction of 3 files into
> >
> /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/695442272597599478
> > [junit] 2008-11-06 20:27:35,910 DEBUG [main]
> > regionserver.HStore(1165): moving
> >
> /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/695442272597599478
> > to
> > /user/hudson/.META./1028785192/info/mapfiles/4041881657389062481
> > [junit] 2008-11-06 20:27:36,213 DEBUG [main]
> > regionserver.HStore(925): Completed compaction of
> > 1028785192/info store size is 3.5k; time since last major
> > compaction: 0 seconds
> > [junit] 2008-11-06 20:27:36,257 INFO [main]
> > regionserver.HRegion(912): compaction completed on region
> > .META.,,1 in 0sec
> > [junit] 2008-11-06 20:27:36,259 DEBUG [main]
> > regionserver.HRegion(2184): Opening region: REGION =>
> > {NAME => 'TestMergeTool,row_0100,1226003253332',
> > STARTKEY => 'row_0100', ENDKEY =>
> > 'row_0600', ENCODED => 1303204373, 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-11-06 20:27:36,260 DEBUG [main]
> > regionserver.HRegion(432): Opening region
> > TestMergeTool,row_0100,1226003253332/1303204373
> > [junit] 2008-11-06 20:27:36,267 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/TestMergeTool/1303204373/contents/info/4066640136817504354,
> > isReference=false, sequence id=2, length=486
> > [junit] 2008-11-06 20:27:36,268 DEBUG [main]
> > regionserver.HStore(229): Loaded 1 file(s) in hstore
> > 1303204373/contents, max sequence id 2
> > [junit] 2008-11-06 20:27:36,274 DEBUG [main]
> > regionserver.HRegion(493): Next sequence id for region
> > TestMergeTool,row_0100,1226003253332 is 3
> > [junit] 2008-11-06 20:27:36,275 INFO [main]
> > regionserver.HRegion(515): region
> > TestMergeTool,row_0100,1226003253332/1303204373 available
> > [junit] 2008-11-06 20:27:36,275 DEBUG [main]
> > regionserver.HRegion(2184): Opening region: REGION =>
> > {NAME => 'TestMergeTool,,1226003224721', STARTKEY
> > => '', ENDKEY => '', ENCODED =>
> > 944282181, 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-11-06 20:27:36,276 DEBUG [main]
> > regionserver.HRegion(432): Opening region
> > TestMergeTool,,1226003224721/944282181
> > [junit] 2008-11-06 20:27:36,366 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/TestMergeTool/944282181/contents/info/6933377788073175383,
> > isReference=false, sequence id=2, length=204
> > [junit] 2008-11-06 20:27:36,367 DEBUG [main]
> > regionserver.HStore(229): Loaded 1 file(s) in hstore
> > 944282181/contents, max sequence id 2
> > [junit] 2008-11-06 20:27:36,379 DEBUG [main]
> > regionserver.HRegion(493): Next sequence id for region
> > TestMergeTool,,1226003224721 is 3
> > [junit] 2008-11-06 20:27:36,380 INFO [main]
> > regionserver.HRegion(515): region
> > TestMergeTool,,1226003224721/944282181 available
> > [junit] 2008-11-06 20:27:36,381 INFO [main]
> > regionserver.HRegion(898): starting compaction on region
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:36,394 DEBUG [main]
> > regionserver.HStore(907): started compaction of 1 files into
> >
> /user/hudson/TestMergeTool/compaction.dir/1303204373/contents/mapfiles/2868426775794350852
> > [junit] 2008-11-06 20:27:37,325 DEBUG [main]
> > regionserver.HStore(1165): moving
> >
> /user/hudson/TestMergeTool/compaction.dir/1303204373/contents/mapfiles/2868426775794350852
> > to
> >
> /user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570
> > [junit] 2008-11-06 20:27:37,476 DEBUG [main]
> > regionserver.HStore(925): Completed compaction of
> > 1303204373/contents store size is 486.0
> > [junit] 2008-11-06 20:27:37,506 INFO [main]
> > regionserver.HRegion(912): compaction completed on region
> > TestMergeTool,row_0100,1226003253332 in 1sec
> > [junit] 2008-11-06 20:27:37,506 DEBUG [main]
> > regionserver.HRegion(174): Files for region:
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:37,507 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents
> > [junit] 2008-11-06 20:27:37,508 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/info
> > [junit] 2008-11-06 20:27:37,509 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/info/5616567245829338570
> > size=9
> > [junit] 2008-11-06 20:27:37,509 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles
> > [junit] 2008-11-06 20:27:37,510 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570
> > [junit] 2008-11-06 20:27:37,511 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570/data
> > size=486
> > [junit] 2008-11-06 20:27:37,511 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570/index
> > size=227
> > [junit] 2008-11-06 20:27:37,512 INFO [main]
> > regionserver.HRegion(898): starting compaction on region
> > TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:37,521 DEBUG [main]
> > regionserver.HStore(907): started compaction of 1 files into
> >
> /user/hudson/TestMergeTool/compaction.dir/944282181/contents/mapfiles/5908293202449466261
> > [junit] 2008-11-06 20:27:37,796 DEBUG [main]
> > regionserver.HStore(1165): moving
> >
> /user/hudson/TestMergeTool/compaction.dir/944282181/contents/mapfiles/5908293202449466261
> > to
> >
> /user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171
> > [junit] 2008-11-06 20:27:37,955 DEBUG [main]
> > regionserver.HStore(925): Completed compaction of
> > 944282181/contents store size is 204.0
> > [junit] 2008-11-06 20:27:37,996 INFO [main]
> > regionserver.HRegion(912): compaction completed on region
> > TestMergeTool,,1226003224721 in 0sec
> > [junit] 2008-11-06 20:27:37,996 DEBUG [main]
> > regionserver.HRegion(179): Files for region:
> > TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:37,998 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents
> > [junit] 2008-11-06 20:27:37,999 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/info
> > [junit] 2008-11-06 20:27:38,000 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/info/1442289836149478171
> > size=9
> > [junit] 2008-11-06 20:27:38,000 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles
> > [junit] 2008-11-06 20:27:38,001 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171
> > [junit] 2008-11-06 20:27:38,002 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171/data
> > size=204
> > [junit] 2008-11-06 20:27:38,002 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171/index
> > size=227
> > [junit] 2008-11-06 20:27:38,003 INFO [main]
> > regionserver.HRegion(203): Creating new region REGION =>
> > {NAME => 'TestMergeTool,,1226003258003', STARTKEY
> > => '', ENDKEY => '', ENCODED =>
> > 1931386174, 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-11-06 20:27:38,034 INFO [main]
> > regionserver.HRegion(212): starting merge of regions:
> > TestMergeTool,row_0100,1226003253332 and
> > TestMergeTool,,1226003224721 into new region REGION =>
> > {NAME => 'TestMergeTool,,1226003258003', STARTKEY
> > => '', ENDKEY => '', ENCODED =>
> > 1931386174, 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...@1ea0252>
> > and end key <[B...@1ea0252>
> > [junit] 2008-11-06 20:27:38,034 DEBUG [main]
> > regionserver.HRegion(578): Compactions and cache flushes
> > disabled for region TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:38,034 DEBUG [main]
> > regionserver.HRegion(593): Scanners disabled for region
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:38,034 DEBUG [main]
> > regionserver.HRegion(608): No more active scanners for
> > region TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> > regionserver.HRegion(610): Updates disabled for region
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> > regionserver.HRegion(616): No more row locks outstanding on
> > region TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> > regionserver.HStore(595): closed 1303204373/contents
> > [junit] 2008-11-06 20:27:38,035 INFO [main]
> > regionserver.HRegion(629): closed
> > TestMergeTool,row_0100,1226003253332
> > [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> > regionserver.HRegion(578): Compactions and cache flushes
> > disabled for region TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> > regionserver.HRegion(593): Scanners disabled for region
> > TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> > regionserver.HRegion(608): No more active scanners for
> > region TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> > regionserver.HRegion(610): Updates disabled for region
> > TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> > regionserver.HRegion(616): No more row locks outstanding on
> > region TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> > regionserver.HStore(595): closed 944282181/contents
> > [junit] 2008-11-06 20:27:38,037 INFO [main]
> > regionserver.HRegion(629): closed
> > TestMergeTool,,1226003224721
> > [junit] 2008-11-06 20:27:38,097 DEBUG [main]
> > regionserver.HRegion(235): Adjusting sequence id of
> > storeFile 944282181/contents/1442289836149478171 down by
> > one; sequence id A=2, sequence id B=2
> > [junit] 2008-11-06 20:27:38,204 DEBUG [main]
> > regionserver.HRegion(246): Renaming
> > 1303204373/contents/5616567245829338570 to
> > 1931386174/contents/1777032553035286939
> > [junit] 2008-11-06 20:27:38,264 DEBUG [main]
> > regionserver.HRegion(246): Renaming
> > 944282181/contents/1442289836149478171 to
> > 1931386174/contents/4071795926526351466
> > [junit] 2008-11-06 20:27:38,331 DEBUG [main]
> > regionserver.HRegion(252): Files for new region
> > [junit] 2008-11-06 20:27:38,332 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents
> > [junit] 2008-11-06 20:27:38,333 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info
> > [junit] 2008-11-06 20:27:38,333 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/1777032553035286939
> > size=9
> > [junit] 2008-11-06 20:27:38,334 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/4071795926526351466
> > size=9
> > [junit] 2008-11-06 20:27:38,334 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles
> > [junit] 2008-11-06 20:27:38,334 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939
> > [junit] 2008-11-06 20:27:38,335 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939/data
> > size=486
> > [junit] 2008-11-06 20:27:38,335 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939/index
> > size=227
> > [junit] 2008-11-06 20:27:38,335 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466
> > [junit] 2008-11-06 20:27:38,336 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466/data
> > size=204
> > [junit] 2008-11-06 20:27:38,336 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466/index
> > size=227
> > [junit] 2008-11-06 20:27:38,337 DEBUG [main]
> > regionserver.HRegion(432): Opening region
> > TestMergeTool,,1226003258003/1931386174
> > [junit] 2008-11-06 20:27:38,344 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/TestMergeTool/1931386174/contents/info/1777032553035286939,
> > isReference=false, sequence id=2, length=486
> > [junit] 2008-11-06 20:27:38,349 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/TestMergeTool/1931386174/contents/info/4071795926526351466,
> > isReference=false, sequence id=1, length=204
> > [junit] 2008-11-06 20:27:38,350 DEBUG [main]
> > regionserver.HStore(229): Loaded 2 file(s) in hstore
> > 1931386174/contents, max sequence id 2
> > [junit] 2008-11-06 20:27:38,361 DEBUG [main]
> > regionserver.HRegion(493): Next sequence id for region
> > TestMergeTool,,1226003258003 is 3
> > [junit] 2008-11-06 20:27:38,362 INFO [main]
> > regionserver.HRegion(515): region
> > TestMergeTool,,1226003258003/1931386174 available
> > [junit] 2008-11-06 20:27:38,363 INFO [main]
> > regionserver.HRegion(898): starting compaction on region
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:38,366 DEBUG [main]
> > regionserver.HStore(889): Compaction size of
> > 1931386174/contents: 1.1k; Skipped 0 files , size: 0
> > [junit] 2008-11-06 20:27:38,377 DEBUG [main]
> > regionserver.HStore(907): started compaction of 2 files into
> >
> /user/hudson/TestMergeTool/compaction.dir/1931386174/contents/mapfiles/3853862809414353089
> > [junit] 2008-11-06 20:27:38,704 DEBUG [main]
> > regionserver.HStore(1165): moving
> >
> /user/hudson/TestMergeTool/compaction.dir/1931386174/contents/mapfiles/3853862809414353089
> > to
> >
> /user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479
> > [junit] 2008-11-06 20:27:38,916 DEBUG [main]
> > regionserver.HStore(925): Completed compaction of
> > 1931386174/contents store size is 580.0; time since last
> > major compaction: 0 seconds
> > [junit] 2008-11-06 20:27:38,951 INFO [main]
> > regionserver.HRegion(912): compaction completed on region
> > TestMergeTool,,1226003258003 in 0sec
> > [junit] 2008-11-06 20:27:38,951 DEBUG [main]
> > regionserver.HRegion(259): Files for new region
> > [junit] 2008-11-06 20:27:38,952 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents
> > [junit] 2008-11-06 20:27:38,953 DEBUG [main]
> > regionserver.HRegion(312): d
> > hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info
> > [junit] 2008-11-06 20:27:38,954 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/3550934524445237479
> > size=9
> > [junit] 2008-11-06 20:27:38,954 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles
> > [junit] 2008-11-06 20:27:38,955 DEBUG [main]
> > regionserver.HRegion(312): d
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479
> > [junit] 2008-11-06 20:27:38,955 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479/data
> > size=580
> > [junit] 2008-11-06 20:27:38,956 DEBUG [main]
> > regionserver.HRegion(315): f
> >
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479/index
> > size=227
> > [junit] 2008-11-06 20:27:38,956 DEBUG [main]
> > regionserver.HRegion(2281): DELETING region
> > hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373
> > [junit] 2008-11-06 20:27:38,994 DEBUG [main]
> > regionserver.HRegion(2281): DELETING region
> > hdfs://localhost:42234/user/hudson/TestMergeTool/944282181
> > [junit] 2008-11-06 20:27:39,029 INFO [main]
> > regionserver.HRegion(265): merge completed. New region is
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,029 DEBUG [main]
> > util.Merge(308): Removing region: REGION => {NAME =>
> > 'TestMergeTool,row_0100,1226003253332', STARTKEY
> > => 'row_0100', ENDKEY => 'row_0600',
> > ENCODED => 1303204373, 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-11-06 20:27:39,031 DEBUG [main]
> > util.Merge(308): Removing region: REGION => {NAME =>
> > 'TestMergeTool,,1226003224721', STARTKEY =>
> > '', ENDKEY => '', ENCODED =>
> > 944282181, 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-11-06 20:27:39,038 INFO [main]
> > util.Merge(244): Adding REGION => {NAME =>
> > 'TestMergeTool,,1226003258003', STARTKEY =>
> > '', ENDKEY => '', ENCODED =>
> > 1931386174, 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-11-06 20:27:39,039 DEBUG [main]
> > regionserver.HRegion(578): Compactions and cache flushes
> > disabled for region TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> > regionserver.HRegion(593): Scanners disabled for region
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> > regionserver.HRegion(608): No more active scanners for
> > region TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> > regionserver.HRegion(610): Updates disabled for region
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> > regionserver.HRegion(616): No more row locks outstanding on
> > region TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> > regionserver.HStore(595): closed 1931386174/contents
> > [junit] 2008-11-06 20:27:39,040 INFO [main]
> > regionserver.HRegion(629): closed
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> > regionserver.HRegion(578): Compactions and cache flushes
> > disabled for region -ROOT-,,0
> > [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> > regionserver.HRegion(593): Scanners disabled for region
> > -ROOT-,,0
> > [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> > regionserver.HRegion(608): No more active scanners for
> > region -ROOT-,,0
> > [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> > regionserver.HRegion(610): Updates disabled for region
> > -ROOT-,,0
> > [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> > regionserver.HRegion(616): No more row locks outstanding on
> > region -ROOT-,,0
> > [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> > regionserver.HStore(595): closed 70236052/info
> > [junit] 2008-11-06 20:27:39,041 INFO [main]
> > regionserver.HRegion(629): closed -ROOT-,,0
> > [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> > regionserver.HRegion(578): Compactions and cache flushes
> > disabled for region .META.,,1
> > [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> > regionserver.HRegion(593): Scanners disabled for region
> > .META.,,1
> > [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> > regionserver.HRegion(608): No more active scanners for
> > region .META.,,1
> > [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> > regionserver.HRegion(610): Updates disabled for region
> > .META.,,1
> > [junit] 2008-11-06 20:27:39,042 DEBUG [main]
> > regionserver.HRegion(616): No more row locks outstanding on
> > region .META.,,1
> > [junit] 2008-11-06 20:27:39,042 DEBUG [main]
> > regionserver.HRegion(1023): Started memcache flush for
> > region .META.,,1. Current region memcache size 466.0
> > [junit] 2008-11-06 20:27:39,340 DEBUG [main]
> > regionserver.HStore(690): Added
> > /user/hudson/.META./1028785192/info/mapfiles/2677182741781292076
> > with 3 entries, sequence id 21, data size 466.0, file size
> > 618.0
> > [junit] 2008-11-06 20:27:39,341 DEBUG [main]
> > regionserver.HRegion(1097): Finished memcache flush for
> > region .META.,,1 in 299ms, sequence id=21, compaction
> > requested=true
> > [junit] 2008-11-06 20:27:39,341 DEBUG [main]
> > regionserver.HStore(595): closed 1028785192/historian
> > [junit] 2008-11-06 20:27:39,342 DEBUG [main]
> > regionserver.HStore(595): closed 1028785192/info
> > [junit] 2008-11-06 20:27:39,342 INFO [main]
> > regionserver.HRegion(629): closed .META.,,1
> > [junit] 2008-11-06 20:27:39,385 DEBUG [main]
> > regionserver.HLog(259): Closing current log writer
> > /user/hudson/log_1226003255391/hlog.dat.0
> > [junit] 2008-11-06 20:27:39,428 INFO [main]
> > regionserver.HLog(272): New log writer created at
> > /user/hudson/log_1226003255391/hlog.dat.1226003259386
> > [junit] 2008-11-06 20:27:39,429 DEBUG [main]
> > regionserver.HLog(277): Last sequence written is empty.
> > Deleting all old hlogs
> > [junit] 2008-11-06 20:27:39,429 INFO [main]
> > regionserver.HLog(329): removing old log file
> > /user/hudson/log_1226003255391/hlog.dat.0 whose highest
> > sequence/edit id is 21
> > [junit] 2008-11-06 20:27:39,429 DEBUG [main]
> > regionserver.HLog(365): closing log writer in
> > hdfs://localhost:42234/user/hudson/log_1226003255391
> > [junit] 2008-11-06 20:27:39,515 DEBUG [main]
> > regionserver.HRegion(2184): Opening region: REGION =>
> > {NAME => 'TestMergeTool,,1226003258003', STARTKEY
> > => '', ENDKEY => '', ENCODED =>
> > 1931386174, 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-11-06 20:27:39,515 DEBUG [main]
> > regionserver.HRegion(432): Opening region
> > TestMergeTool,,1226003258003/1931386174
> > [junit] 2008-11-06 20:27:39,523 DEBUG [main]
> > regionserver.HStore(490): loaded
> > /user/hudson/TestMergeTool/1931386174/contents/info/3550934524445237479,
> > isReference=false, sequence id=2, length=580
> > [junit] 2008-11-06 20:27:39,523 DEBUG [main]
> > regionserver.HStore(229): Loaded 1 file(s) in hstore
> > 1931386174/contents, max sequence id 2
> > [junit] 2008-11-06 20:27:39,530 DEBUG [main]
> > regionserver.HRegion(493): Next sequence id for region
> > TestMergeTool,,1226003258003 is 3
> > [junit] 2008-11-06 20:27:39,532 INFO [main]
> > regionserver.HRegion(515): region
> > TestMergeTool,,1226003258003/1931386174 available
> > [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> > regionserver.HRegion(578): Compactions and cache flushes
> > disabled for region TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> > regionserver.HRegion(593): Scanners disabled for region
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> > regionserver.HRegion(608): No more active scanners for
> > region TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> > regionserver.HRegion(610): Updates disabled for region
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,534 DEBUG [main]
> > regionserver.HRegion(616): No more row locks outstanding on
> > region TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,534 DEBUG [main]
> > regionserver.HStore(595): closed 1931386174/contents
> > [junit] 2008-11-06 20:27:39,534 INFO [main]
> > regionserver.HRegion(629): closed
> > TestMergeTool,,1226003258003
> > [junit] 2008-11-06 20:27:39,534 INFO [main]
> > util.TestMergeTool(171): Verified merging regions 0+1+2+3
> > and 4
> > [junit] 2008-11-06 20:27:39,534 DEBUG [main]
> > regionserver.HLog(365): closing log writer in
> > /tmp/log_1226003234747
> > [junit] 2008-11-06 20:27:39,617 INFO [main]
> > hbase.HBaseTestCase(600): Shutting down FileSystem
> > [junit] 2008-11-06 20:27:39,617 INFO [main]
> > hbase.HBaseTestCase(607): Shutting down Mini DFS
> > [junit] Shutting down the Mini HDFS Cluster
> > [junit] Shutting down DataNode 1
> > [junit] 2008-11-06 20:27:39,624 INFO [main]
> > http.SocketListener(212): Stopped SocketListener on
> > 127.0.0.1:42252
> > [junit] 2008-11-06 20:27:39,753 INFO [main]
> > util.Container(156): Stopped HttpContext[/static,/static]
> > [junit] 2008-11-06 20:27:39,753 INFO [main]
> > util.Container(156): Stopped
> > org.mortbay.jetty.servlet.WebApplicationHandler@135f44e
> > [junit] 2008-11-06 20:27:39,827 INFO [main]
> > util.Container(156): Stopped WebApplicationContext[/,/]
> > [junit] 2008-11-06 20:27:39,828 INFO [main]
> > util.Container(156): Stopped
> > org.mortbay.jetty.Server@17c2891
> > [junit] Shutting down DataNode 0
> > [junit] 2008-11-06 20:27:40,837 INFO [main]
> > http.SocketListener(212): Stopped SocketListener on
> > 127.0.0.1:42244
> > [junit] 2008-11-06 20:27:40,837 INFO [Acceptor
> > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42244]]
> > util.ThreadedServer$Acceptor(656): Stopping Acceptor
> > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42244]
> > [junit] 2008-11-06 20:27:40,947 INFO [main]
> > util.Container(156): Stopped HttpContext[/static,/static]
> > [junit] 2008-11-06 20:27:40,948 INFO [main]
> > util.Container(156): Stopped
> > org.mortbay.jetty.servlet.WebApplicationHandler@4d2af2
> > [junit] 2008-11-06 20:27:41,049 INFO [main]
> > util.Container(156): Stopped WebApplicationContext[/,/]
> > [junit] 2008-11-06 20:27:41,049 INFO [main]
> > util.Container(156): Stopped
> > org.mortbay.jetty.Server@1579a30
> > [junit] 2008-11-06 20:27:42,066 INFO [main]
> > http.SocketListener(212): Stopped SocketListener on
> > 127.0.0.1:42240
> > [junit] 2008-11-06 20:27:42,137 INFO [main]
> > util.Container(156): Stopped HttpContext[/static,/static]
> > [junit] 2008-11-06 20:27:42,137 INFO [main]
> > util.Container(156): Stopped
> > org.mortbay.jetty.servlet.WebApplicationHandler@1c54796
> > [junit] 2008-11-06 20:27:42,248 INFO [main]
> > util.Container(156): Stopped WebApplicationContext[/,/]
> > [junit] 2008-11-06 20:27:42,248 INFO [main]
> > util.Container(156): Stopped
> > org.mortbay.jetty.Server@19113f8
> > [junit] 2008-11-06 20:27:42,249 WARN
> >
> [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@401369
> ]
> > namenode.FSNamesystem$ReplicationMonitor(2280):
> > ReplicationMonitor thread received
> > InterruptedException.java.lang.InterruptedException: sleep
> > interrupted
> > [junit] Tests run: 1, Failures: 0, Errors: 0, Time
> > elapsed: 37.746 sec
> > [junit] Running
> > org.apache.hadoop.hbase.util.TestRootPath
> > [junit] 2008-11-06 20:27:42,947 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.081 sec
> > [junit] Running org.onelab.test.TestFilter
> > [junit] 2008-11-06 20:27:43,570 INFO [main]
> > test.TestFilter(244): Checking for false negatives
> > [junit] 2008-11-06 20:27:43,575 INFO [main]
> > test.TestFilter(255): Checking for false positives
> > [junit] 2008-11-06 20:27:43,576 INFO [main]
> > test.TestFilter(262): Success!
> > [junit] 2008-11-06 20:27:43,576 INFO [main]
> > test.TestFilter(226): Checking serialization/deserialization
> > [junit] 2008-11-06 20:27:43,578 INFO [main]
> > test.TestFilter(244): Checking for false negatives
> > [junit] 2008-11-06 20:27:43,580 INFO [main]
> > test.TestFilter(255): Checking for false positives
> > [junit] 2008-11-06 20:27:43,581 INFO [main]
> > test.TestFilter(262): Success!
> > [junit] Tests run: 3, Failures: 0, Errors: 0, Time
> > elapsed: 0.068 sec
> >
> > BUILD FAILED
> >
> /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:453:
> > Tests failed!
> >
> > Total time: 45 minutes 35 seconds
> > [locks-and-latches] Releasing all the locks
> > [locks-and-latches] All the locks released
> > Recording test results
>
>
>
>
Re: Build failed in Hudson: HBase-Patch #391
Posted by Andrew Purtell <ap...@yahoo.com>.
This doesn't make any sense to me. I only added ~10 lines to HStore.
What's this about "root path does not have a scheme"?
- Andy
> From: Apache Hudson Server <hu...@hudson.zones.apache.org>
> Subject: Build failed in Hudson: HBase-Patch #391
> To: hbase-dev@hadoop.apache.org
> Date: Thursday, November 6, 2008, 12:27 PM
> See
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/391/changes
>
> Changes:
>
> [apurtell] HBASE-981 hbase.io.index.interval doesn't
> seem to have an effect
>
> ------------------------------------------
> [...truncated 21304 lines...]
> [junit] 2008-11-06 20:27:35,265 INFO [main]
> regionserver.HLog(272): New log writer created at
> /user/hudson/log_1226003250956/hlog.dat.1226003255228
> [junit] 2008-11-06 20:27:35,266 DEBUG [main]
> regionserver.HLog(277): Last sequence written is empty.
> Deleting all old hlogs
> [junit] 2008-11-06 20:27:35,266 INFO [main]
> regionserver.HLog(329): removing old log file
> /user/hudson/log_1226003250956/hlog.dat.0 whose highest
> sequence/edit id is 17
> [junit] 2008-11-06 20:27:35,267 DEBUG [main]
> regionserver.HLog(365): closing log writer in
> hdfs://localhost:42234/user/hudson/log_1226003250956
> [junit] 2008-11-06 20:27:35,358 DEBUG [main]
> regionserver.HRegion(2184): Opening region: REGION =>
> {NAME => 'TestMergeTool,row_0100,1226003253332',
> STARTKEY => 'row_0100', ENDKEY =>
> 'row_0600', ENCODED => 1303204373, 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-11-06 20:27:35,358 DEBUG [main]
> regionserver.HRegion(432): Opening region
> TestMergeTool,row_0100,1226003253332/1303204373
> [junit] 2008-11-06 20:27:35,367 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/TestMergeTool/1303204373/contents/info/4066640136817504354,
> isReference=false, sequence id=2, length=486
> [junit] 2008-11-06 20:27:35,368 DEBUG [main]
> regionserver.HStore(229): Loaded 1 file(s) in hstore
> 1303204373/contents, max sequence id 2
> [junit] 2008-11-06 20:27:35,374 DEBUG [main]
> regionserver.HRegion(493): Next sequence id for region
> TestMergeTool,row_0100,1226003253332 is 3
> [junit] 2008-11-06 20:27:35,375 INFO [main]
> regionserver.HRegion(515): region
> TestMergeTool,row_0100,1226003253332/1303204373 available
> [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> regionserver.HRegion(578): Compactions and cache flushes
> disabled for region TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> regionserver.HRegion(593): Scanners disabled for region
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> regionserver.HRegion(608): No more active scanners for
> region TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:35,376 DEBUG [main]
> regionserver.HRegion(610): Updates disabled for region
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:35,377 DEBUG [main]
> regionserver.HRegion(616): No more row locks outstanding on
> region TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:35,377 DEBUG [main]
> regionserver.HStore(595): closed 1303204373/contents
> [junit] 2008-11-06 20:27:35,377 INFO [main]
> regionserver.HRegion(629): closed
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:35,377 INFO [main]
> util.TestMergeTool(171): Verified merging regions 0+1+2 and
> 3
> [junit] 2008-11-06 20:27:35,377 INFO [main]
> util.TestMergeTool(158): merging regions 0+1+2+3 and 4
> [junit] 2008-11-06 20:27:35,377 INFO [main]
> util.Merge(80): Verifying that file system is available...
> [junit] 2008-11-06 20:27:35,378 INFO [main]
> util.Merge(89): Verifying that HBase is not running...
> [junit] 2008-11-06 20:27:35,390 INFO [main]
> util.Merge(190): Merging regions [B@1ca3cb and [B@4577f9 in
> table [B@c78e02
> [junit] 2008-11-06 20:27:35,456 INFO [main]
> regionserver.HLog(272): New log writer created at
> /user/hudson/log_1226003255391/hlog.dat.1226003255413
> [junit] 2008-11-06 20:27:35,457 DEBUG [main]
> regionserver.HRegion(2184): 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-11-06 20:27:35,459 DEBUG [main]
> regionserver.HRegion(432): Opening region -ROOT-,,0/70236052
> [junit] 2008-11-06 20:27:35,471 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/-ROOT-/70236052/info/info/932505270467494489,
> isReference=false, sequence id=1, length=569
> [junit] 2008-11-06 20:27:35,473 DEBUG [main]
> regionserver.HStore(229): Loaded 1 file(s) in hstore
> 70236052/info, max sequence id 1
> [junit] 2008-11-06 20:27:35,480 DEBUG [main]
> regionserver.HRegion(493): Next sequence id for region
> -ROOT-,,0 is 2
> [junit] 2008-11-06 20:27:35,481 INFO [main]
> regionserver.HRegion(515): region -ROOT-,,0/70236052
> available
> [junit] 2008-11-06 20:27:35,481 DEBUG [main]
> regionserver.HLog(205): changing sequence number from 0 to 2
> [junit] 2008-11-06 20:27:35,482 INFO [main]
> regionserver.HRegion(898): starting compaction on region
> -ROOT-,,0
> [junit] 2008-11-06 20:27:35,484 INFO [main]
> regionserver.HRegion(912): compaction completed on region
> -ROOT-,,0 in 0sec
> [junit] 2008-11-06 20:27:35,490 INFO [main]
> util.Merge(204): Found meta for region1 [B@10e468f, meta for
> region2 [B@10e468f
> [junit] 2008-11-06 20:27:35,490 DEBUG [main]
> regionserver.HRegion(2184): 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-11-06 20:27:35,490 DEBUG [main]
> regionserver.HRegion(432): Opening region
> .META.,,1/1028785192
> [junit] 2008-11-06 20:27:35,499 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/.META./1028785192/info/info/6238399713985357904,
> isReference=false, sequence id=5, length=1928
> [junit] 2008-11-06 20:27:35,505 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/.META./1028785192/info/info/6496600197375491971,
> isReference=false, sequence id=17, length=658
> [junit] 2008-11-06 20:27:35,537 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/.META./1028785192/info/info/8037543892230871542,
> isReference=false, sequence id=13, length=1206
> [junit] 2008-11-06 20:27:35,538 DEBUG [main]
> regionserver.HStore(229): Loaded 3 file(s) in hstore
> 1028785192/info, max sequence id 17
> [junit] 2008-11-06 20:27:35,565 DEBUG [main]
> regionserver.HRegion(493): Next sequence id for region
> .META.,,1 is 18
> [junit] 2008-11-06 20:27:35,568 INFO [main]
> regionserver.HRegion(515): region .META.,,1/1028785192
> available
> [junit] 2008-11-06 20:27:35,569 DEBUG [main]
> regionserver.HLog(205): changing sequence number from 2 to
> 18
> [junit] 2008-11-06 20:27:35,569 INFO [main]
> regionserver.HRegion(898): starting compaction on region
> .META.,,1
> [junit] 2008-11-06 20:27:35,573 DEBUG [main]
> regionserver.HStore(889): Compaction size of
> 1028785192/info: 4.5k; Skipped 0 files , size: 0
> [junit] 2008-11-06 20:27:35,613 DEBUG [main]
> regionserver.HStore(907): started compaction of 3 files into
> /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/695442272597599478
> [junit] 2008-11-06 20:27:35,910 DEBUG [main]
> regionserver.HStore(1165): moving
> /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/695442272597599478
> to
> /user/hudson/.META./1028785192/info/mapfiles/4041881657389062481
> [junit] 2008-11-06 20:27:36,213 DEBUG [main]
> regionserver.HStore(925): Completed compaction of
> 1028785192/info store size is 3.5k; time since last major
> compaction: 0 seconds
> [junit] 2008-11-06 20:27:36,257 INFO [main]
> regionserver.HRegion(912): compaction completed on region
> .META.,,1 in 0sec
> [junit] 2008-11-06 20:27:36,259 DEBUG [main]
> regionserver.HRegion(2184): Opening region: REGION =>
> {NAME => 'TestMergeTool,row_0100,1226003253332',
> STARTKEY => 'row_0100', ENDKEY =>
> 'row_0600', ENCODED => 1303204373, 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-11-06 20:27:36,260 DEBUG [main]
> regionserver.HRegion(432): Opening region
> TestMergeTool,row_0100,1226003253332/1303204373
> [junit] 2008-11-06 20:27:36,267 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/TestMergeTool/1303204373/contents/info/4066640136817504354,
> isReference=false, sequence id=2, length=486
> [junit] 2008-11-06 20:27:36,268 DEBUG [main]
> regionserver.HStore(229): Loaded 1 file(s) in hstore
> 1303204373/contents, max sequence id 2
> [junit] 2008-11-06 20:27:36,274 DEBUG [main]
> regionserver.HRegion(493): Next sequence id for region
> TestMergeTool,row_0100,1226003253332 is 3
> [junit] 2008-11-06 20:27:36,275 INFO [main]
> regionserver.HRegion(515): region
> TestMergeTool,row_0100,1226003253332/1303204373 available
> [junit] 2008-11-06 20:27:36,275 DEBUG [main]
> regionserver.HRegion(2184): Opening region: REGION =>
> {NAME => 'TestMergeTool,,1226003224721', STARTKEY
> => '', ENDKEY => '', ENCODED =>
> 944282181, 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-11-06 20:27:36,276 DEBUG [main]
> regionserver.HRegion(432): Opening region
> TestMergeTool,,1226003224721/944282181
> [junit] 2008-11-06 20:27:36,366 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/TestMergeTool/944282181/contents/info/6933377788073175383,
> isReference=false, sequence id=2, length=204
> [junit] 2008-11-06 20:27:36,367 DEBUG [main]
> regionserver.HStore(229): Loaded 1 file(s) in hstore
> 944282181/contents, max sequence id 2
> [junit] 2008-11-06 20:27:36,379 DEBUG [main]
> regionserver.HRegion(493): Next sequence id for region
> TestMergeTool,,1226003224721 is 3
> [junit] 2008-11-06 20:27:36,380 INFO [main]
> regionserver.HRegion(515): region
> TestMergeTool,,1226003224721/944282181 available
> [junit] 2008-11-06 20:27:36,381 INFO [main]
> regionserver.HRegion(898): starting compaction on region
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:36,394 DEBUG [main]
> regionserver.HStore(907): started compaction of 1 files into
> /user/hudson/TestMergeTool/compaction.dir/1303204373/contents/mapfiles/2868426775794350852
> [junit] 2008-11-06 20:27:37,325 DEBUG [main]
> regionserver.HStore(1165): moving
> /user/hudson/TestMergeTool/compaction.dir/1303204373/contents/mapfiles/2868426775794350852
> to
> /user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570
> [junit] 2008-11-06 20:27:37,476 DEBUG [main]
> regionserver.HStore(925): Completed compaction of
> 1303204373/contents store size is 486.0
> [junit] 2008-11-06 20:27:37,506 INFO [main]
> regionserver.HRegion(912): compaction completed on region
> TestMergeTool,row_0100,1226003253332 in 1sec
> [junit] 2008-11-06 20:27:37,506 DEBUG [main]
> regionserver.HRegion(174): Files for region:
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:37,507 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents
> [junit] 2008-11-06 20:27:37,508 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/info
> [junit] 2008-11-06 20:27:37,509 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/info/5616567245829338570
> size=9
> [junit] 2008-11-06 20:27:37,509 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles
> [junit] 2008-11-06 20:27:37,510 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570
> [junit] 2008-11-06 20:27:37,511 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570/data
> size=486
> [junit] 2008-11-06 20:27:37,511 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373/contents/mapfiles/5616567245829338570/index
> size=227
> [junit] 2008-11-06 20:27:37,512 INFO [main]
> regionserver.HRegion(898): starting compaction on region
> TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:37,521 DEBUG [main]
> regionserver.HStore(907): started compaction of 1 files into
> /user/hudson/TestMergeTool/compaction.dir/944282181/contents/mapfiles/5908293202449466261
> [junit] 2008-11-06 20:27:37,796 DEBUG [main]
> regionserver.HStore(1165): moving
> /user/hudson/TestMergeTool/compaction.dir/944282181/contents/mapfiles/5908293202449466261
> to
> /user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171
> [junit] 2008-11-06 20:27:37,955 DEBUG [main]
> regionserver.HStore(925): Completed compaction of
> 944282181/contents store size is 204.0
> [junit] 2008-11-06 20:27:37,996 INFO [main]
> regionserver.HRegion(912): compaction completed on region
> TestMergeTool,,1226003224721 in 0sec
> [junit] 2008-11-06 20:27:37,996 DEBUG [main]
> regionserver.HRegion(179): Files for region:
> TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:37,998 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents
> [junit] 2008-11-06 20:27:37,999 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/info
> [junit] 2008-11-06 20:27:38,000 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/info/1442289836149478171
> size=9
> [junit] 2008-11-06 20:27:38,000 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles
> [junit] 2008-11-06 20:27:38,001 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171
> [junit] 2008-11-06 20:27:38,002 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171/data
> size=204
> [junit] 2008-11-06 20:27:38,002 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181/contents/mapfiles/1442289836149478171/index
> size=227
> [junit] 2008-11-06 20:27:38,003 INFO [main]
> regionserver.HRegion(203): Creating new region REGION =>
> {NAME => 'TestMergeTool,,1226003258003', STARTKEY
> => '', ENDKEY => '', ENCODED =>
> 1931386174, 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-11-06 20:27:38,034 INFO [main]
> regionserver.HRegion(212): starting merge of regions:
> TestMergeTool,row_0100,1226003253332 and
> TestMergeTool,,1226003224721 into new region REGION =>
> {NAME => 'TestMergeTool,,1226003258003', STARTKEY
> => '', ENDKEY => '', ENCODED =>
> 1931386174, 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...@1ea0252>
> and end key <[B...@1ea0252>
> [junit] 2008-11-06 20:27:38,034 DEBUG [main]
> regionserver.HRegion(578): Compactions and cache flushes
> disabled for region TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:38,034 DEBUG [main]
> regionserver.HRegion(593): Scanners disabled for region
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:38,034 DEBUG [main]
> regionserver.HRegion(608): No more active scanners for
> region TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> regionserver.HRegion(610): Updates disabled for region
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> regionserver.HRegion(616): No more row locks outstanding on
> region TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> regionserver.HStore(595): closed 1303204373/contents
> [junit] 2008-11-06 20:27:38,035 INFO [main]
> regionserver.HRegion(629): closed
> TestMergeTool,row_0100,1226003253332
> [junit] 2008-11-06 20:27:38,035 DEBUG [main]
> regionserver.HRegion(578): Compactions and cache flushes
> disabled for region TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> regionserver.HRegion(593): Scanners disabled for region
> TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> regionserver.HRegion(608): No more active scanners for
> region TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> regionserver.HRegion(610): Updates disabled for region
> TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> regionserver.HRegion(616): No more row locks outstanding on
> region TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:38,036 DEBUG [main]
> regionserver.HStore(595): closed 944282181/contents
> [junit] 2008-11-06 20:27:38,037 INFO [main]
> regionserver.HRegion(629): closed
> TestMergeTool,,1226003224721
> [junit] 2008-11-06 20:27:38,097 DEBUG [main]
> regionserver.HRegion(235): Adjusting sequence id of
> storeFile 944282181/contents/1442289836149478171 down by
> one; sequence id A=2, sequence id B=2
> [junit] 2008-11-06 20:27:38,204 DEBUG [main]
> regionserver.HRegion(246): Renaming
> 1303204373/contents/5616567245829338570 to
> 1931386174/contents/1777032553035286939
> [junit] 2008-11-06 20:27:38,264 DEBUG [main]
> regionserver.HRegion(246): Renaming
> 944282181/contents/1442289836149478171 to
> 1931386174/contents/4071795926526351466
> [junit] 2008-11-06 20:27:38,331 DEBUG [main]
> regionserver.HRegion(252): Files for new region
> [junit] 2008-11-06 20:27:38,332 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents
> [junit] 2008-11-06 20:27:38,333 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info
> [junit] 2008-11-06 20:27:38,333 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/1777032553035286939
> size=9
> [junit] 2008-11-06 20:27:38,334 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/4071795926526351466
> size=9
> [junit] 2008-11-06 20:27:38,334 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles
> [junit] 2008-11-06 20:27:38,334 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939
> [junit] 2008-11-06 20:27:38,335 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939/data
> size=486
> [junit] 2008-11-06 20:27:38,335 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/1777032553035286939/index
> size=227
> [junit] 2008-11-06 20:27:38,335 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466
> [junit] 2008-11-06 20:27:38,336 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466/data
> size=204
> [junit] 2008-11-06 20:27:38,336 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/4071795926526351466/index
> size=227
> [junit] 2008-11-06 20:27:38,337 DEBUG [main]
> regionserver.HRegion(432): Opening region
> TestMergeTool,,1226003258003/1931386174
> [junit] 2008-11-06 20:27:38,344 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/TestMergeTool/1931386174/contents/info/1777032553035286939,
> isReference=false, sequence id=2, length=486
> [junit] 2008-11-06 20:27:38,349 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/TestMergeTool/1931386174/contents/info/4071795926526351466,
> isReference=false, sequence id=1, length=204
> [junit] 2008-11-06 20:27:38,350 DEBUG [main]
> regionserver.HStore(229): Loaded 2 file(s) in hstore
> 1931386174/contents, max sequence id 2
> [junit] 2008-11-06 20:27:38,361 DEBUG [main]
> regionserver.HRegion(493): Next sequence id for region
> TestMergeTool,,1226003258003 is 3
> [junit] 2008-11-06 20:27:38,362 INFO [main]
> regionserver.HRegion(515): region
> TestMergeTool,,1226003258003/1931386174 available
> [junit] 2008-11-06 20:27:38,363 INFO [main]
> regionserver.HRegion(898): starting compaction on region
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:38,366 DEBUG [main]
> regionserver.HStore(889): Compaction size of
> 1931386174/contents: 1.1k; Skipped 0 files , size: 0
> [junit] 2008-11-06 20:27:38,377 DEBUG [main]
> regionserver.HStore(907): started compaction of 2 files into
> /user/hudson/TestMergeTool/compaction.dir/1931386174/contents/mapfiles/3853862809414353089
> [junit] 2008-11-06 20:27:38,704 DEBUG [main]
> regionserver.HStore(1165): moving
> /user/hudson/TestMergeTool/compaction.dir/1931386174/contents/mapfiles/3853862809414353089
> to
> /user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479
> [junit] 2008-11-06 20:27:38,916 DEBUG [main]
> regionserver.HStore(925): Completed compaction of
> 1931386174/contents store size is 580.0; time since last
> major compaction: 0 seconds
> [junit] 2008-11-06 20:27:38,951 INFO [main]
> regionserver.HRegion(912): compaction completed on region
> TestMergeTool,,1226003258003 in 0sec
> [junit] 2008-11-06 20:27:38,951 DEBUG [main]
> regionserver.HRegion(259): Files for new region
> [junit] 2008-11-06 20:27:38,952 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents
> [junit] 2008-11-06 20:27:38,953 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info
> [junit] 2008-11-06 20:27:38,954 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/info/3550934524445237479
> size=9
> [junit] 2008-11-06 20:27:38,954 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles
> [junit] 2008-11-06 20:27:38,955 DEBUG [main]
> regionserver.HRegion(312): d
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479
> [junit] 2008-11-06 20:27:38,955 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479/data
> size=580
> [junit] 2008-11-06 20:27:38,956 DEBUG [main]
> regionserver.HRegion(315): f
> hdfs://localhost:42234/user/hudson/TestMergeTool/1931386174/contents/mapfiles/3550934524445237479/index
> size=227
> [junit] 2008-11-06 20:27:38,956 DEBUG [main]
> regionserver.HRegion(2281): DELETING region
> hdfs://localhost:42234/user/hudson/TestMergeTool/1303204373
> [junit] 2008-11-06 20:27:38,994 DEBUG [main]
> regionserver.HRegion(2281): DELETING region
> hdfs://localhost:42234/user/hudson/TestMergeTool/944282181
> [junit] 2008-11-06 20:27:39,029 INFO [main]
> regionserver.HRegion(265): merge completed. New region is
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,029 DEBUG [main]
> util.Merge(308): Removing region: REGION => {NAME =>
> 'TestMergeTool,row_0100,1226003253332', STARTKEY
> => 'row_0100', ENDKEY => 'row_0600',
> ENCODED => 1303204373, 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-11-06 20:27:39,031 DEBUG [main]
> util.Merge(308): Removing region: REGION => {NAME =>
> 'TestMergeTool,,1226003224721', STARTKEY =>
> '', ENDKEY => '', ENCODED =>
> 944282181, 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-11-06 20:27:39,038 INFO [main]
> util.Merge(244): Adding REGION => {NAME =>
> 'TestMergeTool,,1226003258003', STARTKEY =>
> '', ENDKEY => '', ENCODED =>
> 1931386174, 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-11-06 20:27:39,039 DEBUG [main]
> regionserver.HRegion(578): Compactions and cache flushes
> disabled for region TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> regionserver.HRegion(593): Scanners disabled for region
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> regionserver.HRegion(608): No more active scanners for
> region TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> regionserver.HRegion(610): Updates disabled for region
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> regionserver.HRegion(616): No more row locks outstanding on
> region TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,039 DEBUG [main]
> regionserver.HStore(595): closed 1931386174/contents
> [junit] 2008-11-06 20:27:39,040 INFO [main]
> regionserver.HRegion(629): closed
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> regionserver.HRegion(578): Compactions and cache flushes
> disabled for region -ROOT-,,0
> [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> regionserver.HRegion(593): Scanners disabled for region
> -ROOT-,,0
> [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> regionserver.HRegion(608): No more active scanners for
> region -ROOT-,,0
> [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> regionserver.HRegion(610): Updates disabled for region
> -ROOT-,,0
> [junit] 2008-11-06 20:27:39,040 DEBUG [main]
> regionserver.HRegion(616): No more row locks outstanding on
> region -ROOT-,,0
> [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> regionserver.HStore(595): closed 70236052/info
> [junit] 2008-11-06 20:27:39,041 INFO [main]
> regionserver.HRegion(629): closed -ROOT-,,0
> [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> regionserver.HRegion(578): Compactions and cache flushes
> disabled for region .META.,,1
> [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> regionserver.HRegion(593): Scanners disabled for region
> .META.,,1
> [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> regionserver.HRegion(608): No more active scanners for
> region .META.,,1
> [junit] 2008-11-06 20:27:39,041 DEBUG [main]
> regionserver.HRegion(610): Updates disabled for region
> .META.,,1
> [junit] 2008-11-06 20:27:39,042 DEBUG [main]
> regionserver.HRegion(616): No more row locks outstanding on
> region .META.,,1
> [junit] 2008-11-06 20:27:39,042 DEBUG [main]
> regionserver.HRegion(1023): Started memcache flush for
> region .META.,,1. Current region memcache size 466.0
> [junit] 2008-11-06 20:27:39,340 DEBUG [main]
> regionserver.HStore(690): Added
> /user/hudson/.META./1028785192/info/mapfiles/2677182741781292076
> with 3 entries, sequence id 21, data size 466.0, file size
> 618.0
> [junit] 2008-11-06 20:27:39,341 DEBUG [main]
> regionserver.HRegion(1097): Finished memcache flush for
> region .META.,,1 in 299ms, sequence id=21, compaction
> requested=true
> [junit] 2008-11-06 20:27:39,341 DEBUG [main]
> regionserver.HStore(595): closed 1028785192/historian
> [junit] 2008-11-06 20:27:39,342 DEBUG [main]
> regionserver.HStore(595): closed 1028785192/info
> [junit] 2008-11-06 20:27:39,342 INFO [main]
> regionserver.HRegion(629): closed .META.,,1
> [junit] 2008-11-06 20:27:39,385 DEBUG [main]
> regionserver.HLog(259): Closing current log writer
> /user/hudson/log_1226003255391/hlog.dat.0
> [junit] 2008-11-06 20:27:39,428 INFO [main]
> regionserver.HLog(272): New log writer created at
> /user/hudson/log_1226003255391/hlog.dat.1226003259386
> [junit] 2008-11-06 20:27:39,429 DEBUG [main]
> regionserver.HLog(277): Last sequence written is empty.
> Deleting all old hlogs
> [junit] 2008-11-06 20:27:39,429 INFO [main]
> regionserver.HLog(329): removing old log file
> /user/hudson/log_1226003255391/hlog.dat.0 whose highest
> sequence/edit id is 21
> [junit] 2008-11-06 20:27:39,429 DEBUG [main]
> regionserver.HLog(365): closing log writer in
> hdfs://localhost:42234/user/hudson/log_1226003255391
> [junit] 2008-11-06 20:27:39,515 DEBUG [main]
> regionserver.HRegion(2184): Opening region: REGION =>
> {NAME => 'TestMergeTool,,1226003258003', STARTKEY
> => '', ENDKEY => '', ENCODED =>
> 1931386174, 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-11-06 20:27:39,515 DEBUG [main]
> regionserver.HRegion(432): Opening region
> TestMergeTool,,1226003258003/1931386174
> [junit] 2008-11-06 20:27:39,523 DEBUG [main]
> regionserver.HStore(490): loaded
> /user/hudson/TestMergeTool/1931386174/contents/info/3550934524445237479,
> isReference=false, sequence id=2, length=580
> [junit] 2008-11-06 20:27:39,523 DEBUG [main]
> regionserver.HStore(229): Loaded 1 file(s) in hstore
> 1931386174/contents, max sequence id 2
> [junit] 2008-11-06 20:27:39,530 DEBUG [main]
> regionserver.HRegion(493): Next sequence id for region
> TestMergeTool,,1226003258003 is 3
> [junit] 2008-11-06 20:27:39,532 INFO [main]
> regionserver.HRegion(515): region
> TestMergeTool,,1226003258003/1931386174 available
> [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> regionserver.HRegion(578): Compactions and cache flushes
> disabled for region TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> regionserver.HRegion(593): Scanners disabled for region
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> regionserver.HRegion(608): No more active scanners for
> region TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,533 DEBUG [main]
> regionserver.HRegion(610): Updates disabled for region
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,534 DEBUG [main]
> regionserver.HRegion(616): No more row locks outstanding on
> region TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,534 DEBUG [main]
> regionserver.HStore(595): closed 1931386174/contents
> [junit] 2008-11-06 20:27:39,534 INFO [main]
> regionserver.HRegion(629): closed
> TestMergeTool,,1226003258003
> [junit] 2008-11-06 20:27:39,534 INFO [main]
> util.TestMergeTool(171): Verified merging regions 0+1+2+3
> and 4
> [junit] 2008-11-06 20:27:39,534 DEBUG [main]
> regionserver.HLog(365): closing log writer in
> /tmp/log_1226003234747
> [junit] 2008-11-06 20:27:39,617 INFO [main]
> hbase.HBaseTestCase(600): Shutting down FileSystem
> [junit] 2008-11-06 20:27:39,617 INFO [main]
> hbase.HBaseTestCase(607): Shutting down Mini DFS
> [junit] Shutting down the Mini HDFS Cluster
> [junit] Shutting down DataNode 1
> [junit] 2008-11-06 20:27:39,624 INFO [main]
> http.SocketListener(212): Stopped SocketListener on
> 127.0.0.1:42252
> [junit] 2008-11-06 20:27:39,753 INFO [main]
> util.Container(156): Stopped HttpContext[/static,/static]
> [junit] 2008-11-06 20:27:39,753 INFO [main]
> util.Container(156): Stopped
> org.mortbay.jetty.servlet.WebApplicationHandler@135f44e
> [junit] 2008-11-06 20:27:39,827 INFO [main]
> util.Container(156): Stopped WebApplicationContext[/,/]
> [junit] 2008-11-06 20:27:39,828 INFO [main]
> util.Container(156): Stopped
> org.mortbay.jetty.Server@17c2891
> [junit] Shutting down DataNode 0
> [junit] 2008-11-06 20:27:40,837 INFO [main]
> http.SocketListener(212): Stopped SocketListener on
> 127.0.0.1:42244
> [junit] 2008-11-06 20:27:40,837 INFO [Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42244]]
> util.ThreadedServer$Acceptor(656): Stopping Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42244]
> [junit] 2008-11-06 20:27:40,947 INFO [main]
> util.Container(156): Stopped HttpContext[/static,/static]
> [junit] 2008-11-06 20:27:40,948 INFO [main]
> util.Container(156): Stopped
> org.mortbay.jetty.servlet.WebApplicationHandler@4d2af2
> [junit] 2008-11-06 20:27:41,049 INFO [main]
> util.Container(156): Stopped WebApplicationContext[/,/]
> [junit] 2008-11-06 20:27:41,049 INFO [main]
> util.Container(156): Stopped
> org.mortbay.jetty.Server@1579a30
> [junit] 2008-11-06 20:27:42,066 INFO [main]
> http.SocketListener(212): Stopped SocketListener on
> 127.0.0.1:42240
> [junit] 2008-11-06 20:27:42,137 INFO [main]
> util.Container(156): Stopped HttpContext[/static,/static]
> [junit] 2008-11-06 20:27:42,137 INFO [main]
> util.Container(156): Stopped
> org.mortbay.jetty.servlet.WebApplicationHandler@1c54796
> [junit] 2008-11-06 20:27:42,248 INFO [main]
> util.Container(156): Stopped WebApplicationContext[/,/]
> [junit] 2008-11-06 20:27:42,248 INFO [main]
> util.Container(156): Stopped
> org.mortbay.jetty.Server@19113f8
> [junit] 2008-11-06 20:27:42,249 WARN
> [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@401369]
> namenode.FSNamesystem$ReplicationMonitor(2280):
> ReplicationMonitor thread received
> InterruptedException.java.lang.InterruptedException: sleep
> interrupted
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time
> elapsed: 37.746 sec
> [junit] Running
> org.apache.hadoop.hbase.util.TestRootPath
> [junit] 2008-11-06 20:27:42,947 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.081 sec
> [junit] Running org.onelab.test.TestFilter
> [junit] 2008-11-06 20:27:43,570 INFO [main]
> test.TestFilter(244): Checking for false negatives
> [junit] 2008-11-06 20:27:43,575 INFO [main]
> test.TestFilter(255): Checking for false positives
> [junit] 2008-11-06 20:27:43,576 INFO [main]
> test.TestFilter(262): Success!
> [junit] 2008-11-06 20:27:43,576 INFO [main]
> test.TestFilter(226): Checking serialization/deserialization
> [junit] 2008-11-06 20:27:43,578 INFO [main]
> test.TestFilter(244): Checking for false negatives
> [junit] 2008-11-06 20:27:43,580 INFO [main]
> test.TestFilter(255): Checking for false positives
> [junit] 2008-11-06 20:27:43,581 INFO [main]
> test.TestFilter(262): Success!
> [junit] Tests run: 3, Failures: 0, Errors: 0, Time
> elapsed: 0.068 sec
>
> BUILD FAILED
> /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:453:
> Tests failed!
>
> Total time: 45 minutes 35 seconds
> [locks-and-latches] Releasing all the locks
> [locks-and-latches] All the locks released
> Recording test results