" [junit] 2009-03-19 01:56:17,491 ERROR [main]
zookeeper.ZooKeeperWrapper(97): Failed to create ZooKeeper object:
java.net.UnknownHostException: ${hbase.master.hostname}"
looks like the zookeeper patch isnt being picked up by the test run?
On Wed, Mar 18, 2009 at 6:44 PM, Apache Hudson Server <
[email protected]> wrote:
> See http://hudson.zones.apache.org/hudson/job/HBase-Patch/536/changes
>
> Changes:
>
> [stack] HBASE-1267 binary keys broken in trunk (again)
>
> ------------------------------------------
> [...truncated 36541 lines...]
> [junit] 2009-03-19 01:56:17,405 DEBUG [main] util.Merge(308): Removing
> region: REGION => {NAME => 'TestMergeTool,row_0500,1237427774398', STARTKEY
> => 'row_0500', ENDKEY => 'row_0600', ENCODED => 274918869, TABLE => {{NAME
> => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES =>
> [{NAME => 'contents', BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION
> => 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '65536',
> IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES => []}} from
> .META.,,1
> [junit] 2009-03-19 01:56:17,409 INFO [main] util.Merge(244): Adding
> REGION => {NAME => 'TestMergeTool,row_0100,1237427777347', STARTKEY =>
> 'row_0100', ENDKEY => 'row_0600', ENCODED => 1683856165, TABLE => {{NAME =>
> 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME
> => 'contents', BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION =>
> 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY
> => 'false', BLOCKCACHE => 'false'}], INDEXES => []}} to REGION => {NAME =>
> '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE =>
> {{NAME => '.META.', IS_ROOT => 'false', IS_META => 'true', FAMILIES =>
> [{NAME => 'info', BLOOMFILTER => 'false', VERSIONS => '10', COMPRESSION =>
> 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '8192', IN_MEMORY
> => 'false', BLOCKCACHE => 'true'}, {NAME => 'historian', BLOOMFILTER =>
> 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH =>
> '2147483647', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false',
> BLOCKCACHE => 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,410 DEBUG [main] regionserver.HRegion(386):
> Closing TestMergeTool,row_0100,1237427777347: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,410 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,410 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,410 DEBUG [main] regionserver.Store(434):
> closed 1683856165/contents
> [junit] 2009-03-19 01:56:17,410 INFO [main] regionserver.HRegion(435):
> Closed TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,411 DEBUG [main] regionserver.HRegion(386):
> Closing -ROOT-,,0: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,411 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on -ROOT-,,0
> [junit] 2009-03-19 01:56:17,411 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region -ROOT-,,0
> [junit] 2009-03-19 01:56:17,411 DEBUG [main] regionserver.Store(434):
> closed 70236052/info
> [junit] 2009-03-19 01:56:17,411 INFO [main] regionserver.HRegion(435):
> Closed -ROOT-,,0
> [junit] 2009-03-19 01:56:17,412 DEBUG [main] regionserver.HRegion(386):
> Closing .META.,,1: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,412 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on .META.,,1
> [junit] 2009-03-19 01:56:17,412 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region .META.,,1
> [junit] 2009-03-19 01:56:17,412 DEBUG [main] regionserver.HRegion(827):
> Started memcache flush for region .META.,,1. Current region memcache size
> 968.0
> [junit] 2009-03-19 01:56:17,425 DEBUG [main] regionserver.Store(516):
> Added
> hdfs://localhost:56920/user/hudson/.META./1028785192/info/5376444466840656494,
> entries=3, sequenceid=17, memsize=968.0, filesize=968.0 to .META.,,1
> [junit] 2009-03-19 01:56:17,425 DEBUG [main] regionserver.HRegion(904):
> Finished memcache flush of ~968.0 for region .META.,,1 in 13ms, sequence
> id=17, compaction requested=true
> [junit] 2009-03-19 01:56:17,426 DEBUG [main] regionserver.Store(434):
> closed 1028785192/historian
> [junit] 2009-03-19 01:56:17,426 DEBUG [main] regionserver.Store(434):
> closed 1028785192/info
> [junit] 2009-03-19 01:56:17,426 INFO [main] regionserver.HRegion(435):
> Closed .META.,,1
> [junit] 2009-03-19 01:56:17,433 INFO [main] regionserver.HLog(273):
> Closed hdfs://localhost:56920/user/hudson/log_1237427777182/hlog.dat.0,
> entries=4. New log writer:
> /user/hudson/log_1237427777182/hlog.dat.1237427777432
> [junit] 2009-03-19 01:56:17,434 DEBUG [main] regionserver.HLog(280):
> Last sequence written is empty. Deleting all old hlogs
> [junit] 2009-03-19 01:56:17,434 INFO [main] regionserver.HLog(391):
> removing old log file /user/hudson/log_1237427777182/hlog.dat.0 whose
> highest sequence/edit id is 17
> [junit] 2009-03-19 01:56:17,435 DEBUG [main] regionserver.HLog(427):
> closing log writer in hdfs://localhost:56920/user/hudson/log_1237427777182
> [junit] 2009-03-19 01:56:17,443 DEBUG [main] regionserver.HRegion(2207):
> Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1237427777347',
> STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1683856165, TABLE
> => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false',
> FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', VERSIONS => '3',
> COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE =>
> '65536', IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,444 DEBUG [main] regionserver.HRegion(238):
> Opening region TestMergeTool,row_0100,1237427777347/1683856165
> [junit] 2009-03-19 01:56:17,449 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/TestMergeTool/1683856165/contents/8990490937022118833,
> isReference=false, sequence id=5, length=718, majorCompaction=false
> [junit] 2009-03-19 01:56:17,450 DEBUG [main] regionserver.Store(213):
> Loaded 1 file(s) in Store 1683856165/contents, max sequence id 5
> [junit] 2009-03-19 01:56:17,451 DEBUG [main] regionserver.HRegion(298):
> Next sequence id for region TestMergeTool,row_0100,1237427777347 is 6
> [junit] 2009-03-19 01:56:17,452 INFO [main] regionserver.HRegion(315):
> region TestMergeTool,row_0100,1237427777347/1683856165 available
> [junit] 2009-03-19 01:56:17,452 DEBUG [main] regionserver.HLog(217):
> changing sequence number from 5 to 6
> [junit] 2009-03-19 01:56:17,466 DEBUG [main] regionserver.HRegion(386):
> Closing TestMergeTool,row_0100,1237427777347: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,467 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,467 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,467 DEBUG [main] regionserver.Store(434):
> closed 1683856165/contents
> [junit] 2009-03-19 01:56:17,467 INFO [main] regionserver.HRegion(435):
> Closed TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,467 INFO [main] util.TestMergeTool(169):
> Verified merging regions 0+1+2 and 3
> [junit] 2009-03-19 01:56:17,468 INFO [main] util.TestMergeTool(156):
> merging regions 0+1+2+3 and 4
> [junit] 2009-03-19 01:56:17,468 INFO [main] util.Merge(80): Verifying
> that file system is available...
> [junit] 2009-03-19 01:56:17,469 INFO [main] util.Merge(89): Verifying
> that HBase is not running...
> [junit] 2009-03-19 01:56:17,491 ERROR [main]
> zookeeper.ZooKeeperWrapper(97): Failed to create ZooKeeper object:
> java.net.UnknownHostException: ${hbase.master.hostname}
> [junit] 2009-03-19 01:56:17,493 INFO [main] util.Merge(190): Merging
> regions [...@1ce9085 and [...@722969 in table [...@fcc13d
> [junit] 2009-03-19 01:56:17,497 INFO [main] regionserver.HLog(273): New
> log writer: /user/hudson/log_1237427777494/hlog.dat.1237427777495
> [junit] 2009-03-19 01:56:17,497 DEBUG [main] regionserver.HRegion(2207):
> 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 => '10', LENGTH => '2147483647', TTL =>
> '-1', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}],
> INDEXES => []}}
> [junit] 2009-03-19 01:56:17,497 DEBUG [main] regionserver.HRegion(238):
> Opening region -ROOT-,,0/70236052
> [junit] 2009-03-19 01:56:17,503 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/-ROOT-/70236052/info/7559429375003032526,
> isReference=false, sequence id=1, length=856, majorCompaction=false
> [junit] 2009-03-19 01:56:17,503 DEBUG [main] regionserver.Store(213):
> Loaded 1 file(s) in Store 70236052/info, max sequence id 1
> [junit] 2009-03-19 01:56:17,505 DEBUG [main] regionserver.HRegion(298):
> Next sequence id for region -ROOT-,,0 is 2
> [junit] 2009-03-19 01:56:17,506 INFO [main] regionserver.HRegion(315):
> region -ROOT-,,0/70236052 available
> [junit] 2009-03-19 01:56:17,506 DEBUG [main] regionserver.HLog(217):
> changing sequence number from 0 to 2
> [junit] 2009-03-19 01:56:17,506 INFO [main] regionserver.HRegion(702):
> starting compaction on region -ROOT-,,0
> [junit] 2009-03-19 01:56:17,508 INFO [main] regionserver.HRegion(717):
> compaction completed on region -ROOT-,,0 in 0sec
> [junit] 2009-03-19 01:56:17,513 INFO [main] util.Merge(204): Found meta
> for region1 [...@1064b80, meta for region2 [...@1064b80
> [junit] 2009-03-19 01:56:17,513 DEBUG [main] regionserver.HRegion(2207):
> 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 => '10', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL =>
> '-1', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'},
> {NAME => 'historian', BLOOMFILTER => 'false', VERSIONS => '2147483647',
> COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '604800', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,513 DEBUG [main] regionserver.HRegion(238):
> Opening region .META.,,1/1028785192
> [junit] 2009-03-19 01:56:17,519 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/.META./1028785192/info/5376444466840656494,
> isReference=false, sequence id=17, length=968, majorCompaction=false
> [junit] 2009-03-19 01:56:17,523 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/.META./1028785192/info/6855178046127786192,
> isReference=false, sequence id=13, length=1531, majorCompaction=false
> [junit] 2009-03-19 01:56:17,527 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/.META./1028785192/info/8071180888179849762,
> isReference=false, sequence id=5, length=2330, majorCompaction=false
> [junit] 2009-03-19 01:56:17,527 DEBUG [main] regionserver.Store(213):
> Loaded 3 file(s) in Store 1028785192/info, max sequence id 17
> [junit] 2009-03-19 01:56:17,529 DEBUG [main] regionserver.HRegion(298):
> Next sequence id for region .META.,,1 is 18
> [junit] 2009-03-19 01:56:17,530 INFO [main] regionserver.HRegion(315):
> region .META.,,1/1028785192 available
> [junit] 2009-03-19 01:56:17,531 DEBUG [main] regionserver.HLog(217):
> changing sequence number from 2 to 18
> [junit] 2009-03-19 01:56:17,531 INFO [main] regionserver.HRegion(702):
> starting compaction on region .META.,,1
> [junit] 2009-03-19 01:56:17,532 DEBUG [main] regionserver.Store(619):
> 1028785192/historian: no store files to compact
> [junit] 2009-03-19 01:56:17,533 DEBUG [main] regionserver.Store(683):
> Compaction size of 1028785192/info: 4.7k; Skipped 0 file(s), size: 0
> [junit] 2009-03-19 01:56:17,537 DEBUG [main] regionserver.Store(692):
> Started compaction of 3 file(s) into
> /user/hudson/.META./1028785192/info/5047861126213101
> [junit] 2009-03-19 01:56:17,571 DEBUG [main] regionserver.Store(707):
> Completed compaction of 1028785192/info store size is 3.9k
> [junit] 2009-03-19 01:56:17,572 INFO [main] regionserver.HRegion(717):
> compaction completed on region .META.,,1 in 0sec
> [junit] 2009-03-19 01:56:17,574 DEBUG [main] regionserver.HRegion(2207):
> Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1237427777347',
> STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1683856165, TABLE
> => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false',
> FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION =>
> 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE =>
> '65536', IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,575 DEBUG [main] regionserver.HRegion(238):
> Opening region TestMergeTool,row_0100,1237427777347/1683856165
> [junit] 2009-03-19 01:56:17,580 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/TestMergeTool/1683856165/contents/8990490937022118833,
> isReference=false, sequence id=5, length=718, majorCompaction=false
> [junit] 2009-03-19 01:56:17,580 DEBUG [main] regionserver.Store(213):
> Loaded 1 file(s) in Store 1683856165/contents, max sequence id 5
> [junit] 2009-03-19 01:56:17,581 DEBUG [main] regionserver.HRegion(298):
> Next sequence id for region TestMergeTool,row_0100,1237427777347 is 6
> [junit] 2009-03-19 01:56:17,583 INFO [main] regionserver.HRegion(315):
> region TestMergeTool,row_0100,1237427777347/1683856165 available
> [junit] 2009-03-19 01:56:17,583 DEBUG [main] regionserver.HRegion(2207):
> Opening region: REGION => {NAME => 'TestMergeTool,,1237427774398', STARTKEY
> => '', ENDKEY => '', ENCODED => 1581125076, TABLE => {{NAME =>
> 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME
> => 'contents', BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION =>
> 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY
> => 'false', BLOCKCACHE => 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,583 DEBUG [main] regionserver.HRegion(238):
> Opening region TestMergeTool,,1237427774398/1581125076
> [junit] 2009-03-19 01:56:17,588 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/TestMergeTool/1581125076/contents/1443150371782598385,
> isReference=false, sequence id=6, length=462, majorCompaction=false
> [junit] 2009-03-19 01:56:17,589 DEBUG [main] regionserver.Store(213):
> Loaded 1 file(s) in Store 1581125076/contents, max sequence id 6
> [junit] 2009-03-19 01:56:17,590 DEBUG [main] regionserver.HRegion(298):
> Next sequence id for region TestMergeTool,,1237427774398 is 7
> [junit] 2009-03-19 01:56:17,591 INFO [main] regionserver.HRegion(315):
> region TestMergeTool,,1237427774398/1581125076 available
> [junit] 2009-03-19 01:56:17,591 INFO [main] regionserver.HRegion(702):
> starting major compaction on region TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,596 DEBUG [main] regionserver.Store(692):
> Started compaction of 1 file(s) into
> /user/hudson/TestMergeTool/1683856165/contents/1167733704818426773
> [junit] 2009-03-19 01:56:17,618 DEBUG [main] regionserver.Store(707):
> Completed major compaction of 1683856165/contents store size is 718.0
> [junit] 2009-03-19 01:56:17,619 INFO [main] regionserver.HRegion(717):
> compaction completed on region TestMergeTool,row_0100,1237427777347 in 0sec
> [junit] 2009-03-19 01:56:17,619 DEBUG [main] regionserver.HRegion(2447):
> Files for region: TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,620 DEBUG [main] regionserver.HRegion(2591):
> d hdfs://localhost:56920/user/hudson/TestMergeTool/1683856165/contents
> [junit] 2009-03-19 01:56:17,621 DEBUG [main] regionserver.HRegion(2594):
> f
> hdfs://localhost:56920/user/hudson/TestMergeTool/1683856165/contents/4446031468344231511
> size=718
> [junit] 2009-03-19 01:56:17,621 INFO [main] regionserver.HRegion(702):
> starting major compaction on region TestMergeTool,,1237427774398
> [junit] 2009-03-19 01:56:17,626 DEBUG [main] regionserver.Store(692):
> Started compaction of 1 file(s) into
> /user/hudson/TestMergeTool/1581125076/contents/7506853680511212595
> [junit] 2009-03-19 01:56:17,649 DEBUG [main] regionserver.Store(707):
> Completed major compaction of 1581125076/contents store size is 462.0
> [junit] 2009-03-19 01:56:17,650 INFO [main] regionserver.HRegion(717):
> compaction completed on region TestMergeTool,,1237427774398 in 0sec
> [junit] 2009-03-19 01:56:17,650 DEBUG [main] regionserver.HRegion(2452):
> Files for region: TestMergeTool,,1237427774398
> [junit] 2009-03-19 01:56:17,651 DEBUG [main] regionserver.HRegion(2591):
> d hdfs://localhost:56920/user/hudson/TestMergeTool/1581125076/contents
> [junit] 2009-03-19 01:56:17,651 DEBUG [main] regionserver.HRegion(2594):
> f
> hdfs://localhost:56920/user/hudson/TestMergeTool/1581125076/contents/2440792108748354915
> size=462
> [junit] 2009-03-19 01:56:17,652 INFO [main] regionserver.HRegion(2473):
> Creating new region REGION => {NAME => 'TestMergeTool,,1237427777652',
> STARTKEY => '', ENDKEY => '', ENCODED => 923090695, TABLE => {{NAME =>
> 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME
> => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS =>
> '3', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,657 INFO [main] regionserver.HRegion(2482):
> starting merge of regions: TestMergeTool,row_0100,1237427777347 and
> TestMergeTool,,1237427774398 into new region REGION => {NAME =>
> 'TestMergeTool,,1237427777652', STARTKEY => '', ENDKEY => '', ENCODED =>
> 923090695, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META
> => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false',
> COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1',
> BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES
> => []}} with start key <[...@b8deef> and end key <[...@b8deef>
> [junit] 2009-03-19 01:56:17,657 DEBUG [main] regionserver.HRegion(386):
> Closing TestMergeTool,row_0100,1237427777347: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,657 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,657 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,658 DEBUG [main] regionserver.Store(434):
> closed 1683856165/contents
> [junit] 2009-03-19 01:56:17,658 INFO [main] regionserver.HRegion(435):
> Closed TestMergeTool,row_0100,1237427777347
> [junit] 2009-03-19 01:56:17,658 DEBUG [main] regionserver.HRegion(386):
> Closing TestMergeTool,,1237427774398: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,658 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1237427774398
> [junit] 2009-03-19 01:56:17,658 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region TestMergeTool,,1237427774398
> [junit] 2009-03-19 01:56:17,659 DEBUG [main] regionserver.Store(434):
> closed 1581125076/contents
> [junit] 2009-03-19 01:56:17,659 INFO [main] regionserver.HRegion(435):
> Closed TestMergeTool,,1237427774398
> [junit] 2009-03-19 01:56:17,681 DEBUG [main] regionserver.HRegion(2516):
> Files for new region
> [junit] 2009-03-19 01:56:17,681 DEBUG [main] regionserver.HRegion(2591):
> d hdfs://localhost:56920/user/hudson/TestMergeTool/923090695/contents
> [junit] 2009-03-19 01:56:17,682 DEBUG [main] regionserver.HRegion(2594):
> f
> hdfs://localhost:56920/user/hudson/TestMergeTool/923090695/contents/5106499742809690455
> size=462
> [junit] 2009-03-19 01:56:17,683 DEBUG [main] regionserver.HRegion(2594):
> f
> hdfs://localhost:56920/user/hudson/TestMergeTool/923090695/contents/6640237529432977266
> size=718
> [junit] 2009-03-19 01:56:17,683 DEBUG [main] regionserver.HRegion(238):
> Opening region TestMergeTool,,1237427777652/923090695
> [junit] 2009-03-19 01:56:17,688 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/TestMergeTool/923090695/contents/5106499742809690455,
> isReference=false, sequence id=6, length=462, majorCompaction=true
> [junit] 2009-03-19 01:56:17,692 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/TestMergeTool/923090695/contents/6640237529432977266,
> isReference=false, sequence id=5, length=718, majorCompaction=true
> [junit] 2009-03-19 01:56:17,693 DEBUG [main] regionserver.Store(213):
> Loaded 2 file(s) in Store 923090695/contents, max sequence id 6
> [junit] 2009-03-19 01:56:17,694 DEBUG [main] regionserver.HRegion(298):
> Next sequence id for region TestMergeTool,,1237427777652 is 7
> [junit] 2009-03-19 01:56:17,695 INFO [main] regionserver.HRegion(315):
> region TestMergeTool,,1237427777652/923090695 available
> [junit] 2009-03-19 01:56:17,695 INFO [main] regionserver.HRegion(702):
> starting compaction on region TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,697 DEBUG [main] regionserver.Store(683):
> Compaction size of 923090695/contents: 1.2k; Skipped 0 file(s), size: 0
> [junit] 2009-03-19 01:56:17,715 DEBUG [main] regionserver.Store(692):
> Started compaction of 2 file(s) into
> /user/hudson/TestMergeTool/923090695/contents/5456808112216638234
> [junit] 2009-03-19 01:56:17,729 DEBUG [main] regionserver.Store(707):
> Completed compaction of 923090695/contents store size is 847.0
> [junit] 2009-03-19 01:56:17,730 INFO [main] regionserver.HRegion(717):
> compaction completed on region TestMergeTool,,1237427777652 in 0sec
> [junit] 2009-03-19 01:56:17,730 DEBUG [main] regionserver.HRegion(2523):
> Files for new region
> [junit] 2009-03-19 01:56:17,731 DEBUG [main] regionserver.HRegion(2591):
> d hdfs://localhost:56920/user/hudson/TestMergeTool/923090695/contents
> [junit] 2009-03-19 01:56:17,732 DEBUG [main] regionserver.HRegion(2594):
> f
> hdfs://localhost:56920/user/hudson/TestMergeTool/923090695/contents/3834249900436126069
> size=847
> [junit] 2009-03-19 01:56:17,732 DEBUG [main] regionserver.HRegion(2325):
> DELETING region hdfs://localhost:56920/user/hudson/TestMergeTool/1683856165
> [junit] 2009-03-19 01:56:17,733 DEBUG [main] regionserver.HRegion(2325):
> DELETING region hdfs://localhost:56920/user/hudson/TestMergeTool/1581125076
> [junit] 2009-03-19 01:56:17,734 INFO [main] regionserver.HRegion(2529):
> merge completed. New region is TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,734 DEBUG [main] util.Merge(308): Removing
> region: REGION => {NAME => 'TestMergeTool,row_0100,1237427777347', STARTKEY
> => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1683856165, TABLE => {{NAME
> => 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES =>
> [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE',
> VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '65536',
> IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES => []}} from
> .META.,,1
> [junit] 2009-03-19 01:56:17,736 DEBUG [main] util.Merge(308): Removing
> region: REGION => {NAME => 'TestMergeTool,,1237427774398', STARTKEY => '',
> ENDKEY => '', ENCODED => 1581125076, TABLE => {{NAME => 'TestMergeTool',
> IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents',
> BLOOMFILTER => 'false', VERSIONS => '3', COMPRESSION => 'NONE', LENGTH =>
> '2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY => 'false',
> BLOCKCACHE => 'false'}], INDEXES => []}} from .META.,,1
> [junit] 2009-03-19 01:56:17,739 INFO [main] util.Merge(244): Adding
> REGION => {NAME => 'TestMergeTool,,1237427777652', STARTKEY => '', ENDKEY =>
> '', ENCODED => 923090695, TABLE => {{NAME => 'TestMergeTool', IS_ROOT =>
> 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER
> => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647',
> TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE =>
> 'false'}], INDEXES => []}} to REGION => {NAME => '.META.,,1', STARTKEY =>
> '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.',
> IS_ROOT => 'false', IS_META => 'true', FAMILIES => [{NAME => 'info',
> BLOOMFILTER => 'false', VERSIONS => '10', COMPRESSION => 'NONE', LENGTH =>
> '2147483647', TTL => '-1', BLOCKSIZE => '8192', IN_MEMORY => 'false',
> BLOCKCACHE => 'true'}, {NAME => 'historian', BLOOMFILTER => 'false',
> VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL
> => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE =>
> 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,740 DEBUG [main] regionserver.HRegion(386):
> Closing TestMergeTool,,1237427777652: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,741 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,742 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,743 DEBUG [main] regionserver.Store(434):
> closed 923090695/contents
> [junit] 2009-03-19 01:56:17,744 INFO [main] regionserver.HRegion(435):
> Closed TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,745 DEBUG [main] regionserver.HRegion(386):
> Closing -ROOT-,,0: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,745 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on -ROOT-,,0
> [junit] 2009-03-19 01:56:17,746 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region -ROOT-,,0
> [junit] 2009-03-19 01:56:17,747 DEBUG [main] regionserver.Store(434):
> closed 70236052/info
> [junit] 2009-03-19 01:56:17,748 INFO [main] regionserver.HRegion(435):
> Closed -ROOT-,,0
> [junit] 2009-03-19 01:56:17,749 DEBUG [main] regionserver.HRegion(386):
> Closing .META.,,1: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,750 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on .META.,,1
> [junit] 2009-03-19 01:56:17,751 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region .META.,,1
> [junit] 2009-03-19 01:56:17,751 DEBUG [main] regionserver.HRegion(827):
> Started memcache flush for region .META.,,1. Current region memcache size
> 928.0
> [junit] 2009-03-19 01:56:17,765 DEBUG [main] regionserver.Store(516):
> Added
> hdfs://localhost:56920/user/hudson/.META./1028785192/info/1464455216663806897,
> entries=3, sequenceid=21, memsize=928.0, filesize=920.0 to .META.,,1
> [junit] 2009-03-19 01:56:17,766 DEBUG [main] regionserver.HRegion(904):
> Finished memcache flush of ~928.0 for region .META.,,1 in 15ms, sequence
> id=21, compaction requested=true
> [junit] 2009-03-19 01:56:17,767 DEBUG [main] regionserver.Store(434):
> closed 1028785192/historian
> [junit] 2009-03-19 01:56:17,768 DEBUG [main] regionserver.Store(434):
> closed 1028785192/info
> [junit] 2009-03-19 01:56:17,769 INFO [main] regionserver.HRegion(435):
> Closed .META.,,1
> [junit] 2009-03-19 01:56:17,785 INFO [main] regionserver.HLog(273):
> Closed hdfs://localhost:56920/user/hudson/log_1237427777494/hlog.dat.0,
> entries=4. New log writer:
> /user/hudson/log_1237427777494/hlog.dat.1237427777783
> [junit] 2009-03-19 01:56:17,785 DEBUG [main] regionserver.HLog(280):
> Last sequence written is empty. Deleting all old hlogs
> [junit] 2009-03-19 01:56:17,785 INFO [main] regionserver.HLog(391):
> removing old log file /user/hudson/log_1237427777494/hlog.dat.0 whose
> highest sequence/edit id is 21
> [junit] 2009-03-19 01:56:17,786 DEBUG [main] regionserver.HLog(427):
> closing log writer in hdfs://localhost:56920/user/hudson/log_1237427777494
> [junit] 2009-03-19 01:56:17,792 DEBUG [main] regionserver.HRegion(2207):
> Opening region: REGION => {NAME => 'TestMergeTool,,1237427777652', STARTKEY
> => '', ENDKEY => '', ENCODED => 923090695, TABLE => {{NAME =>
> 'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME
> => 'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS =>
> '3', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'false'}], INDEXES => []}}
> [junit] 2009-03-19 01:56:17,793 DEBUG [main] regionserver.HRegion(238):
> Opening region TestMergeTool,,1237427777652/923090695
> [junit] 2009-03-19 01:56:17,798 DEBUG [main] regionserver.Store(383):
> loaded /user/hudson/TestMergeTool/923090695/contents/3834249900436126069,
> isReference=false, sequence id=6, length=847, majorCompaction=false
> [junit] 2009-03-19 01:56:17,798 DEBUG [main] regionserver.Store(213):
> Loaded 1 file(s) in Store 923090695/contents, max sequence id 6
> [junit] 2009-03-19 01:56:17,800 DEBUG [main] regionserver.HRegion(298):
> Next sequence id for region TestMergeTool,,1237427777652 is 7
> [junit] 2009-03-19 01:56:17,801 INFO [main] regionserver.HRegion(315):
> region TestMergeTool,,1237427777652/923090695 available
> [junit] 2009-03-19 01:56:17,801 DEBUG [main] regionserver.HLog(217):
> changing sequence number from 6 to 7
> [junit] 2009-03-19 01:56:17,821 DEBUG [main] regionserver.HRegion(386):
> Closing TestMergeTool,,1237427777652: compactions & flushes disabled
> [junit] 2009-03-19 01:56:17,822 DEBUG [main] regionserver.HRegion(416):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,822 DEBUG [main] regionserver.HRegion(423):
> No more row locks outstanding on region TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,822 DEBUG [main] regionserver.Store(434):
> closed 923090695/contents
> [junit] 2009-03-19 01:56:17,823 INFO [main] regionserver.HRegion(435):
> Closed TestMergeTool,,1237427777652
> [junit] 2009-03-19 01:56:17,823 INFO [main] util.TestMergeTool(169):
> Verified merging regions 0+1+2+3 and 4
> [junit] 2009-03-19 01:56:17,823 DEBUG [main] regionserver.HLog(427):
> closing log writer in /tmp/log_1237427776448
> [junit] 2009-03-19 01:56:17,838 INFO [main] hbase.HBaseTestCase(598):
> Shutting down FileSystem
> [junit] 2009-03-19 01:56:17,838 INFO [main] hbase.HBaseTestCase(605):
> Shutting down Mini DFS
> [junit] Shutting down the Mini HDFS Cluster
> [junit] Shutting down DataNode 1
> [junit] 2009-03-19 01:56:17,839 INFO [Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=34868]]
> util.ThreadedServer$Acceptor(656): Stopping Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=34868]
> [junit] 2009-03-19 01:56:17,841 INFO [main] http.SocketListener(212):
> Stopped SocketListener on 127.0.0.1:34868
> [junit] 2009-03-19 01:56:17,842 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.servlet.webapplicationhand...@1c20eb7
> [junit] 2009-03-19 01:56:17,911 INFO [main] util.Container(156):
> Stopped WebApplicationContext[/static,/static]
> [junit] 2009-03-19 01:56:17,911 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.servlet.webapplicationhand...@15f4a7f
> [junit] 2009-03-19 01:56:17,966 INFO [main] util.Container(156):
> Stopped WebApplicationContext[/,/]
> [junit] 2009-03-19 01:56:17,966 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.ser...@ed32c4
> [junit] 2009-03-19 01:56:17,967 WARN
> [org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@145f939]
> datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:46050,
> storageID=DS-1932349647-67.195.138.9-46050-1237427775942, infoPort=34868,
> ipcPort=37420):DataXceiveServer:
> java.nio.channels.AsynchronousCloseException
> [junit] at
> java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
> [junit] at
> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
> [junit] at
> sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
> [junit] at
> org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:129)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] Shutting down DataNode 0
> [junit] 2009-03-19 01:56:18,967 INFO [Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=60420]]
> util.ThreadedServer$Acceptor(656): Stopping Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=60420]
> [junit] 2009-03-19 01:56:18,967 INFO [main] http.SocketListener(212):
> Stopped SocketListener on 127.0.0.1:60420
> [junit] 2009-03-19 01:56:18,968 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.servlet.webapplicationhand...@3a6e5c
> [junit] 2009-03-19 01:56:19,036 INFO [main] util.Container(156):
> Stopped WebApplicationContext[/static,/static]
> [junit] 2009-03-19 01:56:19,038 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.servlet.webapplicationhand...@1b66b06
> [junit] 2009-03-19 01:56:19,090 INFO [main] util.Container(156):
> Stopped WebApplicationContext[/,/]
> [junit] 2009-03-19 01:56:19,090 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.ser...@1e6e305
> [junit] 2009-03-19 01:56:19,091 WARN
> [org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1dfd868]
> datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:37924,
> storageID=DS-2008395755-67.195.138.9-37924-1237427775724, infoPort=60420,
> ipcPort=42441):DataXceiveServer:
> java.nio.channels.AsynchronousCloseException
> [junit] at
> java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
> [junit] at
> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
> [junit] at
> sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
> [junit] at
> org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:129)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] 2009-03-19 01:56:20,192 INFO [Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=33830]]
> util.ThreadedServer$Acceptor(656): Stopping Acceptor
> ServerSocket[addr=localhost/127.0.0.1,port=0,localport=33830]
> [junit] 2009-03-19 01:56:20,192 INFO [main] http.SocketListener(212):
> Stopped SocketListener on 127.0.0.1:33830
> [junit] 2009-03-19 01:56:20,193 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.servlet.webapplicationhand...@1d32e45
> [junit] 2009-03-19 01:56:20,244 INFO [main] util.Container(156):
> Stopped WebApplicationContext[/static,/static]
> [junit] 2009-03-19 01:56:20,244 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.servlet.webapplicationhand...@c00025
> [junit] 2009-03-19 01:56:20,291 INFO [main] util.Container(156):
> Stopped WebApplicationContext[/,/]
> [junit] 2009-03-19 01:56:20,291 INFO [main] util.Container(156):
> Stopped org.mortbay.jetty.ser...@14ae2c1
> [junit] 2009-03-19 01:56:20,292 WARN
>
> [org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@1ff92f5]
> namenode.FSNamesystem$ReplicationMonitor(2300): ReplicationMonitor thread
> received InterruptedException.java.lang.InterruptedException: sleep
> interrupted
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 6 sec
> [junit] Running org.apache.hadoop.hbase.util.TestRootPath
> [junit] 2009-03-19 01:56:20,654 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:198)
> [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.072 sec
> [junit] Running org.onelab.test.TestFilter
> [junit] 2009-03-19 01:56:21,034 INFO [main] test.TestFilter(244):
> Checking for false negatives
> [junit] 2009-03-19 01:56:21,039 INFO [main] test.TestFilter(255):
> Checking for false positives
> [junit] 2009-03-19 01:56:21,040 INFO [main] test.TestFilter(262):
> Success!
> [junit] 2009-03-19 01:56:21,040 INFO [main] test.TestFilter(226):
> Checking serialization/deserialization
> [junit] 2009-03-19 01:56:21,042 INFO [main] test.TestFilter(244):
> Checking for false negatives
> [junit] 2009-03-19 01:56:21,045 INFO [main] test.TestFilter(255):
> Checking for false positives
> [junit] 2009-03-19 01:56:21,045 INFO [main] test.TestFilter(262):
> Success!
> [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.084 sec
>
> BUILD FAILED
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml:458:
> Tests failed!
>
> Total time: 27 minutes 47 seconds
> [locks-and-latches] Releasing all the locks
> [locks-and-latches] All the locks released
> Recording test results
> Publishing Clover coverage report...
>
>
