See <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/486/changes>

Changes:

[sradia] HDFS-1491  Update Hdfs to match the change of methods from protected 
to public
in AbstractFileSystem (Hadoop-6903)

[eli] HDFS-1160. Improve some FSDataset warnings and comments. Contributed by 
Eli Collins.

------------------------------------------
[...truncated 762903 lines...]
    [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:932)
    [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:932)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 2010-11-13 15:37:28,468 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:932)
    [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:932)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 2010-11-13 15:37:28,469 INFO  datanode.DataNode 
(BlockReceiver.java:run(956)) - PacketResponder blk_2567480301139301540_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:932)
    [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:932)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2010-11-13 15:37:28,469 INFO  datanode.DataNode 
(BlockReceiver.java:run(972)) - PacketResponder 2 for block 
blk_2567480301139301540_1001 terminating
    [junit] 2010-11-13 15:37:28,469 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:46132, 
storageID=DS-1792012205-127.0.1.1-46132-1289662638418, infoPort=44818, 
ipcPort=38771)
    [junit] 2010-11-13 15:37:28,470 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:46132, 
storageID=DS-1792012205-127.0.1.1-46132-1289662638418, infoPort=44818, 
ipcPort=38771)
    [junit] 2010-11-13 15:37:28,468 WARN  hdfs.DFSClient 
(DFSOutputStream.java:setupPipelineForAppendOrRecovery(776)) - Error Recovery 
for block blk_2567480301139301540_1001 in pipeline 127.0.0.1:46132, 
127.0.0.1:34812, 127.0.0.1:57378: bad datanode 127.0.0.1:57378
    [junit] 2010-11-13 15:37:28,470 WARN  datanode.DataNode 
(BlockReceiver.java:run(949)) - 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 
org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:105)
    [junit]     at 
java.io.DataOutputStream.writeShort(DataOutputStream.java:150)
    [junit]     at 
org.apache.hadoop.hdfs.protocol.DataTransferProtocol$Status.write(DataTransferProtocol.java:120)
    [junit]     at 
org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:545)
    [junit]     at 
org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:932)
    [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:932)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 2010-11-13 15:37:28,471 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 
org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:105)
    [junit]     at 
java.io.DataOutputStream.writeShort(DataOutputStream.java:150)
    [junit]     at 
org.apache.hadoop.hdfs.protocol.DataTransferProtocol$Status.write(DataTransferProtocol.java:120)
    [junit]     at 
org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:545)
    [junit]     at 
org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:932)
    [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:932)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 2010-11-13 15:37:28,472 INFO  datanode.DataNode 
(BlockReceiver.java:run(956)) - PacketResponder blk_2567480301139301540_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 
org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:105)
    [junit]     at 
java.io.DataOutputStream.writeShort(DataOutputStream.java:150)
    [junit]     at 
org.apache.hadoop.hdfs.protocol.DataTransferProtocol$Status.write(DataTransferProtocol.java:120)
    [junit]     at 
org.apache.hadoop.hdfs.protocol.DataTransferProtocol$PipelineAck.write(DataTransferProtocol.java:545)
    [junit]     at 
org.apache.hadoop.hdfs.server.datanode.BlockReceiver$PacketResponder.write_aroundBody0(BlockReceiver.java:932)
    [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:932)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2010-11-13 15:37:28,472 INFO  datanode.DataNode 
(BlockReceiver.java:run(972)) - PacketResponder 1 for block 
blk_2567480301139301540_1001 terminating
    [junit] 2010-11-13 15:37:28,473 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:34812, 
storageID=DS-395306574-127.0.1.1-34812-1289662637871, infoPort=54933, 
ipcPort=55262)
    [junit] 2010-11-13 15:37:28,474 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:34812, 
storageID=DS-395306574-127.0.1.1-34812-1289662637871, infoPort=54933, 
ipcPort=55262)
    [junit] 2010-11-13 15:37:28,475 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:46132
    [junit] 2010-11-13 15:37:28,475 INFO  datanode.DataTransferProtocolAspects 
(DataTransferProtocolAspects.aj:ajc$before$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$3$3251489(73))
 - FI: receiverOpWriteBlock
    [junit] 2010-11-13 15:37:28,476 INFO  datanode.DataNode 
(DataXceiver.java:opWriteBlock(231)) - Receiving block 
blk_2567480301139301540_1001 src: /127.0.0.1:36837 dest: /127.0.0.1:46132
    [junit] 2010-11-13 15:37:28,476 INFO  datanode.DataNode 
(FSDataset.java:recoverClose(1243)) - Recover failed close 
blk_2567480301139301540_1001
    [junit] 2010-11-13 15:37:28,477 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:34812
    [junit] 2010-11-13 15:37:28,477 INFO  datanode.DataTransferProtocolAspects 
(DataTransferProtocolAspects.aj:ajc$before$org_apache_hadoop_hdfs_server_datanode_DataTransferProtocolAspects$3$3251489(73))
 - FI: receiverOpWriteBlock
    [junit] 2010-11-13 15:37:28,477 INFO  datanode.DataNode 
(DataXceiver.java:opWriteBlock(231)) - Receiving block 
blk_2567480301139301540_1001 src: /127.0.0.1:56110 dest: /127.0.0.1:34812
    [junit] 2010-11-13 15:37:28,478 INFO  datanode.DataNode 
(FSDataset.java:recoverClose(1243)) - Recover failed close 
blk_2567480301139301540_1001
    [junit] 2010-11-13 15:37:28,478 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:46132
    [junit] 2010-11-13 15:37:28,479 INFO  datanode.DataNode 
(DataXceiver.java:opWriteBlock(380)) - Received block 
blk_2567480301139301540_1002 src: /127.0.0.1:56110 dest: /127.0.0.1:34812 of 
size 1
    [junit] 2010-11-13 15:37:28,479 INFO  datanode.DataNode 
(DataXceiver.java:opWriteBlock(380)) - Received block 
blk_2567480301139301540_1002 src: /127.0.0.1:36837 dest: /127.0.0.1:46132 of 
size 1
    [junit] 2010-11-13 15:37:28,480 INFO  hdfs.StateChange 
(BlockManager.java:addStoredBlock(1126)) - BLOCK* NameSystem.addStoredBlock: 
blockMap updated: 127.0.0.1:34812 is added to 
blk_2567480301139301540_1001{blockUCState=UNDER_CONSTRUCTION, 
primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:46132|RBW], 
ReplicaUnderConstruction[127.0.0.1:34812|RBW], 
ReplicaUnderConstruction[127.0.0.1:57378|RBW]]} size 0
    [junit] 2010-11-13 15:37:28,480 INFO  namenode.FSNamesystem 
(FSNamesystem.java:updatePipeline(4575)) - 
updatePipeline(block=blk_2567480301139301540_1001, newGenerationStamp=1002, 
newLength=1, newNodes=[127.0.0.1:46132, 127.0.0.1:34812], 
clientName=DFSClient_1211128967)
    [junit] 2010-11-13 15:37:28,482 INFO  namenode.FSNamesystem 
(FSNamesystem.java:updatePipeline(4617)) - 
updatePipeline(blk_2567480301139301540_1001) successfully to 
blk_2567480301139301540_1002
    [junit] 2010-11-13 15:37:28,483 INFO  hdfs.StateChange 
(BlockManager.java:addStoredBlock(1126)) - BLOCK* NameSystem.addStoredBlock: 
blockMap updated: 127.0.0.1:46132 is added to 
blk_2567480301139301540_1002{blockUCState=UNDER_CONSTRUCTION, 
primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:46132|RBW], 
ReplicaUnderConstruction[127.0.0.1:34812|RBW]]} size 1
    [junit] 2010-11-13 15:37:28,485 INFO  hdfs.StateChange 
(FSNamesystem.java:completeFileInternal(1713)) - DIR* NameSystem.completeFile: 
file /pipeline_Fi_38/foo is closed by DFSClient_1211128967
    [junit] 2010-11-13 15:37:28,488 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-11-13 15:37:28,489 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:46132
    [junit] Shutting down the Mini HDFS Cluster
    [junit] 2010-11-13 15:37:28,491 INFO  DataNode.clienttrace 
(BlockSender.java:sendBlock(491)) - src: /127.0.0.1:46132, dest: 
/127.0.0.1:36839, bytes: 5, op: HDFS_READ, cliID: DFSClient_1211128967, offset: 
0, srvID: DS-1792012205-127.0.1.1-46132-1289662638418, blockid: 
blk_2567480301139301540_1002, duration: 280379
    [junit] 2010-11-13 15:37:28,491 INFO  hdfs.MiniDFSCluster 
(MiniDFSCluster.java:shutdownDataNodes(772)) - Shutting down DataNode 2
    [junit] 2010-11-13 15:37:28,491 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:46132
    [junit] 2010-11-13 15:37:28,593 INFO  ipc.Server (Server.java:stop(1601)) - 
Stopping server on 38771
    [junit] 2010-11-13 15:37:28,594 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 0 on 38771: exiting
    [junit] 2010-11-13 15:37:28,594 INFO  ipc.Server (Server.java:run(675)) - 
Stopping IPC Server Responder
    [junit] 2010-11-13 15:37:28,594 WARN  datanode.DataNode 
(DataXceiverServer.java:run(141)) - DatanodeRegistration(127.0.0.1:46132, 
storageID=DS-1792012205-127.0.1.1-46132-1289662638418, infoPort=44818, 
ipcPort=38771):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:134)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2010-11-13 15:37:28,594 INFO  ipc.Server (Server.java:run(475)) - 
Stopping IPC Server listener on 38771
    [junit] 2010-11-13 15:37:28,595 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 0
    [junit] 2010-11-13 15:37:28,596 INFO  datanode.DataBlockScanner 
(DataBlockScanner.java:run(622)) - Exiting DataBlockScanner thread.
    [junit] 2010-11-13 15:37:28,596 INFO  datanode.DataNode 
(DataNode.java:run(1440)) - DatanodeRegistration(127.0.0.1:46132, 
storageID=DS-1792012205-127.0.1.1-46132-1289662638418, infoPort=44818, 
ipcPort=38771):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-11-13 15:37:28,596 INFO  ipc.Server (Server.java:stop(1601)) - 
Stopping server on 38771
    [junit] 2010-11-13 15:37:28,596 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 0
    [junit] 2010-11-13 15:37:28,597 INFO  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk 
service threads...
    [junit] 2010-11-13 15:37:28,597 INFO  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads 
have been shut down.
    [junit] 2010-11-13 15:37:28,597 WARN  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already 
shut down.
    [junit] 2010-11-13 15:37:28,598 INFO  hdfs.MiniDFSCluster 
(MiniDFSCluster.java:shutdownDataNodes(772)) - Shutting down DataNode 1
    [junit] 2010-11-13 15:37:28,699 INFO  ipc.Server (Server.java:stop(1601)) - 
Stopping server on 56422
    [junit] 2010-11-13 15:37:28,700 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 0 on 56422: exiting
    [junit] 2010-11-13 15:37:28,700 INFO  ipc.Server (Server.java:run(475)) - 
Stopping IPC Server listener on 56422
    [junit] 2010-11-13 15:37:28,700 INFO  ipc.Server (Server.java:run(675)) - 
Stopping IPC Server Responder
    [junit] 2010-11-13 15:37:28,700 WARN  datanode.DataNode 
(DataXceiverServer.java:run(141)) - DatanodeRegistration(127.0.0.1:57378, 
storageID=DS-927099062-127.0.1.1-57378-1289662638149, infoPort=33635, 
ipcPort=56422):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:134)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2010-11-13 15:37:28,700 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 3
    [junit] 2010-11-13 15:37:28,700 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:57378, 
storageID=DS-927099062-127.0.1.1-57378-1289662638149, infoPort=33635, 
ipcPort=56422)
    [junit] 2010-11-13 15:37:28,700 INFO  datanode.DataNode 
(BlockReceiver.java:run(887)) - PacketResponder blk_2567480301139301540_1001 0 
: Thread is interrupted.
    [junit] 2010-11-13 15:37:28,702 INFO  datanode.DataNode 
(BlockReceiver.java:run(972)) - PacketResponder 0 for block 
blk_2567480301139301540_1001 terminating
    [junit] 2010-11-13 15:37:28,701 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:57378, 
storageID=DS-927099062-127.0.1.1-57378-1289662638149, infoPort=33635, 
ipcPort=56422)
    [junit] 2010-11-13 15:37:28,702 ERROR datanode.DataNode 
(DataXceiver.java:run(116)) - DatanodeRegistration(127.0.0.1:57378, 
storageID=DS-927099062-127.0.1.1-57378-1289662638149, infoPort=33635, 
ipcPort=56422):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:438)
    [junit]     at 
org.apache.hadoop.hdfs.server.datanode.BlockReceiver.receiveBlock(BlockReceiver.java:626)
    [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-11-13 15:37:28,703 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 0
    [junit] 2010-11-13 15:37:28,704 INFO  datanode.DataBlockScanner 
(DataBlockScanner.java:run(622)) - Exiting DataBlockScanner thread.
    [junit] 2010-11-13 15:37:28,704 INFO  datanode.DataNode 
(DataNode.java:run(1440)) - DatanodeRegistration(127.0.0.1:57378, 
storageID=DS-927099062-127.0.1.1-57378-1289662638149, infoPort=33635, 
ipcPort=56422):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-11-13 15:37:28,704 INFO  ipc.Server (Server.java:stop(1601)) - 
Stopping server on 56422
    [junit] 2010-11-13 15:37:28,704 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 0
    [junit] 2010-11-13 15:37:28,705 INFO  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk 
service threads...
    [junit] 2010-11-13 15:37:28,705 INFO  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads 
have been shut down.
    [junit] 2010-11-13 15:37:28,705 WARN  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already 
shut down.
    [junit] 2010-11-13 15:37:28,706 INFO  hdfs.MiniDFSCluster 
(MiniDFSCluster.java:shutdownDataNodes(772)) - Shutting down DataNode 0
    [junit] 2010-11-13 15:37:28,807 INFO  ipc.Server (Server.java:stop(1601)) - 
Stopping server on 55262
    [junit] 2010-11-13 15:37:28,808 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 0 on 55262: exiting
    [junit] 2010-11-13 15:37:28,808 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 1
    [junit] 2010-11-13 15:37:28,808 WARN  datanode.DataNode 
(DataXceiverServer.java:run(141)) - DatanodeRegistration(127.0.0.1:34812, 
storageID=DS-395306574-127.0.1.1-34812-1289662637871, infoPort=54933, 
ipcPort=55262):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:134)
    [junit]     at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2010-11-13 15:37:28,808 INFO  ipc.Server (Server.java:run(675)) - 
Stopping IPC Server Responder
    [junit] 2010-11-13 15:37:28,808 INFO  ipc.Server (Server.java:run(475)) - 
Stopping IPC Server listener on 55262
    [junit] 2010-11-13 15:37:28,810 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 0
    [junit] 2010-11-13 15:37:28,911 INFO  datanode.DataBlockScanner 
(DataBlockScanner.java:run(622)) - Exiting DataBlockScanner thread.
    [junit] 2010-11-13 15:37:28,911 INFO  datanode.DataNode 
(DataNode.java:run(1440)) - DatanodeRegistration(127.0.0.1:34812, 
storageID=DS-395306574-127.0.1.1-34812-1289662637871, infoPort=54933, 
ipcPort=55262):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-11-13 15:37:28,911 INFO  ipc.Server (Server.java:stop(1601)) - 
Stopping server on 55262
    [junit] 2010-11-13 15:37:28,912 INFO  datanode.DataNode 
(DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads 
is 0
    [junit] 2010-11-13 15:37:28,912 INFO  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk 
service threads...
    [junit] 2010-11-13 15:37:28,912 INFO  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads 
have been shut down.
    [junit] 2010-11-13 15:37:28,912 WARN  datanode.FSDatasetAsyncDiskService 
(FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already 
shut down.
    [junit] 2010-11-13 15:37:29,014 WARN  namenode.FSNamesystem 
(FSNamesystem.java:run(2818)) - ReplicationMonitor thread received 
InterruptedException.java.lang.InterruptedException: sleep interrupted
    [junit] 2010-11-13 15:37:29,014 INFO  namenode.FSEditLog 
(FSEditLog.java:printStatistics(630)) - Number of transactions: 6 Total time 
for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of 
syncs: 3 SyncTimes(ms): 6 2 
    [junit] 2010-11-13 15:37:29,014 WARN  namenode.DecommissionManager 
(DecommissionManager.java:run(70)) - Monitor interrupted: 
java.lang.InterruptedException: sleep interrupted
    [junit] 2010-11-13 15:37:29,016 INFO  ipc.Server (Server.java:stop(1601)) - 
Stopping server on 54939
    [junit] 2010-11-13 15:37:29,016 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 0 on 54939: exiting
    [junit] 2010-11-13 15:37:29,016 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 2 on 54939: exiting
    [junit] 2010-11-13 15:37:29,016 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 1 on 54939: exiting
    [junit] 2010-11-13 15:37:29,017 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 7 on 54939: exiting
    [junit] 2010-11-13 15:37:29,017 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 9 on 54939: exiting
    [junit] 2010-11-13 15:37:29,018 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 6 on 54939: exiting
    [junit] 2010-11-13 15:37:29,018 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 4 on 54939: exiting
    [junit] 2010-11-13 15:37:29,018 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 5 on 54939: exiting
    [junit] 2010-11-13 15:37:29,017 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 8 on 54939: exiting
    [junit] 2010-11-13 15:37:29,017 INFO  ipc.Server (Server.java:run(1444)) - 
IPC Server handler 3 on 54939: exiting
    [junit] 2010-11-13 15:37:29,018 INFO  ipc.Server (Server.java:run(675)) - 
Stopping IPC Server Responder
    [junit] 2010-11-13 15:37:29,018 INFO  ipc.Server (Server.java:run(475)) - 
Stopping IPC Server listener on 54939
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 73.743 sec

checkfailure:

run-test-hdfs-all-withtestcaseonly:

run-test-hdfs:

BUILD FAILED
<https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build.xml>:723:
 Tests failed!

Total time: 242 minutes 22 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

Reply via email to