Spurious? I'll try setting hbase.regionserver.safemode.period back to 0.
- Andy > From: Apache Hudson Server <[email protected]> > Subject: Build failed in Hudson: HBase-Patch #471 > To: [email protected] > Date: Tuesday, December 30, 2008, 7:07 PM > See > http://hudson.zones.apache.org/hudson/job/HBase-Patch/471/changes > > Changes: > > [apurtell] make TestForceSplit less demanding (so it will > succeed on my Cygwin laptop) > > ------------------------------------------ > [...truncated 21127 lines...] > [junit] 2008-12-31 03:07:25,923 INFO [main] > regionserver.HRegion(435): Closed -ROOT-,,0 > [junit] 2008-12-31 03:07:25,923 DEBUG [main] > regionserver.HRegion(386): Closing .META.,,1: compactions > & flushes disabled > [junit] 2008-12-31 03:07:25,923 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on .META.,,1 > [junit] 2008-12-31 03:07:25,924 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region .META.,,1 > [junit] 2008-12-31 03:07:25,924 DEBUG [main] > regionserver.HRegion(828): Started memcache flush for region > .META.,,1. Current region memcache size 945.0 > [junit] 2008-12-31 03:07:26,170 DEBUG [main] > regionserver.HStore(694): Added > /user/hudson/.META./1028785192/info/mapfiles/7945932892095673372 > with 3 entries, sequence id 17, data size ~945.0, file size > 662.0 to .META.,,1 > [junit] 2008-12-31 03:07:26,171 DEBUG [main] > regionserver.HRegion(905): Finished memcache flush of ~945.0 > for region .META.,,1 in 247ms, sequence id=17, compaction > requested=true > [junit] 2008-12-31 03:07:26,171 DEBUG [main] > regionserver.HStore(598): closed 1028785192/historian > [junit] 2008-12-31 03:07:26,172 DEBUG [main] > regionserver.HStore(598): closed 1028785192/info > [junit] 2008-12-31 03:07:26,172 INFO [main] > regionserver.HRegion(435): Closed .META.,,1 > [junit] 2008-12-31 03:07:26,238 INFO [main] > regionserver.HLog(265): Closed > hdfs://localhost:56983/user/hudson/log_1230692843113/hlog.dat.0, > entries=4. New log writer: > /user/hudson/log_1230692843113/hlog.dat.1230692846203 > [junit] 2008-12-31 03:07:26,239 DEBUG [main] > regionserver.HLog(272): Last sequence written is empty. > Deleting all old hlogs > [junit] 2008-12-31 03:07:26,239 INFO [main] > regionserver.HLog(383): removing old log file > /user/hudson/log_1230692843113/hlog.dat.0 whose highest > sequence/edit id is 17 > [junit] 2008-12-31 03:07:26,240 DEBUG [main] > regionserver.HLog(419): closing log writer in > hdfs://localhost:56983/user/hudson/log_1230692843113 > [junit] 2008-12-31 03:07:26,307 DEBUG [main] > regionserver.HRegion(2078): Opening region: REGION => > {NAME => 'TestMergeTool,row_0100,1230692845108', > STARTKEY => 'row_0100', ENDKEY => > 'row_0600', ENCODED => 387787907, TABLE => > {{NAME => 'TestMergeTool', IS_ROOT => > 'false', IS_META => 'false', FAMILIES > => [{NAME => 'contents', BLOOMFILTER => > 'false', VERSIONS => '3', COMPRESSION > => 'NONE', LENGTH => '2147483647', TTL > => '-1', IN_MEMORY => 'false', > BLOCKCACHE => 'true'}], INDEXES => []}} > [junit] 2008-12-31 03:07:26,308 DEBUG [main] > regionserver.HRegion(232): Opening region > TestMergeTool,row_0100,1230692845108/387787907 > [junit] 2008-12-31 03:07:26,316 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/TestMergeTool/387787907/contents/info/4664132606861960662, > isReference=false, sequence id=2, length=486, > majorCompaction=false > [junit] 2008-12-31 03:07:26,317 DEBUG [main] > regionserver.HStore(231): Loaded 1 file(s) in hstore > 387787907/contents, max sequence id 2 > [junit] 2008-12-31 03:07:26,324 DEBUG [main] > regionserver.HRegion(293): Next sequence id for region > TestMergeTool,row_0100,1230692845108 is 3 > [junit] 2008-12-31 03:07:26,325 INFO [main] > regionserver.HRegion(315): region > TestMergeTool,row_0100,1230692845108/387787907 available > [junit] 2008-12-31 03:07:26,327 DEBUG [main] > regionserver.HRegion(386): Closing > TestMergeTool,row_0100,1230692845108: compactions & > flushes disabled > [junit] 2008-12-31 03:07:26,327 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:26,327 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:26,327 DEBUG [main] > regionserver.HStore(598): closed 387787907/contents > [junit] 2008-12-31 03:07:26,327 INFO [main] > regionserver.HRegion(435): Closed > TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:26,327 INFO [main] > util.TestMergeTool(171): Verified merging regions 0+1+2 and > 3 > [junit] 2008-12-31 03:07:26,327 INFO [main] > util.TestMergeTool(158): merging regions 0+1+2+3 and 4 > [junit] 2008-12-31 03:07:26,328 INFO [main] > util.Merge(80): Verifying that file system is available... > [junit] 2008-12-31 03:07:26,329 INFO [main] > util.Merge(89): Verifying that HBase is not running... > [junit] 2008-12-31 03:07:26,341 INFO [main] > util.Merge(190): Merging regions [...@94310b and [...@11fdbef in > table [...@a06816 > [junit] 2008-12-31 03:07:26,415 INFO [main] > regionserver.HLog(265): New log writer: > /user/hudson/log_1230692846341/hlog.dat.1230692846378 > [junit] 2008-12-31 03:07:26,416 DEBUG [main] > regionserver.HRegion(2078): 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', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], INDEXES => []}} > [junit] 2008-12-31 03:07:26,416 DEBUG [main] > regionserver.HRegion(232): Opening region -ROOT-,,0/70236052 > [junit] 2008-12-31 03:07:26,425 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/-ROOT-/70236052/info/info/2699768126272560048, > isReference=false, sequence id=1, length=577, > majorCompaction=false > [junit] 2008-12-31 03:07:26,426 DEBUG [main] > regionserver.HStore(231): Loaded 1 file(s) in hstore > 70236052/info, max sequence id 1 > [junit] 2008-12-31 03:07:26,432 DEBUG [main] > regionserver.HRegion(293): Next sequence id for region > -ROOT-,,0 is 2 > [junit] 2008-12-31 03:07:26,433 INFO [main] > regionserver.HRegion(315): region -ROOT-,,0/70236052 > available > [junit] 2008-12-31 03:07:26,433 DEBUG [main] > regionserver.HLog(215): changing sequence number from 0 to 2 > [junit] 2008-12-31 03:07:26,433 INFO [main] > regionserver.HRegion(703): starting compaction on region > -ROOT-,,0 > [junit] 2008-12-31 03:07:26,435 INFO [main] > regionserver.HRegion(718): compaction completed on region > -ROOT-,,0 in 0sec > [junit] 2008-12-31 03:07:26,441 INFO [main] > util.Merge(204): Found meta for region1 [...@8d539f, meta for > region2 [...@8d539f > [junit] 2008-12-31 03:07:26,441 DEBUG [main] > regionserver.HRegion(2078): 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', IN_MEMORY => 'false', > BLOCKCACHE => 'true'}, {NAME => > 'historian', BLOOMFILTER => 'false', > VERSIONS => '2147483647', COMPRESSION => > 'NONE', LENGTH => '2147483647', TTL => > '604800', IN_MEMORY => 'false', > BLOCKCACHE => 'false'}], INDEXES => []}} > [junit] 2008-12-31 03:07:26,442 DEBUG [main] > regionserver.HRegion(232): Opening region > .META.,,1/1028785192 > [junit] 2008-12-31 03:07:26,451 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/.META./1028785192/info/info/1179635509779055136, > isReference=false, sequence id=13, length=1214, > majorCompaction=false > [junit] 2008-12-31 03:07:26,457 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/.META./1028785192/info/info/2475022920103965175, > isReference=false, sequence id=5, length=1948, > majorCompaction=false > [junit] 2008-12-31 03:07:26,469 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/.META./1028785192/info/info/7945932892095673372, > isReference=false, sequence id=17, length=662, > majorCompaction=false > [junit] 2008-12-31 03:07:26,471 DEBUG [main] > regionserver.HStore(231): Loaded 3 file(s) in hstore > 1028785192/info, max sequence id 17 > [junit] 2008-12-31 03:07:26,489 DEBUG [main] > regionserver.HRegion(293): Next sequence id for region > .META.,,1 is 18 > [junit] 2008-12-31 03:07:26,490 INFO [main] > regionserver.HRegion(315): region .META.,,1/1028785192 > available > [junit] 2008-12-31 03:07:26,491 DEBUG [main] > regionserver.HLog(215): changing sequence number from 2 to > 18 > [junit] 2008-12-31 03:07:26,491 INFO [main] > regionserver.HRegion(703): starting compaction on region > .META.,,1 > [junit] 2008-12-31 03:07:26,492 DEBUG [main] > regionserver.HStore(832): 1028785192/historian: no store > files to compact > [junit] 2008-12-31 03:07:26,496 DEBUG [main] > regionserver.HStore(903): Compaction size of > 1028785192/info: 4.5k; Skipped 0 file(s), size: 0 > [junit] 2008-12-31 03:07:26,514 DEBUG [main] > regionserver.HStore(921): Started compaction of 3 file(s) > into > /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/7494668982597294048 > [junit] 2008-12-31 03:07:26,765 DEBUG [main] > regionserver.HStore(1242): moving > /user/hudson/.META./compaction.dir/1028785192/info/mapfiles/7494668982597294048 > to > /user/hudson/.META./1028785192/info/mapfiles/5187676432653891620 > [junit] 2008-12-31 03:07:27,037 DEBUG [main] > regionserver.HStore(940): Completed compaction of > 1028785192/info store size is 3.5k > [junit] 2008-12-31 03:07:27,063 INFO [main] > regionserver.HRegion(718): compaction completed on region > .META.,,1 in 0sec > [junit] 2008-12-31 03:07:27,064 DEBUG [main] > regionserver.HRegion(2078): Opening region: REGION => > {NAME => 'TestMergeTool,row_0100,1230692845108', > STARTKEY => 'row_0100', ENDKEY => > 'row_0600', ENCODED => 387787907, TABLE => > {{NAME => 'TestMergeTool', IS_ROOT => > 'false', IS_META => 'false', FAMILIES > => [{NAME => 'contents', BLOOMFILTER => > 'false', COMPRESSION => 'NONE', VERSIONS > => '3', LENGTH => '2147483647', TTL > => '-1', IN_MEMORY => 'false', > BLOCKCACHE => 'true'}], INDEXES => []}} > [junit] 2008-12-31 03:07:27,064 DEBUG [main] > regionserver.HRegion(232): Opening region > TestMergeTool,row_0100,1230692845108/387787907 > [junit] 2008-12-31 03:07:27,072 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/TestMergeTool/387787907/contents/info/4664132606861960662, > isReference=false, sequence id=2, length=486, > majorCompaction=false > [junit] 2008-12-31 03:07:27,073 DEBUG [main] > regionserver.HStore(231): Loaded 1 file(s) in hstore > 387787907/contents, max sequence id 2 > [junit] 2008-12-31 03:07:27,080 DEBUG [main] > regionserver.HRegion(293): Next sequence id for region > TestMergeTool,row_0100,1230692845108 is 3 > [junit] 2008-12-31 03:07:27,082 INFO [main] > regionserver.HRegion(315): region > TestMergeTool,row_0100,1230692845108/387787907 available > [junit] 2008-12-31 03:07:27,082 DEBUG [main] > regionserver.HRegion(2078): Opening region: REGION => > {NAME => 'TestMergeTool,,1230692818870', STARTKEY > => '', ENDKEY => '', ENCODED => > 963331172, TABLE => {{NAME => 'TestMergeTool', > IS_ROOT => 'false', IS_META => > 'false', FAMILIES => [{NAME => > 'contents', BLOOMFILTER => 'false', > VERSIONS => '3', COMPRESSION => > 'NONE', LENGTH => '2147483647', TTL => > '-1', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], INDEXES => []}} > [junit] 2008-12-31 03:07:27,082 DEBUG [main] > regionserver.HRegion(232): Opening region > TestMergeTool,,1230692818870/963331172 > [junit] 2008-12-31 03:07:27,090 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/TestMergeTool/963331172/contents/info/4943776554179962678, > isReference=false, sequence id=2, length=204, > majorCompaction=false > [junit] 2008-12-31 03:07:27,091 DEBUG [main] > regionserver.HStore(231): Loaded 1 file(s) in hstore > 963331172/contents, max sequence id 2 > [junit] 2008-12-31 03:07:27,097 DEBUG [main] > regionserver.HRegion(293): Next sequence id for region > TestMergeTool,,1230692818870 is 3 > [junit] 2008-12-31 03:07:27,098 INFO [main] > regionserver.HRegion(315): region > TestMergeTool,,1230692818870/963331172 available > [junit] 2008-12-31 03:07:27,098 INFO [main] > regionserver.HRegion(703): starting major compaction on > region TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:27,107 DEBUG [main] > regionserver.HStore(921): Started compaction of 1 file(s) > into > /user/hudson/TestMergeTool/compaction.dir/387787907/contents/mapfiles/3274350789475869209 > [junit] 2008-12-31 03:07:27,355 DEBUG [main] > regionserver.HStore(1242): moving > /user/hudson/TestMergeTool/compaction.dir/387787907/contents/mapfiles/3274350789475869209 > to > /user/hudson/TestMergeTool/387787907/contents/mapfiles/7672451893221368196 > [junit] 2008-12-31 03:07:27,564 DEBUG [main] > regionserver.HStore(940): Completed major compaction of > 387787907/contents store size is 486.0 > [junit] 2008-12-31 03:07:27,588 INFO [main] > regionserver.HRegion(718): compaction completed on region > TestMergeTool,row_0100,1230692845108 in 0sec > [junit] 2008-12-31 03:07:27,589 DEBUG [main] > regionserver.HRegion(2317): Files for region: > TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:27,590 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907/contents > [junit] 2008-12-31 03:07:27,591 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907/contents/info > [junit] 2008-12-31 03:07:27,591 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907/contents/info/7672451893221368196 > size=11 > [junit] 2008-12-31 03:07:27,592 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907/contents/mapfiles > [junit] 2008-12-31 03:07:27,593 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907/contents/mapfiles/7672451893221368196 > [junit] 2008-12-31 03:07:27,594 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907/contents/mapfiles/7672451893221368196/data > size=486 > [junit] 2008-12-31 03:07:27,594 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907/contents/mapfiles/7672451893221368196/index > size=227 > [junit] 2008-12-31 03:07:27,595 INFO [main] > regionserver.HRegion(703): starting major compaction on > region TestMergeTool,,1230692818870 > [junit] 2008-12-31 03:07:27,604 DEBUG [main] > regionserver.HStore(921): Started compaction of 1 file(s) > into > /user/hudson/TestMergeTool/compaction.dir/963331172/contents/mapfiles/8734782240285736443 > [junit] 2008-12-31 03:07:27,858 DEBUG [main] > regionserver.HStore(1242): moving > /user/hudson/TestMergeTool/compaction.dir/963331172/contents/mapfiles/8734782240285736443 > to > /user/hudson/TestMergeTool/963331172/contents/mapfiles/2589933262913296993 > [junit] 2008-12-31 03:07:28,023 DEBUG [main] > regionserver.HStore(940): Completed major compaction of > 963331172/contents store size is 204.0 > [junit] 2008-12-31 03:07:28,058 INFO [main] > regionserver.HRegion(718): compaction completed on region > TestMergeTool,,1230692818870 in 0sec > [junit] 2008-12-31 03:07:28,058 DEBUG [main] > regionserver.HRegion(2322): Files for region: > TestMergeTool,,1230692818870 > [junit] 2008-12-31 03:07:28,059 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172/contents > [junit] 2008-12-31 03:07:28,060 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172/contents/info > [junit] 2008-12-31 03:07:28,061 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172/contents/info/2589933262913296993 > size=11 > [junit] 2008-12-31 03:07:28,061 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172/contents/mapfiles > [junit] 2008-12-31 03:07:28,062 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172/contents/mapfiles/2589933262913296993 > [junit] 2008-12-31 03:07:28,062 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172/contents/mapfiles/2589933262913296993/data > size=204 > [junit] 2008-12-31 03:07:28,063 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172/contents/mapfiles/2589933262913296993/index > size=227 > [junit] 2008-12-31 03:07:28,063 INFO [main] > regionserver.HRegion(2346): Creating new region REGION => > {NAME => 'TestMergeTool,,1230692848063', STARTKEY > => '', ENDKEY => '', ENCODED => > 2050631581, TABLE => {{NAME => > 'TestMergeTool', IS_ROOT => 'false', > IS_META => 'false', FAMILIES => [{NAME => > 'contents', BLOOMFILTER => 'false', > COMPRESSION => 'NONE', VERSIONS => > '3', LENGTH => '2147483647', TTL => > '-1', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], INDEXES => []}} > [junit] 2008-12-31 03:07:28,094 INFO [main] > regionserver.HRegion(2355): starting merge of regions: > TestMergeTool,row_0100,1230692845108 and > TestMergeTool,,1230692818870 into new region REGION => > {NAME => 'TestMergeTool,,1230692848063', STARTKEY > => '', ENDKEY => '', ENCODED => > 2050631581, TABLE => {{NAME => > 'TestMergeTool', IS_ROOT => 'false', > IS_META => 'false', FAMILIES => [{NAME => > 'contents', BLOOMFILTER => 'false', > COMPRESSION => 'NONE', VERSIONS => > '3', LENGTH => '2147483647', TTL => > '-1', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], INDEXES => []}} with start key > <[...@3e89c3> and end key <[...@3e89c3> > [junit] 2008-12-31 03:07:28,094 DEBUG [main] > regionserver.HRegion(386): Closing > TestMergeTool,row_0100,1230692845108: compactions & > flushes disabled > [junit] 2008-12-31 03:07:28,094 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:28,095 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:28,095 DEBUG [main] > regionserver.HStore(598): closed 387787907/contents > [junit] 2008-12-31 03:07:28,095 INFO [main] > regionserver.HRegion(435): Closed > TestMergeTool,row_0100,1230692845108 > [junit] 2008-12-31 03:07:28,096 DEBUG [main] > regionserver.HRegion(386): Closing > TestMergeTool,,1230692818870: compactions & flushes > disabled > [junit] 2008-12-31 03:07:28,096 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on TestMergeTool,,1230692818870 > [junit] 2008-12-31 03:07:28,096 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region TestMergeTool,,1230692818870 > [junit] 2008-12-31 03:07:28,096 DEBUG [main] > regionserver.HStore(598): closed 963331172/contents > [junit] 2008-12-31 03:07:28,096 INFO [main] > regionserver.HRegion(435): Closed > TestMergeTool,,1230692818870 > [junit] 2008-12-31 03:07:28,174 DEBUG [main] > regionserver.HRegion(2378): Adjusting sequence id of > storeFile 963331172/contents/2589933262913296993 down by > one; sequence id A=2, sequence id B=2 > [junit] 2008-12-31 03:07:28,275 DEBUG [main] > regionserver.HRegion(2389): Renaming > 387787907/contents/7672451893221368196 to > 2050631581/contents/7872827285179215279 > [junit] 2008-12-31 03:07:28,347 DEBUG [main] > regionserver.HRegion(2389): Renaming > 963331172/contents/2589933262913296993 to > 2050631581/contents/7985858546101371623 > [junit] 2008-12-31 03:07:28,453 DEBUG [main] > regionserver.HRegion(2395): Files for new region > [junit] 2008-12-31 03:07:28,454 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents > [junit] 2008-12-31 03:07:28,454 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/info > [junit] 2008-12-31 03:07:28,455 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/info/7872827285179215279 > size=11 > [junit] 2008-12-31 03:07:28,456 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/info/7985858546101371623 > size=9 > [junit] 2008-12-31 03:07:28,456 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles > [junit] 2008-12-31 03:07:28,456 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/7872827285179215279 > [junit] 2008-12-31 03:07:28,457 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/7872827285179215279/data > size=486 > [junit] 2008-12-31 03:07:28,457 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/7872827285179215279/index > size=227 > [junit] 2008-12-31 03:07:28,458 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/7985858546101371623 > [junit] 2008-12-31 03:07:28,458 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/7985858546101371623/data > size=204 > [junit] 2008-12-31 03:07:28,459 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/7985858546101371623/index > size=227 > [junit] 2008-12-31 03:07:28,459 DEBUG [main] > regionserver.HRegion(232): Opening region > TestMergeTool,,1230692848063/2050631581 > [junit] 2008-12-31 03:07:28,466 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/TestMergeTool/2050631581/contents/info/7872827285179215279, > isReference=false, sequence id=2, length=486, > majorCompaction=false > [junit] 2008-12-31 03:07:28,501 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/TestMergeTool/2050631581/contents/info/7985858546101371623, > isReference=false, sequence id=1, length=204, > majorCompaction=false > [junit] 2008-12-31 03:07:28,502 DEBUG [main] > regionserver.HStore(231): Loaded 2 file(s) in hstore > 2050631581/contents, max sequence id 2 > [junit] 2008-12-31 03:07:28,513 DEBUG [main] > regionserver.HRegion(293): Next sequence id for region > TestMergeTool,,1230692848063 is 3 > [junit] 2008-12-31 03:07:28,515 INFO [main] > regionserver.HRegion(315): region > TestMergeTool,,1230692848063/2050631581 available > [junit] 2008-12-31 03:07:28,515 INFO [main] > regionserver.HRegion(703): starting compaction on region > TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:28,518 DEBUG [main] > regionserver.HStore(903): Compaction size of > 2050631581/contents: 1.1k; Skipped 0 file(s), size: 0 > [junit] 2008-12-31 03:07:28,528 DEBUG [main] > regionserver.HStore(921): Started compaction of 2 file(s) > into > /user/hudson/TestMergeTool/compaction.dir/2050631581/contents/mapfiles/1255325855965563493 > [junit] 2008-12-31 03:07:29,297 DEBUG [main] > regionserver.HStore(1242): moving > /user/hudson/TestMergeTool/compaction.dir/2050631581/contents/mapfiles/1255325855965563493 > to > /user/hudson/TestMergeTool/2050631581/contents/mapfiles/2475309012236564396 > [junit] 2008-12-31 03:07:29,489 DEBUG [main] > regionserver.HStore(940): Completed compaction of > 2050631581/contents store size is 580.0 > [junit] 2008-12-31 03:07:29,519 INFO [main] > regionserver.HRegion(718): compaction completed on region > TestMergeTool,,1230692848063 in 1sec > [junit] 2008-12-31 03:07:29,519 DEBUG [main] > regionserver.HRegion(2402): Files for new region > [junit] 2008-12-31 03:07:29,520 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents > [junit] 2008-12-31 03:07:29,521 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/info > [junit] 2008-12-31 03:07:29,522 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/info/2475309012236564396 > size=9 > [junit] 2008-12-31 03:07:29,522 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles > [junit] 2008-12-31 03:07:29,523 DEBUG [main] > regionserver.HRegion(2468): d > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/2475309012236564396 > [junit] 2008-12-31 03:07:29,524 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/2475309012236564396/data > size=580 > [junit] 2008-12-31 03:07:29,524 DEBUG [main] > regionserver.HRegion(2471): f > hdfs://localhost:56983/user/hudson/TestMergeTool/2050631581/contents/mapfiles/2475309012236564396/index > size=227 > [junit] 2008-12-31 03:07:29,524 DEBUG [main] > regionserver.HRegion(2196): DELETING region > hdfs://localhost:56983/user/hudson/TestMergeTool/387787907 > [junit] 2008-12-31 03:07:29,553 DEBUG [main] > regionserver.HRegion(2196): DELETING region > hdfs://localhost:56983/user/hudson/TestMergeTool/963331172 > [junit] 2008-12-31 03:07:29,586 INFO [main] > regionserver.HRegion(2408): merge completed. New region is > TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:29,586 DEBUG [main] > util.Merge(308): Removing region: REGION => {NAME => > 'TestMergeTool,row_0100,1230692845108', STARTKEY > => 'row_0100', ENDKEY => 'row_0600', > ENCODED => 387787907, TABLE => {{NAME => > 'TestMergeTool', IS_ROOT => 'false', > IS_META => 'false', FAMILIES => [{NAME => > 'contents', BLOOMFILTER => 'false', > COMPRESSION => 'NONE', VERSIONS => > '3', LENGTH => '2147483647', TTL => > '-1', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], INDEXES => []}} from .META.,,1 > [junit] 2008-12-31 03:07:29,587 DEBUG [main] > util.Merge(308): Removing region: REGION => {NAME => > 'TestMergeTool,,1230692818870', STARTKEY => > '', ENDKEY => '', ENCODED => > 963331172, TABLE => {{NAME => 'TestMergeTool', > IS_ROOT => 'false', IS_META => > 'false', FAMILIES => [{NAME => > 'contents', BLOOMFILTER => 'false', > VERSIONS => '3', COMPRESSION => > 'NONE', LENGTH => '2147483647', TTL => > '-1', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], INDEXES => []}} from .META.,,1 > [junit] 2008-12-31 03:07:29,593 INFO [main] > util.Merge(244): Adding REGION => {NAME => > 'TestMergeTool,,1230692848063', STARTKEY => > '', ENDKEY => '', ENCODED => > 2050631581, TABLE => {{NAME => > 'TestMergeTool', IS_ROOT => 'false', > IS_META => 'false', FAMILIES => [{NAME => > 'contents', BLOOMFILTER => 'false', > COMPRESSION => 'NONE', VERSIONS => > '3', LENGTH => '2147483647', TTL => > '-1', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], 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', IN_MEMORY => 'false', > BLOCKCACHE => 'true'}, {NAME => > 'historian', BLOOMFILTER => 'false', > VERSIONS => '2147483647', COMPRESSION => > 'NONE', LENGTH => '2147483647', TTL => > '604800', IN_MEMORY => 'false', > BLOCKCACHE => 'false'}], INDEXES => []}} > [junit] 2008-12-31 03:07:29,594 DEBUG [main] > regionserver.HRegion(386): Closing > TestMergeTool,,1230692848063: compactions & flushes > disabled > [junit] 2008-12-31 03:07:29,594 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:29,594 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:29,594 DEBUG [main] > regionserver.HStore(598): closed 2050631581/contents > [junit] 2008-12-31 03:07:29,594 INFO [main] > regionserver.HRegion(435): Closed > TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:29,595 DEBUG [main] > regionserver.HRegion(386): Closing -ROOT-,,0: compactions > & flushes disabled > [junit] 2008-12-31 03:07:29,595 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on -ROOT-,,0 > [junit] 2008-12-31 03:07:29,595 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region -ROOT-,,0 > [junit] 2008-12-31 03:07:29,595 DEBUG [main] > regionserver.HStore(598): closed 70236052/info > [junit] 2008-12-31 03:07:29,595 INFO [main] > regionserver.HRegion(435): Closed -ROOT-,,0 > [junit] 2008-12-31 03:07:29,595 DEBUG [main] > regionserver.HRegion(386): Closing .META.,,1: compactions > & flushes disabled > [junit] 2008-12-31 03:07:29,596 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on .META.,,1 > [junit] 2008-12-31 03:07:29,596 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region .META.,,1 > [junit] 2008-12-31 03:07:29,596 DEBUG [main] > regionserver.HRegion(828): Started memcache flush for region > .META.,,1. Current region memcache size 905.0 > [junit] 2008-12-31 03:07:29,850 DEBUG [main] > regionserver.HStore(694): Added > /user/hudson/.META./1028785192/info/mapfiles/5774229911650223267 > with 3 entries, sequence id 21, data size ~905.0, file size > 622.0 to .META.,,1 > [junit] 2008-12-31 03:07:29,851 DEBUG [main] > regionserver.HRegion(905): Finished memcache flush of ~905.0 > for region .META.,,1 in 255ms, sequence id=21, compaction > requested=true > [junit] 2008-12-31 03:07:29,851 DEBUG [main] > regionserver.HStore(598): closed 1028785192/historian > [junit] 2008-12-31 03:07:29,851 DEBUG [main] > regionserver.HStore(598): closed 1028785192/info > [junit] 2008-12-31 03:07:29,851 INFO [main] > regionserver.HRegion(435): Closed .META.,,1 > [junit] 2008-12-31 03:07:29,915 INFO [main] > regionserver.HLog(265): Closed > hdfs://localhost:56983/user/hudson/log_1230692846341/hlog.dat.0, > entries=4. New log writer: > /user/hudson/log_1230692846341/hlog.dat.1230692849889 > [junit] 2008-12-31 03:07:29,915 DEBUG [main] > regionserver.HLog(272): Last sequence written is empty. > Deleting all old hlogs > [junit] 2008-12-31 03:07:29,915 INFO [main] > regionserver.HLog(383): removing old log file > /user/hudson/log_1230692846341/hlog.dat.0 whose highest > sequence/edit id is 21 > [junit] 2008-12-31 03:07:29,916 DEBUG [main] > regionserver.HLog(419): closing log writer in > hdfs://localhost:56983/user/hudson/log_1230692846341 > [junit] 2008-12-31 03:07:29,975 DEBUG [main] > regionserver.HRegion(2078): Opening region: REGION => > {NAME => 'TestMergeTool,,1230692848063', STARTKEY > => '', ENDKEY => '', ENCODED => > 2050631581, TABLE => {{NAME => > 'TestMergeTool', IS_ROOT => 'false', > IS_META => 'false', FAMILIES => [{NAME => > 'contents', BLOOMFILTER => 'false', > COMPRESSION => 'NONE', VERSIONS => > '3', LENGTH => '2147483647', TTL => > '-1', IN_MEMORY => 'false', BLOCKCACHE > => 'true'}], INDEXES => []}} > [junit] 2008-12-31 03:07:29,975 DEBUG [main] > regionserver.HRegion(232): Opening region > TestMergeTool,,1230692848063/2050631581 > [junit] 2008-12-31 03:07:29,982 DEBUG [main] > regionserver.HStore(492): loaded > /user/hudson/TestMergeTool/2050631581/contents/info/2475309012236564396, > isReference=false, sequence id=2, length=580, > majorCompaction=false > [junit] 2008-12-31 03:07:29,983 DEBUG [main] > regionserver.HStore(231): Loaded 1 file(s) in hstore > 2050631581/contents, max sequence id 2 > [junit] 2008-12-31 03:07:29,989 DEBUG [main] > regionserver.HRegion(293): Next sequence id for region > TestMergeTool,,1230692848063 is 3 > [junit] 2008-12-31 03:07:29,990 INFO [main] > regionserver.HRegion(315): region > TestMergeTool,,1230692848063/2050631581 available > [junit] 2008-12-31 03:07:29,992 DEBUG [main] > regionserver.HRegion(386): Closing > TestMergeTool,,1230692848063: compactions & flushes > disabled > [junit] 2008-12-31 03:07:29,992 DEBUG [main] > regionserver.HRegion(416): Updates disabled for region, no > outstanding scanners on TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:29,992 DEBUG [main] > regionserver.HRegion(423): No more row locks outstanding on > region TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:29,992 DEBUG [main] > regionserver.HStore(598): closed 2050631581/contents > [junit] 2008-12-31 03:07:29,992 INFO [main] > regionserver.HRegion(435): Closed > TestMergeTool,,1230692848063 > [junit] 2008-12-31 03:07:29,992 INFO [main] > util.TestMergeTool(171): Verified merging regions 0+1+2+3 > and 4 > [junit] 2008-12-31 03:07:29,993 DEBUG [main] > regionserver.HLog(419): closing log writer in > /tmp/log_1230692827490 > [junit] 2008-12-31 03:07:30,074 INFO [main] > hbase.HBaseTestCase(600): Shutting down FileSystem > [junit] 2008-12-31 03:07:30,075 INFO [main] > hbase.HBaseTestCase(607): Shutting down Mini DFS > [junit] Shutting down the Mini HDFS Cluster > [junit] Shutting down DataNode 1 > [junit] 2008-12-31 03:07:30,081 INFO [main] > http.SocketListener(212): Stopped SocketListener on > 127.0.0.1:56991 > [junit] 2008-12-31 03:07:30,082 INFO [main] > util.Container(156): Stopped > org.mortbay.jetty.servlet.webapplicationhand...@cb42cf > [junit] 2008-12-31 03:07:30,232 INFO [main] > util.Container(156): Stopped > WebApplicationContext[/static,/static] > [junit] 2008-12-31 03:07:30,233 INFO [main] > util.Container(156): Stopped > org.mortbay.jetty.servlet.webapplicationhand...@ed5d9d > [junit] 2008-12-31 03:07:30,317 INFO [main] > util.Container(156): Stopped WebApplicationContext[/,/] > [junit] 2008-12-31 03:07:30,317 INFO [main] > util.Container(156): Stopped org.mortbay.jetty.ser...@670479 > [junit] Shutting down DataNode 0 > [junit] 2008-12-31 03:07:31,324 INFO [Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=56988]] > util.ThreadedServer$Acceptor(656): Stopping Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=56988] > [junit] 2008-12-31 03:07:31,324 INFO [main] > http.SocketListener(212): Stopped SocketListener on > 127.0.0.1:56988 > [junit] 2008-12-31 03:07:31,326 INFO [main] > util.Container(156): Stopped > org.mortbay.jetty.servlet.webapplicationhand...@15e92d7 > [junit] 2008-12-31 03:07:31,433 INFO [main] > util.Container(156): Stopped > WebApplicationContext[/static,/static] > [junit] 2008-12-31 03:07:31,434 INFO [main] > util.Container(156): Stopped > org.mortbay.jetty.servlet.webapplicationhand...@cffc79 > [junit] 2008-12-31 03:07:31,510 INFO [main] > util.Container(156): Stopped WebApplicationContext[/,/] > [junit] 2008-12-31 03:07:31,510 INFO [main] > util.Container(156): Stopped org.mortbay.jetty.ser...@510e39 > [junit] 2008-12-31 03:07:32,515 INFO [Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=56984]] > util.ThreadedServer$Acceptor(656): Stopping Acceptor > ServerSocket[addr=localhost/127.0.0.1,port=0,localport=56984] > [junit] 2008-12-31 03:07:32,515 INFO [main] > http.SocketListener(212): Stopped SocketListener on > 127.0.0.1:56984 > [junit] 2008-12-31 03:07:32,515 INFO [main] > util.Container(156): Stopped > org.mortbay.jetty.servlet.webapplicationhand...@195dd5b > [junit] 2008-12-31 03:07:32,588 INFO [main] > util.Container(156): Stopped > WebApplicationContext[/static,/static] > [junit] 2008-12-31 03:07:32,589 INFO [main] > util.Container(156): Stopped > org.mortbay.jetty.servlet.webapplicationhand...@e34726 > [junit] 2008-12-31 03:07:32,661 INFO [main] > util.Container(156): Stopped WebApplicationContext[/,/] > [junit] 2008-12-31 03:07:32,662 INFO [main] > util.Container(156): Stopped > org.mortbay.jetty.ser...@1b62aab > [junit] 2008-12-31 03:07:32,662 WARN > [org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@ba5bdb] > namenode.FSNamesystem$ReplicationMonitor(2300): > ReplicationMonitor thread received > InterruptedException.java.lang.InterruptedException: sleep > interrupted > [junit] Tests run: 1, Failures: 0, Errors: 0, Time > elapsed: 33.997 sec > [junit] Running > org.apache.hadoop.hbase.util.TestRootPath > [junit] 2008-12-31 03:07:33,285 INFO [main] > util.TestRootPath(60): Got expected exception when checking > invalid path: > [junit] java.io.IOException: Root directory does not > contain a scheme > [junit] at > org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:158) > [junit] at > org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56) > [junit] at > sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > [junit] at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) > [junit] at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) > [junit] at > java.lang.reflect.Method.invoke(Method.java:597) > [junit] at > junit.framework.TestCase.runTest(TestCase.java:154) > [junit] at > junit.framework.TestCase.runBare(TestCase.java:127) > [junit] at > junit.framework.TestResult$1.protect(TestResult.java:106) > [junit] at > junit.framework.TestResult.runProtected(TestResult.java:124) > [junit] at > junit.framework.TestResult.run(TestResult.java:109) > [junit] at > junit.framework.TestCase.run(TestCase.java:118) > [junit] at > junit.framework.TestSuite.runTest(TestSuite.java:208) > [junit] at > junit.framework.TestSuite.run(TestSuite.java:203) > [junit] at > org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421) > [junit] at > org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912) > [junit] at > org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766) > [junit] Tests run: 1, Failures: 0, Errors: 0, Time > elapsed: 0.058 sec > [junit] Running org.onelab.test.TestFilter > [junit] 2008-12-31 03:07:33,823 INFO [main] > test.TestFilter(244): Checking for false negatives > [junit] 2008-12-31 03:07:33,827 INFO [main] > test.TestFilter(255): Checking for false positives > [junit] 2008-12-31 03:07:33,828 INFO [main] > test.TestFilter(262): Success! > [junit] 2008-12-31 03:07:33,829 INFO [main] > test.TestFilter(226): Checking serialization/deserialization > [junit] 2008-12-31 03:07:33,830 INFO [main] > test.TestFilter(244): Checking for false negatives > [junit] 2008-12-31 03:07:33,832 INFO [main] > test.TestFilter(255): Checking for false positives > [junit] 2008-12-31 03:07:33,833 INFO [main] > test.TestFilter(262): Success! > [junit] Tests run: 3, Failures: 0, Errors: 0, Time > elapsed: 0.064 sec > > BUILD FAILED > /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:453: > Tests failed! > > Total time: 36 minutes 47 seconds > [locks-and-latches] Releasing all the locks > [locks-and-latches] All the locks released > Recording test results
