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 2010/01/13 09:02:18 UTC
Build failed in Hudson: HBase-Patch #1068
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1068/changes>
Changes:
[stack] HBASE-2119 Fix top-level NOTICES.txt file. Its stale.
[stack] HBASE-2101 KeyValueSortReducer collapses all values to last passed
------------------------------------------
Started by an SCM change
Building remotely on vesta.apache.org (Ubuntu)
Updating https://svn.apache.org/repos/asf/hadoop/hbase/trunk
U NOTICE.txt
U CHANGES.txt
U src/java/org/apache/hadoop/hbase/mapreduce/KeyValueSortReducer.java
At revision 898669
[locks-and-latches] Checking to see if we really have the locks
[locks-and-latches] Have all the locks, build can start
Buildfile: build.xml
clean-contrib:
clean:
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:544: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build.xml>:63: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/build.xml>:2: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build-contrib.xml>:73: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy-targets.xml>:5: Source resource does not exist: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/${hbase.root}/ivy/libraries.properties>
Total time: 0 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
Publishing Clover HTML report...
Publishing Clover XML report...
Publishing Clover coverage results...
Hudson build is back to normal: HBase-Patch #1074
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1074/changes>
Re: Build failed in Hudson: HBase-Patch #1073
Posted by Kay Kay <ka...@gmail.com>.
ec2 is failing because of trying to reuse build-contrib.xml , that in
turn assumes that all build/contrib need to have their dependencies
managed by ivy .
I understand that ec2 does not need anything at the moment, but putting
in a stub ec2/ivy.xml to make this build pass. ( HBASE-2126 in place ).
On 1/14/10 3:33 PM, Apache Hudson Server wrote:
> See<http://hudson.zones.apache.org/hudson/job/HBase-Patch/1073/changes>
>
> Changes:
>
> [jdcryans] HBASE-2124 Useless exception in HMaster on startup
>
> ------------------------------------------
> [...truncated 117265 lines...]
> [junit] 2010-01-14 23:40:14,224 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263512413133/hlog.dat.1263512413141, entries=6, calcsize=1131, filesize=1075. New hlog /user/hudson/.logs_1263512413133/hlog.dat.1263512414196
> [junit] 2010-01-14 23:40:14,224 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
> [junit] 2010-01-14 23:40:14,224 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263512413133/hlog.dat.1263512413141 whose highest sequence/edit id is 26
> [junit] 2010-01-14 23:40:14,236 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
> [junit] 2010-01-14 23:40:14,238 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
> [junit] 2010-01-14 23:40:14,238 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:45527/user/hudson/.logs_1263512413133
> [junit] 2010-01-14 23:40:14,272 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263512413704', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 370725342, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2010-01-14 23:40:14,272 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263512413704, encoded=370725342
> [junit] 2010-01-14 23:40:14,279 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/370725342/contents/5712218011795593034, isReference=false, sequence id=6, length=791, majorCompaction=false
> [junit] 2010-01-14 23:40:14,282 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263512413704/370725342 available; sequence id is 7
> [junit] 2010-01-14 23:40:14,286 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263512413704: compactions& flushes disabled
> [junit] 2010-01-14 23:40:14,286 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:14,286 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:14,287 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2010-01-14 23:40:14,287 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:14,287 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2 and 3
> [junit] 2010-01-14 23:40:14,287 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
> [junit] fs2=hdfs://localhost:45527
> [junit] 2010-01-14 23:40:14,287 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
> [junit] 2010-01-14 23:40:14,288 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
> [junit] 2010-01-14 23:40:14,288 INFO [main] util.Merge(81): Verifying that file system is available...
> [junit] 2010-01-14 23:40:14,289 INFO [main] util.Merge(90): Verifying that HBase is not running...
> [junit] 2010-01-14 23:40:14,299 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
> [junit] 2010-01-14 23:40:14,300 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
> [junit] 2010-01-14 23:40:14,300 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
> [junit] 2010-01-14 23:40:14,300 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
> [junit] 2010-01-14 23:40:14,301 INFO [main] util.Merge(196): Merging regions TestMergeTool,row_0100,1263512413704 and TestMergeTool,,1263512405819 in table TestMergeTool
> [junit] 2010-01-14 23:40:14,328 INFO [main] wal.HLog(274): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
> [junit] 2010-01-14 23:40:14,338 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
> [junit] 2010-01-14 23:40:14,338 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
> [junit] 2010-01-14 23:40:14,339 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
> [junit] 2010-01-14 23:40:14,339 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
> [junit] 2010-01-14 23:40:14,348 INFO [main] wal.HLog(352): New hlog /user/hudson/.logs_1263512414301/hlog.dat.1263512414328
> [junit] 2010-01-14 23:40:14,348 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2010-01-14 23:40:14,349 DEBUG [main] regionserver.HRegion(266): Opening region -ROOT-,,0, encoded=70236052
> [junit] 2010-01-14 23:40:14,368 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/9030743022311832258, isReference=false, sequence id=2, length=809, majorCompaction=false
> [junit] 2010-01-14 23:40:14,371 INFO [main] regionserver.HRegion(344): region -ROOT-,,0/70236052 available; sequence id is 3
> [junit] 2010-01-14 23:40:14,371 INFO [main] regionserver.HRegion(772): Starting compaction on region -ROOT-,,0
> [junit] 2010-01-14 23:40:14,373 INFO [main] regionserver.HRegion(787): compaction completed on region -ROOT-,,0 in 0sec
> [junit] 2010-01-14 23:40:14,373 INFO [main] util.Merge(210): Found meta for region1 .META.,,1, meta for region2 .META.,,1
> [junit] 2010-01-14 23:40:14,374 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2010-01-14 23:40:14,374 DEBUG [main] regionserver.HRegion(266): Opening region .META.,,1, encoded=1028785192
> [junit] 2010-01-14 23:40:14,381 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/196994515672058370, isReference=false, sequence id=20, length=651, majorCompaction=false
> [junit] 2010-01-14 23:40:14,394 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/4384306433360922478, isReference=false, sequence id=27, length=521, majorCompaction=false
> [junit] 2010-01-14 23:40:14,400 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2830375552578136200, isReference=false, sequence id=20, length=994, majorCompaction=false
> [junit] 2010-01-14 23:40:14,404 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/3805973177772231803, isReference=false, sequence id=6, length=2226, majorCompaction=false
> [junit] 2010-01-14 23:40:14,408 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/5696143304208121871, isReference=false, sequence id=27, length=874, majorCompaction=false
> [junit] 2010-01-14 23:40:14,410 INFO [main] regionserver.HRegion(344): region .META.,,1/1028785192 available; sequence id is 28
> [junit] 2010-01-14 23:40:14,410 INFO [main] regionserver.HRegion(772): Starting compaction on region .META.,,1
> [junit] 2010-01-14 23:40:14,426 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
> [junit] 2010-01-14 23:40:14,426 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
> [junit] 2010-01-14 23:40:14,515 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:45527/user/hudson/.META./1028785192/historian/6011503777248196676; store size is 781
> [junit] 2010-01-14 23:40:14,517 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
> [junit] 2010-01-14 23:40:14,517 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
> [junit] 2010-01-14 23:40:14,744 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:45527/user/hudson/.META./1028785192/info/1950828655909202899; store size is 3.3k
> [junit] 2010-01-14 23:40:14,756 INFO [main] regionserver.HRegion(787): compaction completed on region .META.,,1 in 0sec
> [junit] 2010-01-14 23:40:14,756 INFO [main] util.MetaUtils(126): OPENING META .META.,,1
> [junit] 2010-01-14 23:40:14,758 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263512413704', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 370725342, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2010-01-14 23:40:14,758 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263512413704, encoded=370725342
> [junit] 2010-01-14 23:40:14,765 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/370725342/contents/5712218011795593034, isReference=false, sequence id=6, length=791, majorCompaction=false
> [junit] 2010-01-14 23:40:14,767 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263512413704/370725342 available; sequence id is 7
> [junit] 2010-01-14 23:40:14,767 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263512405819', STARTKEY => '', ENDKEY => '', ENCODED => 1734719325, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2010-01-14 23:40:14,768 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263512405819, encoded=1734719325
> [junit] 2010-01-14 23:40:14,775 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1734719325/contents/5116715506100038178, isReference=false, sequence id=7, length=471, majorCompaction=false
> [junit] 2010-01-14 23:40:14,777 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263512405819/1734719325 available; sequence id is 8
> [junit] 2010-01-14 23:40:14,777 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:14,796 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/370725342, seqid=6
> [junit] 2010-01-14 23:40:14,871 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/contents/3490072812426052972; store size is 791
> [junit] 2010-01-14 23:40:14,883 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,row_0100,1263512413704 in 0sec
> [junit] 2010-01-14 23:40:14,883 DEBUG [main] regionserver.HRegion(2119): Files for region: TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:14,884 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/.regioninfo size=631
> [junit] 2010-01-14 23:40:14,884 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/contents
> [junit] 2010-01-14 23:40:14,885 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/contents/3490072812426052972 size=791
> [junit] 2010-01-14 23:40:14,885 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,,1263512405819
> [junit] 2010-01-14 23:40:14,907 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1734719325, seqid=7
> [junit] 2010-01-14 23:40:14,987 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/contents/4732980784524442874; store size is 471
> [junit] 2010-01-14 23:40:15,002 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263512405819 in 0sec
> [junit] 2010-01-14 23:40:15,003 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,,1263512405819
> [junit] 2010-01-14 23:40:15,004 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/.regioninfo size=584
> [junit] 2010-01-14 23:40:15,004 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/contents
> [junit] 2010-01-14 23:40:15,005 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/contents/4732980784524442874 size=471
> [junit] 2010-01-14 23:40:15,005 INFO [main] regionserver.HRegion(2153): Creating new region REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2010-01-14 23:40:15,027 INFO [main] regionserver.HRegion(2162): starting merge of regions: TestMergeTool,row_0100,1263512413704 and TestMergeTool,,1263512405819 into new region REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, 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] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263512413704: compactions& flushes disabled
> [junit] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2010-01-14 23:40:15,028 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263512413704
> [junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263512405819: compactions& flushes disabled
> [junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263512405819
> [junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263512405819
> [junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2010-01-14 23:40:15,029 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263512405819
> [junit] 2010-01-14 23:40:15,082 DEBUG [main] regionserver.HRegion(2195): Files for new region
> [junit] 2010-01-14 23:40:15,083 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents
> [junit] 2010-01-14 23:40:15,084 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5444659990231525871 size=791
> [junit] 2010-01-14 23:40:15,084 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5694323119149272861 size=471
> [junit] 2010-01-14 23:40:15,084 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263512415005, encoded=1875861921
> [junit] 2010-01-14 23:40:15,143 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1875861921/contents/5444659990231525871, isReference=false, sequence id=6, length=791, majorCompaction=true
> [junit] 2010-01-14 23:40:15,147 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1875861921/contents/5694323119149272861, isReference=false, sequence id=7, length=471, majorCompaction=true
> [junit] 2010-01-14 23:40:15,149 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263512415005/1875861921 available; sequence id is 8
> [junit] 2010-01-14 23:40:15,149 INFO [main] regionserver.HRegion(772): Starting compaction on region TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,170 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
> [junit] 2010-01-14 23:40:15,170 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1875861921, seqid=7
> [junit] 2010-01-14 23:40:15,285 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5254254593921630661; store size is 929
> [junit] 2010-01-14 23:40:15,305 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263512415005 in 0sec
> [junit] 2010-01-14 23:40:15,306 DEBUG [main] regionserver.HRegion(2202): Files for new region
> [junit] 2010-01-14 23:40:15,307 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/.regioninfo size=584
> [junit] 2010-01-14 23:40:15,307 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents
> [junit] 2010-01-14 23:40:15,308 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5254254593921630661 size=929
> [junit] 2010-01-14 23:40:15,308 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:45527/user/hudson/TestMergeTool/370725342
> [junit] 2010-01-14 23:40:15,325 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325
> [junit] 2010-01-14 23:40:15,345 INFO [main] regionserver.HRegion(2208): merge completed. New region is TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,345 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1263512413704', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 370725342, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2010-01-14 23:40:15,346 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,,1263512405819', STARTKEY => '', ENDKEY => '', ENCODED => 1734719325, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2010-01-14 23:40:15,347 INFO [main] util.Merge(254): Adding REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, 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', 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] 2010-01-14 23:40:15,347 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263512415005: compactions& flushes disabled
> [junit] 2010-01-14 23:40:15,347 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2010-01-14 23:40:15,348 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.HRegion(455): Closing -ROOT-,,0: compactions& flushes disabled
> [junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on -ROOT-,,0
> [junit] 2010-01-14 23:40:15,351 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region -ROOT-,,0
> [junit] 2010-01-14 23:40:15,351 DEBUG [main] regionserver.Store(482): closed info
> [junit] 2010-01-14 23:40:15,352 INFO [main] regionserver.HRegion(491): Closed -ROOT-,,0
> [junit] 2010-01-14 23:40:15,352 INFO [main] util.MetaUtils(149): CLOSING META .META.,,1
> [junit] 2010-01-14 23:40:15,352 DEBUG [main] regionserver.HRegion(455): Closing .META.,,1: compactions& flushes disabled
> [junit] 2010-01-14 23:40:15,352 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on .META.,,1
> [junit] 2010-01-14 23:40:15,353 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region .META.,,1
> [junit] 2010-01-14 23:40:15,353 DEBUG [main] regionserver.HRegion(896): Started memstore flush for region .META.,,1. Current region memstore size 968
> [junit] 2010-01-14 23:40:15,509 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:45527/user/hudson/.META./1028785192/historian/3840544017463009130, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
> [junit] 2010-01-14 23:40:15,551 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:45527/user/hudson/.META./1028785192/info/5997280445278780422, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
> [junit] 2010-01-14 23:40:15,551 DEBUG [main] regionserver.HRegion(972): Finished memstore flush of ~968 for region .META.,,1 in 198ms, sequence id=34, compaction requested=true
> [junit] 2010-01-14 23:40:15,552 DEBUG [main] regionserver.Store(482): closed historian
> [junit] 2010-01-14 23:40:15,552 DEBUG [main] regionserver.Store(482): closed info
> [junit] 2010-01-14 23:40:15,552 INFO [main] regionserver.HRegion(491): Closed .META.,,1
> [junit] 2010-01-14 23:40:15,579 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
> [junit] 2010-01-14 23:40:15,579 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
> [junit] 2010-01-14 23:40:15,579 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
> [junit] 2010-01-14 23:40:15,580 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
> [junit] 2010-01-14 23:40:15,593 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263512414301/hlog.dat.1263512414328, entries=6, calcsize=1083, filesize=1027. New hlog /user/hudson/.logs_1263512414301/hlog.dat.1263512415568
> [junit] 2010-01-14 23:40:15,594 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
> [junit] 2010-01-14 23:40:15,594 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263512414301/hlog.dat.1263512414328 whose highest sequence/edit id is 33
> [junit] 2010-01-14 23:40:15,613 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
> [junit] 2010-01-14 23:40:15,614 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
> [junit] 2010-01-14 23:40:15,614 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:45527/user/hudson/.logs_1263512414301
> [junit] 2010-01-14 23:40:15,653 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2010-01-14 23:40:15,654 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263512415005, encoded=1875861921
> [junit] 2010-01-14 23:40:15,660 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1875861921/contents/5254254593921630661, isReference=false, sequence id=7, length=929, majorCompaction=false
> [junit] 2010-01-14 23:40:15,662 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263512415005/1875861921 available; sequence id is 8
> [junit] 2010-01-14 23:40:15,667 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263512415005: compactions& flushes disabled
> [junit] 2010-01-14 23:40:15,667 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,668 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,668 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2010-01-14 23:40:15,668 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263512415005
> [junit] 2010-01-14 23:40:15,668 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2+3 and 4
> [junit] 2010-01-14 23:40:15,669 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
> [junit] 2010-01-14 23:40:15,669 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
> [junit] 2010-01-14 23:40:15,669 DEBUG [main] wal.HLog(594): closing hlog writer in /tmp/.logs_1263512410804
> [junit] 2010-01-14 23:40:15,717 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
> [junit] Shutting down the Mini HDFS Cluster
> [junit] Shutting down DataNode 1
> [junit] 2010-01-14 23:40:15,876 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1a41cc7] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:54208, storageID=DS-1339373315-67.195.138.9-54208-1263512408504, infoPort=43791, ipcPort=47044):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:133)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] Shutting down DataNode 0
> [junit] 2010-01-14 23:40:15,986 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@17c2891] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:56623, storageID=DS-179550687-67.195.138.9-56623-1263512407867, infoPort=41113, ipcPort=51638):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:133)
> [junit] at java.lang.Thread.run(Thread.java:619)
> [junit]
> [junit] 2010-01-14 23:40:16,090 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@1a0225b] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
> [junit] 2010-01-14 23:40:16,091 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@1ee148b] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
> [junit] 2010-01-14 23:40:16,112 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 10.474 sec
> [junit] 2010-01-14 23:40:16,477 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(633): Closed connection with ZooKeeper
> [junit] Running org.apache.hadoop.hbase.util.TestRootPath
> [junit] 2010-01-14 23:40:16,861 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path:
> [junit] java.io.IOException: Root directory does not have a scheme
> [junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:231)
> [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:168)
> [junit] at junit.framework.TestCase.runBare(TestCase.java:134)
> [junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
> [junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
> [junit] at junit.framework.TestResult.run(TestResult.java:113)
> [junit] at junit.framework.TestCase.run(TestCase.java:124)
> [junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
> [junit] at junit.framework.TestSuite.run(TestSuite.java:227)
> [junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:79)
> [junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
> [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.084 sec
>
> test-contrib:
>
> test:
>
> compile:
>
> compile-examples:
>
> ivy-download:
> [get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.1.0/ivy-2.1.0.jar
> [get] To:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivy-2.1.0.jar>
> [get] Not modified - so not downloaded
>
> ivy-init-dirs:
> [mkdir] Created dir:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy>
> [mkdir] Created dir:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib>
> [mkdir] Created dir:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/report>
> [mkdir] Created dir:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/maven>
> [mkdir] Created dir:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib/common>
>
> ivy-probe-antlib:
>
> ivy-init-antlib:
>
> ivy-init:
> :: loading settings :: file =<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivysettings.xml>
>
> ivy-resolve-test:
> [ivy:resolve]<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
>
> BUILD FAILED
> <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:530: 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/ivy-targets.xml>:84: syntax errors in ivy file: java.text.ParseException:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
>
> Total time: 26 minutes 0 seconds
> [locks-and-latches] Releasing all the locks
> [locks-and-latches] All the locks released
> Archiving artifacts
> Recording test results
> Publishing Clover coverage report...
> No Clover report will be published due to a Build Failure
>
>
>
Build failed in Hudson: HBase-Patch #1073
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1073/changes>
Changes:
[jdcryans] HBASE-2124 Useless exception in HMaster on startup
------------------------------------------
[...truncated 117265 lines...]
[junit] 2010-01-14 23:40:14,224 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263512413133/hlog.dat.1263512413141, entries=6, calcsize=1131, filesize=1075. New hlog /user/hudson/.logs_1263512413133/hlog.dat.1263512414196
[junit] 2010-01-14 23:40:14,224 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 23:40:14,224 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263512413133/hlog.dat.1263512413141 whose highest sequence/edit id is 26
[junit] 2010-01-14 23:40:14,236 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 23:40:14,238 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 23:40:14,238 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:45527/user/hudson/.logs_1263512413133
[junit] 2010-01-14 23:40:14,272 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263512413704', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 370725342, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 23:40:14,272 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263512413704, encoded=370725342
[junit] 2010-01-14 23:40:14,279 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/370725342/contents/5712218011795593034, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2010-01-14 23:40:14,282 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263512413704/370725342 available; sequence id is 7
[junit] 2010-01-14 23:40:14,286 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263512413704: compactions & flushes disabled
[junit] 2010-01-14 23:40:14,286 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:14,286 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:14,287 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 23:40:14,287 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:14,287 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2 and 3
[junit] 2010-01-14 23:40:14,287 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
[junit] fs2=hdfs://localhost:45527
[junit] 2010-01-14 23:40:14,287 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 23:40:14,288 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 23:40:14,288 INFO [main] util.Merge(81): Verifying that file system is available...
[junit] 2010-01-14 23:40:14,289 INFO [main] util.Merge(90): Verifying that HBase is not running...
[junit] 2010-01-14 23:40:14,299 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 23:40:14,300 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 23:40:14,300 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 23:40:14,300 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 23:40:14,301 INFO [main] util.Merge(196): Merging regions TestMergeTool,row_0100,1263512413704 and TestMergeTool,,1263512405819 in table TestMergeTool
[junit] 2010-01-14 23:40:14,328 INFO [main] wal.HLog(274): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2010-01-14 23:40:14,338 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 23:40:14,338 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 23:40:14,339 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 23:40:14,339 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 23:40:14,348 INFO [main] wal.HLog(352): New hlog /user/hudson/.logs_1263512414301/hlog.dat.1263512414328
[junit] 2010-01-14 23:40:14,348 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 23:40:14,349 DEBUG [main] regionserver.HRegion(266): Opening region -ROOT-,,0, encoded=70236052
[junit] 2010-01-14 23:40:14,368 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/9030743022311832258, isReference=false, sequence id=2, length=809, majorCompaction=false
[junit] 2010-01-14 23:40:14,371 INFO [main] regionserver.HRegion(344): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2010-01-14 23:40:14,371 INFO [main] regionserver.HRegion(772): Starting compaction on region -ROOT-,,0
[junit] 2010-01-14 23:40:14,373 INFO [main] regionserver.HRegion(787): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2010-01-14 23:40:14,373 INFO [main] util.Merge(210): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2010-01-14 23:40:14,374 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 23:40:14,374 DEBUG [main] regionserver.HRegion(266): Opening region .META.,,1, encoded=1028785192
[junit] 2010-01-14 23:40:14,381 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/196994515672058370, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2010-01-14 23:40:14,394 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/4384306433360922478, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2010-01-14 23:40:14,400 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2830375552578136200, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2010-01-14 23:40:14,404 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/3805973177772231803, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2010-01-14 23:40:14,408 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/5696143304208121871, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2010-01-14 23:40:14,410 INFO [main] regionserver.HRegion(344): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2010-01-14 23:40:14,410 INFO [main] regionserver.HRegion(772): Starting compaction on region .META.,,1
[junit] 2010-01-14 23:40:14,426 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 23:40:14,426 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 23:40:14,515 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:45527/user/hudson/.META./1028785192/historian/6011503777248196676; store size is 781
[junit] 2010-01-14 23:40:14,517 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2010-01-14 23:40:14,517 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 23:40:14,744 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:45527/user/hudson/.META./1028785192/info/1950828655909202899; store size is 3.3k
[junit] 2010-01-14 23:40:14,756 INFO [main] regionserver.HRegion(787): compaction completed on region .META.,,1 in 0sec
[junit] 2010-01-14 23:40:14,756 INFO [main] util.MetaUtils(126): OPENING META .META.,,1
[junit] 2010-01-14 23:40:14,758 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263512413704', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 370725342, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 23:40:14,758 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263512413704, encoded=370725342
[junit] 2010-01-14 23:40:14,765 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/370725342/contents/5712218011795593034, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2010-01-14 23:40:14,767 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263512413704/370725342 available; sequence id is 7
[junit] 2010-01-14 23:40:14,767 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263512405819', STARTKEY => '', ENDKEY => '', ENCODED => 1734719325, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 23:40:14,768 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263512405819, encoded=1734719325
[junit] 2010-01-14 23:40:14,775 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1734719325/contents/5116715506100038178, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2010-01-14 23:40:14,777 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263512405819/1734719325 available; sequence id is 8
[junit] 2010-01-14 23:40:14,777 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:14,796 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/370725342, seqid=6
[junit] 2010-01-14 23:40:14,871 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/contents/3490072812426052972; store size is 791
[junit] 2010-01-14 23:40:14,883 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,row_0100,1263512413704 in 0sec
[junit] 2010-01-14 23:40:14,883 DEBUG [main] regionserver.HRegion(2119): Files for region: TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:14,884 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/.regioninfo size=631
[junit] 2010-01-14 23:40:14,884 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/contents
[junit] 2010-01-14 23:40:14,885 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/370725342/contents/3490072812426052972 size=791
[junit] 2010-01-14 23:40:14,885 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,,1263512405819
[junit] 2010-01-14 23:40:14,907 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1734719325, seqid=7
[junit] 2010-01-14 23:40:14,987 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/contents/4732980784524442874; store size is 471
[junit] 2010-01-14 23:40:15,002 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263512405819 in 0sec
[junit] 2010-01-14 23:40:15,003 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,,1263512405819
[junit] 2010-01-14 23:40:15,004 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/.regioninfo size=584
[junit] 2010-01-14 23:40:15,004 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/contents
[junit] 2010-01-14 23:40:15,005 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325/contents/4732980784524442874 size=471
[junit] 2010-01-14 23:40:15,005 INFO [main] regionserver.HRegion(2153): Creating new region REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 23:40:15,027 INFO [main] regionserver.HRegion(2162): starting merge of regions: TestMergeTool,row_0100,1263512413704 and TestMergeTool,,1263512405819 into new region REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, 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] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263512413704: compactions & flushes disabled
[junit] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:15,027 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 23:40:15,028 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263512413704
[junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263512405819: compactions & flushes disabled
[junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263512405819
[junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263512405819
[junit] 2010-01-14 23:40:15,028 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 23:40:15,029 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263512405819
[junit] 2010-01-14 23:40:15,082 DEBUG [main] regionserver.HRegion(2195): Files for new region
[junit] 2010-01-14 23:40:15,083 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents
[junit] 2010-01-14 23:40:15,084 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5444659990231525871 size=791
[junit] 2010-01-14 23:40:15,084 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5694323119149272861 size=471
[junit] 2010-01-14 23:40:15,084 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263512415005, encoded=1875861921
[junit] 2010-01-14 23:40:15,143 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1875861921/contents/5444659990231525871, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2010-01-14 23:40:15,147 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1875861921/contents/5694323119149272861, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2010-01-14 23:40:15,149 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263512415005/1875861921 available; sequence id is 8
[junit] 2010-01-14 23:40:15,149 INFO [main] regionserver.HRegion(772): Starting compaction on region TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,170 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 23:40:15,170 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1875861921, seqid=7
[junit] 2010-01-14 23:40:15,285 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5254254593921630661; store size is 929
[junit] 2010-01-14 23:40:15,305 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263512415005 in 0sec
[junit] 2010-01-14 23:40:15,306 DEBUG [main] regionserver.HRegion(2202): Files for new region
[junit] 2010-01-14 23:40:15,307 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/.regioninfo size=584
[junit] 2010-01-14 23:40:15,307 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents
[junit] 2010-01-14 23:40:15,308 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:45527/user/hudson/TestMergeTool/1875861921/contents/5254254593921630661 size=929
[junit] 2010-01-14 23:40:15,308 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:45527/user/hudson/TestMergeTool/370725342
[junit] 2010-01-14 23:40:15,325 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:45527/user/hudson/TestMergeTool/1734719325
[junit] 2010-01-14 23:40:15,345 INFO [main] regionserver.HRegion(2208): merge completed. New region is TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,345 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1263512413704', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 370725342, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 23:40:15,346 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,,1263512405819', STARTKEY => '', ENDKEY => '', ENCODED => 1734719325, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 23:40:15,347 INFO [main] util.Merge(254): Adding REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, 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', 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] 2010-01-14 23:40:15,347 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263512415005: compactions & flushes disabled
[junit] 2010-01-14 23:40:15,347 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 23:40:15,348 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.HRegion(455): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2010-01-14 23:40:15,348 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2010-01-14 23:40:15,351 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region -ROOT-,,0
[junit] 2010-01-14 23:40:15,351 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 23:40:15,352 INFO [main] regionserver.HRegion(491): Closed -ROOT-,,0
[junit] 2010-01-14 23:40:15,352 INFO [main] util.MetaUtils(149): CLOSING META .META.,,1
[junit] 2010-01-14 23:40:15,352 DEBUG [main] regionserver.HRegion(455): Closing .META.,,1: compactions & flushes disabled
[junit] 2010-01-14 23:40:15,352 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2010-01-14 23:40:15,353 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region .META.,,1
[junit] 2010-01-14 23:40:15,353 DEBUG [main] regionserver.HRegion(896): Started memstore flush for region .META.,,1. Current region memstore size 968
[junit] 2010-01-14 23:40:15,509 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:45527/user/hudson/.META./1028785192/historian/3840544017463009130, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
[junit] 2010-01-14 23:40:15,551 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:45527/user/hudson/.META./1028785192/info/5997280445278780422, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
[junit] 2010-01-14 23:40:15,551 DEBUG [main] regionserver.HRegion(972): Finished memstore flush of ~968 for region .META.,,1 in 198ms, sequence id=34, compaction requested=true
[junit] 2010-01-14 23:40:15,552 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2010-01-14 23:40:15,552 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 23:40:15,552 INFO [main] regionserver.HRegion(491): Closed .META.,,1
[junit] 2010-01-14 23:40:15,579 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 23:40:15,579 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 23:40:15,579 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 23:40:15,580 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 23:40:15,593 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263512414301/hlog.dat.1263512414328, entries=6, calcsize=1083, filesize=1027. New hlog /user/hudson/.logs_1263512414301/hlog.dat.1263512415568
[junit] 2010-01-14 23:40:15,594 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 23:40:15,594 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263512414301/hlog.dat.1263512414328 whose highest sequence/edit id is 33
[junit] 2010-01-14 23:40:15,613 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 23:40:15,614 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 23:40:15,614 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:45527/user/hudson/.logs_1263512414301
[junit] 2010-01-14 23:40:15,653 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263512415005', STARTKEY => '', ENDKEY => '', ENCODED => 1875861921, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 23:40:15,654 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263512415005, encoded=1875861921
[junit] 2010-01-14 23:40:15,660 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1875861921/contents/5254254593921630661, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2010-01-14 23:40:15,662 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263512415005/1875861921 available; sequence id is 8
[junit] 2010-01-14 23:40:15,667 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263512415005: compactions & flushes disabled
[junit] 2010-01-14 23:40:15,667 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,668 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,668 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 23:40:15,668 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263512415005
[junit] 2010-01-14 23:40:15,668 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2+3 and 4
[junit] 2010-01-14 23:40:15,669 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 23:40:15,669 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 23:40:15,669 DEBUG [main] wal.HLog(594): closing hlog writer in /tmp/.logs_1263512410804
[junit] 2010-01-14 23:40:15,717 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2010-01-14 23:40:15,876 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1a41cc7] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:54208, storageID=DS-1339373315-67.195.138.9-54208-1263512408504, infoPort=43791, ipcPort=47044):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2010-01-14 23:40:15,986 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@17c2891] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:56623, storageID=DS-179550687-67.195.138.9-56623-1263512407867, infoPort=41113, ipcPort=51638):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-01-14 23:40:16,090 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@1a0225b] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 23:40:16,091 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@1ee148b] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 23:40:16,112 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 10.474 sec
[junit] 2010-01-14 23:40:16,477 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(633): Closed connection with ZooKeeper
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2010-01-14 23:40:16,861 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not have a scheme
[junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:231)
[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:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:79)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[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.084 sec
test-contrib:
test:
compile:
compile-examples:
ivy-download:
[get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.1.0/ivy-2.1.0.jar
[get] To: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivy-2.1.0.jar>
[get] Not modified - so not downloaded
ivy-init-dirs:
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/report>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/maven>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib/common>
ivy-probe-antlib:
ivy-init-antlib:
ivy-init:
:: loading settings :: file = <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivysettings.xml>
ivy-resolve-test:
[ivy:resolve] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:530: 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/ivy-targets.xml>:84: syntax errors in ivy file: java.text.ParseException: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
Total time: 26 minutes 0 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: HBase-Patch #1072
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1072/changes>
Changes:
[apurtell] HBASE-2024 [stargate] Deletes not working as expected
HBASE-2122 [stargate] Initializing scanner column families doesn't work
------------------------------------------
[...truncated 117572 lines...]
[junit] 2010-01-14 11:54:31,264 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 11:54:31,264 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 11:54:31,264 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 11:54:31,265 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 11:54:31,275 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263470070241/hlog.dat.1263470070251, entries=6, calcsize=1131, filesize=1055. New hlog /user/hudson/.logs_1263470070241/hlog.dat.1263470071253
[junit] 2010-01-14 11:54:31,275 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 11:54:31,275 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263470070241/hlog.dat.1263470070251 whose highest sequence/edit id is 26
[junit] 2010-01-14 11:54:33,293 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:43426/user/hudson/.logs_1263470070241
[junit] 2010-01-14 11:54:33,323 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263470070747', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1727009387, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 11:54:33,324 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263470070747, encoded=1727009387
[junit] 2010-01-14 11:54:33,331 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1727009387/contents/8592264542747735618, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2010-01-14 11:54:33,334 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263470070747/1727009387 available; sequence id is 7
[junit] 2010-01-14 11:54:33,338 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263470070747: compactions & flushes disabled
[junit] 2010-01-14 11:54:33,338 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,338 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,339 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 11:54:33,339 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,339 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2 and 3
[junit] 2010-01-14 11:54:33,339 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
[junit] fs2=hdfs://localhost:43426
[junit] 2010-01-14 11:54:33,340 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 11:54:33,340 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 11:54:33,341 INFO [main] util.Merge(81): Verifying that file system is available...
[junit] 2010-01-14 11:54:33,341 INFO [main] util.Merge(90): Verifying that HBase is not running...
[junit] 2010-01-14 11:54:33,352 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 11:54:33,353 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 11:54:33,353 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 11:54:33,353 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 11:54:33,354 INFO [main] util.Merge(196): Merging regions TestMergeTool,row_0100,1263470070747 and TestMergeTool,,1263470060650 in table TestMergeTool
[junit] 2010-01-14 11:54:33,363 INFO [main] wal.HLog(274): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2010-01-14 11:54:33,374 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 11:54:33,374 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 11:54:33,374 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 11:54:33,375 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 11:54:33,387 INFO [main] wal.HLog(352): New hlog /user/hudson/.logs_1263470073354/hlog.dat.1263470073363
[junit] 2010-01-14 11:54:33,388 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 11:54:33,388 DEBUG [main] regionserver.HRegion(266): Opening region -ROOT-,,0, encoded=70236052
[junit] 2010-01-14 11:54:33,395 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/1147001904352385793, isReference=false, sequence id=2, length=809, majorCompaction=false
[junit] 2010-01-14 11:54:33,397 INFO [main] regionserver.HRegion(344): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2010-01-14 11:54:33,398 INFO [main] regionserver.HRegion(772): Starting compaction on region -ROOT-,,0
[junit] 2010-01-14 11:54:33,400 INFO [main] regionserver.HRegion(787): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2010-01-14 11:54:33,400 INFO [main] util.Merge(210): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2010-01-14 11:54:33,401 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 11:54:33,401 DEBUG [main] regionserver.HRegion(266): Opening region .META.,,1, encoded=1028785192
[junit] 2010-01-14 11:54:33,408 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/1539051734346136545, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2010-01-14 11:54:33,412 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/8861626539378440099, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2010-01-14 11:54:33,418 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2886944039100717485, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2010-01-14 11:54:33,422 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/5915064856192469851, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2010-01-14 11:54:33,427 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/7738136616555784723, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2010-01-14 11:54:33,429 INFO [main] regionserver.HRegion(344): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2010-01-14 11:54:33,429 INFO [main] regionserver.HRegion(772): Starting compaction on region .META.,,1
[junit] 2010-01-14 11:54:33,439 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 11:54:33,439 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 11:54:33,691 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:43426/user/hudson/.META./1028785192/historian/7327466902744342773; store size is 781
[junit] 2010-01-14 11:54:33,693 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2010-01-14 11:54:33,693 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 11:54:33,751 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:43426/user/hudson/.META./1028785192/info/7319525902739757090; store size is 3.3k
[junit] 2010-01-14 11:54:33,761 INFO [main] regionserver.HRegion(787): compaction completed on region .META.,,1 in 0sec
[junit] 2010-01-14 11:54:33,762 INFO [main] util.MetaUtils(126): OPENING META .META.,,1
[junit] 2010-01-14 11:54:33,764 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263470070747', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1727009387, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 11:54:33,764 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263470070747, encoded=1727009387
[junit] 2010-01-14 11:54:33,771 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1727009387/contents/8592264542747735618, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2010-01-14 11:54:33,773 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263470070747/1727009387 available; sequence id is 7
[junit] 2010-01-14 11:54:33,774 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263470060650', STARTKEY => '', ENDKEY => '', ENCODED => 1151682542, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 11:54:33,774 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263470060650, encoded=1151682542
[junit] 2010-01-14 11:54:33,781 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1151682542/contents/3504600757685350561, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2010-01-14 11:54:33,783 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263470060650/1151682542 available; sequence id is 8
[junit] 2010-01-14 11:54:33,783 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,793 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1727009387, seqid=6
[junit] 2010-01-14 11:54:33,841 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:43426/user/hudson/TestMergeTool/1727009387/contents/519050643180769801; store size is 791
[junit] 2010-01-14 11:54:33,851 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,row_0100,1263470070747 in 0sec
[junit] 2010-01-14 11:54:33,851 DEBUG [main] regionserver.HRegion(2119): Files for region: TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,852 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/1727009387/.regioninfo size=632
[junit] 2010-01-14 11:54:33,852 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:43426/user/hudson/TestMergeTool/1727009387/contents
[junit] 2010-01-14 11:54:33,853 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/1727009387/contents/519050643180769801 size=791
[junit] 2010-01-14 11:54:33,853 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,,1263470060650
[junit] 2010-01-14 11:54:33,863 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1151682542, seqid=7
[junit] 2010-01-14 11:54:33,910 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:43426/user/hudson/TestMergeTool/1151682542/contents/33631878190248943; store size is 471
[junit] 2010-01-14 11:54:33,921 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263470060650 in 0sec
[junit] 2010-01-14 11:54:33,921 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,,1263470060650
[junit] 2010-01-14 11:54:33,922 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/1151682542/.regioninfo size=584
[junit] 2010-01-14 11:54:33,922 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:43426/user/hudson/TestMergeTool/1151682542/contents
[junit] 2010-01-14 11:54:33,923 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/1151682542/contents/33631878190248943 size=471
[junit] 2010-01-14 11:54:33,923 INFO [main] regionserver.HRegion(2153): Creating new region REGION => {NAME => 'TestMergeTool,,1263470073923', STARTKEY => '', ENDKEY => '', ENCODED => 123610072, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 11:54:33,933 INFO [main] regionserver.HRegion(2162): starting merge of regions: TestMergeTool,row_0100,1263470070747 and TestMergeTool,,1263470060650 into new region REGION => {NAME => 'TestMergeTool,,1263470073923', STARTKEY => '', ENDKEY => '', ENCODED => 123610072, 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] 2010-01-14 11:54:33,933 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263470070747: compactions & flushes disabled
[junit] 2010-01-14 11:54:33,933 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,933 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,933 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 11:54:33,934 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263470070747
[junit] 2010-01-14 11:54:33,934 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263470060650: compactions & flushes disabled
[junit] 2010-01-14 11:54:33,934 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263470060650
[junit] 2010-01-14 11:54:33,934 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263470060650
[junit] 2010-01-14 11:54:33,934 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 11:54:33,935 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263470060650
[junit] 2010-01-14 11:54:33,974 DEBUG [main] regionserver.HRegion(2195): Files for new region
[junit] 2010-01-14 11:54:33,975 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:43426/user/hudson/TestMergeTool/123610072/contents
[junit] 2010-01-14 11:54:33,976 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/123610072/contents/2895376315351199492 size=791
[junit] 2010-01-14 11:54:33,976 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/123610072/contents/847717083609993678 size=471
[junit] 2010-01-14 11:54:33,976 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263470073923, encoded=123610072
[junit] 2010-01-14 11:54:34,006 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/123610072/contents/2895376315351199492, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2010-01-14 11:54:34,009 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/123610072/contents/847717083609993678, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2010-01-14 11:54:34,012 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263470073923/123610072 available; sequence id is 8
[junit] 2010-01-14 11:54:34,012 INFO [main] regionserver.HRegion(772): Starting compaction on region TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:34,022 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 11:54:34,022 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/123610072, seqid=7
[junit] 2010-01-14 11:54:34,084 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:43426/user/hudson/TestMergeTool/123610072/contents/7328566065707158335; store size is 929
[junit] 2010-01-14 11:54:34,094 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263470073923 in 0sec
[junit] 2010-01-14 11:54:34,096 DEBUG [main] regionserver.HRegion(2202): Files for new region
[junit] 2010-01-14 11:54:34,097 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/123610072/.regioninfo size=583
[junit] 2010-01-14 11:54:34,097 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:43426/user/hudson/TestMergeTool/123610072/contents
[junit] 2010-01-14 11:54:34,098 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:43426/user/hudson/TestMergeTool/123610072/contents/7328566065707158335 size=929
[junit] 2010-01-14 11:54:34,098 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:43426/user/hudson/TestMergeTool/1727009387
[junit] 2010-01-14 11:54:34,108 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:43426/user/hudson/TestMergeTool/1151682542
[junit] 2010-01-14 11:54:34,213 INFO [main] regionserver.HRegion(2208): merge completed. New region is TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:34,213 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1263470070747', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1727009387, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 11:54:34,214 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,,1263470060650', STARTKEY => '', ENDKEY => '', ENCODED => 1151682542, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 11:54:34,215 INFO [main] util.Merge(254): Adding REGION => {NAME => 'TestMergeTool,,1263470073923', STARTKEY => '', ENDKEY => '', ENCODED => 123610072, 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', 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] 2010-01-14 11:54:34,215 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263470073923: compactions & flushes disabled
[junit] 2010-01-14 11:54:34,216 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:34,216 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:34,216 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 11:54:34,216 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:34,216 DEBUG [main] regionserver.HRegion(455): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2010-01-14 11:54:34,217 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2010-01-14 11:54:34,217 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region -ROOT-,,0
[junit] 2010-01-14 11:54:34,217 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 11:54:34,217 INFO [main] regionserver.HRegion(491): Closed -ROOT-,,0
[junit] 2010-01-14 11:54:34,217 INFO [main] util.MetaUtils(149): CLOSING META .META.,,1
[junit] 2010-01-14 11:54:34,217 DEBUG [main] regionserver.HRegion(455): Closing .META.,,1: compactions & flushes disabled
[junit] 2010-01-14 11:54:34,218 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2010-01-14 11:54:34,218 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region .META.,,1
[junit] 2010-01-14 11:54:34,218 DEBUG [main] regionserver.HRegion(896): Started memstore flush for region .META.,,1. Current region memstore size 968
[junit] 2010-01-14 11:54:34,283 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:43426/user/hudson/.META./1028785192/historian/1391315502552041195, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
[junit] 2010-01-14 11:54:34,323 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:43426/user/hudson/.META./1028785192/info/1463913151382829785, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
[junit] 2010-01-14 11:54:34,323 DEBUG [main] regionserver.HRegion(972): Finished memstore flush of ~968 for region .META.,,1 in 105ms, sequence id=34, compaction requested=true
[junit] 2010-01-14 11:54:34,324 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2010-01-14 11:54:34,324 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 11:54:34,324 INFO [main] regionserver.HRegion(491): Closed .META.,,1
[junit] 2010-01-14 11:54:34,355 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 11:54:34,355 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 11:54:34,355 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 11:54:34,355 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 11:54:34,371 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263470073354/hlog.dat.1263470073363, entries=6, calcsize=1083, filesize=1007. New hlog /user/hudson/.logs_1263470073354/hlog.dat.1263470074344
[junit] 2010-01-14 11:54:34,372 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 11:54:34,372 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263470073354/hlog.dat.1263470073363 whose highest sequence/edit id is 33
[junit] 2010-01-14 11:54:36,388 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:43426/user/hudson/.logs_1263470073354
[junit] 2010-01-14 11:54:36,429 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263470073923', STARTKEY => '', ENDKEY => '', ENCODED => 123610072, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 11:54:36,429 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263470073923, encoded=123610072
[junit] 2010-01-14 11:54:36,436 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/123610072/contents/7328566065707158335, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2010-01-14 11:54:36,438 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263470073923/123610072 available; sequence id is 8
[junit] 2010-01-14 11:54:36,443 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263470073923: compactions & flushes disabled
[junit] 2010-01-14 11:54:36,443 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:36,444 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:36,444 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 11:54:36,444 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263470073923
[junit] 2010-01-14 11:54:36,444 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2+3 and 4
[junit] 2010-01-14 11:54:36,444 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 11:54:36,445 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 11:54:36,445 DEBUG [main] wal.HLog(594): closing hlog writer in /tmp/.logs_1263470065843
[junit] 2010-01-14 11:54:36,481 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2010-01-14 11:54:36,584 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1ea0105] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:33005, storageID=DS-301181052-67.195.138.9-33005-1263470063456, infoPort=44681, ipcPort=59261):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2010-01-14 11:54:36,696 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@196e136] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:42257, storageID=DS-1020423145-67.195.138.9-42257-1263470062754, infoPort=37058, ipcPort=36121):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-01-14 11:54:36,803 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@1a0225b] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 11:54:36,803 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@1ee148b] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 11:54:36,820 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 16.351 sec
[junit] 2010-01-14 11:54:37,735 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2010-01-14 11:54:38,122 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not have a scheme
[junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:231)
[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:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:79)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[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.086 sec
test-contrib:
test:
compile:
compile-examples:
ivy-download:
[get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.1.0/ivy-2.1.0.jar
[get] To: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivy-2.1.0.jar>
[get] Not modified - so not downloaded
ivy-init-dirs:
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/report>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/maven>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib/common>
ivy-probe-antlib:
ivy-init-antlib:
ivy-init:
:: loading settings :: file = <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivysettings.xml>
ivy-resolve-test:
[ivy:resolve] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:530: 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/ivy-targets.xml>:84: syntax errors in ivy file: java.text.ParseException: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
Total time: 28 minutes 17 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: HBase-Patch #1071
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1071/changes>
Changes:
[stack] HBASE-2123 Remove 'master' command-line option from PE
------------------------------------------
[...truncated 117261 lines...]
[junit] 2010-01-14 07:54:12,943 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263455651699/hlog.dat.1263455651710, entries=6, calcsize=1131, filesize=1055. New hlog /user/hudson/.logs_1263455651699/hlog.dat.1263455652923
[junit] 2010-01-14 07:54:12,944 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 07:54:12,944 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263455651699/hlog.dat.1263455651710 whose highest sequence/edit id is 26
[junit] 2010-01-14 07:54:12,951 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 07:54:12,953 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 07:54:12,953 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:48556/user/hudson/.logs_1263455651699
[junit] 2010-01-14 07:54:12,975 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263455652442', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 655928143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 07:54:12,975 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263455652442, encoded=655928143
[junit] 2010-01-14 07:54:12,982 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/655928143/contents/2335849190125310988, isReference=false, sequence id=6, length=745, majorCompaction=false
[junit] 2010-01-14 07:54:12,984 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263455652442/655928143 available; sequence id is 7
[junit] 2010-01-14 07:54:12,988 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263455652442: compactions & flushes disabled
[junit] 2010-01-14 07:54:12,988 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:12,988 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:12,988 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 07:54:12,989 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:12,989 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2 and 3
[junit] 2010-01-14 07:54:12,989 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
[junit] fs2=hdfs://localhost:48556
[junit] 2010-01-14 07:54:12,989 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 07:54:12,990 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 07:54:12,990 INFO [main] util.Merge(81): Verifying that file system is available...
[junit] 2010-01-14 07:54:12,991 INFO [main] util.Merge(90): Verifying that HBase is not running...
[junit] 2010-01-14 07:54:13,001 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 07:54:13,002 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 07:54:13,002 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 07:54:13,002 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 07:54:13,003 INFO [main] util.Merge(196): Merging regions TestMergeTool,row_0100,1263455652442 and TestMergeTool,,1263455644743 in table TestMergeTool
[junit] 2010-01-14 07:54:13,014 INFO [main] wal.HLog(274): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2010-01-14 07:54:13,025 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 07:54:13,025 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 07:54:13,025 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 07:54:13,026 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 07:54:13,034 INFO [main] wal.HLog(352): New hlog /user/hudson/.logs_1263455653003/hlog.dat.1263455653014
[junit] 2010-01-14 07:54:13,035 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 07:54:13,035 DEBUG [main] regionserver.HRegion(266): Opening region -ROOT-,,0, encoded=70236052
[junit] 2010-01-14 07:54:13,042 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/7245457262675438440, isReference=false, sequence id=2, length=809, majorCompaction=false
[junit] 2010-01-14 07:54:13,051 INFO [main] regionserver.HRegion(344): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2010-01-14 07:54:13,052 INFO [main] regionserver.HRegion(772): Starting compaction on region -ROOT-,,0
[junit] 2010-01-14 07:54:13,053 INFO [main] regionserver.HRegion(787): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2010-01-14 07:54:13,054 INFO [main] util.Merge(210): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2010-01-14 07:54:13,054 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 07:54:13,055 DEBUG [main] regionserver.HRegion(266): Opening region .META.,,1, encoded=1028785192
[junit] 2010-01-14 07:54:13,061 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/1671670779830191415, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2010-01-14 07:54:13,065 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/416735723561818154, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2010-01-14 07:54:13,071 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/5632262815675003610, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2010-01-14 07:54:13,075 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/6855205676865813589, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2010-01-14 07:54:13,078 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/7051739243700617068, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2010-01-14 07:54:13,080 INFO [main] regionserver.HRegion(344): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2010-01-14 07:54:13,081 INFO [main] regionserver.HRegion(772): Starting compaction on region .META.,,1
[junit] 2010-01-14 07:54:13,090 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 07:54:13,090 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 07:54:13,142 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:48556/user/hudson/.META./1028785192/historian/2118302409911814611; store size is 781
[junit] 2010-01-14 07:54:13,143 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2010-01-14 07:54:13,143 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 07:54:13,197 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:48556/user/hudson/.META./1028785192/info/1667712930511987580; store size is 3.3k
[junit] 2010-01-14 07:54:13,205 INFO [main] regionserver.HRegion(787): compaction completed on region .META.,,1 in 0sec
[junit] 2010-01-14 07:54:13,205 INFO [main] util.MetaUtils(126): OPENING META .META.,,1
[junit] 2010-01-14 07:54:13,207 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263455652442', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 655928143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 07:54:13,208 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263455652442, encoded=655928143
[junit] 2010-01-14 07:54:13,214 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/655928143/contents/2335849190125310988, isReference=false, sequence id=6, length=745, majorCompaction=false
[junit] 2010-01-14 07:54:13,216 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263455652442/655928143 available; sequence id is 7
[junit] 2010-01-14 07:54:13,216 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263455644743', STARTKEY => '', ENDKEY => '', ENCODED => 190789000, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 07:54:13,217 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263455644743, encoded=190789000
[junit] 2010-01-14 07:54:13,223 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/190789000/contents/2991559514260778126, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2010-01-14 07:54:13,227 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263455644743/190789000 available; sequence id is 8
[junit] 2010-01-14 07:54:13,227 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:13,237 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/655928143, seqid=6
[junit] 2010-01-14 07:54:13,432 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:48556/user/hudson/TestMergeTool/655928143/contents/8779300281192677353; store size is 745
[junit] 2010-01-14 07:54:13,442 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,row_0100,1263455652442 in 0sec
[junit] 2010-01-14 07:54:13,442 DEBUG [main] regionserver.HRegion(2119): Files for region: TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:13,443 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/655928143/.regioninfo size=631
[junit] 2010-01-14 07:54:13,443 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:48556/user/hudson/TestMergeTool/655928143/contents
[junit] 2010-01-14 07:54:13,444 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/655928143/contents/8779300281192677353 size=745
[junit] 2010-01-14 07:54:13,444 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,,1263455644743
[junit] 2010-01-14 07:54:13,454 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/190789000, seqid=7
[junit] 2010-01-14 07:54:13,502 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:48556/user/hudson/TestMergeTool/190789000/contents/6858692282144078941; store size is 471
[junit] 2010-01-14 07:54:13,512 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263455644743 in 0sec
[junit] 2010-01-14 07:54:13,512 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,,1263455644743
[junit] 2010-01-14 07:54:13,513 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/190789000/.regioninfo size=583
[junit] 2010-01-14 07:54:13,513 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:48556/user/hudson/TestMergeTool/190789000/contents
[junit] 2010-01-14 07:54:13,514 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/190789000/contents/6858692282144078941 size=471
[junit] 2010-01-14 07:54:13,514 INFO [main] regionserver.HRegion(2153): Creating new region REGION => {NAME => 'TestMergeTool,,1263455653514', STARTKEY => '', ENDKEY => '', ENCODED => 434966993, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 07:54:13,524 INFO [main] regionserver.HRegion(2162): starting merge of regions: TestMergeTool,row_0100,1263455652442 and TestMergeTool,,1263455644743 into new region REGION => {NAME => 'TestMergeTool,,1263455653514', STARTKEY => '', ENDKEY => '', ENCODED => 434966993, 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] 2010-01-14 07:54:13,524 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263455652442: compactions & flushes disabled
[junit] 2010-01-14 07:54:13,524 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:13,525 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:13,525 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 07:54:13,525 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263455652442
[junit] 2010-01-14 07:54:13,525 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263455644743: compactions & flushes disabled
[junit] 2010-01-14 07:54:13,525 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263455644743
[junit] 2010-01-14 07:54:13,526 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263455644743
[junit] 2010-01-14 07:54:13,526 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 07:54:13,526 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263455644743
[junit] 2010-01-14 07:54:13,558 DEBUG [main] regionserver.HRegion(2195): Files for new region
[junit] 2010-01-14 07:54:13,558 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:48556/user/hudson/TestMergeTool/434966993/contents
[junit] 2010-01-14 07:54:13,559 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/434966993/contents/4252032898036037185 size=745
[junit] 2010-01-14 07:54:13,559 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/434966993/contents/7156956717001443778 size=471
[junit] 2010-01-14 07:54:13,560 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263455653514, encoded=434966993
[junit] 2010-01-14 07:54:13,590 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/434966993/contents/4252032898036037185, isReference=false, sequence id=6, length=745, majorCompaction=true
[junit] 2010-01-14 07:54:13,594 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/434966993/contents/7156956717001443778, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2010-01-14 07:54:13,596 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263455653514/434966993 available; sequence id is 8
[junit] 2010-01-14 07:54:13,596 INFO [main] regionserver.HRegion(772): Starting compaction on region TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:13,608 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 07:54:13,608 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/434966993, seqid=7
[junit] 2010-01-14 07:54:13,677 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:48556/user/hudson/TestMergeTool/434966993/contents/6358716200349609257; store size is 883
[junit] 2010-01-14 07:54:13,687 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263455653514 in 0sec
[junit] 2010-01-14 07:54:13,688 DEBUG [main] regionserver.HRegion(2202): Files for new region
[junit] 2010-01-14 07:54:13,689 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/434966993/.regioninfo size=583
[junit] 2010-01-14 07:54:13,689 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:48556/user/hudson/TestMergeTool/434966993/contents
[junit] 2010-01-14 07:54:13,690 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:48556/user/hudson/TestMergeTool/434966993/contents/6358716200349609257 size=883
[junit] 2010-01-14 07:54:13,690 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:48556/user/hudson/TestMergeTool/655928143
[junit] 2010-01-14 07:54:13,699 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:48556/user/hudson/TestMergeTool/190789000
[junit] 2010-01-14 07:54:13,709 INFO [main] regionserver.HRegion(2208): merge completed. New region is TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:13,709 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1263455652442', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 655928143, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 07:54:13,710 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,,1263455644743', STARTKEY => '', ENDKEY => '', ENCODED => 190789000, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 07:54:13,711 INFO [main] util.Merge(254): Adding REGION => {NAME => 'TestMergeTool,,1263455653514', STARTKEY => '', ENDKEY => '', ENCODED => 434966993, 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', 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] 2010-01-14 07:54:13,711 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263455653514: compactions & flushes disabled
[junit] 2010-01-14 07:54:13,711 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:13,711 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:13,712 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 07:54:13,712 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:13,712 DEBUG [main] regionserver.HRegion(455): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2010-01-14 07:54:13,712 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2010-01-14 07:54:13,713 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region -ROOT-,,0
[junit] 2010-01-14 07:54:13,713 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 07:54:13,713 INFO [main] regionserver.HRegion(491): Closed -ROOT-,,0
[junit] 2010-01-14 07:54:13,713 INFO [main] util.MetaUtils(149): CLOSING META .META.,,1
[junit] 2010-01-14 07:54:13,713 DEBUG [main] regionserver.HRegion(455): Closing .META.,,1: compactions & flushes disabled
[junit] 2010-01-14 07:54:13,714 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2010-01-14 07:54:13,714 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region .META.,,1
[junit] 2010-01-14 07:54:13,714 DEBUG [main] regionserver.HRegion(896): Started memstore flush for region .META.,,1. Current region memstore size 968
[junit] 2010-01-14 07:54:13,748 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48556/user/hudson/.META./1028785192/historian/4211956039743258728, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
[junit] 2010-01-14 07:54:13,775 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48556/user/hudson/.META./1028785192/info/7238395906447917557, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
[junit] 2010-01-14 07:54:13,776 DEBUG [main] regionserver.HRegion(972): Finished memstore flush of ~968 for region .META.,,1 in 62ms, sequence id=34, compaction requested=true
[junit] 2010-01-14 07:54:13,776 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2010-01-14 07:54:13,776 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 07:54:13,777 INFO [main] regionserver.HRegion(491): Closed .META.,,1
[junit] 2010-01-14 07:54:13,813 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 07:54:13,813 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 07:54:13,814 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 07:54:13,814 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 07:54:13,826 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263455653003/hlog.dat.1263455653014, entries=6, calcsize=1083, filesize=1007. New hlog /user/hudson/.logs_1263455653003/hlog.dat.1263455653791
[junit] 2010-01-14 07:54:13,826 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 07:54:13,826 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263455653003/hlog.dat.1263455653014 whose highest sequence/edit id is 33
[junit] 2010-01-14 07:54:13,835 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 07:54:13,837 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 07:54:13,837 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:48556/user/hudson/.logs_1263455653003
[junit] 2010-01-14 07:54:14,016 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263455653514', STARTKEY => '', ENDKEY => '', ENCODED => 434966993, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 07:54:14,017 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263455653514, encoded=434966993
[junit] 2010-01-14 07:54:14,023 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/434966993/contents/6358716200349609257, isReference=false, sequence id=7, length=883, majorCompaction=false
[junit] 2010-01-14 07:54:14,025 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263455653514/434966993 available; sequence id is 8
[junit] 2010-01-14 07:54:14,030 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263455653514: compactions & flushes disabled
[junit] 2010-01-14 07:54:14,030 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:14,030 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:14,030 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 07:54:14,031 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263455653514
[junit] 2010-01-14 07:54:14,031 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2+3 and 4
[junit] 2010-01-14 07:54:14,031 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 07:54:14,031 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 07:54:14,031 DEBUG [main] wal.HLog(594): closing hlog writer in /tmp/.logs_1263455649505
[junit] 2010-01-14 07:54:14,055 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2010-01-14 07:54:14,200 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@38fff7] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:53075, storageID=DS-1465543328-67.195.138.9-53075-1263455647565, infoPort=51899, ipcPort=50349):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2010-01-14 07:54:14,304 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@940f82] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:35585, storageID=DS-1554594539-67.195.138.9-35585-1263455646875, infoPort=44526, ipcPort=55377):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-01-14 07:54:14,412 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@1ee148b] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 07:54:14,414 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@1a0225b] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 07:54:14,430 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 9.868 sec
[junit] 2010-01-14 07:54:15,094 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2010-01-14 07:54:15,484 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not have a scheme
[junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:231)
[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:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:79)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[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.087 sec
test-contrib:
test:
compile:
compile-examples:
ivy-download:
[get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.1.0/ivy-2.1.0.jar
[get] To: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivy-2.1.0.jar>
[get] Not modified - so not downloaded
ivy-init-dirs:
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/report>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/maven>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib/common>
ivy-probe-antlib:
ivy-init-antlib:
ivy-init:
:: loading settings :: file = <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivysettings.xml>
ivy-resolve-test:
[ivy:resolve] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:530: 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/ivy-targets.xml>:84: syntax errors in ivy file: java.text.ParseException: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
Total time: 31 minutes 14 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: HBase-Patch #1070
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1070/changes>
Changes:
[apurtell] [EC2] Update Java version used on new images to 1.6.0_18
[apurtell] HBASE-2120 [stargate] Unable to delete column families
------------------------------------------
[...truncated 117302 lines...]
[junit] 2010-01-14 02:50:35,815 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,row_0100,1263437435464 in 0sec
[junit] 2010-01-14 02:50:35,816 DEBUG [main] regionserver.HRegion(2202): Files for new region
[junit] 2010-01-14 02:50:35,825 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749/.regioninfo size=632
[junit] 2010-01-14 02:50:35,825 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749/contents
[junit] 2010-01-14 02:50:35,827 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749/contents/6651600462734947893 size=791
[junit] 2010-01-14 02:50:35,827 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:35244/user/hudson/TestMergeTool/728005628
[junit] 2010-01-14 02:50:35,838 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:35244/user/hudson/TestMergeTool/1115652100
[junit] 2010-01-14 02:50:35,850 INFO [main] regionserver.HRegion(2208): merge completed. New region is TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:35,850 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1263437434206', STARTKEY => 'row_0100', ENDKEY => 'row_0400', ENCODED => 728005628, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 02:50:35,851 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0500,1263437393437', STARTKEY => 'row_0500', ENDKEY => 'row_0600', ENCODED => 1115652100, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 02:50:35,852 INFO [main] util.Merge(254): Adding REGION => {NAME => 'TestMergeTool,row_0100,1263437435464', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2015858749, 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', 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] 2010-01-14 02:50:35,852 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263437435464: compactions & flushes disabled
[junit] 2010-01-14 02:50:35,853 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:35,853 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:35,853 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 02:50:35,853 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:35,853 DEBUG [main] regionserver.HRegion(455): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2010-01-14 02:50:35,854 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2010-01-14 02:50:35,854 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region -ROOT-,,0
[junit] 2010-01-14 02:50:35,854 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 02:50:35,854 INFO [main] regionserver.HRegion(491): Closed -ROOT-,,0
[junit] 2010-01-14 02:50:35,854 INFO [main] util.MetaUtils(149): CLOSING META .META.,,1
[junit] 2010-01-14 02:50:35,855 DEBUG [main] regionserver.HRegion(455): Closing .META.,,1: compactions & flushes disabled
[junit] 2010-01-14 02:50:35,855 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2010-01-14 02:50:35,855 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region .META.,,1
[junit] 2010-01-14 02:50:35,855 DEBUG [main] regionserver.HRegion(896): Started memstore flush for region .META.,,1. Current region memstore size 1016
[junit] 2010-01-14 02:50:35,891 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:35244/user/hudson/.META./1028785192/historian/1237699695223789377, entries=2, sequenceid=27, memsize=288, filesize=521 to .META.,,1
[junit] 2010-01-14 02:50:35,926 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:35244/user/hudson/.META./1028785192/info/1931039709480691100, entries=3, sequenceid=27, memsize=728, filesize=874 to .META.,,1
[junit] 2010-01-14 02:50:35,927 DEBUG [main] regionserver.HRegion(972): Finished memstore flush of ~1016 for region .META.,,1 in 72ms, sequence id=27, compaction requested=true
[junit] 2010-01-14 02:50:35,927 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2010-01-14 02:50:35,927 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 02:50:35,928 INFO [main] regionserver.HRegion(491): Closed .META.,,1
[junit] 2010-01-14 02:50:35,958 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 02:50:35,958 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 02:50:35,958 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 02:50:35,958 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 02:50:35,971 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263437434750/hlog.dat.1263437434790, entries=6, calcsize=1131, filesize=1055. New hlog /user/hudson/.logs_1263437434750/hlog.dat.1263437435947
[junit] 2010-01-14 02:50:35,971 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 02:50:35,971 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263437434750/hlog.dat.1263437434790 whose highest sequence/edit id is 26
[junit] 2010-01-14 02:50:37,983 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:35244/user/hudson/.logs_1263437434750
[junit] 2010-01-14 02:50:38,007 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263437435464', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2015858749, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 02:50:38,007 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263437435464, encoded=2015858749
[junit] 2010-01-14 02:50:38,015 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/2015858749/contents/6651600462734947893, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2010-01-14 02:50:38,017 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263437435464/2015858749 available; sequence id is 7
[junit] 2010-01-14 02:50:38,021 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263437435464: compactions & flushes disabled
[junit] 2010-01-14 02:50:38,022 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,022 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,022 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 02:50:38,022 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,022 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2 and 3
[junit] 2010-01-14 02:50:38,023 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
[junit] fs2=hdfs://localhost:35244
[junit] 2010-01-14 02:50:38,023 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 02:50:38,023 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-14 02:50:38,024 INFO [main] util.Merge(81): Verifying that file system is available...
[junit] 2010-01-14 02:50:38,024 INFO [main] util.Merge(90): Verifying that HBase is not running...
[junit] 2010-01-14 02:50:38,035 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 02:50:38,035 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 02:50:38,036 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 02:50:38,036 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 02:50:38,037 INFO [main] util.Merge(196): Merging regions TestMergeTool,row_0100,1263437435464 and TestMergeTool,,1263437393437 in table TestMergeTool
[junit] 2010-01-14 02:50:38,051 INFO [main] wal.HLog(274): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2010-01-14 02:50:38,061 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 02:50:38,062 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 02:50:38,062 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 02:50:38,062 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 02:50:38,075 INFO [main] wal.HLog(352): New hlog /user/hudson/.logs_1263437438037/hlog.dat.1263437438051
[junit] 2010-01-14 02:50:38,075 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 02:50:38,076 DEBUG [main] regionserver.HRegion(266): Opening region -ROOT-,,0, encoded=70236052
[junit] 2010-01-14 02:50:38,083 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/741844122169293098, isReference=false, sequence id=2, length=809, majorCompaction=false
[junit] 2010-01-14 02:50:38,087 INFO [main] regionserver.HRegion(344): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2010-01-14 02:50:38,087 INFO [main] regionserver.HRegion(772): Starting compaction on region -ROOT-,,0
[junit] 2010-01-14 02:50:38,089 INFO [main] regionserver.HRegion(787): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2010-01-14 02:50:38,090 INFO [main] util.Merge(210): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2010-01-14 02:50:38,090 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 02:50:38,091 DEBUG [main] regionserver.HRegion(266): Opening region .META.,,1, encoded=1028785192
[junit] 2010-01-14 02:50:38,098 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/1237699695223789377, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2010-01-14 02:50:38,102 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/5211367958670147912, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2010-01-14 02:50:38,108 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/1931039709480691100, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2010-01-14 02:50:38,112 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/6988356274419214408, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2010-01-14 02:50:38,116 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/967438845533077945, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2010-01-14 02:50:38,119 INFO [main] regionserver.HRegion(344): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2010-01-14 02:50:38,119 INFO [main] regionserver.HRegion(772): Starting compaction on region .META.,,1
[junit] 2010-01-14 02:50:38,130 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 02:50:38,131 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 02:50:38,206 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:35244/user/hudson/.META./1028785192/historian/5561001662563232133; store size is 781
[junit] 2010-01-14 02:50:38,208 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2010-01-14 02:50:38,208 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-14 02:50:38,278 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:35244/user/hudson/.META./1028785192/info/7298357732937915416; store size is 3.3k
[junit] 2010-01-14 02:50:38,290 INFO [main] regionserver.HRegion(787): compaction completed on region .META.,,1 in 0sec
[junit] 2010-01-14 02:50:38,290 INFO [main] util.MetaUtils(126): OPENING META .META.,,1
[junit] 2010-01-14 02:50:38,292 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263437435464', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2015858749, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 02:50:38,293 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263437435464, encoded=2015858749
[junit] 2010-01-14 02:50:38,300 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/2015858749/contents/6651600462734947893, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2010-01-14 02:50:38,302 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263437435464/2015858749 available; sequence id is 7
[junit] 2010-01-14 02:50:38,302 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263437393437', STARTKEY => '', ENDKEY => '', ENCODED => 644937953, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 02:50:38,302 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263437393437, encoded=644937953
[junit] 2010-01-14 02:50:38,309 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/644937953/contents/6071462730479887332, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2010-01-14 02:50:38,314 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263437393437/644937953 available; sequence id is 8
[junit] 2010-01-14 02:50:38,314 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,326 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/2015858749, seqid=6
[junit] 2010-01-14 02:50:38,533 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749/contents/3397618302792641015; store size is 791
[junit] 2010-01-14 02:50:38,549 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,row_0100,1263437435464 in 0sec
[junit] 2010-01-14 02:50:38,549 DEBUG [main] regionserver.HRegion(2119): Files for region: TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,550 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749/.regioninfo size=632
[junit] 2010-01-14 02:50:38,550 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749/contents
[junit] 2010-01-14 02:50:38,551 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749/contents/3397618302792641015 size=791
[junit] 2010-01-14 02:50:38,551 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,,1263437393437
[junit] 2010-01-14 02:50:38,569 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/644937953, seqid=7
[junit] 2010-01-14 02:50:38,641 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:35244/user/hudson/TestMergeTool/644937953/contents/3417558590354638725; store size is 471
[junit] 2010-01-14 02:50:38,657 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263437393437 in 0sec
[junit] 2010-01-14 02:50:38,657 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,,1263437393437
[junit] 2010-01-14 02:50:38,658 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/644937953/.regioninfo size=583
[junit] 2010-01-14 02:50:38,658 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:35244/user/hudson/TestMergeTool/644937953/contents
[junit] 2010-01-14 02:50:38,659 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/644937953/contents/3417558590354638725 size=471
[junit] 2010-01-14 02:50:38,659 INFO [main] regionserver.HRegion(2153): Creating new region REGION => {NAME => 'TestMergeTool,,1263437438659', STARTKEY => '', ENDKEY => '', ENCODED => 171210565, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 02:50:38,673 INFO [main] regionserver.HRegion(2162): starting merge of regions: TestMergeTool,row_0100,1263437435464 and TestMergeTool,,1263437393437 into new region REGION => {NAME => 'TestMergeTool,,1263437438659', STARTKEY => '', ENDKEY => '', ENCODED => 171210565, 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] 2010-01-14 02:50:38,673 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263437435464: compactions & flushes disabled
[junit] 2010-01-14 02:50:38,673 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,673 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,674 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 02:50:38,674 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263437435464
[junit] 2010-01-14 02:50:38,674 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263437393437: compactions & flushes disabled
[junit] 2010-01-14 02:50:38,674 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263437393437
[junit] 2010-01-14 02:50:38,674 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263437393437
[junit] 2010-01-14 02:50:38,675 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 02:50:38,675 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263437393437
[junit] 2010-01-14 02:50:38,720 DEBUG [main] regionserver.HRegion(2195): Files for new region
[junit] 2010-01-14 02:50:38,721 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:35244/user/hudson/TestMergeTool/171210565/contents
[junit] 2010-01-14 02:50:38,722 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/171210565/contents/3886874889267241620 size=471
[junit] 2010-01-14 02:50:38,722 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/171210565/contents/6081784999803895091 size=791
[junit] 2010-01-14 02:50:38,723 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263437438659, encoded=171210565
[junit] 2010-01-14 02:50:38,766 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/171210565/contents/3886874889267241620, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2010-01-14 02:50:38,770 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/171210565/contents/6081784999803895091, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2010-01-14 02:50:38,772 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263437438659/171210565 available; sequence id is 8
[junit] 2010-01-14 02:50:38,772 INFO [main] regionserver.HRegion(772): Starting compaction on region TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:38,784 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2010-01-14 02:50:38,785 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/171210565, seqid=7
[junit] 2010-01-14 02:50:38,872 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:35244/user/hudson/TestMergeTool/171210565/contents/1033077002489412569; store size is 929
[junit] 2010-01-14 02:50:38,888 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263437438659 in 0sec
[junit] 2010-01-14 02:50:38,890 DEBUG [main] regionserver.HRegion(2202): Files for new region
[junit] 2010-01-14 02:50:38,891 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/171210565/.regioninfo size=583
[junit] 2010-01-14 02:50:38,891 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:35244/user/hudson/TestMergeTool/171210565/contents
[junit] 2010-01-14 02:50:38,892 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:35244/user/hudson/TestMergeTool/171210565/contents/1033077002489412569 size=929
[junit] 2010-01-14 02:50:38,892 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:35244/user/hudson/TestMergeTool/2015858749
[junit] 2010-01-14 02:50:38,908 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:35244/user/hudson/TestMergeTool/644937953
[junit] 2010-01-14 02:50:38,924 INFO [main] regionserver.HRegion(2208): merge completed. New region is TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:38,924 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1263437435464', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 2015858749, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 02:50:38,925 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,,1263437393437', STARTKEY => '', ENDKEY => '', ENCODED => 644937953, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-14 02:50:38,926 INFO [main] util.Merge(254): Adding REGION => {NAME => 'TestMergeTool,,1263437438659', STARTKEY => '', ENDKEY => '', ENCODED => 171210565, 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', 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] 2010-01-14 02:50:38,926 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263437438659: compactions & flushes disabled
[junit] 2010-01-14 02:50:38,926 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:38,927 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:38,927 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 02:50:38,927 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:38,927 DEBUG [main] regionserver.HRegion(455): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2010-01-14 02:50:38,927 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2010-01-14 02:50:38,928 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region -ROOT-,,0
[junit] 2010-01-14 02:50:38,928 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 02:50:38,928 INFO [main] regionserver.HRegion(491): Closed -ROOT-,,0
[junit] 2010-01-14 02:50:38,928 INFO [main] util.MetaUtils(149): CLOSING META .META.,,1
[junit] 2010-01-14 02:50:38,928 DEBUG [main] regionserver.HRegion(455): Closing .META.,,1: compactions & flushes disabled
[junit] 2010-01-14 02:50:38,929 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2010-01-14 02:50:38,929 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region .META.,,1
[junit] 2010-01-14 02:50:38,929 DEBUG [main] regionserver.HRegion(896): Started memstore flush for region .META.,,1. Current region memstore size 968
[junit] 2010-01-14 02:50:38,971 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:35244/user/hudson/.META./1028785192/historian/318969712457036239, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
[junit] 2010-01-14 02:50:39,027 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:35244/user/hudson/.META./1028785192/info/2565083339687581736, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
[junit] 2010-01-14 02:50:39,027 DEBUG [main] regionserver.HRegion(972): Finished memstore flush of ~968 for region .META.,,1 in 98ms, sequence id=34, compaction requested=true
[junit] 2010-01-14 02:50:39,027 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2010-01-14 02:50:39,028 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-14 02:50:39,028 INFO [main] regionserver.HRegion(491): Closed .META.,,1
[junit] 2010-01-14 02:50:39,054 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-14 02:50:39,055 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-14 02:50:39,055 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-14 02:50:39,055 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-14 02:50:39,069 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263437438037/hlog.dat.1263437438051, entries=6, calcsize=1083, filesize=1007. New hlog /user/hudson/.logs_1263437438037/hlog.dat.1263437439043
[junit] 2010-01-14 02:50:39,069 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-14 02:50:39,069 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263437438037/hlog.dat.1263437438051 whose highest sequence/edit id is 33
[junit] 2010-01-14 02:50:41,193 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:35244/user/hudson/.logs_1263437438037
[junit] 2010-01-14 02:50:41,274 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263437438659', STARTKEY => '', ENDKEY => '', ENCODED => 171210565, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-14 02:50:41,275 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263437438659, encoded=171210565
[junit] 2010-01-14 02:50:41,282 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/171210565/contents/1033077002489412569, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2010-01-14 02:50:41,284 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263437438659/171210565 available; sequence id is 8
[junit] 2010-01-14 02:50:41,289 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263437438659: compactions & flushes disabled
[junit] 2010-01-14 02:50:41,289 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:41,289 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:41,289 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-14 02:50:41,290 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263437438659
[junit] 2010-01-14 02:50:41,290 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2+3 and 4
[junit] 2010-01-14 02:50:41,290 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-14 02:50:41,291 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-14 02:50:41,292 DEBUG [main] wal.HLog(594): closing hlog writer in /tmp/.logs_1263437432467
[junit] 2010-01-14 02:50:41,322 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2010-01-14 02:50:41,433 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@178dc08] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:49238, storageID=DS-1317241266-67.195.138.9-49238-1263437430316, infoPort=37700, ipcPort=37891):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2010-01-14 02:50:41,545 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@d0357a] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:44635, storageID=DS-1105102190-67.195.138.9-44635-1263437429686, infoPort=35838, ipcPort=37180):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-01-14 02:50:41,657 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@ea48be] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 02:50:41,657 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@5976c2] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-14 02:50:41,671 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 48.415 sec
[junit] 2010-01-14 02:50:42,318 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2010-01-14 02:50:42,744 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not have a scheme
[junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:231)
[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:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:79)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[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.089 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:525: Tests failed!
Total time: 27 minutes 39 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: HBase-Patch #1069
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1069/changes>
Changes:
[apurtell] fix another problem with src/contrib/ec2/build.xml on trunk
[apurtell] set hbase.root in src/contrib/ec2/build.xml
------------------------------------------
[...truncated 117456 lines...]
[junit] 2010-01-13 20:51:01,853 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263415860603/hlog.dat.1263415860613, entries=6, calcsize=1131, filesize=1075. New hlog /user/hudson/.logs_1263415860603/hlog.dat.1263415861828
[junit] 2010-01-13 20:51:01,854 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-13 20:51:01,854 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263415860603/hlog.dat.1263415860613 whose highest sequence/edit id is 26
[junit] 2010-01-13 20:51:01,876 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-13 20:51:01,877 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-13 20:51:01,877 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:38390/user/hudson/.logs_1263415860603
[junit] 2010-01-13 20:51:01,920 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263415861270', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1301739641, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-13 20:51:01,921 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263415861270, encoded=1301739641
[junit] 2010-01-13 20:51:01,946 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1301739641/contents/8902766048564869627, isReference=false, sequence id=6, length=745, majorCompaction=false
[junit] 2010-01-13 20:51:01,948 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263415861270/1301739641 available; sequence id is 7
[junit] 2010-01-13 20:51:01,952 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263415861270: compactions & flushes disabled
[junit] 2010-01-13 20:51:01,952 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:01,952 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:01,953 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-13 20:51:01,953 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:01,953 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2 and 3
[junit] 2010-01-13 20:51:01,953 INFO [main] util.TestMergeTool(174): merging regions 0+1+2+3 and 4
[junit] fs2=hdfs://localhost:38390
[junit] 2010-01-13 20:51:01,954 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-13 20:51:01,954 WARN [main] conf.Configuration(617): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2010-01-13 20:51:01,954 INFO [main] util.Merge(81): Verifying that file system is available...
[junit] 2010-01-13 20:51:01,955 INFO [main] util.Merge(90): Verifying that HBase is not running...
[junit] 2010-01-13 20:51:01,966 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-13 20:51:01,966 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-13 20:51:01,966 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-13 20:51:01,966 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-13 20:51:01,967 INFO [main] util.Merge(196): Merging regions TestMergeTool,row_0100,1263415861270 and TestMergeTool,,1263415853724 in table TestMergeTool
[junit] 2010-01-13 20:51:01,980 INFO [main] wal.HLog(274): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2010-01-13 20:51:01,990 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-13 20:51:01,991 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-13 20:51:01,991 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-13 20:51:01,991 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-13 20:51:02,004 INFO [main] wal.HLog(352): New hlog /user/hudson/.logs_1263415861967/hlog.dat.1263415861980
[junit] 2010-01-13 20:51:02,004 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '-ROOT-,,0', STARTKEY => '', ENDKEY => '', ENCODED => 70236052, TABLE => {{NAME => '-ROOT-', IS_ROOT => 'true', IS_META => 'true', FAMILIES => [{NAME => 'info', COMPRESSION => 'NONE', VERSIONS => '10', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-13 20:51:02,005 DEBUG [main] regionserver.HRegion(266): Opening region -ROOT-,,0, encoded=70236052
[junit] 2010-01-13 20:51:02,012 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/3480773540893789328, isReference=false, sequence id=2, length=809, majorCompaction=false
[junit] 2010-01-13 20:51:02,014 INFO [main] regionserver.HRegion(344): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2010-01-13 20:51:02,014 INFO [main] regionserver.HRegion(772): Starting compaction on region -ROOT-,,0
[junit] 2010-01-13 20:51:02,016 INFO [main] regionserver.HRegion(787): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2010-01-13 20:51:02,017 INFO [main] util.Merge(210): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2010-01-13 20:51:02,017 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED => 1028785192, TABLE => {{NAME => '.META.', IS_META => 'true', FAMILIES => [{NAME => 'historian', VERSIONS => '2147483647', COMPRESSION => 'NONE', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'false'}, {NAME => 'info', VERSIONS => '10', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-13 20:51:02,017 DEBUG [main] regionserver.HRegion(266): Opening region .META.,,1, encoded=1028785192
[junit] 2010-01-13 20:51:02,024 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/4161991477834753085, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2010-01-13 20:51:02,028 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/655768641896863789, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2010-01-13 20:51:02,035 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2142429762808697017, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2010-01-13 20:51:02,039 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/7596519175656333912, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2010-01-13 20:51:02,042 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/7708248665986660984, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2010-01-13 20:51:02,045 INFO [main] regionserver.HRegion(344): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2010-01-13 20:51:02,045 INFO [main] regionserver.HRegion(772): Starting compaction on region .META.,,1
[junit] 2010-01-13 20:51:02,059 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2010-01-13 20:51:02,060 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-13 20:51:02,175 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:38390/user/hudson/.META./1028785192/historian/1936831980429855611; store size is 781
[junit] 2010-01-13 20:51:02,177 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2010-01-13 20:51:02,177 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2010-01-13 20:51:02,291 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:38390/user/hudson/.META./1028785192/info/3359628535225151274; store size is 3.3k
[junit] 2010-01-13 20:51:02,310 INFO [main] regionserver.HRegion(787): compaction completed on region .META.,,1 in 0sec
[junit] 2010-01-13 20:51:02,311 INFO [main] util.MetaUtils(126): OPENING META .META.,,1
[junit] 2010-01-13 20:51:02,313 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1263415861270', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1301739641, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-13 20:51:02,313 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,row_0100,1263415861270, encoded=1301739641
[junit] 2010-01-13 20:51:02,320 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1301739641/contents/8902766048564869627, isReference=false, sequence id=6, length=745, majorCompaction=false
[junit] 2010-01-13 20:51:02,322 INFO [main] regionserver.HRegion(344): region TestMergeTool,row_0100,1263415861270/1301739641 available; sequence id is 7
[junit] 2010-01-13 20:51:02,322 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263415853724', STARTKEY => '', ENDKEY => '', ENCODED => 182785933, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-13 20:51:02,323 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263415853724, encoded=182785933
[junit] 2010-01-13 20:51:02,329 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/182785933/contents/5091385191197752734, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2010-01-13 20:51:02,332 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263415853724/182785933 available; sequence id is 8
[junit] 2010-01-13 20:51:02,332 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:02,460 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1301739641, seqid=6
[junit] 2010-01-13 20:51:02,537 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:38390/user/hudson/TestMergeTool/1301739641/contents/8405526869937898912; store size is 745
[junit] 2010-01-13 20:51:02,553 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,row_0100,1263415861270 in 0sec
[junit] 2010-01-13 20:51:02,553 DEBUG [main] regionserver.HRegion(2119): Files for region: TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:02,554 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/1301739641/.regioninfo size=632
[junit] 2010-01-13 20:51:02,554 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:38390/user/hudson/TestMergeTool/1301739641/contents
[junit] 2010-01-13 20:51:02,555 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/1301739641/contents/8405526869937898912 size=745
[junit] 2010-01-13 20:51:02,555 INFO [main] regionserver.HRegion(772): Starting major compaction on region TestMergeTool,,1263415853724
[junit] 2010-01-13 20:51:02,572 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/182785933, seqid=7
[junit] 2010-01-13 20:51:02,660 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:38390/user/hudson/TestMergeTool/182785933/contents/1829407753168227927; store size is 471
[junit] 2010-01-13 20:51:02,676 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263415853724 in 0sec
[junit] 2010-01-13 20:51:02,676 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,,1263415853724
[junit] 2010-01-13 20:51:02,677 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/182785933/.regioninfo size=583
[junit] 2010-01-13 20:51:02,677 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:38390/user/hudson/TestMergeTool/182785933/contents
[junit] 2010-01-13 20:51:02,678 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/182785933/contents/1829407753168227927 size=471
[junit] 2010-01-13 20:51:02,678 INFO [main] regionserver.HRegion(2153): Creating new region REGION => {NAME => 'TestMergeTool,,1263415862678', STARTKEY => '', ENDKEY => '', ENCODED => 1576671027, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-13 20:51:02,696 INFO [main] regionserver.HRegion(2162): starting merge of regions: TestMergeTool,row_0100,1263415861270 and TestMergeTool,,1263415853724 into new region REGION => {NAME => 'TestMergeTool,,1263415862678', STARTKEY => '', ENDKEY => '', ENCODED => 1576671027, 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] 2010-01-13 20:51:02,696 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,row_0100,1263415861270: compactions & flushes disabled
[junit] 2010-01-13 20:51:02,696 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:02,697 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:02,697 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-13 20:51:02,697 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,row_0100,1263415861270
[junit] 2010-01-13 20:51:02,697 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263415853724: compactions & flushes disabled
[junit] 2010-01-13 20:51:02,697 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263415853724
[junit] 2010-01-13 20:51:02,698 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263415853724
[junit] 2010-01-13 20:51:02,698 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-13 20:51:02,698 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263415853724
[junit] 2010-01-13 20:51:02,752 DEBUG [main] regionserver.HRegion(2195): Files for new region
[junit] 2010-01-13 20:51:02,752 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:38390/user/hudson/TestMergeTool/1576671027/contents
[junit] 2010-01-13 20:51:02,753 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/1576671027/contents/7221366791974680388 size=471
[junit] 2010-01-13 20:51:02,754 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/1576671027/contents/8401309115544660415 size=745
[junit] 2010-01-13 20:51:02,754 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263415862678, encoded=1576671027
[junit] 2010-01-13 20:51:02,797 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1576671027/contents/7221366791974680388, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2010-01-13 20:51:02,800 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1576671027/contents/8401309115544660415, isReference=false, sequence id=6, length=745, majorCompaction=true
[junit] 2010-01-13 20:51:02,803 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263415862678/1576671027 available; sequence id is 8
[junit] 2010-01-13 20:51:02,803 INFO [main] regionserver.HRegion(772): Starting compaction on region TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:02,820 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2010-01-13 20:51:02,820 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1576671027, seqid=7
[junit] 2010-01-13 20:51:02,919 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:38390/user/hudson/TestMergeTool/1576671027/contents/2941107990321876520; store size is 883
[junit] 2010-01-13 20:51:02,935 INFO [main] regionserver.HRegion(787): compaction completed on region TestMergeTool,,1263415862678 in 0sec
[junit] 2010-01-13 20:51:02,937 DEBUG [main] regionserver.HRegion(2202): Files for new region
[junit] 2010-01-13 20:51:02,938 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/1576671027/.regioninfo size=584
[junit] 2010-01-13 20:51:02,938 DEBUG [main] regionserver.HRegion(2263): d hdfs://localhost:38390/user/hudson/TestMergeTool/1576671027/contents
[junit] 2010-01-13 20:51:02,939 DEBUG [main] regionserver.HRegion(2266): f hdfs://localhost:38390/user/hudson/TestMergeTool/1576671027/contents/2941107990321876520 size=883
[junit] 2010-01-13 20:51:02,939 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:38390/user/hudson/TestMergeTool/1301739641
[junit] 2010-01-13 20:51:02,955 DEBUG [main] regionserver.HRegion(1998): DELETING region hdfs://localhost:38390/user/hudson/TestMergeTool/182785933
[junit] 2010-01-13 20:51:02,971 INFO [main] regionserver.HRegion(2208): merge completed. New region is TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:02,971 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1263415861270', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1301739641, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-13 20:51:02,972 DEBUG [main] util.Merge(318): Removing region: REGION => {NAME => 'TestMergeTool,,1263415853724', STARTKEY => '', ENDKEY => '', ENCODED => 182785933, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2010-01-13 20:51:02,973 INFO [main] util.Merge(254): Adding REGION => {NAME => 'TestMergeTool,,1263415862678', STARTKEY => '', ENDKEY => '', ENCODED => 1576671027, 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', 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] 2010-01-13 20:51:02,973 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263415862678: compactions & flushes disabled
[junit] 2010-01-13 20:51:02,973 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:02,974 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:02,974 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-13 20:51:02,974 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:02,974 DEBUG [main] regionserver.HRegion(455): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2010-01-13 20:51:02,974 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2010-01-13 20:51:02,975 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region -ROOT-,,0
[junit] 2010-01-13 20:51:02,975 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-13 20:51:02,975 INFO [main] regionserver.HRegion(491): Closed -ROOT-,,0
[junit] 2010-01-13 20:51:02,975 INFO [main] util.MetaUtils(149): CLOSING META .META.,,1
[junit] 2010-01-13 20:51:02,975 DEBUG [main] regionserver.HRegion(455): Closing .META.,,1: compactions & flushes disabled
[junit] 2010-01-13 20:51:02,976 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2010-01-13 20:51:02,976 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region .META.,,1
[junit] 2010-01-13 20:51:02,976 DEBUG [main] regionserver.HRegion(896): Started memstore flush for region .META.,,1. Current region memstore size 968
[junit] 2010-01-13 20:51:03,019 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:38390/user/hudson/.META./1028785192/historian/2694827467924335183, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
[junit] 2010-01-13 20:51:03,066 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:38390/user/hudson/.META./1028785192/info/798050000829387380, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
[junit] 2010-01-13 20:51:03,066 DEBUG [main] regionserver.HRegion(972): Finished memstore flush of ~968 for region .META.,,1 in 90ms, sequence id=34, compaction requested=true
[junit] 2010-01-13 20:51:03,066 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2010-01-13 20:51:03,067 DEBUG [main] regionserver.Store(482): closed info
[junit] 2010-01-13 20:51:03,067 INFO [main] regionserver.HRegion(491): Closed .META.,,1
[junit] 2010-01-13 20:51:03,196 WARN [main] conf.Configuration(617): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2010-01-13 20:51:03,197 WARN [main] conf.Configuration(617): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2010-01-13 20:51:03,197 WARN [main] conf.Configuration(617): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2010-01-13 20:51:03,197 WARN [main] conf.Configuration(617): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2010-01-13 20:51:03,224 INFO [main] wal.HLog(352): Roll /user/hudson/.logs_1263415861967/hlog.dat.1263415861980, entries=6, calcsize=1083, filesize=1027. New hlog /user/hudson/.logs_1263415861967/hlog.dat.1263415863186
[junit] 2010-01-13 20:51:03,224 DEBUG [main] wal.HLog(361): Last sequence written is empty. Deleting all old hlogs
[junit] 2010-01-13 20:51:03,224 INFO [main] wal.HLog(549): removing old hlog file /user/hudson/.logs_1263415861967/hlog.dat.1263415861980 whose highest sequence/edit id is 33
[junit] 2010-01-13 20:51:03,242 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-13 20:51:03,243 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-13 20:51:03,243 DEBUG [main] wal.HLog(594): closing hlog writer in hdfs://localhost:38390/user/hudson/.logs_1263415861967
[junit] 2010-01-13 20:51:03,282 DEBUG [main] regionserver.HRegion(1886): Opening region: REGION => {NAME => 'TestMergeTool,,1263415862678', STARTKEY => '', ENDKEY => '', ENCODED => 1576671027, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2010-01-13 20:51:03,283 DEBUG [main] regionserver.HRegion(266): Opening region TestMergeTool,,1263415862678, encoded=1576671027
[junit] 2010-01-13 20:51:03,289 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1576671027/contents/2941107990321876520, isReference=false, sequence id=7, length=883, majorCompaction=false
[junit] 2010-01-13 20:51:03,291 INFO [main] regionserver.HRegion(344): region TestMergeTool,,1263415862678/1576671027 available; sequence id is 8
[junit] 2010-01-13 20:51:03,296 DEBUG [main] regionserver.HRegion(455): Closing TestMergeTool,,1263415862678: compactions & flushes disabled
[junit] 2010-01-13 20:51:03,296 DEBUG [main] regionserver.HRegion(472): Updates disabled for region, no outstanding scanners on TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:03,296 DEBUG [main] regionserver.HRegion(479): No more row locks outstanding on region TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:03,296 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2010-01-13 20:51:03,297 INFO [main] regionserver.HRegion(491): Closed TestMergeTool,,1263415862678
[junit] 2010-01-13 20:51:03,297 INFO [main] util.TestMergeTool(188): Verified merging regions 0+1+2+3 and 4
[junit] 2010-01-13 20:51:03,297 DEBUG [main.logSyncer] wal.HLog$LogSyncer(766): main.logSyncerinterrupted while waiting for sync requests
[junit] 2010-01-13 20:51:03,297 INFO [main.logSyncer] wal.HLog$LogSyncer(770): main.logSyncer exiting
[junit] 2010-01-13 20:51:03,297 DEBUG [main] wal.HLog(594): closing hlog writer in /tmp/.logs_1263415858601
[junit] 2010-01-13 20:51:03,322 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2010-01-13 20:51:03,356 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@f19d6e] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:44199, storageID=DS-34402195-67.195.138.9-44199-1263415856533, infoPort=56703, ipcPort=59626):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] Shutting down DataNode 0
[junit] 2010-01-13 20:51:03,360 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1250ff2] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:46979, storageID=DS-1267965946-67.195.138.9-46979-1263415855787, infoPort=40548, ipcPort=44527):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:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-01-13 20:51:03,364 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@14dd758] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-13 20:51:03,364 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@12aea3e] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2010-01-13 20:51:03,376 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 9.833 sec
[junit] 2010-01-13 20:51:04,770 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2010-01-13 20:51:05,143 INFO [main] util.TestRootPath(60): Got expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not have a scheme
[junit] at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:231)
[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:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:79)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[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.085 sec
test-contrib:
test:
compile:
compile-examples:
ivy-download:
[get] Getting: http://repo2.maven.org/maven2/org/apache/ivy/ivy/2.1.0/ivy-2.1.0.jar
[get] To: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivy-2.1.0.jar>
[get] Not modified - so not downloaded
ivy-init-dirs:
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/report>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/maven>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/ec2/ivy/lib/common>
ivy-probe-antlib:
ivy-init-antlib:
ivy-init:
:: loading settings :: file = <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/ivy/ivysettings.xml>
ivy-resolve-test:
[ivy:resolve] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:530: 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/ivy-targets.xml>:84: syntax errors in ivy file: java.text.ParseException: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml> (No such file or directory) in <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/ec2/ivy.xml>
Total time: 27 minutes 52 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure