You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@hbase.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2008/10/07 06:28:33 UTC

Build failed in Hudson: HBase-Patch #331

See http://hudson.zones.apache.org/hudson/job/HBase-Patch/331/changes

Changes:

[stack] HBASE-576  Investigate IPC performance

------------------------------------------
[...truncated 6245 lines...]
    [junit] 2008-10-07 04:27:59,379 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//5 val: (/0/tran: 2 op COMMIT/)
    [junit] 2008-10-07 04:27:59,381 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//6 val: (/0/tran: 1 op COMMIT/)
    [junit] 2008-10-07 04:27:59,381 DEBUG [main] transactional.TransactionalHLogManager(269): Read 7 tranasctional operations (skipped 0 because sequence id <= -1): 2 starts, 3 writes, 0 aborts, and 2 commits.
    [junit] 2008-10-07 04:27:59,411 INFO  [main] hbase.HBaseTestCase(600): Shutting down FileSystem
    [junit] 2008-10-07 04:28:00,218 INFO  [main] hbase.HBaseTestCase(607): Shutting down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2008-10-07 04:28:00,222 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42934
    [junit] 2008-10-07 04:28:00,423 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:00,424 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1ca1a68
    [junit] 2008-10-07 04:28:00,495 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:00,496 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@73a5d3
    [junit] Shutting down DataNode 0
    [junit] 2008-10-07 04:28:01,499 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42931
    [junit] 2008-10-07 04:28:01,577 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:01,577 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@136bdda
    [junit] 2008-10-07 04:28:01,646 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:01,647 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@7981d5
    [junit] 2008-10-07 04:28:02,759 INFO  [Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=42927]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=42927]
    [junit] 2008-10-07 04:28:02,760 INFO  [main] http.SocketListener(212): Stopped SocketListener on 0.0.0.0:42927
    [junit] 2008-10-07 04:28:02,828 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:02,829 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1bdbfec
    [junit] 2008-10-07 04:28:02,896 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:02,896 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@1d349e2
    [junit] 2008-10-07 04:28:02,897 WARN  [org.apache.hadoop.dfs.FSNamesystem$ReplicationMonitor@1042fcc] dfs.FSNamesystem$ReplicationMonitor(2193): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 2008-10-07 04:28:03,238 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:03,240 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:03,353 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@1302b69
    [junit] 2008-10-07 04:28:03,356 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:03,357 INFO  [main] http.SocketListener(204): Started SocketListener on 0.0.0.0:42943
    [junit] 2008-10-07 04:28:03,369 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@17c398e
    [junit] Starting DataNode 0 with dfs.data.dir: /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data1,/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data2
    [junit] 2008-10-07 04:28:03,773 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:03,774 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:03,892 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@1a4036f
    [junit] 2008-10-07 04:28:03,895 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:03,897 INFO  [main] http.SocketListener(204): Started SocketListener on 127.0.0.1:42947
    [junit] 2008-10-07 04:28:03,898 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@974600
    [junit] Starting DataNode 1 with dfs.data.dir: /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data3,/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data4
    [junit] 2008-10-07 04:28:04,936 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:04,937 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:05,053 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@1ed688f
    [junit] 2008-10-07 04:28:05,056 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:05,058 INFO  [main] http.SocketListener(204): Started SocketListener on 127.0.0.1:42950
    [junit] 2008-10-07 04:28:05,059 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@16fdcc1
    [junit] 2008-10-07 04:28:06,302 INFO  [main] regionserver.HLog(256): New log writer created at /hbase/testInterlievedAbortCommit/hlog.dat.1223353686276
    [junit] 2008-10-07 04:28:06,305 DEBUG [main] regionserver.HLog(347): closing log writer in /hbase/testInterlievedAbortCommit
    [junit] 2008-10-07 04:28:06,355 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//0 val: (/0/tran: 1 op START/)
    [junit] 2008-10-07 04:28:06,355 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464/row1/1 val: (col:A/1223353686304/tran: 1 op WRITE/val1)
    [junit] 2008-10-07 04:28:06,356 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//2 val: (/0/tran: 2 op START/)
    [junit] 2008-10-07 04:28:06,357 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464/row2/3 val: (col:A/1223353686305/tran: 2 op WRITE/val2)
    [junit] 2008-10-07 04:28:06,358 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//4 val: (/0/tran: 2 op ABORT/)
    [junit] 2008-10-07 04:28:06,359 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464/row3/5 val: (col:A/1223353686305/tran: 1 op WRITE/val3)
    [junit] 2008-10-07 04:28:06,361 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//6 val: (/0/tran: 1 op COMMIT/)
    [junit] 2008-10-07 04:28:06,361 DEBUG [main] transactional.TransactionalHLogManager(269): Read 7 tranasctional operations (skipped 0 because sequence id <= -1): 2 starts, 3 writes, 1 aborts, and 1 commits.
    [junit] 2008-10-07 04:28:06,381 INFO  [main] hbase.HBaseTestCase(600): Shutting down FileSystem
    [junit] 2008-10-07 04:28:06,478 INFO  [main] hbase.HBaseTestCase(607): Shutting down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2008-10-07 04:28:06,481 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42950
    [junit] 2008-10-07 04:28:06,690 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:06,690 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1ed688f
    [junit] 2008-10-07 04:28:06,764 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:06,764 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@16fdcc1
    [junit] Shutting down DataNode 0
    [junit] 2008-10-07 04:28:07,770 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42947
    [junit] 2008-10-07 04:28:07,770 INFO  [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42947]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42947]
    [junit] 2008-10-07 04:28:07,853 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:07,854 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1a4036f
    [junit] 2008-10-07 04:28:07,927 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:07,927 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@974600
    [junit] 2008-10-07 04:28:09,050 INFO  [main] http.SocketListener(212): Stopped SocketListener on 0.0.0.0:42943
    [junit] 2008-10-07 04:28:09,127 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:09,127 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1302b69
    [junit] 2008-10-07 04:28:09,199 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:09,199 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@17c398e
    [junit] 2008-10-07 04:28:09,200 WARN  [org.apache.hadoop.dfs.FSNamesystem$ReplicationMonitor@294f62] dfs.FSNamesystem$ReplicationMonitor(2193): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 2008-10-07 04:28:09,564 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:09,566 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:09,752 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@1107304
    [junit] 2008-10-07 04:28:09,755 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:09,757 INFO  [main] http.SocketListener(204): Started SocketListener on 0.0.0.0:42959
    [junit] 2008-10-07 04:28:09,757 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@193c366
    [junit] Starting DataNode 0 with dfs.data.dir: /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data1,/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data2
    [junit] 2008-10-07 04:28:10,123 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:10,125 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:10,230 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@6e9e64
    [junit] 2008-10-07 04:28:10,232 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:10,234 INFO  [main] http.SocketListener(204): Started SocketListener on 127.0.0.1:42963
    [junit] 2008-10-07 04:28:10,234 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@2982d8
    [junit] Starting DataNode 1 with dfs.data.dir: /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data3,/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data4
    [junit] 2008-10-07 04:28:10,672 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:10,673 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:10,774 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@ceb6dd
    [junit] 2008-10-07 04:28:10,776 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:10,778 INFO  [main] http.SocketListener(204): Started SocketListener on 127.0.0.1:42966
    [junit] 2008-10-07 04:28:10,779 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@510dc2
    [junit] 2008-10-07 04:28:11,933 INFO  [main] regionserver.HLog(256): New log writer created at /hbase/testInterlievedCommitAbort/hlog.dat.1223353691902
    [junit] 2008-10-07 04:28:11,935 DEBUG [main] regionserver.HLog(347): closing log writer in /hbase/testInterlievedCommitAbort
    [junit] 2008-10-07 04:28:11,977 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//0 val: (/0/tran: 1 op START/)
    [junit] 2008-10-07 04:28:11,978 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464/row1/1 val: (col:A/1223353691934/tran: 1 op WRITE/val1)
    [junit] 2008-10-07 04:28:11,978 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//2 val: (/0/tran: 2 op START/)
    [junit] 2008-10-07 04:28:11,979 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464/row2/3 val: (col:A/1223353691935/tran: 2 op WRITE/val2)
    [junit] 2008-10-07 04:28:11,979 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//4 val: (/0/tran: 2 op COMMIT/)
    [junit] 2008-10-07 04:28:11,980 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464/row3/5 val: (col:A/1223353691935/tran: 1 op WRITE/val3)
    [junit] 2008-10-07 04:28:11,981 DEBUG [main] transactional.TransactionalHLogManager(180): Processing edit: key: tablename/tablename,,1223353660464//6 val: (/0/tran: 1 op ABORT/)
    [junit] 2008-10-07 04:28:11,981 DEBUG [main] transactional.TransactionalHLogManager(269): Read 7 tranasctional operations (skipped 0 because sequence id <= -1): 2 starts, 3 writes, 1 aborts, and 1 commits.
    [junit] 2008-10-07 04:28:12,029 INFO  [main] hbase.HBaseTestCase(600): Shutting down FileSystem
    [junit] 2008-10-07 04:28:12,858 INFO  [main] hbase.HBaseTestCase(607): Shutting down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2008-10-07 04:28:12,860 INFO  [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42966]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42966]
    [junit] 2008-10-07 04:28:12,861 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42966
    [junit] 2008-10-07 04:28:13,075 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:13,076 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@ceb6dd
    [junit] 2008-10-07 04:28:13,314 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:13,315 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@510dc2
    [junit] 2008-10-07 04:28:13,317 WARN  [org.apache.hadoop.dfs.DataNode$DataXceiveServer@1ce9f9d] dfs.DataNode$DataXceiveServer(957): DatanodeRegistration(127.0.0.1:42965, storageID=DS-1776592717-140.211.11.106-42965-1223353690784, infoPort=42966, ipcPort=42967):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.dfs.DataNode$DataXceiveServer.run(DataNode.java:953)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] Shutting down DataNode 0
    [junit] 2008-10-07 04:28:14,319 INFO  [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42963]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42963]
    [junit] 2008-10-07 04:28:14,319 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42963
    [junit] 2008-10-07 04:28:14,407 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:14,407 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@6e9e64
    [junit] 2008-10-07 04:28:14,494 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:14,494 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@2982d8
    [junit] 2008-10-07 04:28:15,610 INFO  [main] http.SocketListener(212): Stopped SocketListener on 0.0.0.0:42959
    [junit] 2008-10-07 04:28:15,684 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:15,684 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1107304
    [junit] 2008-10-07 04:28:15,755 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:15,756 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@193c366
    [junit] 2008-10-07 04:28:15,756 WARN  [org.apache.hadoop.dfs.FSNamesystem$ReplicationMonitor@185e90f] dfs.FSNamesystem$ReplicationMonitor(2193): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 35.292 sec
    [junit] Running org.apache.hadoop.hbase.util.TestBase64
    [junit] 
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.15 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.072 sec
    [junit] Running org.apache.hadoop.hbase.util.TestMergeTool
    [junit] 2008-10-07 04:28:20,377 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:20,382 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:21,271 INFO  [main] util.FileResource(60): Checking Resource aliases
    [junit] 2008-10-07 04:28:21,489 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@19b46dc
    [junit] 2008-10-07 04:28:21,578 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:21,581 INFO  [main] http.SocketListener(204): Started SocketListener on 0.0.0.0:42977
    [junit] 2008-10-07 04:28:21,587 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@1cef4f7
    [junit] Starting DataNode 0 with dfs.data.dir: /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data1,/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data2
    [junit] 2008-10-07 04:28:22,890 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:22,891 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:23,186 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@1ed5459
    [junit] 2008-10-07 04:28:23,191 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:23,194 INFO  [main] http.SocketListener(204): Started SocketListener on 127.0.0.1:42981
    [junit] 2008-10-07 04:28:23,194 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@19a37a
    [junit] Starting DataNode 1 with dfs.data.dir: /zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data3,/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build/test/data/dfs/data/data4
    [junit] 2008-10-07 04:28:25,192 INFO  [main] http.HttpServer(729): Version Jetty/5.1.4
    [junit] 2008-10-07 04:28:25,194 INFO  [main] util.Container(74): Started HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:25,583 INFO  [main] util.Container(74): Started org.mortbay.jetty.servlet.WebApplicationHandler@1f8247d
    [junit] 2008-10-07 04:28:25,589 INFO  [main] util.Container(74): Started WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:25,590 INFO  [main] http.SocketListener(204): Started SocketListener on 127.0.0.1:42984
    [junit] 2008-10-07 04:28:25,591 INFO  [main] util.Container(74): Started org.mortbay.jetty.Server@18a9fc8
    [junit] 2008-10-07 04:28:27,312 INFO  [main] regionserver.HLog(256): New log writer created at /user/hudson/-ROOT-/70236052/log/hlog.dat.1223353707193
    [junit] 2008-10-07 04:28:27,315 DEBUG [main] regionserver.HRegion(431): Opening region -ROOT-,,0/70236052
    [junit] 2008-10-07 04:28:27,408 DEBUG [main] regionserver.HRegion(492): Next sequence id for region -ROOT-,,0 is 0
    [junit] 2008-10-07 04:28:27,411 INFO  [main] regionserver.HRegion(514): region -ROOT-,,0/70236052 available
    [junit] 2008-10-07 04:28:27,481 INFO  [main] regionserver.HLog(256): New log writer created at /user/hudson/.META./1028785192/log/hlog.dat.1223353707454
    [junit] 2008-10-07 04:28:27,482 DEBUG [main] regionserver.HRegion(431): Opening region .META.,,1/1028785192
    [junit] 2008-10-07 04:28:27,589 DEBUG [main] regionserver.HRegion(492): Next sequence id for region .META.,,1 is 0
    [junit] 2008-10-07 04:28:27,593 INFO  [main] regionserver.HRegion(514): region .META.,,1/1028785192 available
    [junit] 2008-10-07 04:28:27,596 INFO  [main] hbase.HBaseTestCase(600): Shutting down FileSystem
    [junit] 2008-10-07 04:28:28,428 INFO  [main] hbase.HBaseTestCase(607): Shutting down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2008-10-07 04:28:28,449 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42984
    [junit] 2008-10-07 04:28:28,611 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:28,614 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1f8247d
    [junit] 2008-10-07 04:28:28,686 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:28,686 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@18a9fc8
    [junit] Shutting down DataNode 0
    [junit] 2008-10-07 04:28:29,699 INFO  [main] http.SocketListener(212): Stopped SocketListener on 127.0.0.1:42981
    [junit] 2008-10-07 04:28:29,699 INFO  [Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42981]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=42981]
    [junit] 2008-10-07 04:28:29,864 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:29,866 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1ed5459
    [junit] 2008-10-07 04:28:29,934 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:29,934 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@19a37a
    [junit] 2008-10-07 04:28:29,936 WARN  [org.apache.hadoop.dfs.DataNode$DataXceiveServer@cc0e01] dfs.DataNode$DataXceiveServer(957): DatanodeRegistration(127.0.0.1:42980, storageID=DS-198448914-140.211.11.106-42980-1223353703991, infoPort=42981, ipcPort=42982):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.dfs.DataNode$DataXceiveServer.run(DataNode.java:953)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2008-10-07 04:28:30,939 INFO  [Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=42977]] util.ThreadedServer$Acceptor(656): Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=42977]
    [junit] 2008-10-07 04:28:30,939 INFO  [main] http.SocketListener(212): Stopped SocketListener on 0.0.0.0:42977
    [junit] 2008-10-07 04:28:31,005 INFO  [main] util.Container(156): Stopped HttpContext[/static,/static]
    [junit] 2008-10-07 04:28:31,005 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.servlet.WebApplicationHandler@19b46dc
    [junit] 2008-10-07 04:28:31,071 INFO  [main] util.Container(156): Stopped WebApplicationContext[/,/]
    [junit] 2008-10-07 04:28:31,071 INFO  [main] util.Container(156): Stopped org.mortbay.jetty.Server@1cef4f7
    [junit] 2008-10-07 04:28:31,072 WARN  [org.apache.hadoop.dfs.FSNamesystem$ReplicationMonitor@1c65216] dfs.FSNamesystem$ReplicationMonitor(2193): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 13.399 sec
    [junit] Test org.apache.hadoop.hbase.util.TestMergeTool FAILED
    [junit] Running org.apache.hadoop.hbase.util.TestRootPath
    [junit] 2008-10-07 04:28:31,837 INFO  [main] util.TestRootPath(60): Got expected exception when checking invalid path:
    [junit] java.io.IOException: Root directory does not contain a scheme
    [junit] 	at org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:160)
    [junit] 	at org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    [junit] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    [junit] 	at java.lang.reflect.Method.invoke(Method.java:597)
    [junit] 	at junit.framework.TestCase.runTest(TestCase.java: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:421)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.062 sec
    [junit] Running org.onelab.test.TestFilter
    [junit] 2008-10-07 04:28:32,532 INFO  [main] test.TestFilter(244): Checking for false negatives
    [junit] 2008-10-07 04:28:32,537 INFO  [main] test.TestFilter(255): Checking for false positives
    [junit] 2008-10-07 04:28:32,537 INFO  [main] test.TestFilter(262): Success!
    [junit] 2008-10-07 04:28:32,538 INFO  [main] test.TestFilter(226): Checking serialization/deserialization
    [junit] 2008-10-07 04:28:32,540 INFO  [main] test.TestFilter(244): Checking for false negatives
    [junit] 2008-10-07 04:28:32,542 INFO  [main] test.TestFilter(255): Checking for false positives
    [junit] 2008-10-07 04:28:32,543 INFO  [main] test.TestFilter(262): Success!
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.181 sec

BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/HBase-Patch/workspace/trunk/build.xml:442: Tests failed!

Total time: 11 minutes 39 seconds
Recording test results


Hudson build is back to normal: HBase-Patch #332

Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/332/changes