Return-Path: Delivered-To: apmail-hadoop-hdfs-dev-archive@minotaur.apache.org Received: (qmail 10142 invoked from network); 23 Nov 2010 07:19:39 -0000 Received: from unknown (HELO mail.apache.org) (140.211.11.3) by 140.211.11.9 with SMTP; 23 Nov 2010 07:19:39 -0000 Received: (qmail 61342 invoked by uid 500); 23 Nov 2010 07:20:10 -0000 Delivered-To: apmail-hadoop-hdfs-dev-archive@hadoop.apache.org Received: (qmail 61176 invoked by uid 500); 23 Nov 2010 07:20:07 -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 61168 invoked by uid 99); 23 Nov 2010 07:20:06 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 23 Nov 2010 07:20:06 +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; Tue, 23 Nov 2010 07:20:04 +0000 Received: from aegis (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id EFB5FC0161 for ; Tue, 23 Nov 2010 07:19:43 +0000 (UTC) Date: Tue, 23 Nov 2010 07:19:43 +0000 (UTC) From: Apache Hudson Server To: hdfs-dev@hadoop.apache.org Message-ID: <1250632204.8191290496783935.JavaMail.hudson@aegis> In-Reply-To: <1469955561.8011290477955982.JavaMail.hudson@aegis> References: <1469955561.8011290477955982.JavaMail.hudson@aegis> Subject: Build failed in Hudson: Hadoop-Hdfs-trunk-Commit #466 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit See Changes: [hairong] HDFS-1481. NameNode should validate fsimage before rolling. Contributed by Hairong Kuang. ------------------------------------------ [...truncated 3893 lines...] [junit] 2010-11-23 07:19:15,492 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,492 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,495 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,496 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,496 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,496 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,497 INFO metrics.FSNamesystemMetrics (FSNamesystemMetrics.java:(80)) - Initializing FSNamesystemMetrics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,497 INFO namenode.FSNamesystem (FSNamesystem.java:registerMBean(4469)) - Registered FSNamesystemStatusMBean [junit] 2010-11-23 07:19:15,499 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,499 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,500 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,501 INFO common.Storage (FSImage.java:loadFSImage(1175)) - Number of files = 1 [junit] 2010-11-23 07:19:15,501 INFO common.Storage (FSImage.java:loadFilesUnderConstruction(1755)) - Number of files under construction = 0 [junit] 2010-11-23 07:19:15,502 INFO common.Storage (FSImage.java:loadFSImage(1289)) - Image file of size 113 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,614 INFO common.Storage (FSEditLogLoader.java:loadFSEdits(61)) - Edits file of size 4 edits # 0 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,615 INFO namenode.NameCache (NameCache.java:initialized(143)) - initialized with 0 entries 0 lookups [junit] 2010-11-23 07:19:15,615 INFO namenode.FSNamesystem (FSNamesystem.java:initialize(309)) - Finished loading FSImage in 126 msecs [junit] 2010-11-23 07:19:15,616 INFO util.HostsFileReader (HostsFileReader.java:refresh(85)) - Refreshing hosts (include/exclude) list [junit] 2010-11-23 07:19:15,616 DEBUG namenode.TestNNLeaseRecovery (TestNNLeaseRecovery.java:__CLR3_0_2cpnfswvqn(253)) - Running __CLR3_0_2cpnfswvqn [junit] 2010-11-23 07:19:15,618 INFO namenode.FSEditLog (FSEditLog.java:printStatistics(631)) - Number of transactions: 0 Total time for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of syncs: 0 SyncTimes(ms): 0 [junit] 2010-11-23 07:19:15,618 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 4 [junit] 2010-11-23 07:19:15,619 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049092 written 512 bytes at offset 1048580 [junit] 2010-11-23 07:19:15,626 INFO namenode.FSNamesystem (FSNamesystem.java:commitBlockSynchronization(2326)) - commitBlockSynchronization(lastblock=Mock for BlockInfoUnderConstruction, hashCode: 5683514, newgenerationstamp=2002, newlength=273487234, newtargets=[null], closeFile=true, deleteBlock=false) [junit] 2010-11-23 07:19:15,646 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Cannot initialize JVM Metrics with processName=NameNode, sessionId=null - already initialized [junit] 2010-11-23 07:19:15,647 INFO metrics.NameNodeMetrics (NameNodeMetrics.java:(113)) - Initializing NameNodeMeterics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,647 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,648 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,649 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,649 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,649 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,650 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,650 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,650 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,650 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,651 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,651 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,654 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,655 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,655 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,656 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,656 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,660 INFO common.Storage (FSImage.java:saveFSImage(1412)) - Image file of size 113 saved in 0 seconds. [junit] 2010-11-23 07:19:15,661 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 0 [junit] 2010-11-23 07:19:15,661 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049088 written 512 bytes at offset 1048576 [junit] 2010-11-23 07:19:15,668 INFO common.Storage (FSImage.java:format(1639)) - Storage directory has been successfully formatted. [junit] 2010-11-23 07:19:15,669 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,669 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,670 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,670 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,670 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,670 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,671 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,671 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,671 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,675 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,675 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,676 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,676 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,677 INFO metrics.FSNamesystemMetrics (FSNamesystemMetrics.java:(80)) - Initializing FSNamesystemMetrics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,678 INFO namenode.FSNamesystem (FSNamesystem.java:registerMBean(4469)) - Registered FSNamesystemStatusMBean [junit] 2010-11-23 07:19:15,679 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,680 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,680 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,682 INFO common.Storage (FSImage.java:loadFSImage(1175)) - Number of files = 1 [junit] 2010-11-23 07:19:15,682 INFO common.Storage (FSImage.java:loadFilesUnderConstruction(1755)) - Number of files under construction = 0 [junit] 2010-11-23 07:19:15,683 INFO common.Storage (FSImage.java:loadFSImage(1289)) - Image file of size 113 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,683 INFO common.Storage (FSEditLogLoader.java:loadFSEdits(61)) - Edits file of size 4 edits # 0 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,684 INFO namenode.NameCache (NameCache.java:initialized(143)) - initialized with 0 entries 0 lookups [junit] 2010-11-23 07:19:15,684 INFO namenode.FSNamesystem (FSNamesystem.java:initialize(309)) - Finished loading FSImage in 16 msecs [junit] 2010-11-23 07:19:15,684 INFO util.HostsFileReader (HostsFileReader.java:refresh(85)) - Refreshing hosts (include/exclude) list [junit] 2010-11-23 07:19:15,685 DEBUG namenode.TestNNLeaseRecovery (TestNNLeaseRecovery.java:__CLR3_0_2xseoacvr2(279)) - Running __CLR3_0_2xseoacvr2 [junit] 2010-11-23 07:19:15,687 INFO namenode.FSEditLog (FSEditLog.java:printStatistics(631)) - Number of transactions: 0 Total time for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of syncs: 0 SyncTimes(ms): 0 [junit] 2010-11-23 07:19:15,687 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 4 [junit] 2010-11-23 07:19:15,687 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049092 written 512 bytes at offset 1048580 [junit] 2010-11-23 07:19:15,694 INFO namenode.FSNamesystem (FSNamesystem.java:commitBlockSynchronization(2326)) - commitBlockSynchronization(lastblock=Mock for BlockInfoUnderConstruction, hashCode: 20545116, newgenerationstamp=2002, newlength=273487234, newtargets=[null], closeFile=true, deleteBlock=false) [junit] 2010-11-23 07:19:15,713 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Cannot initialize JVM Metrics with processName=NameNode, sessionId=null - already initialized [junit] 2010-11-23 07:19:15,714 INFO metrics.NameNodeMetrics (NameNodeMetrics.java:(113)) - Initializing NameNodeMeterics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,715 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,715 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,716 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,716 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,717 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,717 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,717 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,718 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,718 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,718 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,719 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,722 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,723 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,723 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,724 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,724 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,729 INFO common.Storage (FSImage.java:saveFSImage(1412)) - Image file of size 113 saved in 0 seconds. [junit] 2010-11-23 07:19:15,730 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 0 [junit] 2010-11-23 07:19:15,731 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049088 written 512 bytes at offset 1048576 [junit] 2010-11-23 07:19:15,736 INFO common.Storage (FSImage.java:format(1639)) - Storage directory has been successfully formatted. [junit] 2010-11-23 07:19:15,737 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,737 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,738 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,738 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,738 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,739 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,739 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,739 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,740 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,744 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,745 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,745 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,745 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,746 INFO metrics.FSNamesystemMetrics (FSNamesystemMetrics.java:(80)) - Initializing FSNamesystemMetrics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,747 INFO namenode.FSNamesystem (FSNamesystem.java:registerMBean(4469)) - Registered FSNamesystemStatusMBean [junit] 2010-11-23 07:19:15,748 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,749 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,749 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,751 INFO common.Storage (FSImage.java:loadFSImage(1175)) - Number of files = 1 [junit] 2010-11-23 07:19:15,751 INFO common.Storage (FSImage.java:loadFilesUnderConstruction(1755)) - Number of files under construction = 0 [junit] 2010-11-23 07:19:15,752 INFO common.Storage (FSImage.java:loadFSImage(1289)) - Image file of size 113 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,752 INFO common.Storage (FSEditLogLoader.java:loadFSEdits(61)) - Edits file of size 4 edits # 0 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,753 INFO namenode.NameCache (NameCache.java:initialized(143)) - initialized with 0 entries 0 lookups [junit] 2010-11-23 07:19:15,753 INFO namenode.FSNamesystem (FSNamesystem.java:initialize(309)) - Finished loading FSImage in 17 msecs [junit] 2010-11-23 07:19:15,753 INFO util.HostsFileReader (HostsFileReader.java:refresh(85)) - Refreshing hosts (include/exclude) list [junit] 2010-11-23 07:19:15,754 DEBUG namenode.TestNNLeaseRecovery (TestNNLeaseRecovery.java:__CLR3_0_2wnrgefvri(307)) - Running __CLR3_0_2wnrgefvri [junit] 2010-11-23 07:19:15,756 INFO namenode.FSEditLog (FSEditLog.java:printStatistics(631)) - Number of transactions: 0 Total time for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of syncs: 0 SyncTimes(ms): 0 [junit] 2010-11-23 07:19:15,756 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 4 [junit] 2010-11-23 07:19:15,756 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049092 written 512 bytes at offset 1048580 [junit] 2010-11-23 07:19:15,763 INFO namenode.FSNamesystem (FSNamesystem.java:commitBlockSynchronization(2326)) - commitBlockSynchronization(lastblock=Mock for BlockInfoUnderConstruction, hashCode: 25591289, newgenerationstamp=2002, newlength=273487234, newtargets=[null], closeFile=true, deleteBlock=false) [junit] 2010-11-23 07:19:15,782 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Cannot initialize JVM Metrics with processName=NameNode, sessionId=null - already initialized [junit] 2010-11-23 07:19:15,783 INFO metrics.NameNodeMetrics (NameNodeMetrics.java:(113)) - Initializing NameNodeMeterics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,783 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,784 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,785 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,785 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,785 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,785 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,786 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,786 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,786 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,787 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,787 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,790 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,791 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,791 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,792 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,792 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,796 INFO common.Storage (FSImage.java:saveFSImage(1412)) - Image file of size 113 saved in 0 seconds. [junit] 2010-11-23 07:19:15,797 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 0 [junit] 2010-11-23 07:19:15,797 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049088 written 512 bytes at offset 1048576 [junit] 2010-11-23 07:19:15,803 INFO common.Storage (FSImage.java:format(1639)) - Storage directory has been successfully formatted. [junit] 2010-11-23 07:19:15,803 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,804 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,804 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,804 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,805 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,805 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,805 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,806 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,806 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,810 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,810 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,810 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,811 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,811 INFO metrics.FSNamesystemMetrics (FSNamesystemMetrics.java:(80)) - Initializing FSNamesystemMetrics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,812 INFO namenode.FSNamesystem (FSNamesystem.java:registerMBean(4469)) - Registered FSNamesystemStatusMBean [junit] 2010-11-23 07:19:15,814 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,814 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,815 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,816 INFO common.Storage (FSImage.java:loadFSImage(1175)) - Number of files = 1 [junit] 2010-11-23 07:19:15,816 INFO common.Storage (FSImage.java:loadFilesUnderConstruction(1755)) - Number of files under construction = 0 [junit] 2010-11-23 07:19:15,817 INFO common.Storage (FSImage.java:loadFSImage(1289)) - Image file of size 113 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,817 INFO common.Storage (FSEditLogLoader.java:loadFSEdits(61)) - Edits file of size 4 edits # 0 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,818 INFO namenode.NameCache (NameCache.java:initialized(143)) - initialized with 0 entries 0 lookups [junit] 2010-11-23 07:19:15,818 INFO namenode.FSNamesystem (FSNamesystem.java:initialize(309)) - Finished loading FSImage in 15 msecs [junit] 2010-11-23 07:19:15,819 INFO util.HostsFileReader (HostsFileReader.java:refresh(85)) - Refreshing hosts (include/exclude) list [junit] 2010-11-23 07:19:15,819 DEBUG namenode.TestNNLeaseRecovery (TestNNLeaseRecovery.java:__CLR3_0_2f0vsivvry(335)) - Running __CLR3_0_2f0vsivvry [junit] 2010-11-23 07:19:15,821 INFO namenode.FSEditLog (FSEditLog.java:printStatistics(631)) - Number of transactions: 0 Total time for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of syncs: 0 SyncTimes(ms): 0 [junit] 2010-11-23 07:19:15,821 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 4 [junit] 2010-11-23 07:19:15,822 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049092 written 512 bytes at offset 1048580 [junit] 2010-11-23 07:19:15,829 INFO namenode.FSNamesystem (FSNamesystem.java:commitBlockSynchronization(2326)) - commitBlockSynchronization(lastblock=Mock for BlockInfoUnderConstruction, hashCode: 23342038, newgenerationstamp=2002, newlength=273487234, newtargets=[null], closeFile=true, deleteBlock=false) [junit] 2010-11-23 07:19:15,847 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Cannot initialize JVM Metrics with processName=NameNode, sessionId=null - already initialized [junit] 2010-11-23 07:19:15,848 INFO metrics.NameNodeMetrics (NameNodeMetrics.java:(113)) - Initializing NameNodeMeterics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,849 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,849 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,850 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,850 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,851 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,851 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,851 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,852 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,852 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,852 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,853 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,860 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,860 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,861 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,861 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,861 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,865 INFO common.Storage (FSImage.java:saveFSImage(1412)) - Image file of size 113 saved in 0 seconds. [junit] 2010-11-23 07:19:15,909 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 0 [junit] 2010-11-23 07:19:15,909 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049088 written 512 bytes at offset 1048576 [junit] 2010-11-23 07:19:15,914 INFO common.Storage (FSImage.java:format(1639)) - Storage directory has been successfully formatted. [junit] 2010-11-23 07:19:15,915 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(173)) - defaultReplication = 3 [junit] 2010-11-23 07:19:15,916 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(174)) - maxReplication = 512 [junit] 2010-11-23 07:19:15,916 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(175)) - minReplication = 1 [junit] 2010-11-23 07:19:15,916 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(176)) - maxReplicationStreams = 2 [junit] 2010-11-23 07:19:15,917 INFO namenode.FSNamesystem (BlockManager.java:setConfigurationParameters(177)) - shouldCheckForEnoughRacks = false [junit] 2010-11-23 07:19:15,917 INFO util.GSet (BlocksMap.java:computeCapacity(84)) - VM type = 32-bit [junit] 2010-11-23 07:19:15,917 INFO util.GSet (BlocksMap.java:computeCapacity(85)) - 2% max memory = 9.86125 MB [junit] 2010-11-23 07:19:15,917 INFO util.GSet (BlocksMap.java:computeCapacity(86)) - capacity = 2^21 = 2097152 entries [junit] 2010-11-23 07:19:15,918 INFO util.GSet (LightWeightGSet.java:(82)) - recommended=2097152, actual=2097152 [junit] 2010-11-23 07:19:15,921 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(460)) - fsOwner=hudson [junit] 2010-11-23 07:19:15,922 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(466)) - supergroup=supergroup [junit] 2010-11-23 07:19:15,922 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(467)) - isPermissionEnabled=false [junit] 2010-11-23 07:19:15,923 INFO namenode.FSNamesystem (FSNamesystem.java:setConfigurationParameters(508)) - isBlockTokenEnabled=false blockKeyUpdateInterval=0 min(s), blockTokenLifetime=0 min(s) [junit] 2010-11-23 07:19:15,923 INFO metrics.FSNamesystemMetrics (FSNamesystemMetrics.java:(80)) - Initializing FSNamesystemMetrics using context object:org.apache.hadoop.metrics.spi.NullContext [junit] 2010-11-23 07:19:15,924 INFO namenode.FSNamesystem (FSNamesystem.java:registerMBean(4469)) - Registered FSNamesystemStatusMBean [junit] 2010-11-23 07:19:15,926 INFO namenode.NameNode (FSDirectory.java:(125)) - Caching file names occuring more than 10 times [junit] 2010-11-23 07:19:15,926 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,927 WARN common.Util (Util.java:stringAsURI(63)) - Path should be specified as a URI in configuration files. Please update hdfs configuration. [junit] 2010-11-23 07:19:15,928 INFO common.Storage (FSImage.java:loadFSImage(1175)) - Number of files = 1 [junit] 2010-11-23 07:19:15,928 INFO common.Storage (FSImage.java:loadFilesUnderConstruction(1755)) - Number of files under construction = 0 [junit] 2010-11-23 07:19:15,929 INFO common.Storage (FSImage.java:loadFSImage(1289)) - Image file of size 113 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,929 INFO common.Storage (FSEditLogLoader.java:loadFSEdits(61)) - Edits file of size 4 edits # 0 loaded in 0 seconds. [junit] 2010-11-23 07:19:15,930 INFO namenode.NameCache (NameCache.java:initialized(143)) - initialized with 0 entries 0 lookups [junit] 2010-11-23 07:19:15,930 INFO namenode.FSNamesystem (FSNamesystem.java:initialize(309)) - Finished loading FSImage in 15 msecs [junit] 2010-11-23 07:19:15,931 INFO util.HostsFileReader (HostsFileReader.java:refresh(85)) - Refreshing hosts (include/exclude) list [junit] 2010-11-23 07:19:15,931 DEBUG namenode.TestNNLeaseRecovery (TestNNLeaseRecovery.java:__CLR3_0_2q30srsvse(363)) - Running __CLR3_0_2q30srsvse [junit] 2010-11-23 07:19:15,933 INFO namenode.FSEditLog (FSEditLog.java:printStatistics(631)) - Number of transactions: 0 Total time for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of syncs: 0 SyncTimes(ms): 0 [junit] 2010-11-23 07:19:15,933 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(173)) - Preallocating Edit log, current size 4 [junit] 2010-11-23 07:19:15,934 DEBUG namenode.FSNamesystem (EditLogFileOutputStream.java:preallocate(180)) - Edit log size is now 1049092 written 512 bytes at offset 1048580 [junit] 2010-11-23 07:19:15,940 INFO namenode.FSNamesystem (FSNamesystem.java:commitBlockSynchronization(2326)) - commitBlockSynchronization(lastblock=Mock for BlockInfoUnderConstruction, hashCode: 25862088, newgenerationstamp=2002, newlength=273487234, newtargets=[null], closeFile=true, deleteBlock=false) [junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 2.524 sec checkfailure: [touch] Creating BUILD FAILED :675: The following error occurred while executing this line: :638: The following error occurred while executing this line: :706: Tests failed! Total time: 55 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