[
https://issues.apache.org/jira/browse/HDFS-17224?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17774890#comment-17774890
]
Steve Loughran commented on HDFS-17224:
---------------------------------------
{code}
java.lang.AssertionError: expected null, but
was:<javax.management.openmbean.CompositeDataSupport(compositeType=javax.management.openmbean.CompositeType(name=org.apache.hadoop.hdfs.protocol.RollingUpgradeInfo$Bean,items=((itemName=blockPoolId,itemType=javax.management.openmbean.SimpleType(name=java.lang.String)),(itemName=createdRollbackImages,itemType=javax.management.openmbean.SimpleType(name=java.lang.Boolean)),(itemName=finalizeTime,itemType=javax.management.openmbean.SimpleType(name=java.lang.Long)),(itemName=startTime,itemType=javax.management.openmbean.SimpleType(name=java.lang.Long)))),contents={blockPoolId=BP-1679863569-172.17.0.2-1696910973814,
createdRollbackImages=true, finalizeTime=0, startTime=1696910977372})>
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotNull(Assert.java:756)
at org.junit.Assert.assertNull(Assert.java:738)
at org.junit.Assert.assertNull(Assert.java:748)
at
org.apache.hadoop.hdfs.TestRollingUpgrade.checkMxBeanIsNull(TestRollingUpgrade.java:326)
at
org.apache.hadoop.hdfs.TestRollingUpgrade.testDFSAdminRollingUpgradeCommands(TestRollingUpgrade.java:134)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at
org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at
org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at
org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365)
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273)
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238)
at
org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159)
at
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
at
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
at
org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
at
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
Standard Output
2023-10-10 04:09:39,478 [main] INFO hdfs.MiniDFSCluster
(MiniDFSCluster.java:<init>(566)) - starting cluster: numNameNodes=1,
numDataNodes=0
2023-10-10 04:09:39,482 [main] INFO namenode.NameNode
(NameNode.java:format(1377)) - Formatting using clusterid: testClusterID
2023-10-10 04:09:39,482 [main] INFO namenode.FSEditLog
(FSEditLog.java:newInstance(238)) - Edit logging is async:true
2023-10-10 04:09:39,490 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:<init>(869)) - KeyProvider: null
2023-10-10 04:09:39,490 [main] INFO namenode.FSNamesystem
(FSNamesystemLock.java:<init>(142)) - fsLock is fair: true
2023-10-10 04:09:39,490 [main] INFO namenode.FSNamesystem
(FSNamesystemLock.java:<init>(160)) - Detailed lock hold time metrics enabled:
false
2023-10-10 04:09:39,490 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:<init>(904)) - fsOwner = jenkins (auth:SIMPLE)
2023-10-10 04:09:39,490 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:<init>(905)) - supergroup = supergroup
2023-10-10 04:09:39,491 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:<init>(906)) - isPermissionEnabled = true
2023-10-10 04:09:39,491 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:<init>(907)) - isStoragePolicyEnabled = true
2023-10-10 04:09:39,491 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:<init>(918)) - HA Enabled: false
2023-10-10 04:09:39,491 [main] INFO common.Util
(Util.java:isDiskStatsEnabled(428)) -
dfs.datanode.fileio.profiling.sampling.percentage set to 0. Disabling file IO
profiling
2023-10-10 04:09:39,491 [main] INFO blockmanagement.DatanodeManager
(DatanodeManager.java:stopSlowPeerCollector(412)) - Slow peers collection
thread shutdown
2023-10-10 04:09:39,492 [main] INFO blockmanagement.DatanodeManager
(DatanodeManager.java:setBlockInvalidateLimit(2170)) -
dfs.block.invalidate.limit : configured=1000, counted=60, effected=1000
2023-10-10 04:09:39,492 [main] INFO blockmanagement.DatanodeManager
(DatanodeManager.java:<init>(320)) -
dfs.namenode.datanode.registration.ip-hostname-check=true
2023-10-10 04:09:39,492 [main] INFO blockmanagement.BlockManager
(InvalidateBlocks.java:printBlockDeletionTime(77)) -
dfs.namenode.startup.delay.block.deletion.sec is set to 000:00:00:00.000
2023-10-10 04:09:39,492 [main] INFO blockmanagement.BlockManager
(InvalidateBlocks.java:printBlockDeletionTime(83)) - The block deletion will
start around 2023 Oct 10 04:09:39
2023-10-10 04:09:39,492 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(408)) - Computing capacity for map
BlocksMap
2023-10-10 04:09:39,492 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(409)) - VM type = 64-bit
2023-10-10 04:09:39,493 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(410)) - 2.0% max memory 1.8 GB = 36.4 MB
2023-10-10 04:09:39,493 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(415)) - capacity = 2^22 = 4194304
entries
2023-10-10 04:09:39,496 [main] INFO blockmanagement.BlockManager
(BlockManager.java:createSPSManager(5662)) - Storage policy satisfier is
disabled
2023-10-10 04:09:39,496 [main] INFO blockmanagement.BlockManager
(BlockManager.java:createBlockTokenSecretManager(676)) -
dfs.block.access.token.enable = false
2023-10-10 04:09:39,496 [main] INFO blockmanagement.BlockManagerSafeMode
(BlockManagerSafeMode.java:<init>(656)) - Using 1000 as SafeModeMonitor Interval
2023-10-10 04:09:39,496 [main] INFO blockmanagement.BlockManagerSafeMode
(BlockManagerSafeMode.java:<init>(161)) - dfs.namenode.safemode.threshold-pct =
0.999
2023-10-10 04:09:39,496 [main] INFO blockmanagement.BlockManagerSafeMode
(BlockManagerSafeMode.java:<init>(162)) - dfs.namenode.safemode.min.datanodes = 0
2023-10-10 04:09:39,496 [main] INFO blockmanagement.BlockManagerSafeMode
(BlockManagerSafeMode.java:<init>(164)) - dfs.namenode.safemode.extension = 0
2023-10-10 04:09:39,497 [main] INFO blockmanagement.BlockManager
(BlockManager.java:printInitialConfigs(662)) - defaultReplication = 0
2023-10-10 04:09:39,497 [main] INFO blockmanagement.BlockManager
(BlockManager.java:printInitialConfigs(663)) - maxReplication = 512
2023-10-10 04:09:39,497 [main] INFO blockmanagement.BlockManager
(BlockManager.java:printInitialConfigs(664)) - minReplication = 1
2023-10-10 04:09:39,497 [main] INFO blockmanagement.BlockManager
(BlockManager.java:printInitialConfigs(665)) - maxReplicationStreams = 2
2023-10-10 04:09:39,497 [main] INFO blockmanagement.BlockManager
(BlockManager.java:printInitialConfigs(666)) - redundancyRecheckInterval =
3000ms
2023-10-10 04:09:39,497 [main] INFO blockmanagement.BlockManager
(BlockManager.java:printInitialConfigs(667)) - encryptDataTransfer =
false
2023-10-10 04:09:39,497 [main] INFO blockmanagement.BlockManager
(BlockManager.java:printInitialConfigs(668)) - maxNumBlocksToLog = 1000
2023-10-10 04:09:39,498 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(408)) - Computing capacity for map
INodeMap
2023-10-10 04:09:39,498 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(409)) - VM type = 64-bit
2023-10-10 04:09:39,498 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(410)) - 1.0% max memory 1.8 GB = 18.2 MB
2023-10-10 04:09:39,498 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(415)) - capacity = 2^21 = 2097152
entries
2023-10-10 04:09:39,500 [main] INFO namenode.FSDirectory
(FSDirectory.java:<init>(339)) - ACLs enabled? true
2023-10-10 04:09:39,500 [main] INFO namenode.FSDirectory
(FSDirectory.java:<init>(343)) - POSIX ACL inheritance enabled? true
2023-10-10 04:09:39,500 [main] INFO namenode.FSDirectory
(FSDirectory.java:<init>(347)) - XAttrs enabled? true
2023-10-10 04:09:39,500 [main] INFO namenode.NameNode
(FSDirectory.java:<init>(414)) - Caching file names occurring more than 10 times
2023-10-10 04:09:39,500 [main] INFO snapshot.SnapshotManager
(SnapshotManager.java:<init>(163)) - Loaded config captureOpenFiles: false,
skipCaptureAccessTimeOnlyChange: false, snapshotDiffAllowSnapRootDescendant:
true, maxSnapshotFSLimit: 65536, maxSnapshotLimit: 65536
2023-10-10 04:09:39,501 [main] INFO snapshot.SnapshotManager
(SnapshotManager.java:<init>(176)) - dfs.namenode.snapshot.deletion.ordered =
false
2023-10-10 04:09:39,501 [main] INFO snapshot.SnapshotManager
(DirectoryDiffListFactory.java:init(43)) - SkipList is disabled
2023-10-10 04:09:39,501 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(408)) - Computing capacity for map
cachedBlocks
2023-10-10 04:09:39,501 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(409)) - VM type = 64-bit
2023-10-10 04:09:39,501 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(410)) - 0.25% max memory 1.8 GB = 4.6 MB
2023-10-10 04:09:39,501 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(415)) - capacity = 2^19 = 524288
entries
2023-10-10 04:09:39,502 [main] INFO metrics.TopMetrics
(TopMetrics.java:logConf(76)) - NNTop conf: dfs.namenode.top.window.num.buckets
= 10
2023-10-10 04:09:39,502 [main] INFO metrics.TopMetrics
(TopMetrics.java:logConf(78)) - NNTop conf: dfs.namenode.top.num.users = 10
2023-10-10 04:09:39,502 [main] INFO metrics.TopMetrics
(TopMetrics.java:logConf(80)) - NNTop conf: dfs.namenode.top.windows.minutes =
1,5,25
2023-10-10 04:09:39,502 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:initRetryCache(1158)) - Retry cache on namenode is enabled
2023-10-10 04:09:39,503 [main] INFO namenode.FSNamesystem
(FSNamesystem.java:initRetryCache(1166)) - Retry cache will use 0.03 of total
heap and retry cache entry expiry time is 600000 millis
2023-10-10 04:09:39,503 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(408)) - Computing capacity for map
NameNodeRetryCache
2023-10-10 04:09:39,503 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(409)) - VM type = 64-bit
2023-10-10 04:09:39,503 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(410)) - 0.029999999329447746% max memory
1.8 GB = 559.3 KB
2023-10-10 04:09:39,503 [main] INFO util.GSet
(LightWeightGSet.java:computeCapacity(415)) - capacity = 2^16 = 65536
entries
2023-10-10 04:09:39,504 [main] INFO namenode.FSImage
(FSImage.java:format(186)) - Allocated new BlockPoolId:
BP-264471917-172.17.0.2-1696910979504
2023-10-10 04:09:39,573 [main] INFO common.Storage
(NNStorage.java:format(595)) - Storage directory
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-1
has been successfully formatted.
2023-10-10 04:09:39,641 [main] INFO common.Storage
(NNStorage.java:format(595)) - Storage directory
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-2
has been successfully formatted.
2023-10-10 04:09:39,651 [FSImageSaver for
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-1
of type IMAGE_AND_EDITS] INFO namenode.FSImageFormatProtobuf
(FSImageFormatProtobuf.java:save(732)) - Saving image file
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-1/current/fsimage.ckpt_0000000000000000000
using no compression
2023-10-10 04:09:39,651 [FSImageSaver for
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-2
of type IMAGE_AND_EDITS] INFO namenode.FSImageFormatProtobuf
(FSImageFormatProtobuf.java:save(732)) - Saving image file
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-2/current/fsimage.ckpt_0000000000000000000
using no compression
2023-10-10 04:09:39,657 [FSImageSaver for
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-1
of type IMAGE_AND_EDITS] INFO namenode.FSImageFormatProtobuf
(FSImageFormatProtobuf.java:save(736)) - Image file
/home/jenkins/jenkins-home/workspace/hadoop-multibranch_PR-4996/ubuntu-focal/src/hadoop-hdfs-project/hadoop-hdfs/target/test/data/1/dfs/name-0-1/current/fsimage.ckpt_0000000000000000000
of size 420 bytes saved in 0 seconds .
2023-10-10 04:09:39,657 [FSImageSaver for
/home/jenkins/jenkins-home/workspace/hadoop-
{code}
> TestRollingUpgrade.testDFSAdminRollingUpgradeCommands failing
> -------------------------------------------------------------
>
> Key: HDFS-17224
> URL: https://issues.apache.org/jira/browse/HDFS-17224
> Project: Hadoop HDFS
> Issue Type: Bug
> Components: dfsadmin, test
> Affects Versions: 3.4.0
> Reporter: Steve Loughran
> Priority: Major
>
> TestRollingUpgrade.testDFSAdminRollingUpgradeCommands failing because the
> static mbean isn't null. This is inevitably related to the fact that in test
> runs, the jvm is reused and so the mbean may be present from a previous test
> -maybe one which didn't clean up.
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
