See <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/466/changes>
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:<init>(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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name/current/edits> 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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name/current/edits> 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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name/current/edits> 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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name/current/edits> 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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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:<init>(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:<init>(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:<init>(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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/data/dfs/name/current/edits> 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 <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build/test/testsfailed> BUILD FAILED <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build.xml>:675: The following error occurred while executing this line: <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build.xml>:638: The following error occurred while executing this line: <https://hudson.apache.org/hudson/job/Hadoop-Hdfs-trunk-Commit/ws/trunk/build.xml>: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