See https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/613/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 706069 lines...]
[junit]
[junit] 2011-03-21 12:22:28,346 INFO ipc.Server (Server.java:run(485)) -
Stopping IPC Server listener on 38735
[junit] 2011-03-21 12:22:28,348 INFO datanode.DataNode
(DataNode.java:shutdown(788)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2011-03-21 12:22:28,348 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(624)) - Exiting DataBlockScanner thread.
[junit] 2011-03-21 12:22:28,348 INFO datanode.DataNode
(DataNode.java:run(1464)) - DatanodeRegistration(127.0.0.1:40498,
storageID=DS-388299994-127.0.1.1-40498-1300710137771, infoPort=47549,
ipcPort=38735):Finishing DataNode in:
FSDataset{dirpath='/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build-fi/test/data/dfs/data/data3/current/finalized,/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build-fi/test/data/dfs/data/data4/current/finalized'}
[junit] 2011-03-21 12:22:28,349 INFO ipc.Server (Server.java:stop(1624)) -
Stopping server on 38735
[junit] 2011-03-21 12:22:28,349 INFO datanode.DataNode
(DataNode.java:shutdown(788)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2011-03-21 12:22:28,349 INFO datanode.FSDatasetAsyncDiskService
(FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk
service threads...
[junit] 2011-03-21 12:22:28,349 INFO datanode.FSDatasetAsyncDiskService
(FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads
have been shut down.
[junit] 2011-03-21 12:22:28,350 WARN datanode.FSDatasetAsyncDiskService
(FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already
shut down.
[junit] 2011-03-21 12:22:28,350 INFO hdfs.MiniDFSCluster
(MiniDFSCluster.java:shutdownDataNodes(835)) - Shutting down DataNode 0
[junit] 2011-03-21 12:22:28,451 INFO ipc.Server (Server.java:stop(1624)) -
Stopping server on 51456
[junit] 2011-03-21 12:22:28,452 INFO ipc.Server (Server.java:run(1457)) -
IPC Server handler 0 on 51456: exiting
[junit] 2011-03-21 12:22:28,452 INFO ipc.Server (Server.java:run(485)) -
Stopping IPC Server listener on 51456
[junit] 2011-03-21 12:22:28,452 INFO datanode.DataNode
(DataNode.java:shutdown(788)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2011-03-21 12:22:28,452 WARN datanode.DataNode
(DataXceiverServer.java:run(142)) - DatanodeRegistration(127.0.0.1:43128,
storageID=DS-2103166115-127.0.1.1-43128-1300710137598, infoPort=42993,
ipcPort=51456):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:159)
[junit] at
sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:135)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 2011-03-21 12:22:28,452 INFO ipc.Server (Server.java:run(689)) -
Stopping IPC Server Responder
[junit] 2011-03-21 12:22:28,454 INFO datanode.DataNode
(DataNode.java:shutdown(788)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2011-03-21 12:22:28,555 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(624)) - Exiting DataBlockScanner thread.
[junit] 2011-03-21 12:22:28,555 INFO datanode.DataNode
(DataNode.java:run(1464)) - DatanodeRegistration(127.0.0.1:43128,
storageID=DS-2103166115-127.0.1.1-43128-1300710137598, infoPort=42993,
ipcPort=51456):Finishing DataNode in:
FSDataset{dirpath='/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build-fi/test/data/dfs/data/data1/current/finalized,/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build-fi/test/data/dfs/data/data2/current/finalized'}
[junit] 2011-03-21 12:22:28,555 INFO ipc.Server (Server.java:stop(1624)) -
Stopping server on 51456
[junit] 2011-03-21 12:22:28,556 INFO datanode.DataNode
(DataNode.java:shutdown(788)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2011-03-21 12:22:28,556 INFO datanode.FSDatasetAsyncDiskService
(FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk
service threads...
[junit] 2011-03-21 12:22:28,556 INFO datanode.FSDatasetAsyncDiskService
(FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads
have been shut down.
[junit] 2011-03-21 12:22:28,556 WARN datanode.FSDatasetAsyncDiskService
(FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already
shut down.
[junit] 2011-03-21 12:22:28,658 WARN namenode.DecommissionManager
(DecommissionManager.java:run(70)) - Monitor interrupted:
java.lang.InterruptedException: sleep interrupted
[junit] 2011-03-21 12:22:28,658 INFO namenode.FSEditLog
(FSEditLog.java:printStatistics(559)) - Number of transactions: 6 Total time
for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of
syncs: 3 SyncTimes(ms): 5 3
[junit] 2011-03-21 12:22:28,658 WARN namenode.FSNamesystem
(FSNamesystem.java:run(2856)) - ReplicationMonitor thread received
InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2011-03-21 12:22:28,660 INFO ipc.Server (Server.java:stop(1624)) -
Stopping server on 55720
[junit] 2011-03-21 12:22:28,660 INFO ipc.Server (Server.java:run(1457)) -
IPC Server handler 0 on 55720: exiting
[junit] 2011-03-21 12:22:28,661 INFO ipc.Server (Server.java:run(485)) -
Stopping IPC Server listener on 55720
[junit] 2011-03-21 12:22:28,661 INFO ipc.Server (Server.java:run(689)) -
Stopping IPC Server Responder
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 35.639 sec
checkfailure:
-run-test-hdfs-fault-inject-withtestcaseonly:
run-test-hdfs-fault-inject:
BUILD FAILED
/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build.xml:749:
Tests failed!
Total time: 49 minutes 27 seconds
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any)
##############################
1 tests failed.
REGRESSION:
org.apache.hadoop.hdfs.server.datanode.TestTransferRbw.testTransferRbw
Error Message:
65536 = numBytes < visible = 89896, r=ReplicaInPipeline,
blk_-8157901986060962899_1001, TEMPORARY getNumBytes() = 65536
getBytesOnDisk() = 0 getVisibleLength()= -1 getVolume() =
/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data3/current/finalized
getBlockFile() =
/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data3/tmp/blk_-8157901986060962899
bytesAcked=0 bytesOnDisk=0
Stack Trace:
org.apache.hadoop.hdfs.server.datanode.ReplicaNotFoundException: 65536 =
numBytes < visible = 89896, r=ReplicaInPipeline, blk_-8157901986060962899_1001,
TEMPORARY
getNumBytes() = 65536
getBytesOnDisk() = 0
getVisibleLength()= -1
getVolume() =
/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data3/current/finalized
getBlockFile() =
/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data3/tmp/blk_-8157901986060962899
bytesAcked=0
bytesOnDisk=0
at
org.apache.hadoop.hdfs.server.datanode.FSDataset.convertTemporaryToRbw(FSDataset.java:1387)
at
org.apache.hadoop.hdfs.server.datanode.DataNode.convertTemporaryToRbw(DataNode.java:2021)
at
org.apache.hadoop.hdfs.server.datanode.TestTransferRbw.__CLR3_0_2r95sa9tfi(TestTransferRbw.java:121)
at
org.apache.hadoop.hdfs.server.datanode.TestTransferRbw.testTransferRbw(TestTransferRbw.java:63)
