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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[junit] 2008-10-07 04:28:02,897 WARN [EMAIL PROTECTED]
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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[junit] 2008-10-07 04:28:09,200 WARN [EMAIL PROTECTED]
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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[junit] 2008-10-07 04:28:13,317 WARN [EMAIL PROTECTED]
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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[junit] 2008-10-07 04:28:15,756 WARN [EMAIL PROTECTED]
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:[EMAIL
PROTECTED]/index.html?query=something#middle, Transformed url
r:http://abc:[EMAIL PROTECTED]/index.html?query=something#middle
[junit] Original url file:///usr/bin/java, Transformed url
file:///usr/bin/java
[junit] Original url dns:www.powerset.com, Transformed url
dns:www.powerset.com
[junit] Original url dns://dns.powerset.com/www.powerset.com, Transformed
url r:dns://com.powerset.dns/www.powerset.com
[junit] Original url http://one.two.three/index.html, Transformed url
r:http://three.two.one/index.html
[junit] Original url https://one.two.three:9443/index.html, Transformed url
r:https://three.two.one:9443/index.html
[junit] Original url ftp://one.two.three/index.html, Transformed url
r:ftp://three.two.one/index.html
[junit] Original url filename, Transformed url filename
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[junit] 2008-10-07 04:28:29,936 WARN [EMAIL PROTECTED]
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
[EMAIL PROTECTED]
[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
[EMAIL PROTECTED]
[junit] 2008-10-07 04:28:31,072 WARN [EMAIL PROTECTED]
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
