I just ran all tests locally using clean trunk and all passed. The failing TestThriftServer passes for me. Maybe this is just a hudson hiccup. Lets see. St.Ack
On Fri, Mar 13, 2009 at 9:34 PM, Apache Hudson Server < [email protected]> wrote: > See http://hudson.zones.apache.org/hudson/job/HBase-Patch/533/changes > > Changes: > > [jimk] HBASE-1157, HBASE-1156 If we do not take start code as a part of > region server recovery, we could inadvertantly try to reassign regions > assigned to a restarted server with a different start code; Improve lease > handling > > ------------------------------------------ > [...truncated 36023 lines...] > [junit] 2009-03-14 04:45:39,772 DEBUG [main] util.Merge(308): Removing > region: REGION => {NAME => 'TestMergeTool,row_0100,1237005929229', STARTKEY > => 'row_0100', ENDKEY => 'row_0400', ENCODED => 1972697540, 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-14 04:45:39,775 DEBUG [main] util.Merge(308): Removing > region: REGION => {NAME => 'TestMergeTool,row_0500,1237005906391', STARTKEY > => 'row_0500', ENDKEY => 'row_0600', ENCODED => 928239058, 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-14 04:45:39,779 INFO [main] util.Merge(244): Adding > REGION => {NAME => 'TestMergeTool,row_0100,1237005939690', STARTKEY => > 'row_0100', ENDKEY => 'row_0600', ENCODED => 829060819, 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-14 04:45:39,779 DEBUG [main] regionserver.HRegion(385): > Closing TestMergeTool,row_0100,1237005939690: compactions & flushes disabled > [junit] 2009-03-14 04:45:39,780 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on > TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:39,780 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:39,780 DEBUG [main] regionserver.Store(434): > closed 829060819/contents > [junit] 2009-03-14 04:45:39,780 INFO [main] regionserver.HRegion(434): > Closed TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:39,780 DEBUG [main] regionserver.HRegion(385): > Closing -ROOT-,,0: compactions & flushes disabled > [junit] 2009-03-14 04:45:39,781 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on -ROOT-,,0 > [junit] 2009-03-14 04:45:39,781 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region -ROOT-,,0 > [junit] 2009-03-14 04:45:39,781 DEBUG [main] regionserver.Store(434): > closed 70236052/info > [junit] 2009-03-14 04:45:39,781 INFO [main] regionserver.HRegion(434): > Closed -ROOT-,,0 > [junit] 2009-03-14 04:45:39,781 DEBUG [main] regionserver.HRegion(385): > Closing .META.,,1: compactions & flushes disabled > [junit] 2009-03-14 04:45:39,782 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on .META.,,1 > [junit] 2009-03-14 04:45:39,782 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region .META.,,1 > [junit] 2009-03-14 04:45:39,782 DEBUG [main] regionserver.HRegion(826): > Started memcache flush for region .META.,,1. Current region memcache size > 968.0 > [junit] 2009-03-14 04:45:39,795 DEBUG [main] regionserver.Store(516): > Added > hdfs://localhost:50169/user/hudson/.META./1028785192/info/2052036617590515680, > entries=3, sequenceid=17, memsize=968.0, filesize=968.0 to .META.,,1 > [junit] 2009-03-14 04:45:39,795 DEBUG [main] regionserver.HRegion(903): > Finished memcache flush of ~968.0 for region .META.,,1 in 13ms, sequence > id=17, compaction requested=true > [junit] 2009-03-14 04:45:39,795 DEBUG [main] regionserver.Store(434): > closed 1028785192/historian > [junit] 2009-03-14 04:45:39,796 DEBUG [main] regionserver.Store(434): > closed 1028785192/info > [junit] 2009-03-14 04:45:39,796 INFO [main] regionserver.HRegion(434): > Closed .META.,,1 > [junit] 2009-03-14 04:45:39,803 INFO [main] regionserver.HLog(273): > Closed hdfs://localhost:50169/user/hudson/log_1237005939450/hlog.dat.0, > entries=4. New log writer: > /user/hudson/log_1237005939450/hlog.dat.1237005939802 > [junit] 2009-03-14 04:45:39,804 DEBUG [main] regionserver.HLog(280): > Last sequence written is empty. Deleting all old hlogs > [junit] 2009-03-14 04:45:39,804 INFO [main] regionserver.HLog(391): > removing old log file /user/hudson/log_1237005939450/hlog.dat.0 whose > highest sequence/edit id is 17 > [junit] 2009-03-14 04:45:39,804 DEBUG [main] regionserver.HLog(427): > closing log writer in hdfs://localhost:50169/user/hudson/log_1237005939450 > [junit] 2009-03-14 04:45:39,810 DEBUG [main] regionserver.HRegion(2197): > Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1237005939690', > STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 829060819, 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-14 04:45:39,811 DEBUG [main] regionserver.HRegion(237): > Opening region TestMergeTool,row_0100,1237005939690/829060819 > [junit] 2009-03-14 04:45:39,816 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/TestMergeTool/829060819/contents/5213203374517672582, > isReference=false, sequence id=5, length=761, majorCompaction=false > [junit] 2009-03-14 04:45:39,816 DEBUG [main] regionserver.Store(213): > Loaded 1 file(s) in Store 829060819/contents, max sequence id 5 > [junit] 2009-03-14 04:45:39,817 DEBUG [main] regionserver.HRegion(297): > Next sequence id for region TestMergeTool,row_0100,1237005939690 is 6 > [junit] 2009-03-14 04:45:39,819 INFO [main] regionserver.HRegion(314): > region TestMergeTool,row_0100,1237005939690/829060819 available > [junit] 2009-03-14 04:45:39,819 DEBUG [main] regionserver.HLog(217): > changing sequence number from 5 to 6 > [junit] 2009-03-14 04:45:39,832 DEBUG [main] regionserver.HRegion(385): > Closing TestMergeTool,row_0100,1237005939690: compactions & flushes disabled > [junit] 2009-03-14 04:45:39,833 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on > TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:39,833 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:39,833 DEBUG [main] regionserver.Store(434): > closed 829060819/contents > [junit] 2009-03-14 04:45:39,833 INFO [main] regionserver.HRegion(434): > Closed TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:39,834 INFO [main] util.TestMergeTool(169): > Verified merging regions 0+1+2 and 3 > [junit] 2009-03-14 04:45:39,834 INFO [main] util.TestMergeTool(156): > merging regions 0+1+2+3 and 4 > [junit] 2009-03-14 04:45:39,834 INFO [main] util.Merge(80): Verifying > that file system is available... > [junit] 2009-03-14 04:45:39,835 INFO [main] util.Merge(89): Verifying > that HBase is not running... > [junit] 2009-03-14 04:45:49,853 INFO [main] util.Merge(190): Merging > regions [...@4f33c1 and [...@1cee0c8 in table [...@1079781 > [junit] 2009-03-14 04:45:49,856 INFO [main] regionserver.HLog(273): New > log writer: /user/hudson/log_1237005949853/hlog.dat.1237005949854 > [junit] 2009-03-14 04:45:49,856 DEBUG [main] regionserver.HRegion(2197): > 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-14 04:45:49,857 DEBUG [main] regionserver.HRegion(237): > Opening region -ROOT-,,0/70236052 > [junit] 2009-03-14 04:45:49,862 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/-ROOT-/70236052/info/8537768645460334891, > isReference=false, sequence id=1, length=856, majorCompaction=false > [junit] 2009-03-14 04:45:49,862 DEBUG [main] regionserver.Store(213): > Loaded 1 file(s) in Store 70236052/info, max sequence id 1 > [junit] 2009-03-14 04:45:49,863 DEBUG [main] regionserver.HRegion(297): > Next sequence id for region -ROOT-,,0 is 2 > [junit] 2009-03-14 04:45:49,864 INFO [main] regionserver.HRegion(314): > region -ROOT-,,0/70236052 available > [junit] 2009-03-14 04:45:49,864 DEBUG [main] regionserver.HLog(217): > changing sequence number from 0 to 2 > [junit] 2009-03-14 04:45:49,865 INFO [main] regionserver.HRegion(701): > starting compaction on region -ROOT-,,0 > [junit] 2009-03-14 04:45:49,866 INFO [main] regionserver.HRegion(716): > compaction completed on region -ROOT-,,0 in 0sec > [junit] 2009-03-14 04:45:49,868 INFO [main] util.Merge(204): Found meta > for region1 [...@18e4a2e, meta for region2 [...@18e4a2e > [junit] 2009-03-14 04:45:49,868 DEBUG [main] regionserver.HRegion(2197): > 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-14 04:45:49,869 DEBUG [main] regionserver.HRegion(237): > Opening region .META.,,1/1028785192 > [junit] 2009-03-14 04:45:49,874 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/.META./1028785192/info/2052036617590515680, > isReference=false, sequence id=17, length=968, majorCompaction=false > [junit] 2009-03-14 04:45:49,878 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/.META./1028785192/info/3331913900248651157, > isReference=false, sequence id=13, length=1531, majorCompaction=false > [junit] 2009-03-14 04:45:49,882 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/.META./1028785192/info/3849184054045363388, > isReference=false, sequence id=5, length=2330, majorCompaction=false > [junit] 2009-03-14 04:45:49,882 DEBUG [main] regionserver.Store(213): > Loaded 3 file(s) in Store 1028785192/info, max sequence id 17 > [junit] 2009-03-14 04:45:49,884 DEBUG [main] regionserver.HRegion(297): > Next sequence id for region .META.,,1 is 18 > [junit] 2009-03-14 04:45:49,885 INFO [main] regionserver.HRegion(314): > region .META.,,1/1028785192 available > [junit] 2009-03-14 04:45:49,885 DEBUG [main] regionserver.HLog(217): > changing sequence number from 2 to 18 > [junit] 2009-03-14 04:45:49,886 INFO [main] regionserver.HRegion(701): > starting compaction on region .META.,,1 > [junit] 2009-03-14 04:45:49,886 DEBUG [main] regionserver.Store(619): > 1028785192/historian: no store files to compact > [junit] 2009-03-14 04:45:49,888 DEBUG [main] regionserver.Store(683): > Compaction size of 1028785192/info: 4.7k; Skipped 0 file(s), size: 0 > [junit] 2009-03-14 04:45:49,892 DEBUG [main] regionserver.Store(692): > Started compaction of 3 file(s) into > /user/hudson/.META./1028785192/info/8064626146425761593 > [junit] 2009-03-14 04:45:49,914 DEBUG [main] regionserver.Store(707): > Completed compaction of 1028785192/info store size is 3.9k > [junit] 2009-03-14 04:45:49,914 INFO [main] regionserver.HRegion(716): > compaction completed on region .META.,,1 in 0sec > [junit] 2009-03-14 04:45:49,917 DEBUG [main] regionserver.HRegion(2197): > Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1237005939690', > STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 829060819, 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-14 04:45:49,917 DEBUG [main] regionserver.HRegion(237): > Opening region TestMergeTool,row_0100,1237005939690/829060819 > [junit] 2009-03-14 04:45:49,922 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/TestMergeTool/829060819/contents/5213203374517672582, > isReference=false, sequence id=5, length=761, majorCompaction=false > [junit] 2009-03-14 04:45:49,922 DEBUG [main] regionserver.Store(213): > Loaded 1 file(s) in Store 829060819/contents, max sequence id 5 > [junit] 2009-03-14 04:45:49,924 DEBUG [main] regionserver.HRegion(297): > Next sequence id for region TestMergeTool,row_0100,1237005939690 is 6 > [junit] 2009-03-14 04:45:49,926 INFO [main] regionserver.HRegion(314): > region TestMergeTool,row_0100,1237005939690/829060819 available > [junit] 2009-03-14 04:45:49,926 DEBUG [main] regionserver.HRegion(2197): > Opening region: REGION => {NAME => 'TestMergeTool,,1237005906391', STARTKEY > => '', ENDKEY => '', ENCODED => 218489900, 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-14 04:45:49,927 DEBUG [main] regionserver.HRegion(237): > Opening region TestMergeTool,,1237005906391/218489900 > [junit] 2009-03-14 04:45:49,932 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/TestMergeTool/218489900/contents/6682330590388812271, > isReference=false, sequence id=6, length=419, majorCompaction=false > [junit] 2009-03-14 04:45:49,932 DEBUG [main] regionserver.Store(213): > Loaded 1 file(s) in Store 218489900/contents, max sequence id 6 > [junit] 2009-03-14 04:45:49,933 DEBUG [main] regionserver.HRegion(297): > Next sequence id for region TestMergeTool,,1237005906391 is 7 > [junit] 2009-03-14 04:45:49,934 INFO [main] regionserver.HRegion(314): > region TestMergeTool,,1237005906391/218489900 available > [junit] 2009-03-14 04:45:49,935 INFO [main] regionserver.HRegion(701): > starting major compaction on region TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:49,945 DEBUG [main] regionserver.Store(692): > Started compaction of 1 file(s) into > /user/hudson/TestMergeTool/829060819/contents/8209395605079600832 > [junit] 2009-03-14 04:45:49,983 DEBUG [main] regionserver.Store(707): > Completed major compaction of 829060819/contents store size is 761.0 > [junit] 2009-03-14 04:45:49,984 INFO [main] regionserver.HRegion(716): > compaction completed on region TestMergeTool,row_0100,1237005939690 in 0sec > [junit] 2009-03-14 04:45:49,984 DEBUG [main] regionserver.HRegion(2437): > Files for region: TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:49,985 DEBUG [main] regionserver.HRegion(2581): > d hdfs://localhost:50169/user/hudson/TestMergeTool/829060819/contents > [junit] 2009-03-14 04:45:49,986 DEBUG [main] regionserver.HRegion(2584): > f > hdfs://localhost:50169/user/hudson/TestMergeTool/829060819/contents/1378772433066674817 > size=761 > [junit] 2009-03-14 04:45:49,987 INFO [main] regionserver.HRegion(701): > starting major compaction on region TestMergeTool,,1237005906391 > [junit] 2009-03-14 04:45:49,992 DEBUG [main] regionserver.Store(692): > Started compaction of 1 file(s) into > /user/hudson/TestMergeTool/218489900/contents/623796321957111037 > [junit] 2009-03-14 04:45:50,015 DEBUG [main] regionserver.Store(707): > Completed major compaction of 218489900/contents store size is 419.0 > [junit] 2009-03-14 04:45:50,016 INFO [main] regionserver.HRegion(716): > compaction completed on region TestMergeTool,,1237005906391 in 0sec > [junit] 2009-03-14 04:45:50,016 DEBUG [main] regionserver.HRegion(2442): > Files for region: TestMergeTool,,1237005906391 > [junit] 2009-03-14 04:45:50,017 DEBUG [main] regionserver.HRegion(2581): > d hdfs://localhost:50169/user/hudson/TestMergeTool/218489900/contents > [junit] 2009-03-14 04:45:50,018 DEBUG [main] regionserver.HRegion(2584): > f > hdfs://localhost:50169/user/hudson/TestMergeTool/218489900/contents/4536792488221544226 > size=419 > [junit] 2009-03-14 04:45:50,018 INFO [main] regionserver.HRegion(2463): > Creating new region REGION => {NAME => 'TestMergeTool,,1237005950018', > STARTKEY => '', ENDKEY => '', ENCODED => 2019194025, 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-14 04:45:50,020 INFO [main] regionserver.HRegion(2472): > starting merge of regions: TestMergeTool,row_0100,1237005939690 and > TestMergeTool,,1237005906391 into new region REGION => {NAME => > 'TestMergeTool,,1237005950018', STARTKEY => '', ENDKEY => '', ENCODED => > 2019194025, 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 <[...@142a80d> and end key <[...@142a80d> > [junit] 2009-03-14 04:45:50,020 DEBUG [main] regionserver.HRegion(385): > Closing TestMergeTool,row_0100,1237005939690: compactions & flushes disabled > [junit] 2009-03-14 04:45:50,021 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on > TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:50,021 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:50,021 DEBUG [main] regionserver.Store(434): > closed 829060819/contents > [junit] 2009-03-14 04:45:50,022 INFO [main] regionserver.HRegion(434): > Closed TestMergeTool,row_0100,1237005939690 > [junit] 2009-03-14 04:45:50,022 DEBUG [main] regionserver.HRegion(385): > Closing TestMergeTool,,1237005906391: compactions & flushes disabled > [junit] 2009-03-14 04:45:50,022 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on > TestMergeTool,,1237005906391 > [junit] 2009-03-14 04:45:50,023 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region TestMergeTool,,1237005906391 > [junit] 2009-03-14 04:45:50,023 DEBUG [main] regionserver.Store(434): > closed 218489900/contents > [junit] 2009-03-14 04:45:50,023 INFO [main] regionserver.HRegion(434): > Closed TestMergeTool,,1237005906391 > [junit] 2009-03-14 04:45:50,035 DEBUG [main] regionserver.HRegion(2506): > Files for new region > [junit] 2009-03-14 04:45:50,036 DEBUG [main] regionserver.HRegion(2581): > d hdfs://localhost:50169/user/hudson/TestMergeTool/2019194025/contents > [junit] 2009-03-14 04:45:50,037 DEBUG [main] regionserver.HRegion(2584): > f > hdfs://localhost:50169/user/hudson/TestMergeTool/2019194025/contents/1778138696786987354 > size=761 > [junit] 2009-03-14 04:45:50,037 DEBUG [main] regionserver.HRegion(2584): > f > hdfs://localhost:50169/user/hudson/TestMergeTool/2019194025/contents/8448325250016769302 > size=419 > [junit] 2009-03-14 04:45:50,038 DEBUG [main] regionserver.HRegion(237): > Opening region TestMergeTool,,1237005950018/2019194025 > [junit] 2009-03-14 04:45:50,045 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/TestMergeTool/2019194025/contents/1778138696786987354, > isReference=false, sequence id=5, length=761, majorCompaction=true > [junit] 2009-03-14 04:45:50,070 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/TestMergeTool/2019194025/contents/8448325250016769302, > isReference=false, sequence id=6, length=419, majorCompaction=true > [junit] 2009-03-14 04:45:50,070 DEBUG [main] regionserver.Store(213): > Loaded 2 file(s) in Store 2019194025/contents, max sequence id 6 > [junit] 2009-03-14 04:45:50,072 DEBUG [main] regionserver.HRegion(297): > Next sequence id for region TestMergeTool,,1237005950018 is 7 > [junit] 2009-03-14 04:45:50,073 INFO [main] regionserver.HRegion(314): > region TestMergeTool,,1237005950018/2019194025 available > [junit] 2009-03-14 04:45:50,073 INFO [main] regionserver.HRegion(701): > starting compaction on region TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,076 DEBUG [main] regionserver.Store(683): > Compaction size of 2019194025/contents: 1.2k; Skipped 0 file(s), size: 0 > [junit] 2009-03-14 04:45:50,079 DEBUG [main] regionserver.Store(692): > Started compaction of 2 file(s) into > /user/hudson/TestMergeTool/2019194025/contents/34368053045812870 > [junit] 2009-03-14 04:45:50,093 DEBUG [main] regionserver.Store(707): > Completed compaction of 2019194025/contents store size is 847.0 > [junit] 2009-03-14 04:45:50,094 INFO [main] regionserver.HRegion(716): > compaction completed on region TestMergeTool,,1237005950018 in 0sec > [junit] 2009-03-14 04:45:50,095 DEBUG [main] regionserver.HRegion(2513): > Files for new region > [junit] 2009-03-14 04:45:50,096 DEBUG [main] regionserver.HRegion(2581): > d hdfs://localhost:50169/user/hudson/TestMergeTool/2019194025/contents > [junit] 2009-03-14 04:45:50,096 DEBUG [main] regionserver.HRegion(2584): > f > hdfs://localhost:50169/user/hudson/TestMergeTool/2019194025/contents/773396703865125501 > size=847 > [junit] 2009-03-14 04:45:50,097 DEBUG [main] regionserver.HRegion(2315): > DELETING region hdfs://localhost:50169/user/hudson/TestMergeTool/829060819 > [junit] 2009-03-14 04:45:50,098 DEBUG [main] regionserver.HRegion(2315): > DELETING region hdfs://localhost:50169/user/hudson/TestMergeTool/218489900 > [junit] 2009-03-14 04:45:50,099 INFO [main] regionserver.HRegion(2519): > merge completed. New region is TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,099 DEBUG [main] util.Merge(308): Removing > region: REGION => {NAME => 'TestMergeTool,row_0100,1237005939690', STARTKEY > => 'row_0100', ENDKEY => 'row_0600', ENCODED => 829060819, 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-14 04:45:50,101 DEBUG [main] util.Merge(308): Removing > region: REGION => {NAME => 'TestMergeTool,,1237005906391', STARTKEY => '', > ENDKEY => '', ENCODED => 218489900, 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-14 04:45:50,104 INFO [main] util.Merge(244): Adding > REGION => {NAME => 'TestMergeTool,,1237005950018', STARTKEY => '', ENDKEY => > '', ENCODED => 2019194025, 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-14 04:45:50,104 DEBUG [main] regionserver.HRegion(385): > Closing TestMergeTool,,1237005950018: compactions & flushes disabled > [junit] 2009-03-14 04:45:50,105 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on > TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,105 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,105 DEBUG [main] regionserver.Store(434): > closed 2019194025/contents > [junit] 2009-03-14 04:45:50,106 INFO [main] regionserver.HRegion(434): > Closed TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,106 DEBUG [main] regionserver.HRegion(385): > Closing -ROOT-,,0: compactions & flushes disabled > [junit] 2009-03-14 04:45:50,106 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on -ROOT-,,0 > [junit] 2009-03-14 04:45:50,107 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region -ROOT-,,0 > [junit] 2009-03-14 04:45:50,107 DEBUG [main] regionserver.Store(434): > closed 70236052/info > [junit] 2009-03-14 04:45:50,107 INFO [main] regionserver.HRegion(434): > Closed -ROOT-,,0 > [junit] 2009-03-14 04:45:50,108 DEBUG [main] regionserver.HRegion(385): > Closing .META.,,1: compactions & flushes disabled > [junit] 2009-03-14 04:45:50,108 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on .META.,,1 > [junit] 2009-03-14 04:45:50,108 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region .META.,,1 > [junit] 2009-03-14 04:45:50,108 DEBUG [main] regionserver.HRegion(826): > Started memcache flush for region .META.,,1. Current region memcache size > 928.0 > [junit] 2009-03-14 04:45:50,123 DEBUG [main] regionserver.Store(516): > Added > hdfs://localhost:50169/user/hudson/.META./1028785192/info/8435164374051575269, > entries=3, sequenceid=21, memsize=928.0, filesize=920.0 to .META.,,1 > [junit] 2009-03-14 04:45:50,123 DEBUG [main] regionserver.HRegion(903): > Finished memcache flush of ~928.0 for region .META.,,1 in 15ms, sequence > id=21, compaction requested=true > [junit] 2009-03-14 04:45:50,123 DEBUG [main] regionserver.Store(434): > closed 1028785192/historian > [junit] 2009-03-14 04:45:50,124 DEBUG [main] regionserver.Store(434): > closed 1028785192/info > [junit] 2009-03-14 04:45:50,124 INFO [main] regionserver.HRegion(434): > Closed .META.,,1 > [junit] 2009-03-14 04:45:50,152 INFO [main] regionserver.HLog(273): > Closed hdfs://localhost:50169/user/hudson/log_1237005949853/hlog.dat.0, > entries=4. New log writer: > /user/hudson/log_1237005949853/hlog.dat.1237005950136 > [junit] 2009-03-14 04:45:50,153 DEBUG [main] regionserver.HLog(280): > Last sequence written is empty. Deleting all old hlogs > [junit] 2009-03-14 04:45:50,153 INFO [main] regionserver.HLog(391): > removing old log file /user/hudson/log_1237005949853/hlog.dat.0 whose > highest sequence/edit id is 21 > [junit] 2009-03-14 04:45:50,154 DEBUG [main] regionserver.HLog(427): > closing log writer in hdfs://localhost:50169/user/hudson/log_1237005949853 > [junit] 2009-03-14 04:45:50,173 DEBUG [main] regionserver.HRegion(2197): > Opening region: REGION => {NAME => 'TestMergeTool,,1237005950018', STARTKEY > => '', ENDKEY => '', ENCODED => 2019194025, 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-14 04:45:50,173 DEBUG [main] regionserver.HRegion(237): > Opening region TestMergeTool,,1237005950018/2019194025 > [junit] 2009-03-14 04:45:50,198 DEBUG [main] regionserver.Store(383): > loaded /user/hudson/TestMergeTool/2019194025/contents/773396703865125501, > isReference=false, sequence id=6, length=847, majorCompaction=false > [junit] 2009-03-14 04:45:50,198 DEBUG [main] regionserver.Store(213): > Loaded 1 file(s) in Store 2019194025/contents, max sequence id 6 > [junit] 2009-03-14 04:45:50,199 DEBUG [main] regionserver.HRegion(297): > Next sequence id for region TestMergeTool,,1237005950018 is 7 > [junit] 2009-03-14 04:45:50,201 INFO [main] regionserver.HRegion(314): > region TestMergeTool,,1237005950018/2019194025 available > [junit] 2009-03-14 04:45:50,201 DEBUG [main] regionserver.HLog(217): > changing sequence number from 6 to 7 > [junit] 2009-03-14 04:45:50,220 DEBUG [main] regionserver.HRegion(385): > Closing TestMergeTool,,1237005950018: compactions & flushes disabled > [junit] 2009-03-14 04:45:50,220 DEBUG [main] regionserver.HRegion(415): > Updates disabled for region, no outstanding scanners on > TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,221 DEBUG [main] regionserver.HRegion(422): > No more row locks outstanding on region TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,221 DEBUG [main] regionserver.Store(434): > closed 2019194025/contents > [junit] 2009-03-14 04:45:50,221 INFO [main] regionserver.HRegion(434): > Closed TestMergeTool,,1237005950018 > [junit] 2009-03-14 04:45:50,221 INFO [main] util.TestMergeTool(169): > Verified merging regions 0+1+2+3 and 4 > [junit] 2009-03-14 04:45:50,222 DEBUG [main] regionserver.HLog(427): > closing log writer in /tmp/log_1237005908411 > [junit] 2009-03-14 04:45:50,228 INFO [main] hbase.HBaseTestCase(598): > Shutting down FileSystem > [junit] 2009-03-14 04:45:50,228 INFO [main] hbase.HBaseTestCase(605): > Shutting down Mini DFS > [junit] Shutting down the Mini HDFS Cluster > [junit] Shutting down DataNode 1 > [junit] 2009-03-14 04:45:50,229 INFO [Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=41656]] > util.ThreadedServer$Acceptor(656): Stopping Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=41656] > [junit] 2009-03-14 04:45:50,234 INFO [main] http.SocketListener(212): > Stopped SocketListener on 127.0.0.1:41656 > [junit] 2009-03-14 04:45:50,234 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.servlet.webapplicationhand...@9300cc > [junit] 2009-03-14 04:45:50,312 INFO [main] util.Container(156): > Stopped WebApplicationContext[/static,/static] > [junit] 2009-03-14 04:45:50,312 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.servlet.webapplicationhand...@20807c > [junit] 2009-03-14 04:45:50,371 INFO [main] util.Container(156): > Stopped WebApplicationContext[/,/] > [junit] 2009-03-14 04:45:50,371 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.ser...@4ab2f > [junit] 2009-03-14 04:45:50,373 WARN > [org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@c26b16] > datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:49332, > storageID=DS-983041510-67.195.138.9-49332-1237005907910, infoPort=41656, > ipcPort=44608):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-14 04:45:51,373 INFO [Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=41289]] > util.ThreadedServer$Acceptor(656): Stopping Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=41289] > [junit] 2009-03-14 04:45:51,373 INFO [main] http.SocketListener(212): > Stopped SocketListener on 127.0.0.1:41289 > [junit] 2009-03-14 04:45:51,374 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.servlet.webapplicationhand...@754fc > [junit] 2009-03-14 04:45:51,443 INFO [main] util.Container(156): > Stopped WebApplicationContext[/static,/static] > [junit] 2009-03-14 04:45:51,444 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.servlet.webapplicationhand...@1f9338f > [junit] 2009-03-14 04:45:51,496 INFO [main] util.Container(156): > Stopped WebApplicationContext[/,/] > [junit] 2009-03-14 04:45:51,496 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.ser...@1fa681c > [junit] 2009-03-14 04:45:51,497 WARN > [org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1a0ae6] > datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:40488, > storageID=DS-83709-67.195.138.9-40488-1237005907676, infoPort=41289, > ipcPort=37291):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-14 04:45:52,598 INFO [Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=60194]] > util.ThreadedServer$Acceptor(656): Stopping Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=60194] > [junit] 2009-03-14 04:45:52,599 INFO [main] http.SocketListener(212): > Stopped SocketListener on 127.0.0.1:60194 > [junit] 2009-03-14 04:45:52,599 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.servlet.webapplicationhand...@f0b4a3 > [junit] 2009-03-14 04:45:52,649 INFO [main] util.Container(156): > Stopped WebApplicationContext[/static,/static] > [junit] 2009-03-14 04:45:52,650 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.servlet.webapplicationhand...@130633a > [junit] 2009-03-14 04:45:52,698 INFO [main] util.Container(156): > Stopped WebApplicationContext[/,/] > [junit] 2009-03-14 04:45:52,698 INFO [main] util.Container(156): > Stopped org.mortbay.jetty.ser...@17a4989 > [junit] 2009-03-14 04:45:52,698 WARN > > [org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@48edb5] > namenode.FSNamesystem$ReplicationMonitor(2300): ReplicationMonitor thread > received InterruptedException.java.lang.InterruptedException: sleep > interrupted > [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 46.412 sec > [junit] Running org.apache.hadoop.hbase.util.TestRootPath > [junit] 2009-03-14 04:45:53,061 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.063 sec > [junit] Running org.onelab.test.TestFilter > [junit] 2009-03-14 04:45:53,406 INFO [main] test.TestFilter(244): > Checking for false negatives > [junit] 2009-03-14 04:45:53,410 INFO [main] test.TestFilter(255): > Checking for false positives > [junit] 2009-03-14 04:45:53,413 INFO [main] test.TestFilter(262): > Success! > [junit] 2009-03-14 04:45:53,414 INFO [main] test.TestFilter(226): > Checking serialization/deserialization > [junit] 2009-03-14 04:45:53,415 INFO [main] test.TestFilter(244): > Checking for false negatives > [junit] 2009-03-14 04:45:53,417 INFO [main] test.TestFilter(255): > Checking for false positives > [junit] 2009-03-14 04:45:53,418 INFO [main] test.TestFilter(262): > Success! > [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.076 sec > > BUILD FAILED > http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml:458: > Tests failed! > > Total time: 29 minutes 56 seconds > [locks-and-latches] Releasing all the locks > [locks-and-latches] All the locks released > Recording test results > Publishing Clover coverage report... > >
