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 #564
Date Fri, 24 Apr 2009 01:51:20 GMT
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/564/changes

Changes:

[stack] HBASE-1340 Fix new javadoc warnings

[stack] HBASE-1292  php thrift's getRow() would throw an exception if the row does not exist

[stack] HBASE-1292  php thrift's getRow() would throw an exception if the row does not exist

[stack] HBASE-1176  Javadocs in HBA should be clear about which functions are asynchronous
and which are synchronous

[apurtell] HBASE-1058 Prevent runaway compactions

[stack] HBASE-1301 HTable.getRow() returns null if the row does no exist

[stack] HBASE-1195  If HBase directory exists but version file is inexistent, still proceed
with bootstrapping

------------------------------------------
[...truncated 22919 lines...]
    [junit] 2009-04-24 02:07:07,028 DEBUG [main] util.Merge(310): Removing region: REGION
=> {NAME => 'TestMergeTool,row_0100,1240538826597', STARTKEY => 'row_0100', ENDKEY
=> 'row_0400', ENCODED => 947639782, 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-04-24 02:07:07,031 DEBUG [main] util.Merge(310): Removing region: REGION
=> {NAME => 'TestMergeTool,row_0500,1240538792926', STARTKEY => 'row_0500', ENDKEY
=> 'row_0600', ENCODED => 1316701608, 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-04-24 02:07:07,035 INFO  [main] util.Merge(246): Adding REGION => {NAME
=> 'TestMergeTool,row_0100,1240538826976', STARTKEY => 'row_0100', ENDKEY => 'row_0600',
ENCODED => 245898640, 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 => 'historian', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION
=> 'NONE', LENGTH => '2147483647', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY
=> 'false', BLOCKCACHE => 'false'}, {NAME => 'info', BLOOMFILTER => 'false', VERSIONS
=> '10', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE
=> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}], INDEXES => []}}
    [junit] 2009-04-24 02:07:07,036 DEBUG [main] regionserver.HRegion(386): Closing TestMergeTool,row_0100,1240538826976:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,036 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,036 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,036 DEBUG [main] regionserver.Store(420): closed contents
    [junit] 2009-04-24 02:07:07,036 INFO  [main] regionserver.HRegion(435): Closed TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,037 DEBUG [main] regionserver.HRegion(386): Closing -ROOT-,,0:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,037 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on -ROOT-,,0
    [junit] 2009-04-24 02:07:07,037 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region -ROOT-,,0
    [junit] 2009-04-24 02:07:07,037 DEBUG [main] regionserver.Store(420): closed info
    [junit] 2009-04-24 02:07:07,037 INFO  [main] regionserver.HRegion(435): Closed -ROOT-,,0
    [junit] 2009-04-24 02:07:07,037 DEBUG [main] regionserver.HRegion(386): Closing .META.,,1:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,038 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on .META.,,1
    [junit] 2009-04-24 02:07:07,038 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region .META.,,1
    [junit] 2009-04-24 02:07:07,038 DEBUG [main] regionserver.HRegion(829): Started memcache
flush for region .META.,,1. Current region memcache size 885.0
    [junit] 2009-04-24 02:07:07,052 DEBUG [main] regionserver.Store(500): Added hdfs://localhost:50678/user/hudson/.META./1028785192/info/8227149467936906333,
entries=3, sequenceid=17, memsize=885.0, filesize=935.0 to .META.,,1
    [junit] 2009-04-24 02:07:07,052 DEBUG [main] regionserver.HRegion(906): Finished memcache
flush of ~885.0 for region .META.,,1 in 14ms, sequence id=17, compaction requested=true
    [junit] 2009-04-24 02:07:07,052 DEBUG [main] regionserver.Store(420): closed historian
    [junit] 2009-04-24 02:07:07,052 DEBUG [main] regionserver.Store(420): closed info
    [junit] 2009-04-24 02:07:07,053 INFO  [main] regionserver.HRegion(435): Closed .META.,,1
    [junit] 2009-04-24 02:07:07,060 INFO  [main] regionserver.HLog(274): Closed hdfs://localhost:50678/user/hudson/log_1240538826832/hlog.dat.0,
entries=4. New log writer: /user/hudson/log_1240538826832/hlog.dat.1240538827058
    [junit] 2009-04-24 02:07:07,060 DEBUG [main] regionserver.HLog(281): Last sequence written
is empty. Deleting all old hlogs
    [junit] 2009-04-24 02:07:07,060 INFO  [main] regionserver.HLog(392): removing old log
file /user/hudson/log_1240538826832/hlog.dat.0 whose highest sequence/edit id is 17
    [junit] 2009-04-24 02:07:07,061 DEBUG [main] regionserver.HLog(428): closing log writer
in hdfs://localhost:50678/user/hudson/log_1240538826832
    [junit] 2009-04-24 02:07:07,068 DEBUG [main] regionserver.HRegion(2215): Opening region:
REGION => {NAME => 'TestMergeTool,row_0100,1240538826976', STARTKEY => 'row_0100',
ENDKEY => 'row_0600', ENCODED => 245898640, 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-04-24 02:07:07,069 DEBUG [main] regionserver.HRegion(238): Opening region
TestMergeTool,row_0100,1240538826976/245898640
    [junit] 2009-04-24 02:07:07,074 DEBUG [main] regionserver.Store(370): loaded /user/hudson/TestMergeTool/245898640/contents/3072182658793563304,
isReference=false, sequence id=5, length=811, majorCompaction=false
    [junit] 2009-04-24 02:07:07,074 DEBUG [main] regionserver.Store(208): Loaded 1 file(s)
in Store contents, max sequence id 5
    [junit] 2009-04-24 02:07:07,075 DEBUG [main] regionserver.HRegion(298): Next sequence
id for region TestMergeTool,row_0100,1240538826976 is 6
    [junit] 2009-04-24 02:07:07,076 INFO  [main] regionserver.HRegion(315): region TestMergeTool,row_0100,1240538826976/245898640
available
    [junit] 2009-04-24 02:07:07,076 DEBUG [main] regionserver.HLog(218): changing sequence
number from 5 to 6
    [junit] 2009-04-24 02:07:07,091 DEBUG [main] regionserver.HRegion(386): Closing TestMergeTool,row_0100,1240538826976:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,091 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,091 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,091 DEBUG [main] regionserver.Store(420): closed contents
    [junit] 2009-04-24 02:07:07,091 INFO  [main] regionserver.HRegion(435): Closed TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,091 INFO  [main] util.TestMergeTool(170): Verified merging
regions 0+1+2 and 3
    [junit] 2009-04-24 02:07:07,092 INFO  [main] util.TestMergeTool(157): merging regions
0+1+2+3 and 4
    [junit] 2009-04-24 02:07:07,092 INFO  [main] util.Merge(80): Verifying that file system
is available...
    [junit] 2009-04-24 02:07:07,093 INFO  [main] util.Merge(89): Verifying that HBase is not
running...
    [junit] 2009-04-24 02:07:07,204 INFO  [main] util.Merge(192): Merging regions TestMergeTool,row_0100,1240538826976
and TestMergeTool,,1240538792926 in table TestMergeTool
    [junit] 2009-04-24 02:07:07,207 INFO  [main] regionserver.HLog(274): New log writer: /user/hudson/log_1240538827204/hlog.dat.1240538827205
    [junit] 2009-04-24 02:07:07,207 DEBUG [main] regionserver.HRegion(2215): 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-04-24 02:07:07,207 DEBUG [main] regionserver.HRegion(238): Opening region
-ROOT-,,0/70236052
    [junit] 2009-04-24 02:07:07,212 DEBUG [main] regionserver.Store(370): loaded /user/hudson/-ROOT-/70236052/info/4739174394380191085,
isReference=false, sequence id=1, length=853, majorCompaction=false
    [junit] 2009-04-24 02:07:07,212 DEBUG [main] regionserver.Store(208): Loaded 1 file(s)
in Store info, max sequence id 1
    [junit] 2009-04-24 02:07:07,213 DEBUG [main] regionserver.HRegion(298): Next sequence
id for region -ROOT-,,0 is 2
    [junit] 2009-04-24 02:07:07,215 INFO  [main] regionserver.HRegion(315): region -ROOT-,,0/70236052
available
    [junit] 2009-04-24 02:07:07,215 DEBUG [main] regionserver.HLog(218): changing sequence
number from 0 to 2
    [junit] 2009-04-24 02:07:07,215 INFO  [main] regionserver.HRegion(704): starting  compaction
on region -ROOT-,,0
    [junit] 2009-04-24 02:07:07,216 INFO  [main] regionserver.HRegion(719): compaction completed
on region -ROOT-,,0 in 0sec
    [junit] 2009-04-24 02:07:07,218 INFO  [main] util.Merge(206): Found meta for region1 .META.,,1,
meta for region2 .META.,,1
    [junit] 2009-04-24 02:07:07,218 DEBUG [main] regionserver.HRegion(2215): Opening region:
REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192,
TABLE => {{NAME => '.META.', IS_ROOT => 'false', IS_META => 'true', FAMILIES =>
[{NAME => 'historian', BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION
=> 'NONE', LENGTH => '2147483647', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY
=> 'false', BLOCKCACHE => 'false'}, {NAME => 'info', BLOOMFILTER => 'false', VERSIONS
=> '10', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE
=> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}], INDEXES => []}}
    [junit] 2009-04-24 02:07:07,219 DEBUG [main] regionserver.HRegion(238): Opening region
.META.,,1/1028785192
    [junit] 2009-04-24 02:07:07,225 DEBUG [main] regionserver.Store(370): loaded /user/hudson/.META./1028785192/info/2784029140206536531,
isReference=false, sequence id=13, length=1469, majorCompaction=false
    [junit] 2009-04-24 02:07:07,228 DEBUG [main] regionserver.Store(370): loaded /user/hudson/.META./1028785192/info/8227149467936906333,
isReference=false, sequence id=17, length=935, majorCompaction=false
    [junit] 2009-04-24 02:07:07,232 DEBUG [main] regionserver.Store(370): loaded /user/hudson/.META./1028785192/info/8615973274919675294,
isReference=false, sequence id=5, length=2331, majorCompaction=false
    [junit] 2009-04-24 02:07:07,232 DEBUG [main] regionserver.Store(208): Loaded 3 file(s)
in Store info, max sequence id 17
    [junit] 2009-04-24 02:07:07,233 DEBUG [main] regionserver.HRegion(298): Next sequence
id for region .META.,,1 is 18
    [junit] 2009-04-24 02:07:07,234 INFO  [main] regionserver.HRegion(315): region .META.,,1/1028785192
available
    [junit] 2009-04-24 02:07:07,235 DEBUG [main] regionserver.HLog(218): changing sequence
number from 2 to 18
    [junit] 2009-04-24 02:07:07,235 INFO  [main] regionserver.HRegion(704): starting  compaction
on region .META.,,1
    [junit] 2009-04-24 02:07:07,236 DEBUG [main] regionserver.Store(612): historian: no store
files to compact
    [junit] 2009-04-24 02:07:07,237 DEBUG [main] regionserver.Store(676): Compaction size
of info: 4.6k; Skipped 0 file(s), size: 0
    [junit] 2009-04-24 02:07:07,241 DEBUG [main] regionserver.Store(685): Started compaction
of 3 file(s)  into /user/hudson/.META./compaction.dir/1336958816918590491
    [junit] 2009-04-24 02:07:07,262 DEBUG [main] regionserver.Store(700): Completed  compaction
of info store size is 3.8k
    [junit] 2009-04-24 02:07:07,263 INFO  [main] regionserver.HRegion(719): compaction completed
on region .META.,,1 in 0sec
    [junit] 2009-04-24 02:07:07,266 DEBUG [main] regionserver.HRegion(2215): Opening region:
REGION => {NAME => 'TestMergeTool,row_0100,1240538826976', STARTKEY => 'row_0100',
ENDKEY => 'row_0600', ENCODED => 245898640, 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-04-24 02:07:07,266 DEBUG [main] regionserver.HRegion(238): Opening region
TestMergeTool,row_0100,1240538826976/245898640
    [junit] 2009-04-24 02:07:07,271 DEBUG [main] regionserver.Store(370): loaded /user/hudson/TestMergeTool/245898640/contents/3072182658793563304,
isReference=false, sequence id=5, length=811, majorCompaction=false
    [junit] 2009-04-24 02:07:07,271 DEBUG [main] regionserver.Store(208): Loaded 1 file(s)
in Store contents, max sequence id 5
    [junit] 2009-04-24 02:07:07,272 DEBUG [main] regionserver.HRegion(298): Next sequence
id for region TestMergeTool,row_0100,1240538826976 is 6
    [junit] 2009-04-24 02:07:07,273 INFO  [main] regionserver.HRegion(315): region TestMergeTool,row_0100,1240538826976/245898640
available
    [junit] 2009-04-24 02:07:07,273 DEBUG [main] regionserver.HRegion(2215): Opening region:
REGION => {NAME => 'TestMergeTool,,1240538792926', STARTKEY => '', ENDKEY => '',
ENCODED => 1139877762, 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-04-24 02:07:07,274 DEBUG [main] regionserver.HRegion(238): Opening region
TestMergeTool,,1240538792926/1139877762
    [junit] 2009-04-24 02:07:07,279 DEBUG [main] regionserver.Store(370): loaded /user/hudson/TestMergeTool/1139877762/contents/2218436514707416309,
isReference=false, sequence id=6, length=461, majorCompaction=false
    [junit] 2009-04-24 02:07:07,279 DEBUG [main] regionserver.Store(208): Loaded 1 file(s)
in Store contents, max sequence id 6
    [junit] 2009-04-24 02:07:07,280 DEBUG [main] regionserver.HRegion(298): Next sequence
id for region TestMergeTool,,1240538792926 is 7
    [junit] 2009-04-24 02:07:07,281 INFO  [main] regionserver.HRegion(315): region TestMergeTool,,1240538792926/1139877762
available
    [junit] 2009-04-24 02:07:07,281 INFO  [main] regionserver.HRegion(704): starting major
compaction on region TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,285 DEBUG [main] regionserver.Store(685): Started compaction
of 1 file(s)  into /user/hudson/TestMergeTool/compaction.dir/6595219596512179001
    [junit] 2009-04-24 02:07:07,306 DEBUG [main] regionserver.Store(700): Completed major
compaction of contents store size is 811.0
    [junit] 2009-04-24 02:07:07,306 INFO  [main] regionserver.HRegion(719): compaction completed
on region TestMergeTool,row_0100,1240538826976 in 0sec
    [junit] 2009-04-24 02:07:07,307 DEBUG [main] regionserver.HRegion(2451): Files for region:
TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,307 DEBUG [main] regionserver.HRegion(2604): d hdfs://localhost:50678/user/hudson/TestMergeTool/245898640/contents
    [junit] 2009-04-24 02:07:07,308 DEBUG [main] regionserver.HRegion(2607): f hdfs://localhost:50678/user/hudson/TestMergeTool/245898640/contents/4680167572884973554
size=811
    [junit] 2009-04-24 02:07:07,308 INFO  [main] regionserver.HRegion(704): starting major
compaction on region TestMergeTool,,1240538792926
    [junit] 2009-04-24 02:07:07,313 DEBUG [main] regionserver.Store(685): Started compaction
of 1 file(s)  into /user/hudson/TestMergeTool/compaction.dir/402654388156133046
    [junit] 2009-04-24 02:07:07,337 DEBUG [main] regionserver.Store(700): Completed major
compaction of contents store size is 461.0
    [junit] 2009-04-24 02:07:07,337 INFO  [main] regionserver.HRegion(719): compaction completed
on region TestMergeTool,,1240538792926 in 0sec
    [junit] 2009-04-24 02:07:07,337 DEBUG [main] regionserver.HRegion(2456): Files for region:
TestMergeTool,,1240538792926
    [junit] 2009-04-24 02:07:07,338 DEBUG [main] regionserver.HRegion(2604): d hdfs://localhost:50678/user/hudson/TestMergeTool/1139877762/contents
    [junit] 2009-04-24 02:07:07,339 DEBUG [main] regionserver.HRegion(2607): f hdfs://localhost:50678/user/hudson/TestMergeTool/1139877762/contents/7808454741023482560
size=461
    [junit] 2009-04-24 02:07:07,339 INFO  [main] regionserver.HRegion(2485): Creating new
region REGION => {NAME => 'TestMergeTool,,1240538827339', STARTKEY => '', ENDKEY
=> '', ENCODED => 1202107463, 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-04-24 02:07:07,345 INFO  [main] regionserver.HRegion(2494): starting merge
of regions: TestMergeTool,row_0100,1240538826976 and TestMergeTool,,1240538792926 into new
region REGION => {NAME => 'TestMergeTool,,1240538827339', STARTKEY => '', ENDKEY
=> '', ENCODED => 1202107463, 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 <> and end key <>
    [junit] 2009-04-24 02:07:07,345 DEBUG [main] regionserver.HRegion(386): Closing TestMergeTool,row_0100,1240538826976:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,345 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,345 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,345 DEBUG [main] regionserver.Store(420): closed contents
    [junit] 2009-04-24 02:07:07,346 INFO  [main] regionserver.HRegion(435): Closed TestMergeTool,row_0100,1240538826976
    [junit] 2009-04-24 02:07:07,346 DEBUG [main] regionserver.HRegion(386): Closing TestMergeTool,,1240538792926:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,346 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on TestMergeTool,,1240538792926
    [junit] 2009-04-24 02:07:07,346 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region TestMergeTool,,1240538792926
    [junit] 2009-04-24 02:07:07,346 DEBUG [main] regionserver.Store(420): closed contents
    [junit] 2009-04-24 02:07:07,347 INFO  [main] regionserver.HRegion(435): Closed TestMergeTool,,1240538792926
    [junit] 2009-04-24 02:07:07,368 DEBUG [main] regionserver.HRegion(2529): Files for new
region
    [junit] 2009-04-24 02:07:07,369 DEBUG [main] regionserver.HRegion(2604): d hdfs://localhost:50678/user/hudson/TestMergeTool/1202107463/contents
    [junit] 2009-04-24 02:07:07,370 DEBUG [main] regionserver.HRegion(2607): f hdfs://localhost:50678/user/hudson/TestMergeTool/1202107463/contents/6145851772574824752
size=811
    [junit] 2009-04-24 02:07:07,370 DEBUG [main] regionserver.HRegion(2607): f hdfs://localhost:50678/user/hudson/TestMergeTool/1202107463/contents/7750274733007801249
size=461
    [junit] 2009-04-24 02:07:07,371 DEBUG [main] regionserver.HRegion(238): Opening region
TestMergeTool,,1240538827339/1202107463
    [junit] 2009-04-24 02:07:07,376 DEBUG [main] regionserver.Store(370): loaded /user/hudson/TestMergeTool/1202107463/contents/6145851772574824752,
isReference=false, sequence id=5, length=811, majorCompaction=true
    [junit] 2009-04-24 02:07:07,379 DEBUG [main] regionserver.Store(370): loaded /user/hudson/TestMergeTool/1202107463/contents/7750274733007801249,
isReference=false, sequence id=6, length=461, majorCompaction=true
    [junit] 2009-04-24 02:07:07,380 DEBUG [main] regionserver.Store(208): Loaded 2 file(s)
in Store contents, max sequence id 6
    [junit] 2009-04-24 02:07:07,381 DEBUG [main] regionserver.HRegion(298): Next sequence
id for region TestMergeTool,,1240538827339 is 7
    [junit] 2009-04-24 02:07:07,382 INFO  [main] regionserver.HRegion(315): region TestMergeTool,,1240538827339/1202107463
available
    [junit] 2009-04-24 02:07:07,382 INFO  [main] regionserver.HRegion(704): starting  compaction
on region TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,384 DEBUG [main] regionserver.Store(676): Compaction size
of contents: 1.2k; Skipped 0 file(s), size: 0
    [junit] 2009-04-24 02:07:07,387 DEBUG [main] regionserver.Store(685): Started compaction
of 2 file(s)  into /user/hudson/TestMergeTool/compaction.dir/3820951474651887165
    [junit] 2009-04-24 02:07:07,405 DEBUG [main] regionserver.Store(700): Completed  compaction
of contents store size is 943.0
    [junit] 2009-04-24 02:07:07,406 INFO  [main] regionserver.HRegion(719): compaction completed
on region TestMergeTool,,1240538827339 in 0sec
    [junit] 2009-04-24 02:07:07,407 DEBUG [main] regionserver.HRegion(2536): Files for new
region
    [junit] 2009-04-24 02:07:07,408 DEBUG [main] regionserver.HRegion(2604): d hdfs://localhost:50678/user/hudson/TestMergeTool/1202107463/contents
    [junit] 2009-04-24 02:07:07,408 DEBUG [main] regionserver.HRegion(2607): f hdfs://localhost:50678/user/hudson/TestMergeTool/1202107463/contents/6685395220471740125
size=943
    [junit] 2009-04-24 02:07:07,409 DEBUG [main] regionserver.HRegion(2330): DELETING region
hdfs://localhost:50678/user/hudson/TestMergeTool/245898640
    [junit] 2009-04-24 02:07:07,409 DEBUG [main] regionserver.HRegion(2330): DELETING region
hdfs://localhost:50678/user/hudson/TestMergeTool/1139877762
    [junit] 2009-04-24 02:07:07,410 INFO  [main] regionserver.HRegion(2542): merge completed.
New region is TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,411 DEBUG [main] util.Merge(310): Removing region: REGION
=> {NAME => 'TestMergeTool,row_0100,1240538826976', STARTKEY => 'row_0100', ENDKEY
=> 'row_0600', ENCODED => 245898640, 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-04-24 02:07:07,412 DEBUG [main] util.Merge(310): Removing region: REGION
=> {NAME => 'TestMergeTool,,1240538792926', STARTKEY => '', ENDKEY => '', ENCODED
=> 1139877762, 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-04-24 02:07:07,415 INFO  [main] util.Merge(246): Adding REGION => {NAME
=> 'TestMergeTool,,1240538827339', STARTKEY => '', ENDKEY => '', ENCODED => 1202107463,
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 => 'historian', BLOOMFILTER
=> 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH => '2147483647',
TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'},
{NAME => 'info', BLOOMFILTER => 'false', VERSIONS => '10', COMPRESSION => 'NONE',
LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '8192', IN_MEMORY => 'false',
BLOCKCACHE => 'true'}], INDEXES => []}}
    [junit] 2009-04-24 02:07:07,415 DEBUG [main] regionserver.HRegion(386): Closing TestMergeTool,,1240538827339:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,416 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,416 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,416 DEBUG [main] regionserver.Store(420): closed contents
    [junit] 2009-04-24 02:07:07,416 INFO  [main] regionserver.HRegion(435): Closed TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,416 DEBUG [main] regionserver.HRegion(386): Closing -ROOT-,,0:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,416 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on -ROOT-,,0
    [junit] 2009-04-24 02:07:07,417 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region -ROOT-,,0
    [junit] 2009-04-24 02:07:07,417 DEBUG [main] regionserver.Store(420): closed info
    [junit] 2009-04-24 02:07:07,417 INFO  [main] regionserver.HRegion(435): Closed -ROOT-,,0
    [junit] 2009-04-24 02:07:07,417 DEBUG [main] regionserver.HRegion(386): Closing .META.,,1:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,417 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on .META.,,1
    [junit] 2009-04-24 02:07:07,418 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region .META.,,1
    [junit] 2009-04-24 02:07:07,420 DEBUG [main] regionserver.HRegion(829): Started memcache
flush for region .META.,,1. Current region memcache size 845.0
    [junit] 2009-04-24 02:07:07,464 DEBUG [main] regionserver.Store(500): Added hdfs://localhost:50678/user/hudson/.META./1028785192/info/5003078297835262384,
entries=3, sequenceid=21, memsize=845.0, filesize=887.0 to .META.,,1
    [junit] 2009-04-24 02:07:07,465 DEBUG [main] regionserver.HRegion(906): Finished memcache
flush of ~845.0 for region .META.,,1 in 47ms, sequence id=21, compaction requested=true
    [junit] 2009-04-24 02:07:07,465 DEBUG [main] regionserver.Store(420): closed historian
    [junit] 2009-04-24 02:07:07,465 DEBUG [main] regionserver.Store(420): closed info
    [junit] 2009-04-24 02:07:07,465 INFO  [main] regionserver.HRegion(435): Closed .META.,,1
    [junit] 2009-04-24 02:07:07,472 INFO  [main] regionserver.HLog(274): Closed hdfs://localhost:50678/user/hudson/log_1240538827204/hlog.dat.0,
entries=4. New log writer: /user/hudson/log_1240538827204/hlog.dat.1240538827470
    [junit] 2009-04-24 02:07:07,472 DEBUG [main] regionserver.HLog(281): Last sequence written
is empty. Deleting all old hlogs
    [junit] 2009-04-24 02:07:07,472 INFO  [main] regionserver.HLog(392): removing old log
file /user/hudson/log_1240538827204/hlog.dat.0 whose highest sequence/edit id is 21
    [junit] 2009-04-24 02:07:07,473 DEBUG [main] regionserver.HLog(428): closing log writer
in hdfs://localhost:50678/user/hudson/log_1240538827204
    [junit] 2009-04-24 02:07:07,479 DEBUG [main] regionserver.HRegion(2215): Opening region:
REGION => {NAME => 'TestMergeTool,,1240538827339', STARTKEY => '', ENDKEY => '',
ENCODED => 1202107463, 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-04-24 02:07:07,479 DEBUG [main] regionserver.HRegion(238): Opening region
TestMergeTool,,1240538827339/1202107463
    [junit] 2009-04-24 02:07:07,484 DEBUG [main] regionserver.Store(370): loaded /user/hudson/TestMergeTool/1202107463/contents/6685395220471740125,
isReference=false, sequence id=6, length=943, majorCompaction=false
    [junit] 2009-04-24 02:07:07,484 DEBUG [main] regionserver.Store(208): Loaded 1 file(s)
in Store contents, max sequence id 6
    [junit] 2009-04-24 02:07:07,485 DEBUG [main] regionserver.HRegion(298): Next sequence
id for region TestMergeTool,,1240538827339 is 7
    [junit] 2009-04-24 02:07:07,486 INFO  [main] regionserver.HRegion(315): region TestMergeTool,,1240538827339/1202107463
available
    [junit] 2009-04-24 02:07:07,486 DEBUG [main] regionserver.HLog(218): changing sequence
number from 6 to 7
    [junit] 2009-04-24 02:07:07,507 DEBUG [main] regionserver.HRegion(386): Closing TestMergeTool,,1240538827339:
compactions & flushes disabled 
    [junit] 2009-04-24 02:07:07,507 DEBUG [main] regionserver.HRegion(416): Updates disabled
for region, no outstanding scanners on TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,507 DEBUG [main] regionserver.HRegion(423): No more row locks
outstanding on region TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,507 DEBUG [main] regionserver.Store(420): closed contents
    [junit] 2009-04-24 02:07:07,508 INFO  [main] regionserver.HRegion(435): Closed TestMergeTool,,1240538827339
    [junit] 2009-04-24 02:07:07,508 INFO  [main] util.TestMergeTool(170): Verified merging
regions 0+1+2+3 and 4
    [junit] 2009-04-24 02:07:07,508 DEBUG [main] regionserver.HLog(428): closing log writer
in /tmp/log_1240538825898
    [junit] 2009-04-24 02:07:07,514 INFO  [main] hbase.HBaseTestCase(592): Shutting down FileSystem
    [junit] 2009-04-24 02:07:07,514 INFO  [main] hbase.HBaseTestCase(599): Shutting down Mini
DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-04-24 02:07:07,515 INFO  [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=53035]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=53035]
    [junit] 2009-04-24 02:07:07,528 INFO  [main] http.SocketListener(212): Stopped SocketListener
on 127.0.0.1:53035
    [junit] 2009-04-24 02:07:07,528 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1bc1fb9
    [junit] 2009-04-24 02:07:07,617 INFO  [main] util.Container(156): Stopped WebApplicationContext[/static,/static]
    [junit] 2009-04-24 02:07:07,617 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1a7789c
    [junit] 2009-04-24 02:07:07,691 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2009-04-24 02:07:07,691 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@504ec1
    [junit] 2009-04-24 02:07:07,694 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1858aa0]
datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:44220, storageID=DS-1551593814-67.195.138.9-44220-1240538824986,
infoPort=53035, ipcPort=52527):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-04-24 02:07:08,694 INFO  [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=56803]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=56803]
    [junit] 2009-04-24 02:07:08,694 INFO  [main] http.SocketListener(212): Stopped SocketListener
on 127.0.0.1:56803
    [junit] 2009-04-24 02:07:08,694 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@4c6320
    [junit] 2009-04-24 02:07:08,749 INFO  [main] util.Container(156): Stopped WebApplicationContext[/static,/static]
    [junit] 2009-04-24 02:07:08,750 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1f9338f
    [junit] 2009-04-24 02:07:08,803 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2009-04-24 02:07:08,803 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@457d21
    [junit] 2009-04-24 02:07:08,804 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@111bfbc]
datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:55277, storageID=DS-1708124006-67.195.138.9-55277-1240538824758,
infoPort=56803, ipcPort=50036):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-04-24 02:07:09,904 INFO  [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=43305]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=43305]
    [junit] 2009-04-24 02:07:09,905 INFO  [main] http.SocketListener(212): Stopped SocketListener
on 127.0.0.1:43305
    [junit] 2009-04-24 02:07:09,905 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@7f58ef
    [junit] 2009-04-24 02:07:09,956 INFO  [main] util.Container(156): Stopped WebApplicationContext[/static,/static]
    [junit] 2009-04-24 02:07:09,956 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@3f96ee
    [junit] 2009-04-24 02:07:10,005 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2009-04-24 02:07:10,005 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@18c74
    [junit] 2009-04-24 02:07:10,006 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@b2c6a6]
namenode.FSNamesystem$ReplicationMonitor(2300): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 37.19 sec
    [junit] Running org.apache.hadoop.hbase.util.TestRootPath
    [junit] 2009-04-24 02:07:10,360 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.07 sec
    [junit] Running org.onelab.test.TestFilter
    [junit] 2009-04-24 02:07:10,706 INFO  [main] test.TestFilter(244): Checking for false
negatives
    [junit] 2009-04-24 02:07:10,711 INFO  [main] test.TestFilter(255): Checking for false
positives
    [junit] 2009-04-24 02:07:10,711 INFO  [main] test.TestFilter(262): Success!
    [junit] 2009-04-24 02:07:10,712 INFO  [main] test.TestFilter(226): Checking serialization/deserialization
    [junit] 2009-04-24 02:07:10,713 INFO  [main] test.TestFilter(244): Checking for false
negatives
    [junit] 2009-04-24 02:07:10,716 INFO  [main] test.TestFilter(255): Checking for false
positives
    [junit] 2009-04-24 02:07:10,717 INFO  [main] test.TestFilter(262): Success!
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.088 sec

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

Total time: 27 minutes 8 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