See https://builds.apache.org/job/Hadoop-Hdfs-trunk/741/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 1069428 lines...]
[junit] 2011-08-06 13:26:22,404 INFO datanode.FSDatasetAsyncDiskService
(FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads
have been shut down.
[junit] 2011-08-06 13:26:22,404 INFO mortbay.log (Slf4jLog.java:info(67))
- Stopped SelectChannelConnector@localhost:0
[junit] 2011-08-06 13:26:22,505 WARN blockmanagement.DecommissionManager
(DecommissionManager.java:run(75)) - Monitor interrupted:
java.lang.InterruptedException: sleep interrupted
[junit] 2011-08-06 13:26:22,506 WARN blockmanagement.BlockManager
(BlockManager.java:run(2370)) - ReplicationMonitor thread received
InterruptedException.
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor.run(BlockManager.java:2368)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2011-08-06 13:26:22,506 INFO namenode.FSEditLog
(FSEditLog.java:endCurrentLogSegment(822)) - Ending log segment 1
[junit] 2011-08-06 13:26:22,516 INFO namenode.FSEditLog
(FSEditLog.java:printStatistics(501)) - Number of transactions: 8 Total time
for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of
syncs: 7 SyncTimes(ms): 46 39
[junit] 2011-08-06 13:26:22,517 INFO ipc.Server (Server.java:stop(1715)) -
Stopping server on 38144
[junit] 2011-08-06 13:26:22,518 INFO ipc.Server (Server.java:run(1539)) -
IPC Server handler 0 on 38144: exiting
[junit] 2011-08-06 13:26:22,518 INFO ipc.Server (Server.java:run(505)) -
Stopping IPC Server listener on 38144
[junit] 2011-08-06 13:26:22,518 INFO ipc.Server (Server.java:run(647)) -
Stopping IPC Server Responder
[junit] 2011-08-06 13:26:22,518 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stop(199)) - Stopping DataNode metrics system...
[junit] 2011-08-06 13:26:22,518 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source JvmMetrics
[junit] 2011-08-06 13:26:22,518 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
NameNodeActivity
[junit] 2011-08-06 13:26:22,519 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcActivityForPort38144
[junit] 2011-08-06 13:26:22,519 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcDetailedActivityForPort38144
[junit] 2011-08-06 13:26:22,519 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source FSNamesystem
[junit] 2011-08-06 13:26:22,519 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcActivityForPort37245
[junit] 2011-08-06 13:26:22,520 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcDetailedActivityForPort37245
[junit] 2011-08-06 13:26:22,520 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source JvmMetrics-1
[junit] 2011-08-06 13:26:22,520 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
DataNodeActivity-janus.apache.org-35678
[junit] 2011-08-06 13:26:22,520 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcActivityForPort58168
[junit] 2011-08-06 13:26:22,521 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcDetailedActivityForPort58168
[junit] 2011-08-06 13:26:22,521 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source JvmMetrics-2
[junit] 2011-08-06 13:26:22,521 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
DataNodeActivity-janus.apache.org-57663
[junit] 2011-08-06 13:26:22,521 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcActivityForPort60389
[junit] 2011-08-06 13:26:22,522 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcDetailedActivityForPort60389
[junit] 2011-08-06 13:26:22,522 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source JvmMetrics-3
[junit] 2011-08-06 13:26:22,522 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
DataNodeActivity-janus.apache.org-46874
[junit] 2011-08-06 13:26:22,522 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcActivityForPort50478
[junit] 2011-08-06 13:26:22,523 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
RpcDetailedActivityForPort50478
[junit] 2011-08-06 13:26:22,523 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source JvmMetrics-4
[junit] 2011-08-06 13:26:22,523 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stopSources(408)) - Stopping metrics source
DataNodeActivity-janus.apache.org-51241
[junit] 2011-08-06 13:26:22,523 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stop(205)) - DataNode metrics system stopped.
[junit] 2011-08-06 13:26:22,524 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:shutdown(553)) - DataNode metrics system shutdown
complete.
[junit] Tests run: 16, Failures: 0, Errors: 0, Time elapsed: 123.329 sec
checkfailure:
-run-test-hdfs-fault-inject-withtestcaseonly:
run-test-hdfs-fault-inject:
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Hadoop-Hdfs-trunk/trunk/build.xml:777:
Tests failed!
Total time: 114 minutes 41 seconds
[FINDBUGS] Skipping publisher since build result is FAILURE
Archiving artifacts
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Recording test results
Publishing Javadoc
Recording fingerprints
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any)
##############################
1 tests failed.
REGRESSION:
org.apache.hadoop.hdfs.security.token.block.TestBlockToken.testBlockTokenRpcLeak
Error Message:
Call to localhost/127.0.0.1:57096 failed on local exception:
java.io.IOException: java.net.SocketTimeoutException: 1000 millis timeout while
waiting for channel to be ready for read. ch :
java.nio.channels.SocketChannel[connected local=/127.0.0.1:52139
remote=localhost/127.0.0.1:57096]
Stack Trace:
java.io.IOException: Call to localhost/127.0.0.1:57096 failed on local
exception: java.io.IOException: java.net.SocketTimeoutException: 1000 millis
timeout while waiting for channel to be ready for read. ch :
java.nio.channels.SocketChannel[connected local=/127.0.0.1:52139
remote=localhost/127.0.0.1:57096]
at org.apache.hadoop.ipc.Client.wrapException(Client.java:1089)
at org.apache.hadoop.ipc.Client.call(Client.java:1057)
at
org.apache.hadoop.ipc.WritableRpcEngine$Invoker.invoke(WritableRpcEngine.java:193)
at $Proxy7.getReplicaVisibleLength(Unknown Source)
at
org.apache.hadoop.hdfs.security.token.block.TestBlockToken.__CLR2_4_313r7v311rh(TestBlockToken.java:298)
at
org.apache.hadoop.hdfs.security.token.block.TestBlockToken.testBlockTokenRpcLeak(TestBlockToken.java:262)
Caused by: java.io.IOException: java.net.SocketTimeoutException: 1000 millis
timeout while waiting for channel to be ready for read. ch :
java.nio.channels.SocketChannel[connected local=/127.0.0.1:52139
remote=localhost/127.0.0.1:57096]
at org.apache.hadoop.ipc.Client$Connection$1.run(Client.java:504)
at java.security.AccessController.doPrivileged(Native Method)
at javax.security.auth.Subject.doAs(Subject.java:396)
at
org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1135)
at
org.apache.hadoop.ipc.Client$Connection.handleSaslConnectionFailure(Client.java:468)
at
org.apache.hadoop.ipc.Client$Connection.setupIOstreams(Client.java:552)
at org.apache.hadoop.ipc.Client$Connection.access$2000(Client.java:207)
at org.apache.hadoop.ipc.Client.getConnection(Client.java:1190)
at org.apache.hadoop.ipc.Client.call(Client.java:1034)
Caused by: java.net.SocketTimeoutException: 1000 millis timeout while waiting
for channel to be ready for read. ch :
java.nio.channels.SocketChannel[connected local=/127.0.0.1:52139
remote=localhost/127.0.0.1:57096]
at
org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:164)
at
org.apache.hadoop.net.SocketInputStream.read(SocketInputStream.java:159)
at
org.apache.hadoop.net.SocketInputStream.read(SocketInputStream.java:132)
at java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
at java.io.BufferedInputStream.read(BufferedInputStream.java:237)
at java.io.DataInputStream.readInt(DataInputStream.java:370)
at
org.apache.hadoop.security.SaslRpcClient.readStatus(SaslRpcClient.java:109)
at
org.apache.hadoop.security.SaslRpcClient.saslConnect(SaslRpcClient.java:173)
at
org.apache.hadoop.ipc.Client$Connection.setupSaslConnection(Client.java:406)
at org.apache.hadoop.ipc.Client$Connection.access$1200(Client.java:207)
at org.apache.hadoop.ipc.Client$Connection$2.run(Client.java:545)
at org.apache.hadoop.ipc.Client$Connection$2.run(Client.java:542)
at java.security.AccessController.doPrivileged(Native Method)
at javax.security.auth.Subject.doAs(Subject.java:396)
at
org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1135)
at
org.apache.hadoop.ipc.Client$Connection.setupIOstreams(Client.java:541)
