You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@hbase.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2009/12/19 01:58:42 UTC
Build failed in Hudson: HBase-Patch #1040
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1040/changes>
Changes:
[apurtell] HBASE-2032 Support for installation of user packages
------------------------------------------
[...truncated 1022530 lines...]
[echo] contrib: transactional
jar:
[echo] contrib: transactional
[jar] Building jar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/hbase-0.21.0-dev-transactional.jar>
compile-examples:
jar-examples:
package:
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/transactional>
[copy] Copying 2 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/transactional>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[copy] Copying 12 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[copy] Copied 10 empty directories to 1 empty directory under <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/lib>
[copy] Copying 28 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/lib>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/conf>
[copy] Copying 6 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/conf>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/bin>
[copy] Copying 15 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/bin>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
[copy] Copying 822 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
[copy] Copying 5 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copying 545 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copied 121 empty directories to 4 empty directories under <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
init:
jspc:
compile-core:
[javac] Compiling 4 source files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/classes>
[clover] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover] Clover: Open Source License registered to Apache.
[clover] Updating existing database at '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'.>
[clover] Processing files at 1.6 source level.
[clover] Clover all over. Instrumented 3 files (3 packages).
[clover] Elapsed time = 0.906 secs. (3.311 files/sec, 428.256 srclines/sec)
compile-contrib:
compile:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
compile:
jar:
[jar] Building jar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev.jar>
javadoc:
[javadoc] Generating Javadoc
[javadoc] Javadoc execution
[javadoc] Loading source files for package org.apache.hadoop.hbase...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.filter...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io.hfile...
[javadoc] Loading source files for package org.apache.hadoop.hbase.ipc...
[javadoc] Loading source files for package org.apache.hadoop.hbase.mapreduce...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics.file...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.wal...
[javadoc] Loading source files for package org.apache.hadoop.hbase.thrift...
[javadoc] Loading source files for package org.apache.hadoop.hbase.util...
[javadoc] Loading source files for package org.apache.hadoop.hbase.zookeeper...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.model...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.protobuf.generated...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.consumer...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.producer...
[javadoc] Constructing Javadoc information...
[javadoc] Standard Doclet version 1.6.0_11
[javadoc] Building tree for all the packages and classes...
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/MultiTableOutputFormat.java>:62: warning - Tag @link: reference not found: IndexBuilder
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/InternalScanner.java>:59: warning - @param argument "results" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] Building index for all the packages and classes...
[javadoc] Building index for all classes...
[javadoc] Generating <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/docs/api/stylesheet.css...>
[javadoc] 16 warnings
package:
[echo] Be sure to run 'docs' target before this one else package will be missing site documentation
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
package:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
jar:
[echo] contrib: stargate
war:
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/lib>
[war] Building war: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/hbase-0.21.0-dev-stargate.war>
package:
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate>
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate/lib>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
jar:
[echo] contrib: transactional
compile-examples:
jar-examples:
package:
[copy] Copying 821 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
tar:
[tar] Building tar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1040/artifact/trunk/build/hbase-0.21.0-dev.tar.gz>
BUILD SUCCESSFUL
Total time: 41 minutes 55 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
ERROR: Failed to archive test reports
hudson.util.IOException2: remote file operation failed
at hudson.FilePath.act(FilePath.java:672)
at hudson.FilePath.act(FilePath.java:660)
at hudson.tasks.junit.JUnitResultArchiver.perform(JUnitResultArchiver.java:117)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:19)
at hudson.model.AbstractBuild$AbstractRunner.perform(AbstractBuild.java:480)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:466)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:454)
at hudson.model.Build$RunnerImpl.post2(Build.java:181)
at hudson.model.AbstractBuild$AbstractRunner.post(AbstractBuild.java:438)
at hudson.model.Run.run(Run.java:1143)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:123)
Caused by: java.io.IOException: Remote call failed
at hudson.remoting.Channel.call(Channel.java:558)
at hudson.FilePath.act(FilePath.java:667)
... 12 more
Caused by: java.lang.OutOfMemoryError: Java heap space
Publishing Clover coverage report...
Publishing Clover HTML report...
Publishing Clover XML report...
Publishing Clover coverage results...
Hudson build is back to normal: HBase-Patch #1046
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1046/changes>
Build failed in Hudson: HBase-Patch #1045
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1045/changes>
Changes:
[jdcryans] HBASE-2065 Cannot disable a table if any of its region is opening at the same time
------------------------------------------
[...truncated 119942 lines...]
[junit] 2009-12-24 02:51:53,748 DEBUG [main] regionserver.HRegion(2262): d hdfs://localhost:55085/user/hudson/TestMergeTool/1695643034/contents
[junit] 2009-12-24 02:51:53,749 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1695643034/contents/2543356527371237151 size=791
[junit] 2009-12-24 02:51:53,749 DEBUG [main] regionserver.HRegion(1997): DELETING region hdfs://localhost:55085/user/hudson/TestMergeTool/145783824
[junit] 2009-12-24 02:51:53,763 DEBUG [main] regionserver.HRegion(1997): DELETING region hdfs://localhost:55085/user/hudson/TestMergeTool/1214415786
[junit] 2009-12-24 02:51:53,775 INFO [main] regionserver.HRegion(2207): merge completed. New region is TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:53,775 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1261623110241', STARTKEY => 'row_0100', ENDKEY => 'row_0400', ENCODED => 145783824, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-24 02:51:53,776 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0500,1261623101261', STARTKEY => 'row_0500', ENDKEY => 'row_0600', ENCODED => 1214415786, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-24 02:51:53,777 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,row_0100,1261623113451', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1695643034, 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] 2009-12-24 02:51:53,777 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261623113451: compactions & flushes disabled
[junit] 2009-12-24 02:51:53,777 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:53,778 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:53,778 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-24 02:51:53,778 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:53,778 DEBUG [main] regionserver.HRegion(454): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-12-24 02:51:53,778 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-12-24 02:51:53,779 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-12-24 02:51:53,779 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-24 02:51:53,779 INFO [main] regionserver.HRegion(490): Closed -ROOT-,,0
[junit] 2009-12-24 02:51:53,779 INFO [main] util.MetaUtils(148): CLOSING META .META.,,1
[junit] 2009-12-24 02:51:53,779 DEBUG [main] regionserver.HRegion(454): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-12-24 02:51:53,780 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-12-24 02:51:53,780 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region .META.,,1
[junit] 2009-12-24 02:51:53,780 DEBUG [main] regionserver.HRegion(895): Started memstore flush for region .META.,,1. Current region memstore size 1016
[junit] 2009-12-24 02:51:53,818 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:55085/user/hudson/.META./1028785192/historian/6922373364472965374, entries=2, sequenceid=27, memsize=288, filesize=521 to .META.,,1
[junit] 2009-12-24 02:51:53,858 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:55085/user/hudson/.META./1028785192/info/7458733247715094189, entries=3, sequenceid=27, memsize=728, filesize=874 to .META.,,1
[junit] 2009-12-24 02:51:53,858 DEBUG [main] regionserver.HRegion(971): Finished memstore flush of ~1016 for region .META.,,1 in 78ms, sequence id=27, compaction requested=true
[junit] 2009-12-24 02:51:53,858 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2009-12-24 02:51:53,859 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-24 02:51:53,859 INFO [main] regionserver.HRegion(490): Closed .META.,,1
[junit] 2009-12-24 02:51:53,895 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-24 02:51:53,895 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-24 02:51:53,896 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-24 02:51:53,896 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-24 02:51:53,907 INFO [main] wal.HLog(321): Roll /user/hudson/.logs_1261623112914/hlog.dat.1261623112923, entries=6, calcsize=1131, filesize=1055. New hlog /user/hudson/.logs_1261623112914/hlog.dat.1261623113878
[junit] 2009-12-24 02:51:53,907 DEBUG [main] wal.HLog(330): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-12-24 02:51:53,908 INFO [main] wal.HLog(490): removing old hlog file /user/hudson/.logs_1261623112914/hlog.dat.1261623112923 whose highest sequence/edit id is 26
[junit] 2009-12-24 02:51:53,922 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
[junit] 2009-12-24 02:51:53,923 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
[junit] 2009-12-24 02:51:53,923 DEBUG [main] wal.HLog(535): closing hlog writer in hdfs://localhost:55085/user/hudson/.logs_1261623112914
[junit] 2009-12-24 02:51:54,977 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1261623113451', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1695643034, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-24 02:51:54,978 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,row_0100,1261623113451, encoded=1695643034
[junit] 2009-12-24 02:51:54,985 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1695643034/contents/2543356527371237151, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-12-24 02:51:54,987 INFO [main] regionserver.HRegion(343): region TestMergeTool,row_0100,1261623113451/1695643034 available; sequence id is 7
[junit] 2009-12-24 02:51:54,991 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261623113451: compactions & flushes disabled
[junit] 2009-12-24 02:51:54,991 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:54,992 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:54,992 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-24 02:51:54,992 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:54,992 INFO [main] util.TestMergeTool(186): Verified merging regions 0+1+2 and 3
[junit] 2009-12-24 02:51:54,992 INFO [main] util.TestMergeTool(173): merging regions 0+1+2+3 and 4
[junit] 2009-12-24 02:51:54,993 WARN [main] conf.Configuration(616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2009-12-24 02:51:54,993 WARN [main] conf.Configuration(616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2009-12-24 02:51:54,993 INFO [main] util.Merge(82): Verifying that file system is available...
[junit] 2009-12-24 02:51:54,994 INFO [main] util.Merge(91): Verifying that HBase is not running...
[junit] 2009-12-24 02:51:55,011 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-24 02:51:55,011 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-24 02:51:55,011 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-24 02:51:55,012 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-24 02:51:55,013 INFO [main] util.Merge(197): Merging regions TestMergeTool,row_0100,1261623113451 and TestMergeTool,,1261623101261 in table TestMergeTool
[junit] 2009-12-24 02:51:55,021 INFO [main] wal.HLog(243): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-24 02:51:55,038 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-24 02:51:55,038 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-24 02:51:55,038 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-24 02:51:55,039 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-24 02:51:55,049 INFO [main] wal.HLog(321): New hlog /user/hudson/.logs_1261623115013/hlog.dat.1261623115021
[junit] 2009-12-24 02:51:55,050 DEBUG [main] regionserver.HRegion(1885): 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] 2009-12-24 02:51:55,050 DEBUG [main] regionserver.HRegion(265): Opening region -ROOT-,,0, encoded=70236052
[junit] 2009-12-24 02:51:55,057 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/7956458448872735126, isReference=false, sequence id=2, length=809, majorCompaction=false
[junit] 2009-12-24 02:51:55,060 INFO [main] regionserver.HRegion(343): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2009-12-24 02:51:55,060 INFO [main] regionserver.HRegion(771): Starting compaction on region -ROOT-,,0
[junit] 2009-12-24 02:51:55,062 INFO [main] regionserver.HRegion(786): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2009-12-24 02:51:55,062 INFO [main] util.Merge(211): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2009-12-24 02:51:55,063 DEBUG [main] regionserver.HRegion(1885): 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] 2009-12-24 02:51:55,063 DEBUG [main] regionserver.HRegion(265): Opening region .META.,,1, encoded=1028785192
[junit] 2009-12-24 02:51:55,070 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/6916337074257718273, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2009-12-24 02:51:55,074 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/6922373364472965374, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2009-12-24 02:51:55,081 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/1020993872531145005, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2009-12-24 02:51:55,084 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/4714453332631030294, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2009-12-24 02:51:55,088 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/7458733247715094189, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2009-12-24 02:51:55,091 INFO [main] regionserver.HRegion(343): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2009-12-24 02:51:55,091 INFO [main] regionserver.HRegion(771): Starting compaction on region .META.,,1
[junit] 2009-12-24 02:51:55,101 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-12-24 02:51:55,101 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2009-12-24 02:51:55,192 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:55085/user/hudson/.META./1028785192/historian/3624221464295904437; store size is 781
[junit] 2009-12-24 02:51:55,194 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2009-12-24 02:51:55,194 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2009-12-24 02:51:55,398 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:55085/user/hudson/.META./1028785192/info/4871382658367330410; store size is 3.3k
[junit] 2009-12-24 02:51:55,414 INFO [main] regionserver.HRegion(786): compaction completed on region .META.,,1 in 0sec
[junit] 2009-12-24 02:51:55,415 INFO [main] util.MetaUtils(125): OPENING META .META.,,1
[junit] 2009-12-24 02:51:55,417 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1261623113451', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1695643034, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-24 02:51:55,417 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,row_0100,1261623113451, encoded=1695643034
[junit] 2009-12-24 02:51:55,423 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1695643034/contents/2543356527371237151, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-12-24 02:51:55,426 INFO [main] regionserver.HRegion(343): region TestMergeTool,row_0100,1261623113451/1695643034 available; sequence id is 7
[junit] 2009-12-24 02:51:55,426 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,,1261623101261', STARTKEY => '', ENDKEY => '', ENCODED => 1904440817, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-24 02:51:55,426 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261623101261, encoded=1904440817
[junit] 2009-12-24 02:51:55,433 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1904440817/contents/4642896835959557091, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2009-12-24 02:51:55,435 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261623101261/1904440817 available; sequence id is 8
[junit] 2009-12-24 02:51:55,435 INFO [main] regionserver.HRegion(771): Starting major compaction on region TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:55,450 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1695643034, seqid=6
[junit] 2009-12-24 02:51:55,526 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:55085/user/hudson/TestMergeTool/1695643034/contents/6110139936453655312; store size is 791
[junit] 2009-12-24 02:51:55,542 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,row_0100,1261623113451 in 0sec
[junit] 2009-12-24 02:51:55,542 DEBUG [main] regionserver.HRegion(2118): Files for region: TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:55,543 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1695643034/.regioninfo size=632
[junit] 2009-12-24 02:51:55,543 DEBUG [main] regionserver.HRegion(2262): d hdfs://localhost:55085/user/hudson/TestMergeTool/1695643034/contents
[junit] 2009-12-24 02:51:55,544 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1695643034/contents/6110139936453655312 size=791
[junit] 2009-12-24 02:51:55,544 INFO [main] regionserver.HRegion(771): Starting major compaction on region TestMergeTool,,1261623101261
[junit] 2009-12-24 02:51:55,562 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1904440817, seqid=7
[junit] 2009-12-24 02:51:55,637 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:55085/user/hudson/TestMergeTool/1904440817/contents/3290828418484436084; store size is 471
[junit] 2009-12-24 02:51:55,653 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,,1261623101261 in 0sec
[junit] 2009-12-24 02:51:55,653 DEBUG [main] regionserver.HRegion(2123): Files for region: TestMergeTool,,1261623101261
[junit] 2009-12-24 02:51:55,654 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1904440817/.regioninfo size=584
[junit] 2009-12-24 02:51:55,654 DEBUG [main] regionserver.HRegion(2262): d hdfs://localhost:55085/user/hudson/TestMergeTool/1904440817/contents
[junit] 2009-12-24 02:51:55,655 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1904440817/contents/3290828418484436084 size=471
[junit] 2009-12-24 02:51:55,656 INFO [main] regionserver.HRegion(2152): Creating new region REGION => {NAME => 'TestMergeTool,,1261623115655', STARTKEY => '', ENDKEY => '', ENCODED => 1430398840, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-24 02:51:55,673 INFO [main] regionserver.HRegion(2161): starting merge of regions: TestMergeTool,row_0100,1261623113451 and TestMergeTool,,1261623101261 into new region REGION => {NAME => 'TestMergeTool,,1261623115655', STARTKEY => '', ENDKEY => '', ENCODED => 1430398840, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <> and end key <>
[junit] 2009-12-24 02:51:55,673 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261623113451: compactions & flushes disabled
[junit] 2009-12-24 02:51:55,673 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:55,674 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:55,674 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-24 02:51:55,674 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261623113451
[junit] 2009-12-24 02:51:55,674 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261623101261: compactions & flushes disabled
[junit] 2009-12-24 02:51:55,675 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261623101261
[junit] 2009-12-24 02:51:55,675 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261623101261
[junit] 2009-12-24 02:51:55,675 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-24 02:51:55,675 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261623101261
[junit] 2009-12-24 02:51:55,721 DEBUG [main] regionserver.HRegion(2194): Files for new region
[junit] 2009-12-24 02:51:55,722 DEBUG [main] regionserver.HRegion(2262): d hdfs://localhost:55085/user/hudson/TestMergeTool/1430398840/contents
[junit] 2009-12-24 02:51:55,722 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1430398840/contents/1885105279425419572 size=471
[junit] 2009-12-24 02:51:55,723 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1430398840/contents/4762176296109139386 size=791
[junit] 2009-12-24 02:51:55,723 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261623115655, encoded=1430398840
[junit] 2009-12-24 02:51:55,766 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1430398840/contents/1885105279425419572, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2009-12-24 02:51:55,770 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1430398840/contents/4762176296109139386, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2009-12-24 02:51:55,772 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261623115655/1430398840 available; sequence id is 8
[junit] 2009-12-24 02:51:55,772 INFO [main] regionserver.HRegion(771): Starting compaction on region TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:55,785 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2009-12-24 02:51:55,785 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1430398840, seqid=7
[junit] 2009-12-24 02:51:55,876 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:55085/user/hudson/TestMergeTool/1430398840/contents/3060968308192150412; store size is 929
[junit] 2009-12-24 02:51:55,892 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,,1261623115655 in 0sec
[junit] 2009-12-24 02:51:55,893 DEBUG [main] regionserver.HRegion(2201): Files for new region
[junit] 2009-12-24 02:51:55,894 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1430398840/.regioninfo size=584
[junit] 2009-12-24 02:51:55,894 DEBUG [main] regionserver.HRegion(2262): d hdfs://localhost:55085/user/hudson/TestMergeTool/1430398840/contents
[junit] 2009-12-24 02:51:55,895 DEBUG [main] regionserver.HRegion(2265): f hdfs://localhost:55085/user/hudson/TestMergeTool/1430398840/contents/3060968308192150412 size=929
[junit] 2009-12-24 02:51:55,895 DEBUG [main] regionserver.HRegion(1997): DELETING region hdfs://localhost:55085/user/hudson/TestMergeTool/1695643034
[junit] 2009-12-24 02:51:55,912 DEBUG [main] regionserver.HRegion(1997): DELETING region hdfs://localhost:55085/user/hudson/TestMergeTool/1904440817
[junit] 2009-12-24 02:51:55,928 INFO [main] regionserver.HRegion(2207): merge completed. New region is TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:55,928 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1261623113451', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1695643034, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-24 02:51:55,929 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,,1261623101261', STARTKEY => '', ENDKEY => '', ENCODED => 1904440817, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-24 02:51:55,930 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,,1261623115655', STARTKEY => '', ENDKEY => '', ENCODED => 1430398840, 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] 2009-12-24 02:51:55,930 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261623115655: compactions & flushes disabled
[junit] 2009-12-24 02:51:55,931 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:55,931 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:55,931 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-24 02:51:55,931 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:55,931 DEBUG [main] regionserver.HRegion(454): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-12-24 02:51:55,932 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-12-24 02:51:55,932 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-12-24 02:51:55,932 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-24 02:51:55,932 INFO [main] regionserver.HRegion(490): Closed -ROOT-,,0
[junit] 2009-12-24 02:51:55,933 INFO [main] util.MetaUtils(148): CLOSING META .META.,,1
[junit] 2009-12-24 02:51:55,933 DEBUG [main] regionserver.HRegion(454): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-12-24 02:51:55,933 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-12-24 02:51:55,933 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region .META.,,1
[junit] 2009-12-24 02:51:55,933 DEBUG [main] regionserver.HRegion(895): Started memstore flush for region .META.,,1. Current region memstore size 968
[junit] 2009-12-24 02:51:56,097 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:55085/user/hudson/.META./1028785192/historian/6899668306265408920, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
[junit] 2009-12-24 02:51:56,134 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:55085/user/hudson/.META./1028785192/info/6528555168644895276, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
[junit] 2009-12-24 02:51:56,135 DEBUG [main] regionserver.HRegion(971): Finished memstore flush of ~968 for region .META.,,1 in 202ms, sequence id=34, compaction requested=true
[junit] 2009-12-24 02:51:56,135 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2009-12-24 02:51:56,135 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-24 02:51:56,136 INFO [main] regionserver.HRegion(490): Closed .META.,,1
[junit] 2009-12-24 02:51:56,168 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-24 02:51:56,168 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-24 02:51:56,169 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-24 02:51:56,169 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-24 02:51:56,181 INFO [main] wal.HLog(321): Roll /user/hudson/.logs_1261623115013/hlog.dat.1261623115021, entries=6, calcsize=1083, filesize=1027. New hlog /user/hudson/.logs_1261623115013/hlog.dat.1261623116151
[junit] 2009-12-24 02:51:56,181 DEBUG [main] wal.HLog(330): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-12-24 02:51:56,181 INFO [main] wal.HLog(490): removing old hlog file /user/hudson/.logs_1261623115013/hlog.dat.1261623115021 whose highest sequence/edit id is 33
[junit] 2009-12-24 02:51:56,195 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
[junit] 2009-12-24 02:51:56,197 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
[junit] 2009-12-24 02:51:56,197 DEBUG [main] wal.HLog(535): closing hlog writer in hdfs://localhost:55085/user/hudson/.logs_1261623115013
[junit] 2009-12-24 02:51:56,231 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,,1261623115655', STARTKEY => '', ENDKEY => '', ENCODED => 1430398840, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-24 02:51:56,232 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261623115655, encoded=1430398840
[junit] 2009-12-24 02:51:56,238 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1430398840/contents/3060968308192150412, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2009-12-24 02:51:56,240 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261623115655/1430398840 available; sequence id is 8
[junit] 2009-12-24 02:51:56,245 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261623115655: compactions & flushes disabled
[junit] 2009-12-24 02:51:56,245 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:56,246 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:56,246 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-24 02:51:56,246 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261623115655
[junit] 2009-12-24 02:51:56,246 INFO [main] util.TestMergeTool(186): Verified merging regions 0+1+2+3 and 4
[junit] 2009-12-24 02:51:56,246 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
[junit] 2009-12-24 02:51:56,247 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
[junit] 2009-12-24 02:51:56,247 DEBUG [main] wal.HLog(535): closing hlog writer in /tmp/.logs_1261623108431
[junit] 2009-12-24 02:51:56,678 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-12-24 02:51:56,780 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@dac21] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:33091, storageID=DS-220617547-67.195.138.9-33091-1261623104182, infoPort=50200, ipcPort=52293):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] 2009-12-24 02:51:56,884 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@17c2891] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:59493, storageID=DS-1963816808-67.195.138.9-59493-1261623103525, infoPort=37788, ipcPort=53330):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] 2009-12-24 02:51:56,887 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@1ce669e] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2009-12-24 02:51:56,889 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@15e2075] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2009-12-24 02:51:56,901 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 15.815 sec
[junit] 2009-12-24 02:51:57,080 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-12-24 02:51:57,470 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:228)
[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:83)
[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.077 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:504: Tests failed!
Total time: 30 minutes 38 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
Re: Build failed in Hudson: HBase-Patch #1044
Posted by Jean-Daniel Cryans <jd...@apache.org>.
Seems my new test is failing. That's good, it means it's working.
The problem is that the region which we want to disable is pending
open but we clear its state in ChangeTableState. The master is then
confused when it gets back as opened by the region server.
J-D
On Tue, Dec 22, 2009 at 7:44 PM, Apache Hudson Server
<hu...@hudson.zones.apache.org> wrote:
> See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1044/changes>
>
> Changes:
>
> [jdcryans] HBASE-2064 Cannot disable a table if at the same the Master is moving
> its regions around
> HBASE-2065 Cannot disable a table if any of its region is opening
> at the same time
>
> ------------------------------------------
> [...truncated 118988 lines...]
> [junit] 2009-12-23 03:48:21,604 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents
> [junit] 2009-12-23 03:48:21,605 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents/3530192824530754175 size=791
> [junit] 2009-12-23 03:48:21,605 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1695461468
> [junit] 2009-12-23 03:48:21,619 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1054953998
> [junit] 2009-12-23 03:48:21,631 INFO [main] regionserver.HRegion(2213): merge completed. New region is TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:21,631 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1261540097920', STARTKEY => 'row_0100', ENDKEY => 'row_0400', ENCODED => 1695461468, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2009-12-23 03:48:21,632 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0500,1261540083850', STARTKEY => 'row_0500', ENDKEY => 'row_0600', ENCODED => 1054953998, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2009-12-23 03:48:21,633 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, 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] 2009-12-23 03:48:21,633 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261540101208: compactions & flushes disabled
> [junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2009-12-23 03:48:21,634 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(454): Closing -ROOT-,,0: compactions & flushes disabled
> [junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on -ROOT-,,0
> [junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region -ROOT-,,0
> [junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.Store(482): closed info
> [junit] 2009-12-23 03:48:21,635 INFO [main] regionserver.HRegion(490): Closed -ROOT-,,0
> [junit] 2009-12-23 03:48:21,635 INFO [main] util.MetaUtils(148): CLOSING META .META.,,1
> [junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.HRegion(454): Closing .META.,,1: compactions & flushes disabled
> [junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on .META.,,1
> [junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region .META.,,1
> [junit] 2009-12-23 03:48:21,636 DEBUG [main] regionserver.HRegion(895): Started memstore flush for region .META.,,1. Current region memstore size 1016
> [junit] 2009-12-23 03:48:21,674 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/historian/1033018758061668555, entries=2, sequenceid=27, memsize=288, filesize=521 to .META.,,1
> [junit] 2009-12-23 03:48:21,714 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/info/2637185596448341006, entries=3, sequenceid=27, memsize=728, filesize=874 to .META.,,1
> [junit] 2009-12-23 03:48:21,715 DEBUG [main] regionserver.HRegion(971): Finished memstore flush of ~1016 for region .META.,,1 in 79ms, sequence id=27, compaction requested=true
> [junit] 2009-12-23 03:48:21,715 DEBUG [main] regionserver.Store(482): closed historian
> [junit] 2009-12-23 03:48:21,716 DEBUG [main] regionserver.Store(482): closed info
> [junit] 2009-12-23 03:48:21,717 INFO [main] regionserver.HRegion(490): Closed .META.,,1
> [junit] 2009-12-23 03:48:21,779 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
> [junit] 2009-12-23 03:48:21,780 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
> [junit] 2009-12-23 03:48:21,781 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
> [junit] 2009-12-23 03:48:21,781 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
> [junit] 2009-12-23 03:48:21,793 INFO [main] wal.HLog(321): Roll /user/hudson/.logs_1261540099585/hlog.dat.1261540099595, entries=6, calcsize=1131, filesize=1075. New hlog /user/hudson/.logs_1261540099585/hlog.dat.1261540101732
> [junit] 2009-12-23 03:48:21,793 DEBUG [main] wal.HLog(330): Last sequence written is empty. Deleting all old hlogs
> [junit] 2009-12-23 03:48:21,794 INFO [main] wal.HLog(490): removing old hlog file /user/hudson/.logs_1261540099585/hlog.dat.1261540099595 whose highest sequence/edit id is 26
> [junit] 2009-12-23 03:48:21,806 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
> [junit] 2009-12-23 03:48:21,807 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
> [junit] 2009-12-23 03:48:21,807 DEBUG [main] wal.HLog(535): closing hlog writer in hdfs://localhost:48623/user/hudson/.logs_1261540099585
> [junit] 2009-12-23 03:48:21,838 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-12-23 03:48:21,839 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,row_0100,1261540101208, encoded=1147554832
> [junit] 2009-12-23 03:48:21,846 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1147554832/contents/3530192824530754175, isReference=false, sequence id=6, length=791, majorCompaction=false
> [junit] 2009-12-23 03:48:21,848 INFO [main] regionserver.HRegion(343): region TestMergeTool,row_0100,1261540101208/1147554832 available; sequence id is 7
> [junit] 2009-12-23 03:48:21,852 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261540101208: compactions & flushes disabled
> [junit] 2009-12-23 03:48:21,852 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:21,852 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:21,853 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2009-12-23 03:48:21,853 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:21,853 INFO [main] util.TestMergeTool(186): Verified merging regions 0+1+2 and 3
> [junit] 2009-12-23 03:48:21,853 INFO [main] util.TestMergeTool(173): merging regions 0+1+2+3 and 4
> [junit] 2009-12-23 03:48:21,853 WARN [main] conf.Configuration(616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
> [junit] 2009-12-23 03:48:21,853 WARN [main] conf.Configuration(616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
> [junit] 2009-12-23 03:48:21,854 INFO [main] util.Merge(82): Verifying that file system is available...
> [junit] 2009-12-23 03:48:21,854 INFO [main] util.Merge(91): Verifying that HBase is not running...
> [junit] 2009-12-23 03:48:21,871 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
> [junit] 2009-12-23 03:48:21,871 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
> [junit] 2009-12-23 03:48:21,872 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
> [junit] 2009-12-23 03:48:21,872 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
> [junit] 2009-12-23 03:48:21,873 INFO [main] util.Merge(197): Merging regions TestMergeTool,row_0100,1261540101208 and TestMergeTool,,1261540083850 in table TestMergeTool
> [junit] 2009-12-23 03:48:21,883 INFO [main] wal.HLog(243): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
> [junit] 2009-12-23 03:48:21,900 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
> [junit] 2009-12-23 03:48:21,900 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
> [junit] 2009-12-23 03:48:21,900 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
> [junit] 2009-12-23 03:48:21,901 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
> [junit] 2009-12-23 03:48:21,911 INFO [main] wal.HLog(321): New hlog /user/hudson/.logs_1261540101873/hlog.dat.1261540101883
> [junit] 2009-12-23 03:48:21,912 DEBUG [main] regionserver.HRegion(1885): 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] 2009-12-23 03:48:21,912 DEBUG [main] regionserver.HRegion(265): Opening region -ROOT-,,0, encoded=70236052
> [junit] 2009-12-23 03:48:21,920 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/2740212953581004924, isReference=false, sequence id=2, length=809, majorCompaction=false
> [junit] 2009-12-23 03:48:21,922 INFO [main] regionserver.HRegion(343): region -ROOT-,,0/70236052 available; sequence id is 3
> [junit] 2009-12-23 03:48:21,922 INFO [main] regionserver.HRegion(771): Starting compaction on region -ROOT-,,0
> [junit] 2009-12-23 03:48:21,924 INFO [main] regionserver.HRegion(786): compaction completed on region -ROOT-,,0 in 0sec
> [junit] 2009-12-23 03:48:21,924 INFO [main] util.Merge(211): Found meta for region1 .META.,,1, meta for region2 .META.,,1
> [junit] 2009-12-23 03:48:21,925 DEBUG [main] regionserver.HRegion(1885): 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] 2009-12-23 03:48:21,925 DEBUG [main] regionserver.HRegion(265): Opening region .META.,,1, encoded=1028785192
> [junit] 2009-12-23 03:48:21,932 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/1033018758061668555, isReference=false, sequence id=27, length=521, majorCompaction=false
> [junit] 2009-12-23 03:48:21,938 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/3476054143409226755, isReference=false, sequence id=20, length=651, majorCompaction=false
> [junit] 2009-12-23 03:48:21,947 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2637185596448341006, isReference=false, sequence id=27, length=874, majorCompaction=false
> [junit] 2009-12-23 03:48:21,951 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2926731446109122526, isReference=false, sequence id=20, length=994, majorCompaction=false
> [junit] 2009-12-23 03:48:21,955 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/4589151583594890471, isReference=false, sequence id=6, length=2226, majorCompaction=false
> [junit] 2009-12-23 03:48:21,958 INFO [main] regionserver.HRegion(343): region .META.,,1/1028785192 available; sequence id is 28
> [junit] 2009-12-23 03:48:21,958 INFO [main] regionserver.HRegion(771): Starting compaction on region .META.,,1
> [junit] 2009-12-23 03:48:21,967 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
> [junit] 2009-12-23 03:48:21,967 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
> [junit] 2009-12-23 03:48:22,168 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:48623/user/hudson/.META./1028785192/historian/6900876774987438370; store size is 781
> [junit] 2009-12-23 03:48:22,169 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
> [junit] 2009-12-23 03:48:22,170 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
> [junit] 2009-12-23 03:48:22,227 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:48623/user/hudson/.META./1028785192/info/8123136151735169395; store size is 3.3k
> [junit] 2009-12-23 03:48:22,238 INFO [main] regionserver.HRegion(786): compaction completed on region .META.,,1 in 0sec
> [junit] 2009-12-23 03:48:22,239 INFO [main] util.MetaUtils(125): OPENING META .META.,,1
> [junit] 2009-12-23 03:48:22,241 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-12-23 03:48:22,241 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,row_0100,1261540101208, encoded=1147554832
> [junit] 2009-12-23 03:48:22,248 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1147554832/contents/3530192824530754175, isReference=false, sequence id=6, length=791, majorCompaction=false
> [junit] 2009-12-23 03:48:22,250 INFO [main] regionserver.HRegion(343): region TestMergeTool,row_0100,1261540101208/1147554832 available; sequence id is 7
> [junit] 2009-12-23 03:48:22,250 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,,1261540083850', STARTKEY => '', ENDKEY => '', ENCODED => 1897256012, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-12-23 03:48:22,251 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261540083850, encoded=1897256012
> [junit] 2009-12-23 03:48:22,257 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1897256012/contents/3625255122862528899, isReference=false, sequence id=7, length=471, majorCompaction=false
> [junit] 2009-12-23 03:48:22,260 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261540083850/1897256012 available; sequence id is 8
> [junit] 2009-12-23 03:48:22,260 INFO [main] regionserver.HRegion(771): Starting major compaction on region TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:22,271 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1147554832, seqid=6
> [junit] 2009-12-23 03:48:22,326 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents/1222024413056464161; store size is 791
> [junit] 2009-12-23 03:48:22,335 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,row_0100,1261540101208 in 0sec
> [junit] 2009-12-23 03:48:22,335 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:22,336 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/.regioninfo size=632
> [junit] 2009-12-23 03:48:22,336 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents
> [junit] 2009-12-23 03:48:22,337 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents/1222024413056464161 size=791
> [junit] 2009-12-23 03:48:22,338 INFO [main] regionserver.HRegion(771): Starting major compaction on region TestMergeTool,,1261540083850
> [junit] 2009-12-23 03:48:22,347 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1897256012, seqid=7
> [junit] 2009-12-23 03:48:22,395 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/contents/2696629698052375339; store size is 471
> [junit] 2009-12-23 03:48:22,406 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,,1261540083850 in 0sec
> [junit] 2009-12-23 03:48:22,406 DEBUG [main] regionserver.HRegion(2129): Files for region: TestMergeTool,,1261540083850
> [junit] 2009-12-23 03:48:22,407 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/.regioninfo size=584
> [junit] 2009-12-23 03:48:22,407 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/contents
> [junit] 2009-12-23 03:48:22,408 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/contents/2696629698052375339 size=471
> [junit] 2009-12-23 03:48:22,409 INFO [main] regionserver.HRegion(2158): Creating new region REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-12-23 03:48:22,419 INFO [main] regionserver.HRegion(2167): starting merge of regions: TestMergeTool,row_0100,1261540101208 and TestMergeTool,,1261540083850 into new region REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <> and end key <>
> [junit] 2009-12-23 03:48:22,419 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261540101208: compactions & flushes disabled
> [junit] 2009-12-23 03:48:22,419 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:22,420 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:22,420 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2009-12-23 03:48:22,420 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261540101208
> [junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261540083850: compactions & flushes disabled
> [junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261540083850
> [junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261540083850
> [junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2009-12-23 03:48:22,422 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261540083850
> [junit] 2009-12-23 03:48:22,455 DEBUG [main] regionserver.HRegion(2200): Files for new region
> [junit] 2009-12-23 03:48:22,455 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents
> [junit] 2009-12-23 03:48:22,456 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/1183869435374919717 size=471
> [junit] 2009-12-23 03:48:22,457 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/5201998480557514100 size=791
> [junit] 2009-12-23 03:48:22,457 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261540102408, encoded=1888871559
> [junit] 2009-12-23 03:48:22,494 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1888871559/contents/1183869435374919717, isReference=false, sequence id=7, length=471, majorCompaction=true
> [junit] 2009-12-23 03:48:22,498 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1888871559/contents/5201998480557514100, isReference=false, sequence id=6, length=791, majorCompaction=true
> [junit] 2009-12-23 03:48:22,500 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261540102408/1888871559 available; sequence id is 8
> [junit] 2009-12-23 03:48:22,501 INFO [main] regionserver.HRegion(771): Starting compaction on region TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,510 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
> [junit] 2009-12-23 03:48:22,511 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1888871559, seqid=7
> [junit] 2009-12-23 03:48:22,570 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/4485189901783544259; store size is 929
> [junit] 2009-12-23 03:48:22,581 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,,1261540102408 in 0sec
> [junit] 2009-12-23 03:48:22,583 DEBUG [main] regionserver.HRegion(2207): Files for new region
> [junit] 2009-12-23 03:48:22,584 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/.regioninfo size=584
> [junit] 2009-12-23 03:48:22,585 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents
> [junit] 2009-12-23 03:48:22,586 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/4485189901783544259 size=929
> [junit] 2009-12-23 03:48:22,586 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832
> [junit] 2009-12-23 03:48:22,697 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012
> [junit] 2009-12-23 03:48:22,713 INFO [main] regionserver.HRegion(2213): merge completed. New region is TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,713 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2009-12-23 03:48:22,714 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,,1261540083850', STARTKEY => '', ENDKEY => '', ENCODED => 1897256012, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
> [junit] 2009-12-23 03:48:22,715 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, 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] 2009-12-23 03:48:22,715 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261540102408: compactions & flushes disabled
> [junit] 2009-12-23 03:48:22,715 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,716 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,716 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2009-12-23 03:48:22,716 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,716 DEBUG [main] regionserver.HRegion(454): Closing -ROOT-,,0: compactions & flushes disabled
> [junit] 2009-12-23 03:48:22,717 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on -ROOT-,,0
> [junit] 2009-12-23 03:48:22,717 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region -ROOT-,,0
> [junit] 2009-12-23 03:48:22,717 DEBUG [main] regionserver.Store(482): closed info
> [junit] 2009-12-23 03:48:22,717 INFO [main] regionserver.HRegion(490): Closed -ROOT-,,0
> [junit] 2009-12-23 03:48:22,718 INFO [main] util.MetaUtils(148): CLOSING META .META.,,1
> [junit] 2009-12-23 03:48:22,718 DEBUG [main] regionserver.HRegion(454): Closing .META.,,1: compactions & flushes disabled
> [junit] 2009-12-23 03:48:22,718 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on .META.,,1
> [junit] 2009-12-23 03:48:22,718 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region .META.,,1
> [junit] 2009-12-23 03:48:22,719 DEBUG [main] regionserver.HRegion(895): Started memstore flush for region .META.,,1. Current region memstore size 968
> [junit] 2009-12-23 03:48:22,748 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/historian/7124521150580429384, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
> [junit] 2009-12-23 03:48:22,777 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/info/372924957441297594, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
> [junit] 2009-12-23 03:48:22,777 DEBUG [main] regionserver.HRegion(971): Finished memstore flush of ~968 for region .META.,,1 in 58ms, sequence id=34, compaction requested=true
> [junit] 2009-12-23 03:48:22,777 DEBUG [main] regionserver.Store(482): closed historian
> [junit] 2009-12-23 03:48:22,778 DEBUG [main] regionserver.Store(482): closed info
> [junit] 2009-12-23 03:48:22,778 INFO [main] regionserver.HRegion(490): Closed .META.,,1
> [junit] 2009-12-23 03:48:22,809 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
> [junit] 2009-12-23 03:48:22,810 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
> [junit] 2009-12-23 03:48:22,810 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
> [junit] 2009-12-23 03:48:22,810 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
> [junit] 2009-12-23 03:48:22,823 INFO [main] wal.HLog(321): Roll /user/hudson/.logs_1261540101873/hlog.dat.1261540101883, entries=6, calcsize=1083, filesize=1007. New hlog /user/hudson/.logs_1261540101873/hlog.dat.1261540102793
> [junit] 2009-12-23 03:48:22,823 DEBUG [main] wal.HLog(330): Last sequence written is empty. Deleting all old hlogs
> [junit] 2009-12-23 03:48:22,824 INFO [main] wal.HLog(490): removing old hlog file /user/hudson/.logs_1261540101873/hlog.dat.1261540101883 whose highest sequence/edit id is 33
> [junit] 2009-12-23 03:48:22,833 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
> [junit] 2009-12-23 03:48:22,834 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
> [junit] 2009-12-23 03:48:22,834 DEBUG [main] wal.HLog(535): closing hlog writer in hdfs://localhost:48623/user/hudson/.logs_1261540101873
> [junit] 2009-12-23 03:48:22,857 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
> [junit] 2009-12-23 03:48:22,858 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261540102408, encoded=1888871559
> [junit] 2009-12-23 03:48:22,864 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1888871559/contents/4485189901783544259, isReference=false, sequence id=7, length=929, majorCompaction=false
> [junit] 2009-12-23 03:48:22,866 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261540102408/1888871559 available; sequence id is 8
> [junit] 2009-12-23 03:48:22,871 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261540102408: compactions & flushes disabled
> [junit] 2009-12-23 03:48:22,871 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,872 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,872 DEBUG [main] regionserver.Store(482): closed contents
> [junit] 2009-12-23 03:48:22,872 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261540102408
> [junit] 2009-12-23 03:48:22,872 INFO [main] util.TestMergeTool(186): Verified merging regions 0+1+2+3 and 4
> [junit] 2009-12-23 03:48:22,872 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
> [junit] 2009-12-23 03:48:22,873 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
> [junit] 2009-12-23 03:48:22,873 DEBUG [main] wal.HLog(535): closing hlog writer in /tmp/.logs_1261540094128
> [junit] 2009-12-23 03:48:23,902 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
> [junit] Shutting down the Mini HDFS Cluster
> [junit] Shutting down DataNode 1
> [junit] 2009-12-23 03:48:24,011 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1df2964] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:49057, storageID=DS-573717694-67.195.138.9-49057-1261540086752, infoPort=34369, ipcPort=36108):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] 2009-12-23 03:48:24,116 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@9300cc] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:34972, storageID=DS-469890356-67.195.138.9-34972-1261540086036, infoPort=60432, ipcPort=44611):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] 2009-12-23 03:48:24,218 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@5eb489] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
> [junit] 2009-12-23 03:48:24,218 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@3a1ec6] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
> [junit] 2009-12-23 03:48:24,229 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 20.554 sec
> [junit] 2009-12-23 03:48:25,276 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
> [junit] Running org.apache.hadoop.hbase.util.TestRootPath
> [junit] 2009-12-23 03:48:25,640 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:228)
> [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:83)
> [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.077 sec
>
> BUILD FAILED
> <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:504: Tests failed!
>
> Total time: 28 minutes 11 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 #1044
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1044/changes>
Changes:
[jdcryans] HBASE-2064 Cannot disable a table if at the same the Master is moving
its regions around
HBASE-2065 Cannot disable a table if any of its region is opening
at the same time
------------------------------------------
[...truncated 118988 lines...]
[junit] 2009-12-23 03:48:21,604 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents
[junit] 2009-12-23 03:48:21,605 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents/3530192824530754175 size=791
[junit] 2009-12-23 03:48:21,605 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1695461468
[junit] 2009-12-23 03:48:21,619 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1054953998
[junit] 2009-12-23 03:48:21,631 INFO [main] regionserver.HRegion(2213): merge completed. New region is TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:21,631 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1261540097920', STARTKEY => 'row_0100', ENDKEY => 'row_0400', ENCODED => 1695461468, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-23 03:48:21,632 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0500,1261540083850', STARTKEY => 'row_0500', ENDKEY => 'row_0600', ENCODED => 1054953998, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-23 03:48:21,633 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, 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] 2009-12-23 03:48:21,633 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261540101208: compactions & flushes disabled
[junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-23 03:48:21,634 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(454): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-12-23 03:48:21,634 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-23 03:48:21,635 INFO [main] regionserver.HRegion(490): Closed -ROOT-,,0
[junit] 2009-12-23 03:48:21,635 INFO [main] util.MetaUtils(148): CLOSING META .META.,,1
[junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.HRegion(454): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-12-23 03:48:21,635 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region .META.,,1
[junit] 2009-12-23 03:48:21,636 DEBUG [main] regionserver.HRegion(895): Started memstore flush for region .META.,,1. Current region memstore size 1016
[junit] 2009-12-23 03:48:21,674 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/historian/1033018758061668555, entries=2, sequenceid=27, memsize=288, filesize=521 to .META.,,1
[junit] 2009-12-23 03:48:21,714 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/info/2637185596448341006, entries=3, sequenceid=27, memsize=728, filesize=874 to .META.,,1
[junit] 2009-12-23 03:48:21,715 DEBUG [main] regionserver.HRegion(971): Finished memstore flush of ~1016 for region .META.,,1 in 79ms, sequence id=27, compaction requested=true
[junit] 2009-12-23 03:48:21,715 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2009-12-23 03:48:21,716 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-23 03:48:21,717 INFO [main] regionserver.HRegion(490): Closed .META.,,1
[junit] 2009-12-23 03:48:21,779 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-23 03:48:21,780 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-23 03:48:21,781 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-23 03:48:21,781 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-23 03:48:21,793 INFO [main] wal.HLog(321): Roll /user/hudson/.logs_1261540099585/hlog.dat.1261540099595, entries=6, calcsize=1131, filesize=1075. New hlog /user/hudson/.logs_1261540099585/hlog.dat.1261540101732
[junit] 2009-12-23 03:48:21,793 DEBUG [main] wal.HLog(330): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-12-23 03:48:21,794 INFO [main] wal.HLog(490): removing old hlog file /user/hudson/.logs_1261540099585/hlog.dat.1261540099595 whose highest sequence/edit id is 26
[junit] 2009-12-23 03:48:21,806 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
[junit] 2009-12-23 03:48:21,807 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
[junit] 2009-12-23 03:48:21,807 DEBUG [main] wal.HLog(535): closing hlog writer in hdfs://localhost:48623/user/hudson/.logs_1261540099585
[junit] 2009-12-23 03:48:21,838 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-23 03:48:21,839 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,row_0100,1261540101208, encoded=1147554832
[junit] 2009-12-23 03:48:21,846 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1147554832/contents/3530192824530754175, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-12-23 03:48:21,848 INFO [main] regionserver.HRegion(343): region TestMergeTool,row_0100,1261540101208/1147554832 available; sequence id is 7
[junit] 2009-12-23 03:48:21,852 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261540101208: compactions & flushes disabled
[junit] 2009-12-23 03:48:21,852 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:21,852 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:21,853 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-23 03:48:21,853 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:21,853 INFO [main] util.TestMergeTool(186): Verified merging regions 0+1+2 and 3
[junit] 2009-12-23 03:48:21,853 INFO [main] util.TestMergeTool(173): merging regions 0+1+2+3 and 4
[junit] 2009-12-23 03:48:21,853 WARN [main] conf.Configuration(616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2009-12-23 03:48:21,853 WARN [main] conf.Configuration(616): mapred.used.genericoptionsparser is deprecated. Instead, use mapreduce.client.genericoptionsparser.used
[junit] 2009-12-23 03:48:21,854 INFO [main] util.Merge(82): Verifying that file system is available...
[junit] 2009-12-23 03:48:21,854 INFO [main] util.Merge(91): Verifying that HBase is not running...
[junit] 2009-12-23 03:48:21,871 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-23 03:48:21,871 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-23 03:48:21,872 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-23 03:48:21,872 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-23 03:48:21,873 INFO [main] util.Merge(197): Merging regions TestMergeTool,row_0100,1261540101208 and TestMergeTool,,1261540083850 in table TestMergeTool
[junit] 2009-12-23 03:48:21,883 INFO [main] wal.HLog(243): HLog configuration: blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-23 03:48:21,900 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-23 03:48:21,900 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-23 03:48:21,900 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-23 03:48:21,901 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-23 03:48:21,911 INFO [main] wal.HLog(321): New hlog /user/hudson/.logs_1261540101873/hlog.dat.1261540101883
[junit] 2009-12-23 03:48:21,912 DEBUG [main] regionserver.HRegion(1885): 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] 2009-12-23 03:48:21,912 DEBUG [main] regionserver.HRegion(265): Opening region -ROOT-,,0, encoded=70236052
[junit] 2009-12-23 03:48:21,920 DEBUG [main] regionserver.Store(417): loaded /user/hudson/-ROOT-/70236052/info/2740212953581004924, isReference=false, sequence id=2, length=809, majorCompaction=false
[junit] 2009-12-23 03:48:21,922 INFO [main] regionserver.HRegion(343): region -ROOT-,,0/70236052 available; sequence id is 3
[junit] 2009-12-23 03:48:21,922 INFO [main] regionserver.HRegion(771): Starting compaction on region -ROOT-,,0
[junit] 2009-12-23 03:48:21,924 INFO [main] regionserver.HRegion(786): compaction completed on region -ROOT-,,0 in 0sec
[junit] 2009-12-23 03:48:21,924 INFO [main] util.Merge(211): Found meta for region1 .META.,,1, meta for region2 .META.,,1
[junit] 2009-12-23 03:48:21,925 DEBUG [main] regionserver.HRegion(1885): 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] 2009-12-23 03:48:21,925 DEBUG [main] regionserver.HRegion(265): Opening region .META.,,1, encoded=1028785192
[junit] 2009-12-23 03:48:21,932 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/1033018758061668555, isReference=false, sequence id=27, length=521, majorCompaction=false
[junit] 2009-12-23 03:48:21,938 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/historian/3476054143409226755, isReference=false, sequence id=20, length=651, majorCompaction=false
[junit] 2009-12-23 03:48:21,947 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2637185596448341006, isReference=false, sequence id=27, length=874, majorCompaction=false
[junit] 2009-12-23 03:48:21,951 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/2926731446109122526, isReference=false, sequence id=20, length=994, majorCompaction=false
[junit] 2009-12-23 03:48:21,955 DEBUG [main] regionserver.Store(417): loaded /user/hudson/.META./1028785192/info/4589151583594890471, isReference=false, sequence id=6, length=2226, majorCompaction=false
[junit] 2009-12-23 03:48:21,958 INFO [main] regionserver.HRegion(343): region .META.,,1/1028785192 available; sequence id is 28
[junit] 2009-12-23 03:48:21,958 INFO [main] regionserver.HRegion(771): Starting compaction on region .META.,,1
[junit] 2009-12-23 03:48:21,967 DEBUG [main] regionserver.Store(750): Compaction size of historian: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-12-23 03:48:21,967 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2009-12-23 03:48:22,168 DEBUG [main] regionserver.Store(764): Completed compaction of historian; new storefile is hdfs://localhost:48623/user/hudson/.META./1028785192/historian/6900876774987438370; store size is 781
[junit] 2009-12-23 03:48:22,169 DEBUG [main] regionserver.Store(750): Compaction size of info: 4.0k; Skipped 1 file(s), size: 2226
[junit] 2009-12-23 03:48:22,170 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/.META./compaction.dir/1028785192, seqid=27
[junit] 2009-12-23 03:48:22,227 DEBUG [main] regionserver.Store(764): Completed compaction of info; new storefile is hdfs://localhost:48623/user/hudson/.META./1028785192/info/8123136151735169395; store size is 3.3k
[junit] 2009-12-23 03:48:22,238 INFO [main] regionserver.HRegion(786): compaction completed on region .META.,,1 in 0sec
[junit] 2009-12-23 03:48:22,239 INFO [main] util.MetaUtils(125): OPENING META .META.,,1
[junit] 2009-12-23 03:48:22,241 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-23 03:48:22,241 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,row_0100,1261540101208, encoded=1147554832
[junit] 2009-12-23 03:48:22,248 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1147554832/contents/3530192824530754175, isReference=false, sequence id=6, length=791, majorCompaction=false
[junit] 2009-12-23 03:48:22,250 INFO [main] regionserver.HRegion(343): region TestMergeTool,row_0100,1261540101208/1147554832 available; sequence id is 7
[junit] 2009-12-23 03:48:22,250 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,,1261540083850', STARTKEY => '', ENDKEY => '', ENCODED => 1897256012, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-23 03:48:22,251 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261540083850, encoded=1897256012
[junit] 2009-12-23 03:48:22,257 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1897256012/contents/3625255122862528899, isReference=false, sequence id=7, length=471, majorCompaction=false
[junit] 2009-12-23 03:48:22,260 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261540083850/1897256012 available; sequence id is 8
[junit] 2009-12-23 03:48:22,260 INFO [main] regionserver.HRegion(771): Starting major compaction on region TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:22,271 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1147554832, seqid=6
[junit] 2009-12-23 03:48:22,326 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents/1222024413056464161; store size is 791
[junit] 2009-12-23 03:48:22,335 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,row_0100,1261540101208 in 0sec
[junit] 2009-12-23 03:48:22,335 DEBUG [main] regionserver.HRegion(2124): Files for region: TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:22,336 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/.regioninfo size=632
[junit] 2009-12-23 03:48:22,336 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents
[junit] 2009-12-23 03:48:22,337 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832/contents/1222024413056464161 size=791
[junit] 2009-12-23 03:48:22,338 INFO [main] regionserver.HRegion(771): Starting major compaction on region TestMergeTool,,1261540083850
[junit] 2009-12-23 03:48:22,347 DEBUG [main] regionserver.Store(757): Started compaction of 1 file(s) into /user/hudson/TestMergeTool/compaction.dir/1897256012, seqid=7
[junit] 2009-12-23 03:48:22,395 DEBUG [main] regionserver.Store(764): Completed major compaction of contents; new storefile is hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/contents/2696629698052375339; store size is 471
[junit] 2009-12-23 03:48:22,406 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,,1261540083850 in 0sec
[junit] 2009-12-23 03:48:22,406 DEBUG [main] regionserver.HRegion(2129): Files for region: TestMergeTool,,1261540083850
[junit] 2009-12-23 03:48:22,407 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/.regioninfo size=584
[junit] 2009-12-23 03:48:22,407 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/contents
[junit] 2009-12-23 03:48:22,408 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012/contents/2696629698052375339 size=471
[junit] 2009-12-23 03:48:22,409 INFO [main] regionserver.HRegion(2158): Creating new region REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-23 03:48:22,419 INFO [main] regionserver.HRegion(2167): starting merge of regions: TestMergeTool,row_0100,1261540101208 and TestMergeTool,,1261540083850 into new region REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} with start key <> and end key <>
[junit] 2009-12-23 03:48:22,419 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,row_0100,1261540101208: compactions & flushes disabled
[junit] 2009-12-23 03:48:22,419 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:22,420 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:22,420 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-23 03:48:22,420 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,row_0100,1261540101208
[junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261540083850: compactions & flushes disabled
[junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261540083850
[junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261540083850
[junit] 2009-12-23 03:48:22,421 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-23 03:48:22,422 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261540083850
[junit] 2009-12-23 03:48:22,455 DEBUG [main] regionserver.HRegion(2200): Files for new region
[junit] 2009-12-23 03:48:22,455 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents
[junit] 2009-12-23 03:48:22,456 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/1183869435374919717 size=471
[junit] 2009-12-23 03:48:22,457 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/5201998480557514100 size=791
[junit] 2009-12-23 03:48:22,457 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261540102408, encoded=1888871559
[junit] 2009-12-23 03:48:22,494 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1888871559/contents/1183869435374919717, isReference=false, sequence id=7, length=471, majorCompaction=true
[junit] 2009-12-23 03:48:22,498 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1888871559/contents/5201998480557514100, isReference=false, sequence id=6, length=791, majorCompaction=true
[junit] 2009-12-23 03:48:22,500 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261540102408/1888871559 available; sequence id is 8
[junit] 2009-12-23 03:48:22,501 INFO [main] regionserver.HRegion(771): Starting compaction on region TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,510 DEBUG [main] regionserver.Store(750): Compaction size of contents: 1.2k; Skipped 0 file(s), size: 0
[junit] 2009-12-23 03:48:22,511 DEBUG [main] regionserver.Store(757): Started compaction of 2 file(s) into /user/hudson/TestMergeTool/compaction.dir/1888871559, seqid=7
[junit] 2009-12-23 03:48:22,570 DEBUG [main] regionserver.Store(764): Completed compaction of contents; new storefile is hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/4485189901783544259; store size is 929
[junit] 2009-12-23 03:48:22,581 INFO [main] regionserver.HRegion(786): compaction completed on region TestMergeTool,,1261540102408 in 0sec
[junit] 2009-12-23 03:48:22,583 DEBUG [main] regionserver.HRegion(2207): Files for new region
[junit] 2009-12-23 03:48:22,584 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/.regioninfo size=584
[junit] 2009-12-23 03:48:22,585 DEBUG [main] regionserver.HRegion(2268): d hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents
[junit] 2009-12-23 03:48:22,586 DEBUG [main] regionserver.HRegion(2271): f hdfs://localhost:48623/user/hudson/TestMergeTool/1888871559/contents/4485189901783544259 size=929
[junit] 2009-12-23 03:48:22,586 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1147554832
[junit] 2009-12-23 03:48:22,697 DEBUG [main] regionserver.HRegion(2003): DELETING region hdfs://localhost:48623/user/hudson/TestMergeTool/1897256012
[junit] 2009-12-23 03:48:22,713 INFO [main] regionserver.HRegion(2213): merge completed. New region is TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,713 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,row_0100,1261540101208', STARTKEY => 'row_0100', ENDKEY => 'row_0600', ENCODED => 1147554832, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-23 03:48:22,714 DEBUG [main] util.Merge(319): Removing region: REGION => {NAME => 'TestMergeTool,,1261540083850', STARTKEY => '', ENDKEY => '', ENCODED => 1897256012, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}} from .META.,,1
[junit] 2009-12-23 03:48:22,715 INFO [main] util.Merge(255): Adding REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, 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] 2009-12-23 03:48:22,715 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261540102408: compactions & flushes disabled
[junit] 2009-12-23 03:48:22,715 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,716 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,716 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-23 03:48:22,716 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,716 DEBUG [main] regionserver.HRegion(454): Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-12-23 03:48:22,717 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-12-23 03:48:22,717 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region -ROOT-,,0
[junit] 2009-12-23 03:48:22,717 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-23 03:48:22,717 INFO [main] regionserver.HRegion(490): Closed -ROOT-,,0
[junit] 2009-12-23 03:48:22,718 INFO [main] util.MetaUtils(148): CLOSING META .META.,,1
[junit] 2009-12-23 03:48:22,718 DEBUG [main] regionserver.HRegion(454): Closing .META.,,1: compactions & flushes disabled
[junit] 2009-12-23 03:48:22,718 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-12-23 03:48:22,718 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region .META.,,1
[junit] 2009-12-23 03:48:22,719 DEBUG [main] regionserver.HRegion(895): Started memstore flush for region .META.,,1. Current region memstore size 968
[junit] 2009-12-23 03:48:22,748 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/historian/7124521150580429384, entries=2, sequenceid=34, memsize=280, filesize=505 to .META.,,1
[junit] 2009-12-23 03:48:22,777 DEBUG [main] regionserver.Store(564): Added hdfs://localhost:48623/user/hudson/.META./1028785192/info/372924957441297594, entries=3, sequenceid=34, memsize=688, filesize=826 to .META.,,1
[junit] 2009-12-23 03:48:22,777 DEBUG [main] regionserver.HRegion(971): Finished memstore flush of ~968 for region .META.,,1 in 58ms, sequence id=34, compaction requested=true
[junit] 2009-12-23 03:48:22,777 DEBUG [main] regionserver.Store(482): closed historian
[junit] 2009-12-23 03:48:22,778 DEBUG [main] regionserver.Store(482): closed info
[junit] 2009-12-23 03:48:22,778 INFO [main] regionserver.HRegion(490): Closed .META.,,1
[junit] 2009-12-23 03:48:22,809 WARN [main] conf.Configuration(616): fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-23 03:48:22,810 WARN [main] conf.Configuration(616): fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-23 03:48:22,810 WARN [main] conf.Configuration(616): fs.checkpoint.edits.dir is deprecated. Instead, use dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-23 03:48:22,810 WARN [main] conf.Configuration(616): fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-23 03:48:22,823 INFO [main] wal.HLog(321): Roll /user/hudson/.logs_1261540101873/hlog.dat.1261540101883, entries=6, calcsize=1083, filesize=1007. New hlog /user/hudson/.logs_1261540101873/hlog.dat.1261540102793
[junit] 2009-12-23 03:48:22,823 DEBUG [main] wal.HLog(330): Last sequence written is empty. Deleting all old hlogs
[junit] 2009-12-23 03:48:22,824 INFO [main] wal.HLog(490): removing old hlog file /user/hudson/.logs_1261540101873/hlog.dat.1261540101883 whose highest sequence/edit id is 33
[junit] 2009-12-23 03:48:22,833 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
[junit] 2009-12-23 03:48:22,834 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
[junit] 2009-12-23 03:48:22,834 DEBUG [main] wal.HLog(535): closing hlog writer in hdfs://localhost:48623/user/hudson/.logs_1261540101873
[junit] 2009-12-23 03:48:22,857 DEBUG [main] regionserver.HRegion(1885): Opening region: REGION => {NAME => 'TestMergeTool,,1261540102408', STARTKEY => '', ENDKEY => '', ENCODED => 1888871559, TABLE => {{NAME => 'TestMergeTool', FAMILIES => [{NAME => 'contents', COMPRESSION => 'NONE', VERSIONS => '3', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}}
[junit] 2009-12-23 03:48:22,858 DEBUG [main] regionserver.HRegion(265): Opening region TestMergeTool,,1261540102408, encoded=1888871559
[junit] 2009-12-23 03:48:22,864 DEBUG [main] regionserver.Store(417): loaded /user/hudson/TestMergeTool/1888871559/contents/4485189901783544259, isReference=false, sequence id=7, length=929, majorCompaction=false
[junit] 2009-12-23 03:48:22,866 INFO [main] regionserver.HRegion(343): region TestMergeTool,,1261540102408/1888871559 available; sequence id is 8
[junit] 2009-12-23 03:48:22,871 DEBUG [main] regionserver.HRegion(454): Closing TestMergeTool,,1261540102408: compactions & flushes disabled
[junit] 2009-12-23 03:48:22,871 DEBUG [main] regionserver.HRegion(471): Updates disabled for region, no outstanding scanners on TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,872 DEBUG [main] regionserver.HRegion(478): No more row locks outstanding on region TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,872 DEBUG [main] regionserver.Store(482): closed contents
[junit] 2009-12-23 03:48:22,872 INFO [main] regionserver.HRegion(490): Closed TestMergeTool,,1261540102408
[junit] 2009-12-23 03:48:22,872 INFO [main] util.TestMergeTool(186): Verified merging regions 0+1+2+3 and 4
[junit] 2009-12-23 03:48:22,872 DEBUG [main.logSyncer] wal.HLog$LogSyncer(704): main.logSyncerinterrupted while waiting for sync requests
[junit] 2009-12-23 03:48:22,873 INFO [main.logSyncer] wal.HLog$LogSyncer(708): main.logSyncer exiting
[junit] 2009-12-23 03:48:22,873 DEBUG [main] wal.HLog(535): closing hlog writer in /tmp/.logs_1261540094128
[junit] 2009-12-23 03:48:23,902 INFO [main] hbase.HBaseTestCase(627): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-12-23 03:48:24,011 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1df2964] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:49057, storageID=DS-573717694-67.195.138.9-49057-1261540086752, infoPort=34369, ipcPort=36108):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] 2009-12-23 03:48:24,116 WARN [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@9300cc] datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:34972, storageID=DS-469890356-67.195.138.9-34972-1261540086036, infoPort=60432, ipcPort=44611):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] 2009-12-23 03:48:24,218 WARN [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@5eb489] namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2009-12-23 03:48:24,218 WARN [org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor@3a1ec6] namenode.DecommissionManager$Monitor(67): Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2009-12-23 03:48:24,229 INFO [main] hbase.HBaseTestCase(638): Shutting down FileSystem
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 20.554 sec
[junit] 2009-12-23 03:48:25,276 DEBUG [HCM.shutdownHook] zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-12-23 03:48:25,640 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:228)
[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:83)
[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.077 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:504: Tests failed!
Total time: 28 minutes 11 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 #1043
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1043/changes>
Changes:
[apurtell] HBASE-1982 [EC2] Handle potentially large and uneven instance startup times
------------------------------------------
[...truncated 1025304 lines...]
[copy] Copying 830 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
[copy] Copying 5 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copying 548 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copied 121 empty directories to 4 empty directories under <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
init:
jspc:
compile-core:
[javac] Compiling 4 source files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/classes>
[clover] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover] Clover: Open Source License registered to Apache.
[clover] Updating existing database at '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'.>
[clover] Processing files at 1.6 source level.
[clover] Clover all over. Instrumented 3 files (3 packages).
[clover] Elapsed time = 0.927 secs. (3.236 files/sec, 418.554 srclines/sec)
compile-contrib:
compile:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
compile:
jar:
[jar] Building jar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev.jar>
javadoc:
[javadoc] Generating Javadoc
[javadoc] Javadoc execution
[javadoc] Loading source files for package org.apache.hadoop.hbase...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.filter...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io.hfile...
[javadoc] Loading source files for package org.apache.hadoop.hbase.ipc...
[javadoc] Loading source files for package org.apache.hadoop.hbase.mapreduce...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics.file...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.wal...
[javadoc] Loading source files for package org.apache.hadoop.hbase.thrift...
[javadoc] Loading source files for package org.apache.hadoop.hbase.util...
[javadoc] Loading source files for package org.apache.hadoop.hbase.zookeeper...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.model...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.protobuf.generated...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.consumer...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.producer...
[javadoc] Constructing Javadoc information...
[javadoc] Standard Doclet version 1.6.0_11
[javadoc] Building tree for all the packages and classes...
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/MultiTableOutputFormat.java>:62: warning - Tag @link: reference not found: IndexBuilder
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/InternalScanner.java>:59: warning - @param argument "results" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:104: warning - Tag @link: can't find getReader(org.apache.hadoop.fs.FileSystem,
[javadoc] org.apache.hadoop.fs.Path, org.apache.hadoop.conf.Configuration) in org.apache.hadoop.hbase.regionserver.wal.HLog
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:361: warning - @param argument "keyClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:361: warning - @param argument "valueClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:386: warning - @param argument "keyClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:386: warning - @param argument "valueClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] Building index for all the packages and classes...
[javadoc] Building index for all classes...
[javadoc] Generating <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/docs/api/stylesheet.css...>
[javadoc] 21 warnings
package:
[echo] Be sure to run 'docs' target before this one else package will be missing site documentation
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
package:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
jar:
[echo] contrib: stargate
war:
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/lib>
[war] Building war: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/hbase-0.21.0-dev-stargate.war>
package:
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate>
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate/lib>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
jar:
[echo] contrib: transactional
compile-examples:
jar-examples:
package:
[copy] Copying 829 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
tar:
[tar] Building tar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1043/artifact/trunk/build/hbase-0.21.0-dev.tar.gz>
BUILD SUCCESSFUL
Total time: 40 minutes 21 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
ERROR: Failed to archive test reports
hudson.util.IOException2: remote file operation failed
at hudson.FilePath.act(FilePath.java:672)
at hudson.FilePath.act(FilePath.java:660)
at hudson.tasks.junit.JUnitResultArchiver.perform(JUnitResultArchiver.java:117)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:19)
at hudson.model.AbstractBuild$AbstractRunner.perform(AbstractBuild.java:480)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:466)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:454)
at hudson.model.Build$RunnerImpl.post2(Build.java:181)
at hudson.model.AbstractBuild$AbstractRunner.post(AbstractBuild.java:438)
at hudson.model.Run.run(Run.java:1143)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:123)
Caused by: java.io.IOException: Remote call failed
at hudson.remoting.Channel.call(Channel.java:558)
at hudson.FilePath.act(FilePath.java:667)
... 12 more
Caused by: java.lang.OutOfMemoryError: Java heap space
at java.util.Arrays.copyOf(Arrays.java:2786)
at java.io.ByteArrayOutputStream.write(ByteArrayOutputStream.java:94)
at java.io.ObjectOutputStream$BlockDataOutputStream.drain(ObjectOutputStream.java:1838)
at java.io.ObjectOutputStream$BlockDataOutputStream.setBlockDataMode(ObjectOutputStream.java:1747)
at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1161)
at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:326)
at hudson.remoting.UserRequest._serialize(UserRequest.java:141)
at hudson.remoting.UserRequest.serialize(UserRequest.java:150)
at hudson.remoting.UserRequest.perform(UserRequest.java:112)
at hudson.remoting.UserRequest.perform(UserRequest.java:48)
at hudson.remoting.Request$2.run(Request.java:236)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
Publishing Clover coverage report...
Publishing Clover HTML report...
Publishing Clover XML report...
Publishing Clover coverage results...
Build failed in Hudson: HBase-Patch #1042
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1042/changes>
Changes:
[jdcryans] HBASE-2054 memstore size 0 is >= than blocking -2.0g size
[jdcryans] HBASE-2054 memstore size 0 is >= than blocking -2.0g size
[apurtell] HBASE-2028 Add HTable.incrementColumnValue support to shell
[apurtell] HBASE-2060 Missing closing tag in mapreduce package info
------------------------------------------
[...truncated 1024816 lines...]
compile-examples:
jar-examples:
package:
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/transactional>
[copy] Copying 2 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/transactional>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[copy] Copying 12 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[copy] Copied 10 empty directories to 1 empty directory under <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/lib>
[copy] Copying 28 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/lib>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/conf>
[copy] Copying 6 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/conf>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/bin>
[copy] Copying 15 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/bin>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
[copy] Copying 830 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
[copy] Copying 5 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copying 547 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copied 121 empty directories to 4 empty directories under <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
init:
jspc:
compile-core:
[javac] Compiling 4 source files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/classes>
[clover] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover] Clover: Open Source License registered to Apache.
[clover] Updating existing database at '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'.>
[clover] Processing files at 1.6 source level.
[clover] Clover all over. Instrumented 3 files (3 packages).
[clover] Elapsed time = 0.899 secs. (3.337 files/sec, 431.591 srclines/sec)
compile-contrib:
compile:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
compile:
jar:
[jar] Building jar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev.jar>
javadoc:
[javadoc] Generating Javadoc
[javadoc] Javadoc execution
[javadoc] Loading source files for package org.apache.hadoop.hbase...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.filter...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io.hfile...
[javadoc] Loading source files for package org.apache.hadoop.hbase.ipc...
[javadoc] Loading source files for package org.apache.hadoop.hbase.mapreduce...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics.file...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.wal...
[javadoc] Loading source files for package org.apache.hadoop.hbase.thrift...
[javadoc] Loading source files for package org.apache.hadoop.hbase.util...
[javadoc] Loading source files for package org.apache.hadoop.hbase.zookeeper...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.model...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.protobuf.generated...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.consumer...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.producer...
[javadoc] Constructing Javadoc information...
[javadoc] Standard Doclet version 1.6.0_11
[javadoc] Building tree for all the packages and classes...
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/MultiTableOutputFormat.java>:62: warning - Tag @link: reference not found: IndexBuilder
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/InternalScanner.java>:59: warning - @param argument "results" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:104: warning - Tag @link: can't find getReader(org.apache.hadoop.fs.FileSystem,
[javadoc] org.apache.hadoop.fs.Path, org.apache.hadoop.conf.Configuration) in org.apache.hadoop.hbase.regionserver.wal.HLog
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:361: warning - @param argument "keyClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:361: warning - @param argument "valueClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:386: warning - @param argument "keyClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:386: warning - @param argument "valueClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] Building index for all the packages and classes...
[javadoc] Building index for all classes...
[javadoc] Generating <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/docs/api/stylesheet.css...>
[javadoc] 21 warnings
package:
[echo] Be sure to run 'docs' target before this one else package will be missing site documentation
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
package:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
jar:
[echo] contrib: stargate
war:
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/lib>
[war] Building war: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/hbase-0.21.0-dev-stargate.war>
package:
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate>
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate/lib>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
jar:
[echo] contrib: transactional
compile-examples:
jar-examples:
package:
[copy] Copying 829 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
tar:
[tar] Building tar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1042/artifact/trunk/build/hbase-0.21.0-dev.tar.gz>
BUILD SUCCESSFUL
Total time: 37 minutes 24 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
ERROR: Failed to archive test reports
hudson.util.IOException2: remote file operation failed
at hudson.FilePath.act(FilePath.java:672)
at hudson.FilePath.act(FilePath.java:660)
at hudson.tasks.junit.JUnitResultArchiver.perform(JUnitResultArchiver.java:117)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:19)
at hudson.model.AbstractBuild$AbstractRunner.perform(AbstractBuild.java:480)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:466)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:454)
at hudson.model.Build$RunnerImpl.post2(Build.java:181)
at hudson.model.AbstractBuild$AbstractRunner.post(AbstractBuild.java:438)
at hudson.model.Run.run(Run.java:1143)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:123)
Caused by: java.io.IOException: Remote call failed
at hudson.remoting.Channel.call(Channel.java:558)
at hudson.FilePath.act(FilePath.java:667)
... 12 more
Caused by: java.lang.OutOfMemoryError: Java heap space
Publishing Clover coverage report...
Publishing Clover HTML report...
Publishing Clover XML report...
Publishing Clover coverage results...
Build failed in Hudson: HBase-Patch #1041
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1041/changes>
Changes:
[apurtell] HBASE-2059 Break out WAL reader and writer impl from HLog
[apurtell] HBASE-2032 [EC2] Add support for installation of user packages; update readme
------------------------------------------
[...truncated 1025406 lines...]
compile-examples:
jar-examples:
package:
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/transactional>
[copy] Copying 2 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/transactional>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[copy] Copying 12 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[copy] Copied 10 empty directories to 1 empty directory under <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/webapps>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/lib>
[copy] Copying 28 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/lib>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/conf>
[copy] Copying 6 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/conf>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/bin>
[copy] Copying 15 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/bin>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
[copy] Copying 830 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
[copy] Copying 5 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
[mkdir] Created dir: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copying 547 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
[copy] Copied 121 empty directories to 4 empty directories under <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/src>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
init:
jspc:
compile-core:
[javac] Compiling 4 source files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/classes>
[clover] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover] Clover: Open Source License registered to Apache.
[clover] Updating existing database at '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/clover/db/hbase_coverage.db'.>
[clover] Processing files at 1.6 source level.
[clover] Clover all over. Instrumented 3 files (3 packages).
[clover] Elapsed time = 0.863 secs. (3.476 files/sec, 449.594 srclines/sec)
compile-contrib:
compile:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
compile:
jar:
[jar] Building jar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev.jar>
javadoc:
[javadoc] Generating Javadoc
[javadoc] Javadoc execution
[javadoc] Loading source files for package org.apache.hadoop.hbase...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.filter...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io...
[javadoc] Loading source files for package org.apache.hadoop.hbase.io.hfile...
[javadoc] Loading source files for package org.apache.hadoop.hbase.ipc...
[javadoc] Loading source files for package org.apache.hadoop.hbase.mapreduce...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master...
[javadoc] Loading source files for package org.apache.hadoop.hbase.master.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.metrics.file...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.metrics...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.wal...
[javadoc] Loading source files for package org.apache.hadoop.hbase.thrift...
[javadoc] Loading source files for package org.apache.hadoop.hbase.util...
[javadoc] Loading source files for package org.apache.hadoop.hbase.zookeeper...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.client.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.tableindexed...
[javadoc] Loading source files for package org.apache.hadoop.hbase.regionserver.transactional...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.client...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.model...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.protobuf.generated...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.consumer...
[javadoc] Loading source files for package org.apache.hadoop.hbase.stargate.provider.producer...
[javadoc] Constructing Javadoc information...
[javadoc] Standard Doclet version 1.6.0_11
[javadoc] Building tree for all the packages and classes...
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions()}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "123" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see:illegal character: "64" in "{@link #setMaxVersions(int)}"
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:192: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions()}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/client/Scan.java>:207: warning - Tag @see: reference not found: {@link #setMaxVersions(int)}
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/MultiTableOutputFormat.java>:62: warning - Tag @link: reference not found: IndexBuilder
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/InternalScanner.java>:59: warning - @param argument "results" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:104: warning - Tag @link: can't find getReader(org.apache.hadoop.fs.FileSystem,
[javadoc] org.apache.hadoop.fs.Path, org.apache.hadoop.conf.Configuration) in org.apache.hadoop.hbase.regionserver.wal.HLog
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:361: warning - @param argument "keyClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:361: warning - @param argument "valueClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:386: warning - @param argument "keyClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/regionserver/wal/HLog.java>:386: warning - @param argument "valueClass" is not a parameter name.
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/java/org/apache/hadoop/hbase/mapreduce/package-info.java>:171: warning - Tag @link: reference not found: SimpleTotalOrderPartitioner
[javadoc] Building index for all the packages and classes...
[javadoc] Building index for all classes...
[javadoc] Generating <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/docs/api/stylesheet.css...>
[javadoc] 21 warnings
package:
[echo] Be sure to run 'docs' target before this one else package will be missing site documentation
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev>
package:
check-contrib:
init:
[echo] contrib: stargate
init-contrib:
compile-jar:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/classes>
compile-war:
[javac] Compiling 1 source file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/warClasses>
compile:
[echo] contrib: stargate
jar:
[echo] contrib: stargate
war:
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/lib>
[war] Building war: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/stargate/hbase-0.21.0-dev-stargate.war>
package:
[copy] Copying 1 file to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate>
[copy] Copying 10 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/contrib/stargate/lib>
clover.setup:
[clover-setup] Clover Version 2.4.3, built on March 09 2009 (build-756)
[clover-setup] Loaded from: /home/hudson/tools/clover/latest/lib/clover.jar
[clover-setup] Clover: Open Source License registered to Apache.
[clover-setup] Clover is enabled with initstring '<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/contrib/transactional/test/clover/db/hbase_coverage.db'>
clover.info:
clover:
check-contrib:
init:
[echo] contrib: transactional
init-contrib:
compile:
[echo] contrib: transactional
jar:
[echo] contrib: transactional
compile-examples:
jar-examples:
package:
[copy] Copying 829 files to <http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/hbase-0.21.0-dev/docs>
tar:
[tar] Building tar: <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1041/artifact/trunk/build/hbase-0.21.0-dev.tar.gz>
BUILD SUCCESSFUL
Total time: 39 minutes 14 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
ERROR: Failed to archive test reports
hudson.util.IOException2: remote file operation failed
at hudson.FilePath.act(FilePath.java:672)
at hudson.FilePath.act(FilePath.java:660)
at hudson.tasks.junit.JUnitResultArchiver.perform(JUnitResultArchiver.java:117)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:19)
at hudson.model.AbstractBuild$AbstractRunner.perform(AbstractBuild.java:480)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:466)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:454)
at hudson.model.Build$RunnerImpl.post2(Build.java:181)
at hudson.model.AbstractBuild$AbstractRunner.post(AbstractBuild.java:438)
at hudson.model.Run.run(Run.java:1143)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:123)
Caused by: java.io.IOException: Remote call failed
at hudson.remoting.Channel.call(Channel.java:558)
at hudson.FilePath.act(FilePath.java:667)
... 12 more
Caused by: java.lang.OutOfMemoryError: Java heap space
Publishing Clover coverage report...
Publishing Clover HTML report...
Publishing Clover XML report...
Publishing Clover coverage results...