You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@hbase.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2009/06/30 05:57:28 UTC
Build failed in Hudson: HBase-Patch #685
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/685/changes
Changes:
[stack] HBASE-1591 HBASE-1554 broke org.apache.hadoop.hbase.io.hfile.TestLruBlockCache.testResizeBlockCache
------------------------------------------
[...truncated 27874 lines...]
[junit] 2009-06-30 03:57:16,316 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 03:57:16,317 DEBUG [main] regionserver.HRegion(264): Opening region -ROOT-,,0, encoded=70236052
[junit] 2009-06-30 03:57:16,323 DEBUG [main] regionserver.Store(380): loaded /user/hudson/-ROOT-/70236052/info/4339548386246392681, isReference=false, sequence id=2, length=840, majorCompaction=false
[junit] 2009-06-30 03:57:16,325 INFO [main] regionserver.HRegion(339): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2009-06-30 03:57:16,325 INFO [main] regionserver.HRegion(764): Starting compaction on region -ROOT-,,0
[junit] 2009-06-30 03:57:16,327 INFO [main] regionserver.HRegion(779): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2009-06-30 03:57:16,328 INFO [main] util.Merge(211): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2009-06-30 03:57:16,328 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 03:57:16,329 DEBUG [main] regionserver.HRegion(264): Opening region .META.,,1, encoded=1028785192
[junit] 2009-06-30 03:57:16,336 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/5069761041856656638, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2009-06-30 03:57:16,340 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/6185116046084952634, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2009-06-30 03:57:16,345 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/3580226687465594789, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2009-06-30 03:57:16,349 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/8246226911915738154, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2009-06-30 03:57:16,353 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/8694815133596552284, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2009-06-30 03:57:16,355 INFO [main] regionserver.HRegion(339): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2009-06-30 03:57:16,356 INFO [main] regionserver.HRegion(764): Starting compaction on region .META.,,1
[junit] 2009-06-30 03:57:16,368 DEBUG [main] regionserver.Store(710): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 03:57:16,388 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/4345181293809946232
[junit] 2009-06-30 03:57:16,463 DEBUG [main] regionserver.Store(734): Completed compaction of historian; store size is 781.0
[junit] 2009-06-30 03:57:16,465 DEBUG [main] regionserver.Store(710): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2009-06-30 03:57:16,483 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/4756716123822289492
[junit] 2009-06-30 03:57:16,559 DEBUG [main] regionserver.Store(734): Completed compaction of info; store size is 3.3k
[junit] 2009-06-30 03:57:16,575 INFO [main] regionserver.HRegion(779): compaction completed on region .META.,,1 in 0sec
[junit] 2009-06-30 03:57:16,577 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246334235613', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 296810668, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 03:57:16,577 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246334235613, encoded=296810668
[junit] 2009-06-30 03:57:16,583 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/296810668/contents/88071840529929138, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-06-30 03:57:16,586 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246334235613/296810668 available; sequence id is 7
[junit] 2009-06-30 03:57:16,586 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246334227227', STARTKEY => '', ENDKEY => '', ENCODED => 556286697, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 03:57:16,586 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246334227227, encoded=556286697
[junit] 2009-06-30 03:57:16,594 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/556286697/contents/1084511642783190185, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2009-06-30 03:57:16,596 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246334227227/556286697 available; sequence id is 8
[junit] 2009-06-30 03:57:16,596 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,row_0100,1246334235613
[junit] 2009-06-30 03:57:16,627 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/296810668/3365216675693282626
[junit] 2009-06-30 03:57:16,682 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 791.0
[junit] 2009-06-30 03:57:16,853 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0100,1246334235613 in 0sec
[junit] 2009-06-30 03:57:16,853 DEBUG [main] regionserver.HRegion(2070): Files for region: TestMergeTool,row_0100,1246334235613
[junit] 2009-06-30 03:57:16,854 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/.regioninfo size=631
[junit] 2009-06-30 03:57:16,854 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/contents
[junit] 2009-06-30 03:57:16,855 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/contents/8452771743625025355 size=791
[junit] 2009-06-30 03:57:16,855 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,,1246334227227
[junit] 2009-06-30 03:57:16,885 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/556286697/6880362628274821301
[junit] 2009-06-30 03:57:16,956 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 471.0
[junit] 2009-06-30 03:57:16,972 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246334227227 in 0sec
[junit] 2009-06-30 03:57:16,972 DEBUG [main] regionserver.HRegion(2075): Files for region: TestMergeTool,,1246334227227
[junit] 2009-06-30 03:57:16,973 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/.regioninfo size=583
[junit] 2009-06-30 03:57:16,973 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/contents
[junit] 2009-06-30 03:57:16,974 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/contents/4035034942458743945 size=471
[junit] 2009-06-30 03:57:16,975 INFO [main] regionserver.HRegion(2104): Creating new region REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 03:57:16,988 INFO [main] regionserver.HRegion(2113): starting merge of regions: TestMergeTool,row_0100,1246334235613 and TestMergeTool,,1246334227227 into new region REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <> and end key <>
[junit] 2009-06-30 03:57:16,988 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246334235613: compactions & flushes disabled
[junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246334235613
[junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246334235613
[junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 03:57:16,989 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246334235613
[junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246334227227: compactions & flushes disabled
[junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246334227227
[junit] 2009-06-30 03:57:16,990 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246334227227
[junit] 2009-06-30 03:57:16,990 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 03:57:16,990 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246334227227
[junit] 2009-06-30 03:57:17,036 DEBUG [main] regionserver.HRegion(2146): Files for new region
[junit] 2009-06-30 03:57:17,037 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents
[junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/4373146720411115184 size=791
[junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/4708547828713801463 size=471
[junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246334236974, encoded=1737903548
[junit] 2009-06-30 03:57:17,080 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1737903548/contents/4373146720411115184, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2009-06-30 03:57:17,084 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1737903548/contents/4708547828713801463, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2009-06-30 03:57:17,087 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246334236974/1737903548 available; sequence id is 8
[junit] 2009-06-30 03:57:17,087 INFO [main] regionserver.HRegion(764): Starting compaction on region TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,096 DEBUG [main] regionserver.Store(710): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 03:57:17,111 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1737903548/2106020000373448348
[junit] 2009-06-30 03:57:17,174 DEBUG [main] regionserver.Store(734): Completed compaction of contents; store size is 929.0
[junit] 2009-06-30 03:57:17,190 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246334236974 in 0sec
[junit] 2009-06-30 03:57:17,191 DEBUG [main] regionserver.HRegion(2153): Files for new region
[junit] 2009-06-30 03:57:17,192 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/.regioninfo size=584
[junit] 2009-06-30 03:57:17,192 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents
[junit] 2009-06-30 03:57:17,193 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/1215187022045408950 size=929
[junit] 2009-06-30 03:57:17,194 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:45638/user/hudson/TestMergeTool/296810668
[junit] 2009-06-30 03:57:17,206 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:45638/user/hudson/TestMergeTool/556286697
[junit] 2009-06-30 03:57:17,222 INFO [main] regionserver.HRegion(2159): merge completed. New region is TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,222 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1246334235613', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 296810668, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 03:57:17,223 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,,1246334227227', STARTKEY => '', ENDKEY => '', ENCODED => 556286697, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 03:57:17,224 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246334236974: compactions & flushes disabled
[junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 03:57:17,226 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 03:57:17,227 INFO [main] regionserver.HRegion(485): Closed -ROOT-,,0
[junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region .META.,,1
[junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(889): Started memstore flush for region .META.,,1. Current region memstore size 1.1k
[junit] 2009-06-30 03:57:17,265 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:45638/user/hudson/.META./1028785192/historian/7821267978201518970, entries=2, sequenceid=34, memsize=356.0, filesize=505.0 to .META.,,1
[junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:45638/user/hudson/.META./1028785192/info/6068259940689918333, entries=3, sequenceid=34, memsize=804.0, filesize=826.0 to .META.,,1
[junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.HRegion(966): Finished memstore flush of ~1.1k for region .META.,,1 in 74ms, sequence id=34, compaction requested=true
[junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.Store(445): closed historian
[junit] 2009-06-30 03:57:17,302 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 03:57:17,302 INFO [main] regionserver.HRegion(485): Closed .META.,,1
[junit] 2009-06-30 03:57:17,335 INFO [main] regionserver.HLog(294): Roll /user/hudson/.logs_1246334236267/hlog.dat.1246334236300, entries=6, calcsize=1083, filesize=1103. New hlog /user/hudson/.logs_1246334236267/hlog.dat.1246334237318
[junit] 2009-06-30 03:57:17,335 DEBUG [main] regionserver.HLog(303): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-06-30 03:57:17,335 INFO [main] regionserver.HLog(428): removing old hlog file /user/hudson/.logs_1246334236267/hlog.dat.1246334236300 whose highest sequence/edit id is 33
[junit] 2009-06-30 03:57:17,349 DEBUG [main] regionserver.HLog(465): closing hlog writer in hdfs://localhost:45638/user/hudson/.logs_1246334236267
[junit] 2009-06-30 03:57:17,381 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 03:57:17,382 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246334236974, encoded=1737903548
[junit] 2009-06-30 03:57:17,388 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1737903548/contents/1215187022045408950, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2009-06-30 03:57:17,390 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246334236974/1737903548 available; sequence id is 8
[junit] 2009-06-30 03:57:17,395 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246334236974: compactions & flushes disabled
[junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 03:57:17,396 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246334236974
[junit] 2009-06-30 03:57:17,396 INFO [main] util.TestMergeTool(187): Verified merging regions 0+1+2+3 and 4
[junit] 2009-06-30 03:57:17,397 DEBUG [main] regionserver.HLog(465): closing hlog writer in /tmp/.logs_1246334232474
[junit] 2009-06-30 03:57:17,433 INFO [main] hbase.HBaseTestCase(627): Shutting down FileSystem
[junit] 2009-06-30 03:57:17,433 INFO [main] hbase.HBaseTestCase(634): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-30 03:57:17,446 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@14627a] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:51166, storageID=DS-1933627198-67.195.138.9-51166-1246334230528, infoPort=44091, ipcPort=43500):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2009-06-30 03:57:18,466 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@682406] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:58118, storageID=DS-35413305-67.195.138.9-58118-1246334229778, infoPort=40885, ipcPort=56618):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-06-30 03:57:19,466 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@2f0d54] namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 12.365 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-06-30 03:57:19,889 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not contain a scheme
[junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:212)
[junit] at org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at junit.framework.TestCase.runTest(TestCase.java:154)
[junit] at junit.framework.TestCase.runBare(TestCase.java:127)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:124)
[junit] at junit.framework.TestResult.run(TestResult.java:109)
[junit] at junit.framework.TestCase.run(TestCase.java:118)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
[junit] at junit.framework.TestSuite.run(TestSuite.java:203)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.072 sec
test-contrib:
test:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
compile-war:
compile-test:
[javac] Compiling 20 source files to http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/test
test:
[junit] Running org.apache.hadoop.hbase.stargate.Test00MiniCluster
[junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you sure you have Clover in the runtime classpath? (class java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.Test00MiniCluster FAILED
[junit] Running org.apache.hadoop.hbase.stargate.TestRowResource
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.TestRowResource FAILED
[junit] Running org.apache.hadoop.hbase.stargate.TestScannerResource
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.TestScannerResource FAILED
[junit] Running org.apache.hadoop.hbase.stargate.TestSchemaResource
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.TestSchemaResource FAILED
[junit] Running org.apache.hadoop.hbase.stargate.TestStatusResource
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.TestStatusResource FAILED
[junit] Running org.apache.hadoop.hbase.stargate.TestTableResource
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.TestTableResource FAILED
[junit] Running org.apache.hadoop.hbase.stargate.TestVersionResource
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.TestVersionResource FAILED
[junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you sure you have Clover in the runtime classpath? (class java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
[junit] Running org.apache.hadoop.hbase.stargate.model.TestCellModel
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestCellModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestCellSetModel
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestCellSetModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestColumnSchemaModel
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.028 sec
[junit] Running org.apache.hadoop.hbase.stargate.model.TestRowModel
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestRowModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestScannerModel
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestScannerModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestStorageClusterStatusModel
[junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you sure you have Clover in the runtime classpath? (class java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
[junit] Tests run: 3, Failures: 0, Errors: 3, Time elapsed: 0.013 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestStorageClusterStatusModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestStorageClusterVersionModel
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.006 sec
[junit] Running org.apache.hadoop.hbase.stargate.model.TestTableInfoModel
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableInfoModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestTableListModel
[junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableListModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestTableRegionModel
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableRegionModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestTableSchemaModel
[junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableSchemaModel FAILED
[junit] Running org.apache.hadoop.hbase.stargate.model.TestVersionModel
[junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
[junit] TEST org.apache.hadoop.hbase.stargate.model.TestVersionModel FAILED
[junit] Tests FAILED
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :511: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build.xml :54: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/stargate/build.xml :66: Tests failed!
Total time: 40 minutes 51 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...
Hudson build is back to normal: HBase-Patch #689
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/689/changes
Build failed in Hudson: HBase-Patch #688
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/688/changes
Changes:
[stack] HBASE-1594 Fix scan addcolumns after hbase-1385 commit (broken hudson build)
------------------------------------------
[...truncated 31761 lines...]
[junit] 2009-06-30 22:01:57,692 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 471.0
[junit] 2009-06-30 22:01:57,702 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0500,1246399276162 in 0sec
[junit] 2009-06-30 22:01:57,703 DEBUG [main] regionserver.HRegion(2075): Files for region: TestMergeTool,row_0500,1246399276162
[junit] 2009-06-30 22:01:57,704 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/1519757544/.regioninfo size=632
[junit] 2009-06-30 22:01:57,705 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:35148/user/hudson/TestMergeTool/1519757544/contents
[junit] 2009-06-30 22:01:57,706 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/1519757544/contents/5189086775946386483 size=471
[junit] 2009-06-30 22:01:57,706 INFO [main] regionserver.HRegion(2104): Creating new region REGION => {NAME => 'TestMergeTool,row_0100,1246399317706', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2049149723, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:57,716 INFO [main] regionserver.HRegion(2113): starting merge of regions: TestMergeTool,row_0100,1246399316578 and TestMergeTool,row_0500,1246399276162 into new region REGION => {NAME => 'TestMergeTool,row_0100,1246399317706', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2049149723, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <row_0100> and end key <row_0600>
[junit] 2009-06-30 22:01:57,716 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246399316578: compactions & flushes disabled
[junit] 2009-06-30 22:01:57,717 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246399316578
[junit] 2009-06-30 22:01:57,717 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246399316578
[junit] 2009-06-30 22:01:57,717 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:57,718 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246399316578
[junit] 2009-06-30 22:01:57,718 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0500,1246399276162: compactions & flushes disabled
[junit] 2009-06-30 22:01:57,718 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0500,1246399276162
[junit] 2009-06-30 22:01:57,719 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0500,1246399276162
[junit] 2009-06-30 22:01:57,719 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:57,719 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0500,1246399276162
[junit] 2009-06-30 22:01:57,752 DEBUG [main] regionserver.HRegion(2146): Files for new region
[junit] 2009-06-30 22:01:57,754 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/contents
[junit] 2009-06-30 22:01:57,755 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/contents/1942709506765161550 size=653
[junit] 2009-06-30 22:01:57,757 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/contents/4476073015574583752 size=471
[junit] 2009-06-30 22:01:57,758 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246399317706, encoded=2049149723
[junit] 2009-06-30 22:01:57,788 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/2049149723/contents/1942709506765161550, isReference=false, sequence id=5, length=653, majorCompaction=true
[junit] 2009-06-30 22:01:57,793 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/2049149723/contents/4476073015574583752, isReference=false, sequence id=6, length=471, majorCompaction=true
[junit] 2009-06-30 22:01:57,796 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246399317706/2049149723 available; sequence id is 7
[junit] 2009-06-30 22:01:57,796 INFO [main] regionserver.HRegion(764): Starting compaction on region TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:57,808 DEBUG [main] regionserver.Store(710): Compaction size of contents: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 22:01:57,820 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/2049149723/2510102360374032087
[junit] 2009-06-30 22:01:58,003 DEBUG [main] regionserver.Store(734): Completed compaction of contents; store size is 791.0
[junit] 2009-06-30 22:01:58,013 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0100,1246399317706 in 0sec
[junit] 2009-06-30 22:01:58,014 DEBUG [main] regionserver.HRegion(2153): Files for new region
[junit] 2009-06-30 22:01:58,015 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/.regioninfo size=632
[junit] 2009-06-30 22:01:58,015 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/contents
[junit] 2009-06-30 22:01:58,016 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/contents/4319690479893695652 size=791
[junit] 2009-06-30 22:01:58,016 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:35148/user/hudson/TestMergeTool/887004818
[junit] 2009-06-30 22:01:58,025 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:35148/user/hudson/TestMergeTool/1519757544
[junit] 2009-06-30 22:01:58,035 INFO [main] regionserver.HRegion(2159): merge completed. New region is TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,035 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1246399316578', STARTKEY => 'row_0100', ENDKEY => 'row_0400', ENCODED => 887004818, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 22:01:58,036 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0500,1246399276162', STARTKEY => 'row_0500', ENDKEY => 'row_0600', ENCODED => 1519757544, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 22:01:58,037 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,row_0100,1246399317706', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2049149723, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:58,038 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246399317706: compactions & flushes disabled
[junit] 2009-06-30 22:01:58,038 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,038 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,039 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:58,039 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,039 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-06-30 22:01:58,040 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-06-30 22:01:58,040 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-06-30 22:01:58,040 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 22:01:58,041 INFO [main] regionserver.HRegion(485): Closed -ROOT-,,0
[junit] 2009-06-30 22:01:58,041 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-06-30 22:01:58,041 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-06-30 22:01:58,042 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region .META.,,1
[junit] 2009-06-30 22:01:58,042 DEBUG [main] regionserver.HRegion(889): Started memstore flush for region .META.,,1. Current region memstore size 1.2k
[junit] 2009-06-30 22:01:58,070 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:35148/user/hudson/.META./1028785192/historian/6581668136327600245, entries=2, sequenceid=27, memsize=364.0, filesize=521.0 to .META.,,1
[junit] 2009-06-30 22:01:58,100 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:35148/user/hudson/.META./1028785192/info/1981878739901526641, entries=3, sequenceid=27, memsize=844.0, filesize=874.0 to .META.,,1
[junit] 2009-06-30 22:01:58,101 DEBUG [main] regionserver.HRegion(966): Finished memstore flush of ~1.2k for region .META.,,1 in 59ms, sequence id=27, compaction requested=true
[junit] 2009-06-30 22:01:58,101 DEBUG [main] regionserver.Store(445): closed historian
[junit] 2009-06-30 22:01:58,102 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 22:01:58,102 INFO [main] regionserver.HRegion(485): Closed .META.,,1
[junit] 2009-06-30 22:01:58,126 INFO [main] regionserver.HLog(294): Roll /user/hudson/.logs_1246399317161/hlog.dat.1246399317177, entries=6, calcsize=1131, filesize=1151. New hlog /user/hudson/.logs_1246399317161/hlog.dat.1246399318115
[junit] 2009-06-30 22:01:58,126 DEBUG [main] regionserver.HLog(303): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-06-30 22:01:58,126 INFO [main] regionserver.HLog(428): removing old hlog file /user/hudson/.logs_1246399317161/hlog.dat.1246399317177 whose highest sequence/edit id is 26
[junit] 2009-06-30 22:01:58,135 DEBUG [main] regionserver.HLog(465): closing hlog writer in hdfs://localhost:35148/user/hudson/.logs_1246399317161
[junit] 2009-06-30 22:01:58,159 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246399317706', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2049149723, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:58,159 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246399317706, encoded=2049149723
[junit] 2009-06-30 22:01:58,166 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/2049149723/contents/4319690479893695652, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-06-30 22:01:58,169 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246399317706/2049149723 available; sequence id is 7
[junit] 2009-06-30 22:01:58,173 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246399317706: compactions & flushes disabled
[junit] 2009-06-30 22:01:58,173 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,173 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,174 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:58,174 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,174 INFO [main] util.TestMergeTool(187): Verified merging regions 0+1+2 and 3
[junit] 2009-06-30 22:01:58,175 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
[junit] 2009-06-30 22:01:58,175 INFO [main] util.Merge(82): Verifying that file system is available...
[junit] 2009-06-30 22:01:58,176 INFO [main] util.Merge(91): Verifying that HBase is not running...
[junit] 2009-06-30 22:01:58,299 INFO [main] util.Merge(197): Merging regions TestMergeTool,row_0100,1246399317706 and TestMergeTool,,1246399276162 in table TestMergeTool
[junit] 2009-06-30 22:01:58,310 INFO [main] regionserver.HLog(209): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
[junit] 2009-06-30 22:01:58,320 INFO [main] regionserver.HLog(294): New hlog /user/hudson/.logs_1246399318299/hlog.dat.1246399318310
[junit] 2009-06-30 22:01:58,321 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:58,321 DEBUG [main] regionserver.HRegion(264): Opening region -ROOT-,,0, encoded=70236052
[junit] 2009-06-30 22:01:58,328 DEBUG [main] regionserver.Store(380): loaded /user/hudson/-ROOT-/70236052/info/3921423027510213129, isReference=false, sequence id=2, length=840, majorCompaction=false
[junit] 2009-06-30 22:01:58,331 INFO [main] regionserver.HRegion(339): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2009-06-30 22:01:58,331 INFO [main] regionserver.HRegion(764): Starting compaction on region -ROOT-,,0
[junit] 2009-06-30 22:01:58,333 INFO [main] regionserver.HRegion(779): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2009-06-30 22:01:58,334 INFO [main] util.Merge(211): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2009-06-30 22:01:58,334 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:58,335 DEBUG [main] regionserver.HRegion(264): Opening region .META.,,1, encoded=1028785192
[junit] 2009-06-30 22:01:58,341 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/2242373471032321291, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2009-06-30 22:01:58,345 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/6581668136327600245, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2009-06-30 22:01:58,351 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/1580329960020786143, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2009-06-30 22:01:58,355 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/1981878739901526641, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2009-06-30 22:01:58,359 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/2822999092568944052, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2009-06-30 22:01:58,362 INFO [main] regionserver.HRegion(339): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2009-06-30 22:01:58,362 INFO [main] regionserver.HRegion(764): Starting compaction on region .META.,,1
[junit] 2009-06-30 22:01:58,374 DEBUG [main] regionserver.Store(710): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 22:01:58,386 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/1726907788894025963
[junit] 2009-06-30 22:01:58,434 DEBUG [main] regionserver.Store(734): Completed compaction of historian; store size is 781.0
[junit] 2009-06-30 22:01:58,436 DEBUG [main] regionserver.Store(710): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2009-06-30 22:01:58,448 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/5893305085728614183
[junit] 2009-06-30 22:01:58,495 DEBUG [main] regionserver.Store(734): Completed compaction of info; store size is 3.3k
[junit] 2009-06-30 22:01:58,505 INFO [main] regionserver.HRegion(779): compaction completed on region .META.,,1 in 0sec
[junit] 2009-06-30 22:01:58,507 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246399317706', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2049149723, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:58,508 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246399317706, encoded=2049149723
[junit] 2009-06-30 22:01:58,515 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/2049149723/contents/4319690479893695652, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-06-30 22:01:58,517 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246399317706/2049149723 available; sequence id is 7
[junit] 2009-06-30 22:01:58,518 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246399276162', STARTKEY => '', ENDKEY => '', ENCODED => 952427740, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:58,518 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246399276162, encoded=952427740
[junit] 2009-06-30 22:01:58,525 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/952427740/contents/180815927148714076, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2009-06-30 22:01:58,527 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246399276162/952427740 available; sequence id is 8
[junit] 2009-06-30 22:01:58,527 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,549 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/2049149723/7136072926277147871
[junit] 2009-06-30 22:01:58,713 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 791.0
[junit] 2009-06-30 22:01:58,723 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0100,1246399317706 in 0sec
[junit] 2009-06-30 22:01:58,723 DEBUG [main] regionserver.HRegion(2070): Files for region: TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,724 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/.regioninfo size=632
[junit] 2009-06-30 22:01:58,725 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/contents
[junit] 2009-06-30 22:01:58,726 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723/contents/5349636777064792090 size=791
[junit] 2009-06-30 22:01:58,726 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,,1246399276162
[junit] 2009-06-30 22:01:58,763 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/952427740/4359179143095145418
[junit] 2009-06-30 22:01:58,818 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 471.0
[junit] 2009-06-30 22:01:58,834 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246399276162 in 0sec
[junit] 2009-06-30 22:01:58,834 DEBUG [main] regionserver.HRegion(2075): Files for region: TestMergeTool,,1246399276162
[junit] 2009-06-30 22:01:58,836 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/952427740/.regioninfo size=583
[junit] 2009-06-30 22:01:58,836 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:35148/user/hudson/TestMergeTool/952427740/contents
[junit] 2009-06-30 22:01:58,837 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/952427740/contents/1918972045874906571 size=471
[junit] 2009-06-30 22:01:58,837 INFO [main] regionserver.HRegion(2104): Creating new region REGION => {NAME => 'TestMergeTool,,1246399318837', STARTKEY => '', ENDKEY => '', ENCODED => 1987548867, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:58,854 INFO [main] regionserver.HRegion(2113): starting merge of regions: TestMergeTool,row_0100,1246399317706 and TestMergeTool,,1246399276162 into new region REGION => {NAME => 'TestMergeTool,,1246399318837', STARTKEY => '', ENDKEY => '', ENCODED => 1987548867, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <> and end key <>
[junit] 2009-06-30 22:01:58,854 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246399317706: compactions & flushes disabled
[junit] 2009-06-30 22:01:58,855 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,855 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,855 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:58,856 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246399317706
[junit] 2009-06-30 22:01:58,856 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246399276162: compactions & flushes disabled
[junit] 2009-06-30 22:01:58,856 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246399276162
[junit] 2009-06-30 22:01:58,857 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246399276162
[junit] 2009-06-30 22:01:58,857 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:58,857 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246399276162
[junit] 2009-06-30 22:01:58,914 DEBUG [main] regionserver.HRegion(2146): Files for new region
[junit] 2009-06-30 22:01:58,915 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:35148/user/hudson/TestMergeTool/1987548867/contents
[junit] 2009-06-30 22:01:58,916 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/1987548867/contents/3863776980815499581 size=791
[junit] 2009-06-30 22:01:58,916 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/1987548867/contents/7867296772397052678 size=471
[junit] 2009-06-30 22:01:58,917 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246399318837, encoded=1987548867
[junit] 2009-06-30 22:01:58,959 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1987548867/contents/3863776980815499581, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2009-06-30 22:01:58,963 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1987548867/contents/7867296772397052678, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2009-06-30 22:01:58,965 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246399318837/1987548867 available; sequence id is 8
[junit] 2009-06-30 22:01:58,966 INFO [main] regionserver.HRegion(764): Starting compaction on region TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:58,978 DEBUG [main] regionserver.Store(710): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 22:01:59,001 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1987548867/4148275341560214936
[junit] 2009-06-30 22:01:59,077 DEBUG [main] regionserver.Store(734): Completed compaction of contents; store size is 929.0
[junit] 2009-06-30 22:01:59,093 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246399318837 in 0sec
[junit] 2009-06-30 22:01:59,095 DEBUG [main] regionserver.HRegion(2153): Files for new region
[junit] 2009-06-30 22:01:59,096 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/1987548867/.regioninfo size=584
[junit] 2009-06-30 22:01:59,097 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:35148/user/hudson/TestMergeTool/1987548867/contents
[junit] 2009-06-30 22:01:59,098 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:35148/user/hudson/TestMergeTool/1987548867/contents/5584223169378809009 size=929
[junit] 2009-06-30 22:01:59,098 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:35148/user/hudson/TestMergeTool/2049149723
[junit] 2009-06-30 22:01:59,113 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:35148/user/hudson/TestMergeTool/952427740
[junit] 2009-06-30 22:01:59,129 INFO [main] regionserver.HRegion(2159): merge completed. New region is TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:59,129 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1246399317706', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2049149723, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 22:01:59,130 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,,1246399276162', STARTKEY => '', ENDKEY => '', ENCODED => 952427740, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 22:01:59,131 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,,1246399318837', STARTKEY => '', ENDKEY => '', ENCODED => 1987548867, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:59,132 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246399318837: compactions & flushes disabled
[junit] 2009-06-30 22:01:59,132 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:59,132 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:59,133 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:59,133 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:59,133 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-06-30 22:01:59,134 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-06-30 22:01:59,134 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-06-30 22:01:59,134 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 22:01:59,135 INFO [main] regionserver.HRegion(485): Closed -ROOT-,,0
[junit] 2009-06-30 22:01:59,135 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-06-30 22:01:59,135 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-06-30 22:01:59,135 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region .META.,,1
[junit] 2009-06-30 22:01:59,136 DEBUG [main] regionserver.HRegion(889): Started memstore flush for region .META.,,1. Current region memstore size 1.1k
[junit] 2009-06-30 22:01:59,172 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:35148/user/hudson/.META./1028785192/historian/3484827956334796702, entries=2, sequenceid=34, memsize=356.0, filesize=505.0 to .META.,,1
[junit] 2009-06-30 22:01:59,215 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:35148/user/hudson/.META./1028785192/info/7970813303790703029, entries=3, sequenceid=34, memsize=804.0, filesize=826.0 to .META.,,1
[junit] 2009-06-30 22:01:59,216 DEBUG [main] regionserver.HRegion(966): Finished memstore flush of ~1.1k for region .META.,,1 in 80ms, sequence id=34, compaction requested=true
[junit] 2009-06-30 22:01:59,216 DEBUG [main] regionserver.Store(445): closed historian
[junit] 2009-06-30 22:01:59,216 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 22:01:59,217 INFO [main] regionserver.HRegion(485): Closed .META.,,1
[junit] 2009-06-30 22:01:59,253 INFO [main] regionserver.HLog(294): Roll /user/hudson/.logs_1246399318299/hlog.dat.1246399318310, entries=6, calcsize=1083, filesize=1103. New hlog /user/hudson/.logs_1246399318299/hlog.dat.1246399319236
[junit] 2009-06-30 22:01:59,253 DEBUG [main] regionserver.HLog(303): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-06-30 22:01:59,253 INFO [main] regionserver.HLog(428): removing old hlog file /user/hudson/.logs_1246399318299/hlog.dat.1246399318310 whose highest sequence/edit id is 33
[junit] 2009-06-30 22:01:59,268 DEBUG [main] regionserver.HLog(465): closing hlog writer in hdfs://localhost:35148/user/hudson/.logs_1246399318299
[junit] 2009-06-30 22:01:59,304 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246399318837', STARTKEY => '', ENDKEY => '', ENCODED => 1987548867, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 22:01:59,304 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246399318837, encoded=1987548867
[junit] 2009-06-30 22:01:59,311 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/1987548867/contents/5584223169378809009, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2009-06-30 22:01:59,313 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246399318837/1987548867 available; sequence id is 8
[junit] 2009-06-30 22:01:59,318 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246399318837: compactions & flushes disabled
[junit] 2009-06-30 22:01:59,318 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:59,318 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:59,319 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 22:01:59,319 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246399318837
[junit] 2009-06-30 22:01:59,319 INFO [main] util.TestMergeTool(187): Verified merging regions 0+1+2+3 and 4
[junit] 2009-06-30 22:01:59,319 DEBUG [main] regionserver.HLog(465): closing hlog writer in /tmp/.logs_1246399314615
[junit] 2009-06-30 22:01:59,484 INFO [main] hbase.HBaseTestCase(627): Shutting down FileSystem
[junit] 2009-06-30 22:01:59,484 INFO [main] hbase.HBaseTestCase(634): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-30 22:01:59,591 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1682a53] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:56334, storageID=DS-838021826-67.195.138.9-56334-1246399312478, infoPort=44915, ipcPort=37988):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2009-06-30 22:02:00,699 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@9fea8a] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:34658, storageID=DS-786939468-67.195.138.9-34658-1246399311698, infoPort=54820, ipcPort=55306):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-06-30 22:02:01,801 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@a9255c] namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 45.768 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-06-30 22:02:02,247 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.071 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :506: Tests failed!
Total time: 45 minutes 36 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...
Build failed in Hudson: HBase-Patch #687
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/687/changes
Changes:
[stack] HBASE-1385 Revamp TableInputFormat, needs updating to match hadoop 0.20.x AND remove bit where we can make < maps than regions
[stack] HBASE-1385 Revamp TableInputFormat, needs updating to match hadoop 0.20.x AND remove bit where we can make < maps than regions
[stack] HBASE-1385 Revamp TableInputFormat, needs updating to match hadoop 0.20.x AND remove bit where we can make < maps than regions
[stack] HBASE-1588 Rig our build to support our new contribs -- stargate and THBase/ITHBase
------------------------------------------
[...truncated 31519 lines...]
[junit] 2009-06-30 18:59:14,672 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 471.0
[junit] 2009-06-30 18:59:14,688 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0500,1246388346148 in 0sec
[junit] 2009-06-30 18:59:14,688 DEBUG [main] regionserver.HRegion(2075): Files for region: TestMergeTool,row_0500,1246388346148
[junit] 2009-06-30 18:59:14,689 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/2083029779/.regioninfo size=632
[junit] 2009-06-30 18:59:14,689 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:33120/user/hudson/TestMergeTool/2083029779/contents
[junit] 2009-06-30 18:59:14,690 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/2083029779/contents/1346399353014743363 size=471
[junit] 2009-06-30 18:59:14,690 INFO [main] regionserver.HRegion(2104): Creating new region REGION => {NAME => 'TestMergeTool,row_0100,1246388354690', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 248742143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:14,708 INFO [main] regionserver.HRegion(2113): starting merge of regions: TestMergeTool,row_0100,1246388353308 and TestMergeTool,row_0500,1246388346148 into new region REGION => {NAME => 'TestMergeTool,row_0100,1246388354690', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 248742143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <row_0100> and end key <row_0600>
[junit] 2009-06-30 18:59:14,708 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246388353308: compactions & flushes disabled
[junit] 2009-06-30 18:59:14,708 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246388353308
[junit] 2009-06-30 18:59:14,708 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246388353308
[junit] 2009-06-30 18:59:14,709 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:14,709 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246388353308
[junit] 2009-06-30 18:59:14,709 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0500,1246388346148: compactions & flushes disabled
[junit] 2009-06-30 18:59:14,709 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0500,1246388346148
[junit] 2009-06-30 18:59:14,709 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0500,1246388346148
[junit] 2009-06-30 18:59:14,710 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:14,710 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0500,1246388346148
[junit] 2009-06-30 18:59:14,767 DEBUG [main] regionserver.HRegion(2146): Files for new region
[junit] 2009-06-30 18:59:14,768 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/contents
[junit] 2009-06-30 18:59:14,769 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/contents/3717358522824381231 size=653
[junit] 2009-06-30 18:59:14,769 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/contents/7059079817567193345 size=471
[junit] 2009-06-30 18:59:14,770 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246388354690, encoded=248742143
[junit] 2009-06-30 18:59:14,812 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/248742143/contents/3717358522824381231, isReference=false, sequence id=5, length=653, majorCompaction=true
[junit] 2009-06-30 18:59:14,816 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/248742143/contents/7059079817567193345, isReference=false, sequence id=6, length=471, majorCompaction=true
[junit] 2009-06-30 18:59:14,818 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246388354690/248742143 available; sequence id is 7
[junit] 2009-06-30 18:59:14,818 INFO [main] regionserver.HRegion(764): Starting compaction on region TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:14,831 DEBUG [main] regionserver.Store(710): Compaction size of contents: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 18:59:14,851 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/248742143/5843766490062516459
[junit] 2009-06-30 18:59:15,066 DEBUG [main] regionserver.Store(734): Completed compaction of contents; store size is 791.0
[junit] 2009-06-30 18:59:15,082 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0100,1246388354690 in 0sec
[junit] 2009-06-30 18:59:15,083 DEBUG [main] regionserver.HRegion(2153): Files for new region
[junit] 2009-06-30 18:59:15,084 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/.regioninfo size=631
[junit] 2009-06-30 18:59:15,084 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/contents
[junit] 2009-06-30 18:59:15,085 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/contents/6396245586910674309 size=791
[junit] 2009-06-30 18:59:15,085 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:33120/user/hudson/TestMergeTool/1355550087
[junit] 2009-06-30 18:59:15,102 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:33120/user/hudson/TestMergeTool/2083029779
[junit] 2009-06-30 18:59:15,118 INFO [main] regionserver.HRegion(2159): merge completed. New region is TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,119 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1246388353308', STARTKEY => 'row_0100', ENDKEY => 'row_0400', ENCODED => 1355550087, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 18:59:15,119 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0500,1246388346148', STARTKEY => 'row_0500', ENDKEY => 'row_0600', ENCODED => 2083029779, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 18:59:15,121 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,row_0100,1246388354690', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 248742143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:15,121 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246388354690: compactions & flushes disabled
[junit] 2009-06-30 18:59:15,121 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,121 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,122 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:15,122 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,122 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-06-30 18:59:15,122 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-06-30 18:59:15,122 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-06-30 18:59:15,122 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 18:59:15,123 INFO [main] regionserver.HRegion(485): Closed -ROOT-,,0
[junit] 2009-06-30 18:59:15,123 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-06-30 18:59:15,123 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-06-30 18:59:15,123 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region .META.,,1
[junit] 2009-06-30 18:59:15,123 DEBUG [main] regionserver.HRegion(889): Started memstore flush for region .META.,,1. Current region memstore size 1.2k
[junit] 2009-06-30 18:59:15,161 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:33120/user/hudson/.META./1028785192/historian/3669682013110019675, entries=2, sequenceid=27, memsize=364.0, filesize=521.0 to .META.,,1
[junit] 2009-06-30 18:59:15,205 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:33120/user/hudson/.META./1028785192/info/2371490309826932383, entries=3, sequenceid=27, memsize=844.0, filesize=874.0 to .META.,,1
[junit] 2009-06-30 18:59:15,205 DEBUG [main] regionserver.HRegion(966): Finished memstore flush of ~1.2k for region .META.,,1 in 82ms, sequence id=27, compaction requested=true
[junit] 2009-06-30 18:59:15,206 DEBUG [main] regionserver.Store(445): closed historian
[junit] 2009-06-30 18:59:15,206 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 18:59:15,206 INFO [main] regionserver.HRegion(485): Closed .META.,,1
[junit] 2009-06-30 18:59:15,243 INFO [main] regionserver.HLog(294): Roll /user/hudson/.logs_1246388354023/hlog.dat.1246388354038, entries=6, calcsize=1131, filesize=1151. New hlog /user/hudson/.logs_1246388354023/hlog.dat.1246388355226
[junit] 2009-06-30 18:59:15,243 DEBUG [main] regionserver.HLog(303): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-06-30 18:59:15,243 INFO [main] regionserver.HLog(428): removing old hlog file /user/hudson/.logs_1246388354023/hlog.dat.1246388354038 whose highest sequence/edit id is 26
[junit] 2009-06-30 18:59:15,257 DEBUG [main] regionserver.HLog(465): closing hlog writer in hdfs://localhost:33120/user/hudson/.logs_1246388354023
[junit] 2009-06-30 18:59:15,293 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246388354690', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 248742143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:15,294 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246388354690, encoded=248742143
[junit] 2009-06-30 18:59:15,300 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/248742143/contents/6396245586910674309, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-06-30 18:59:15,302 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246388354690/248742143 available; sequence id is 7
[junit] 2009-06-30 18:59:15,306 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246388354690: compactions & flushes disabled
[junit] 2009-06-30 18:59:15,306 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,306 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,306 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:15,307 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,307 INFO [main] util.TestMergeTool(187): Verified merging regions 0+1+2 and 3
[junit] 2009-06-30 18:59:15,307 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
[junit] 2009-06-30 18:59:15,307 INFO [main] util.Merge(82): Verifying that file system is available...
[junit] 2009-06-30 18:59:15,308 INFO [main] util.Merge(91): Verifying that HBase is not running...
[junit] 2009-06-30 18:59:15,430 INFO [main] util.Merge(197): Merging regions TestMergeTool,row_0100,1246388354690 and TestMergeTool,,1246388346148 in table TestMergeTool
[junit] 2009-06-30 18:59:15,441 INFO [main] regionserver.HLog(209): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
[junit] 2009-06-30 18:59:15,458 INFO [main] regionserver.HLog(294): New hlog /user/hudson/.logs_1246388355431/hlog.dat.1246388355441
[junit] 2009-06-30 18:59:15,458 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:15,458 DEBUG [main] regionserver.HRegion(264): Opening region -ROOT-,,0, encoded=70236052
[junit] 2009-06-30 18:59:15,486 DEBUG [main] regionserver.Store(380): loaded /user/hudson/-ROOT-/70236052/info/2863495751369746187, isReference=false, sequence id=2, length=840, majorCompaction=false
[junit] 2009-06-30 18:59:15,488 INFO [main] regionserver.HRegion(339): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2009-06-30 18:59:15,488 INFO [main] regionserver.HRegion(764): Starting compaction on region -ROOT-,,0
[junit] 2009-06-30 18:59:15,490 INFO [main] regionserver.HRegion(779): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2009-06-30 18:59:15,490 INFO [main] util.Merge(211): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2009-06-30 18:59:15,491 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:15,491 DEBUG [main] regionserver.HRegion(264): Opening region .META.,,1, encoded=1028785192
[junit] 2009-06-30 18:59:15,498 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/3669682013110019675, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2009-06-30 18:59:15,525 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/historian/4984309125494904377, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2009-06-30 18:59:15,530 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/1941293685682310349, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2009-06-30 18:59:15,534 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/2371490309826932383, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2009-06-30 18:59:15,538 DEBUG [main] regionserver.Store(380): loaded /user/hudson/.META./1028785192/info/937624280542399351, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2009-06-30 18:59:15,540 INFO [main] regionserver.HRegion(339): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2009-06-30 18:59:15,540 INFO [main] regionserver.HRegion(764): Starting compaction on region .META.,,1
[junit] 2009-06-30 18:59:15,553 DEBUG [main] regionserver.Store(710): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 18:59:15,573 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/2227590692102291779
[junit] 2009-06-30 18:59:15,649 DEBUG [main] regionserver.Store(734): Completed compaction of historian; store size is 781.0
[junit] 2009-06-30 18:59:15,650 DEBUG [main] regionserver.Store(710): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2009-06-30 18:59:15,668 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192/559374912099876038
[junit] 2009-06-30 18:59:15,744 DEBUG [main] regionserver.Store(734): Completed compaction of info; store size is 3.3k
[junit] 2009-06-30 18:59:15,760 INFO [main] regionserver.HRegion(779): compaction completed on region .META.,,1 in 0sec
[junit] 2009-06-30 18:59:15,762 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246388354690', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 248742143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:15,763 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,row_0100,1246388354690, encoded=248742143
[junit] 2009-06-30 18:59:15,769 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/248742143/contents/6396245586910674309, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-06-30 18:59:15,771 INFO [main] regionserver.HRegion(339): region TestMergeTool,row_0100,1246388354690/248742143 available; sequence id is 7
[junit] 2009-06-30 18:59:15,771 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246388346148', STARTKEY => '', ENDKEY => '', ENCODED => 452419846, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:15,771 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246388346148, encoded=452419846
[junit] 2009-06-30 18:59:15,777 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/452419846/contents/6044425450785823641, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2009-06-30 18:59:15,779 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246388346148/452419846 available; sequence id is 8
[junit] 2009-06-30 18:59:15,780 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:15,812 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/248742143/2480896195186007800
[junit] 2009-06-30 18:59:15,999 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 791.0
[junit] 2009-06-30 18:59:16,007 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,row_0100,1246388354690 in 0sec
[junit] 2009-06-30 18:59:16,008 DEBUG [main] regionserver.HRegion(2070): Files for region: TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:16,009 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/.regioninfo size=631
[junit] 2009-06-30 18:59:16,009 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/contents
[junit] 2009-06-30 18:59:16,010 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/248742143/contents/3697437893606145588 size=791
[junit] 2009-06-30 18:59:16,010 INFO [main] regionserver.HRegion(764): Starting major compaction on region TestMergeTool,,1246388346148
[junit] 2009-06-30 18:59:16,032 DEBUG [main] regionserver.Store(719): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/452419846/7031438710742762964
[junit] 2009-06-30 18:59:16,071 DEBUG [main] regionserver.Store(734): Completed major compaction of contents; store size is 471.0
[junit] 2009-06-30 18:59:16,079 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246388346148 in 0sec
[junit] 2009-06-30 18:59:16,079 DEBUG [main] regionserver.HRegion(2075): Files for region: TestMergeTool,,1246388346148
[junit] 2009-06-30 18:59:16,080 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/452419846/.regioninfo size=583
[junit] 2009-06-30 18:59:16,081 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:33120/user/hudson/TestMergeTool/452419846/contents
[junit] 2009-06-30 18:59:16,089 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/452419846/contents/2535921224512560791 size=471
[junit] 2009-06-30 18:59:16,089 INFO [main] regionserver.HRegion(2104): Creating new region REGION => {NAME => 'TestMergeTool,,1246388356089', STARTKEY => '', ENDKEY => '', ENCODED => 235661358, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:16,099 INFO [main] regionserver.HRegion(2113): starting merge of regions: TestMergeTool,row_0100,1246388354690 and TestMergeTool,,1246388346148 into new region REGION => {NAME => 'TestMergeTool,,1246388356089', STARTKEY => '', ENDKEY => '', ENCODED => 235661358, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <> and end key <>
[junit] 2009-06-30 18:59:16,099 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,row_0100,1246388354690: compactions & flushes disabled
[junit] 2009-06-30 18:59:16,100 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:16,100 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:16,100 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:16,100 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,row_0100,1246388354690
[junit] 2009-06-30 18:59:16,100 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246388346148: compactions & flushes disabled
[junit] 2009-06-30 18:59:16,101 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246388346148
[junit] 2009-06-30 18:59:16,101 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246388346148
[junit] 2009-06-30 18:59:16,101 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:16,101 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246388346148
[junit] 2009-06-30 18:59:16,135 DEBUG [main] regionserver.HRegion(2146): Files for new region
[junit] 2009-06-30 18:59:16,136 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:33120/user/hudson/TestMergeTool/235661358/contents
[junit] 2009-06-30 18:59:16,137 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/235661358/contents/2163190563800610425 size=791
[junit] 2009-06-30 18:59:16,137 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/235661358/contents/663513066348273476 size=471
[junit] 2009-06-30 18:59:16,137 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246388356089, encoded=235661358
[junit] 2009-06-30 18:59:16,164 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/235661358/contents/2163190563800610425, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2009-06-30 18:59:16,173 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/235661358/contents/663513066348273476, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2009-06-30 18:59:16,175 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246388356089/235661358 available; sequence id is 8
[junit] 2009-06-30 18:59:16,175 INFO [main] regionserver.HRegion(764): Starting compaction on region TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,187 DEBUG [main] regionserver.Store(710): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2009-06-30 18:59:16,199 DEBUG [main] regionserver.Store(719): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/235661358/2039020980942039499
[junit] 2009-06-30 18:59:16,243 DEBUG [main] regionserver.Store(734): Completed compaction of contents; store size is 929.0
[junit] 2009-06-30 18:59:16,251 INFO [main] regionserver.HRegion(779): compaction completed on region TestMergeTool,,1246388356089 in 0sec
[junit] 2009-06-30 18:59:16,253 DEBUG [main] regionserver.HRegion(2153): Files for new region
[junit] 2009-06-30 18:59:16,253 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/235661358/.regioninfo size=583
[junit] 2009-06-30 18:59:16,254 DEBUG [main] regionserver.HRegion(2214): d hdfs://localhost:33120/user/hudson/TestMergeTool/235661358/contents
[junit] 2009-06-30 18:59:16,254 DEBUG [main] regionserver.HRegion(2217): f hdfs://localhost:33120/user/hudson/TestMergeTool/235661358/contents/3389587005571861327 size=929
[junit] 2009-06-30 18:59:16,255 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:33120/user/hudson/TestMergeTool/248742143
[junit] 2009-06-30 18:59:16,263 DEBUG [main] regionserver.HRegion(1949): DELETING region hdfs://localhost:33120/user/hudson/TestMergeTool/452419846
[junit] 2009-06-30 18:59:16,271 INFO [main] regionserver.HRegion(2159): merge completed. New region is TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,271 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1246388354690', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 248742143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 18:59:16,271 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,,1246388346148', STARTKEY => '', ENDKEY => '', ENCODED => 452419846, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-06-30 18:59:16,273 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,,1246388356089', STARTKEY => '', ENDKEY => '', ENCODED => 235661358, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:16,273 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246388356089: compactions & flushes disabled
[junit] 2009-06-30 18:59:16,273 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,273 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,274 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:16,274 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,274 DEBUG [main] regionserver.HRegion(436): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-06-30 18:59:16,274 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-06-30 18:59:16,274 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-06-30 18:59:16,275 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 18:59:16,275 INFO [main] regionserver.HRegion(485): Closed -ROOT-,,0
[junit] 2009-06-30 18:59:16,275 DEBUG [main] regionserver.HRegion(436): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-06-30 18:59:16,275 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-06-30 18:59:16,275 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region .META.,,1
[junit] 2009-06-30 18:59:16,275 DEBUG [main] regionserver.HRegion(889): Started memstore flush for region .META.,,1. Current region memstore size 1.1k
[junit] 2009-06-30 18:59:16,306 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:33120/user/hudson/.META./1028785192/historian/3728917613222791342, entries=2, sequenceid=34, memsize=356.0, filesize=505.0 to .META.,,1
[junit] 2009-06-30 18:59:16,333 DEBUG [main] regionserver.Store(527): Added hdfs://localhost:33120/user/hudson/.META./1028785192/info/818842578157304076, entries=3, sequenceid=34, memsize=804.0, filesize=826.0 to .META.,,1
[junit] 2009-06-30 18:59:16,334 DEBUG [main] regionserver.HRegion(966): Finished memstore flush of ~1.1k for region .META.,,1 in 59ms, sequence id=34, compaction requested=true
[junit] 2009-06-30 18:59:16,334 DEBUG [main] regionserver.Store(445): closed historian
[junit] 2009-06-30 18:59:16,334 DEBUG [main] regionserver.Store(445): closed info
[junit] 2009-06-30 18:59:16,334 INFO [main] regionserver.HRegion(485): Closed .META.,,1
[junit] 2009-06-30 18:59:16,355 INFO [main] regionserver.HLog(294): Roll /user/hudson/.logs_1246388355431/hlog.dat.1246388355441, entries=6, calcsize=1083, filesize=1103. New hlog /user/hudson/.logs_1246388355431/hlog.dat.1246388356346
[junit] 2009-06-30 18:59:16,355 DEBUG [main] regionserver.HLog(303): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-06-30 18:59:16,356 INFO [main] regionserver.HLog(428): removing old hlog file /user/hudson/.logs_1246388355431/hlog.dat.1246388355441 whose highest sequence/edit id is 33
[junit] 2009-06-30 18:59:16,366 DEBUG [main] regionserver.HLog(465): closing hlog writer in hdfs://localhost:33120/user/hudson/.logs_1246388355431
[junit] 2009-06-30 18:59:16,386 DEBUG [main] regionserver.HRegion(1831): Opening region: REGION => {NAME => 'TestMergeTool,,1246388356089', STARTKEY => '', ENDKEY => '', ENCODED => 235661358, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-06-30 18:59:16,387 DEBUG [main] regionserver.HRegion(264): Opening region TestMergeTool,,1246388356089, encoded=235661358
[junit] 2009-06-30 18:59:16,393 DEBUG [main] regionserver.Store(380): loaded /user/hudson/TestMergeTool/235661358/contents/3389587005571861327, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2009-06-30 18:59:16,395 INFO [main] regionserver.HRegion(339): region TestMergeTool,,1246388356089/235661358 available; sequence id is 8
[junit] 2009-06-30 18:59:16,400 DEBUG [main] regionserver.HRegion(436): Closing TestMergeTool,,1246388356089: compactions & flushes disabled
[junit] 2009-06-30 18:59:16,400 DEBUG [main] regionserver.HRegion(466): Updates disabled for region, no outstanding scanners on TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,400 DEBUG [main] regionserver.HRegion(473): No more row locks outstanding on region TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,400 DEBUG [main] regionserver.Store(445): closed contents
[junit] 2009-06-30 18:59:16,400 INFO [main] regionserver.HRegion(485): Closed TestMergeTool,,1246388356089
[junit] 2009-06-30 18:59:16,401 INFO [main] util.TestMergeTool(187): Verified merging regions 0+1+2+3 and 4
[junit] 2009-06-30 18:59:16,401 DEBUG [main] regionserver.HLog(465): closing hlog writer in /tmp/.logs_1246388351401
[junit] 2009-06-30 18:59:16,541 INFO [main] hbase.HBaseTestCase(627): Shutting down FileSystem
[junit] 2009-06-30 18:59:16,541 INFO [main] hbase.HBaseTestCase(634): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-06-30 18:59:16,574 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1a5ec6c] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:39477, storageID=DS-1348486730-67.195.138.9-39477-1246388349377, infoPort=32784, ipcPort=33511):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2009-06-30 18:59:17,709 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@11daa0e] datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:48558, storageID=DS-925359981-67.195.138.9-48558-1246388348666, infoPort=55181, ipcPort=47139):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-06-30 18:59:17,812 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@2f0d54] namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 11.798 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-06-30 18:59:18,257 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.071 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :506: Tests failed!
Total time: 42 minutes 55 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...
Build failed in Hudson: HBase-Patch #686
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/686/changes
Changes:
[stack] HBASE-1588 Rig our build to support our new contribs -- stargate and THBase/ITHBase; Try and fix the broke clover build issue up on hudson
[stack] HBASE-1582 Translate ColumnValueFilter and RowFilterSet to the new Filter interface
------------------------------------------
[...truncated 36578 lines...]
[junit] 09/06/30 06:03:10 INFO master.ServerManager: 2 region servers, 0 dead, average load 1.5
[junit] 09/06/30 06:03:10 INFO transactional.CleanOldTransactionsChore: RegionServer:0.oldTransactionCleaner exiting
[junit] 09/06/30 06:03:10 INFO transactional.CleanOldTransactionsChore: RegionServer:1.oldTransactionCleaner exiting
[junit] 09/06/30 06:03:10 INFO master.BaseScanner: RegionManager.rootScanner scanning meta region {server: 67.195.138.9:42410, regionname: -ROOT-,,0, startKey: <>}
[junit] 09/06/30 06:03:10 INFO master.BaseScanner: RegionManager.metaScanner scanning meta region {server: 67.195.138.9:54289, regionname: .META.,,1, startKey: <>}
[junit] 09/06/30 06:03:10 INFO master.BaseScanner: RegionManager.rootScanner scan of 1 row(s) of meta region {server: 67.195.138.9:42410, regionname: -ROOT-,,0, startKey: <>} complete
[junit] 09/06/30 06:03:10 INFO master.BaseScanner: RegionManager.metaScanner scan of 1 row(s) of meta region {server: 67.195.138.9:54289, regionname: .META.,,1, startKey: <>} complete
[junit] 09/06/30 06:03:10 INFO master.BaseScanner: All 1 .META. region(s) scanned
[junit] 09/06/30 06:03:11 INFO zookeeper.ZooKeeper: Initiating client connection, host=localhost:21810 sessionTimeout=30000 watcher=org.apache.hadoop.hbase.zookeeper.WatcherWrapper@a0e990
[junit] 09/06/30 06:03:11 INFO zookeeper.ClientCnxn: Attempting connection to server localhost/127.0.0.1:21810
[junit] 09/06/30 06:03:11 INFO zookeeper.ClientCnxn: Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:36848 remote=localhost/127.0.0.1:21810]
[junit] 09/06/30 06:03:11 INFO zookeeper.ClientCnxn: Server connection successful
[junit] 09/06/30 06:03:11 INFO server.NIOServerCnxn: Connected to /127.0.0.1:36848 lastZxid 0
[junit] 09/06/30 06:03:11 INFO server.NIOServerCnxn: Creating new session 0x1222fc22d620005
[junit] 09/06/30 06:03:11 INFO server.NIOServerCnxn: Finished init of 0x1222fc22d620005 valid:true
[junit] 09/06/30 06:03:11 INFO regionserver.HLog: HLog configuration: blocksize=33554432, rollsize=31876710, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
[junit] 09/06/30 06:03:11 INFO regionserver.HLog: New hlog /tmp/hbase-hudson/hbase/__GLOBAL_TRX_LOG__/1184347806/.logs/hlog.dat.1246341791145
[junit] 09/06/30 06:03:11 INFO regionserver.HRegion: region __GLOBAL_TRX_LOG__,,1246341791131/1184347806 available; sequence id is 0
[junit] 09/06/30 06:03:11 INFO regionserver.HRegion: Closed __GLOBAL_TRX_LOG__,,1246341791131
[junit] 09/06/30 06:03:11 INFO master.HMaster: created table __GLOBAL_TRX_LOG__
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: Starting compaction on region -ROOT-,,0
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: compaction completed on region -ROOT-,,0 in 0sec
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: Starting compaction on region table1,,1246341781017
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: compaction completed on region table1,,1246341781017 in 0sec
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: Starting compaction on region .META.,,1
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: compaction completed on region .META.,,1 in 0sec
[junit] 09/06/30 06:03:12 INFO master.RegionManager: Assigning region __GLOBAL_TRX_LOG__,,1246341791131 to vesta.apache.org,54289,1246341771998
[junit] 09/06/30 06:03:12 INFO regionserver.HRegionServer: MSG_REGION_OPEN: __GLOBAL_TRX_LOG__,,1246341791131
[junit] 09/06/30 06:03:12 INFO regionserver.HRegionServer: Worker: MSG_REGION_OPEN: __GLOBAL_TRX_LOG__,,1246341791131
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: region __GLOBAL_TRX_LOG__,,1246341791131/1184347806 available; sequence id is 0
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: Starting compaction on region __GLOBAL_TRX_LOG__,,1246341791131
[junit] 09/06/30 06:03:12 INFO regionserver.HRegion: compaction completed on region __GLOBAL_TRX_LOG__,,1246341791131 in 0sec
[junit] 09/06/30 06:03:13 INFO master.ServerManager: Received MSG_REPORT_PROCESS_OPEN: __GLOBAL_TRX_LOG__,,1246341791131 from vesta.apache.org,54289,1246341771998; 1 of 2
[junit] 09/06/30 06:03:13 INFO master.ServerManager: Received MSG_REPORT_OPEN: __GLOBAL_TRX_LOG__,,1246341791131 from vesta.apache.org,54289,1246341771998; 2 of 2
[junit] 09/06/30 06:03:13 INFO master.RegionServerOperation: __GLOBAL_TRX_LOG__,,1246341791131 open on 67.195.138.9:54289
[junit] 09/06/30 06:03:13 INFO master.RegionServerOperation: updating row __GLOBAL_TRX_LOG__,,1246341791131 in region .META.,,1 with startcode 1246341771998 and server 67.195.138.9:54289
[junit] 09/06/30 06:03:16 INFO zookeeper.ZooKeeper: Initiating client connection, host=localhost:21810 sessionTimeout=30000 watcher=org.apache.hadoop.hbase.zookeeper.WatcherWrapper@76f2e8
[junit] 09/06/30 06:03:16 INFO zookeeper.ClientCnxn: Attempting connection to server localhost/127.0.0.1:21810
[junit] 09/06/30 06:03:16 INFO zookeeper.ClientCnxn: Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:36849 remote=localhost/127.0.0.1:21810]
[junit] 09/06/30 06:03:16 INFO zookeeper.ClientCnxn: Server connection successful
[junit] 09/06/30 06:03:16 INFO server.NIOServerCnxn: Connected to /127.0.0.1:36849 lastZxid 0
[junit] 09/06/30 06:03:16 INFO server.NIOServerCnxn: Creating new session 0x1222fc22d620006
[junit] 09/06/30 06:03:16 INFO server.NIOServerCnxn: Finished init of 0x1222fc22d620006 valid:true
[junit] 09/06/30 06:03:16 INFO zookeeper.ZooKeeper: Initiating client connection, host=localhost:21810 sessionTimeout=30000 watcher=org.apache.hadoop.hbase.zookeeper.WatcherWrapper@1588325
[junit] 09/06/30 06:03:16 INFO zookeeper.ClientCnxn: Attempting connection to server localhost/127.0.0.1:21810
[junit] 09/06/30 06:03:16 INFO zookeeper.ClientCnxn: Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:36850 remote=localhost/127.0.0.1:21810]
[junit] 09/06/30 06:03:16 INFO zookeeper.ClientCnxn: Server connection successful
[junit] 09/06/30 06:03:16 INFO server.NIOServerCnxn: Connected to /127.0.0.1:36850 lastZxid 0
[junit] 09/06/30 06:03:16 INFO server.NIOServerCnxn: Creating new session 0x1222fc22d620007
[junit] 09/06/30 06:03:16 INFO server.NIOServerCnxn: Finished init of 0x1222fc22d620007 valid:true
[junit] 09/06/30 06:03:16 ERROR ipc.HbaseRPC: Unsupported type beginTransaction
[junit] 09/06/30 06:03:20 INFO master.ServerManager: 2 region servers, 0 dead, average load 2.0
[junit] 09/06/30 06:03:20 INFO master.BaseScanner: RegionManager.metaScanner scanning meta region {server: 67.195.138.9:54289, regionname: .META.,,1, startKey: <>}
[junit] 09/06/30 06:03:20 INFO master.BaseScanner: RegionManager.rootScanner scanning meta region {server: 67.195.138.9:42410, regionname: -ROOT-,,0, startKey: <>}
[junit] 09/06/30 06:03:20 INFO master.BaseScanner: RegionManager.rootScanner scan of 1 row(s) of meta region {server: 67.195.138.9:42410, regionname: -ROOT-,,0, startKey: <>} complete
[junit] 09/06/30 06:03:20 INFO master.BaseScanner: RegionManager.metaScanner scan of 2 row(s) of meta region {server: 67.195.138.9:54289, regionname: .META.,,1, startKey: <>} complete
[junit] 09/06/30 06:03:20 INFO master.BaseScanner: All 1 .META. region(s) scanned
[junit] 09/06/30 06:03:26 INFO ipc.HBaseServer: IPC Server handler 2 on 42410, call get(-5929525583699879111, [B@15af049, row=row1, maxVersions=1, timeRange=[0,9223372036854775807), families={(family=family, columns={a}}) from 67.195.138.9:51247: error: org.apache.hadoop.hbase.client.transactional.UnknownTransactionException: transaction: [-5929525583699879111], region: [table1,,1246341781017]
[junit] org.apache.hadoop.hbase.client.transactional.UnknownTransactionException: transaction: [-5929525583699879111], region: [table1,,1246341781017]
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegion.getTransactionState(TransactionalRegion.java:554)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegion.get(TransactionalRegion.java:237)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.get(TransactionalRegionServer.java:230)
[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 org.apache.hadoop.hbase.ipc.HBaseRPC$Server.call(HBaseRPC.java:643)
[junit] at org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:913)
[junit] 09/06/30 06:03:26 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620004
[junit] 09/06/30 06:03:26 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620004
[junit] 09/06/30 06:03:26 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620004
[junit] 09/06/30 06:03:26 INFO server.NIOServerCnxn: closing session:0x1222fc22d620004 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36846]
[junit] 09/06/30 06:03:26 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620004 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:26 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620004
[junit] 09/06/30 06:03:26 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620004 closed
[junit] 09/06/30 06:03:26 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:26 INFO master.HMaster: Cluster shutdown requested. Starting to quiesce servers
[junit] 09/06/30 06:03:27 INFO master.RootScanner: RegionManager.rootScanner exiting
[junit] 09/06/30 06:03:27 INFO master.MetaScanner: RegionManager.metaScanner exiting
[junit] 09/06/30 06:03:27 INFO regionserver.HRegionServer: MSG_REGIONSERVER_QUIESCE
[junit] 09/06/30 06:03:27 INFO regionserver.HRegionServer: Worker: MSG_REGIONSERVER_QUIESCE
[junit] 09/06/30 06:03:27 INFO transactional.TransactionalRegion: Preparing to close region __GLOBAL_TRX_LOG__,,1246341791131
[junit] 09/06/30 06:03:27 INFO regionserver.HRegionServer: MSG_REGIONSERVER_QUIESCE
[junit] 09/06/30 06:03:27 INFO regionserver.HRegionServer: Worker: MSG_REGIONSERVER_QUIESCE
[junit] 09/06/30 06:03:27 INFO transactional.TransactionalRegion: Preparing to close region table1,,1246341781017
[junit] 09/06/30 06:03:27 INFO regionserver.HRegion: Closed __GLOBAL_TRX_LOG__,,1246341791131
[junit] 09/06/30 06:03:27 INFO regionserver.HRegion: Closed table1,,1246341781017
[junit] 09/06/30 06:03:28 INFO master.ServerManager: Region server vesta.apache.org,54289,1246341771998 quiesced
[junit] 09/06/30 06:03:28 INFO master.ServerManager: Region server vesta.apache.org,42410,1246341771967 quiesced
[junit] 09/06/30 06:03:28 INFO master.ServerManager: All user tables quiesced. Proceeding with shutdown
[junit] 09/06/30 06:03:28 INFO regionserver.HRegionServer: MSG_REGIONSERVER_STOP
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: Stopping server on 42410
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: IPC Server handler 0 on 42410: exiting
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: IPC Server handler 3 on 42410: exiting
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: Stopping IPC Server Responder
[junit] 09/06/30 06:03:28 INFO regionserver.CompactSplitThread: RegionServer:1.compactor exiting
[junit] 09/06/30 06:03:28 INFO regionserver.LogFlusher: RegionServer:1.logFlusher exiting
[junit] 09/06/30 06:03:28 INFO regionserver.HRegionServer$MajorCompactionChecker: RegionServer:1.majorCompactionChecker exiting
[junit] 09/06/30 06:03:28 INFO transactional.TransactionalRegion: Preparing to close region -ROOT-,,0
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: IPC Server handler 4 on 42410: exiting
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: IPC Server handler 2 on 42410: exiting
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: Stopping IPC Server listener on 42410
[junit] 09/06/30 06:03:28 INFO ipc.HBaseServer: IPC Server handler 1 on 42410: exiting
[junit] 09/06/30 06:03:28 INFO regionserver.MemStoreFlusher: RegionServer:1.cacheFlusher exiting
[junit] 09/06/30 06:03:28 INFO regionserver.LogRoller: LogRoller exiting.
[junit] 09/06/30 06:03:28 INFO regionserver.HRegion: Closed -ROOT-,,0
[junit] 09/06/30 06:03:28 INFO regionserver.HRegionServer: telling master that region server is shutting down at: 67.195.138.9:42410
[junit] 09/06/30 06:03:28 INFO master.ServerManager: Removing server's info vesta.apache.org,42410,1246341771967
[junit] 09/06/30 06:03:28 INFO master.ServerManager: Region server vesta.apache.org,42410,1246341771967: MSG_REPORT_EXITING
[junit] 09/06/30 06:03:28 INFO regionserver.HRegionServer: stopping server at: 67.195.138.9:42410
[junit] 09/06/30 06:03:28 INFO regionserver.HRegionServer: worker thread exiting
[junit] 09/06/30 06:03:28 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620002
[junit] 09/06/30 06:03:28 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620002
[junit] 09/06/30 06:03:28 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620002
[junit] 09/06/30 06:03:28 INFO master.ServerManager: vesta.apache.org,42410,1246341771967 znode expired
[junit] 09/06/30 06:03:28 INFO server.NIOServerCnxn: closing session:0x1222fc22d620002 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36842]
[junit] 09/06/30 06:03:28 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620002 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:28 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620002
[junit] 09/06/30 06:03:28 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620002 closed
[junit] 09/06/30 06:03:28 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:28 INFO regionserver.HRegionServer: RegionServer:1 exiting
[junit] 09/06/30 06:03:29 INFO hbase.Leases: RegionServer:1.leaseChecker closing leases
[junit] 09/06/30 06:03:29 INFO hbase.Leases: RegionServer:1.leaseChecker closed leases
[junit] 09/06/30 06:03:29 INFO master.ServerManager: Waiting on following regionserver(s) to go down [address: 67.195.138.9:54289, startcode: 1246341771998, load: (requests=0, regions=2, usedHeap=97, maxHeap=246)]
[junit] 09/06/30 06:03:29 INFO master.ServerManager: All user tables quiesced. Proceeding with shutdown
[junit] 09/06/30 06:03:29 INFO master.ServerManager: Waiting on following regionserver(s) to go down [address: 67.195.138.9:54289, startcode: 1246341771998, load: (requests=0, regions=2, usedHeap=97, maxHeap=246)]
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: MSG_REGIONSERVER_STOP
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: Stopping server on 54289
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 0 on 54289: exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: Stopping IPC Server listener on 54289
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 2 on 54289: exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 3 on 54289: exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 1 on 54289: exiting
[junit] 09/06/30 06:03:29 INFO transactional.TransactionalRegion: Preparing to close region .META.,,1
[junit] 09/06/30 06:03:29 INFO regionserver.LogRoller: LogRoller exiting.
[junit] 09/06/30 06:03:29 INFO regionserver.CompactSplitThread: RegionServer:0.compactor exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 4 on 54289: exiting
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer$MajorCompactionChecker: RegionServer:0.majorCompactionChecker exiting
[junit] 09/06/30 06:03:29 INFO regionserver.MemStoreFlusher: RegionServer:0.cacheFlusher exiting
[junit] 09/06/30 06:03:29 INFO regionserver.LogFlusher: RegionServer:0.logFlusher exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: Stopping IPC Server Responder
[junit] 09/06/30 06:03:29 INFO regionserver.HRegion: Closed .META.,,1
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: telling master that region server is shutting down at: 67.195.138.9:54289
[junit] 09/06/30 06:03:29 INFO master.ServerManager: Removing server's info vesta.apache.org,54289,1246341771998
[junit] 09/06/30 06:03:29 INFO master.RegionManager: Offlining META region: {server: 67.195.138.9:54289, regionname: .META.,,1, startKey: <>}
[junit] 09/06/30 06:03:29 INFO master.RegionManager: META region removed from onlineMetaRegions
[junit] 09/06/30 06:03:29 INFO master.ServerManager: Region server vesta.apache.org,54289,1246341771998: MSG_REPORT_EXITING
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: Stopping server on 60000
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 0 on 60000: exiting
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: stopping server at: 67.195.138.9:54289
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 1 on 60000: exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 2 on 60000: exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 3 on 60000: exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: Stopping IPC Server listener on 60000
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: IPC Server handler 4 on 60000: exiting
[junit] 09/06/30 06:03:29 INFO ipc.HBaseServer: Stopping IPC Server Responder
[junit] 09/06/30 06:03:29 INFO master.ServerManager: vesta.apache.org,54289,1246341771998 znode expired
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620000
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620000
[junit] 09/06/30 06:03:29 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620000
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Got ZooKeeper event, state: SyncConnected, type: NodeDeleted, path: /hbase/master
[junit] 09/06/30 06:03:29 INFO server.NIOServerCnxn: closing session:0x1222fc22d620000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36840]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: worker thread exiting
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620001
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620001
[junit] 09/06/30 06:03:29 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620001
[junit] 09/06/30 06:03:29 INFO server.NIOServerCnxn: closing session:0x1222fc22d620001 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36841]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620001 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620000
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620000 closed
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620000
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620000
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620000
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620000 closed
[junit] 09/06/30 06:03:29 INFO master.HMaster: HMaster main thread exiting
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620001
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620001 closed
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: RegionServer:0 exiting
[junit] 09/06/30 06:03:29 INFO hbase.LocalHBaseCluster: Shutdown HMaster 2 region server(s)
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620007
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620007
[junit] 09/06/30 06:03:29 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620007
[junit] 09/06/30 06:03:29 INFO server.NIOServerCnxn: closing session:0x1222fc22d620007 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36850]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620007 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620007
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620007 closed
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620006
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620006
[junit] 09/06/30 06:03:29 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620006
[junit] 09/06/30 06:03:29 INFO server.NIOServerCnxn: closing session:0x1222fc22d620006 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36849]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620006 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620006
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620006 closed
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620005
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620005
[junit] 09/06/30 06:03:29 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620005
[junit] 09/06/30 06:03:29 INFO server.NIOServerCnxn: closing session:0x1222fc22d620005 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36848]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620005 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620005
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620005 closed
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Closing session: 0x1222fc22d620003
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1222fc22d620003
[junit] 09/06/30 06:03:29 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1222fc22d620003
[junit] 09/06/30 06:03:29 INFO server.NIOServerCnxn: closing session:0x1222fc22d620003 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36843]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1222fc22d620003 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1222fc22d620003
[junit] 09/06/30 06:03:29 INFO zookeeper.ZooKeeper: Session: 0x1222fc22d620003 closed
[junit] 09/06/30 06:03:29 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/06/30 06:03:29 INFO server.NIOServerCnxn: NIOServerCnxn factory exited run method
[junit] 09/06/30 06:03:29 INFO server.FinalRequestProcessor: shutdown of request processor complete
[junit] 09/06/30 06:03:29 INFO server.SyncRequestProcessor: SyncRequestProcessor exited!
[junit] 09/06/30 06:03:29 INFO server.PrepRequestProcessor: PrepRequestProcessor exited loop!
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegion.getTransactionState(TransactionalRegion.java:554)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegion.get(TransactionalRegion.java:237)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.get(TransactionalRegionServer.java:230)
[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 org.apache.hadoop.hbase.ipc.HBaseRPC$Server.call(HBaseRPC.java:643)
[junit] at org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:913)
[junit] )
[junit] Tests run: 2, Failures: 0, Errors: 2, Time elapsed: 80.031 sec
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Starting shutdown thread.
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Starting shutdown thread.
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Starting shutdown thread.
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Starting shutdown thread.
[junit] 09/06/30 06:03:29 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] Test org.apache.hadoop.hbase.regionserver.transactional.TestTHLogRecovery FAILED
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :511: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build.xml :54: The following error occurred while executing this line:
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build-contrib.xml :283: Tests failed!
Total time: 47 minutes 8 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...
Re: Build failed in Hudson: HBase-Patch #685
Posted by Andrew Purtell <ap...@apache.org>.
Can we disable the automatic Stargate build for now? I need to dive in
and wrap up Stargate with Javadoc, package doc, and build cleanups. Hope
to get this done tomorrow through the rest of the week. Been very busy
since the weekend.
- Andy
________________________________
From: stack <st...@duboce.net>
To: hbase-dev@hadoop.apache.org
Sent: Monday, June 29, 2009 10:02:05 PM
Subject: Re: Build failed in Hudson: HBase-Patch #685
All core hbase tests are passing. The src/contrib ones are failing because
of clover. Will try an fix as part of hbase-1588.
St.Ack
On Mon, Jun 29, 2009 at 8:57 PM, Apache Hudson Server <
hudson@hudson.zones.apache.org> wrote:
> See http://hudson.zones.apache.org/hudson/job/HBase-Patch/685/changes
>
> Changes:
>
> [stack] HBASE-1591 HBASE-1554 broke
> org.apache.hadoop.hbase.io.hfile.TestLruBlockCache.testResizeBlockCache
>
> ------------------------------------------
> [...truncated 27874 lines...]
> [junit] 2009-06-30 03:57:16,316 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY =>
> '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true',
> IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME =>
> 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647',
> BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,317 DEBUG [main] regionserver.HRegion(264):
> Opening region -ROOT-,,0, encoded=70236052
> [junit] 2009-06-30 03:57:16,323 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/-ROOT-/70236052/info/4339548386246392681,
> isReference=false, sequence id=2, length=840, majorCompaction=false
> [junit] 2009-06-30 03:57:16,325 INFO [main] regionserver.HRegion(339):
> region -ROOT-,,0/70236052 available; sequence id is 3
> [junit] 2009-06-30 03:57:16,325 INFO [main] regionserver.HRegion(764):
> Starting compaction on region -ROOT-,,0
> [junit] 2009-06-30 03:57:16,327 INFO [main] regionserver.HRegion(779):
> compaction completed on region -ROOT-,,0 in 0sec
> [junit] 2009-06-30 03:57:16,328 INFO [main] util.Merge(211): Found meta
> for region1 .META.,,1, meta for region2 .META.,,1
> [junit] 2009-06-30 03:57:16,328 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY =>
> '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true',
> MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS
> => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info',
> VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,329 DEBUG [main] regionserver.HRegion(264):
> Opening region .META.,,1, encoded=1028785192
> [junit] 2009-06-30 03:57:16,336 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/historian/5069761041856656638,
> isReference=false, sequence id=27, length=521, majorCompaction=false
> [junit] 2009-06-30 03:57:16,340 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/historian/6185116046084952634,
> isReference=false, sequence id=20, length=651, majorCompaction=false
> [junit] 2009-06-30 03:57:16,345 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/info/3580226687465594789,
> isReference=false, sequence id=27, length=874, majorCompaction=false
> [junit] 2009-06-30 03:57:16,349 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/info/8246226911915738154,
> isReference=false, sequence id=6, length=2226, majorCompaction=false
> [junit] 2009-06-30 03:57:16,353 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/info/8694815133596552284,
> isReference=false, sequence id=20, length=994, majorCompaction=false
> [junit] 2009-06-30 03:57:16,355 INFO [main] regionserver.HRegion(339):
> region .META.,,1/1028785192 available; sequence id is 28
> [junit] 2009-06-30 03:57:16,356 INFO [main] regionserver.HRegion(764):
> Starting compaction on region .META.,,1
> [junit] 2009-06-30 03:57:16,368 DEBUG [main] regionserver.Store(710):
> Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
> [junit] 2009-06-30 03:57:16,388 DEBUG [main] regionserver.Store(719):
> Started compaction of 2 file(s) into
> /user/hudson/.META./compaction.dir/1028785192/4345181293809946232
> [junit] 2009-06-30 03:57:16,463 DEBUG [main] regionserver.Store(734):
> Completed compaction of historian; store size is 781.0
> [junit] 2009-06-30 03:57:16,465 DEBUG [main] regionserver.Store(710):
> Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
> [junit] 2009-06-30 03:57:16,483 DEBUG [main] regionserver.Store(719):
> Started compaction of 2 file(s) into
> /user/hudson/.META./compaction.dir/1028785192/4756716123822289492
> [junit] 2009-06-30 03:57:16,559 DEBUG [main] regionserver.Store(734):
> Completed compaction of info; store size is 3.3k
> [junit] 2009-06-30 03:57:16,575 INFO [main] regionserver.HRegion(779):
> compaction completed on region .META.,,1 in 0sec
> [junit] 2009-06-30 03:57:16,577 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246334235613',
> STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 296810668, TABLE =>
> {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION =>
> 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536',
> IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,577 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,row_0100,1246334235613, encoded=296810668
> [junit] 2009-06-30 03:57:16,583 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/296810668/contents/88071840529929138,
> isReference=false, sequence id=6, length=791, majorCompaction=false
> [junit] 2009-06-30 03:57:16,586 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,row_0100,1246334235613/296810668 available; sequence id
> is 7
> [junit] 2009-06-30 03:57:16,586 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => 'TestMergeTool,,1246334227227', STARTKEY
> => '', ENDKEY => '', ENCODED => 556286697, TABLE => {{NAME =>
> 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3',
> COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY
> => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,586 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,,1246334227227, encoded=556286697
> [junit] 2009-06-30 03:57:16,594 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/556286697/contents/1084511642783190185,
> isReference=false, sequence id=7, length=471, majorCompaction=false
> [junit] 2009-06-30 03:57:16,596 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,,1246334227227/556286697 available; sequence id is 8
> [junit] 2009-06-30 03:57:16,596 INFO [main] regionserver.HRegion(764):
> Starting major compaction on region TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,627 DEBUG [main] regionserver.Store(719):
> Started compaction of 1 file(s) into
> /user/hudson/TestMergeTool/compaction.dir/296810668/3365216675693282626
> [junit] 2009-06-30 03:57:16,682 DEBUG [main] regionserver.Store(734):
> Completed major compaction of contents; store size is 791.0
> [junit] 2009-06-30 03:57:16,853 INFO [main] regionserver.HRegion(779):
> compaction completed on region TestMergeTool,row_0100,1246334235613 in 0sec
> [junit] 2009-06-30 03:57:16,853 DEBUG [main] regionserver.HRegion(2070):
> Files for region: TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,854 DEBUG [main] regionserver.HRegion(2217):
> f hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/.regioninfo
> size=631
> [junit] 2009-06-30 03:57:16,854 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/contents
> [junit] 2009-06-30 03:57:16,855 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/contents/8452771743625025355
> size=791
> [junit] 2009-06-30 03:57:16,855 INFO [main] regionserver.HRegion(764):
> Starting major compaction on region TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,885 DEBUG [main] regionserver.Store(719):
> Started compaction of 1 file(s) into
> /user/hudson/TestMergeTool/compaction.dir/556286697/6880362628274821301
> [junit] 2009-06-30 03:57:16,956 DEBUG [main] regionserver.Store(734):
> Completed major compaction of contents; store size is 471.0
> [junit] 2009-06-30 03:57:16,972 INFO [main] regionserver.HRegion(779):
> compaction completed on region TestMergeTool,,1246334227227 in 0sec
> [junit] 2009-06-30 03:57:16,972 DEBUG [main] regionserver.HRegion(2075):
> Files for region: TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,973 DEBUG [main] regionserver.HRegion(2217):
> f hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/.regioninfo
> size=583
> [junit] 2009-06-30 03:57:16,973 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/contents
> [junit] 2009-06-30 03:57:16,974 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/contents/4035034942458743945
> size=471
> [junit] 2009-06-30 03:57:16,975 INFO [main] regionserver.HRegion(2104):
> Creating new region REGION => {NAME => 'TestMergeTool,,1246334236974',
> STARTKEY => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME =>
> 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE',
> VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,988 INFO [main] regionserver.HRegion(2113):
> starting merge of regions: TestMergeTool,row_0100,1246334235613 and
> TestMergeTool,,1246334227227 into new region REGION => {NAME =>
> 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY => '', ENCODED =>
> 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME =>
> 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647',
> BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with
> start key <> and end key <>
> [junit] 2009-06-30 03:57:16,988 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,row_0100,1246334235613: compactions & flushes disabled
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:16,989 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,,1246334227227: compactions & flushes disabled
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,990 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,990 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:16,990 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:17,036 DEBUG [main] regionserver.HRegion(2146):
> Files for new region
> [junit] 2009-06-30 03:57:17,037 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents
> [junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/4373146720411115184
> size=791
> [junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/4708547828713801463
> size=471
> [junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,,1246334236974, encoded=1737903548
> [junit] 2009-06-30 03:57:17,080 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/1737903548/contents/4373146720411115184,
> isReference=false, sequence id=6, length=791, majorCompaction=true
> [junit] 2009-06-30 03:57:17,084 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/1737903548/contents/4708547828713801463,
> isReference=false, sequence id=7, length=471, majorCompaction=true
> [junit] 2009-06-30 03:57:17,087 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,,1246334236974/1737903548 available; sequence id is 8
> [junit] 2009-06-30 03:57:17,087 INFO [main] regionserver.HRegion(764):
> Starting compaction on region TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,096 DEBUG [main] regionserver.Store(710):
> Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
> [junit] 2009-06-30 03:57:17,111 DEBUG [main] regionserver.Store(719):
> Started compaction of 2 file(s) into
> /user/hudson/TestMergeTool/compaction.dir/1737903548/2106020000373448348
> [junit] 2009-06-30 03:57:17,174 DEBUG [main] regionserver.Store(734):
> Completed compaction of contents; store size is 929.0
> [junit] 2009-06-30 03:57:17,190 INFO [main] regionserver.HRegion(779):
> compaction completed on region TestMergeTool,,1246334236974 in 0sec
> [junit] 2009-06-30 03:57:17,191 DEBUG [main] regionserver.HRegion(2153):
> Files for new region
> [junit] 2009-06-30 03:57:17,192 DEBUG [main] regionserver.HRegion(2217):
> f hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/.regioninfo
> size=584
> [junit] 2009-06-30 03:57:17,192 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents
> [junit] 2009-06-30 03:57:17,193 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/1215187022045408950
> size=929
> [junit] 2009-06-30 03:57:17,194 DEBUG [main] regionserver.HRegion(1949):
> DELETING region hdfs://localhost:45638/user/hudson/TestMergeTool/296810668
> [junit] 2009-06-30 03:57:17,206 DEBUG [main] regionserver.HRegion(1949):
> DELETING region hdfs://localhost:45638/user/hudson/TestMergeTool/556286697
> [junit] 2009-06-30 03:57:17,222 INFO [main] regionserver.HRegion(2159):
> merge completed. New region is TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,222 DEBUG [main] util.Merge(319): Removing
> region: REGION => {NAME => 'TestMergeTool,row_0100,1246334235613', STARTKEY
> => 'row_0100', ENDKEY => 'row_0600', ENCODED => 296810668, TABLE => {{NAME
> => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE',
> VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2009-06-30 03:57:17,223 DEBUG [main] util.Merge(319): Removing
> region: REGION => {NAME => 'TestMergeTool,,1246334227227', STARTKEY => '',
> ENDKEY => '', ENCODED => 556286697, TABLE => {{NAME => 'TestMergeTool',
> FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE',
> TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
> => 'true'}]}} from .META.,,1
> [junit] 2009-06-30 03:57:17,224 INFO [main] util.Merge(255): Adding
> REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY =>
> '', ENCODED => 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
> [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL =>
> '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE =>
> 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '',
> ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true',
> MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS
> => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info',
> VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,,1246334236974: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:17,226 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(436):
> Closing -ROOT-,,0: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on -ROOT-,,0
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region -ROOT-,,0
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.Store(445):
> closed info
> [junit] 2009-06-30 03:57:17,227 INFO [main] regionserver.HRegion(485):
> Closed -ROOT-,,0
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(436):
> Closing .META.,,1: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on .META.,,1
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region .META.,,1
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(889):
> Started memstore flush for region .META.,,1. Current region memstore size
> 1.1k
> [junit] 2009-06-30 03:57:17,265 DEBUG [main] regionserver.Store(527):
> Added
> hdfs://localhost:45638/user/hudson/.META./1028785192/historian/7821267978201518970,
> entries=2, sequenceid=34, memsize=356.0, filesize=505.0 to .META.,,1
> [junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.Store(527):
> Added
> hdfs://localhost:45638/user/hudson/.META./1028785192/info/6068259940689918333,
> entries=3, sequenceid=34, memsize=804.0, filesize=826.0 to .META.,,1
> [junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.HRegion(966):
> Finished memstore flush of ~1.1k for region .META.,,1 in 74ms, sequence
> id=34, compaction requested=true
> [junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.Store(445):
> closed historian
> [junit] 2009-06-30 03:57:17,302 DEBUG [main] regionserver.Store(445):
> closed info
> [junit] 2009-06-30 03:57:17,302 INFO [main] regionserver.HRegion(485):
> Closed .META.,,1
> [junit] 2009-06-30 03:57:17,335 INFO [main] regionserver.HLog(294):
> Roll /user/hudson/.logs_1246334236267/hlog.dat.1246334236300, entries=6,
> calcsize=1083, filesize=1103. New hlog
> /user/hudson/.logs_1246334236267/hlog.dat.1246334237318
> [junit] 2009-06-30 03:57:17,335 DEBUG [main] regionserver.HLog(303):
> Last sequence written is empty. Deleting all old hlogs
> [junit] 2009-06-30 03:57:17,335 INFO [main] regionserver.HLog(428):
> removing old hlog file
> /user/hudson/.logs_1246334236267/hlog.dat.1246334236300 whose highest
> sequence/edit id is 33
> [junit] 2009-06-30 03:57:17,349 DEBUG [main] regionserver.HLog(465):
> closing hlog writer in
> hdfs://localhost:45638/user/hudson/.logs_1246334236267
> [junit] 2009-06-30 03:57:17,381 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY
> => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME =>
> 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE',
> VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:17,382 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,,1246334236974, encoded=1737903548
> [junit] 2009-06-30 03:57:17,388 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/1737903548/contents/1215187022045408950,
> isReference=false, sequence id=7, length=929, majorCompaction=false
> [junit] 2009-06-30 03:57:17,390 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,,1246334236974/1737903548 available; sequence id is 8
> [junit] 2009-06-30 03:57:17,395 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,,1246334236974: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:17,396 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,396 INFO [main] util.TestMergeTool(187):
> Verified merging regions 0+1+2+3 and 4
> [junit] 2009-06-30 03:57:17,397 DEBUG [main] regionserver.HLog(465):
> closing hlog writer in /tmp/.logs_1246334232474
> [junit] 2009-06-30 03:57:17,433 INFO [main] hbase.HBaseTestCase(627):
> Shutting down FileSystem
> [junit] 2009-06-30 03:57:17,433 INFO [main] hbase.HBaseTestCase(634):
> Shutting down Mini DFS
> [junit] Shutting down the Mini HDFS Cluster
> [junit] Shutting down DataNode 1
> [junit] 2009-06-30 03:57:17,446 WARN
> [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@14627a]
> datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:51166,
> storageID=DS-1933627198-67.195.138.9-51166-1246334230528, infoPort=44091,
> ipcPort=43500):DataXceiveServer:
> java.nio.channels.AsynchronousCloseException
> [junit] at
> java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
> [junit] at
> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
> [junit] at
> sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
> [junit] at
> org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] Shutting down DataNode 0
> [junit] 2009-06-30 03:57:18,466 WARN
> [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@682406]
> datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:58118,
> storageID=DS-35413305-67.195.138.9-58118-1246334229778, infoPort=40885,
> ipcPort=56618):DataXceiveServer:
> java.nio.channels.AsynchronousCloseException
> [junit] at
> java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
> [junit] at
> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
> [junit] at
> sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
> [junit] at
> org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] 2009-06-30 03:57:19,466 WARN
> [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@2f0d54]
> namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread
> received InterruptedException.java.lang.InterruptedException: sleep
> interrupted
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 12.365 sec
> [junit] Running org.apache.hadoop.hbase.util.TestRootPath
> [junit] 2009-06-30 03:57:19,889 INFO [main] util.TestRootPath(60): Got
> expected exception when checking invalid path:
> [junit] java.io.IOException: Root directory does not contain a scheme
> [junit] at
> org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:212)
> [junit] at
> org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
> [junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
> [junit] at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> [junit] at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> [junit] at java.lang.reflect.Method.invoke(Method.java:597)
> [junit] at junit.framework.TestCase.runTest(TestCase.java:154)
> [junit] at junit.framework.TestCase.runBare(TestCase.java:127)
> [junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
> [junit] at
> junit.framework.TestResult.runProtected(TestResult.java:124)
> [junit] at junit.framework.TestResult.run(TestResult.java:109)
> [junit] at junit.framework.TestCase.run(TestCase.java:118)
> [junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
> [junit] at junit.framework.TestSuite.run(TestSuite.java:203)
> [junit] at
> org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
> [junit] at
> org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
> [junit] at
> org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.072 sec
>
> test-contrib:
>
> test:
>
> check-contrib:
>
> init:
> [echo] contrib: stargate
>
> init-contrib:
>
> compile-jar:
>
> compile-war:
>
> compile-test:
> [javac] Compiling 20 source files to
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/test
>
> test:
> [junit] Running org.apache.hadoop.hbase.stargate.Test00MiniCluster
> [junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you
> sure you have Clover in the runtime classpath? (class
> java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.Test00MiniCluster FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestRowResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestRowResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestScannerResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestScannerResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestSchemaResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestSchemaResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestStatusResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestStatusResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestTableResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestTableResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestVersionResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestVersionResource FAILED
> [junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you
> sure you have Clover in the runtime classpath? (class
> java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestCellModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestCellModel FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestCellSetModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestCellSetModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestColumnSchemaModel
> [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.028 sec
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestRowModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestRowModel FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestScannerModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestScannerModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestStorageClusterStatusModel
> [junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you
> sure you have Clover in the runtime classpath? (class
> java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
> [junit] Tests run: 3, Failures: 0, Errors: 3, Time elapsed: 0.013 sec
> [junit] TEST
> org.apache.hadoop.hbase.stargate.model.TestStorageClusterStatusModel FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestStorageClusterVersionModel
> [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.006 sec
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableInfoModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableInfoModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableListModel
> [junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableListModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableRegionModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableRegionModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableSchemaModel
> [junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableSchemaModel
> FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestVersionModel
> [junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestVersionModel
> FAILED
> [junit] Tests FAILED
>
> BUILD FAILED
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml:511: The following error occurred while executing this line:
>
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build.xml:54: The following error occurred while executing this line:
>
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/stargate/build.xml:66: Tests failed!
>
> Total time: 40 minutes 51 seconds
> [locks-and-latches] Releasing all the locks
> [locks-and-latches] All the locks released
> Recording test results
> Publishing Clover coverage report...
>
>
Re: Build failed in Hudson: HBase-Patch #685
Posted by stack <st...@duboce.net>.
All core hbase tests are passing. The src/contrib ones are failing because
of clover. Will try an fix as part of hbase-1588.
St.Ack
On Mon, Jun 29, 2009 at 8:57 PM, Apache Hudson Server <
hudson@hudson.zones.apache.org> wrote:
> See http://hudson.zones.apache.org/hudson/job/HBase-Patch/685/changes
>
> Changes:
>
> [stack] HBASE-1591 HBASE-1554 broke
> org.apache.hadoop.hbase.io.hfile.TestLruBlockCache.testResizeBlockCache
>
> ------------------------------------------
> [...truncated 27874 lines...]
> [junit] 2009-06-30 03:57:16,316 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY =>
> '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true',
> IS_META => 'true', MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME =>
> 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647',
> BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,317 DEBUG [main] regionserver.HRegion(264):
> Opening region -ROOT-,,0, encoded=70236052
> [junit] 2009-06-30 03:57:16,323 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/-ROOT-/70236052/info/4339548386246392681,
> isReference=false, sequence id=2, length=840, majorCompaction=false
> [junit] 2009-06-30 03:57:16,325 INFO [main] regionserver.HRegion(339):
> region -ROOT-,,0/70236052 available; sequence id is 3
> [junit] 2009-06-30 03:57:16,325 INFO [main] regionserver.HRegion(764):
> Starting compaction on region -ROOT-,,0
> [junit] 2009-06-30 03:57:16,327 INFO [main] regionserver.HRegion(779):
> compaction completed on region -ROOT-,,0 in 0sec
> [junit] 2009-06-30 03:57:16,328 INFO [main] util.Merge(211): Found meta
> for region1 .META.,,1, meta for region2 .META.,,1
> [junit] 2009-06-30 03:57:16,328 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY =>
> '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true',
> MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS
> => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info',
> VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,329 DEBUG [main] regionserver.HRegion(264):
> Opening region .META.,,1, encoded=1028785192
> [junit] 2009-06-30 03:57:16,336 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/historian/5069761041856656638,
> isReference=false, sequence id=27, length=521, majorCompaction=false
> [junit] 2009-06-30 03:57:16,340 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/historian/6185116046084952634,
> isReference=false, sequence id=20, length=651, majorCompaction=false
> [junit] 2009-06-30 03:57:16,345 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/info/3580226687465594789,
> isReference=false, sequence id=27, length=874, majorCompaction=false
> [junit] 2009-06-30 03:57:16,349 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/info/8246226911915738154,
> isReference=false, sequence id=6, length=2226, majorCompaction=false
> [junit] 2009-06-30 03:57:16,353 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/.META./1028785192/info/8694815133596552284,
> isReference=false, sequence id=20, length=994, majorCompaction=false
> [junit] 2009-06-30 03:57:16,355 INFO [main] regionserver.HRegion(339):
> region .META.,,1/1028785192 available; sequence id is 28
> [junit] 2009-06-30 03:57:16,356 INFO [main] regionserver.HRegion(764):
> Starting compaction on region .META.,,1
> [junit] 2009-06-30 03:57:16,368 DEBUG [main] regionserver.Store(710):
> Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
> [junit] 2009-06-30 03:57:16,388 DEBUG [main] regionserver.Store(719):
> Started compaction of 2 file(s) into
> /user/hudson/.META./compaction.dir/1028785192/4345181293809946232
> [junit] 2009-06-30 03:57:16,463 DEBUG [main] regionserver.Store(734):
> Completed compaction of historian; store size is 781.0
> [junit] 2009-06-30 03:57:16,465 DEBUG [main] regionserver.Store(710):
> Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
> [junit] 2009-06-30 03:57:16,483 DEBUG [main] regionserver.Store(719):
> Started compaction of 2 file(s) into
> /user/hudson/.META./compaction.dir/1028785192/4756716123822289492
> [junit] 2009-06-30 03:57:16,559 DEBUG [main] regionserver.Store(734):
> Completed compaction of info; store size is 3.3k
> [junit] 2009-06-30 03:57:16,575 INFO [main] regionserver.HRegion(779):
> compaction completed on region .META.,,1 in 0sec
> [junit] 2009-06-30 03:57:16,577 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1246334235613',
> STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 296810668, TABLE =>
> {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION =>
> 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536',
> IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,577 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,row_0100,1246334235613, encoded=296810668
> [junit] 2009-06-30 03:57:16,583 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/296810668/contents/88071840529929138,
> isReference=false, sequence id=6, length=791, majorCompaction=false
> [junit] 2009-06-30 03:57:16,586 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,row_0100,1246334235613/296810668 available; sequence id
> is 7
> [junit] 2009-06-30 03:57:16,586 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => 'TestMergeTool,,1246334227227', STARTKEY
> => '', ENDKEY => '', ENCODED => 556286697, TABLE => {{NAME =>
> 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3',
> COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY
> => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,586 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,,1246334227227, encoded=556286697
> [junit] 2009-06-30 03:57:16,594 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/556286697/contents/1084511642783190185,
> isReference=false, sequence id=7, length=471, majorCompaction=false
> [junit] 2009-06-30 03:57:16,596 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,,1246334227227/556286697 available; sequence id is 8
> [junit] 2009-06-30 03:57:16,596 INFO [main] regionserver.HRegion(764):
> Starting major compaction on region TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,627 DEBUG [main] regionserver.Store(719):
> Started compaction of 1 file(s) into
> /user/hudson/TestMergeTool/compaction.dir/296810668/3365216675693282626
> [junit] 2009-06-30 03:57:16,682 DEBUG [main] regionserver.Store(734):
> Completed major compaction of contents; store size is 791.0
> [junit] 2009-06-30 03:57:16,853 INFO [main] regionserver.HRegion(779):
> compaction completed on region TestMergeTool,row_0100,1246334235613 in 0sec
> [junit] 2009-06-30 03:57:16,853 DEBUG [main] regionserver.HRegion(2070):
> Files for region: TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,854 DEBUG [main] regionserver.HRegion(2217):
> f hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/.regioninfo
> size=631
> [junit] 2009-06-30 03:57:16,854 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/contents
> [junit] 2009-06-30 03:57:16,855 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/296810668/contents/8452771743625025355
> size=791
> [junit] 2009-06-30 03:57:16,855 INFO [main] regionserver.HRegion(764):
> Starting major compaction on region TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,885 DEBUG [main] regionserver.Store(719):
> Started compaction of 1 file(s) into
> /user/hudson/TestMergeTool/compaction.dir/556286697/6880362628274821301
> [junit] 2009-06-30 03:57:16,956 DEBUG [main] regionserver.Store(734):
> Completed major compaction of contents; store size is 471.0
> [junit] 2009-06-30 03:57:16,972 INFO [main] regionserver.HRegion(779):
> compaction completed on region TestMergeTool,,1246334227227 in 0sec
> [junit] 2009-06-30 03:57:16,972 DEBUG [main] regionserver.HRegion(2075):
> Files for region: TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,973 DEBUG [main] regionserver.HRegion(2217):
> f hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/.regioninfo
> size=583
> [junit] 2009-06-30 03:57:16,973 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/contents
> [junit] 2009-06-30 03:57:16,974 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/556286697/contents/4035034942458743945
> size=471
> [junit] 2009-06-30 03:57:16,975 INFO [main] regionserver.HRegion(2104):
> Creating new region REGION => {NAME => 'TestMergeTool,,1246334236974',
> STARTKEY => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME =>
> 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE',
> VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:16,988 INFO [main] regionserver.HRegion(2113):
> starting merge of regions: TestMergeTool,row_0100,1246334235613 and
> TestMergeTool,,1246334227227 into new region REGION => {NAME =>
> 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY => '', ENCODED =>
> 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME =>
> 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647',
> BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with
> start key <> and end key <>
> [junit] 2009-06-30 03:57:16,988 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,row_0100,1246334235613: compactions & flushes disabled
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:16,989 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,row_0100,1246334235613
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,,1246334227227: compactions & flushes disabled
> [junit] 2009-06-30 03:57:16,989 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,990 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:16,990 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:16,990 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,,1246334227227
> [junit] 2009-06-30 03:57:17,036 DEBUG [main] regionserver.HRegion(2146):
> Files for new region
> [junit] 2009-06-30 03:57:17,037 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents
> [junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/4373146720411115184
> size=791
> [junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/4708547828713801463
> size=471
> [junit] 2009-06-30 03:57:17,038 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,,1246334236974, encoded=1737903548
> [junit] 2009-06-30 03:57:17,080 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/1737903548/contents/4373146720411115184,
> isReference=false, sequence id=6, length=791, majorCompaction=true
> [junit] 2009-06-30 03:57:17,084 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/1737903548/contents/4708547828713801463,
> isReference=false, sequence id=7, length=471, majorCompaction=true
> [junit] 2009-06-30 03:57:17,087 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,,1246334236974/1737903548 available; sequence id is 8
> [junit] 2009-06-30 03:57:17,087 INFO [main] regionserver.HRegion(764):
> Starting compaction on region TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,096 DEBUG [main] regionserver.Store(710):
> Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
> [junit] 2009-06-30 03:57:17,111 DEBUG [main] regionserver.Store(719):
> Started compaction of 2 file(s) into
> /user/hudson/TestMergeTool/compaction.dir/1737903548/2106020000373448348
> [junit] 2009-06-30 03:57:17,174 DEBUG [main] regionserver.Store(734):
> Completed compaction of contents; store size is 929.0
> [junit] 2009-06-30 03:57:17,190 INFO [main] regionserver.HRegion(779):
> compaction completed on region TestMergeTool,,1246334236974 in 0sec
> [junit] 2009-06-30 03:57:17,191 DEBUG [main] regionserver.HRegion(2153):
> Files for new region
> [junit] 2009-06-30 03:57:17,192 DEBUG [main] regionserver.HRegion(2217):
> f hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/.regioninfo
> size=584
> [junit] 2009-06-30 03:57:17,192 DEBUG [main] regionserver.HRegion(2214):
> d hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents
> [junit] 2009-06-30 03:57:17,193 DEBUG [main] regionserver.HRegion(2217):
> f
> hdfs://localhost:45638/user/hudson/TestMergeTool/1737903548/contents/1215187022045408950
> size=929
> [junit] 2009-06-30 03:57:17,194 DEBUG [main] regionserver.HRegion(1949):
> DELETING region hdfs://localhost:45638/user/hudson/TestMergeTool/296810668
> [junit] 2009-06-30 03:57:17,206 DEBUG [main] regionserver.HRegion(1949):
> DELETING region hdfs://localhost:45638/user/hudson/TestMergeTool/556286697
> [junit] 2009-06-30 03:57:17,222 INFO [main] regionserver.HRegion(2159):
> merge completed. New region is TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,222 DEBUG [main] util.Merge(319): Removing
> region: REGION => {NAME => 'TestMergeTool,row_0100,1246334235613', STARTKEY
> => 'row_0100', ENDKEY => 'row_0600', ENCODED => 296810668, TABLE => {{NAME
> => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE',
> VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2009-06-30 03:57:17,223 DEBUG [main] util.Merge(319): Removing
> region: REGION => {NAME => 'TestMergeTool,,1246334227227', STARTKEY => '',
> ENDKEY => '', ENCODED => 556286697, TABLE => {{NAME => 'TestMergeTool',
> FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE',
> TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE
> => 'true'}]}} from .META.,,1
> [junit] 2009-06-30 03:57:17,224 INFO [main] util.Merge(255): Adding
> REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY => '', ENDKEY =>
> '', ENCODED => 1737903548, TABLE => {{NAME => 'TestMergeTool', FAMILIES =>
> [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL =>
> '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE =>
> 'true'}]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '',
> ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true',
> MEMSTORE_FLUSHSIZE => '16384', FAMILIES => [{NAME => 'historian', VERSIONS
> => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info',
> VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE =>
> '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,,1246334236974: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,225 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:17,226 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(436):
> Closing -ROOT-,,0: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on -ROOT-,,0
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region -ROOT-,,0
> [junit] 2009-06-30 03:57:17,226 DEBUG [main] regionserver.Store(445):
> closed info
> [junit] 2009-06-30 03:57:17,227 INFO [main] regionserver.HRegion(485):
> Closed -ROOT-,,0
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(436):
> Closing .META.,,1: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on .META.,,1
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region .META.,,1
> [junit] 2009-06-30 03:57:17,227 DEBUG [main] regionserver.HRegion(889):
> Started memstore flush for region .META.,,1. Current region memstore size
> 1.1k
> [junit] 2009-06-30 03:57:17,265 DEBUG [main] regionserver.Store(527):
> Added
> hdfs://localhost:45638/user/hudson/.META./1028785192/historian/7821267978201518970,
> entries=2, sequenceid=34, memsize=356.0, filesize=505.0 to .META.,,1
> [junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.Store(527):
> Added
> hdfs://localhost:45638/user/hudson/.META./1028785192/info/6068259940689918333,
> entries=3, sequenceid=34, memsize=804.0, filesize=826.0 to .META.,,1
> [junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.HRegion(966):
> Finished memstore flush of ~1.1k for region .META.,,1 in 74ms, sequence
> id=34, compaction requested=true
> [junit] 2009-06-30 03:57:17,301 DEBUG [main] regionserver.Store(445):
> closed historian
> [junit] 2009-06-30 03:57:17,302 DEBUG [main] regionserver.Store(445):
> closed info
> [junit] 2009-06-30 03:57:17,302 INFO [main] regionserver.HRegion(485):
> Closed .META.,,1
> [junit] 2009-06-30 03:57:17,335 INFO [main] regionserver.HLog(294):
> Roll /user/hudson/.logs_1246334236267/hlog.dat.1246334236300, entries=6,
> calcsize=1083, filesize=1103. New hlog
> /user/hudson/.logs_1246334236267/hlog.dat.1246334237318
> [junit] 2009-06-30 03:57:17,335 DEBUG [main] regionserver.HLog(303):
> Last sequence written is empty. Deleting all old hlogs
> [junit] 2009-06-30 03:57:17,335 INFO [main] regionserver.HLog(428):
> removing old hlog file
> /user/hudson/.logs_1246334236267/hlog.dat.1246334236300 whose highest
> sequence/edit id is 33
> [junit] 2009-06-30 03:57:17,349 DEBUG [main] regionserver.HLog(465):
> closing hlog writer in
> hdfs://localhost:45638/user/hudson/.logs_1246334236267
> [junit] 2009-06-30 03:57:17,381 DEBUG [main] regionserver.HRegion(1831):
> Opening region: REGION => {NAME => 'TestMergeTool,,1246334236974', STARTKEY
> => '', ENDKEY => '', ENCODED => 1737903548, TABLE => {{NAME =>
> 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE',
> VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY =>
> 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-06-30 03:57:17,382 DEBUG [main] regionserver.HRegion(264):
> Opening region TestMergeTool,,1246334236974, encoded=1737903548
> [junit] 2009-06-30 03:57:17,388 DEBUG [main] regionserver.Store(380):
> loaded /user/hudson/TestMergeTool/1737903548/contents/1215187022045408950,
> isReference=false, sequence id=7, length=929, majorCompaction=false
> [junit] 2009-06-30 03:57:17,390 INFO [main] regionserver.HRegion(339):
> region TestMergeTool,,1246334236974/1737903548 available; sequence id is 8
> [junit] 2009-06-30 03:57:17,395 DEBUG [main] regionserver.HRegion(436):
> Closing TestMergeTool,,1246334236974: compactions & flushes disabled
> [junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.HRegion(466):
> Updates disabled for region, no outstanding scanners on
> TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.HRegion(473):
> No more row locks outstanding on region TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,396 DEBUG [main] regionserver.Store(445):
> closed contents
> [junit] 2009-06-30 03:57:17,396 INFO [main] regionserver.HRegion(485):
> Closed TestMergeTool,,1246334236974
> [junit] 2009-06-30 03:57:17,396 INFO [main] util.TestMergeTool(187):
> Verified merging regions 0+1+2+3 and 4
> [junit] 2009-06-30 03:57:17,397 DEBUG [main] regionserver.HLog(465):
> closing hlog writer in /tmp/.logs_1246334232474
> [junit] 2009-06-30 03:57:17,433 INFO [main] hbase.HBaseTestCase(627):
> Shutting down FileSystem
> [junit] 2009-06-30 03:57:17,433 INFO [main] hbase.HBaseTestCase(634):
> Shutting down Mini DFS
> [junit] Shutting down the Mini HDFS Cluster
> [junit] Shutting down DataNode 1
> [junit] 2009-06-30 03:57:17,446 WARN
> [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@14627a]
> datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:51166,
> storageID=DS-1933627198-67.195.138.9-51166-1246334230528, infoPort=44091,
> ipcPort=43500):DataXceiveServer:
> java.nio.channels.AsynchronousCloseException
> [junit] at
> java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
> [junit] at
> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
> [junit] at
> sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
> [junit] at
> org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] Shutting down DataNode 0
> [junit] 2009-06-30 03:57:18,466 WARN
> [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@682406]
> datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:58118,
> storageID=DS-35413305-67.195.138.9-58118-1246334229778, infoPort=40885,
> ipcPort=56618):DataXceiveServer:
> java.nio.channels.AsynchronousCloseException
> [junit] at
> java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
> [junit] at
> sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
> [junit] at
> sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
> [junit] at
> org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] 2009-06-30 03:57:19,466 WARN
> [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@2f0d54]
> namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread
> received InterruptedException.java.lang.InterruptedException: sleep
> interrupted
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 12.365 sec
> [junit] Running org.apache.hadoop.hbase.util.TestRootPath
> [junit] 2009-06-30 03:57:19,889 INFO [main] util.TestRootPath(60): Got
> expected exception when checking invalid path:
> [junit] java.io.IOException: Root directory does not contain a scheme
> [junit] at
> org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:212)
> [junit] at
> org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
> [junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
> [junit] at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> [junit] at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> [junit] at java.lang.reflect.Method.invoke(Method.java:597)
> [junit] at junit.framework.TestCase.runTest(TestCase.java:154)
> [junit] at junit.framework.TestCase.runBare(TestCase.java:127)
> [junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
> [junit] at
> junit.framework.TestResult.runProtected(TestResult.java:124)
> [junit] at junit.framework.TestResult.run(TestResult.java:109)
> [junit] at junit.framework.TestCase.run(TestCase.java:118)
> [junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
> [junit] at junit.framework.TestSuite.run(TestSuite.java:203)
> [junit] at
> org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
> [junit] at
> org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
> [junit] at
> org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.072 sec
>
> test-contrib:
>
> test:
>
> check-contrib:
>
> init:
> [echo] contrib: stargate
>
> init-contrib:
>
> compile-jar:
>
> compile-war:
>
> compile-test:
> [javac] Compiling 20 source files to
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/test
>
> test:
> [junit] Running org.apache.hadoop.hbase.stargate.Test00MiniCluster
> [junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you
> sure you have Clover in the runtime classpath? (class
> java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.Test00MiniCluster FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestRowResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestRowResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestScannerResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestScannerResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestSchemaResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestSchemaResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestStatusResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestStatusResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestTableResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestTableResource FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.TestVersionResource
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.TestVersionResource FAILED
> [junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you
> sure you have Clover in the runtime classpath? (class
> java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestCellModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestCellModel FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestCellSetModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestCellSetModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestColumnSchemaModel
> [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.028 sec
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestRowModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestRowModel FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestScannerModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestScannerModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestStorageClusterStatusModel
> [junit] [CLOVER] FATAL ERROR: Clover could not be initialised. Are you
> sure you have Clover in the runtime classpath? (class
> java.lang.NoClassDefFoundError:com_cenqua_clover/CloverVersionInfo)
> [junit] Tests run: 3, Failures: 0, Errors: 3, Time elapsed: 0.013 sec
> [junit] TEST
> org.apache.hadoop.hbase.stargate.model.TestStorageClusterStatusModel FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestStorageClusterVersionModel
> [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.006 sec
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableInfoModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableInfoModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableListModel
> [junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableListModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableRegionModel
> [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableRegionModel
> FAILED
> [junit] Running
> org.apache.hadoop.hbase.stargate.model.TestTableSchemaModel
> [junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestTableSchemaModel
> FAILED
> [junit] Running org.apache.hadoop.hbase.stargate.model.TestVersionModel
> [junit] Tests run: 3, Failures: 0, Errors: 1, Time elapsed: 0.006 sec
> [junit] TEST org.apache.hadoop.hbase.stargate.model.TestVersionModel
> FAILED
> [junit] Tests FAILED
>
> BUILD FAILED
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml:511: The following error occurred while executing this line:
>
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build.xml:54: The following error occurred while executing this line:
>
> http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/stargate/build.xml:66: Tests failed!
>
> Total time: 40 minutes 51 seconds
> [locks-and-latches] Releasing all the locks
> [locks-and-latches] All the locks released
> Recording test results
> Publishing Clover coverage report...
>
>