See <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/463/>
------------------------------------------
[...truncated 754892 lines...]
[junit] 2010-10-22 00:48:03,367 WARN hdfs.DFSClient (DFSOutputStream.java:setupPipelineForAppendOrRecovery(776)) - Error Recovery for block blk_-863992521392524374_1001 in pipeline 127.0.0.1:51714, 127.0.0.1:59488, 127.0.0.1:35585: bad datanode 127.0.0.1:35585
[junit] 2010-10-22 00:48:03,367 INFO datanode.BlockReceiverAspects (BlockReceiverAspects.aj:ajc$after$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$8$9594fb70(205)) - FI: fiPipelineAck, datanode=DatanodeRegistration(127.0.0.1:51714, storageID=DS-1113396603-127.0.1.1-51714-1287708473055, infoPort=49069, ipcPort=48461)
[junit] 2010-10-22 00:48:03,368 WARN datanode.DataNode (BlockReceiver.java:run(948)) - IOException in BlockReceiver.run():
[junit] java.io.IOException: Connection reset by peer
[junit] at sun.nio.ch.FileDispatcher.write0(Native Method)
[junit] at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
[junit] at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
[junit] at sun.nio.ch.IOUtil.write(IOUtil.java:75)
[junit] at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
[junit] at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:60)
[junit] at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:151)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:112)
[junit] at java.io.DataOutputStream.writeLong(DataOutputStream.java:207)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:542)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:931)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody1$advice(BlockReceiver.java:160)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.run(BlockReceiver.java:931)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-10-22 00:48:03,368 WARN datanode.DataNode (DataNode.java:checkDiskError(828)) - checkDiskError: exception:
[junit] java.io.IOException: Connection reset by peer
[junit] at sun.nio.ch.FileDispatcher.write0(Native Method)
[junit] at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
[junit] at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
[junit] at sun.nio.ch.IOUtil.write(IOUtil.java:75)
[junit] at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
[junit] at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:60)
[junit] at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:151)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:112)
[junit] at java.io.DataOutputStream.writeLong(DataOutputStream.java:207)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:542)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:931)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody1$advice(BlockReceiver.java:160)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.run(BlockReceiver.java:931)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-10-22 00:48:03,369 INFO datanode.DataNode (BlockReceiver.java:run(955)) - PacketResponder blk_-863992521392524374_1001 2 Exception java.io.IOException: Connection reset by peer
[junit] at sun.nio.ch.FileDispatcher.write0(Native Method)
[junit] at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
[junit] at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
[junit] at sun.nio.ch.IOUtil.write(IOUtil.java:75)
[junit] at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
[junit] at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:60)
[junit] at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:151)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:112)
[junit] at java.io.DataOutputStream.writeLong(DataOutputStream.java:207)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:542)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:931)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody1$advice(BlockReceiver.java:160)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.run(BlockReceiver.java:931)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-10-22 00:48:03,369 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 2 for block blk_-863992521392524374_1001 terminating
[junit] 2010-10-22 00:48:03,369 INFO datanode.BlockReceiverAspects (BlockReceiverAspects.aj:ajc$after$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$9$725950a6(220)) - FI: blockFileClose, datanode=DatanodeRegistration(127.0.0.1:51714, storageID=DS-1113396603-127.0.1.1-51714-1287708473055, infoPort=49069, ipcPort=48461)
[junit] 2010-10-22 00:48:03,369 WARN datanode.DataNode (BlockReceiver.java:run(948)) - IOException in BlockReceiver.run():
[junit] java.io.IOException: Connection reset by peer
[junit] at sun.nio.ch.FileDispatcher.write0(Native Method)
[junit] at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
[junit] at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
[junit] at sun.nio.ch.IOUtil.write(IOUtil.java:75)
[junit] at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
[junit] at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:60)
[junit] at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:151)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:112)
[junit] at java.io.DataOutputStream.writeLong(DataOutputStream.java:207)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:542)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:931)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody1$advice(BlockReceiver.java:160)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.run(BlockReceiver.java:931)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-10-22 00:48:03,370 INFO datanode.BlockReceiverAspects (BlockReceiverAspects.aj:ajc$after$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$9$725950a6(220)) - FI: blockFileClose, datanode=DatanodeRegistration(127.0.0.1:51714, storageID=DS-1113396603-127.0.1.1-51714-1287708473055, infoPort=49069, ipcPort=48461)
[junit] 2010-10-22 00:48:03,370 WARN datanode.DataNode (DataNode.java:checkDiskError(828)) - checkDiskError: exception:
[junit] java.io.IOException: Connection reset by peer
[junit] at sun.nio.ch.FileDispatcher.write0(Native Method)
[junit] at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
[junit] at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
[junit] at sun.nio.ch.IOUtil.write(IOUtil.java:75)
[junit] at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
[junit] at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:60)
[junit] at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:151)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:112)
[junit] at java.io.DataOutputStream.writeLong(DataOutputStream.java:207)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:542)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:931)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody1$advice(BlockReceiver.java:160)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.run(BlockReceiver.java:931)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-10-22 00:48:03,371 INFO datanode.DataNode (BlockReceiver.java:run(955)) - PacketResponder blk_-863992521392524374_1001 1 Exception java.io.IOException: Connection reset by peer
[junit] at sun.nio.ch.FileDispatcher.write0(Native Method)
[junit] at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
[junit] at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
[junit] at sun.nio.ch.IOUtil.write(IOUtil.java:75)
[junit] at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
[junit] at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:60)
[junit] at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:151)
[junit] at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:112)
[junit] at java.io.DataOutputStream.writeLong(DataOutputStream.java:207)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:542)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:931)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody1$advice(BlockReceiver.java:160)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.run(BlockReceiver.java:931)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-10-22 00:48:03,371 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 1 for block blk_-863992521392524374_1001 terminating
[junit] 2010-10-22 00:48:03,372 INFO datanode.BlockReceiverAspects (BlockReceiverAspects.aj:ajc$after$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$9$725950a6(220)) - FI: blockFileClose, datanode=DatanodeRegistration(127.0.0.1:59488, storageID=DS-1762502180-127.0.1.1-59488-1287708472800, infoPort=46435, ipcPort=53960)
[junit] 2010-10-22 00:48:03,372 INFO datanode.BlockReceiverAspects (BlockReceiverAspects.aj:ajc$after$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$9$725950a6(220)) - FI: blockFileClose, datanode=DatanodeRegistration(127.0.0.1:59488, storageID=DS-1762502180-127.0.1.1-59488-1287708472800, infoPort=46435, ipcPort=53960)
[junit] 2010-10-22 00:48:03,373 INFO datanode.DataTransferProtocolAspects (DataTransferProtocolAspects.aj:ajc$afterReturning$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$1$8f59fdd7(51)) - FI: receiverOp WRITE_BLOCK, datanode=127.0.0.1:51714
[junit] 2010-10-22 00:48:03,373 INFO datanode.DataTransferProtocolAspects (DataTransferProtocolAspects.aj:ajc$before$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$3$3251489(73)) - FI: receiverOpWriteBlock
[junit] 2010-10-22 00:48:03,373 INFO datanode.DataNode (DataXceiver.java:opWriteBlock(231)) - Receiving block blk_-863992521392524374_1001 src: /127.0.0.1:55113 dest: /127.0.0.1:51714
[junit] 2010-10-22 00:48:03,373 INFO datanode.DataNode (FSDataset.java:recoverClose(1246)) - Recover failed close blk_-863992521392524374_1001
[junit] 2010-10-22 00:48:03,374 INFO datanode.DataTransferProtocolAspects (DataTransferProtocolAspects.aj:ajc$afterReturning$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$1$8f59fdd7(51)) - FI: receiverOp WRITE_BLOCK, datanode=127.0.0.1:59488
[junit] 2010-10-22 00:48:03,375 INFO datanode.DataTransferProtocolAspects (DataTransferProtocolAspects.aj:ajc$before$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$3$3251489(73)) - FI: receiverOpWriteBlock
[junit] 2010-10-22 00:48:03,375 INFO datanode.DataNode (DataXceiver.java:opWriteBlock(231)) - Receiving block blk_-863992521392524374_1001 src: /127.0.0.1:60112 dest: /127.0.0.1:59488
[junit] 2010-10-22 00:48:03,375 INFO datanode.DataNode (FSDataset.java:recoverClose(1246)) - Recover failed close blk_-863992521392524374_1001
[junit] 2010-10-22 00:48:03,376 INFO datanode.DataTransferProtocolAspects (DataTransferProtocolAspects.aj:ajc$afterReturning$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$2$d4f6605f(61)) - FI: statusRead SUCCESS, datanode=127.0.0.1:51714
[junit] 2010-10-22 00:48:03,377 INFO datanode.DataNode (DataXceiver.java:opWriteBlock(380)) - Received block blk_-863992521392524374_1002 src: /127.0.0.1:55113 dest: /127.0.0.1:51714 of size 1
[junit] 2010-10-22 00:48:03,377 INFO hdfs.StateChange (BlockManager.java:addStoredBlock(1126)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:59488 is added to blk_-863992521392524374_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:51714|RBW], ReplicaUnderConstruction[127.0.0.1:59488|RBW], ReplicaUnderConstruction[127.0.0.1:35585|RBW]]} size 0
[junit] 2010-10-22 00:48:03,377 INFO datanode.DataNode (DataXceiver.java:opWriteBlock(380)) - Received block blk_-863992521392524374_1002 src: /127.0.0.1:60112 dest: /127.0.0.1:59488 of size 1
[junit] 2010-10-22 00:48:03,378 INFO namenode.FSNamesystem (FSNamesystem.java:updatePipeline(4553)) - updatePipeline(block=blk_-863992521392524374_1001, newGenerationStamp=1002, newLength=1, newNodes=[127.0.0.1:51714, 127.0.0.1:59488], clientName=DFSClient_342554748)
[junit] 2010-10-22 00:48:03,380 INFO namenode.FSNamesystem (FSNamesystem.java:updatePipeline(4595)) - updatePipeline(blk_-863992521392524374_1001) successfully to blk_-863992521392524374_1002
[junit] 2010-10-22 00:48:03,381 INFO hdfs.StateChange (BlockManager.java:addStoredBlock(1126)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51714 is added to blk_-863992521392524374_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:51714|RBW], ReplicaUnderConstruction[127.0.0.1:59488|RBW]]} size 1
[junit] 2010-10-22 00:48:03,384 INFO hdfs.StateChange (FSNamesystem.java:completeFileInternal(1713)) - DIR* NameSystem.completeFile: file /pipeline_Fi_38/foo is closed by DFSClient_342554748
[junit] 2010-10-22 00:48:03,387 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=open src=/pipeline_Fi_38/foo dst=null perm=null
[junit] 2010-10-22 00:48:03,388 INFO datanode.DataTransferProtocolAspects (DataTransferProtocolAspects.aj:ajc$afterReturning$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$1$8f59fdd7(51)) - FI: receiverOp READ_BLOCK, datanode=127.0.0.1:51714
[junit] Shutting down the Mini HDFS Cluster
[junit] 2010-10-22 00:48:03,390 INFO DataNode.clienttrace (BlockSender.java:sendBlock(490)) - src: /127.0.0.1:51714, dest: /127.0.0.1:55115, bytes: 5, op: HDFS_READ, cliID: DFSClient_342554748, offset: 0, srvID: DS-1113396603-127.0.1.1-51714-1287708473055, blockid: blk_-863992521392524374_1002, duration: 292944
[junit] 2010-10-22 00:48:03,390 INFO hdfs.MiniDFSCluster (MiniDFSCluster.java:shutdownDataNodes(772)) - Shutting down DataNode 2
[junit] 2010-10-22 00:48:03,390 INFO datanode.DataTransferProtocolAspects (DataTransferProtocolAspects.aj:ajc$afterReturning$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$2$d4f6605f(61)) - FI: statusRead CHECKSUM_OK, datanode=127.0.0.1:51714
[junit] 2010-10-22 00:48:03,492 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 60696
[junit] 2010-10-22 00:48:03,492 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 60696: exiting
[junit] 2010-10-22 00:48:03,492 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 60696
[junit] 2010-10-22 00:48:03,493 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-10-22 00:48:03,493 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 3
[junit] 2010-10-22 00:48:03,493 INFO datanode.DataNode (BlockReceiver.java:run(886)) - PacketResponder blk_-863992521392524374_1001 0 : Thread is interrupted.
[junit] 2010-10-22 00:48:03,493 INFO datanode.BlockReceiverAspects (BlockReceiverAspects.aj:ajc$after$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$9$725950a6(220)) - FI: blockFileClose, datanode=DatanodeRegistration(127.0.0.1:35585, storageID=DS-421696816-127.0.1.1-35585-1287708473297, infoPort=60123, ipcPort=60696)
[junit] 2010-10-22 00:48:03,493 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 0 for block blk_-863992521392524374_1001 terminating
[junit] 2010-10-22 00:48:03,493 WARN datanode.DataNode (DataXceiverServer.java:run(140)) - DatanodeRegistration(127.0.0.1:35585, storageID=DS-421696816-127.0.1.1-35585-1287708473297, infoPort=60123, ipcPort=60696):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.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-10-22 00:48:03,494 INFO datanode.BlockReceiverAspects (BlockReceiverAspects.aj:ajc$after$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$9$725950a6(220)) - FI: blockFileClose, datanode=DatanodeRegistration(127.0.0.1:35585, storageID=DS-421696816-127.0.1.1-35585-1287708473297, infoPort=60123, ipcPort=60696)
[junit] 2010-10-22 00:48:03,495 ERROR datanode.DataNode (DataXceiver.java:run(116)) - DatanodeRegistration(127.0.0.1:35585, storageID=DS-421696816-127.0.1.1-35585-1287708473297, infoPort=60123, ipcPort=60696):DataXceiver
[junit] java.lang.RuntimeException: java.lang.InterruptedException: sleep interrupted
[junit] at org.apache.hadoop.fi.FiTestUtil.sleep(FiTestUtil.java:82)
[junit] at org.apache.hadoop.fi.DataTransferTestUtil$SleepAction.run(DataTransferTestUtil.java:346)
[junit] at org.apache.hadoop.fi.DataTransferTestUtil$SleepAction.run(DataTransferTestUtil.java:1)
[junit] at org.apache.hadoop.fi.FiTestUtil$ActionContainer.run(FiTestUtil.java:116)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiverAspects.ajc$before$org_apache_hadoop_hdfs_server_datanode_BlockReceiverAspects$7$b9c2bffe(BlockReceiverAspects.aj:193)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver.receivePacket(BlockReceiver.java:437)
[junit] at org.apache.hadoop.hdfs.server.datanode.BlockReceiver.receiveBlock(BlockReceiver.java:625)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiver.opWriteBlock(DataXceiver.java:363)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$Receiver.opWriteBlock(DataTransferProtocol.java:389)
[junit] at org.apache.hadoop.hdfs.protocol.DataTransferProtocol$Receiver.processOp(DataTransferProtocol.java:331)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiver.run(DataXceiver.java:114)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] Caused by: java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.fi.FiTestUtil.sleep(FiTestUtil.java:80)
[junit] ... 11 more
[junit] 2010-10-22 00:48:03,495 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2010-10-22 00:48:03,496 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(621)) - Exiting DataBlockScanner thread.
[junit] 2010-10-22 00:48:03,496 INFO datanode.DataNode (DataNode.java:run(1443)) - DatanodeRegistration(127.0.0.1:35585, storageID=DS-421696816-127.0.1.1-35585-1287708473297, infoPort=60123, ipcPort=60696):Finishing DataNode in: FSDataset{dirpath='<https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build-fi/test/data/dfs/data/data5/current/finalized,/grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build-fi/test/data/dfs/data/data6/current/finalized'}>
[junit] 2010-10-22 00:48:03,496 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 60696
[junit] 2010-10-22 00:48:03,497 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2010-10-22 00:48:03,497 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk service threads...
[junit] 2010-10-22 00:48:03,497 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads have been shut down.
[junit] 2010-10-22 00:48:03,498 WARN datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already shut down.
[junit] 2010-10-22 00:48:03,498 INFO hdfs.MiniDFSCluster (MiniDFSCluster.java:shutdownDataNodes(772)) - Shutting down DataNode 1
[junit] 2010-10-22 00:48:03,600 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 48461
[junit] 2010-10-22 00:48:03,600 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 48461: exiting
[junit] 2010-10-22 00:48:03,600 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-10-22 00:48:03,600 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2010-10-22 00:48:03,601 WARN datanode.DataNode (DataXceiverServer.java:run(140)) - DatanodeRegistration(127.0.0.1:51714, storageID=DS-1113396603-127.0.1.1-51714-1287708473055, infoPort=49069, ipcPort=48461):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.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-10-22 00:48:03,601 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 48461
[junit] 2010-10-22 00:48:03,603 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2010-10-22 00:48:03,603 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(621)) - Exiting DataBlockScanner thread.
[junit] 2010-10-22 00:48:03,604 INFO datanode.DataNode (DataNode.java:run(1443)) - DatanodeRegistration(127.0.0.1:51714, storageID=DS-1113396603-127.0.1.1-51714-1287708473055, infoPort=49069, ipcPort=48461):Finishing DataNode in: FSDataset{dirpath='<https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/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] 2010-10-22 00:48:03,604 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 48461
[junit] 2010-10-22 00:48:03,604 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2010-10-22 00:48:03,605 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk service threads...
[junit] 2010-10-22 00:48:03,605 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads have been shut down.
[junit] 2010-10-22 00:48:03,605 WARN datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already shut down.
[junit] 2010-10-22 00:48:03,605 INFO hdfs.MiniDFSCluster (MiniDFSCluster.java:shutdownDataNodes(772)) - Shutting down DataNode 0
[junit] 2010-10-22 00:48:03,607 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 53960
[junit] 2010-10-22 00:48:03,608 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 53960: exiting
[junit] 2010-10-22 00:48:03,608 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 53960
[junit] 2010-10-22 00:48:03,608 WARN datanode.DataNode (DataXceiverServer.java:run(140)) - DatanodeRegistration(127.0.0.1:59488, storageID=DS-1762502180-127.0.1.1-59488-1287708472800, infoPort=46435, ipcPort=53960):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.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-10-22 00:48:03,608 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2010-10-22 00:48:03,608 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-10-22 00:48:03,709 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(621)) - Exiting DataBlockScanner thread.
[junit] 2010-10-22 00:48:03,709 INFO datanode.DataNode (DataNode.java:run(1443)) - DatanodeRegistration(127.0.0.1:59488, storageID=DS-1762502180-127.0.1.1-59488-1287708472800, infoPort=46435, ipcPort=53960):Finishing DataNode in: FSDataset{dirpath='<https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/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] 2010-10-22 00:48:03,709 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 53960
[junit] 2010-10-22 00:48:03,709 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2010-10-22 00:48:03,710 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk service threads...
[junit] 2010-10-22 00:48:03,710 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads have been shut down.
[junit] 2010-10-22 00:48:03,710 WARN datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already shut down.
[junit] 2010-10-22 00:48:03,812 WARN namenode.DecommissionManager (DecommissionManager.java:run(70)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2010-10-22 00:48:03,812 WARN namenode.FSNamesystem (FSNamesystem.java:run(2818)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2010-10-22 00:48:03,812 INFO namenode.FSNamesystem (FSEditLog.java:printStatistics(1122)) - Number of transactions: 6 Total time for transactions(ms): 2Number of transactions batched in Syncs: 0 Number of syncs: 3 SyncTimes(ms): 6 3
[junit] 2010-10-22 00:48:03,814 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 49576
[junit] 2010-10-22 00:48:03,814 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 49576: exiting
[junit] 2010-10-22 00:48:03,814 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 2 on 49576: exiting
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 5 on 49576: exiting
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 8 on 49576: exiting
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 88.475 sec
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 49576
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 9 on 49576: exiting
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 1 on 49576: exiting
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 4 on 49576: exiting
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 6 on 49576: exiting
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 7 on 49576: exiting
[junit] 2010-10-22 00:48:03,815 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 3 on 49576: exiting
checkfailure:
run-test-hdfs-all-withtestcaseonly:
run-test-hdfs:
BUILD FAILED
<https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build.xml>:708: Tests failed!
Total time: 240 minutes 15 seconds
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
