Return-Path: Delivered-To: apmail-hadoop-hdfs-dev-archive@minotaur.apache.org Received: (qmail 59188 invoked from network); 20 Jan 2011 12:32:40 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.3) by minotaur.apache.org with SMTP; 20 Jan 2011 12:32:40 -0000 Received: (qmail 65490 invoked by uid 500); 20 Jan 2011 12:32:40 -0000 Delivered-To: apmail-hadoop-hdfs-dev-archive@hadoop.apache.org Received: (qmail 65135 invoked by uid 500); 20 Jan 2011 12:32:36 -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 65127 invoked by uid 99); 20 Jan 2011 12:32:35 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 20 Jan 2011 12:32:35 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=10.0 tests=ALL_TRUSTED 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; Thu, 20 Jan 2011 12:32:34 +0000 Received: from aegis (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id 0F3BBC0168 for ; Thu, 20 Jan 2011 12:32:14 +0000 (UTC) Date: Thu, 20 Jan 2011 12:32:09 +0000 (UTC) From: Apache Hudson Server To: hdfs-dev@hadoop.apache.org Message-ID: <440090244.2961295526733820.JavaMail.hudson@aegis> In-Reply-To: <1996417304.931295453220193.JavaMail.hudson@aegis> References: <1996417304.931295453220193.JavaMail.hudson@aegis> Subject: Hadoop-Hdfs-trunk - Build # 558 - Still Failing MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit See https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk/558/ ################################################################################### ########################## LAST 60 LINES OF THE CONSOLE ########################### [...truncated 666794 lines...] [junit] 2011-01-20 12:32:12,854 WARN datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already shut down. [junit] 2011-01-20 12:32:12,854 INFO hdfs.MiniDFSCluster (MiniDFSCluster.java:shutdownDataNodes(831)) - Shutting down DataNode 0 [junit] 2011-01-20 12:32:12,956 INFO ipc.Server (Server.java:stop(1610)) - Stopping server on 37101 [junit] 2011-01-20 12:32:12,956 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 0 on 37101: exiting [junit] 2011-01-20 12:32:12,956 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 37101 [junit] 2011-01-20 12:32:12,956 INFO datanode.DataNode (DataNode.java:shutdown(785)) - Waiting for threadgroup to exit, active threads is 1 [junit] 2011-01-20 12:32:12,957 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder [junit] 2011-01-20 12:32:12,957 WARN datanode.DataNode (DataXceiverServer.java:run(141)) - DatanodeRegistration(127.0.0.1:44942, storageID=DS-291946037-127.0.1.1-44942-1295526721841, infoPort=37284, ipcPort=37101):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] 2011-01-20 12:32:12,959 INFO datanode.DataNode (DataNode.java:shutdown(785)) - Waiting for threadgroup to exit, active threads is 0 [junit] 2011-01-20 12:32:13,059 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(622)) - Exiting DataBlockScanner thread. [junit] 2011-01-20 12:32:13,060 INFO datanode.DataNode (DataNode.java:run(1459)) - DatanodeRegistration(127.0.0.1:44942, storageID=DS-291946037-127.0.1.1-44942-1295526721841, infoPort=37284, ipcPort=37101):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-01-20 12:32:13,060 INFO ipc.Server (Server.java:stop(1610)) - Stopping server on 37101 [junit] 2011-01-20 12:32:13,060 INFO datanode.DataNode (DataNode.java:shutdown(785)) - Waiting for threadgroup to exit, active threads is 0 [junit] 2011-01-20 12:32:13,061 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk service threads... [junit] 2011-01-20 12:32:13,061 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads have been shut down. [junit] 2011-01-20 12:32:13,061 WARN datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already shut down. [junit] 2011-01-20 12:32:13,163 WARN namenode.FSNamesystem (FSNamesystem.java:run(2844)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted [junit] 2011-01-20 12:32:13,163 INFO namenode.FSEditLog (FSEditLog.java:printStatistics(631)) - Number of transactions: 6 Total time for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of syncs: 3 SyncTimes(ms): 10 2 [junit] 2011-01-20 12:32:13,164 WARN namenode.DecommissionManager (DecommissionManager.java:run(70)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted [junit] 2011-01-20 12:32:13,165 INFO ipc.Server (Server.java:stop(1610)) - Stopping server on 60156 [junit] 2011-01-20 12:32:13,165 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 0 on 60156: exiting [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 9 on 60156: exiting [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 2 on 60156: exiting [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 1 on 60156: exiting [junit] 2011-01-20 12:32:13,167 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 3 on 60156: exiting [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 36.507 sec [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 5 on 60156: exiting [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 8 on 60156: exiting [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 60156 [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 7 on 60156: exiting [junit] 2011-01-20 12:32:13,166 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 4 on 60156: exiting [junit] 2011-01-20 12:32:13,168 INFO ipc.Server (Server.java:run(1443)) - IPC Server handler 6 on 60156: exiting 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:744: Tests failed! Total time: 58 minutes 54 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) ############################## 4 tests failed. REGRESSION: org.apache.hadoop.hdfs.TestFileConcurrentReader.testUnfinishedBlockCRCErrorTransferToVerySmallWrite Error Message: Too many open files Stack Trace: java.io.IOException: Too many open files at sun.nio.ch.IOUtil.initPipe(Native Method) at sun.nio.ch.EPollSelectorImpl.(EPollSelectorImpl.java:49) at sun.nio.ch.EPollSelectorProvider.openSelector(EPollSelectorProvider.java:18) at java.nio.channels.Selector.open(Selector.java:209) at org.apache.hadoop.ipc.Server$Listener.(Server.java:318) at org.apache.hadoop.ipc.Server.(Server.java:1501) at org.apache.hadoop.ipc.RPC$Server.(RPC.java:408) at org.apache.hadoop.ipc.WritableRpcEngine$Server.(WritableRpcEngine.java:332) at org.apache.hadoop.ipc.WritableRpcEngine.getServer(WritableRpcEngine.java:292) at org.apache.hadoop.ipc.WritableRpcEngine.getServer(WritableRpcEngine.java:47) at org.apache.hadoop.ipc.RPC.getServer(RPC.java:382) at org.apache.hadoop.hdfs.server.datanode.DataNode.initIpcServer(DataNode.java:421) at org.apache.hadoop.hdfs.server.datanode.DataNode.startDataNode(DataNode.java:512) at org.apache.hadoop.hdfs.server.datanode.DataNode.(DataNode.java:282) at org.apache.hadoop.hdfs.server.datanode.DataNode.(DataNode.java:264) at org.apache.hadoop.hdfs.server.datanode.DataNode.makeInstance(DataNode.java:1575) at org.apache.hadoop.hdfs.server.datanode.DataNode.instantiateDataNode(DataNode.java:1518) at org.apache.hadoop.hdfs.server.datanode.DataNode.instantiateDataNode(DataNode.java:1485) at org.apache.hadoop.hdfs.MiniDFSCluster.startDataNodes(MiniDFSCluster.java:674) at org.apache.hadoop.hdfs.MiniDFSCluster.initMiniDFSCluster(MiniDFSCluster.java:479) at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:199) at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:74) at org.apache.hadoop.hdfs.MiniDFSCluster$Builder.build(MiniDFSCluster.java:191) at org.apache.hadoop.hdfs.TestFileConcurrentReader.init(TestFileConcurrentReader.java:88) at org.apache.hadoop.hdfs.TestFileConcurrentReader.setUp(TestFileConcurrentReader.java:73) REGRESSION: org.apache.hadoop.hdfs.TestFileConcurrentReader.testUnfinishedBlockCRCErrorNormalTransfer Error Message: Cannot lock storage /grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/name1. The directory is already locked. Stack Trace: java.io.IOException: Cannot lock storage /grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/name1. The directory is already locked. at org.apache.hadoop.hdfs.server.common.Storage$StorageDirectory.lock(Storage.java:615) at org.apache.hadoop.hdfs.server.namenode.FSImage.format(FSImage.java:1342) at org.apache.hadoop.hdfs.server.namenode.FSImage.format(FSImage.java:1360) at org.apache.hadoop.hdfs.server.namenode.NameNode.format(NameNode.java:1408) at org.apache.hadoop.hdfs.server.namenode.NameNode.format(NameNode.java:202) at org.apache.hadoop.hdfs.MiniDFSCluster.initMiniDFSCluster(MiniDFSCluster.java:466) at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:199) at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:74) at org.apache.hadoop.hdfs.MiniDFSCluster$Builder.build(MiniDFSCluster.java:191) at org.apache.hadoop.hdfs.TestFileConcurrentReader.init(TestFileConcurrentReader.java:88) at org.apache.hadoop.hdfs.TestFileConcurrentReader.setUp(TestFileConcurrentReader.java:73) REGRESSION: org.apache.hadoop.hdfs.TestFileConcurrentReader.testUnfinishedBlockCRCErrorNormalTransferVerySmallWrite Error Message: Cannot lock storage /grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/name1. The directory is already locked. Stack Trace: java.io.IOException: Cannot lock storage /grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/name1. The directory is already locked. at org.apache.hadoop.hdfs.server.common.Storage$StorageDirectory.lock(Storage.java:615) at org.apache.hadoop.hdfs.server.namenode.FSImage.format(FSImage.java:1342) at org.apache.hadoop.hdfs.server.namenode.FSImage.format(FSImage.java:1360) at org.apache.hadoop.hdfs.server.namenode.NameNode.format(NameNode.java:1408) at org.apache.hadoop.hdfs.server.namenode.NameNode.format(NameNode.java:202) at org.apache.hadoop.hdfs.MiniDFSCluster.initMiniDFSCluster(MiniDFSCluster.java:466) at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:199) at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:74) at org.apache.hadoop.hdfs.MiniDFSCluster$Builder.build(MiniDFSCluster.java:191) at org.apache.hadoop.hdfs.TestFileConcurrentReader.init(TestFileConcurrentReader.java:88) at org.apache.hadoop.hdfs.TestFileConcurrentReader.setUp(TestFileConcurrentReader.java:73) FAILED: org.apache.hadoop.hdfs.server.namenode.TestStorageRestore.testStorageRestore Error Message: Image file /grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/secondary/current/fsimage is corrupt with MD5 checksum of 5d56d76f2edb6df1d35f4b0ebc6a6454 but expecting 55b614bdf02a63d831043b08e74543dc Stack Trace: java.io.IOException: Image file /grid/0/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/secondary/current/fsimage is corrupt with MD5 checksum of 5d56d76f2edb6df1d35f4b0ebc6a6454 but expecting 55b614bdf02a63d831043b08e74543dc at org.apache.hadoop.hdfs.server.namenode.FSImage.loadFSImage(FSImage.java:1063) at org.apache.hadoop.hdfs.server.namenode.SecondaryNameNode$CheckpointStorage.doMerge(SecondaryNameNode.java:702) at org.apache.hadoop.hdfs.server.namenode.SecondaryNameNode$CheckpointStorage.access$500(SecondaryNameNode.java:600) at org.apache.hadoop.hdfs.server.namenode.SecondaryNameNode.doMerge(SecondaryNameNode.java:477) at org.apache.hadoop.hdfs.server.namenode.SecondaryNameNode.doCheckpoint(SecondaryNameNode.java:438) at org.apache.hadoop.hdfs.server.namenode.TestStorageRestore.__CLR3_0_2dn2tm4tme(TestStorageRestore.java:316) at org.apache.hadoop.hdfs.server.namenode.TestStorageRestore.testStorageRestore(TestStorageRestore.java:286)