See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/1055/changes>
Changes:
[apurtell] HBASE-2084 [EC2] JAVA_HOME handling broken
[apurtell] increase region server heap size to 2000m default
[stack] HBASE-2036 Use Configuration instead of HBaseConfiguration
------------------------------------------
[...truncated 123830 lines...]
[junit] 2009-12-31 21:49:25,873 WARN [main] namenode.FSNamesystem(348):
Scheme is undefined for
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/name2>
[junit] 2009-12-31 21:49:25,873 WARN [main] namenode.FSNamesystem(349):
Please check your file system configuration in hdfs-site.xml
[junit] 2009-12-31 21:49:26,013 WARN [main] conf.Configuration(326):
slave.host.name is deprecated. Instead, use mapreduce.tasktracker.host.name
[junit] 2009-12-31 21:49:26,568 WARN [main] namenode.FSNamesystem(348):
Scheme is undefined for
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/name1>
[junit] 2009-12-31 21:49:26,569 WARN [main] namenode.FSNamesystem(349):
Please check your file system configuration in hdfs-site.xml
[junit] 2009-12-31 21:49:26,569 WARN [main] namenode.FSNamesystem(348):
Scheme is undefined for
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/name2>
[junit] 2009-12-31 21:49:26,570 WARN [main] namenode.FSNamesystem(349):
Please check your file system configuration in hdfs-site.xml
[junit] 2009-12-31 21:49:26,571 WARN [main] namenode.FSNamesystem(348):
Scheme is undefined for
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/name1>
[junit] 2009-12-31 21:49:26,572 WARN [main] namenode.FSNamesystem(349):
Please check your file system configuration in hdfs-site.xml
[junit] 2009-12-31 21:49:26,572 WARN [main] namenode.FSNamesystem(348):
Scheme is undefined for
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/name2>
[junit] 2009-12-31 21:49:26,572 WARN [main] namenode.FSNamesystem(349):
Please check your file system configuration in hdfs-site.xml
[junit] 2009-12-31 21:49:26.639::INFO: Logging to STDERR via
org.mortbay.log.StdErrLog
[junit] 2009-12-31 21:49:26.691::INFO: jetty-6.1.14
[junit] 2009-12-31 21:49:26.761::INFO: Extract
jar:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-hdfs-0.21.0-dev-r836012.jar!/webapps/hdfs>
to /tmp/Jetty_localhost_47223_hdfs____.p30h52/webapp
[junit] 2009-12-31 21:49:27.270::INFO: Started
selectchannelconnec...@localhost:47223
[junit] Starting DataNode 0 with dfs.data.dir:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data2>
[junit] 2009-12-31 21:49:27.770::INFO: jetty-6.1.14
[junit] 2009-12-31 21:49:27.776::INFO: Extract
jar:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-hdfs-0.21.0-dev-r836012.jar!/webapps/datanode>
to /tmp/Jetty_localhost_57821_datanode____c8lh4s/webapp
[junit] 2009-12-31 21:49:27.940::INFO: Started
selectchannelconnec...@localhost:57821
[junit] Starting DataNode 1 with dfs.data.dir:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data4>
[junit] 2009-12-31 21:49:28.404::INFO: jetty-6.1.14
[junit] 2009-12-31 21:49:28.409::INFO: Extract
jar:<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-hdfs-0.21.0-dev-r836012.jar!/webapps/datanode>
to /tmp/Jetty_localhost_58486_datanode____.saroiy/webapp
[junit] 2009-12-31 21:49:28.536::INFO: Started
selectchannelconnec...@localhost:58486
[junit] 2009-12-31 21:49:28,619 WARN [main] conf.Configuration(326):
mapred.task.id is deprecated. Instead, use mapreduce.task.attempt.id
[junit] fs=DFS[DFSClient[clientName=DFSClient_1079168929,
ugi=hudson,hudson]]
[junit] 2009-12-31 21:49:28,807 DEBUG [main] util.FSUtils(213): Created
version file at hdfs://localhost:48781/user/hudson set its version at:7
[junit] 2009-12-31 21:49:28,843 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:28,863 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:28,863 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:28,864 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:28,864 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:28,865 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:28,894 INFO [main] wal.HLog(352): New hlog
/user/hudson/-ROOT-/70236052/.logs/hlog.dat.1262296168843
[junit] 2009-12-31 21:49:28,896 DEBUG [main] regionserver.HRegion(266):
Opening region -ROOT-,,0, encoded=70236052
[junit] 2009-12-31 21:49:28,951 INFO [main] regionserver.HRegion(344):
region -ROOT-,,0/70236052 available; sequence id is 0
[junit] 2009-12-31 21:49:28,974 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:28,992 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:28,992 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:28,993 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:28,993 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:28,994 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:29,002 INFO [main] wal.HLog(352): New hlog
/user/hudson/.META./1028785192/.logs/hlog.dat.1262296168974
[junit] 2009-12-31 21:49:29,003 DEBUG [main] regionserver.HRegion(266):
Opening region .META.,,1, encoded=1028785192
[junit] 2009-12-31 21:49:29,057 INFO [main] regionserver.HRegion(344):
region .META.,,1/1028785192 available; sequence id is 0
[junit] 2009-12-31 21:49:29,077 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:29,093 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:29,094 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:29,094 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:29,095 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:29,095 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:29,106 INFO [main] wal.HLog(352): New hlog
/user/hudson/TestMergeTool/1426858884/.logs/hlog.dat.1262296169078
[junit] 2009-12-31 21:49:29,106 DEBUG [main] regionserver.HRegion(266):
Opening region TestMergeTool,row_0200,1262296165673, encoded=1426858884
[junit] 2009-12-31 21:49:29,145 INFO [main] regionserver.HRegion(344):
region TestMergeTool,row_0200,1262296165673/1426858884 available; sequence id
is 0
[junit] 2009-12-31 21:49:29,165 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:29,181 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:29,181 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:29,182 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:29,182 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:29,183 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:29,198 INFO [main] wal.HLog(352): New hlog
/user/hudson/TestMergeTool/1300191893/.logs/hlog.dat.1262296169165
[junit] 2009-12-31 21:49:29,199 DEBUG [main] regionserver.HRegion(266):
Opening region TestMergeTool,row_0250,1262296165674, encoded=1300191893
[junit] 2009-12-31 21:49:29,237 INFO [main] regionserver.HRegion(344):
region TestMergeTool,row_0250,1262296165674/1300191893 available; sequence id
is 0
[junit] 2009-12-31 21:49:29,256 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:29,272 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:29,272 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:29,273 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:29,273 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:29,274 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:29,285 INFO [main] wal.HLog(352): New hlog
/user/hudson/TestMergeTool/1473006481/.logs/hlog.dat.1262296169257
[junit] 2009-12-31 21:49:29,285 DEBUG [main] regionserver.HRegion(266):
Opening region TestMergeTool,row_0100,1262296165674, encoded=1473006481
[junit] 2009-12-31 21:49:29,420 INFO [main] regionserver.HRegion(344):
region TestMergeTool,row_0100,1262296165674/1473006481 available; sequence id
is 0
[junit] 2009-12-31 21:49:29,456 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:29,467 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:29,467 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:29,467 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:29,468 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:29,468 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:29,476 INFO [main] wal.HLog(352): New hlog
/user/hudson/TestMergeTool/741810422/.logs/hlog.dat.1262296169456
[junit] 2009-12-31 21:49:29,476 DEBUG [main] regionserver.HRegion(266):
Opening region TestMergeTool,row_0500,1262296165674, encoded=741810422
[junit] 2009-12-31 21:49:29,547 INFO [main] regionserver.HRegion(344):
region TestMergeTool,row_0500,1262296165674/741810422 available; sequence id is 0
[junit] 2009-12-31 21:49:29,579 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:29,590 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:29,590 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:29,590 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:29,591 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:29,591 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:29,599 INFO [main] wal.HLog(352): New hlog
/user/hudson/TestMergeTool/537076701/.logs/hlog.dat.1262296169579
[junit] 2009-12-31 21:49:29,600 DEBUG [main] regionserver.HRegion(266):
Opening region TestMergeTool,,1262296165674, encoded=537076701
[junit] 2009-12-31 21:49:29,663 INFO [main] regionserver.HRegion(344):
region TestMergeTool,,1262296165674/537076701 available; sequence id is 0
[junit] 2009-12-31 21:49:29,665 DEBUG [main] regionserver.HRegion(455):
Closing .META.,,1: compactions & flushes disabled
[junit] 2009-12-31 21:49:29,665 DEBUG [main] regionserver.HRegion(472):
Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-12-31 21:49:29,665 DEBUG [main] regionserver.HRegion(479): No
more row locks outstanding on region .META.,,1
[junit] 2009-12-31 21:49:29,666 DEBUG [main] regionserver.HRegion(896):
Started memstore flush for region .META.,,1. Current region memstore size 2.2k
[junit] 2009-12-31 21:49:29,788 INFO [main] regionserver.StoreFile(241):
Allocating LruBlockCache with maximum size 98.6m
[junit] 2009-12-31 21:49:29,806 DEBUG [main] regionserver.Store(564): Added
hdfs://localhost:48781/user/hudson/.META./1028785192/info/5792011100566435481,
entries=5, sequenceid=6, memsize=2.2k, filesize=2.2k to .META.,,1
[junit] 2009-12-31 21:49:29,806 DEBUG [main] regionserver.HRegion(972):
Finished memstore flush of ~2.2k for region .META.,,1 in 141ms, sequence id=6,
compaction requested=false
[junit] 2009-12-31 21:49:29,807 DEBUG [main] regionserver.Store(482):
closed historian
[junit] 2009-12-31 21:49:29,807 DEBUG [main] regionserver.Store(482):
closed info
[junit] 2009-12-31 21:49:29,807 INFO [main] regionserver.HRegion(491):
Closed .META.,,1
[junit] 2009-12-31 21:49:29,808 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:29,808 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:29,808 DEBUG [main] wal.HLog(564): closing hlog
writer in hdfs://localhost:48781/user/hudson/.META./1028785192/.logs
[junit] 2009-12-31 21:49:30,888 DEBUG [main] regionserver.HRegion(455):
Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-12-31 21:49:30,889 DEBUG [main] regionserver.HRegion(472):
Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-12-31 21:49:30,889 DEBUG [main] regionserver.HRegion(479): No
more row locks outstanding on region -ROOT-,,0
[junit] 2009-12-31 21:49:30,889 DEBUG [main] regionserver.HRegion(896):
Started memstore flush for region -ROOT-,,0. Current region memstore size 544
[junit] 2009-12-31 21:49:30,957 DEBUG [main] regionserver.Store(564): Added
hdfs://localhost:48781/user/hudson/-ROOT-/70236052/info/1373420627075787595,
entries=1, sequenceid=2, memsize=544, filesize=809 to -ROOT-,,0
[junit] 2009-12-31 21:49:30,957 DEBUG [main] regionserver.HRegion(972):
Finished memstore flush of ~544 for region -ROOT-,,0 in 68ms, sequence id=2,
compaction requested=false
[junit] 2009-12-31 21:49:30,958 DEBUG [main] regionserver.Store(482):
closed info
[junit] 2009-12-31 21:49:30,958 INFO [main] regionserver.HRegion(491):
Closed -ROOT-,,0
[junit] 2009-12-31 21:49:30,958 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:30,958 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:30,959 DEBUG [main] wal.HLog(564): closing hlog
writer in hdfs://localhost:48781/user/hudson/-ROOT-/70236052/.logs
[junit] 2009-12-31 21:49:32,014 DEBUG [main] regionserver.HRegion(455):
Closing TestMergeTool,row_0200,1262296165673: compactions & flushes disabled
[junit] 2009-12-31 21:49:32,014 DEBUG [main] regionserver.HRegion(472):
Updates disabled for region, no outstanding scanners on
TestMergeTool,row_0200,1262296165673
[junit] 2009-12-31 21:49:32,015 DEBUG [main] regionserver.HRegion(479): No
more row locks outstanding on region TestMergeTool,row_0200,1262296165673
[junit] 2009-12-31 21:49:32,015 DEBUG [main] regionserver.HRegion(896):
Started memstore flush for region TestMergeTool,row_0200,1262296165673. Current
region memstore size 256
[junit] 2009-12-31 21:49:32,199 DEBUG [main] regionserver.Store(564): Added
hdfs://localhost:48781/user/hudson/TestMergeTool/1426858884/contents/772025260913997483,
entries=2, sequenceid=3, memsize=256, filesize=425 to
TestMergeTool,row_0200,1262296165673
[junit] 2009-12-31 21:49:32,199 DEBUG [main] regionserver.HRegion(972):
Finished memstore flush of ~256 for region TestMergeTool,row_0200,1262296165673
in 184ms, sequence id=3, compaction requested=false
[junit] 2009-12-31 21:49:32,200 DEBUG [main] regionserver.Store(482):
closed contents
[junit] 2009-12-31 21:49:32,200 INFO [main] regionserver.HRegion(491):
Closed TestMergeTool,row_0200,1262296165673
[junit] 2009-12-31 21:49:32,201 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:32,201 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:32,201 DEBUG [main] wal.HLog(564): closing hlog
writer in hdfs://localhost:48781/user/hudson/TestMergeTool/1426858884/.logs
[junit] 2009-12-31 21:49:32,235 DEBUG [main] regionserver.HRegion(455):
Closing TestMergeTool,row_0250,1262296165674: compactions & flushes disabled
[junit] 2009-12-31 21:49:32,235 DEBUG [main] regionserver.HRegion(472):
Updates disabled for region, no outstanding scanners on
TestMergeTool,row_0250,1262296165674
[junit] 2009-12-31 21:49:32,235 DEBUG [main] regionserver.HRegion(479): No
more row locks outstanding on region TestMergeTool,row_0250,1262296165674
[junit] 2009-12-31 21:49:32,235 DEBUG [main] regionserver.HRegion(896):
Started memstore flush for region TestMergeTool,row_0250,1262296165674. Current
region memstore size 376
[junit] 2009-12-31 21:49:32,277 DEBUG [main] regionserver.Store(564): Added
hdfs://localhost:48781/user/hudson/TestMergeTool/1300191893/contents/8590966930996355414,
entries=3, sequenceid=4, memsize=376, filesize=469 to
TestMergeTool,row_0250,1262296165674
[junit] 2009-12-31 21:49:32,278 DEBUG [main] regionserver.HRegion(972):
Finished memstore flush of ~376 for region TestMergeTool,row_0250,1262296165674
in 43ms, sequence id=4, compaction requested=false
[junit] 2009-12-31 21:49:32,278 DEBUG [main] regionserver.Store(482):
closed contents
[junit] 2009-12-31 21:49:32,279 INFO [main] regionserver.HRegion(491):
Closed TestMergeTool,row_0250,1262296165674
[junit] 2009-12-31 21:49:32,279 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:32,279 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:32,280 DEBUG [main] wal.HLog(564): closing hlog
writer in hdfs://localhost:48781/user/hudson/TestMergeTool/1300191893/.logs
[junit] 2009-12-31 21:49:33,358 DEBUG [main] regionserver.HRegion(455):
Closing TestMergeTool,row_0100,1262296165674: compactions & flushes disabled
[junit] 2009-12-31 21:49:33,358 DEBUG [main] regionserver.HRegion(472):
Updates disabled for region, no outstanding scanners on
TestMergeTool,row_0100,1262296165674
[junit] 2009-12-31 21:49:33,359 DEBUG [main] regionserver.HRegion(479): No
more row locks outstanding on region TestMergeTool,row_0100,1262296165674
[junit] 2009-12-31 21:49:33,359 DEBUG [main] regionserver.HRegion(896):
Started memstore flush for region TestMergeTool,row_0100,1262296165674. Current
region memstore size 384
[junit] 2009-12-31 21:49:33,401 DEBUG [main] regionserver.Store(564): Added
hdfs://localhost:48781/user/hudson/TestMergeTool/1473006481/contents/3760940148277035060,
entries=3, sequenceid=5, memsize=384, filesize=471 to
TestMergeTool,row_0100,1262296165674
[junit] 2009-12-31 21:49:33,401 DEBUG [main] regionserver.HRegion(972):
Finished memstore flush of ~384 for region TestMergeTool,row_0100,1262296165674
in 42ms, sequence id=5, compaction requested=false
[junit] 2009-12-31 21:49:33,402 DEBUG [main] regionserver.Store(482):
closed contents
[junit] 2009-12-31 21:49:33,402 INFO [main] regionserver.HRegion(491):
Closed TestMergeTool,row_0100,1262296165674
[junit] 2009-12-31 21:49:33,402 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:33,403 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:33,403 DEBUG [main] wal.HLog(564): closing hlog
writer in hdfs://localhost:48781/user/hudson/TestMergeTool/1473006481/.logs
[junit] 2009-12-31 21:49:33,434 DEBUG [main] regionserver.HRegion(455):
Closing TestMergeTool,row_0500,1262296165674: compactions & flushes disabled
[junit] 2009-12-31 21:49:33,434 DEBUG [main] regionserver.HRegion(472):
Updates disabled for region, no outstanding scanners on
TestMergeTool,row_0500,1262296165674
[junit] 2009-12-31 21:49:33,434 DEBUG [main] regionserver.HRegion(479): No
more row locks outstanding on region TestMergeTool,row_0500,1262296165674
[junit] 2009-12-31 21:49:33,434 DEBUG [main] regionserver.HRegion(896):
Started memstore flush for region TestMergeTool,row_0500,1262296165674. Current
region memstore size 384
[junit] 2009-12-31 21:49:33,475 DEBUG [main] regionserver.Store(564): Added
hdfs://localhost:48781/user/hudson/TestMergeTool/741810422/contents/4392598328220521400,
entries=3, sequenceid=6, memsize=384, filesize=471 to
TestMergeTool,row_0500,1262296165674
[junit] 2009-12-31 21:49:33,475 DEBUG [main] regionserver.HRegion(972):
Finished memstore flush of ~384 for region TestMergeTool,row_0500,1262296165674
in 41ms, sequence id=6, compaction requested=false
[junit] 2009-12-31 21:49:33,476 DEBUG [main] regionserver.Store(482):
closed contents
[junit] 2009-12-31 21:49:33,476 INFO [main] regionserver.HRegion(491):
Closed TestMergeTool,row_0500,1262296165674
[junit] 2009-12-31 21:49:33,476 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:33,477 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:33,477 DEBUG [main] wal.HLog(564): closing hlog
writer in hdfs://localhost:48781/user/hudson/TestMergeTool/741810422/.logs
[junit] 2009-12-31 21:49:33,505 DEBUG [main] regionserver.HRegion(455):
Closing TestMergeTool,,1262296165674: compactions & flushes disabled
[junit] 2009-12-31 21:49:33,506 DEBUG [main] regionserver.HRegion(472):
Updates disabled for region, no outstanding scanners on
TestMergeTool,,1262296165674
[junit] 2009-12-31 21:49:33,506 DEBUG [main] regionserver.HRegion(479): No
more row locks outstanding on region TestMergeTool,,1262296165674
[junit] 2009-12-31 21:49:33,506 DEBUG [main] regionserver.HRegion(896):
Started memstore flush for region TestMergeTool,,1262296165674. Current region
memstore size 384
[junit] 2009-12-31 21:49:33,547 DEBUG [main] regionserver.Store(564): Added
hdfs://localhost:48781/user/hudson/TestMergeTool/537076701/contents/3330272730114305268,
entries=3, sequenceid=7, memsize=384, filesize=471 to
TestMergeTool,,1262296165674
[junit] 2009-12-31 21:49:33,547 DEBUG [main] regionserver.HRegion(972):
Finished memstore flush of ~384 for region TestMergeTool,,1262296165674 in
41ms, sequence id=7, compaction requested=false
[junit] 2009-12-31 21:49:33,547 DEBUG [main] regionserver.Store(482):
closed contents
[junit] 2009-12-31 21:49:33,548 INFO [main] regionserver.HRegion(491):
Closed TestMergeTool,,1262296165674
[junit] 2009-12-31 21:49:33,548 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:33,549 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:33,549 DEBUG [main] wal.HLog(564): closing hlog
writer in hdfs://localhost:48781/user/hudson/TestMergeTool/537076701/.logs
[junit] 2009-12-31 21:49:33,576 INFO [main] util.TestMergeTool(250):
Creating log /tmp/.logs_1262296173576
[junit] 2009-12-31 21:49:33,590 INFO [main] wal.HLog(274): HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=1, optionallogflushinternal=1000ms
[junit] 2009-12-31 21:49:33,600 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:33,601 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:33,601 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:33,602 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:33,602 WARN [main] hbase.HBaseConfiguration(59):
instantinating HBaseConfiguration() is deprecated. Please use
HBaseConfiguration#create(conf) to construct a plain Configuration
[junit] 2009-12-31 21:49:33,612 INFO [main] wal.HLog(352): New hlog
/tmp/.logs_1262296173576/hlog.dat.1262296173591
[junit] 2009-12-31 21:49:33,615 INFO [main] util.TestMergeTool(174):
merging regions 0 and 1
[junit] fs2=hdfs://localhost:48781
[junit] 2009-12-31 21:49:33,622 WARN [main] conf.Configuration(616):
mapred.used.genericoptionsparser is deprecated. Instead, use
mapreduce.client.genericoptionsparser.used
[junit] 2009-12-31 21:49:33,623 WARN [main] conf.Configuration(616):
mapred.used.genericoptionsparser is deprecated. Instead, use
mapreduce.client.genericoptionsparser.used
[junit] 2009-12-31 21:49:33,623 INFO [main] util.Merge(81): Verifying that
file system is available...
[junit] 2009-12-31 21:49:33,625 INFO [main] util.Merge(90): Verifying that
HBase is not running...
[junit] 2009-12-31 21:49:33,640 WARN [main] conf.Configuration(616):
fs.checkpoint.size is deprecated. Instead, use dfs.namenode.checkpoint.size
[junit] 2009-12-31 21:49:33,640 WARN [main] conf.Configuration(616):
fs.checkpoint.dir is deprecated. Instead, use dfs.namenode.checkpoint.dir
[junit] 2009-12-31 21:49:33,641 WARN [main] conf.Configuration(616):
fs.checkpoint.edits.dir is deprecated. Instead, use
dfs.namenode.checkpoint.edits.dir
[junit] 2009-12-31 21:49:33,641 WARN [main] conf.Configuration(616):
fs.checkpoint.period is deprecated. Instead, use dfs.namenode.checkpoint.period
[junit] 2009-12-31 21:49:33,790 DEBUG [main]
zookeeper.ZooKeeperWrapper(392): Read ZNode /hbase/master got 67.195.138.9:60000
[junit] 2009-12-31 21:49:33,814 FATAL [main] util.Merge(93): HBase cluster
must be off-line.
[junit] 2009-12-31 21:49:33,814 DEBUG [main.logSyncer]
wal.HLog$LogSyncer(734): main.logSyncerinterrupted while waiting for sync
requests
[junit] 2009-12-31 21:49:33,815 INFO [main.logSyncer]
wal.HLog$LogSyncer(738): main.logSyncer exiting
[junit] 2009-12-31 21:49:33,815 DEBUG [main] wal.HLog(564): closing hlog
writer in /tmp/.logs_1262296173576
[junit] 2009-12-31 21:49:33,853 INFO [main] hbase.HBaseTestCase(627):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-12-31 21:49:33,957 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1c8fb4b]
datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:52857,
storageID=DS-1217547157-67.195.138.9-52857-1262296168539, infoPort=58486,
ipcPort=38593):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-31 21:49:34,061 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@1250ff2]
datanode.DataXceiverServer(140): DatanodeRegistration(127.0.0.1:34901,
storageID=DS-512490309-67.195.138.9-34901-1262296167946, infoPort=57821,
ipcPort=38374):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-31 21:49:34,196 WARN
[org.apache.hadoop.hdfs.server.namenode.decommissionmanager$moni...@112e7f7]
namenode.DecommissionManager$Monitor(67): Monitor interrupted:
java.lang.InterruptedException: sleep interrupted
[junit] 2009-12-31 21:49:34,197 WARN
[org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@92dcdb]
namenode.FSNamesystem$ReplicationMonitor(2326): ReplicationMonitor thread
received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2009-12-31 21:49:34,210 INFO [main] hbase.HBaseTestCase(638):
Shutting down FileSystem
[junit] Tests run: 1, Failures: 1, Errors: 0, Time elapsed: 8.713 sec
[junit] 2009-12-31 21:49:34,607 DEBUG [HCM.shutdownHook]
zookeeper.ZooKeeperWrapper(632): Closed connection with ZooKeeper
[junit] Test org.apache.hadoop.hbase.util.TestMergeTool FAILED
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-12-31 21:49:34,990 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: 27 minutes 21 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
