You are viewing a plain text version of this content. The canonical link for it is here.
Posted to common-dev@hadoop.apache.org by hu...@lucene.zones.apache.org on 2007/12/29 14:33:28 UTC

Build failed in Hudson: Hadoop-Nightly #349

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:bcd@www.example.com/index.html?query=something#middle, Transformed url r:http://abc:bcd@com.example.www/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


Build failed in Hudson: Hadoop-Nightly #355

Posted by hu...@lucene.zones.apache.org.
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/355/changes

------------------------------------------
[...truncated 1049 lines...]
A         src/java/org/apache/hadoop/mapred/TaskTrackerAction.java
A         src/java/org/apache/hadoop/mapred/Task_Counter.properties
A         src/java/org/apache/hadoop/mapred/BasicTypeSorterBase.java
A         src/java/org/apache/hadoop/mapred/DefaultJobHistoryParser.java
A         src/java/org/apache/hadoop/mapred/TextOutputFormat.java
A         src/java/org/apache/hadoop/mapred/TaskTrackerStatus.java
A         src/java/org/apache/hadoop/mapred/KeyValueTextInputFormat.java
A         src/java/org/apache/hadoop/mapred/ReduceTaskRunner.java
A         src/java/org/apache/hadoop/mapred/KillJobAction.java
A         src/java/org/apache/hadoop/mapred/InvalidInputException.java
A         src/java/org/apache/hadoop/mapred/Reducer.java
A         src/java/org/apache/hadoop/mapred/MapTaskRunner.java
A         src/java/org/apache/hadoop/mapred/SequenceFileRecordReader.java
A         src/java/org/apache/hadoop/mapred/LaunchTaskAction.java
A         src/java/org/apache/hadoop/mapred/ReduceTaskStatus.java
A         src/java/org/apache/hadoop/mapred/MapOutputFile.java
A         src/java/org/apache/hadoop/mapred/SequenceFileInputFilter.java
A         src/java/org/apache/hadoop/HadoopVersionAnnotation.java
A         src/java/org/apache/hadoop/ipc
A         src/java/org/apache/hadoop/ipc/VersionedProtocol.java
A         src/java/org/apache/hadoop/ipc/RPC.java
A         src/java/org/apache/hadoop/ipc/Server.java
A         src/java/org/apache/hadoop/ipc/RemoteException.java
A         src/java/org/apache/hadoop/ipc/Client.java
A         src/java/org/apache/hadoop/ipc/SocketChannelOutputStream.java
A         src/java/org/apache/hadoop/ipc/package.html
A         src/java/org/apache/hadoop/dfs
A         src/java/org/apache/hadoop/dfs/UnderReplicatedBlocks.java
A         src/java/org/apache/hadoop/dfs/DFSClient.java
A         src/java/org/apache/hadoop/dfs/Upgradeable.java
A         src/java/org/apache/hadoop/dfs/FileDataServlet.java
A         src/java/org/apache/hadoop/dfs/UpgradeObject.java
A         src/java/org/apache/hadoop/dfs/UnregisteredDatanodeException.java
A         src/java/org/apache/hadoop/dfs/NameNode.java
A         src/java/org/apache/hadoop/dfs/LocatedBlock.java
A         src/java/org/apache/hadoop/dfs/LeaseExpiredException.java
A         src/java/org/apache/hadoop/dfs/DatanodeInfo.java
A         src/java/org/apache/hadoop/dfs/NotReplicatedYetException.java
AU        src/java/org/apache/hadoop/dfs/FSDatasetInterface.java
A         src/java/org/apache/hadoop/dfs/DatanodeProtocol.java
A         src/java/org/apache/hadoop/dfs/Storage.java
A         src/java/org/apache/hadoop/dfs/HftpFileSystem.java
A         src/java/org/apache/hadoop/dfs/DatanodeRegistration.java
A         src/java/org/apache/hadoop/dfs/BlocksMap.java
AU        src/java/org/apache/hadoop/dfs/NameNodeMetrics.java
A         src/java/org/apache/hadoop/dfs/FSNamesystem.java
A         src/java/org/apache/hadoop/dfs/package.html
A         src/java/org/apache/hadoop/dfs/TransferFsImage.java
A         src/java/org/apache/hadoop/dfs/FSEditLog.java
A         src/java/org/apache/hadoop/dfs/UpgradeManagerDatanode.java
A         src/java/org/apache/hadoop/dfs/UpgradeObjectNamenode.java
A         src/java/org/apache/hadoop/dfs/UpgradeCommand.java
A         src/java/org/apache/hadoop/dfs/Host2NodesMap.java
A         src/java/org/apache/hadoop/dfs/GetImageServlet.java
A         src/java/org/apache/hadoop/dfs/DistributedFileSystem.java
A         src/java/org/apache/hadoop/dfs/UpgradeStatusReport.java
A         src/java/org/apache/hadoop/dfs/BlockCommand.java
A         src/java/org/apache/hadoop/dfs/FsckServlet.java
A         src/java/org/apache/hadoop/dfs/DatanodeID.java
A         src/java/org/apache/hadoop/dfs/SecondaryNameNode.java
A         src/java/org/apache/hadoop/dfs/INode.java
A         src/java/org/apache/hadoop/dfs/ClientProtocol.java
AU        src/java/org/apache/hadoop/dfs/Balancer.java
A         src/java/org/apache/hadoop/dfs/NamespaceInfo.java
A         src/java/org/apache/hadoop/dfs/LocatedBlocks.java
A         src/java/org/apache/hadoop/dfs/FSDataset.java
A         src/java/org/apache/hadoop/dfs/DataNode.java
A         src/java/org/apache/hadoop/dfs/UpgradeManager.java
A         src/java/org/apache/hadoop/dfs/DFSAdmin.java
A         src/java/org/apache/hadoop/dfs/InconsistentFSStateException.java
A         src/java/org/apache/hadoop/dfs/BlockCrcUpgrade.java
A         src/java/org/apache/hadoop/dfs/DataChecksum.java
A         src/java/org/apache/hadoop/dfs/AlreadyBeingCreatedException.java
A         src/java/org/apache/hadoop/dfs/DataStorage.java
A         src/java/org/apache/hadoop/dfs/SafeModeException.java
A         src/java/org/apache/hadoop/dfs/ReplicationTargetChooser.java
A         src/java/org/apache/hadoop/dfs/FSConstants.java
A         src/java/org/apache/hadoop/dfs/DisallowedDatanodeException.java
A         src/java/org/apache/hadoop/dfs/StringBytesWritable.java
AU        src/java/org/apache/hadoop/dfs/NamenodeProtocol.java
A         src/java/org/apache/hadoop/dfs/ChecksumDistributedFileSystem.java
A         src/java/org/apache/hadoop/dfs/FSImage.java
A         src/java/org/apache/hadoop/dfs/PendingReplicationBlocks.java
A         src/java/org/apache/hadoop/dfs/IncorrectVersionException.java
A         src/java/org/apache/hadoop/dfs/DatanodeDescriptor.java
A         src/java/org/apache/hadoop/dfs/UpgradeObjectDatanode.java
A         src/java/org/apache/hadoop/dfs/ListPathsServlet.java
AU        src/java/org/apache/hadoop/dfs/BlocksWithLocations.java
A         src/java/org/apache/hadoop/dfs/DFSFileInfo.java
A         src/java/org/apache/hadoop/dfs/UpgradeManagerNamenode.java
A         src/java/org/apache/hadoop/dfs/DfsPath.java
AU        src/java/org/apache/hadoop/dfs/DFSck.java
A         src/java/org/apache/hadoop/dfs/Block.java
A         src/java/org/apache/hadoop/dfs/UpgradeObjectCollection.java
A         src/java/org/apache/hadoop/dfs/StreamFile.java
A         src/java/org/apache/hadoop/dfs/JspHelper.java
A         src/java/org/apache/hadoop/dfs/NamenodeFsck.java
A         src/java/org/apache/hadoop/dfs/FSDirectory.java
A         src/java/org/apache/hadoop/util
A         src/java/org/apache/hadoop/util/RunJar.java
A         src/java/org/apache/hadoop/util/VersionInfo.java
A         src/java/org/apache/hadoop/util/Daemon.java
A         src/java/org/apache/hadoop/util/Progress.java
A         src/java/org/apache/hadoop/util/GenericOptionsParser.java
A         src/java/org/apache/hadoop/util/ShellUtil.java
A         src/java/org/apache/hadoop/util/MergeSort.java
A         src/java/org/apache/hadoop/util/CopyFiles_Counter.properties
A         src/java/org/apache/hadoop/util/Shell.java
A         src/java/org/apache/hadoop/util/ToolRunner.java
A         src/java/org/apache/hadoop/util/NativeCodeLoader.java
A         src/java/org/apache/hadoop/util/ToolBase.java
A         src/java/org/apache/hadoop/util/ReflectionUtils.java
A         src/java/org/apache/hadoop/util/Tool.java
A         src/java/org/apache/hadoop/util/package.html
A         src/java/org/apache/hadoop/util/DiskChecker.java
A         src/java/org/apache/hadoop/util/XMLUtils.java
AU        src/java/org/apache/hadoop/util/ServletUtil.java
A         src/java/org/apache/hadoop/util/ProgramDriver.java
A         src/java/org/apache/hadoop/util/HostsFileReader.java
A         src/java/org/apache/hadoop/util/PrintJarMainClass.java
A         src/java/org/apache/hadoop/util/PlatformName.java
A         src/java/org/apache/hadoop/util/PriorityQueue.java
A         src/java/org/apache/hadoop/util/StringUtils.java
A         src/java/org/apache/hadoop/util/Progressable.java
A         src/java/org/apache/hadoop/util/CopyFiles.java
A         src/java/org/apache/hadoop/record
A         src/java/org/apache/hadoop/record/RecordOutput.java
A         src/java/org/apache/hadoop/record/XmlRecordInput.java
A         src/java/org/apache/hadoop/record/Utils.java
A         src/java/org/apache/hadoop/record/XmlRecordOutput.java
A         src/java/org/apache/hadoop/record/BinaryRecordInput.java
A         src/java/org/apache/hadoop/record/BinaryRecordOutput.java
A         src/java/org/apache/hadoop/record/RecordComparator.java
A         src/java/org/apache/hadoop/record/Index.java
A         src/java/org/apache/hadoop/record/Buffer.java
A         src/java/org/apache/hadoop/record/CsvRecordInput.java
A         src/java/org/apache/hadoop/record/compiler
A         src/java/org/apache/hadoop/record/compiler/ant
A         src/java/org/apache/hadoop/record/compiler/ant/RccTask.java
A         src/java/org/apache/hadoop/record/compiler/JInt.java
A         src/java/org/apache/hadoop/record/compiler/CodeBuffer.java
A         src/java/org/apache/hadoop/record/compiler/JLong.java
A         src/java/org/apache/hadoop/record/compiler/JCompType.java
A         src/java/org/apache/hadoop/record/compiler/JVector.java
A         src/java/org/apache/hadoop/record/compiler/JByte.java
A         src/java/org/apache/hadoop/record/compiler/generated
A         src/java/org/apache/hadoop/record/compiler/generated/Token.java
A         src/java/org/apache/hadoop/record/compiler/generated/TokenMgrError.java
A         src/java/org/apache/hadoop/record/compiler/generated/RccTokenManager.java
A         src/java/org/apache/hadoop/record/compiler/generated/SimpleCharStream.java
A         src/java/org/apache/hadoop/record/compiler/generated/RccConstants.java
A         src/java/org/apache/hadoop/record/compiler/generated/Rcc.java
A         src/java/org/apache/hadoop/record/compiler/generated/ParseException.java
A         src/java/org/apache/hadoop/record/compiler/generated/rcc.jj
A         src/java/org/apache/hadoop/record/compiler/generated/package.html
A         src/java/org/apache/hadoop/record/compiler/package.html
A         src/java/org/apache/hadoop/record/compiler/JFloat.java
A         src/java/org/apache/hadoop/record/compiler/JString.java
A         src/java/org/apache/hadoop/record/compiler/CodeGenerator.java
A         src/java/org/apache/hadoop/record/compiler/JBuffer.java
A         src/java/org/apache/hadoop/record/compiler/JDouble.java
A         src/java/org/apache/hadoop/record/compiler/JMap.java
A         src/java/org/apache/hadoop/record/compiler/JRecord.java
A         src/java/org/apache/hadoop/record/compiler/JavaGenerator.java
A         src/java/org/apache/hadoop/record/compiler/JFile.java
A         src/java/org/apache/hadoop/record/compiler/JBoolean.java
A         src/java/org/apache/hadoop/record/compiler/CppGenerator.java
A         src/java/org/apache/hadoop/record/compiler/CGenerator.java
A         src/java/org/apache/hadoop/record/compiler/JType.java
A         src/java/org/apache/hadoop/record/compiler/JField.java
A         src/java/org/apache/hadoop/record/CsvRecordOutput.java
A         src/java/org/apache/hadoop/record/Record.java
A         src/java/org/apache/hadoop/record/RecordInput.java
A         src/java/org/apache/hadoop/record/package.html
A         src/java/overview.html
A         src/webapps
A         src/webapps/datanode
A         src/webapps/datanode/browseDirectory.jsp
A         src/webapps/datanode/tail.jsp
A         src/webapps/datanode/browseBlock.jsp
A         src/webapps/task
A         src/webapps/task/tasktracker.jsp
A         src/webapps/task/index.html
A         src/webapps/static
A         src/webapps/static/hadoop.css
A         src/webapps/static/jobconf.xsl
AU        src/webapps/static/hadoop-logo.jpg
A         src/webapps/job
A         src/webapps/job/jobtracker.jsp
A         src/webapps/job/machines.jsp
A         src/webapps/job/taskdetailshistory.jsp
A         src/webapps/job/jobhistory.jsp
A         src/webapps/job/loadhistory.jsp
A         src/webapps/job/jobtaskshistory.jsp
A         src/webapps/job/jobdetailshistory.jsp
A         src/webapps/job/jobfailures.jsp
A         src/webapps/job/taskdetails.jsp
A         src/webapps/job/analysejobhistory.jsp
A         src/webapps/job/jobblacklistedtrackers.jsp
A         src/webapps/job/index.html
A         src/webapps/job/jobtasks.jsp
A         src/webapps/job/jobdetails.jsp
A         src/webapps/job/jobconf.jsp
A         src/webapps/job/taskstats.jsp
A         src/webapps/dfs
A         src/webapps/dfs/nn_browsedfscontent.jsp
A         src/webapps/dfs/index.html
A         src/webapps/dfs/dfshealth.jsp
A         src/ant
A         src/ant/org
A         src/ant/org/apache
A         src/ant/org/apache/hadoop
A         src/ant/org/apache/hadoop/ant
A         src/ant/org/apache/hadoop/ant/DfsTask.java
A         src/ant/org/apache/hadoop/ant/condition
A         src/ant/org/apache/hadoop/ant/condition/DfsIsDir.java
A         src/ant/org/apache/hadoop/ant/condition/DfsBaseConditional.java
A         src/ant/org/apache/hadoop/ant/condition/DfsZeroLen.java
A         src/ant/org/apache/hadoop/ant/condition/DfsExists.java
A         src/ant/org/apache/hadoop/ant/antlib.xml
A         src/native
ERROR: Failed to check out https://svn.apache.org/repos/asf/lucene/hadoop/trunk
org.tmatesoft.svn.core.SVNException: svn: REPORT request failed on '/repos/asf/!svn/vcc/default'
svn: SSL peer shut down incorrectly
	at org.tmatesoft.svn.core.internal.wc.SVNErrorManager.error(SVNErrorManager.java:80)
	at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:517)
	at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:234)
	at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:222)
	at org.tmatesoft.svn.core.internal.io.dav.DAVConnection.doReport(DAVConnection.java:219)
	at org.tmatesoft.svn.core.internal.io.dav.DAVConnection.doReport(DAVConnection.java:211)
	at org.tmatesoft.svn.core.internal.io.dav.DAVRepository.update(DAVRepository.java:609)
	at org.tmatesoft.svn.core.wc.SVNUpdateClient.doUpdate(SVNUpdateClient.java:162)
	at org.tmatesoft.svn.core.wc.SVNUpdateClient.doCheckout(SVNUpdateClient.java:325)
	at hudson.scm.SubversionSCM$CheckOutTask.invoke(SubversionSCM.java:397)
	at hudson.scm.SubversionSCM$CheckOutTask.invoke(SubversionSCM.java:349)
	at hudson.FilePath.act(FilePath.java:291)
	at hudson.scm.SubversionSCM.checkout(SubversionSCM.java:346)
	at hudson.scm.SubversionSCM.checkout(SubversionSCM.java:292)
	at hudson.model.AbstractProject.checkout(AbstractProject.java:541)
	at hudson.model.AbstractBuild$AbstractRunner.checkout(AbstractBuild.java:223)
	at hudson.model.AbstractBuild$AbstractRunner.run(AbstractBuild.java:189)
	at hudson.model.Run.run(Run.java:649)
	at hudson.model.Build.run(Build.java:102)
	at hudson.model.ResourceController.execute(ResourceController.java:70)
	at hudson.model.Executor.run(Executor.java:64)
ERROR: No artifacts found that match the file pattern "trunk/*.tar.gz trunk/build/test/checkstyle-errors.* trunk/findbugs/*ml trunk/build/test/clover/reports/coverage.xml". Configuration error?
Recording fingerprints
Publishing Javadoc
Recording test results


Build failed in Hudson: Hadoop-Nightly #354

Posted by hu...@lucene.zones.apache.org.
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/354/changes

------------------------------------------
[...truncated 38279 lines...]
    [junit] 2008-01-03 16:56:16,798 INFO  dfs.DataNode (FSDataset.java:invalidate(740)) - Deleting block blk_-8378777297456820170 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data4/current/blk_-8378777297456820170 
    [junit] 2008-01-03 16:56:17,003 INFO  dfs.DataNode (FSDataset.java:invalidate(740)) - Deleting block blk_-2114403456717153875 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1/current/blk_-2114403456717153875 
    [junit] 2008-01-03 16:56:17,604 INFO  dfs.DataNode (FSDataset.java:invalidate(740)) - Deleting block blk_-8378777297456820170 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data2/current/blk_-8378777297456820170 
    [junit] 2008-01-03 16:56:17,732 INFO  mapred.MiniMRCluster (MiniMRCluster.java:waitUntilIdle(207)) - Waiting for task tracker tracker_lucene.zones.apache.org:/127.0.0.1:60203 to be idle.
    [junit] 2008-01-03 16:56:18,474 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(886)) - Removed completed task 'task_200801031654_0004_m_000000_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:60203'
    [junit] 2008-01-03 16:56:18,474 INFO  mapred.JobTracker (JobTracker.java:removeMarkedTasks(886)) - Removed completed task 'task_200801031654_0004_r_000000_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:60203'
    [junit] 2008-01-03 16:56:18,475 INFO  mapred.TaskTracker (TaskTracker.java:purgeJob(1116)) - Received 'KillJobAction' for job: job_200801031654_0004
    [junit] 2008-01-03 16:56:18,475 INFO  mapred.TaskRunner (MapTaskRunner.java:close(41)) - task_200801031654_0004_m_000000_0 done; removing files.
    [junit] 2008-01-03 16:56:18,677 INFO  mapred.TaskRunner (ReduceTaskRunner.java:close(45)) - task_200801031654_0004_r_000000_0 done; removing files.
    [junit] 2008-01-03 16:56:18,977 INFO  ipc.Server (Server.java:stop(976)) - Stopping server on 60199
    [junit] 2008-01-03 16:56:18,978 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60199: exiting
    [junit] 2008-01-03 16:56:18,978 INFO  ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60199
    [junit] 2008-01-03 16:56:18,978 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60199: exiting
    [junit] 2008-01-03 16:56:18,979 INFO  ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder
    [junit] 2008-01-03 16:56:19,671 INFO  mapred.TaskTracker (TaskTracker.java:shutdown(713)) - Shutting down StatusHttpServer
    [junit] 2008-01-03 16:56:19,671 INFO  mapred.TaskTracker (TaskTracker.java:run(509)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_lucene.zones.apache.org:/127.0.0.1:60199
    [junit] 2008-01-03 16:56:19,682 INFO  http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60198
    [junit] 2008-01-03 16:56:19,683 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1779885
    [junit] 2008-01-03 16:56:19,858 INFO  util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
    [junit] 2008-01-03 16:56:20,194 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
    [junit] 2008-01-03 16:56:20,460 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
    [junit] 2008-01-03 16:56:20,462 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@df83e5
    [junit] 2008-01-03 16:56:20,511 INFO  ipc.Client (Client.java:run(305)) - java.net.SocketException: Socket closed
    [junit] 	at java.net.SocketInputStream.read(SocketInputStream.java:162)
    [junit] 	at java.io.FilterInputStream.read(FilterInputStream.java:111)
    [junit] 	at org.apache.hadoop.ipc.Client$Connection$1.read(Client.java:188)
    [junit] 	at java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
    [junit] 	at java.io.BufferedInputStream.read(BufferedInputStream.java:235)
    [junit] 	at java.io.DataInputStream.readInt(DataInputStream.java:353)
    [junit] 	at org.apache.hadoop.ipc.Client$Connection.run(Client.java:274)

    [junit] 2008-01-03 16:56:20,512 ERROR mapred.TaskTracker (TaskTracker.java:offerService(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.$Proxy6.heartbeat(Unknown Source)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.transmitHeartBeat(TaskTracker.java:1019)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.offerService(TaskTracker.java:902)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.run(TaskTracker.java:1315)
    [junit] 	at org.apache.hadoop.mapred.MiniMRCluster$TaskTrackerRunner.run(MiniMRCluster.java:144)
    [junit] 	at java.lang.Thread.run(Thread.java:595)

    [junit] 2008-01-03 16:56:20,573 INFO  ipc.Server (Server.java:stop(976)) - Stopping server on 60203
    [junit] 2008-01-03 16:56:20,573 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60203: exiting
    [junit] 2008-01-03 16:56:20,573 INFO  ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60203
    [junit] 2008-01-03 16:56:20,574 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60203: exiting
    [junit] 2008-01-03 16:56:20,575 INFO  ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder
    [junit] 2008-01-03 16:56:21,088 INFO  mapred.TaskTracker (TaskTracker.java:shutdown(713)) - Shutting down StatusHttpServer
    [junit] 2008-01-03 16:56:21,089 INFO  mapred.TaskTracker (TaskTracker.java:run(540)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_lucene.zones.apache.org:/127.0.0.1:60203
    [junit] 2008-01-03 16:56:21,092 INFO  http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60202
    [junit] 2008-01-03 16:56:21,093 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@26f9e5
    [junit] 2008-01-03 16:56:21,230 INFO  util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
    [junit] 2008-01-03 16:56:21,349 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
    [junit] 2008-01-03 16:56:21,481 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
    [junit] 2008-01-03 16:56:21,482 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@9f0d
    [junit] 2008-01-03 16:56:21,482 INFO  mapred.TaskTracker (TaskTracker.java:offerService(934)) - Interrupted. Closing down.
    [junit] 2008-01-03 16:56:21,483 INFO  mapred.JobTracker (JobTracker.java:close(726)) - Stopping infoServer
    [junit] 2008-01-03 16:56:21,485 INFO  http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60195
    [junit] 2008-01-03 16:56:21,486 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@18a6e6e
    [junit] 2008-01-03 16:56:21,609 INFO  util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
    [junit] 2008-01-03 16:56:21,738 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
    [junit] 2008-01-03 16:56:21,858 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
    [junit] 2008-01-03 16:56:21,859 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@14b6bed
    [junit] 2008-01-03 16:56:21,859 INFO  mapred.JobTracker (JobTracker.java:close(734)) - Stopping interTrackerServer
    [junit] 2008-01-03 16:56:21,859 INFO  ipc.Server (Server.java:stop(976)) - Stopping server on 60194
    [junit] 2008-01-03 16:56:21,860 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60194: exiting
    [junit] 2008-01-03 16:56:21,860 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 8 on 60194: exiting
    [junit] 2008-01-03 16:56:21,861 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 4 on 60194: exiting
    [junit] 2008-01-03 16:56:21,861 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 3 on 60194: exiting
    [junit] 2008-01-03 16:56:21,861 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 2 on 60194: exiting
    [junit] 2008-01-03 16:56:21,861 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 5 on 60194: exiting
    [junit] 2008-01-03 16:56:21,860 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 7 on 60194: exiting
    [junit] 2008-01-03 16:56:21,860 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 6 on 60194: exiting
    [junit] 2008-01-03 16:56:21,863 INFO  ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder
    [junit] 2008-01-03 16:56:21,860 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60194: exiting
    [junit] 2008-01-03 16:56:21,860 INFO  mapred.JobTracker (JobTracker.java:offerService(721)) - Stopped interTrackerServer
    [junit] 2008-01-03 16:56:21,860 INFO  mapred.JobTracker (JobTracker.java:close(738)) - Stopping expireTrackers
    [junit] 2008-01-03 16:56:21,860 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 9 on 60194: exiting
    [junit] 2008-01-03 16:56:21,860 INFO  ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60194
    [junit] 2008-01-03 16:56:21,865 INFO  mapred.JobTracker (JobTracker.java:close(747)) - Stopping retirer
    [junit] 2008-01-03 16:56:21,866 INFO  mapred.JobTracker (JobTracker.java:close(756)) - Stopping initer
    [junit] 2008-01-03 16:56:21,866 INFO  mapred.JobTracker (JobTracker.java:close(765)) - Stopping expireLaunchingTasks
    [junit] 2008-01-03 16:56:21,867 INFO  mapred.JobTracker (JobTracker.java:close(774)) - Stopping TaskCommit thread
    [junit] 2008-01-03 16:56:21,868 WARN  mapred.JobTracker (JobTracker.java:run(2032)) - Task Commit Thread exiting...
    [junit] 2008-01-03 16:56:21,868 INFO  mapred.JobTracker (JobTracker.java:close(782)) - stopped all jobtracker services
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2008-01-03 16:56:21,870 INFO  http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60192
    [junit] 2008-01-03 16:56:21,871 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@b23d12
    [junit] 2008-01-03 16:56:21,994 INFO  util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
    [junit] 2008-01-03 16:56:22,114 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
    [junit] 2008-01-03 16:56:22,239 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
    [junit] 2008-01-03 16:56:22,240 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@b4be3d
    [junit] 2008-01-03 16:56:22,242 INFO  dfs.DataNode (DataNode.java:run(1741)) - 127.0.0.1:60191:Finishing DataNode in: FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data4/current'} 
    [junit] Shutting down DataNode 0
    [junit] 2008-01-03 16:56:22,243 INFO  http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60186
    [junit] 2008-01-03 16:56:22,244 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@31f2a7
    [junit] 2008-01-03 16:56:22,365 INFO  util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
    [junit] 2008-01-03 16:56:22,481 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
    [junit] 2008-01-03 16:56:22,595 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
    [junit] 2008-01-03 16:56:22,596 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@19ec4ed
    [junit] 2008-01-03 16:56:22,597 INFO  dfs.DataNode (DataNode.java:run(1741)) - 127.0.0.1:60185:Finishing DataNode in: FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data2/current'} 
    [junit] 2008-01-03 16:56:22,599 INFO  http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60180
    [junit] 2008-01-03 16:56:22,600 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@165f738
    [junit] 2008-01-03 16:56:22,712 INFO  util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
    [junit] 2008-01-03 16:56:22,822 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
    [junit] 2008-01-03 16:56:22,961 INFO  util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
    [junit] 2008-01-03 16:56:22,961 INFO  util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@1bbf1ca
    [junit] 2008-01-03 16:56:22,962 INFO  fs.FSNamesystem (FSEditLog.java:printStatistics(739)) - Number of transactions: 117 Total time for transactions(ms): 7 Number of syncs: 74 SyncTimes(ms): 7687 7737 
    [junit] 2008-01-03 16:56:23,180 INFO  ipc.Server (Server.java:stop(976)) - Stopping server on 60179
    [junit] 2008-01-03 16:56:23,181 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60179: exiting
    [junit] 2008-01-03 16:56:23,182 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 5 on 60179: exiting
    [junit] 2008-01-03 16:56:23,182 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 8 on 60179: exiting
    [junit] 2008-01-03 16:56:23,182 INFO  ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60179
    [junit] 2008-01-03 16:56:23,184 INFO  ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder
    [junit] 2008-01-03 16:56:23,182 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 2 on 60179: exiting
    [junit] 2008-01-03 16:56:23,182 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 6 on 60179: exiting
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 146.038 sec
    [junit] 2008-01-03 16:56:23,185 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 9 on 60179: exiting
    [junit] 2008-01-03 16:56:23,181 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 3 on 60179: exiting
    [junit] 2008-01-03 16:56:23,181 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 7 on 60179: exiting
    [junit] 2008-01-03 16:56:23,195 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60179: exiting
    [junit] 2008-01-03 16:56:23,181 INFO  ipc.Server (Server.java:run(924)) - IPC Server handler 4 on 60179: exiting
    [junit] Running org.apache.hadoop.net.TestNetworkTopology
    [junit] 2008-01-03 16:56:26,042 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020
    [junit] 2008-01-03 16:56:26,048 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020
    [junit] 2008-01-03 16:56:26,048 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020
    [junit] 2008-01-03 16:56:26,049 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020
    [junit] 2008-01-03 16:56:26,050 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020
    [junit] 2008-01-03 16:56:26,051 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020
    [junit] 2008-01-03 16:56:26,052 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h7:5020
    [junit] 2008-01-03 16:56:26,103 INFO  net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h1:5020
    [junit] 2008-01-03 16:56:26,104 INFO  net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h2:5020
    [junit] 2008-01-03 16:56:26,106 INFO  net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h3:5020
    [junit] 2008-01-03 16:56:26,107 INFO  net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h4:5020
    [junit] 2008-01-03 16:56:26,108 INFO  net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h5:5020
    [junit] 2008-01-03 16:56:26,109 INFO  net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h6:5020
    [junit] 2008-01-03 16:56:26,110 INFO  net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h7:5020
    [junit] 2008-01-03 16:56:26,111 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020
    [junit] 2008-01-03 16:56:26,113 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020
    [junit] 2008-01-03 16:56:26,114 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020
    [junit] 2008-01-03 16:56:26,115 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020
    [junit] 2008-01-03 16:56:26,116 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020
    [junit] 2008-01-03 16:56:26,118 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020
    [junit] 2008-01-03 16:56:26,119 INFO  net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h7:5020
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.07 sec
    [junit] Running org.apache.hadoop.record.TestBuffer
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.16 sec
    [junit] Running org.apache.hadoop.record.TestRecordIO
    [junit] Illustrating toString bugT,102,4567,99344109427290,3.1449999809265137,1.5234,'random text,#000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff,v{},m{},s{'other random text}

    [junit] Illustrating toString bugT,102,4567,99344109427290,3.1449999809265137,1.5234,'random text,#000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff,v{},m{},s{'other random text}

    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.546 sec
    [junit] Running org.apache.hadoop.record.TestRecordMR
    [junit] 2008-01-03 16:56:30,267 INFO  jvm.JvmMetrics (JvmMetrics.java:init(56)) - Initializing JVM Metrics with processName=JobTracker, sessionId=
    [junit] 2008-01-03 16:56:30,377 WARN  mapred.JobClient (JobClient.java:submitJob(496)) - No job jar file set.  User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2008-01-03 16:56:30,395 INFO  mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
    [junit] 2008-01-03 16:56:30,790 WARN  conf.Configuration (Configuration.java:loadResource(808)) - build/test/mapred/local/localRunner/job_local_1.xml:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] 2008-01-03 16:56:30,798 INFO  mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_1
    [junit] 2008-01-03 16:56:30,834 INFO  mapred.MapTask (MapTask.java:run(171)) - numReduceTasks: 1
    [junit] 2008-01-03 16:56:31,087 INFO  mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genins/answer.key :0+190
    [junit] 2008-01-03 16:56:31,090 INFO  mapred.TaskRunner (Task.java:done(381)) - Task 'job_local_1_map_0000' done.
    [junit] 2008-01-03 16:56:31,253 INFO  mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
    [junit] 2008-01-03 16:56:31,255 INFO  mapred.TaskRunner (Task.java:done(381)) - Task 'reduce_crezyc' done.
    [junit] 2008-01-03 16:56:31,279 INFO  mapred.TaskRunner (Task.java:saveTaskOutput(433)) - Saved output of task 'reduce_crezyc' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genouts 
    [junit] 2008-01-03 16:56:31,802 INFO  mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_1
    [junit] 2008-01-03 16:56:31,805 INFO  mapred.JobClient (Counters.java:log(491)) - Counters: 9
    [junit] 2008-01-03 16:56:31,806 INFO  mapred.JobClient (Counters.java:log(493)) -   Map-Reduce Framework
    [junit] 2008-01-03 16:56:31,809 INFO  mapred.JobClient (Counters.java:log(495)) -     Map input records=10
    [junit] 2008-01-03 16:56:31,811 INFO  mapred.JobClient (Counters.java:log(495)) -     Map output records=102
    [junit] 2008-01-03 16:56:31,813 INFO  mapred.JobClient (Counters.java:log(495)) -     Map input bytes=100
    [junit] 2008-01-03 16:56:31,814 INFO  mapred.JobClient (Counters.java:log(495)) -     Map output bytes=713
    [junit] 2008-01-03 16:56:31,815 INFO  mapred.JobClient (Counters.java:log(495)) -     Combine input records=0
    [junit] 2008-01-03 16:56:31,816 INFO  mapred.JobClient (Counters.java:log(495)) -     Combine output records=0
    [junit] 2008-01-03 16:56:31,817 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce input groups=102
    [junit] 2008-01-03 16:56:31,818 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce input records=102
    [junit] 2008-01-03 16:56:31,819 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce output records=102
    [junit] 2008-01-03 16:56:31,890 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
    [junit] 2008-01-03 16:56:31,894 WARN  mapred.JobClient (JobClient.java:submitJob(496)) - No job jar file set.  User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2008-01-03 16:56:31,896 INFO  mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
    [junit] 2008-01-03 16:56:32,248 WARN  conf.Configuration (Configuration.java:loadResource(808)) - build/test/mapred/local/localRunner/job_local_2.xml:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] 2008-01-03 16:56:32,251 INFO  mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_2
    [junit] 2008-01-03 16:56:32,260 INFO  mapred.MapTask (MapTask.java:run(171)) - numReduceTasks: 1
    [junit] 2008-01-03 16:56:32,346 INFO  mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genouts/part-00000 :0+1113
    [junit] 2008-01-03 16:56:32,347 INFO  mapred.TaskRunner (Task.java:done(381)) - Task 'job_local_2_map_0000' done.
    [junit] 2008-01-03 16:56:32,393 INFO  mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
    [junit] 2008-01-03 16:56:32,394 INFO  mapred.TaskRunner (Task.java:done(381)) - Task 'reduce_fo40o' done.
    [junit] 2008-01-03 16:56:32,425 INFO  mapred.TaskRunner (Task.java:saveTaskOutput(433)) - Saved output of task 'reduce_fo40o' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/intermediateouts 
    [junit] 2008-01-03 16:56:33,261 INFO  mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_2
    [junit] 2008-01-03 16:56:33,262 INFO  mapred.JobClient (Counters.java:log(491)) - Counters: 9
    [junit] 2008-01-03 16:56:33,263 INFO  mapred.JobClient (Counters.java:log(493)) -   Map-Reduce Framework
    [junit] 2008-01-03 16:56:33,263 INFO  mapred.JobClient (Counters.java:log(495)) -     Map input records=102
    [junit] 2008-01-03 16:56:33,263 INFO  mapred.JobClient (Counters.java:log(495)) -     Map output records=102
    [junit] 2008-01-03 16:56:33,264 INFO  mapred.JobClient (Counters.java:log(495)) -     Map input bytes=1020
    [junit] 2008-01-03 16:56:33,264 INFO  mapred.JobClient (Counters.java:log(495)) -     Map output bytes=306
    [junit] 2008-01-03 16:56:33,265 INFO  mapred.JobClient (Counters.java:log(495)) -     Combine input records=0
    [junit] 2008-01-03 16:56:33,265 INFO  mapred.JobClient (Counters.java:log(495)) -     Combine output records=0
    [junit] 2008-01-03 16:56:33,266 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce input groups=10
    [junit] 2008-01-03 16:56:33,266 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce input records=102
    [junit] 2008-01-03 16:56:33,267 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce output records=10
    [junit] 2008-01-03 16:56:33,334 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
    [junit] 2008-01-03 16:56:33,336 WARN  mapred.JobClient (JobClient.java:submitJob(496)) - No job jar file set.  User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2008-01-03 16:56:33,337 INFO  mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
    [junit] 2008-01-03 16:56:33,640 WARN  conf.Configuration (Configuration.java:loadResource(808)) - build/test/mapred/local/localRunner/job_local_3.xml:a attempt to override final parameter: hadoop.tmp.dir;  Ignoring.
    [junit] 2008-01-03 16:56:33,642 INFO  mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_3
    [junit] 2008-01-03 16:56:33,663 INFO  mapred.MapTask (MapTask.java:run(171)) - numReduceTasks: 1
    [junit] 2008-01-03 16:56:33,681 INFO  mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/intermediateouts/part-00000 :0+209
    [junit] 2008-01-03 16:56:33,682 INFO  mapred.TaskRunner (Task.java:done(381)) - Task 'job_local_3_map_0000' done.
    [junit] 2008-01-03 16:56:33,730 INFO  mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
    [junit] 2008-01-03 16:56:33,731 INFO  mapred.TaskRunner (Task.java:done(381)) - Task 'reduce_eamlf8' done.
    [junit] 2008-01-03 16:56:33,793 INFO  mapred.TaskRunner (Task.java:saveTaskOutput(433)) - Saved output of task 'reduce_eamlf8' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/finalouts 
    [junit] 2008-01-03 16:56:34,651 INFO  mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_3
    [junit] 2008-01-03 16:56:34,652 INFO  mapred.JobClient (Counters.java:log(491)) - Counters: 9
    [junit] 2008-01-03 16:56:34,653 INFO  mapred.JobClient (Counters.java:log(493)) -   Map-Reduce Framework
    [junit] 2008-01-03 16:56:34,654 INFO  mapred.JobClient (Counters.java:log(495)) -     Map input records=10
    [junit] 2008-01-03 16:56:34,655 INFO  mapred.JobClient (Counters.java:log(495)) -     Map output records=10
    [junit] 2008-01-03 16:56:34,655 INFO  mapred.JobClient (Counters.java:log(495)) -     Map input bytes=116
    [junit] 2008-01-03 16:56:34,656 INFO  mapred.JobClient (Counters.java:log(495)) -     Map output bytes=20
    [junit] 2008-01-03 16:56:34,657 INFO  mapred.JobClient (Counters.java:log(495)) -     Combine input records=0
    [junit] 2008-01-03 16:56:34,658 INFO  mapred.JobClient (Counters.java:log(495)) -     Combine output records=0
    [junit] 2008-01-03 16:56:34,659 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce input groups=10
    [junit] 2008-01-03 16:56:34,660 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce input records=10
    [junit] 2008-01-03 16:56:34,660 INFO  mapred.JobClient (Counters.java:log(495)) -     Reduce output records=10
    [junit] Original sum: 102
    [junit] Recomputed sum: 102
    [junit] Success=true
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 5.377 sec
    [junit] Running org.apache.hadoop.record.TestRecordWritable
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 8.516 sec
    [junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.474 sec
    [junit] Running org.apache.hadoop.util.TestReflectionUtils
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.905 sec

BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml :511: Tests failed!

Total time: 133 minutes 14 seconds
Recording fingerprints
Publishing Javadoc
Recording test results


Build failed in Hudson: Hadoop-Nightly #353

Posted by hu...@lucene.zones.apache.org.
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/353/changes

Changes:

[acmurthy] Added missing attribution of HADOOP-2344 for Amar Kamat.

[acmurthy] HADOOP-2344. Enhance the utility for executing shell commands to read the
stdout/stderr streams while waiting for the command to finish (to free up
the buffers). Also, this patch throws away stderr of the DF utility.
@deprecated
  org.apache.hadoop.fs.ShellCommand for org.apache.hadoop.util.Shell
  org.apache.hadoop.util.ShellUtil for org.apache.hadoop.util.Shell.ShellCommandExecutor

[dhruba] HADOOP-2492. Additional debugging in the rpc server to better
diagnose ConcurrentModificationException. (dhruba)

------------------------------------------
[...truncated 51259 lines...]
    [junit] 2008-01-02 13:39:36,594 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting r2,,1199281176236/e (195367671/e) (no reconstruction log)
    [junit] 2008-01-02 13:39:36,597 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore r2,,1199281176236/e (195367671/e) is -1
    [junit] 2008-01-02 13:39:36,599 DEBUG [IPC Server handler 1 on 60000] hbase.HRegion(300): Next sequence id for region r2,,1199281176236 is 0
    [junit] 2008-01-02 13:39:36,601 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(328): region r2,,1199281176236 available
    [junit] 2008-01-02 13:39:36,604 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed r2,,1199281176236/e (195367671/e)
    [junit] 2008-01-02 13:39:36,605 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed r2,,1199281176236/d (195367671/d)
    [junit] 2008-01-02 13:39:36,606 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(430): closed r2,,1199281176236
    [junit] 2008-01-02 13:39:36,607 DEBUG [IPC Server handler 1 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_195367671/log
    [junit] 2008-01-02 13:39:36,731 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(2547): created table r2
    [junit] 2008-01-02 13:39:36,733 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
    [junit] 2008-01-02 13:39:36,738 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: r2,,1199281176236, startKey: <>, encodedName(195367671) 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}}}
    [junit] 2008-01-02 13:39:36,739 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table r2 not currently being served.
    [junit] 2008-01-02 13:39:37,465 INFO  [IPC Server handler 2 on 60000] hbase.HMaster(1858): assigning region r2,,1199281176236 to the only server 140.211.11.75:52289
    [junit] 2008-01-02 13:39:37,467 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : r2,,1199281176236
    [junit] 2008-01-02 13:39:37,473 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199281176236/d (195367671/d) (no reconstruction log)
    [junit] 2008-01-02 13:39:37,474 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region -ROOT-,,0. Size 92.0
    [junit] 2008-01-02 13:39:37,478 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199281176236/d (195367671/d) is -1
    [junit] 2008-01-02 13:39:37,485 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199281176236/e (195367671/e) (no reconstruction log)
    [junit] 2008-01-02 13:39:37,488 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199281176236/e (195367671/e) is -1
    [junit] 2008-01-02 13:39:37,489 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region r2,,1199281176236 is 0
    [junit] 2008-01-02 13:39:37,490 INFO  [RegionServer:0.worker] hbase.HRegion(328): region r2,,1199281176236 available
    [junit] 2008-01-02 13:39:38,005 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 70236052/info/4306440574436419582 with 2 entries, sequence id 23, and size 230.0 for -ROOT-,,0/info
    [junit] 2008-01-02 13:39:38,006 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region -ROOT-,,0 in 532ms, sequenceid=23
    [junit] 2008-01-02 13:39:38,007 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore -ROOT-,,0/info not needed.
    [junit] 2008-01-02 13:39:38,008 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region -ROOT-,,0 does not need compaction
    [junit] 2008-01-02 13:39:38,444 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52289}
    [junit] 2008-01-02 13:39:38,475 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : r2,,1199281176236 from 140.211.11.75:52289
    [junit] 2008-01-02 13:39:38,477 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : r2,,1199281176236 from 140.211.11.75:52289
    [junit] 2008-01-02 13:39:38,477 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1577): 140.211.11.75:52289 serving r2,,1199281176236
    [junit] 2008-01-02 13:39:38,478 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:39:38,478 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:52289
    [junit] 2008-01-02 13:39:38,480 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: r2,,1199281176236, startKey: <>, encodedName(195367671) 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}}} open on 140.211.11.75:52289
    [junit] 2008-01-02 13:39:38,480 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2008-01-02 13:39:38,481 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row r2,,1199281176236 in table .META.,,1 with startcode 1199281167185 and server 140.211.11.75:52289
    [junit] 2008-01-02 13:39:38,483 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52289} complete
    [junit] 2008-01-02 13:39:39,414 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 472.0
    [junit] 2008-01-02 13:39:39,938 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/91662947690732964 with 6 entries, sequence id 26, and size 666.0 for .META.,,1/info
    [junit] 2008-01-02 13:39:39,939 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 525ms, sequenceid=26
    [junit] 2008-01-02 13:39:39,941 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed.
    [junit] 2008-01-02 13:39:39,942 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction
    [junit] 2008-01-02 13:39:40,424 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52289}
    [junit] 2008-01-02 13:39:40,452 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199281167218, startKey: <>, encodedName(1426859610) 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:39:40,456 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199281176236, startKey: <>, encodedName(195367671) 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:39:40,458 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52289} complete
    [junit] 2008-01-02 13:39:40,459 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2008-01-02 13:39:41,444 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r1,,1199281167218. Size 310.0
    [junit] 2008-01-02 13:39:41,743 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table r2
    [junit] 2008-01-02 13:39:41,761 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for r2 at address: 140.211.11.75:52289, 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] 2008-01-02 13:39:42,824 INFO  [IPC Server handler 2 on 60000] hbase.HLog(313): new log writer created at /hbase/hregion_326816702/log/hlog.dat.000
    [junit] 2008-01-02 13:39:43,600 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1199281181768/a (326816702/a) (no reconstruction log)
    [junit] 2008-01-02 13:39:43,608 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/a (326816702/a) is -1
    [junit] 2008-01-02 13:39:43,924 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1426859610/a/6276154632038349785 with 5 entries, sequence id 27, and size 270.0 for r1,,1199281167218/a
    [junit] 2008-01-02 13:39:44,113 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1199281181768/b (326816702/b) (no reconstruction log)
    [junit] 2008-01-02 13:39:44,116 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/b (326816702/b) is -1
    [junit] 2008-01-02 13:39:44,395 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1199281181768/c (326816702/c) (no reconstruction log)
    [junit] 2008-01-02 13:39:44,397 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/c (326816702/c) is -1
    [junit] 2008-01-02 13:39:44,570 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1199281181768/d (326816702/d) (no reconstruction log)
    [junit] 2008-01-02 13:39:44,572 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/d (326816702/d) is -1
    [junit] 2008-01-02 13:39:44,653 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting result_table,,1199281181768/e (326816702/e) (no reconstruction log)
    [junit] 2008-01-02 13:39:44,656 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/e (326816702/e) is -1
    [junit] 2008-01-02 13:39:44,657 DEBUG [IPC Server handler 2 on 60000] hbase.HRegion(300): Next sequence id for region result_table,,1199281181768 is 0
    [junit] 2008-01-02 13:39:44,658 INFO  [IPC Server handler 2 on 60000] hbase.HRegion(328): region result_table,,1199281181768 available
    [junit] 2008-01-02 13:39:44,661 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1199281181768/e (326816702/e)
    [junit] 2008-01-02 13:39:44,661 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1199281181768/d (326816702/d)
    [junit] 2008-01-02 13:39:44,662 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1199281181768/a (326816702/a)
    [junit] 2008-01-02 13:39:44,662 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1199281181768/b (326816702/b)
    [junit] 2008-01-02 13:39:44,662 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed result_table,,1199281181768/c (326816702/c)
    [junit] 2008-01-02 13:39:44,663 INFO  [IPC Server handler 2 on 60000] hbase.HRegion(430): closed result_table,,1199281181768
    [junit] 2008-01-02 13:39:44,663 DEBUG [IPC Server handler 2 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_326816702/log
    [junit] 2008-01-02 13:39:44,795 INFO  [IPC Server handler 2 on 60000] hbase.HMaster(2547): created table result_table
    [junit] 2008-01-02 13:39:44,796 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing a find...
    [junit] 2008-01-02 13:39:44,811 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: result_table,,1199281181768, startKey: <>, encodedName(326816702) 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] 2008-01-02 13:39:44,812 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table result_table not currently being served.
    [junit] 2008-01-02 13:39:45,290 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1426859610/b/4486848146279812977 with 5 entries, sequence id 27, and size 270.0 for r1,,1199281167218/b
    [junit] 2008-01-02 13:39:45,544 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(1858): assigning region result_table,,1199281181768 to the only server 140.211.11.75:52289
    [junit] 2008-01-02 13:39:45,545 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : result_table,,1199281181768
    [junit] 2008-01-02 13:39:45,549 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199281181768/a (326816702/a) (no reconstruction log)
    [junit] 2008-01-02 13:39:45,551 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/a (326816702/a) is -1
    [junit] 2008-01-02 13:39:45,558 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199281181768/b (326816702/b) (no reconstruction log)
    [junit] 2008-01-02 13:39:45,560 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/b (326816702/b) is -1
    [junit] 2008-01-02 13:39:45,566 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199281181768/c (326816702/c) (no reconstruction log)
    [junit] 2008-01-02 13:39:45,569 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/c (326816702/c) is -1
    [junit] 2008-01-02 13:39:45,576 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199281181768/d (326816702/d) (no reconstruction log)
    [junit] 2008-01-02 13:39:45,578 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/d (326816702/d) is -1
    [junit] 2008-01-02 13:39:45,583 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199281181768/e (326816702/e) (no reconstruction log)
    [junit] 2008-01-02 13:39:45,585 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199281181768/e (326816702/e) is -1
    [junit] 2008-01-02 13:39:45,587 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region result_table,,1199281181768 is 0
    [junit] 2008-01-02 13:39:45,589 INFO  [RegionServer:0.worker] hbase.HRegion(328): region result_table,,1199281181768 available
    [junit] 2008-01-02 13:39:45,735 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1426859610/c/6572207897595388638 with 5 entries, sequence id 27, and size 310.0 for r1,,1199281167218/c
    [junit] 2008-01-02 13:39:45,736 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r1,,1199281167218 in 4292ms, sequenceid=27
    [junit] 2008-01-02 13:39:45,736 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199281167218/a not needed.
    [junit] 2008-01-02 13:39:45,737 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199281167218/b not needed.
    [junit] 2008-01-02 13:39:45,737 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199281167218/c not needed.
    [junit] 2008-01-02 13:39:45,738 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r1,,1199281167218 does not need compaction
    [junit] 2008-01-02 13:39:46,555 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : result_table,,1199281181768 from 140.211.11.75:52289
    [junit] 2008-01-02 13:39:46,555 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : result_table,,1199281181768 from 140.211.11.75:52289
    [junit] 2008-01-02 13:39:46,555 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1577): 140.211.11.75:52289 serving result_table,,1199281181768
    [junit] 2008-01-02 13:39:46,556 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:52289
    [junit] 2008-01-02 13:39:46,557 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: result_table,,1199281181768, startKey: <>, encodedName(326816702) 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:52289
    [junit] 2008-01-02 13:39:46,557 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2008-01-02 13:39:46,557 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row result_table,,1199281181768 in table .META.,,1 with startcode 1199281167185 and server 140.211.11.75:52289
    [junit] 2008-01-02 13:39:47,493 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r2,,1199281176236. Size 132.0
    [junit] 2008-01-02 13:39:47,926 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 195367671/e/3101153976359177550 with 3 entries, sequence id 37, and size 218.0 for r2,,1199281176236/e
    [junit] 2008-01-02 13:39:48,453 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52289}
    [junit] 2008-01-02 13:39:48,509 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:39:48,513 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52289} complete
    [junit] 2008-01-02 13:39:48,897 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 195367671/d/7980928966242705583 with 3 entries, sequence id 37, and size 218.0 for r2,,1199281176236/d
    [junit] 2008-01-02 13:39:48,898 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r2,,1199281176236 in 1405ms, sequenceid=37
    [junit] 2008-01-02 13:39:48,898 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199281176236/e not needed.
    [junit] 2008-01-02 13:39:48,898 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199281176236/d not needed.
    [junit] 2008-01-02 13:39:48,899 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r2,,1199281176236 does not need compaction
    [junit] 2008-01-02 13:39:49,813 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table result_table
    [junit] 2008-01-02 13:39:49,830 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for result_table at address: 140.211.11.75:52289, 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] 2008-01-02 13:39:49,943 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 331.0
    [junit] 2008-01-02 13:39:50,129 WARN  [IPC Server handler 1 on 52242] dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system because it does not exist
    [junit] 2008-01-02 13:39:50,433 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52289}
    [junit] 2008-01-02 13:39:50,447 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199281167218, startKey: <>, encodedName(1426859610) 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:39:50,449 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199281176236, startKey: <>, encodedName(195367671) 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:39:50,451 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52289} complete
    [junit] 2008-01-02 13:39:50,452 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2008-01-02 13:39:50,977 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/7178006554177543494 with 3 entries, sequence id 38, and size 483.0 for .META.,,1/info
    [junit] 2008-01-02 13:39:50,978 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 1035ms, sequenceid=38
    [junit] 2008-01-02 13:39:50,978 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed.
    [junit] 2008-01-02 13:39:50,979 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction
    [junit] 2008-01-02 13:39:51,980 WARN  [IPC Server handler 6 on 52242] 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] 2008-01-02 13:39:51,981 WARN  [main] mapred.JobClient(496): No job jar file set.  User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2008-01-02 13:39:52,034 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find...
    [junit] 2008-01-02 13:39:52,059 DEBUG [main] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r1
    [junit] 2008-01-02 13:39:52,096 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2008-01-02 13:39:52,711 DEBUG [Thread-204] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2008-01-02 13:39:52,859 DEBUG [Thread-204] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
    [junit] 2008-01-02 13:39:52,892 DEBUG [Thread-204] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r2
    [junit] 2008-01-02 13:39:55,016 WARN  [Task Commit Thread] mapred.JobTracker$TaskCommitQueue(2032): Task Commit Thread exiting...
    [junit] 2008-01-02 13:39:55,592 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region result_table,,1199281181768. Size 306.0
    [junit] 2008-01-02 13:39:56,985 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 326816702/e/493596157583612290 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199281181768/e
    [junit] 2008-01-02 13:39:57,986 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 326816702/d/1363113781485101250 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199281181768/d
    [junit] 2008-01-02 13:39:58,462 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52289}
    [junit] 2008-01-02 13:39:58,491 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:39:58,494 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52289} complete
    [junit] 2008-01-02 13:39:58,657 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 326816702/a/8442079636926915353 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199281181768/a
    [junit] 2008-01-02 13:39:59,256 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 326816702/b/4777187673889836021 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199281181768/b
    [junit] 2008-01-02 13:39:59,887 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 326816702/c/3292054299989590801 with 3 entries, sequence id 54, and size 230.0 for result_table,,1199281181768/c
    [junit] 2008-01-02 13:39:59,887 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region result_table,,1199281181768 in 4295ms, sequenceid=54
    [junit] 2008-01-02 13:39:59,888 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199281181768/e not needed.
    [junit] 2008-01-02 13:39:59,889 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199281181768/d not needed.
    [junit] 2008-01-02 13:39:59,890 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199281181768/a not needed.
    [junit] 2008-01-02 13:39:59,890 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199281181768/b not needed.
    [junit] 2008-01-02 13:39:59,891 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199281181768/c not needed.
    [junit] 2008-01-02 13:39:59,891 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region result_table,,1199281181768 does not need compaction
    [junit] 2008-01-02 13:40:00,442 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52289}
    [junit] 2008-01-02 13:40:00,478 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199281167218, startKey: <>, encodedName(1426859610) 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:40:00,483 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199281176236, startKey: <>, encodedName(195367671) 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:40:00,486 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1199281181768, startKey: <>, encodedName(326816702) 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:52289, startCode: 1199281167185
    [junit] 2008-01-02 13:40:00,489 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52289} complete
    [junit] 2008-01-02 13:40:00,490 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2008-01-02 13:40:05,101 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2008-01-02 13:40:05,104 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2008-01-02 13:40:05,106 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2008-01-02 13:40:05,109 INFO  [main] algebra.TestTableJoinMapReduce(244): result_table.row.count: 3
    [junit] 2008-01-02 13:40:05,110 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down HBase Cluster
    [junit] 2008-01-02 13:40:05,111 INFO  [main] hbase.HMaster(2526): Cluster shutdown requested. Starting to quiesce servers
    [junit] 2008-01-02 13:40:05,764 INFO  [RegionServer:0] hbase.HRegionServer(774): Got quiesce server message
    [junit] 2008-01-02 13:40:05,765 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGIONSERVER_QUIESCE : 
    [junit] 2008-01-02 13:40:05,766 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r1,,1199281167218
    [junit] 2008-01-02 13:40:05,766 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199281167218/a (1426859610/a)
    [junit] 2008-01-02 13:40:05,767 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199281167218/b (1426859610/b)
    [junit] 2008-01-02 13:40:05,767 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199281167218/c (1426859610/c)
    [junit] 2008-01-02 13:40:05,768 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed r1,,1199281167218
    [junit] 2008-01-02 13:40:05,768 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r2,,1199281176236
    [junit] 2008-01-02 13:40:05,769 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199281176236/e (195367671/e)
    [junit] 2008-01-02 13:40:05,769 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199281176236/d (195367671/d)
    [junit] 2008-01-02 13:40:05,770 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed r2,,1199281176236
    [junit] 2008-01-02 13:40:05,770 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region result_table,,1199281181768
    [junit] 2008-01-02 13:40:05,771 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199281181768/e (326816702/e)
    [junit] 2008-01-02 13:40:05,772 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199281181768/d (326816702/d)
    [junit] 2008-01-02 13:40:05,773 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199281181768/a (326816702/a)
    [junit] 2008-01-02 13:40:05,773 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199281181768/b (326816702/b)
    [junit] 2008-01-02 13:40:05,774 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199281181768/c (326816702/c)
    [junit] 2008-01-02 13:40:05,774 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed result_table,,1199281181768
    [junit] 2008-01-02 13:40:06,773 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1372): Region server 140.211.11.75:52289 quiesced
    [junit] 2008-01-02 13:40:06,774 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1380): All user tables quiesced. Proceeding with shutdown
    [junit] 2008-01-02 13:40:06,774 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1206): telling root scanner to stop
    [junit] 2008-01-02 13:40:06,775 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1214): telling meta scanner to stop
    [junit] 2008-01-02 13:40:06,775 INFO  [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner exiting
    [junit] 2008-01-02 13:40:06,775 INFO  [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner exiting
    [junit] 2008-01-02 13:40:06,775 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1222): meta and root scanners notified
    [junit] 2008-01-02 13:40:06,778 INFO  [RegionServer:0] hbase.HRegionServer(768): Got regionserver stop message
    [junit] 2008-01-02 13:40:06,778 INFO  [RegionServer:0] hbase.Leases(109): RegionServer:0 closing leases
    [junit] 2008-01-02 13:40:06,779 INFO  [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker exiting
    [junit] 2008-01-02 13:40:06,779 INFO  [RegionServer:0] hbase.Leases(125): RegionServer:0 closed leases
    [junit] 2008-01-02 13:40:06,780 INFO  [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting
    [junit] 2008-01-02 13:40:06,782 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1202): worker thread exiting
    [junit] 2008-01-02 13:40:06,781 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region -ROOT-,,0
    [junit] 2008-01-02 13:40:06,782 INFO  [RegionServer:0.splitter] hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting
    [junit] 2008-01-02 13:40:06,784 DEBUG [RegionServer:0] hbase.HStore(880): closed -ROOT-,,0/info (70236052/info)
    [junit] 2008-01-02 13:40:06,781 INFO  [RegionServer:0.compactor] hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting
    [junit] 2008-01-02 13:40:06,785 INFO  [RegionServer:0] hbase.HRegion(430): closed -ROOT-,,0
    [junit] 2008-01-02 13:40:06,786 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region .META.,,1
    [junit] 2008-01-02 13:40:06,787 DEBUG [RegionServer:0] hbase.HStore(880): closed .META.,,1/info (1028785192/info)
    [junit] 2008-01-02 13:40:06,787 INFO  [RegionServer:0] hbase.HRegion(430): closed .META.,,1
    [junit] 2008-01-02 13:40:06,788 DEBUG [RegionServer:0] hbase.HLog(399): closing log writer in /hbase/log_140.211.11.75_1199281167185_52289
    [junit] 2008-01-02 13:40:06,966 INFO  [RegionServer:0] hbase.HRegionServer(888): telling master that region server is shutting down at: 140.211.11.75:52289
    [junit] 2008-01-02 13:40:06,968 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1339): Region server 140.211.11.75:52289: MSG_REPORT_EXITING -- cancelling lease
    [junit] 2008-01-02 13:40:06,969 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1481): Cancelling lease for 140.211.11.75:52289
    [junit] 2008-01-02 13:40:06,970 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1346): Region server 140.211.11.75:52289: MSG_REPORT_EXITING -- lease cancelled
    [junit] 2008-01-02 13:40:06,970 INFO  [RegionServer:0] hbase.HRegionServer(895): stopping server at: 140.211.11.75:52289
    [junit] 2008-01-02 13:40:06,971 INFO  [RegionServer:0] hbase.HRegionServer(900): RegionServer:0 exiting
    [junit] 2008-01-02 13:40:07,781 DEBUG [HMaster] hbase.HMaster(1206): telling root scanner to stop
    [junit] 2008-01-02 13:40:07,782 DEBUG [HMaster] hbase.HMaster(1214): telling meta scanner to stop
    [junit] 2008-01-02 13:40:07,783 DEBUG [HMaster] hbase.HMaster(1222): meta and root scanners notified
    [junit] 2008-01-02 13:40:07,783 INFO  [HMaster] hbase.Leases(109): HMaster closing leases
    [junit] 2008-01-02 13:40:07,785 INFO  [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker exiting
    [junit] 2008-01-02 13:40:07,786 INFO  [HMaster] hbase.Leases(125): HMaster closed leases
    [junit] 2008-01-02 13:40:07,787 INFO  [HMaster] hbase.HMaster(1139): HMaster main thread exiting
    [junit] 2008-01-02 13:40:07,787 INFO  [main] hbase.LocalHBaseCluster(238): Shutdown HMaster 1 region server(s)
    [junit] 2008-01-02 13:40:08,342 INFO  [main] hbase.StaticTestEnvironment(133): Shutting down FileSystem
    [junit] 2008-01-02 13:40:08,421 INFO  [main] hbase.StaticTestEnvironment(140): Shutting down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 0
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 48.683 sec
    [junit] 2008-01-02 13:40:09,324 INFO  [main] hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
    [junit] 2008-01-02 13:40:09,325 INFO  [main] hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
    [junit] Running org.apache.hadoop.hbase.util.TestBase64

    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.314 sec
    [junit] Running org.apache.hadoop.hbase.util.TestKeying
    [junit] Original url http://abc:bcd@www.example.com/index.html?query=something#middle, Transformed url r:http://abc:bcd@com.example.www/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.059 sec
    [junit] Running org.onelab.test.TestFilter
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.057 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: 130 minutes 59 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2344
Updating HADOOP-2492


Build failed in Hudson: Hadoop-Nightly #352

Posted by hu...@lucene.zones.apache.org.
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/352/changes

Changes:

[stack] HADOOP-2505 formatter classes missing apache license

------------------------------------------
[...truncated 49270 lines...]
    [junit] 2008-01-01 12:47:06,234 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(601): starting r2,,1199191625921/e (2130032715/e) (no reconstruction log)
    [junit] 2008-01-01 12:47:06,236 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(629): maximum sequence id for hstore r2,,1199191625921/e (2130032715/e) is -1
    [junit] 2008-01-01 12:47:06,237 DEBUG [IPC Server handler 2 on 60000] hbase.HRegion(300): Next sequence id for region r2,,1199191625921 is 0
    [junit] 2008-01-01 12:47:06,239 INFO  [IPC Server handler 2 on 60000] hbase.HRegion(328): region r2,,1199191625921 available
    [junit] 2008-01-01 12:47:06,240 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed r2,,1199191625921/e (2130032715/e)
    [junit] 2008-01-01 12:47:06,241 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(880): closed r2,,1199191625921/d (2130032715/d)
    [junit] 2008-01-01 12:47:06,241 INFO  [IPC Server handler 2 on 60000] hbase.HRegion(430): closed r2,,1199191625921
    [junit] 2008-01-01 12:47:06,241 DEBUG [IPC Server handler 2 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_2130032715/log
    [junit] 2008-01-01 12:47:06,734 INFO  [IPC Server handler 2 on 60000] hbase.HMaster(2547): created table r2
    [junit] 2008-01-01 12:47:06,735 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
    [junit] 2008-01-01 12:47:06,738 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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}}}
    [junit] 2008-01-01 12:47:06,739 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table r2 not currently being served.
    [junit] 2008-01-01 12:47:07,013 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region -ROOT-,,0. Size 92.0
    [junit] 2008-01-01 12:47:07,054 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(1858): assigning region r2,,1199191625921 to the only server 140.211.11.75:54586
    [junit] 2008-01-01 12:47:07,055 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : r2,,1199191625921
    [junit] 2008-01-01 12:47:07,060 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199191625921/d (2130032715/d) (no reconstruction log)
    [junit] 2008-01-01 12:47:07,062 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199191625921/d (2130032715/d) is -1
    [junit] 2008-01-01 12:47:07,068 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199191625921/e (2130032715/e) (no reconstruction log)
    [junit] 2008-01-01 12:47:07,071 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199191625921/e (2130032715/e) is -1
    [junit] 2008-01-01 12:47:07,072 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region r2,,1199191625921 is 0
    [junit] 2008-01-01 12:47:07,085 INFO  [RegionServer:0.worker] hbase.HRegion(328): region r2,,1199191625921 available
    [junit] 2008-01-01 12:47:07,342 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 70236052/info/3579751099364642485 with 2 entries, sequence id 23, and size 230.0 for -ROOT-,,0/info
    [junit] 2008-01-01 12:47:07,342 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region -ROOT-,,0 in 329ms, sequenceid=23
    [junit] 2008-01-01 12:47:07,343 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore -ROOT-,,0/info not needed.
    [junit] 2008-01-01 12:47:07,343 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region -ROOT-,,0 does not need compaction
    [junit] 2008-01-01 12:47:08,013 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586}
    [junit] 2008-01-01 12:47:08,048 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:08,051 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} complete
    [junit] 2008-01-01 12:47:08,064 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : r2,,1199191625921 from 140.211.11.75:54586
    [junit] 2008-01-01 12:47:08,064 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : r2,,1199191625921 from 140.211.11.75:54586
    [junit] 2008-01-01 12:47:08,065 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1577): 140.211.11.75:54586 serving r2,,1199191625921
    [junit] 2008-01-01 12:47:08,065 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:54586
    [junit] 2008-01-01 12:47:08,066 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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}}} open on 140.211.11.75:54586
    [junit] 2008-01-01 12:47:08,066 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2008-01-01 12:47:08,066 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row r2,,1199191625921 in table .META.,,1 with startcode 1199191616628 and server 140.211.11.75:54586
    [junit] 2008-01-01 12:47:08,993 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 472.0
    [junit] 2008-01-01 12:47:09,396 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/3309222824595699538 with 6 entries, sequence id 26, and size 666.0 for .META.,,1/info
    [junit] 2008-01-01 12:47:09,396 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 403ms, sequenceid=26
    [junit] 2008-01-01 12:47:09,397 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed.
    [junit] 2008-01-01 12:47:09,397 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction
    [junit] 2008-01-01 12:47:10,003 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586}
    [junit] 2008-01-01 12:47:10,034 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199191616648, startKey: <>, encodedName(1778447438) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:10,036 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:10,038 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} complete
    [junit] 2008-01-01 12:47:10,038 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2008-01-01 12:47:11,033 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r1,,1199191616648. Size 310.0
    [junit] 2008-01-01 12:47:11,375 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1778447438/a/7291194348426289937 with 5 entries, sequence id 27, and size 270.0 for r1,,1199191616648/a
    [junit] 2008-01-01 12:47:11,736 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1778447438/b/637892970681593511 with 5 entries, sequence id 27, and size 270.0 for r1,,1199191616648/b
    [junit] 2008-01-01 12:47:11,742 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table r2
    [junit] 2008-01-01 12:47:11,761 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for r2 at address: 140.211.11.75:54586, 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] 2008-01-01 12:47:12,017 INFO  [IPC Server handler 1 on 60000] hbase.HLog(313): new log writer created at /hbase/hregion_61750783/log/hlog.dat.000
    [junit] 2008-01-01 12:47:12,158 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/a (61750783/a) (no reconstruction log)
    [junit] 2008-01-01 12:47:12,160 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/a (61750783/a) is -1
    [junit] 2008-01-01 12:47:12,206 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1778447438/c/1839596736998806994 with 5 entries, sequence id 27, and size 310.0 for r1,,1199191616648/c
    [junit] 2008-01-01 12:47:12,206 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r1,,1199191616648 in 1174ms, sequenceid=27
    [junit] 2008-01-01 12:47:12,207 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199191616648/a not needed.
    [junit] 2008-01-01 12:47:12,207 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199191616648/b not needed.
    [junit] 2008-01-01 12:47:12,207 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199191616648/c not needed.
    [junit] 2008-01-01 12:47:12,208 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r1,,1199191616648 does not need compaction
    [junit] 2008-01-01 12:47:12,258 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/b (61750783/b) (no reconstruction log)
    [junit] 2008-01-01 12:47:12,260 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/b (61750783/b) is -1
    [junit] 2008-01-01 12:47:12,330 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/c (61750783/c) (no reconstruction log)
    [junit] 2008-01-01 12:47:12,332 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/c (61750783/c) is -1
    [junit] 2008-01-01 12:47:12,405 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/d (61750783/d) (no reconstruction log)
    [junit] 2008-01-01 12:47:12,407 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/d (61750783/d) is -1
    [junit] 2008-01-01 12:47:12,480 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199191631766/e (61750783/e) (no reconstruction log)
    [junit] 2008-01-01 12:47:12,482 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/e (61750783/e) is -1
    [junit] 2008-01-01 12:47:12,484 DEBUG [IPC Server handler 1 on 60000] hbase.HRegion(300): Next sequence id for region result_table,,1199191631766 is 0
    [junit] 2008-01-01 12:47:12,486 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(328): region result_table,,1199191631766 available
    [junit] 2008-01-01 12:47:12,487 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/e (61750783/e)
    [junit] 2008-01-01 12:47:12,488 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/d (61750783/d)
    [junit] 2008-01-01 12:47:12,488 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/a (61750783/a)
    [junit] 2008-01-01 12:47:12,488 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/b (61750783/b)
    [junit] 2008-01-01 12:47:12,489 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199191631766/c (61750783/c)
    [junit] 2008-01-01 12:47:12,489 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(430): closed result_table,,1199191631766
    [junit] 2008-01-01 12:47:12,489 DEBUG [IPC Server handler 1 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_61750783/log
    [junit] 2008-01-01 12:47:12,579 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(2547): created table result_table
    [junit] 2008-01-01 12:47:12,580 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing a find...
    [junit] 2008-01-01 12:47:12,597 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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] 2008-01-01 12:47:12,599 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table result_table not currently being served.
    [junit] 2008-01-01 12:47:13,113 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1858): assigning region result_table,,1199191631766 to the only server 140.211.11.75:54586
    [junit] 2008-01-01 12:47:13,115 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : result_table,,1199191631766
    [junit] 2008-01-01 12:47:13,120 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/a (61750783/a) (no reconstruction log)
    [junit] 2008-01-01 12:47:13,123 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/a (61750783/a) is -1
    [junit] 2008-01-01 12:47:13,127 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/b (61750783/b) (no reconstruction log)
    [junit] 2008-01-01 12:47:13,129 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/b (61750783/b) is -1
    [junit] 2008-01-01 12:47:13,133 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/c (61750783/c) (no reconstruction log)
    [junit] 2008-01-01 12:47:13,135 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/c (61750783/c) is -1
    [junit] 2008-01-01 12:47:13,139 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/d (61750783/d) (no reconstruction log)
    [junit] 2008-01-01 12:47:13,141 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/d (61750783/d) is -1
    [junit] 2008-01-01 12:47:13,146 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199191631766/e (61750783/e) (no reconstruction log)
    [junit] 2008-01-01 12:47:13,148 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199191631766/e (61750783/e) is -1
    [junit] 2008-01-01 12:47:13,149 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region result_table,,1199191631766 is 0
    [junit] 2008-01-01 12:47:13,151 INFO  [RegionServer:0.worker] hbase.HRegion(328): region result_table,,1199191631766 available
    [junit] 2008-01-01 12:47:14,124 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : result_table,,1199191631766 from 140.211.11.75:54586
    [junit] 2008-01-01 12:47:14,124 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : result_table,,1199191631766 from 140.211.11.75:54586
    [junit] 2008-01-01 12:47:14,124 INFO  [IPC Server handler 3 on 60000] hbase.HMaster(1577): 140.211.11.75:54586 serving result_table,,1199191631766
    [junit] 2008-01-01 12:47:14,125 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:54586
    [junit] 2008-01-01 12:47:14,126 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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:54586
    [junit] 2008-01-01 12:47:14,126 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2008-01-01 12:47:14,126 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row result_table,,1199191631766 in table .META.,,1 with startcode 1199191616628 and server 140.211.11.75:54586
    [junit] 2008-01-01 12:47:17,092 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r2,,1199191625921. Size 132.0
    [junit] 2008-01-01 12:47:17,434 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 2130032715/e/7830696023592587082 with 3 entries, sequence id 37, and size 218.0 for r2,,1199191625921/e
    [junit] 2008-01-01 12:47:17,601 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table result_table
    [junit] 2008-01-01 12:47:17,633 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for result_table at address: 140.211.11.75:54586, 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] 2008-01-01 12:47:17,761 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 2130032715/d/4493033548954924806 with 3 entries, sequence id 37, and size 218.0 for r2,,1199191625921/d
    [junit] 2008-01-01 12:47:17,761 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r2,,1199191625921 in 669ms, sequenceid=37
    [junit] 2008-01-01 12:47:17,763 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199191625921/e not needed.
    [junit] 2008-01-01 12:47:17,763 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199191625921/d not needed.
    [junit] 2008-01-01 12:47:17,763 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r2,,1199191625921 does not need compaction
    [junit] 2008-01-01 12:47:17,894 WARN  [IPC Server handler 9 on 54567] dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system because it does not exist
    [junit] 2008-01-01 12:47:18,021 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586}
    [junit] 2008-01-01 12:47:18,045 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:18,047 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} complete
    [junit] 2008-01-01 12:47:19,401 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 331.0
    [junit] 2008-01-01 12:47:19,754 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/2655026151830195016 with 3 entries, sequence id 38, and size 483.0 for .META.,,1/info
    [junit] 2008-01-01 12:47:19,755 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 354ms, sequenceid=38
    [junit] 2008-01-01 12:47:19,756 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed.
    [junit] 2008-01-01 12:47:19,756 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction
    [junit] 2008-01-01 12:47:19,774 WARN  [IPC Server handler 0 on 54567] 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] 2008-01-01 12:47:19,775 WARN  [main] mapred.JobClient(496): No job jar file set.  User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2008-01-01 12:47:19,807 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find...
    [junit] 2008-01-01 12:47:19,847 DEBUG [main] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r1
    [junit] 2008-01-01 12:47:19,880 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2008-01-01 12:47:20,011 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586}
    [junit] 2008-01-01 12:47:20,034 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199191616648, startKey: <>, encodedName(1778447438) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:20,036 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:20,039 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:20,042 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} complete
    [junit] 2008-01-01 12:47:20,042 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2008-01-01 12:47:20,259 DEBUG [Thread-199] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2008-01-01 12:47:20,525 DEBUG [Thread-199] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
    [junit] 2008-01-01 12:47:20,547 DEBUG [Thread-199] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r2
    [junit] 2008-01-01 12:47:22,350 WARN  [Task Commit Thread] mapred.JobTracker$TaskCommitQueue(2032): Task Commit Thread exiting...
    [junit] 2008-01-01 12:47:23,161 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region result_table,,1199191631766. Size 306.0
    [junit] 2008-01-01 12:47:23,525 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/e/8628481918409188180 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199191631766/e
    [junit] 2008-01-01 12:47:23,891 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/d/802572002926107038 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199191631766/d
    [junit] 2008-01-01 12:47:24,315 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/a/8509768190983818557 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199191631766/a
    [junit] 2008-01-01 12:47:24,663 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/b/1278437776700412546 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199191631766/b
    [junit] 2008-01-01 12:47:24,989 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 61750783/c/4518460444595148452 with 3 entries, sequence id 54, and size 230.0 for result_table,,1199191631766/c
    [junit] 2008-01-01 12:47:24,990 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region result_table,,1199191631766 in 1829ms, sequenceid=54
    [junit] 2008-01-01 12:47:24,990 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/e not needed.
    [junit] 2008-01-01 12:47:24,990 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/d not needed.
    [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/a not needed.
    [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/b not needed.
    [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199191631766/c not needed.
    [junit] 2008-01-01 12:47:24,991 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region result_table,,1199191631766 does not need compaction
    [junit] 2008-01-01 12:47:28,030 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586}
    [junit] 2008-01-01 12:47:28,053 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:28,055 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:54586} complete
    [junit] 2008-01-01 12:47:30,020 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586}
    [junit] 2008-01-01 12:47:30,041 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199191616648, startKey: <>, encodedName(1778447438) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:30,043 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199191625921, startKey: <>, encodedName(2130032715) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:30,045 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1199191631766, startKey: <>, encodedName(61750783) 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:54586, startCode: 1199191616628
    [junit] 2008-01-01 12:47:30,047 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:54586} complete
    [junit] 2008-01-01 12:47:30,047 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2008-01-01 12:47:32,435 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2008-01-01 12:47:32,437 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2008-01-01 12:47:32,439 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2008-01-01 12:47:32,441 INFO  [main] algebra.TestTableJoinMapReduce(244): result_table.row.count: 3
    [junit] 2008-01-01 12:47:32,441 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down HBase Cluster
    [junit] 2008-01-01 12:47:32,442 INFO  [main] hbase.HMaster(2526): Cluster shutdown requested. Starting to quiesce servers
    [junit] 2008-01-01 12:47:33,451 INFO  [RegionServer:0] hbase.HRegionServer(774): Got quiesce server message
    [junit] 2008-01-01 12:47:33,452 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGIONSERVER_QUIESCE : 
    [junit] 2008-01-01 12:47:33,452 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r1,,1199191616648
    [junit] 2008-01-01 12:47:33,453 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199191616648/a (1778447438/a)
    [junit] 2008-01-01 12:47:33,453 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199191616648/b (1778447438/b)
    [junit] 2008-01-01 12:47:33,453 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199191616648/c (1778447438/c)
    [junit] 2008-01-01 12:47:33,453 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed r1,,1199191616648
    [junit] 2008-01-01 12:47:33,454 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r2,,1199191625921
    [junit] 2008-01-01 12:47:33,454 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199191625921/e (2130032715/e)
    [junit] 2008-01-01 12:47:33,454 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199191625921/d (2130032715/d)
    [junit] 2008-01-01 12:47:33,454 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed r2,,1199191625921
    [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region result_table,,1199191631766
    [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/e (61750783/e)
    [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/d (61750783/d)
    [junit] 2008-01-01 12:47:33,455 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/a (61750783/a)
    [junit] 2008-01-01 12:47:33,456 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/b (61750783/b)
    [junit] 2008-01-01 12:47:33,456 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199191631766/c (61750783/c)
    [junit] 2008-01-01 12:47:33,456 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed result_table,,1199191631766
    [junit] 2008-01-01 12:47:34,460 INFO  [IPC Server handler 3 on 60000] hbase.HMaster(1372): Region server 140.211.11.75:54586 quiesced
    [junit] 2008-01-01 12:47:34,460 INFO  [IPC Server handler 3 on 60000] hbase.HMaster(1380): All user tables quiesced. Proceeding with shutdown
    [junit] 2008-01-01 12:47:34,461 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1206): telling root scanner to stop
    [junit] 2008-01-01 12:47:34,461 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1214): telling meta scanner to stop
    [junit] 2008-01-01 12:47:34,461 INFO  [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner exiting
    [junit] 2008-01-01 12:47:34,461 INFO  [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner exiting
    [junit] 2008-01-01 12:47:34,461 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1222): meta and root scanners notified
    [junit] 2008-01-01 12:47:34,463 INFO  [RegionServer:0] hbase.HRegionServer(768): Got regionserver stop message
    [junit] 2008-01-01 12:47:34,463 INFO  [RegionServer:0] hbase.Leases(109): RegionServer:0 closing leases
    [junit] 2008-01-01 12:47:34,464 INFO  [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker exiting
    [junit] 2008-01-01 12:47:34,464 INFO  [RegionServer:0] hbase.Leases(125): RegionServer:0 closed leases
    [junit] 2008-01-01 12:47:34,465 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region -ROOT-,,0
    [junit] 2008-01-01 12:47:34,466 DEBUG [RegionServer:0] hbase.HStore(880): closed -ROOT-,,0/info (70236052/info)
    [junit] 2008-01-01 12:47:34,466 INFO  [RegionServer:0.splitter] hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting
    [junit] 2008-01-01 12:47:34,467 INFO  [RegionServer:0] hbase.HRegion(430): closed -ROOT-,,0
    [junit] 2008-01-01 12:47:34,465 INFO  [RegionServer:0.compactor] hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting
    [junit] 2008-01-01 12:47:34,468 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region .META.,,1
    [junit] 2008-01-01 12:47:34,465 INFO  [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting
    [junit] 2008-01-01 12:47:34,468 DEBUG [RegionServer:0] hbase.HStore(880): closed .META.,,1/info (1028785192/info)
    [junit] 2008-01-01 12:47:34,469 INFO  [RegionServer:0] hbase.HRegion(430): closed .META.,,1
    [junit] 2008-01-01 12:47:34,469 DEBUG [RegionServer:0] hbase.HLog(399): closing log writer in /hbase/log_140.211.11.75_1199191616628_54586
    [junit] 2008-01-01 12:47:34,605 INFO  [RegionServer:0] hbase.HRegionServer(888): telling master that region server is shutting down at: 140.211.11.75:54586
    [junit] 2008-01-01 12:47:34,607 DEBUG [IPC Server handler 2 on 60000] hbase.HMaster(1339): Region server 140.211.11.75:54586: MSG_REPORT_EXITING -- cancelling lease
    [junit] 2008-01-01 12:47:34,607 INFO  [IPC Server handler 2 on 60000] hbase.HMaster(1481): Cancelling lease for 140.211.11.75:54586
    [junit] 2008-01-01 12:47:34,607 INFO  [IPC Server handler 2 on 60000] hbase.HMaster(1346): Region server 140.211.11.75:54586: MSG_REPORT_EXITING -- lease cancelled
    [junit] 2008-01-01 12:47:34,608 INFO  [RegionServer:0] hbase.HRegionServer(895): stopping server at: 140.211.11.75:54586
    [junit] 2008-01-01 12:47:35,359 DEBUG [HMaster] hbase.HMaster(1206): telling root scanner to stop
    [junit] 2008-01-01 12:47:35,359 DEBUG [HMaster] hbase.HMaster(1214): telling meta scanner to stop
    [junit] 2008-01-01 12:47:35,360 DEBUG [HMaster] hbase.HMaster(1222): meta and root scanners notified
    [junit] 2008-01-01 12:47:35,360 INFO  [HMaster] hbase.Leases(109): HMaster closing leases
    [junit] 2008-01-01 12:47:35,361 INFO  [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker exiting
    [junit] 2008-01-01 12:47:35,361 INFO  [HMaster] hbase.Leases(125): HMaster closed leases
    [junit] 2008-01-01 12:47:35,361 INFO  [HMaster] hbase.HMaster(1139): HMaster main thread exiting
    [junit] 2008-01-01 12:47:35,469 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1202): worker thread exiting
    [junit] 2008-01-01 12:47:35,469 INFO  [RegionServer:0] hbase.HRegionServer(900): RegionServer:0 exiting
    [junit] 2008-01-01 12:47:35,470 INFO  [main] hbase.LocalHBaseCluster(238): Shutdown HMaster 1 region server(s)
    [junit] 2008-01-01 12:47:35,656 INFO  [main] hbase.StaticTestEnvironment(133): Shutting down FileSystem
    [junit] 2008-01-01 12:47:36,369 INFO  [main] hbase.StaticTestEnvironment(140): Shutting down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 0
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 54.37 sec
    [junit] 2008-01-01 12:47:37,181 INFO  [main] hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
    [junit] 2008-01-01 12:47:37,181 INFO  [main] hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
    [junit] Running org.apache.hadoop.hbase.util.TestBase64

    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.236 sec
    [junit] Running org.apache.hadoop.hbase.util.TestKeying
    [junit] Original url http://abc:bcd@www.example.com/index.html?query=something#middle, Transformed url r:http://abc:bcd@com.example.www/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.048 sec
    [junit] Running org.onelab.test.TestFilter
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.054 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: 92 minutes 50 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2505


Build failed in Hudson: Hadoop-Nightly #351

Posted by hu...@lucene.zones.apache.org.
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/351/changes

------------------------------------------
[...truncated 48796 lines...]
    [junit] 2007-12-31 13:17:39,108 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(601): starting r2,,1199107058702/d (347143206/d) (no reconstruction log)
    [junit] 2007-12-31 13:17:39,111 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(629): maximum sequence id for hstore r2,,1199107058702/d (347143206/d) is -1
    [junit] 2007-12-31 13:17:39,258 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(601): starting r2,,1199107058702/e (347143206/e) (no reconstruction log)
    [junit] 2007-12-31 13:17:39,261 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(629): maximum sequence id for hstore r2,,1199107058702/e (347143206/e) is -1
    [junit] 2007-12-31 13:17:39,262 DEBUG [IPC Server handler 4 on 60000] hbase.HRegion(300): Next sequence id for region r2,,1199107058702 is 0
    [junit] 2007-12-31 13:17:39,264 INFO  [IPC Server handler 4 on 60000] hbase.HRegion(328): region r2,,1199107058702 available
    [junit] 2007-12-31 13:17:39,266 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(880): closed r2,,1199107058702/e (347143206/e)
    [junit] 2007-12-31 13:17:39,266 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(880): closed r2,,1199107058702/d (347143206/d)
    [junit] 2007-12-31 13:17:39,267 INFO  [IPC Server handler 4 on 60000] hbase.HRegion(430): closed r2,,1199107058702
    [junit] 2007-12-31 13:17:39,267 DEBUG [IPC Server handler 4 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_347143206/log
    [junit] 2007-12-31 13:17:39,734 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region -ROOT-,,0. Size 92.0
    [junit] 2007-12-31 13:17:39,953 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(2547): created table r2
    [junit] 2007-12-31 13:17:39,954 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
    [junit] 2007-12-31 13:17:39,958 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: r2,,1199107058702, startKey: <>, encodedName(347143206) 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}}}
    [junit] 2007-12-31 13:17:39,959 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table r2 not currently being served.
    [junit] 2007-12-31 13:17:40,508 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 70236052/info/2321728754829859779 with 2 entries, sequence id 23, and size 230.0 for -ROOT-,,0/info
    [junit] 2007-12-31 13:17:40,509 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region -ROOT-,,0 in 775ms, sequenceid=23
    [junit] 2007-12-31 13:17:40,510 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore -ROOT-,,0/info not needed.
    [junit] 2007-12-31 13:17:40,510 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region -ROOT-,,0 does not need compaction
    [junit] 2007-12-31 13:17:40,784 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:37011}
    [junit] 2007-12-31 13:17:40,785 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(1858): assigning region r2,,1199107058702 to the only server 140.211.11.75:37011
    [junit] 2007-12-31 13:17:40,786 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : r2,,1199107058702
    [junit] 2007-12-31 13:17:40,790 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199107058702/d (347143206/d) (no reconstruction log)
    [junit] 2007-12-31 13:17:40,793 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199107058702/d (347143206/d) is -1
    [junit] 2007-12-31 13:17:40,798 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting r2,,1199107058702/e (347143206/e) (no reconstruction log)
    [junit] 2007-12-31 13:17:40,801 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore r2,,1199107058702/e (347143206/e) is -1
    [junit] 2007-12-31 13:17:40,802 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region r2,,1199107058702 is 0
    [junit] 2007-12-31 13:17:40,805 INFO  [RegionServer:0.worker] hbase.HRegion(328): region r2,,1199107058702 available
    [junit] 2007-12-31 13:17:40,813 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:17:40,819 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:37011} complete
    [junit] 2007-12-31 13:17:41,714 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 364.0
    [junit] 2007-12-31 13:17:41,795 DEBUG [IPC Server handler 2 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : r2,,1199107058702 from 140.211.11.75:37011
    [junit] 2007-12-31 13:17:41,795 DEBUG [IPC Server handler 2 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : r2,,1199107058702 from 140.211.11.75:37011
    [junit] 2007-12-31 13:17:41,796 INFO  [IPC Server handler 2 on 60000] hbase.HMaster(1577): 140.211.11.75:37011 serving r2,,1199107058702
    [junit] 2007-12-31 13:17:41,796 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:37011
    [junit] 2007-12-31 13:17:41,796 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: r2,,1199107058702, startKey: <>, encodedName(347143206) 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}}} open on 140.211.11.75:37011
    [junit] 2007-12-31 13:17:41,797 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2007-12-31 13:17:41,797 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row r2,,1199107058702 in table .META.,,1 with startcode 1199107049117 and server 140.211.11.75:37011
    [junit] 2007-12-31 13:17:42,724 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:37011}
    [junit] 2007-12-31 13:17:42,734 WARN  [HMaster.metaScanner] hbase.HMaster$BaseScanner(238): info:regioninfo is empty; has keys: [r2,,1199107058702/info:serverstartcode/1199107061797, r2,,1199107058702/info:server/1199107061797]
    [junit] 2007-12-31 13:17:42,736 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:37011} complete
    [junit] 2007-12-31 13:17:42,736 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2007-12-31 13:17:43,669 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/3231676785541596388 with 4 entries, sequence id 24, and size 530.0 for .META.,,1/info
    [junit] 2007-12-31 13:17:43,669 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 1955ms, sequenceid=24
    [junit] 2007-12-31 13:17:43,670 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed.
    [junit] 2007-12-31 13:17:43,670 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction
    [junit] 2007-12-31 13:17:43,753 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r1,,1199107049137. Size 310.0
    [junit] 2007-12-31 13:17:44,877 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 922550440/a/3056363259608155180 with 5 entries, sequence id 27, and size 270.0 for r1,,1199107049137/a
    [junit] 2007-12-31 13:17:44,963 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table r2
    [junit] 2007-12-31 13:17:44,978 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for r2 at address: 140.211.11.75:37011, 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-31 13:17:45,294 INFO  [IPC Server handler 1 on 60000] hbase.HLog(313): new log writer created at /hbase/hregion_267448769/log/hlog.dat.000
    [junit] 2007-12-31 13:17:45,585 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199107064984/a (267448769/a) (no reconstruction log)
    [junit] 2007-12-31 13:17:45,587 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/a (267448769/a) is -1
    [junit] 2007-12-31 13:17:45,806 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199107064984/b (267448769/b) (no reconstruction log)
    [junit] 2007-12-31 13:17:45,808 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/b (267448769/b) is -1
    [junit] 2007-12-31 13:17:45,869 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 922550440/b/4344510381853337059 with 5 entries, sequence id 27, and size 270.0 for r1,,1199107049137/b
    [junit] 2007-12-31 13:17:46,060 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199107064984/c (267448769/c) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,062 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/c (267448769/c) is -1
    [junit] 2007-12-31 13:17:46,310 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199107064984/d (267448769/d) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,312 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/d (267448769/d) is -1
    [junit] 2007-12-31 13:17:46,501 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(601): starting result_table,,1199107064984/e (267448769/e) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,504 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/e (267448769/e) is -1
    [junit] 2007-12-31 13:17:46,505 DEBUG [IPC Server handler 1 on 60000] hbase.HRegion(300): Next sequence id for region result_table,,1199107064984 is 0
    [junit] 2007-12-31 13:17:46,506 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(328): region result_table,,1199107064984 available
    [junit] 2007-12-31 13:17:46,509 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199107064984/e (267448769/e)
    [junit] 2007-12-31 13:17:46,509 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199107064984/d (267448769/d)
    [junit] 2007-12-31 13:17:46,509 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199107064984/a (267448769/a)
    [junit] 2007-12-31 13:17:46,510 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199107064984/b (267448769/b)
    [junit] 2007-12-31 13:17:46,510 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(880): closed result_table,,1199107064984/c (267448769/c)
    [junit] 2007-12-31 13:17:46,510 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(430): closed result_table,,1199107064984
    [junit] 2007-12-31 13:17:46,511 DEBUG [IPC Server handler 1 on 60000] hbase.HLog(399): closing log writer in /hbase/hregion_267448769/log
    [junit] 2007-12-31 13:17:46,777 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(2547): created table result_table
    [junit] 2007-12-31 13:17:46,778 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing a find...
    [junit] 2007-12-31 13:17:46,828 DEBUG [main] hbase.HConnectionManager$TableServers(778): no server address for regionname: result_table,,1199107064984, startKey: <>, encodedName(267448769) 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-31 13:17:46,829 DEBUG [main] hbase.HConnectionManager$TableServers(676): Sleeping. Table result_table not currently being served.
    [junit] 2007-12-31 13:17:46,844 INFO  [IPC Server handler 3 on 60000] hbase.HMaster(1858): assigning region result_table,,1199107064984 to the only server 140.211.11.75:37011
    [junit] 2007-12-31 13:17:46,845 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGION_OPEN : result_table,,1199107064984
    [junit] 2007-12-31 13:17:46,849 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199107064984/a (267448769/a) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,851 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/a (267448769/a) is -1
    [junit] 2007-12-31 13:17:46,857 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199107064984/b (267448769/b) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,859 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/b (267448769/b) is -1
    [junit] 2007-12-31 13:17:46,862 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199107064984/c (267448769/c) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,864 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/c (267448769/c) is -1
    [junit] 2007-12-31 13:17:46,869 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199107064984/d (267448769/d) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,871 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/d (267448769/d) is -1
    [junit] 2007-12-31 13:17:46,876 DEBUG [RegionServer:0.worker] hbase.HStore(601): starting result_table,,1199107064984/e (267448769/e) (no reconstruction log)
    [junit] 2007-12-31 13:17:46,878 DEBUG [RegionServer:0.worker] hbase.HStore(629): maximum sequence id for hstore result_table,,1199107064984/e (267448769/e) is -1
    [junit] 2007-12-31 13:17:46,879 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next sequence id for region result_table,,1199107064984 is 0
    [junit] 2007-12-31 13:17:46,880 INFO  [RegionServer:0.worker] hbase.HRegion(328): region result_table,,1199107064984 available
    [junit] 2007-12-31 13:17:46,927 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 922550440/c/4747646899221343448 with 5 entries, sequence id 27, and size 310.0 for r1,,1199107049137/c
    [junit] 2007-12-31 13:17:46,928 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r1,,1199107049137 in 3175ms, sequenceid=27
    [junit] 2007-12-31 13:17:46,928 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199107049137/a not needed.
    [junit] 2007-12-31 13:17:46,928 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199107049137/b not needed.
    [junit] 2007-12-31 13:17:46,929 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r1,,1199107049137/c not needed.
    [junit] 2007-12-31 13:17:46,929 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r1,,1199107049137 does not need compaction
    [junit] 2007-12-31 13:17:47,854 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1516): Received MSG_REPORT_PROCESS_OPEN : result_table,,1199107064984 from 140.211.11.75:37011
    [junit] 2007-12-31 13:17:47,855 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1516): Received MSG_REPORT_OPEN : result_table,,1199107064984 from 140.211.11.75:37011
    [junit] 2007-12-31 13:17:47,855 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1577): 140.211.11.75:37011 serving result_table,,1199107064984
    [junit] 2007-12-31 13:17:47,856 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop: PendingOpenOperation from 140.211.11.75:37011
    [junit] 2007-12-31 13:17:47,856 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2458): regionname: result_table,,1199107064984, startKey: <>, encodedName(267448769) 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:37011
    [junit] 2007-12-31 13:17:47,857 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2007-12-31 13:17:47,857 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2472): updating row result_table,,1199107064984 in table .META.,,1 with startcode 1199107049117 and server 140.211.11.75:37011
    [junit] 2007-12-31 13:17:50,793 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:37011}
    [junit] 2007-12-31 13:17:50,812 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region r2,,1199107058702. Size 132.0
    [junit] 2007-12-31 13:17:50,826 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:17:50,829 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:37011} complete
    [junit] 2007-12-31 13:17:51,832 DEBUG [main] hbase.HConnectionManager$TableServers(685): Wake. Retry finding table result_table
    [junit] 2007-12-31 13:17:51,848 DEBUG [main] hbase.HConnectionManager$TableServers(728): Found 1 region(s) for result_table at address: 140.211.11.75:37011, 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-31 13:17:52,236 WARN  [IPC Server handler 1 on 36988] dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system because it does not exist
    [junit] 2007-12-31 13:17:52,562 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 347143206/e/6532525785356112226 with 3 entries, sequence id 37, and size 218.0 for r2,,1199107058702/e
    [junit] 2007-12-31 13:17:52,755 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:37011}
    [junit] 2007-12-31 13:17:52,825 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199107049137, startKey: <>, encodedName(922550440) 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:17:52,829 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199107058702, startKey: <>, encodedName(347143206) 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:17:52,831 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1199107064984, startKey: <>, encodedName(267448769) 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:17:52,834 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:37011} complete
    [junit] 2007-12-31 13:17:52,834 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2007-12-31 13:17:54,123 WARN  [IPC Server handler 3 on 36988] 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-31 13:17:54,125 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-31 13:17:54,168 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find...
    [junit] 2007-12-31 13:17:54,219 DEBUG [main] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r1
    [junit] 2007-12-31 13:17:54,249 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2007-12-31 13:17:54,683 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 347143206/d/7348942903137327137 with 3 entries, sequence id 37, and size 218.0 for r2,,1199107058702/d
    [junit] 2007-12-31 13:17:54,684 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region r2,,1199107058702 in 3872ms, sequenceid=37
    [junit] 2007-12-31 13:17:54,684 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199107058702/e not needed.
    [junit] 2007-12-31 13:17:54,684 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 439.0
    [junit] 2007-12-31 13:17:54,684 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore r2,,1199107058702/d not needed.
    [junit] 2007-12-31 13:17:54,686 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region r2,,1199107058702 does not need compaction
    [junit] 2007-12-31 13:17:55,296 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 1028785192/info/5956398320316659128 with 5 entries, sequence id 38, and size 619.0 for .META.,,1/info
    [junit] 2007-12-31 13:17:55,296 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 612ms, sequenceid=38
    [junit] 2007-12-31 13:17:55,297 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore .META.,,1/info not needed.
    [junit] 2007-12-31 13:17:55,297 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region .META.,,1 does not need compaction
    [junit] 2007-12-31 13:17:55,359 DEBUG [Thread-192] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2007-12-31 13:17:55,643 DEBUG [Thread-192] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
    [junit] 2007-12-31 13:17:55,666 DEBUG [Thread-192] hbase.HConnectionManager$TableServers(759): Found 1 servers for table r2
    [junit] 2007-12-31 13:17:57,086 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837): Started memcache flush for region result_table,,1199107064984. Size 306.0
    [junit] 2007-12-31 13:17:57,443 WARN  [Task Commit Thread] mapred.JobTracker$TaskCommitQueue(2032): Task Commit Thread exiting...
    [junit] 2007-12-31 13:17:58,960 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 267448769/e/6013730320729459657 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199107064984/e
    [junit] 2007-12-31 13:17:59,709 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 267448769/d/2991612619063617492 with 3 entries, sequence id 54, and size 212.0 for result_table,,1199107064984/d
    [junit] 2007-12-31 13:18:00,378 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 267448769/a/7635498640604229061 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199107064984/a
    [junit] 2007-12-31 13:18:00,801 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:37011}
    [junit] 2007-12-31 13:18:00,840 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:18:00,842 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region {regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:37011} complete
    [junit] 2007-12-31 13:18:01,437 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 267448769/b/1651761746381397428 with 3 entries, sequence id 54, and size 206.0 for result_table,,1199107064984/b
    [junit] 2007-12-31 13:18:02,341 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(972): Added 267448769/c/5200785515031915841 with 3 entries, sequence id 54, and size 230.0 for result_table,,1199107064984/c
    [junit] 2007-12-31 13:18:02,342 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943): Finished memcache flush for region result_table,,1199107064984 in 5366ms, sequenceid=54
    [junit] 2007-12-31 13:18:02,342 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199107064984/e not needed.
    [junit] 2007-12-31 13:18:02,343 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199107064984/d not needed.
    [junit] 2007-12-31 13:18:02,343 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199107064984/a not needed.
    [junit] 2007-12-31 13:18:02,343 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199107064984/b not needed.
    [junit] 2007-12-31 13:18:02,343 DEBUG [RegionServer:0.compactor] hbase.HStore(996): compaction for HStore result_table,,1199107064984/c not needed.
    [junit] 2007-12-31 13:18:02,344 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region result_table,,1199107064984 does not need compaction
    [junit] 2007-12-31 13:18:02,741 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:37011}
    [junit] 2007-12-31 13:18:02,766 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r1,,1199107049137, startKey: <>, encodedName(922550440) 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:18:02,768 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: r2,,1199107058702, startKey: <>, encodedName(347143206) 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:18:02,770 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname: result_table,,1199107064984, startKey: <>, encodedName(267448769) 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:37011, startCode: 1199107049117
    [junit] 2007-12-31 13:18:02,772 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region {regionname: .META.,,1, startKey: <>, server: 140.211.11.75:37011} complete
    [junit] 2007-12-31 13:18:02,772 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
    [junit] 2007-12-31 13:18:07,494 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2007-12-31 13:18:07,497 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2007-12-31 13:18:07,498 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size: 5
    [junit] 2007-12-31 13:18:07,500 INFO  [main] algebra.TestTableJoinMapReduce(244): result_table.row.count: 3
    [junit] 2007-12-31 13:18:07,501 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down HBase Cluster
    [junit] 2007-12-31 13:18:07,502 INFO  [main] hbase.HMaster(2526): Cluster shutdown requested. Starting to quiesce servers
    [junit] 2007-12-31 13:18:07,542 INFO  [RegionServer:0] hbase.HRegionServer(774): Got quiesce server message
    [junit] 2007-12-31 13:18:07,542 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153): MSG_REGIONSERVER_QUIESCE : 
    [junit] 2007-12-31 13:18:07,543 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r1,,1199107049137
    [junit] 2007-12-31 13:18:07,543 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199107049137/a (922550440/a)
    [junit] 2007-12-31 13:18:07,543 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199107049137/b (922550440/b)
    [junit] 2007-12-31 13:18:07,544 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r1,,1199107049137/c (922550440/c)
    [junit] 2007-12-31 13:18:07,544 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed r1,,1199107049137
    [junit] 2007-12-31 13:18:07,544 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region r2,,1199107058702
    [junit] 2007-12-31 13:18:07,545 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199107058702/e (347143206/e)
    [junit] 2007-12-31 13:18:07,545 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed r2,,1199107058702/d (347143206/d)
    [junit] 2007-12-31 13:18:07,545 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed r2,,1199107058702
    [junit] 2007-12-31 13:18:07,545 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295): closing region result_table,,1199107064984
    [junit] 2007-12-31 13:18:07,546 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199107064984/e (267448769/e)
    [junit] 2007-12-31 13:18:07,546 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199107064984/d (267448769/d)
    [junit] 2007-12-31 13:18:07,546 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199107064984/a (267448769/a)
    [junit] 2007-12-31 13:18:07,547 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199107064984/b (267448769/b)
    [junit] 2007-12-31 13:18:07,547 DEBUG [RegionServer:0.worker] hbase.HStore(880): closed result_table,,1199107064984/c (267448769/c)
    [junit] 2007-12-31 13:18:07,547 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed result_table,,1199107064984
    [junit] 2007-12-31 13:18:08,551 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1372): Region server 140.211.11.75:37011 quiesced
    [junit] 2007-12-31 13:18:08,551 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1380): All user tables quiesced. Proceeding with shutdown
    [junit] 2007-12-31 13:18:08,552 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1206): telling root scanner to stop
    [junit] 2007-12-31 13:18:08,552 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1214): telling meta scanner to stop
    [junit] 2007-12-31 13:18:08,552 INFO  [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner exiting
    [junit] 2007-12-31 13:18:08,552 INFO  [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner exiting
    [junit] 2007-12-31 13:18:08,552 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1222): meta and root scanners notified
    [junit] 2007-12-31 13:18:08,554 INFO  [RegionServer:0] hbase.HRegionServer(768): Got regionserver stop message
    [junit] 2007-12-31 13:18:08,554 INFO  [RegionServer:0] hbase.Leases(109): RegionServer:0 closing leases
    [junit] 2007-12-31 13:18:08,555 INFO  [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker exiting
    [junit] 2007-12-31 13:18:08,555 INFO  [RegionServer:0] hbase.Leases(125): RegionServer:0 closed leases
    [junit] 2007-12-31 13:18:08,556 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region -ROOT-,,0
    [junit] 2007-12-31 13:18:08,556 INFO  [RegionServer:0.compactor] hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting
    [junit] 2007-12-31 13:18:08,557 DEBUG [RegionServer:0] hbase.HStore(880): closed -ROOT-,,0/info (70236052/info)
    [junit] 2007-12-31 13:18:08,556 INFO  [RegionServer:0.splitter] hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting
    [junit] 2007-12-31 13:18:08,558 INFO  [RegionServer:0] hbase.HRegion(430): closed -ROOT-,,0
    [junit] 2007-12-31 13:18:08,556 INFO  [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting
    [junit] 2007-12-31 13:18:08,559 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing region .META.,,1
    [junit] 2007-12-31 13:18:08,560 DEBUG [RegionServer:0] hbase.HStore(880): closed .META.,,1/info (1028785192/info)
    [junit] 2007-12-31 13:18:08,560 INFO  [RegionServer:0] hbase.HRegion(430): closed .META.,,1
    [junit] 2007-12-31 13:18:08,561 DEBUG [RegionServer:0] hbase.HLog(399): closing log writer in /hbase/log_140.211.11.75_1199107049117_37011
    [junit] 2007-12-31 13:18:09,119 INFO  [RegionServer:0] hbase.HRegionServer(888): telling master that region server is shutting down at: 140.211.11.75:37011
    [junit] 2007-12-31 13:18:09,120 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1339): Region server 140.211.11.75:37011: MSG_REPORT_EXITING -- cancelling lease
    [junit] 2007-12-31 13:18:09,121 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1481): Cancelling lease for 140.211.11.75:37011
    [junit] 2007-12-31 13:18:09,121 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1346): Region server 140.211.11.75:37011: MSG_REPORT_EXITING -- lease cancelled
    [junit] 2007-12-31 13:18:09,122 INFO  [RegionServer:0] hbase.HRegionServer(895): stopping server at: 140.211.11.75:37011
    [junit] 2007-12-31 13:18:09,200 DEBUG [HMaster] hbase.HMaster(1206): telling root scanner to stop
    [junit] 2007-12-31 13:18:09,201 DEBUG [HMaster] hbase.HMaster(1214): telling meta scanner to stop
    [junit] 2007-12-31 13:18:09,201 DEBUG [HMaster] hbase.HMaster(1222): meta and root scanners notified
    [junit] 2007-12-31 13:18:09,202 INFO  [HMaster] hbase.Leases(109): HMaster closing leases
    [junit] 2007-12-31 13:18:09,202 INFO  [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker exiting
    [junit] 2007-12-31 13:18:09,203 INFO  [HMaster] hbase.Leases(125): HMaster closed leases
    [junit] 2007-12-31 13:18:09,203 INFO  [HMaster] hbase.HMaster(1139): HMaster main thread exiting
    [junit] 2007-12-31 13:18:09,560 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1202): worker thread exiting
    [junit] 2007-12-31 13:18:09,560 INFO  [RegionServer:0] hbase.HRegionServer(900): RegionServer:0 exiting
    [junit] 2007-12-31 13:18:09,561 INFO  [main] hbase.LocalHBaseCluster(238): Shutdown HMaster 1 region server(s)
    [junit] 2007-12-31 13:18:09,885 INFO  [main] hbase.StaticTestEnvironment(133): Shutting down FileSystem
    [junit] 2007-12-31 13:18:10,560 INFO  [main] hbase.StaticTestEnvironment(140): Shutting down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 0
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 50.73 sec
    [junit] 2007-12-31 13:18:11,909 INFO  [main] hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
    [junit] 2007-12-31 13:18:11,910 INFO  [main] hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
    [junit] Running org.apache.hadoop.hbase.util.TestBase64

    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.258 sec
    [junit] Running org.apache.hadoop.hbase.util.TestKeying
    [junit] Original url http://abc:bcd@www.example.com/index.html?query=something#middle, Transformed url r:http://abc:bcd@com.example.www/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.054 sec
    [junit] Running org.onelab.test.TestFilter
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.056 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: 114 minutes 20 seconds
Recording fingerprints
Publishing Javadoc
Recording test results


Build failed in Hudson: Hadoop-Nightly #350

Posted by hu...@lucene.zones.apache.org.
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/350/changes

------------------------------------------
[...truncated 1260 lines...]
A         src/webapps/static/jobconf.xsl
AU        src/webapps/static/hadoop-logo.jpg
A         src/webapps/job
A         src/webapps/job/jobtracker.jsp
A         src/webapps/job/machines.jsp
A         src/webapps/job/taskdetailshistory.jsp
A         src/webapps/job/jobhistory.jsp
A         src/webapps/job/loadhistory.jsp
A         src/webapps/job/jobtaskshistory.jsp
A         src/webapps/job/jobdetailshistory.jsp
A         src/webapps/job/jobfailures.jsp
A         src/webapps/job/taskdetails.jsp
A         src/webapps/job/analysejobhistory.jsp
A         src/webapps/job/jobblacklistedtrackers.jsp
A         src/webapps/job/index.html
A         src/webapps/job/jobtasks.jsp
A         src/webapps/job/jobdetails.jsp
A         src/webapps/job/jobconf.jsp
A         src/webapps/job/taskstats.jsp
A         src/webapps/dfs
A         src/webapps/dfs/nn_browsedfscontent.jsp
A         src/webapps/dfs/index.html
A         src/webapps/dfs/dfshealth.jsp
A         src/ant
A         src/ant/org
A         src/ant/org/apache
A         src/ant/org/apache/hadoop
A         src/ant/org/apache/hadoop/ant
A         src/ant/org/apache/hadoop/ant/DfsTask.java
A         src/ant/org/apache/hadoop/ant/condition
A         src/ant/org/apache/hadoop/ant/condition/DfsIsDir.java
A         src/ant/org/apache/hadoop/ant/condition/DfsBaseConditional.java
A         src/ant/org/apache/hadoop/ant/condition/DfsZeroLen.java
A         src/ant/org/apache/hadoop/ant/condition/DfsExists.java
A         src/ant/org/apache/hadoop/ant/antlib.xml
A         src/native
AU        src/native/configure
A         src/native/Makefile.in
A         src/native/AUTHORS
A         src/native/ChangeLog
A         src/native/src
A         src/native/src/org
A         src/native/src/org/apache
A         src/native/src/org/apache/hadoop
A         src/native/src/org/apache/hadoop/io
A         src/native/src/org/apache/hadoop/io/compress
A         src/native/src/org/apache/hadoop/io/compress/zlib
A         src/native/src/org/apache/hadoop/io/compress/zlib/Makefile.in
A         src/native/src/org/apache/hadoop/io/compress/zlib/ZlibDecompressor.c
A         src/native/src/org/apache/hadoop/io/compress/zlib/Makefile.am
A         src/native/src/org/apache/hadoop/io/compress/zlib/org_apache_hadoop_io_compress_zlib.h
A         src/native/src/org/apache/hadoop/io/compress/zlib/ZlibCompressor.c
A         src/native/src/org/apache/hadoop/io/compress/lzo
A         src/native/src/org/apache/hadoop/io/compress/lzo/LzoCompressor.c
A         src/native/src/org/apache/hadoop/io/compress/lzo/Makefile.in
A         src/native/src/org/apache/hadoop/io/compress/lzo/Makefile.am
A         src/native/src/org/apache/hadoop/io/compress/lzo/LzoDecompressor.c
A         src/native/src/org/apache/hadoop/io/compress/lzo/org_apache_hadoop_io_compress_lzo.h
A         src/native/src/org_apache_hadoop.h
A         src/native/README
A         src/native/config.h.in
A         src/native/configure.ac
A         src/native/config
AU        src/native/config/depcomp
AU        src/native/config/missing
AU        src/native/config/config.guess
AU        src/native/config/config.sub
A         src/native/config/ltmain.sh
AU        src/native/config/install-sh
A         src/native/lib
A         src/native/lib/Makefile.in
A         src/native/lib/Makefile.am
A         src/native/acinclude.m4
A         src/native/INSTALL
AU        src/native/packageNativeHadoop.sh
A         src/native/COPYING
A         src/native/Makefile.am
A         src/native/NEWS
A         src/native/aclocal.m4
A         src/native/.autom4te.cfg
A         src/c++
A         src/c++/pipes
AU        src/c++/pipes/configure
A         src/c++/pipes/Makefile.in
A         src/c++/pipes/debug
A         src/c++/pipes/debug/pipes-default-script
A         src/c++/pipes/debug/pipes-default-gdb-commands.txt
A         src/c++/pipes/depcomp
A         src/c++/pipes/compile
A         src/c++/pipes/config.guess
A         src/c++/pipes/ltmain.sh
A         src/c++/pipes/config.sub
A         src/c++/pipes/impl
A         src/c++/pipes/impl/HadoopPipes.cc
A         src/c++/pipes/impl/config.h.in
A         src/c++/pipes/configure.ac
A         src/c++/pipes/missing
A         src/c++/pipes/api
A         src/c++/pipes/api/hadoop
A         src/c++/pipes/api/hadoop/TemplateFactory.hh
A         src/c++/pipes/api/hadoop/Pipes.hh
A         src/c++/pipes/Makefile.am
A         src/c++/pipes/aclocal.m4
A         src/c++/pipes/install-sh
A         src/c++/pipes/.autom4te.cfg
A         src/c++/utils
A         src/c++/utils/m4
A         src/c++/utils/m4/hadoop_utils.m4
AU        src/c++/utils/configure
A         src/c++/utils/impl
A         src/c++/utils/impl/config.h.in
A         src/c++/utils/impl/SerialUtils.cc
A         src/c++/utils/impl/StringUtils.cc
A         src/c++/utils/Makefile.in
A         src/c++/utils/configure.ac
A         src/c++/utils/depcomp
A         src/c++/utils/Makefile.am
A         src/c++/utils/api
A         src/c++/utils/api/hadoop
A         src/c++/utils/api/hadoop/StringUtils.hh
A         src/c++/utils/api/hadoop/SerialUtils.hh
A         src/c++/utils/missing
A         src/c++/utils/config.guess
A         src/c++/utils/aclocal.m4
A         src/c++/utils/config.sub
A         src/c++/utils/ltmain.sh
A         src/c++/utils/.autom4te.cfg
A         src/c++/utils/install-sh
A         src/c++/libhdfs
A         src/c++/libhdfs/hdfs_write.c
A         src/c++/libhdfs/hdfs_read.c
A         src/c++/libhdfs/tests
AU        src/c++/libhdfs/tests/test-libhdfs.sh
A         src/c++/libhdfs/tests/conf
A         src/c++/libhdfs/tests/conf/hadoop-site.xml
A         src/c++/libhdfs/tests/conf/slaves
A         src/c++/libhdfs/hdfs_test.c
A         src/c++/libhdfs/hdfs.c
A         src/c++/libhdfs/hdfsJniHelper.c
A         src/c++/libhdfs/docs
A         src/c++/libhdfs/docs/Doxyfile
A         src/c++/libhdfs/docs/libhdfs_footer.html
A         src/c++/libhdfs/hdfs.h
A         src/c++/libhdfs/hdfsJniHelper.h
A         src/c++/libhdfs/Makefile
A         src/c++/librecordio
A         src/c++/librecordio/archive.hh
A         src/c++/librecordio/filestream.cc
A         src/c++/librecordio/test
A         src/c++/librecordio/test/test.cc
A         src/c++/librecordio/test/testFromJava.hh
A         src/c++/librecordio/test/test.jr
A         src/c++/librecordio/test/testFromJava.cc
A         src/c++/librecordio/test/Makefile
A         src/c++/librecordio/test/test.hh
A         src/c++/librecordio/xmlarchive.hh
A         src/c++/librecordio/csvarchive.cc
A         src/c++/librecordio/exception.cc
A         src/c++/librecordio/recordio.hh
A         src/c++/librecordio/xmlarchive.cc
A         src/c++/librecordio/binarchive.hh
A         src/c++/librecordio/filestream.hh
A         src/c++/librecordio/recordio.cc
A         src/c++/librecordio/csvarchive.hh
A         src/c++/librecordio/exception.hh
A         src/c++/librecordio/Makefile
A         src/c++/librecordio/binarchive.cc
A         src/docs
A         src/docs/forrest.properties
A         src/docs/status.xml
A         src/docs/src
A         src/docs/src/documentation
A         src/docs/src/documentation/skinconf.xml
A         src/docs/src/documentation/content
A         src/docs/src/documentation/content/xdocs
A         src/docs/src/documentation/content/xdocs/cluster_setup.xml
A         src/docs/src/documentation/content/xdocs/site.xml
A         src/docs/src/documentation/content/xdocs/index.xml
A         src/docs/src/documentation/content/xdocs/streaming.xml
A         src/docs/src/documentation/content/xdocs/tabs.xml
A         src/docs/src/documentation/content/xdocs/quickstart.xml
A         src/docs/src/documentation/content/xdocs/mapred_tutorial.xml
A         src/docs/src/documentation/content/xdocs/hdfs_design.xml
A         src/docs/src/documentation/resources
A         src/docs/src/documentation/resources/images
AU        src/docs/src/documentation/resources/images/hdfsarchitecture.odg
AU        src/docs/src/documentation/resources/images/hdfsdatanodes.odg
AU        src/docs/src/documentation/resources/images/lucene_green_150.gif
AU        src/docs/src/documentation/resources/images/architecture.gif
AU        src/docs/src/documentation/resources/images/hadoop-logo-big.jpg
AU        src/docs/src/documentation/resources/images/hadoop-logo.jpg
AU        src/docs/src/documentation/resources/images/hdfsarchitecture.png
AU        src/docs/src/documentation/resources/images/hdfsdatanodes.png
AU        src/docs/src/documentation/resources/images/hdfsarchitecture.gif
AU        src/docs/src/documentation/resources/images/hdfsdatanodes.gif
AU        src/docs/src/documentation/resources/images/favicon.ico
A         src/docs/src/documentation/README.txt
A         src/docs/src/documentation/classes
A         src/docs/src/documentation/classes/CatalogManager.properties
AU        src/saveVersion.sh
A         src/examples
A         src/examples/pipes
AU        src/examples/pipes/configure
A         src/examples/pipes/impl
A         src/examples/pipes/impl/wordcount-simple.cc
A         src/examples/pipes/impl/config.h.in
A         src/examples/pipes/impl/wordcount-nopipe.cc
A         src/examples/pipes/impl/sort.cc
A         src/examples/pipes/impl/wordcount-part.cc
A         src/examples/pipes/Makefile.in
A         src/examples/pipes/configure.ac
A         src/examples/pipes/conf
A         src/examples/pipes/conf/word.xml
A         src/examples/pipes/conf/word-part.xml
A         src/examples/pipes/depcomp
A         src/examples/pipes/Makefile.am
A         src/examples/pipes/missing
A         src/examples/pipes/config.guess
A         src/examples/pipes/README.txt
A         src/examples/pipes/aclocal.m4
A         src/examples/pipes/config.sub
ERROR: Failed to check out http://svn.apache.org/repos/asf/lucene/hadoop/trunk
org.tmatesoft.svn.core.SVNException: svn: REPORT request failed on '/repos/asf/!svn/vcc/default'
svn: CRLF expected at end of chunk: 42/154
	at org.tmatesoft.svn.core.internal.wc.SVNErrorManager.error(SVNErrorManager.java:80)
	at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:517)
	at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:234)
	at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:222)
	at org.tmatesoft.svn.core.internal.io.dav.DAVConnection.doReport(DAVConnection.java:219)
	at org.tmatesoft.svn.core.internal.io.dav.DAVConnection.doReport(DAVConnection.java:211)
	at org.tmatesoft.svn.core.internal.io.dav.DAVRepository.update(DAVRepository.java:609)
	at org.tmatesoft.svn.core.wc.SVNUpdateClient.doUpdate(SVNUpdateClient.java:162)
	at org.tmatesoft.svn.core.wc.SVNUpdateClient.doCheckout(SVNUpdateClient.java:325)
	at hudson.scm.SubversionSCM$CheckOutTask.invoke(SubversionSCM.java:397)
	at hudson.scm.SubversionSCM$CheckOutTask.invoke(SubversionSCM.java:349)
	at hudson.FilePath.act(FilePath.java:291)
	at hudson.scm.SubversionSCM.checkout(SubversionSCM.java:346)
	at hudson.scm.SubversionSCM.checkout(SubversionSCM.java:292)
	at hudson.model.AbstractProject.checkout(AbstractProject.java:541)
	at hudson.model.AbstractBuild$AbstractRunner.checkout(AbstractBuild.java:223)
	at hudson.model.AbstractBuild$AbstractRunner.run(AbstractBuild.java:189)
	at hudson.model.Run.run(Run.java:649)
	at hudson.model.Build.run(Build.java:102)
	at hudson.model.ResourceController.execute(ResourceController.java:70)
	at hudson.model.Executor.run(Executor.java:64)
ERROR: No artifacts found that match the file pattern "trunk/*.tar.gz trunk/build/test/checkstyle-errors.* trunk/findbugs/*ml trunk/build/test/clover/reports/coverage.xml". Configuration error?
Recording fingerprints
Publishing Javadoc
Recording test results