See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/349/changes
Changes:
[nigel] Preparing for release 0.15.2
[nigel] HADOOP-2456. Hardcode English locale to prevent NumberFormatException
from occurring when starting the NameNode with certain locales. Contributed by
Matthias Friedrich.
[nigel] HADOOP-2453. Move CHANGES.txt comment to 0.16.0
------------------------------------------
[...truncated 48268 lines...]
[junit] 2007-12-29 13:20:17,356 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(880): closed result_table,,1198934398607/b (330763908/b)
[junit] 2007-12-29 13:20:17,357 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(880): closed result_table,,1198934398607/c (330763908/c)
[junit] 2007-12-29 13:20:17,357 INFO [IPC Server handler 2 on 60000]
hbase.HRegion(430): closed result_table,,1198934398607
[junit] 2007-12-29 13:20:17,357 DEBUG [IPC Server handler 2 on 60000]
hbase.HLog(388): closing log writer in /hbase/hregion_330763908/log
[junit] 2007-12-29 13:20:17,358 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r1,,1198934363198, startKey: <>, encodedName(1004432247) tableDesc: {name: r1,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, c:={name: c, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:38758,
startCode: 1198934363169
[junit] 2007-12-29 13:20:17,361 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r2,,1198934393554, startKey: <>, encodedName(132806885) tableDesc: {name: r2,
families: {d:={name: d, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:17,364 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:38758} complete
[junit] 2007-12-29 13:20:17,364 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-29 13:20:18,523 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(972): Added 1004432247/a/7585352402922746197 with 5 entries,
sequence id 33, and size 270.0 for r1,,1198934363198/a
[junit] 2007-12-29 13:20:22,274 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(2547): created table result_table
[junit] 2007-12-29 13:20:22,275 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing
a find...
[junit] 2007-12-29 13:20:22,289 DEBUG [main]
hbase.HConnectionManager$TableServers(782): no server address for regionname:
result_table,,1198934398607, startKey: <>, encodedName(330763908) tableDesc:
{name: result_table, families: {a:={name: a, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, b:={name:
b, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, c:={name: c, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
[junit] 2007-12-29 13:20:22,290 DEBUG [main]
hbase.HConnectionManager$TableServers(680): Sleeping. Table result_table not
currently being served.
[junit] 2007-12-29 13:20:22,961 INFO [IPC Server handler 0 on 60000]
hbase.HMaster(1858): assigning region result_table,,1198934398607 to the only
server 140.211.11.75:38758
[junit] 2007-12-29 13:20:22,962 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : result_table,,1198934398607
[junit] 2007-12-29 13:20:22,967 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198934398607/a (330763908/a) (no
reconstruction log)
[junit] 2007-12-29 13:20:22,969 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198934398607/a
(330763908/a) is -1
[junit] 2007-12-29 13:20:22,973 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198934398607/b (330763908/b) (no
reconstruction log)
[junit] 2007-12-29 13:20:22,975 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198934398607/b
(330763908/b) is -1
[junit] 2007-12-29 13:20:22,980 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198934398607/c (330763908/c) (no
reconstruction log)
[junit] 2007-12-29 13:20:22,982 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198934398607/c
(330763908/c) is -1
[junit] 2007-12-29 13:20:22,986 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198934398607/d (330763908/d) (no
reconstruction log)
[junit] 2007-12-29 13:20:22,988 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198934398607/d
(330763908/d) is -1
[junit] 2007-12-29 13:20:22,992 DEBUG [RegionServer:0.worker]
hbase.HStore(601): starting result_table,,1198934398607/e (330763908/e) (no
reconstruction log)
[junit] 2007-12-29 13:20:22,993 DEBUG [RegionServer:0.worker]
hbase.HStore(629): maximum sequence id for hstore result_table,,1198934398607/e
(330763908/e) is -1
[junit] 2007-12-29 13:20:22,994 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region result_table,,1198934398607 is 0
[junit] 2007-12-29 13:20:22,996 INFO [RegionServer:0.worker]
hbase.HRegion(328): region result_table,,1198934398607 available
[junit] 2007-12-29 13:20:23,971 DEBUG [IPC Server handler 3 on 60000]
hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN :
result_table,,1198934398607 from 140.211.11.75:38758
[junit] 2007-12-29 13:20:23,972 DEBUG [IPC Server handler 3 on 60000]
hbase.HMaster(1516): Received MSG_REPORT_OPEN : result_table,,1198934398607
from 140.211.11.75:38758
[junit] 2007-12-29 13:20:23,972 INFO [IPC Server handler 3 on 60000]
hbase.HMaster(1577): 140.211.11.75:38758 serving result_table,,1198934398607
[junit] 2007-12-29 13:20:23,972 DEBUG [HMaster] hbase.HMaster(1055): Main
processing loop: PendingOpenOperation from 140.211.11.75:38758
[junit] 2007-12-29 13:20:23,973 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2458): regionname: result_table,,1198934398607,
startKey: <>, encodedName(330763908) tableDesc: {name: result_table, families:
{a:={name: a, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, b:={name: b, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, c:={name:
c, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, d:={name: d, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, e:={name:
e, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}}} open on 140.211.11.75:38758
[junit] 2007-12-29 13:20:23,973 DEBUG [HMaster]
hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1,
onlineMetaRegions.size(): 1
[junit] 2007-12-29 13:20:23,973 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2472): updating row result_table,,1198934398607
in table .META.,,1 with startcode 1198934363169 and server 140.211.11.75:38758
[junit] 2007-12-29 13:20:25,359 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:38758}
[junit] 2007-12-29 13:20:25,384 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:25,386 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:38758} complete
[junit] 2007-12-29 13:20:27,310 DEBUG [main]
hbase.HConnectionManager$TableServers(689): Wake. Retry finding table
result_table
[junit] 2007-12-29 13:20:27,349 DEBUG [main]
hbase.HConnectionManager$TableServers(732): Found 1 region(s) for result_table
at address: 140.211.11.75:38758, regioninfo: regionname: .META.,,1, startKey:
<>, encodedName(1028785192) tableDesc: {name: .META., families: {info:={name:
info, max versions: 1, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}}}
[junit] 2007-12-29 13:20:27,349 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:38758}
[junit] 2007-12-29 13:20:27,364 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r1,,1198934363198, startKey: <>, encodedName(1004432247) tableDesc: {name: r1,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, c:={name: c, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:38758,
startCode: 1198934363169
[junit] 2007-12-29 13:20:27,366 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r2,,1198934393554, startKey: <>, encodedName(132806885) tableDesc: {name: r2,
families: {d:={name: d, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:27,368 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
result_table,,1198934398607, startKey: <>, encodedName(330763908) tableDesc:
{name: result_table, families: {a:={name: a, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, b:={name:
b, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, c:={name: c, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:27,371 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:38758} complete
[junit] 2007-12-29 13:20:27,371 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-29 13:20:28,124 WARN [IPC Server handler 3 on 38728]
dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove
/user/hudson/build/contrib/hbase/test/system because it does not exist
[junit] 2007-12-29 13:20:30,764 WARN [IPC Server handler 1 on 38728]
dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove
/user/hudson/build/contrib/hbase/test/system/job_local_1 because it does not
exist
[junit] 2007-12-29 13:20:30,765 WARN [main] mapred.JobClient(496): No job
jar file set. User classes may not be found. See JobConf(Class) or
JobConf#setJar(String).
[junit] 2007-12-29 13:20:30,796 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find...
[junit] 2007-12-29 13:20:30,812 DEBUG [main]
hbase.HConnectionManager$TableServers(763): Found 1 servers for table r1
[junit] 2007-12-29 13:20:30,846 DEBUG [main] mapred.TableInputFormat(175):
split: 0->r1,,
[junit] 2007-12-29 13:20:32,751 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(972): Added 1004432247/b/1590656782838317561 with 5 entries,
sequence id 33, and size 270.0 for r1,,1198934363198/b
[junit] 2007-12-29 13:20:35,367 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:38758}
[junit] 2007-12-29 13:20:35,392 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:35,394 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:38758} complete
[junit] 2007-12-29 13:20:37,098 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(1858): assigning region .META.,,1 to the only server
140.211.11.75:38758
[junit] 2007-12-29 13:20:37,099 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : .META.,,1
[junit] 2007-12-29 13:20:37,357 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:38758}
[junit] 2007-12-29 13:20:37,396 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r1,,1198934363198, startKey: <>, encodedName(1004432247) tableDesc: {name: r1,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, c:={name: c, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:38758,
startCode: 1198934363169
[junit] 2007-12-29 13:20:37,399 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r2,,1198934393554, startKey: <>, encodedName(132806885) tableDesc: {name: r2,
families: {d:={name: d, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:37,401 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
result_table,,1198934398607, startKey: <>, encodedName(330763908) tableDesc:
{name: result_table, families: {a:={name: a, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, b:={name:
b, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, c:={name: c, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:37,402 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:38758} complete
[junit] 2007-12-29 13:20:37,402 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-29 13:20:38,108 DEBUG [IPC Server handler 0 on 60000]
hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : .META.,,1 from
140.211.11.75:38758
[junit] java.io.IOException: Target
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/contrib/hbase/test/mapred/local/localRunner/job_local_1.xml
already exists
[junit] at org.apache.hadoop.fs.FileUtil.checkDest(FileUtil.java:246)
[junit] at org.apache.hadoop.fs.FileUtil.copy(FileUtil.java:125)
[junit] at org.apache.hadoop.fs.FileUtil.copy(FileUtil.java:116)
[junit] at
org.apache.hadoop.fs.FileSystem.copyToLocalFile(FileSystem.java:914)
[junit] at
org.apache.hadoop.fs.FileSystem.copyToLocalFile(FileSystem.java:895)
[junit] at
org.apache.hadoop.mapred.LocalJobRunner$Job.<init>(LocalJobRunner.java:86)
[junit] at
org.apache.hadoop.mapred.LocalJobRunner.submitJob(LocalJobRunner.java:281)
[junit] at
org.apache.hadoop.mapred.JobClient.submitJob(JobClient.java:558)
[junit] at org.apache.hadoop.mapred.JobClient.runJob(JobClient.java:753)
[junit] at
org.apache.hadoop.hbase.shell.algebra.TestTableJoinMapReduce.testTableJoinMapReduce(TestTableJoinMapReduce.java:191)
[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:585)
[junit] at junit.framework.TestCase.runTest(TestCase.java:154)
[junit] at junit.framework.TestCase.runBare(TestCase.java:127)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:124)
[junit] at junit.framework.TestResult.run(TestResult.java:109)
[junit] at junit.framework.TestCase.run(TestCase.java:118)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
[junit] at junit.framework.TestSuite.run(TestSuite.java:203)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:297)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:672)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:567)
[junit] 2007-12-29 13:20:41,134 ERROR [Thread-207] mapred.TaskTracker(954):
Caught exception: java.io.InterruptedIOException
[junit] at java.net.SocketOutputStream.socketWrite0(Native Method)
[junit] at
java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:92)
[junit] at
java.net.SocketOutputStream.write(SocketOutputStream.java:136)
[junit] at
org.apache.hadoop.ipc.Client$Connection$2.write(Client.java:199)
[junit] at
java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65)
[junit] at
java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123)
[junit] at java.io.DataOutputStream.flush(DataOutputStream.java:106)
[junit] at
org.apache.hadoop.ipc.Client$Connection.sendParam(Client.java:344)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:501)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198)
[junit] at org.apache.hadoop.mapred.$Proxy9.heartbeat(Unknown Source)
[junit] at
org.apache.hadoop.mapred.TaskTracker.transmitHeartBeat(TaskTracker.java:1004)
[junit] at
org.apache.hadoop.mapred.TaskTracker.offerService(TaskTracker.java:902)
[junit] at
org.apache.hadoop.mapred.TaskTracker.run(TaskTracker.java:1300)
[junit] at
org.apache.hadoop.mapred.MiniMRCluster$TaskTrackerRunner.run(MiniMRCluster.java:144)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] 2007-12-29 13:20:41,137 INFO [IPC Server handler 4 on 60000]
hbase.HMaster(1858): assigning region r1,,1198934363198 to the only server
140.211.11.75:38758
[junit] 2007-12-29 13:20:41,138 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : r1,,1198934363198
[junit] 2007-12-29 13:20:42,441 WARN [Task Commit Thread]
mapred.JobTracker$TaskCommitQueue(2032): Task Commit Thread exiting...
[junit] 2007-12-29 13:20:42,441 DEBUG [IPC Server handler 2 on 60000]
hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : r1,,1198934363198 from
140.211.11.75:38758
[junit] 2007-12-29 13:20:42,466 DEBUG [main] hbase.LocalHBaseCluster(212):
Shutting down HBase Cluster
[junit] 2007-12-29 13:20:42,466 INFO [main] hbase.HMaster(2526): Cluster
shutdown requested. Starting to quiesce servers
[junit] 2007-12-29 13:20:43,448 INFO [RegionServer:0]
hbase.HRegionServer(774): Got quiesce server message
[junit] 2007-12-29 13:20:43,448 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1153): MSG_REGIONSERVER_QUIESCE :
[junit] 2007-12-29 13:20:43,448 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1295): closing region r1,,1198934363198
[junit] 2007-12-29 13:20:45,376 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:38758}
[junit] 2007-12-29 13:20:45,396 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:38758, startCode: 1198934363169
[junit] 2007-12-29 13:20:45,398 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:38758} complete
[junit] 2007-12-29 13:20:46,028 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(972): Added 1004432247/c/4976254715571928525 with 5 entries,
sequence id 33, and size 310.0 for r1,,1198934363198/c
[junit] 2007-12-29 13:20:46,029 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region r1,,1198934363198 in
44386ms, sequenceid=33
[junit] 2007-12-29 13:20:46,030 DEBUG [RegionServer:0.compactor]
hbase.HStore(996): compaction for HStore r1,,1198934363198/a not needed.
[junit] 2007-12-29 13:20:46,030 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 558.0
[junit] 2007-12-29 13:20:46,030 DEBUG [RegionServer:0.compactor]
hbase.HStore(996): compaction for HStore r1,,1198934363198/b not needed.
[junit] 2007-12-29 13:20:46,030 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r1,,1198934363198/a (1004432247/a)
[junit] 2007-12-29 13:20:46,031 DEBUG [RegionServer:0.compactor]
hbase.HStore(996): compaction for HStore r1,,1198934363198/c not needed.
[junit] 2007-12-29 13:20:46,031 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region r1,,1198934363198 does not need compaction
[junit] 2007-12-29 13:20:46,031 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r1,,1198934363198/b (1004432247/b)
[junit] 2007-12-29 13:20:46,032 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r1,,1198934363198/c (1004432247/c)
[junit] 2007-12-29 13:20:46,032 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed r1,,1198934363198
[junit] 2007-12-29 13:20:46,033 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1295): closing region r2,,1198934393554
[junit] 2007-12-29 13:20:46,033 DEBUG [RegionServer:0.worker]
hbase.HRegion(837): Started memcache flush for region r2,,1198934393554. Size
132.0
[junit] 2007-12-29 13:20:47,365 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:38758}
[junit] 2007-12-29 13:20:47,379 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
r1,,1198934363198, startKey: <>, encodedName(1004432247) tableDesc: {name: r1,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, c:={name: c, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:38758,
startCode: 1198934363169
[junit] 2007-12-29 13:20:47,380 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:38758} complete
[junit] 2007-12-29 13:20:47,381 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-29 13:20:50,435 INFO [HMaster.leaseChecker]
hbase.Leases$Lease(342): HMaster.leaseChecker lease expired
1437218154/1437218154
[junit] 2007-12-29 13:20:50,436 INFO [HMaster.leaseChecker]
hbase.HMaster$ServerExpirer(3147): 140.211.11.75:38758 lease expired
[junit] 2007-12-29 13:20:50,515 DEBUG [HMaster] hbase.HMaster(1206):
telling root scanner to stop
[junit] 2007-12-29 13:20:50,515 DEBUG [HMaster] hbase.HMaster(1214):
telling meta scanner to stop
[junit] 2007-12-29 13:20:50,516 INFO [HMaster.rootScanner]
hbase.Chore(66): HMaster.rootScanner exiting
[junit] 2007-12-29 13:20:50,516 INFO [HMaster.metaScanner]
hbase.Chore(66): HMaster.metaScanner exiting
[junit] 2007-12-29 13:20:50,516 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1380): All user tables quiesced. Proceeding with shutdown
[junit] 2007-12-29 13:20:50,516 DEBUG [HMaster] hbase.HMaster(1222): meta
and root scanners notified
[junit] 2007-12-29 13:20:50,517 DEBUG [IPC Server handler 1 on 60000]
hbase.HMaster(1206): telling root scanner to stop
[junit] 2007-12-29 13:20:50,517 DEBUG [HMaster] hbase.HMaster(1206):
telling root scanner to stop
[junit] 2007-12-29 13:20:50,517 DEBUG [IPC Server handler 1 on 60000]
hbase.HMaster(1214): telling meta scanner to stop
[junit] 2007-12-29 13:20:50,517 DEBUG [HMaster] hbase.HMaster(1214):
telling meta scanner to stop
[junit] 2007-12-29 13:20:50,518 DEBUG [IPC Server handler 1 on 60000]
hbase.HMaster(1222): meta and root scanners notified
[junit] 2007-12-29 13:20:50,518 DEBUG [HMaster] hbase.HMaster(1222): meta
and root scanners notified
[junit] 2007-12-29 13:20:50,519 INFO [HMaster] hbase.Leases(109): HMaster
closing leases
[junit] 2007-12-29 13:20:50,519 INFO [RegionServer:0]
hbase.HRegionServer(768): Got regionserver stop message
[junit] 2007-12-29 13:20:50,519 INFO [RegionServer:0] hbase.Leases(109):
RegionServer:0 closing leases
[junit] 2007-12-29 13:20:50,519 INFO [HMaster.leaseChecker]
hbase.Chore(66): HMaster.leaseChecker exiting
[junit] 2007-12-29 13:20:50,520 INFO [RegionServer:0.leaseChecker]
hbase.Chore(66): RegionServer:0.leaseChecker exiting
[junit] 2007-12-29 13:20:50,520 INFO [HMaster] hbase.Leases(125): HMaster
closed leases
[junit] 2007-12-29 13:20:50,520 INFO [RegionServer:0] hbase.Leases(125):
RegionServer:0 closed leases
[junit] 2007-12-29 13:20:50,521 INFO [HMaster] hbase.HMaster(1139):
HMaster main thread exiting
[junit] 2007-12-29 13:20:51,075 INFO [RegionServer:0.compactor]
hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting
[junit] 2007-12-29 13:20:51,525 INFO [RegionServer:0.splitter]
hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting
[junit] 2007-12-29 13:20:55,211 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(972): Added 1028785192/info/3573498259001272973 with 6 entries,
sequence id 37, and size 752.0 for .META.,,1/info
[junit] 2007-12-29 13:20:55,212 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 9182ms,
sequenceid=37
[junit] 2007-12-29 13:20:55,213 DEBUG [RegionServer:0]
hbase.HRegionServer(1261): closing region -ROOT-,,0
[junit] 2007-12-29 13:20:55,213 DEBUG [RegionServer:0] hbase.HStore(880):
closed -ROOT-,,0/info (70236052/info)
[junit] 2007-12-29 13:20:55,214 INFO [RegionServer:0] hbase.HRegion(430):
closed -ROOT-,,0
[junit] 2007-12-29 13:20:55,214 DEBUG [RegionServer:0]
hbase.HRegionServer(1261): closing region .META.,,1
[junit] 2007-12-29 13:20:55,215 DEBUG [RegionServer:0] hbase.HStore(880):
closed .META.,,1/info (1028785192/info)
[junit] 2007-12-29 13:20:55,215 INFO [RegionServer:0] hbase.HRegion(430):
closed .META.,,1
[junit] 2007-12-29 13:21:04,696 DEBUG [RegionServer:0.worker]
hbase.HStore(972): Added 132806885/e/2002921514783112533 with 3 entries,
sequence id 38, and size 218.0 for r2,,1198934393554/e
[junit] 2007-12-29 13:21:08,505 DEBUG [RegionServer:0.worker]
hbase.HStore(972): Added 132806885/d/6242598714378742295 with 3 entries,
sequence id 38, and size 218.0 for r2,,1198934393554/d
[junit] 2007-12-29 13:21:08,506 DEBUG [RegionServer:0.worker]
hbase.HRegion(943): Finished memcache flush for region r2,,1198934393554 in
22473ms, sequenceid=38
[junit] 2007-12-29 13:21:08,506 DEBUG [RegionServer:0] hbase.HLog(388):
closing log writer in /hbase/log_140.211.11.75_1198934363169_38758
[junit] 2007-12-29 13:21:08,506 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r2,,1198934393554/e (132806885/e)
[junit] 2007-12-29 13:21:08,507 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed r2,,1198934393554/d (132806885/d)
[junit] 2007-12-29 13:21:08,507 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed r2,,1198934393554
[junit] 2007-12-29 13:21:08,507 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1295): closing region result_table,,1198934398607
[junit] 2007-12-29 13:21:08,508 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198934398607/e (330763908/e)
[junit] 2007-12-29 13:21:08,508 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198934398607/d (330763908/d)
[junit] 2007-12-29 13:21:08,508 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198934398607/a (330763908/a)
[junit] 2007-12-29 13:21:08,508 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198934398607/b (330763908/b)
[junit] 2007-12-29 13:21:08,509 DEBUG [RegionServer:0.worker]
hbase.HStore(880): closed result_table,,1198934398607/c (330763908/c)
[junit] 2007-12-29 13:21:08,509 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed result_table,,1198934398607
[junit] 2007-12-29 13:21:08,509 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1202): worker thread exiting
[junit] 2007-12-29 13:21:11,187 INFO [RegionServer:0]
hbase.HRegionServer(888): telling master that region server is shutting down
at: 140.211.11.75:38758
[junit] 2007-12-29 13:21:21,284 WARN [RegionServer:0]
hbase.HRegionServer(892): Failed to send exiting message to master:
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
[junit] at
java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
[junit] at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit] at java.net.Socket.connect(Socket.java:520)
[junit] at
org.apache.hadoop.ipc.Client$Connection.setupIOstreams(Client.java:159)
[junit] at org.apache.hadoop.ipc.Client.getConnection(Client.java:575)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:498)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198)
[junit] at $Proxy6.regionServerReport(Unknown Source)
[junit] at
org.apache.hadoop.hbase.HRegionServer.run(HRegionServer.java:890)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] 2007-12-29 13:21:21,286 INFO [RegionServer:0]
hbase.HRegionServer(895): stopping server at: 140.211.11.75:38758
[junit] 2007-12-29 13:21:21,286 INFO [RegionServer:0]
hbase.HRegionServer(900): RegionServer:0 exiting
[junit] 2007-12-29 13:21:21,287 INFO [main] hbase.LocalHBaseCluster(238):
Shutdown HMaster 1 region server(s)
[junit] 2007-12-29 13:21:32,388 INFO [main]
hbase.StaticTestEnvironment(133): Shutting down FileSystem
[junit] 2007-12-29 13:21:33,367 INFO [main]
hbase.StaticTestEnvironment(140): Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 0
[junit] 2007-12-29 13:21:33,694 ERROR [DataNode:
[http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/contrib/hbase/test/data/dfs/data/data1,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/contrib/hbase/test/data/dfs/data/data2]]
dfs.DataNode(1725): Exception: java.lang.reflect.UndeclaredThrowableException
[junit] at org.apache.hadoop.dfs.$Proxy1.sendHeartbeat(Unknown Source)
[junit] at
org.apache.hadoop.dfs.DataNode.offerService(DataNode.java:554)
[junit] at org.apache.hadoop.dfs.DataNode.run(DataNode.java:1723)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] Caused by: java.lang.InterruptedException
[junit] at java.lang.Object.wait(Native Method)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:504)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198)
[junit] ... 4 more
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 175.885 sec
[junit] 2007-12-29 13:21:37,038 INFO [main]
hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
[junit] 2007-12-29 13:21:37,039 INFO [main]
hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
[junit] Test org.apache.hadoop.hbase.shell.algebra.TestTableJoinMapReduce
FAILED
[junit] Running org.apache.hadoop.hbase.util.TestBase64
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.246 sec
[junit] Running org.apache.hadoop.hbase.util.TestKeying
[junit] Original url http://abc:[EMAIL
PROTECTED]/index.html?query=something#middle, Transformed url
r:http://abc:[EMAIL PROTECTED]/index.html?query=something#middle
[junit] Original url file:///usr/bin/java, Transformed url
file:///usr/bin/java
[junit] Original url dns:www.powerset.com, Transformed url
dns:www.powerset.com
[junit] Original url dns://dns.powerset.com/www.powerset.com, Transformed
url r:dns://com.powerset.dns/www.powerset.com
[junit] Original url http://one.two.three/index.html, Transformed url
r:http://three.two.one/index.html
[junit] Original url https://one.two.three:9443/index.html, Transformed url
r:https://three.two.one:9443/index.html
[junit] Original url ftp://one.two.three/index.html, Transformed url
r:ftp://three.two.one/index.html
[junit] Original url filename, Transformed url filename
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.061 sec
[junit] Running org.onelab.test.TestFilter
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.071 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml
:515: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/build.xml
:31: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/build-contrib.xml
:206: Tests failed!
Total time: 127 minutes 21 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2453
Updating HADOOP-2456
