Return-Path: Delivered-To: apmail-hadoop-hdfs-dev-archive@minotaur.apache.org Received: (qmail 86139 invoked from network); 13 Nov 2010 15:36:05 -0000 Received: from unknown (HELO mail.apache.org) (140.211.11.3) by 140.211.11.9 with SMTP; 13 Nov 2010 15:36:05 -0000 Received: (qmail 90010 invoked by uid 500); 13 Nov 2010 15:36:36 -0000 Delivered-To: apmail-hadoop-hdfs-dev-archive@hadoop.apache.org Received: (qmail 89719 invoked by uid 500); 13 Nov 2010 15:36:35 -0000 Mailing-List: contact hdfs-dev-help@hadoop.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: hdfs-dev@hadoop.apache.org Delivered-To: mailing list hdfs-dev@hadoop.apache.org Received: (qmail 89709 invoked by uid 99); 13 Nov 2010 15:36:34 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 13 Nov 2010 15:36:34 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=10.0 tests=ALL_TRUSTED,LOTS_OF_MONEY X-Spam-Check-By: apache.org Received: from [140.211.11.8] (HELO aegis.apache.org) (140.211.11.8) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 13 Nov 2010 15:36:32 +0000 Received: from aegis (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id BBA06C0164 for ; Sat, 13 Nov 2010 15:36:12 +0000 (UTC) Date: Sat, 13 Nov 2010 15:36:09 +0000 (UTC) From: Apache Hudson Server To: hdfs-dev@hadoop.apache.org Message-ID: <415167594.10181289662572767.JavaMail.hudson@aegis> In-Reply-To: <956715408.9161289575733509.JavaMail.hudson@aegis> References: <956715408.9161289575733509.JavaMail.hudson@aegis> Subject: Build failed in Hudson: Hadoop-Hdfs-trunk #486 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit See 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=' [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=' [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=' [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 :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