hbase-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: HBase-Patch #655
Date Sat, 20 Jun 2009 01:39:01 GMT
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/655/changes

Changes:

[apurtell] HBASE-1553 ClassSize missing in trunk

[apurtell] HBASE-1547 atomicIncrement doesnt increase hregion.memcacheSize

[stack] HBASE-1545 atomicIncrements creating new values with Long.MAX_VALUE

[stack] HBASE-1387 Before release verify all object sizes using Ryans' instrumented JVM trick

[nitay] HBASE-1534 Got ZooKeeper event, state: Disconnected on HRS and then NPE on reinit

[nitay] HBASE-1550 CHANGES.txt update

[stack] HBASE-1549 in zoo.cfg, use localhost instead of 127.0.0.1?

[stack] HBASE-1531 Change new Get to use new filter API

[nitay] HBASE-1550 hbase-daemon.sh stop should provide more information when stop command
fails

[apurtell] HBASE-1552 provide version running on cluster via getClusterStatus

------------------------------------------
[...truncated 27718 lines...]
    [junit] 2009-06-20 01:38:50,120 DEBUG [main] regionserver.Store(728): Completed major
compaction of contents; store size is 471.0
    [junit] 2009-06-20 01:38:50,136 INFO  [main] regionserver.HRegion(774): compaction completed
on region TestMergeTool,row_0500,1245461905473 in 0sec
    [junit] 2009-06-20 01:38:50,136 DEBUG [main] regionserver.HRegion(2076): Files for region:
TestMergeTool,row_0500,1245461905473
    [junit] 2009-06-20 01:38:50,137 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/79087722/.regioninfo
size=630
    [junit] 2009-06-20 01:38:50,137 DEBUG [main] regionserver.HRegion(2215): d hdfs://localhost:45601/user/hudson/TestMergeTool/79087722/contents
    [junit] 2009-06-20 01:38:50,138 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/79087722/contents/8827595561109831381
size=471
    [junit] 2009-06-20 01:38:50,139 INFO  [main] regionserver.HRegion(2105): Creating new
region REGION => {NAME => 'TestMergeTool,row_0100,1245461930138', STARTKEY => 'row_0100',
ENDKEY => 'row_0600', ENCODED => 654978192, TABLE => {{NAME => 'TestMergeTool',
FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL
=> '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:50,156 INFO  [main] regionserver.HRegion(2114): starting merge
of regions: TestMergeTool,row_0100,1245461928867 and TestMergeTool,row_0500,1245461905473
into new region REGION => {NAME => 'TestMergeTool,row_0100,1245461930138', STARTKEY
=> 'row_0100', ENDKEY => 'row_0600', ENCODED => 654978192, TABLE => {{NAME =>
'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION =>
'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}} with start key <row_0100> and end key <row_0600>
    [junit] 2009-06-20 01:38:50,156 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1245461928867:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:50,156 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0100,1245461928867
    [junit] 2009-06-20 01:38:50,156 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,row_0100,1245461928867
    [junit] 2009-06-20 01:38:50,157 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:50,157 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1245461928867
    [junit] 2009-06-20 01:38:50,157 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0500,1245461905473:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:50,157 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0500,1245461905473
    [junit] 2009-06-20 01:38:50,157 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,row_0500,1245461905473
    [junit] 2009-06-20 01:38:50,158 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:50,158 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,row_0500,1245461905473
    [junit] 2009-06-20 01:38:50,216 DEBUG [main] regionserver.HRegion(2147): Files for new
region
    [junit] 2009-06-20 01:38:50,216 DEBUG [main] regionserver.HRegion(2215): d hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/contents
    [junit] 2009-06-20 01:38:50,218 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/contents/2379924206468089115
size=471
    [junit] 2009-06-20 01:38:50,218 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/contents/5393758022079558328
size=699
    [junit] 2009-06-20 01:38:50,218 DEBUG [main] regionserver.HRegion(264): Opening region
TestMergeTool,row_0100,1245461930138, encoded=654978192
    [junit] 2009-06-20 01:38:50,261 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/654978192/contents/2379924206468089115,
isReference=false, sequence id=6, length=471, majorCompaction=true
    [junit] 2009-06-20 01:38:50,265 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/654978192/contents/5393758022079558328,
isReference=false, sequence id=5, length=699, majorCompaction=true
    [junit] 2009-06-20 01:38:50,267 INFO  [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1245461930138/654978192
available; sequence id is 7
    [junit] 2009-06-20 01:38:50,267 INFO  [main] regionserver.HRegion(759): Starting compaction
on region TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,279 DEBUG [main] regionserver.Store(704): Compaction size
of contents: 1.1k; Skipped 0 file(s), size: 0
    [junit] 2009-06-20 01:38:50,299 DEBUG [main] regionserver.Store(713): Started compaction
of 2 file(s)  into /user/hudson/TestMergeTool/compaction.dir/654978192/8765150624645001286
    [junit] 2009-06-20 01:38:50,487 DEBUG [main] regionserver.Store(728): Completed compaction
of contents; store size is 837.0
    [junit] 2009-06-20 01:38:50,503 INFO  [main] regionserver.HRegion(774): compaction completed
on region TestMergeTool,row_0100,1245461930138 in 0sec
    [junit] 2009-06-20 01:38:50,503 DEBUG [main] regionserver.HRegion(2154): Files for new
region
    [junit] 2009-06-20 01:38:50,504 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/.regioninfo
size=631
    [junit] 2009-06-20 01:38:50,504 DEBUG [main] regionserver.HRegion(2215): d hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/contents
    [junit] 2009-06-20 01:38:50,505 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/contents/7514522130877583563
size=837
    [junit] 2009-06-20 01:38:50,505 DEBUG [main] regionserver.HRegion(1950): DELETING region
hdfs://localhost:45601/user/hudson/TestMergeTool/1844716129
    [junit] 2009-06-20 01:38:50,523 DEBUG [main] regionserver.HRegion(1950): DELETING region
hdfs://localhost:45601/user/hudson/TestMergeTool/79087722
    [junit] 2009-06-20 01:38:50,539 INFO  [main] regionserver.HRegion(2160): merge completed.
New region is TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,539 DEBUG [main] util.Merge(319): Removing region: REGION
=> {NAME => 'TestMergeTool,row_0100,1245461928867', STARTKEY => 'row_0100', ENDKEY
=> 'row_0400', ENCODED => 1844716129, TABLE => {{NAME => 'TestMergeTool', FAMILIES
=> [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
    [junit] 2009-06-20 01:38:50,539 DEBUG [main] util.Merge(319): Removing region: REGION
=> {NAME => 'TestMergeTool,row_0500,1245461905473', STARTKEY => 'row_0500', ENDKEY
=> 'row_0600', ENCODED => 79087722, TABLE => {{NAME => 'TestMergeTool', FAMILIES
=> [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
    [junit] 2009-06-20 01:38:50,541 INFO  [main] util.Merge(255): Adding REGION => {NAME
=> 'TestMergeTool,row_0100,1245461930138', STARTKEY => 'row_0100', ENDKEY => 'row_0600',
ENCODED => 654978192, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME =>
'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE
=> '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME
=> '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE =>
{{NAME => '.META.', MEMCACHE_FLUSHSIZE => '16384', IS_META => 'true', FAMILIES =>
[{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL =>
'604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME
=> 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE
=> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:50,541 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1245461930138:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:50,541 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,541 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,542 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:50,542 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,542 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:50,542 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on -ROOT-,,0
    [junit] 2009-06-20 01:38:50,542 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region -ROOT-,,0
    [junit] 2009-06-20 01:38:50,543 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-20 01:38:50,543 INFO  [main] regionserver.HRegion(485): Closed -ROOT-,,0
    [junit] 2009-06-20 01:38:50,543 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:50,543 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on .META.,,1
    [junit] 2009-06-20 01:38:50,543 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region .META.,,1
    [junit] 2009-06-20 01:38:50,543 DEBUG [main] regionserver.HRegion(884): Started memcache
flush for region .META.,,1. Current region memcache size 1.2k
    [junit] 2009-06-20 01:38:50,586 DEBUG [main] regionserver.Store(526): Added hdfs://localhost:45601/user/hudson/.META./1028785192/historian/8261545689376471455,
entries=2, sequenceid=27, memsize=364.0, filesize=521.0 to .META.,,1
    [junit] 2009-06-20 01:38:50,630 DEBUG [main] regionserver.Store(526): Added hdfs://localhost:45601/user/hudson/.META./1028785192/info/5350495304047198328,
entries=3, sequenceid=27, memsize=844.0, filesize=874.0 to .META.,,1
    [junit] 2009-06-20 01:38:50,630 DEBUG [main] regionserver.HRegion(961): Finished memcache
flush of ~1.2k for region .META.,,1 in 87ms, sequence id=27, compaction requested=true
    [junit] 2009-06-20 01:38:50,630 DEBUG [main] regionserver.Store(445): closed historian
    [junit] 2009-06-20 01:38:50,630 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-20 01:38:50,631 INFO  [main] regionserver.HRegion(485): Closed .META.,,1
    [junit] 2009-06-20 01:38:50,667 INFO  [main] regionserver.HLog(299): Roll /user/hudson/.logs_1245461929495/hlog.dat.1245461929506,
entries=6, calcsize=1403, filesize=1151. New hlog /user/hudson/.logs_1245461929495/hlog.dat.1245461930650
    [junit] 2009-06-20 01:38:50,667 DEBUG [main] regionserver.HLog(308): Last sequence written
is empty. Deleting all old hlogs
    [junit] 2009-06-20 01:38:50,667 INFO  [main] regionserver.HLog(419): removing old hlog
file /user/hudson/.logs_1245461929495/hlog.dat.1245461929506 whose highest sequence/edit id
is 26
    [junit] 2009-06-20 01:38:50,682 DEBUG [main] regionserver.HLog(456): closing hlog writer
in hdfs://localhost:45601/user/hudson/.logs_1245461929495
    [junit] 2009-06-20 01:38:50,718 DEBUG [main] regionserver.HRegion(1832): Opening region:
REGION => {NAME => 'TestMergeTool,row_0100,1245461930138', STARTKEY => 'row_0100',
ENDKEY => 'row_0600', ENCODED => 654978192, TABLE => {{NAME => 'TestMergeTool',
FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL
=> '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:50,719 DEBUG [main] regionserver.HRegion(264): Opening region
TestMergeTool,row_0100,1245461930138, encoded=654978192
    [junit] 2009-06-20 01:38:50,726 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/654978192/contents/7514522130877583563,
isReference=false, sequence id=6, length=837, majorCompaction=false
    [junit] 2009-06-20 01:38:50,728 INFO  [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1245461930138/654978192
available; sequence id is 7
    [junit] 2009-06-20 01:38:50,732 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1245461930138:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:50,732 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,733 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,733 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:50,733 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:50,733 INFO  [main] util.TestMergeTool(187): Verified merging
regions 0+1+2 and 3
    [junit] 2009-06-20 01:38:50,733 INFO  [main] util.TestMergeTool(174): merging regions
0+1+2+3 and 4
    [junit] 2009-06-20 01:38:50,734 INFO  [main] util.Merge(82): Verifying that file system
is available...
    [junit] 2009-06-20 01:38:50,734 INFO  [main] util.Merge(91): Verifying that HBase is not
running...
    [junit] 2009-06-20 01:38:50,858 INFO  [main] util.Merge(197): Merging regions TestMergeTool,row_0100,1245461930138
and TestMergeTool,,1245461905473 in table TestMergeTool
    [junit] 2009-06-20 01:38:50,869 INFO  [main] regionserver.HLog(209): HLog configuration:
blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
    [junit] 2009-06-20 01:38:50,886 INFO  [main] regionserver.HLog(299): New hlog /user/hudson/.logs_1245461930858/hlog.dat.1245461930870
    [junit] 2009-06-20 01:38:50,886 DEBUG [main] regionserver.HRegion(1832): Opening region:
REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052,
TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', MEMCACHE_FLUSHSIZE => '16384',
IS_META => 'true', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS
=> '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}}
    [junit] 2009-06-20 01:38:50,886 DEBUG [main] regionserver.HRegion(264): Opening region
-ROOT-,,0, encoded=70236052
    [junit] 2009-06-20 01:38:50,893 DEBUG [main] regionserver.Store(380): loaded /user/hudson/-ROOT-/70236052/info/853794761231823289,
isReference=false, sequence id=2, length=840, majorCompaction=false
    [junit] 2009-06-20 01:38:50,895 INFO  [main] regionserver.HRegion(339): region -ROOT-,,0/70236052
available; sequence id is 3
    [junit] 2009-06-20 01:38:50,896 INFO  [main] regionserver.HRegion(759): Starting compaction
on region -ROOT-,,0
    [junit] 2009-06-20 01:38:50,898 INFO  [main] regionserver.HRegion(774): compaction completed
on region -ROOT-,,0 in 0sec
    [junit] 2009-06-20 01:38:50,898 INFO  [main] util.Merge(211): Found meta for region1 .META.,,1,
meta for region2 .META.,,1
    [junit] 2009-06-20 01:38:50,899 DEBUG [main] regionserver.HRegion(1832): Opening region:
REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192,
TABLE => {{NAME => '.META.', MEMCACHE_FLUSHSIZE => '16384', IS_META => 'true',
FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE',
TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'},
{NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647',
BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:50,899 DEBUG [main] regionserver.HRegion(264): Opening region
.META.,,1, encoded=1028785192
    [junit] 2009-06-20 01:38:50,917 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/2320670336416478592,
isReference=false, sequence id=20, length=651, majorCompaction=false
    [junit] 2009-06-20 01:38:50,921 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/8261545689376471455,
isReference=false, sequence id=27, length=521, majorCompaction=false
    [junit] 2009-06-20 01:38:50,927 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/5350495304047198328,
isReference=false, sequence id=27, length=874, majorCompaction=false
    [junit] 2009-06-20 01:38:50,931 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/7000255272075905158,
isReference=false, sequence id=6, length=2226, majorCompaction=false
    [junit] 2009-06-20 01:38:50,935 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/8290000493773518360,
isReference=false, sequence id=20, length=994, majorCompaction=false
    [junit] 2009-06-20 01:38:50,938 INFO  [main] regionserver.HRegion(339): region .META.,,1/1028785192
available; sequence id is 28
    [junit] 2009-06-20 01:38:50,938 INFO  [main] regionserver.HRegion(759): Starting compaction
on region .META.,,1
    [junit] 2009-06-20 01:38:50,949 DEBUG [main] regionserver.Store(704): Compaction size
of historian: 1.1k; Skipped 0 file(s), size: 0
    [junit] 2009-06-20 01:38:50,969 DEBUG [main] regionserver.Store(713): Started compaction
of 2 file(s)  into /user/hudson/.META./compaction.dir/1028785192/6884886592643587576
    [junit] 2009-06-20 01:38:51,041 DEBUG [main] regionserver.Store(728): Completed compaction
of historian; store size is 781.0
    [junit] 2009-06-20 01:38:51,043 DEBUG [main] regionserver.Store(704): Compaction size
of info: 4.0k; Skipped 1 file(s), size: 2226
    [junit] 2009-06-20 01:38:51,061 DEBUG [main] regionserver.Store(713): Started compaction
of 2 file(s)  into /user/hudson/.META./compaction.dir/1028785192/6883671398272431349
    [junit] 2009-06-20 01:38:51,137 DEBUG [main] regionserver.Store(728): Completed compaction
of info; store size is 3.3k
    [junit] 2009-06-20 01:38:51,152 INFO  [main] regionserver.HRegion(774): compaction completed
on region .META.,,1 in 0sec
    [junit] 2009-06-20 01:38:51,154 DEBUG [main] regionserver.HRegion(1832): Opening region:
REGION => {NAME => 'TestMergeTool,row_0100,1245461930138', STARTKEY => 'row_0100',
ENDKEY => 'row_0600', ENCODED => 654978192, TABLE => {{NAME => 'TestMergeTool',
FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL
=> '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:51,155 DEBUG [main] regionserver.HRegion(264): Opening region
TestMergeTool,row_0100,1245461930138, encoded=654978192
    [junit] 2009-06-20 01:38:51,161 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/654978192/contents/7514522130877583563,
isReference=false, sequence id=6, length=837, majorCompaction=false
    [junit] 2009-06-20 01:38:51,163 INFO  [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1245461930138/654978192
available; sequence id is 7
    [junit] 2009-06-20 01:38:51,164 DEBUG [main] regionserver.HRegion(1832): Opening region:
REGION => {NAME => 'TestMergeTool,,1245461905473', STARTKEY => '', ENDKEY => '',
ENCODED => 772122477, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME =>
'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE
=> '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:51,164 DEBUG [main] regionserver.HRegion(264): Opening region
TestMergeTool,,1245461905473, encoded=772122477
    [junit] 2009-06-20 01:38:51,170 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/772122477/contents/1873277992399485024,
isReference=false, sequence id=7, length=471, majorCompaction=false
    [junit] 2009-06-20 01:38:51,173 INFO  [main] regionserver.HRegion(339): region TestMergeTool,,1245461905473/772122477
available; sequence id is 8
    [junit] 2009-06-20 01:38:51,173 INFO  [main] regionserver.HRegion(759): Starting major
compaction on region TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:51,204 DEBUG [main] regionserver.Store(713): Started compaction
of 1 file(s)  into /user/hudson/TestMergeTool/compaction.dir/654978192/2685906261636666943
    [junit] 2009-06-20 01:38:51,359 DEBUG [main] regionserver.Store(728): Completed major
compaction of contents; store size is 837.0
    [junit] 2009-06-20 01:38:51,373 INFO  [main] regionserver.HRegion(774): compaction completed
on region TestMergeTool,row_0100,1245461930138 in 0sec
    [junit] 2009-06-20 01:38:51,373 DEBUG [main] regionserver.HRegion(2071): Files for region:
TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:51,374 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/.regioninfo
size=631
    [junit] 2009-06-20 01:38:51,375 DEBUG [main] regionserver.HRegion(2215): d hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/contents
    [junit] 2009-06-20 01:38:51,375 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/654978192/contents/6336703247078949847
size=837
    [junit] 2009-06-20 01:38:51,376 INFO  [main] regionserver.HRegion(759): Starting major
compaction on region TestMergeTool,,1245461905473
    [junit] 2009-06-20 01:38:51,409 DEBUG [main] regionserver.Store(713): Started compaction
of 1 file(s)  into /user/hudson/TestMergeTool/compaction.dir/772122477/1464605569637384704
    [junit] 2009-06-20 01:38:51,465 DEBUG [main] regionserver.Store(728): Completed major
compaction of contents; store size is 471.0
    [junit] 2009-06-20 01:38:51,481 INFO  [main] regionserver.HRegion(774): compaction completed
on region TestMergeTool,,1245461905473 in 0sec
    [junit] 2009-06-20 01:38:51,481 DEBUG [main] regionserver.HRegion(2076): Files for region:
TestMergeTool,,1245461905473
    [junit] 2009-06-20 01:38:51,482 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/772122477/.regioninfo
size=583
    [junit] 2009-06-20 01:38:51,482 DEBUG [main] regionserver.HRegion(2215): d hdfs://localhost:45601/user/hudson/TestMergeTool/772122477/contents
    [junit] 2009-06-20 01:38:51,483 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/772122477/contents/6710208802662424848
size=471
    [junit] 2009-06-20 01:38:51,484 INFO  [main] regionserver.HRegion(2105): Creating new
region REGION => {NAME => 'TestMergeTool,,1245461931483', STARTKEY => '', ENDKEY
=> '', ENCODED => 2027697173, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
[{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:51,501 INFO  [main] regionserver.HRegion(2114): starting merge
of regions: TestMergeTool,row_0100,1245461930138 and TestMergeTool,,1245461905473 into new
region REGION => {NAME => 'TestMergeTool,,1245461931483', STARTKEY => '', ENDKEY
=> '', ENCODED => 2027697173, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
[{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key
<> and end key <>
    [junit] 2009-06-20 01:38:51,501 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1245461930138:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:51,501 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:51,501 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:51,501 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:51,502 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1245461930138
    [junit] 2009-06-20 01:38:51,502 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1245461905473:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:51,502 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,,1245461905473
    [junit] 2009-06-20 01:38:51,502 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,,1245461905473
    [junit] 2009-06-20 01:38:51,502 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:51,503 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,,1245461905473
    [junit] 2009-06-20 01:38:51,552 DEBUG [main] regionserver.HRegion(2147): Files for new
region
    [junit] 2009-06-20 01:38:51,553 DEBUG [main] regionserver.HRegion(2215): d hdfs://localhost:45601/user/hudson/TestMergeTool/2027697173/contents
    [junit] 2009-06-20 01:38:51,554 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/2027697173/contents/3921038687188742463
size=837
    [junit] 2009-06-20 01:38:51,554 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/2027697173/contents/870453743262587577
size=471
    [junit] 2009-06-20 01:38:51,555 DEBUG [main] regionserver.HRegion(264): Opening region
TestMergeTool,,1245461931483, encoded=2027697173
    [junit] 2009-06-20 01:38:51,597 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/2027697173/contents/3921038687188742463,
isReference=false, sequence id=6, length=837, majorCompaction=true
    [junit] 2009-06-20 01:38:51,601 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/2027697173/contents/870453743262587577,
isReference=false, sequence id=7, length=471, majorCompaction=true
    [junit] 2009-06-20 01:38:51,608 INFO  [main] regionserver.HRegion(339): region TestMergeTool,,1245461931483/2027697173
available; sequence id is 8
    [junit] 2009-06-20 01:38:51,608 INFO  [main] regionserver.HRegion(759): Starting compaction
on region TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,624 DEBUG [main] regionserver.Store(704): Compaction size
of contents: 1.3k; Skipped 0 file(s), size: 0
    [junit] 2009-06-20 01:38:51,640 DEBUG [main] regionserver.Store(713): Started compaction
of 2 file(s)  into /user/hudson/TestMergeTool/compaction.dir/2027697173/7015473618164012585
    [junit] 2009-06-20 01:38:51,720 DEBUG [main] regionserver.Store(728): Completed compaction
of contents; store size is 975.0
    [junit] 2009-06-20 01:38:51,735 INFO  [main] regionserver.HRegion(774): compaction completed
on region TestMergeTool,,1245461931483 in 0sec
    [junit] 2009-06-20 01:38:51,736 DEBUG [main] regionserver.HRegion(2154): Files for new
region
    [junit] 2009-06-20 01:38:51,737 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/2027697173/.regioninfo
size=584
    [junit] 2009-06-20 01:38:51,737 DEBUG [main] regionserver.HRegion(2215): d hdfs://localhost:45601/user/hudson/TestMergeTool/2027697173/contents
    [junit] 2009-06-20 01:38:51,738 DEBUG [main] regionserver.HRegion(2218): f hdfs://localhost:45601/user/hudson/TestMergeTool/2027697173/contents/7130453815428263652
size=975
    [junit] 2009-06-20 01:38:51,738 DEBUG [main] regionserver.HRegion(1950): DELETING region
hdfs://localhost:45601/user/hudson/TestMergeTool/654978192
    [junit] 2009-06-20 01:38:51,751 DEBUG [main] regionserver.HRegion(1950): DELETING region
hdfs://localhost:45601/user/hudson/TestMergeTool/772122477
    [junit] 2009-06-20 01:38:51,767 INFO  [main] regionserver.HRegion(2160): merge completed.
New region is TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,768 DEBUG [main] util.Merge(319): Removing region: REGION
=> {NAME => 'TestMergeTool,row_0100,1245461930138', STARTKEY => 'row_0100', ENDKEY
=> 'row_0600', ENCODED => 654978192, TABLE => {{NAME => 'TestMergeTool', FAMILIES
=> [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
    [junit] 2009-06-20 01:38:51,768 DEBUG [main] util.Merge(319): Removing region: REGION
=> {NAME => 'TestMergeTool,,1245461905473', STARTKEY => '', ENDKEY => '', ENCODED
=> 772122477, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents',
VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536',
IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
    [junit] 2009-06-20 01:38:51,770 INFO  [main] util.Merge(255): Adding REGION => {NAME
=> 'TestMergeTool,,1245461931483', STARTKEY => '', ENDKEY => '', ENCODED => 2027697173,
TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION
=> 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY
=> 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY
=> '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', MEMCACHE_FLUSHSIZE
=> '16384', IS_META => 'true', FAMILIES => [{NAME => 'historian', VERSIONS =>
'2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY
=> 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION
=> 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE
=> 'true'}]}}
    [junit] 2009-06-20 01:38:51,770 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1245461931483:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:51,770 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,770 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,771 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:51,771 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,771 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:51,771 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on -ROOT-,,0
    [junit] 2009-06-20 01:38:51,771 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region -ROOT-,,0
    [junit] 2009-06-20 01:38:51,772 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-20 01:38:51,772 INFO  [main] regionserver.HRegion(485): Closed -ROOT-,,0
    [junit] 2009-06-20 01:38:51,772 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:51,772 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on .META.,,1
    [junit] 2009-06-20 01:38:51,772 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region .META.,,1
    [junit] 2009-06-20 01:38:51,772 DEBUG [main] regionserver.HRegion(884): Started memcache
flush for region .META.,,1. Current region memcache size 1.1k
    [junit] 2009-06-20 01:38:51,810 DEBUG [main] regionserver.Store(526): Added hdfs://localhost:45601/user/hudson/.META./1028785192/historian/857765960345819639,
entries=2, sequenceid=34, memsize=356.0, filesize=505.0 to .META.,,1
    [junit] 2009-06-20 01:38:51,850 DEBUG [main] regionserver.Store(526): Added hdfs://localhost:45601/user/hudson/.META./1028785192/info/3949892496175236793,
entries=3, sequenceid=34, memsize=804.0, filesize=826.0 to .META.,,1
    [junit] 2009-06-20 01:38:51,850 DEBUG [main] regionserver.HRegion(961): Finished memcache
flush of ~1.1k for region .META.,,1 in 78ms, sequence id=34, compaction requested=true
    [junit] 2009-06-20 01:38:51,851 DEBUG [main] regionserver.Store(445): closed historian
    [junit] 2009-06-20 01:38:51,851 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-20 01:38:51,851 INFO  [main] regionserver.HRegion(485): Closed .META.,,1
    [junit] 2009-06-20 01:38:51,884 INFO  [main] regionserver.HLog(299): Roll /user/hudson/.logs_1245461930858/hlog.dat.1245461930870,
entries=6, calcsize=1355, filesize=1103. New hlog /user/hudson/.logs_1245461930858/hlog.dat.1245461931867
    [junit] 2009-06-20 01:38:51,884 DEBUG [main] regionserver.HLog(308): Last sequence written
is empty. Deleting all old hlogs
    [junit] 2009-06-20 01:38:51,884 INFO  [main] regionserver.HLog(419): removing old hlog
file /user/hudson/.logs_1245461930858/hlog.dat.1245461930870 whose highest sequence/edit id
is 33
    [junit] 2009-06-20 01:38:51,899 DEBUG [main] regionserver.HLog(456): closing hlog writer
in hdfs://localhost:45601/user/hudson/.logs_1245461930858
    [junit] 2009-06-20 01:38:51,931 DEBUG [main] regionserver.HRegion(1832): Opening region:
REGION => {NAME => 'TestMergeTool,,1245461931483', STARTKEY => '', ENDKEY => '',
ENCODED => 2027697173, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME
=> 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE
=> '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
    [junit] 2009-06-20 01:38:51,931 DEBUG [main] regionserver.HRegion(264): Opening region
TestMergeTool,,1245461931483, encoded=2027697173
    [junit] 2009-06-20 01:38:51,937 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/2027697173/contents/7130453815428263652,
isReference=false, sequence id=7, length=975, majorCompaction=false
    [junit] 2009-06-20 01:38:51,940 INFO  [main] regionserver.HRegion(339): region TestMergeTool,,1245461931483/2027697173
available; sequence id is 8
    [junit] 2009-06-20 01:38:51,945 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1245461931483:
compactions & flushes disabled 
    [junit] 2009-06-20 01:38:51,945 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,945 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,945 DEBUG [main] regionserver.Store(445): closed contents
    [junit] 2009-06-20 01:38:51,945 INFO  [main] regionserver.HRegion(485): Closed TestMergeTool,,1245461931483
    [junit] 2009-06-20 01:38:51,946 INFO  [main] util.TestMergeTool(187): Verified merging
regions 0+1+2+3 and 4
    [junit] 2009-06-20 01:38:51,946 DEBUG [main] regionserver.HLog(456): closing hlog writer
in /tmp/.logs_1245461926912
    [junit] 2009-06-20 01:38:52,003 INFO  [main] hbase.HBaseTestCase(626): Shutting down FileSystem
    [junit] 2009-06-20 01:38:52,003 INFO  [main] hbase.HBaseTestCase(633): Shutting down Mini
DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-06-20 01:38:52,112 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@151b0a5]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:40417, storageID=DS-1574919284-67.195.138.9-40417-1245461924216,
infoPort=34717, ipcPort=58311):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] Shutting down DataNode 0
    [junit] 2009-06-20 01:38:53,220 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1a2f02e]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:50485, storageID=DS-2043649810-67.195.138.9-50485-1245461923521,
infoPort=54540, ipcPort=48408):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2009-06-20 01:38:54,423 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@193f6e2]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 29.043 sec
    [junit] Running org.apache.hadoop.hbase.util.TestRootPath
    [junit] 2009-06-20 01:38:54,808 INFO  [main] util.TestRootPath(60): Got expected exception
when checking invalid path:
    [junit] java.io.IOException: Root directory does not contain a scheme
    [junit] 	at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:212)
    [junit] 	at org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    [junit] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    [junit] 	at java.lang.reflect.Method.invoke(Method.java:597)
    [junit] 	at junit.framework.TestCase.runTest(TestCase.java:154)
    [junit] 	at junit.framework.TestCase.runBare(TestCase.java:127)
    [junit] 	at junit.framework.TestResult$1.protect(TestResult.java:106)
    [junit] 	at junit.framework.TestResult.runProtected(TestResult.java:124)
    [junit] 	at junit.framework.TestResult.run(TestResult.java:109)
    [junit] 	at junit.framework.TestCase.run(TestCase.java:118)
    [junit] 	at junit.framework.TestSuite.runTest(TestSuite.java:208)
    [junit] 	at junit.framework.TestSuite.run(TestSuite.java:203)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.072 sec

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :461: Tests failed!

Total time: 43 minutes 24 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...


Mime
View raw message