[HDDS-8027] Intermittent timeout shutting down datanodes

• Type: Sub-task
• Status: Resolved
• Priority: Major
• Resolution: Fixed
• Fix Version/s: 1.4.0
• Component/s: test
• Target Version/s: 1.4.0
• Assignee: Unassigned
• Reporter: Attila Doroszlai
• Created: 24/Feb/23 16:16
• Updated: 20/Jun/23 08:59
• Resolved: 20/Jun/23 08:59

Description

https://github.com/adoroszlai/ozone-build-results/blob/master/2022/12/19/19178/it-client/2022-12-19T17-38-15_060-jvmRun1.dump

"5192963b-e9c7-4ead-ad8b-7e1afcfc94a5-impl-thread3" 
   java.lang.Thread.State: WAITING
        at java.lang.Object.wait(Native Method)
        at java.util.concurrent.ForkJoinTask.externalAwaitDone(ForkJoinTask.java:334)
        at java.util.concurrent.ForkJoinTask.doInvoke(ForkJoinTask.java:405)
        at java.util.concurrent.ForkJoinTask.invoke(ForkJoinTask.java:734)
        ...
        at org.apache.ratis.util.PeerProxyMap.close(PeerProxyMap.java:168)
        at org.apache.ratis.client.impl.RaftClientRpcWithProxy.close(RaftClientRpcWithProxy.java:53)
        at org.apache.ratis.client.impl.RaftClientImpl.close(RaftClientImpl.java:407)
        ...
        at org.apache.ratis.server.impl.RaftServerImpl.close(RaftServerImpl.java:457)
        ...
        at org.apache.ratis.util.ConcurrentUtils.accept(ConcurrentUtils.java:173)
        at org.apache.ratis.util.ConcurrentUtils.lambda$null$3(ConcurrentUtils.java:165)
        at org.apache.ratis.util.ConcurrentUtils$$Lambda$515/869880469.run(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:750)

"ForkJoinPool.commonPool-worker-0" 
   java.lang.Thread.State: TIMED_WAITING
        ...
        at java.util.concurrent.ThreadPoolExecutor.awaitTermination(ThreadPoolExecutor.java:1475)
        at org.apache.ratis.util.ConcurrentUtils.shutdownAndWait(ConcurrentUtils.java:128)
        at org.apache.ratis.util.ConcurrentUtils.shutdownAndWait(ConcurrentUtils.java:120)
        at org.apache.ratis.server.impl.RaftServerProxy.lambda$close$6(RaftServerProxy.java:425)
        ...
        at org.apache.ratis.server.impl.RaftServerProxy.close(RaftServerProxy.java:408)
        at org.apache.hadoop.ozone.container.common.transport.server.ratis.XceiverServerRatis.stop(XceiverServerRatis.java:554)
        at org.apache.hadoop.ozone.container.ozoneimpl.OzoneContainer.stop(OzoneContainer.java:420)
        at org.apache.hadoop.ozone.container.common.statemachine.DatanodeStateMachine.close(DatanodeStateMachine.java:418)
        at org.apache.hadoop.ozone.container.common.statemachine.DatanodeStateMachine.stopDaemon(DatanodeStateMachine.java:596)
        at org.apache.hadoop.ozone.HddsDatanodeService.stop(HddsDatanodeService.java:576)
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.stopDatanode(MiniOzoneClusterImpl.java:531)
        ...
        at java.util.concurrent.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1056)
        at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1692)
        at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:175)

"Listener at 127.0.0.1/39097" 
   java.lang.Thread.State: WAITING
        at java.lang.Object.wait(Native Method)
        at java.util.concurrent.ForkJoinTask.externalAwaitDone(ForkJoinTask.java:334)
        at java.util.concurrent.ForkJoinTask.doInvoke(ForkJoinTask.java:405)
        at java.util.concurrent.ForkJoinTask.invoke(ForkJoinTask.java:734)
        ...
        at org.apache.ratis.util.PeerProxyMap.close(PeerProxyMap.java:168)
        at org.apache.ratis.server.RaftServerRpcWithProxy.closeImpl(RaftServerRpcWithProxy.java:85)
        at org.apache.ratis.grpc.server.GrpcService.closeImpl(GrpcService.java:273)
        ...
        at org.apache.ratis.server.RaftServerRpcWithProxy.close(RaftServerRpcWithProxy.java:80)
        ...
        at org.apache.ratis.server.impl.RaftServerProxy.close(RaftServerProxy.java:408)
        at org.apache.hadoop.ozone.container.common.transport.server.ratis.XceiverServerRatis.stop(XceiverServerRatis.java:554)
        at org.apache.hadoop.ozone.container.ozoneimpl.OzoneContainer.stop(OzoneContainer.java:420)
        at org.apache.hadoop.ozone.container.common.statemachine.DatanodeStateMachine.close(DatanodeStateMachine.java:418)
        at org.apache.hadoop.ozone.container.common.statemachine.DatanodeStateMachine.stopDaemon(DatanodeStateMachine.java:596)
        at org.apache.hadoop.ozone.HddsDatanodeService.stop(HddsDatanodeService.java:576)
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.stopDatanode(MiniOzoneClusterImpl.java:531)
        ...
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.stopDatanodes(MiniOzoneClusterImpl.java:525)
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.stop(MiniOzoneClusterImpl.java:466)
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.shutdown(MiniOzoneClusterImpl.java:453)
        at org.apache.hadoop.ozone.client.rpc.TestBlockDataStreamOutput.shutdown(TestBlockDataStreamOutput.java:131)

https://github.com/adoroszlai/ozone-build-results/blob/master/2022/11/07/18301/it-flaky/2022-11-07T12-56-16_806-jvmRun1.dump

"ForkJoinPool.commonPool-worker-0" 
   java.lang.Thread.State: WAITING
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:502)
        at org.apache.ratis.thirdparty.io.grpc.internal.ServerImpl.awaitTermination(ServerImpl.java:320)
        at org.apache.ratis.grpc.server.GrpcService.closeImpl(GrpcService.java:283)
        at org.apache.ratis.server.RaftServerRpcWithProxy$$Lambda$1408/943138690.run(Unknown Source)
        at org.apache.ratis.util.LifeCycle.lambda$checkStateAndClose$4(LifeCycle.java:299)
        at org.apache.ratis.server.RaftServerRpcWithProxy.close(RaftServerRpcWithProxy.java:80)
        ...
        at org.apache.hadoop.ozone.container.common.transport.server.ratis.XceiverServerRatis.stop(XceiverServerRatis.java:513)
        at org.apache.hadoop.ozone.container.ozoneimpl.OzoneContainer.stop(OzoneContainer.java:403)
        at org.apache.hadoop.ozone.container.common.statemachine.DatanodeStateMachine.close(DatanodeStateMachine.java:414)
        at org.apache.hadoop.ozone.container.common.statemachine.DatanodeStateMachine.stopDaemon(DatanodeStateMachine.java:592)
        at org.apache.hadoop.ozone.HddsDatanodeService.stop(HddsDatanodeService.java:571)
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.stopDatanode(MiniOzoneClusterImpl.java:530)
        ...
        at java.util.concurrent.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1058)
        at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1692)
        at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:175)

"Listener at 127.0.0.1/44333" 
   java.lang.Thread.State: WAITING
        at java.lang.Object.wait(Native Method)
        at java.util.concurrent.ForkJoinTask.externalAwaitDone(ForkJoinTask.java:334)
        at java.util.concurrent.ForkJoinTask.doInvoke(ForkJoinTask.java:405)
        at java.util.concurrent.ForkJoinTask.invoke(ForkJoinTask.java:734)
        ...
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.stopDatanodes(MiniOzoneClusterImpl.java:524)
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.stop(MiniOzoneClusterImpl.java:465)
        at org.apache.hadoop.ozone.MiniOzoneClusterImpl.shutdown(MiniOzoneClusterImpl.java:452)
        at org.apache.hadoop.ozone.client.rpc.TestContainerStateMachineFailures.shutdown(TestContainerStateMachineFailures.java:181)

Issue Links

• is fixed by: HDDS-8382 Upgrade Ratis to 2.5.1
• is related to: RATIS-1792 Replace parallelStream usage in PeerProxyMap