[
https://issues.apache.org/jira/browse/HDDS-10739?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17840528#comment-17840528
]
Hemant Kumar edited comment on HDDS-10739 at 4/24/24 5:36 PM:
--------------------------------------------------------------
[~jyosin] I don't think OM is down due to *InterruptedException* 'Unable to
process metadata snapshot request'. It is the effect of the OM shutting down.
It is just an error that gets logged
[here|https://github.com/apache/ozone/blob/master/hadoop-hdds/framework/src/main/java/org/apache/hadoop/hdds/utils/DBCheckpointServlet.java#L238].
OM shutting down causing InterruptedException to the thread and hence it gets
logged.
{code:java}
...
2024-04-22 13:16:20,573 WARN [IPC Server handler 48 on
9862]-org.apache.hadoop.ipc.Server: IPC Server handler 48 on 9862, call Call#4
Retry#3 org.apache.hadoop.ozone.om.protocol.OzoneManagerProtocol.submitRequest
from 10.17.207.21:56466: output error
2024-04-22 13:16:20,573 ERROR
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.impl.StateMachineUpdater:
om131@group-2BC026ED99AC-StateMachineUpdater caught a Throwable.
org.apache.ratis.server.raftlog.RaftLogIOException:
java.lang.IllegalStateException: retry cache entry should be pending:
4@client-5DDA567B3E84:done
at
org.apache.ratis.server.impl.RaftServerImpl.applyLogToStateMachine(RaftServerImpl.java:1780)
at
org.apache.ratis.server.impl.StateMachineUpdater.applyLog(StateMachineUpdater.java:242)
at
org.apache.ratis.server.impl.StateMachineUpdater.run(StateMachineUpdater.java:184)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.IllegalStateException: retry cache entry should be
pending: 4@client-5DDA567B3E84:done
at org.apache.ratis.util.Preconditions.assertTrue(Preconditions.java:60)
at
org.apache.ratis.server.impl.RaftServerImpl.replyPendingRequest(RaftServerImpl.java:1727)
at
org.apache.ratis.server.impl.RaftServerImpl.applyLogToStateMachine(RaftServerImpl.java:1778)
... 3 more
...
{code}
{code}
...
2024-04-22 13:16:20,577 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.RaftServer$Division:
om131@group-2BC026ED99AC: shutdown
...
{code}
{code}
...
2024-04-22 13:16:20,579 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.util.JmxRegister:
Successfully un-registered JMX Bean with object name
Ratis:service=RaftServer,group=group-2BC026ED99AC,id=om131
2024-04-22 13:16:20,579 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.impl.RoleInfo:
om131: shutdown om131@group-2BC026ED99AC-LeaderStateImpl
2024-04-22 13:16:20,581 INFO [IPC Server handler 1 on
9862]-org.apache.hadoop.ipc.Server: IPC Server handler 1 on 9862, call Call#3
Retry#9 org.apache.hadoop.ozone.om.protocol.OzoneManagerProtocol.submitRequest
from 10.17.207.21:37118
java.util.concurrent.ExecutionException:
org.apache.ratis.protocol.exceptions.ServerNotReadyException:
om131@group-2BC026ED99AC is not in [RUNNING]: current state is CLOSING
at
java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:357)
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1908)
at
org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer.submitRequestToRatis(OzoneManagerRatisServer.java:296)
at
org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer.submitRequest(OzoneManagerRatisServer.java:258)
at
org.apache.hadoop.ozone.protocolPB.OzoneManagerProtocolServerSideTranslatorPB.submitRequestToRatis(OzoneManagerProtocolServerSideTranslatorPB.java:211)
at
org.apache.hadoop.ozone.protocolPB.OzoneManagerProtocolServerSideTranslatorPB.processRequest(OzoneManagerProtocolServerSideTranslatorPB.java:196)
at
org.apache.hadoop.hdds.server.OzoneProtocolMessageDispatcher.processRequest(OzoneProtocolMessageDispatcher.java:87)
at
org.apache.hadoop.ozone.protocolPB.OzoneManagerProtocolServerSideTranslatorPB.submitRequest(OzoneManagerProtocolServerSideTranslatorPB.java:143)
at
org.apache.hadoop.ozone.protocol.proto.OzoneManagerProtocolProtos$OzoneManagerService$2.callBlockingMethod(OzoneManagerProtocolProtos.java)
at
org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:533)
...
{code}
{code}
....
2024-04-22 13:16:20,598 WARN
[om131@group-2BC026ED99AC->om132-GrpcLogAppender-LogAppenderDaemon]-org.apache.ratis.grpc.server.GrpcLogAppender:
om131@group-2BC026ED99AC->om132-GrpcLogAppender: Wait interrupted by
java.lang.InterruptedException
2024-04-22 13:16:20,598 WARN
[om131@group-2BC026ED99AC->om133-GrpcLogAppender-LogAppenderDaemon]-org.apache.ratis.grpc.server.GrpcLogAppender:
om131@group-2BC026ED99AC->om133-GrpcLogAppender: Wait interrupted by
java.lang.InterruptedException
2024-04-22 13:16:20,599 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.impl.PendingRequests:
om131@group-2BC026ED99AC-PendingRequests: sendNotLeaderResponses
...
{code}
{code}
...
2024-04-22 13:16:20,626 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.ratis.OzoneManagerStateMachine:
Stopping OzoneManagerStateMachine:om131:group-2BC026ED99AC. Shutdown also
OzoneManager org.apache.hadoop.ozone.om.OzoneManager@2d95879c.
...
{code}
{code}
...
2024-04-22 13:16:20,627 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.OzoneManager:
om131[vc0113.halxg.cloudera.com:9862]: Stopping Ozone Manager
2024-04-22 13:16:20,627 INFO [IPC Server handler 69 on
9862]-org.apache.hadoop.ipc.Server: IPC Server handler 69 on 9862 caught an
exception
java.nio.channels.ClosedChannelException
at
sun.nio.ch.SocketChannelImpl.ensureWriteOpen(SocketChannelImpl.java:270)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:461)
at org.apache.hadoop.ipc.Server.channelWrite(Server.java:3487)
at org.apache.hadoop.ipc.Server.access$1800(Server.java:143)
at
org.apache.hadoop.ipc.Server$Responder.processResponse(Server.java:1615)
at org.apache.hadoop.ipc.Server$Responder.doRespond(Server.java:1685)
at
org.apache.hadoop.ipc.Server$Connection.sendResponse(Server.java:2772)
at org.apache.hadoop.ipc.Server$Connection.access$300(Server.java:1757)
at org.apache.hadoop.ipc.Server$RpcCall.doResponse(Server.java:1076)
at org.apache.hadoop.ipc.Server$Call.doResponse(Server.java:863)
at org.apache.hadoop.ipc.Server$Call.sendResponse(Server.java:849)
at org.apache.hadoop.ipc.Server$RpcCall.run(Server.java:1011)
at org.apache.hadoop.ipc.Server$RpcCall.run(Server.java:922)
at java.security.AccessController.doPrivileged(Native Method)
at javax.security.auth.Subject.doAs(Subject.java:422)
at
org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1899)
at org.apache.hadoop.ipc.Server$Handler.run(Server.java:2899)
2024-04-22 13:16:20,628 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ipc.Server:
Stopping server on 9862
2024-04-22 13:16:20,641 INFO [IPC Server listener on
9862]-org.apache.hadoop.ipc.Server: Stopping IPC Server listener on 9862
2024-04-22 13:16:20,641 INFO [IPC Server
Responder]-org.apache.hadoop.ipc.Server: Stopping IPC Server Responder
2024-04-22 13:16:24,686 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.GrpcOzoneManagerServer:
Server GrpcOzoneManagerServer is shutdown
2024-04-22 13:16:24,686 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer:
Stopping org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer@76af3cdf at
port 9872
2024-04-22 13:16:24,689 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.RaftServer:
om131: close
2024-04-22 13:16:24,691 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.grpc.server.GrpcService:
om131: shutdown server GrpcServerProtocolService now
2024-04-22 13:16:24,707 INFO
[Thread-669097]-org.apache.ratis.grpc.server.GrpcServerProtocolClient: om133
Close channels
2024-04-22 13:16:24,707 INFO
[Thread-669096]-org.apache.ratis.grpc.server.GrpcServerProtocolClient: om132
Close channels
2024-04-22 13:16:24,719 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.grpc.server.GrpcService:
om131: shutdown server GrpcServerProtocolService successfully
2024-04-22 13:16:24,720 INFO
[JvmPauseMonitor0]-org.apache.ratis.util.JvmPauseMonitor:
JvmPauseMonitor-om131: Stopped
2024-04-22 13:16:24,720 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.hdds.utils.BackgroundService:
Shutting down service KeyDeletingService
2024-04-22 13:16:46,960 WARN
[SnapshotDeletingService#0]-org.apache.hadoop.hdds.utils.BackgroundService:
Background task execution failed
java.lang.NullPointerException
at
org.apache.hadoop.ozone.om.OzoneManager.isLeaderReady(OzoneManager.java:4028)
at
org.apache.hadoop.ozone.om.service.SnapshotDeletingService.shouldRun(SnapshotDeletingService.java:671)
at
org.apache.hadoop.ozone.om.service.SnapshotDeletingService.access$0(SnapshotDeletingService.java:670)
at
org.apache.hadoop.ozone.om.service.SnapshotDeletingService$SnapshotDeletingTask.call(SnapshotDeletingService.java:141)
at
org.apache.hadoop.hdds.utils.BackgroundService$PeriodicalTask.lambda$run$0(BackgroundService.java:121)
at
java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1640)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
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:748)
...
{code}
Finally "Unable to process metadata snapshot request" after shutting down all
the background services.
{code}
...
2024-04-22 13:18:24,723 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.hdds.utils.BackgroundService:
Shutting down service SnapshotDeletingService
2024-04-22 13:18:24,723 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.OzoneManager:
Stopping secret key client.
2024-04-22 13:18:24,724 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.OzoneManager:
Stopping OM delegation token secret manager.
2024-04-22 13:18:24,726 INFO
[Thread[Thread-17752,5,main]]-org.apache.hadoop.ozone.security.OzoneDelegationTokenSecretManager:
ExpiredTokenRemover was interrupted.
java.lang.InterruptedException: sleep interrupted
at java.lang.Thread.sleep(Native Method)
at
org.apache.hadoop.ozone.security.OzoneDelegationTokenSecretManager$ExpiredTokenRemover.run(OzoneDelegationTokenSecretManager.java:631)
at java.lang.Thread.run(Thread.java:748)
2024-04-22 13:18:24,749 WARN
[SstFilteringService#0]-org.apache.hadoop.hdds.utils.BackgroundService:
Background task execution failed
java.lang.InterruptedException
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:998)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
at java.util.concurrent.Semaphore.acquire(Semaphore.java:312)
at
org.apache.hadoop.ozone.lock.BootstrapStateHandler$Lock.lock(BootstrapStateHandler.java:31)
at
org.apache.hadoop.ozone.om.SstFilteringService$SstFilteringTask.call(SstFilteringService.java:192)
at
org.apache.hadoop.hdds.utils.BackgroundService$PeriodicalTask.lambda$run$0(BackgroundService.java:121)
at
java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1640)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
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:748)
2024-04-22 13:18:24,750 WARN
[SstFilteringService#0]-org.apache.hadoop.hdds.utils.BackgroundService:
SstFilteringService Background task execution took 9788381000748ns >
300000000000ns(timeout)
2024-04-22 13:18:25,048 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.handler.ContextHandler:
Stopped
o.e.j.w.WebAppContext@5ad0c7df{ozoneManager,/,null,STOPPED}{jar:file:/opt/cloudera/parcels/CDH-7.1.9-1.cdh7.1.9.p1.52243585/jars/ozone-manager-1.4.0.7.1.9.1-232.jar!/webapps/ozoneManager}
2024-04-22 13:18:25,069 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.AbstractConnector:
Stopped ServerConnector@3e7f090b{SSL, (ssl, http/1.1)}{0.0.0.0:9875}
2024-04-22 13:18:25,069 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.session:
node0 Stopped scavenging
2024-04-22 13:18:25,070 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.handler.ContextHandler:
Stopped
o.e.j.s.ServletContextHandler@4ad8bc3{static,/static,jar:file:/opt/cloudera/parcels/CDH-7.1.9-1.cdh7.1.9.p1.52243585/jars/ozone-manager-1.4.0.7.1.9.1-232.jar!/webapps/static,STOPPED}
2024-04-22 13:18:25,071 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.handler.ContextHandler:
Stopped
o.e.j.s.ServletContextHandler@74e87f03{logs,/logs,file:///var/log/hadoop-ozone/,STOPPED}
2024-04-22 13:18:40,074 ERROR
[qtp560715723-676269]-org.apache.hadoop.hdds.utils.DBCheckpointServlet: Unable
to process metadata snapshot request.
java.lang.InterruptedException
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:998)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
at java.util.concurrent.Semaphore.acquire(Semaphore.java:312)
at
org.apache.hadoop.ozone.lock.BootstrapStateHandler$Lock.lock(BootstrapStateHandler.java:31)
at
org.apache.hadoop.ozone.om.OMDBCheckpointServlet$Lock.lock(OMDBCheckpointServlet.java:654)
at
org.apache.hadoop.hdds.utils.DBCheckpointServlet.generateSnapshotCheckpoint(DBCheckpointServlet.java:197)
at
org.apache.hadoop.hdds.utils.DBCheckpointServlet.doGet(DBCheckpointServlet.java:303)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:687)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at
org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799)
at
org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656)
at
org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter.doFilter(StaticUserWebFilter.java:110)
at
org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
at
org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
at
org.apache.hadoop.hdds.server.http.HttpServer2$QuotingInputFilter.doFilter(HttpServer2.java:1681)
at
org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
at
org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
at
org.apache.hadoop.hdds.server.http.NoCacheFilter.doFilter(NoCacheFilter.java:48)
at
org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
at
org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:552)
at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at
org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)
at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235)
at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505)
at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355)
at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at
org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146)
at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
at org.eclipse.jetty.server.Server.handle(Server.java:516)
at
org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487)
at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479)
at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
at
org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555)
at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410)
at
org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.lang.Thread.run(Thread.java:748)
{code}
was (Author: JIRAUSER297350):
[~jyosin] I don't think OM is down due to *InterruptedException* 'Unable to
process metadata snapshot request'. It is the effect of the OM shutting down.
It is just an error that gets logged
[here|https://github.com/apache/ozone/blob/master/hadoop-hdds/framework/src/main/java/org/apache/hadoop/hdds/utils/DBCheckpointServlet.java#L238].
OM shutting down causing InterruptedException to the thread and hence it gets
logged.
{code:java}
...
2024-04-22 13:16:20,573 WARN [IPC Server handler 48 on
9862]-org.apache.hadoop.ipc.Server: IPC Server handler 48 on 9862, call Call#4
Retry#3 org.apache.hadoop.ozone.om.protocol.OzoneManagerProtocol.submitRequest
from 10.17.207.21:56466: output error
2024-04-22 13:16:20,573 ERROR
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.impl.StateMachineUpdater:
om131@group-2BC026ED99AC-StateMachineUpdater caught a Throwable.
org.apache.ratis.server.raftlog.RaftLogIOException:
java.lang.IllegalStateException: retry cache entry should be pending:
4@client-5DDA567B3E84:done
at
org.apache.ratis.server.impl.RaftServerImpl.applyLogToStateMachine(RaftServerImpl.java:1780)
at
org.apache.ratis.server.impl.StateMachineUpdater.applyLog(StateMachineUpdater.java:242)
at
org.apache.ratis.server.impl.StateMachineUpdater.run(StateMachineUpdater.java:184)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.IllegalStateException: retry cache entry should be
pending: 4@client-5DDA567B3E84:done
at org.apache.ratis.util.Preconditions.assertTrue(Preconditions.java:60)
at
org.apache.ratis.server.impl.RaftServerImpl.replyPendingRequest(RaftServerImpl.java:1727)
at
org.apache.ratis.server.impl.RaftServerImpl.applyLogToStateMachine(RaftServerImpl.java:1778)
... 3 more
...
{code}
{code}
...
2024-04-22 13:16:20,577 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.RaftServer$Division:
om131@group-2BC026ED99AC: shutdown
...
{code}
{code}
...
2024-04-22 13:16:20,579 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.util.JmxRegister:
Successfully un-registered JMX Bean with object name
Ratis:service=RaftServer,group=group-2BC026ED99AC,id=om131
2024-04-22 13:16:20,579 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.impl.RoleInfo:
om131: shutdown om131@group-2BC026ED99AC-LeaderStateImpl
2024-04-22 13:16:20,581 INFO [IPC Server handler 1 on
9862]-org.apache.hadoop.ipc.Server: IPC Server handler 1 on 9862, call Call#3
Retry#9 org.apache.hadoop.ozone.om.protocol.OzoneManagerProtocol.submitRequest
from 10.17.207.21:37118
java.util.concurrent.ExecutionException:
org.apache.ratis.protocol.exceptions.ServerNotReadyException:
om131@group-2BC026ED99AC is not in [RUNNING]: current state is CLOSING
at
java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:357)
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1908)
at
org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer.submitRequestToRatis(OzoneManagerRatisServer.java:296)
at
org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer.submitRequest(OzoneManagerRatisServer.java:258)
at
org.apache.hadoop.ozone.protocolPB.OzoneManagerProtocolServerSideTranslatorPB.submitRequestToRatis(OzoneManagerProtocolServerSideTranslatorPB.java:211)
at
org.apache.hadoop.ozone.protocolPB.OzoneManagerProtocolServerSideTranslatorPB.processRequest(OzoneManagerProtocolServerSideTranslatorPB.java:196)
at
org.apache.hadoop.hdds.server.OzoneProtocolMessageDispatcher.processRequest(OzoneProtocolMessageDispatcher.java:87)
at
org.apache.hadoop.ozone.protocolPB.OzoneManagerProtocolServerSideTranslatorPB.submitRequest(OzoneManagerProtocolServerSideTranslatorPB.java:143)
at
org.apache.hadoop.ozone.protocol.proto.OzoneManagerProtocolProtos$OzoneManagerService$2.callBlockingMethod(OzoneManagerProtocolProtos.java)
at
org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:533)
...
{code}
{code}
....
2024-04-22 13:16:20,599 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.impl.PendingRequests:
om131@group-2BC026ED99AC-PendingRequests: sendNotLeaderResponses
...
{code}
{code}
...
2024-04-22 13:16:20,626 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.ratis.OzoneManagerStateMachine:
Stopping OzoneManagerStateMachine:om131:group-2BC026ED99AC. Shutdown also
OzoneManager org.apache.hadoop.ozone.om.OzoneManager@2d95879c.
...
{code}
{code}
...
2024-04-22 13:16:20,627 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.OzoneManager:
om131[vc0113.halxg.cloudera.com:9862]: Stopping Ozone Manager
2024-04-22 13:16:20,627 INFO [IPC Server handler 69 on
9862]-org.apache.hadoop.ipc.Server: IPC Server handler 69 on 9862 caught an
exception
java.nio.channels.ClosedChannelException
at
sun.nio.ch.SocketChannelImpl.ensureWriteOpen(SocketChannelImpl.java:270)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:461)
at org.apache.hadoop.ipc.Server.channelWrite(Server.java:3487)
at org.apache.hadoop.ipc.Server.access$1800(Server.java:143)
at
org.apache.hadoop.ipc.Server$Responder.processResponse(Server.java:1615)
at org.apache.hadoop.ipc.Server$Responder.doRespond(Server.java:1685)
at
org.apache.hadoop.ipc.Server$Connection.sendResponse(Server.java:2772)
at org.apache.hadoop.ipc.Server$Connection.access$300(Server.java:1757)
at org.apache.hadoop.ipc.Server$RpcCall.doResponse(Server.java:1076)
at org.apache.hadoop.ipc.Server$Call.doResponse(Server.java:863)
at org.apache.hadoop.ipc.Server$Call.sendResponse(Server.java:849)
at org.apache.hadoop.ipc.Server$RpcCall.run(Server.java:1011)
at org.apache.hadoop.ipc.Server$RpcCall.run(Server.java:922)
at java.security.AccessController.doPrivileged(Native Method)
at javax.security.auth.Subject.doAs(Subject.java:422)
at
org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1899)
at org.apache.hadoop.ipc.Server$Handler.run(Server.java:2899)
2024-04-22 13:16:20,628 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ipc.Server:
Stopping server on 9862
2024-04-22 13:16:20,641 INFO [IPC Server listener on
9862]-org.apache.hadoop.ipc.Server: Stopping IPC Server listener on 9862
2024-04-22 13:16:20,641 INFO [IPC Server
Responder]-org.apache.hadoop.ipc.Server: Stopping IPC Server Responder
2024-04-22 13:16:24,686 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.GrpcOzoneManagerServer:
Server GrpcOzoneManagerServer is shutdown
2024-04-22 13:16:24,686 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer:
Stopping org.apache.hadoop.ozone.om.ratis.OzoneManagerRatisServer@76af3cdf at
port 9872
2024-04-22 13:16:24,689 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.server.RaftServer:
om131: close
2024-04-22 13:16:24,691 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.grpc.server.GrpcService:
om131: shutdown server GrpcServerProtocolService now
2024-04-22 13:16:24,707 INFO
[Thread-669097]-org.apache.ratis.grpc.server.GrpcServerProtocolClient: om133
Close channels
2024-04-22 13:16:24,707 INFO
[Thread-669096]-org.apache.ratis.grpc.server.GrpcServerProtocolClient: om132
Close channels
2024-04-22 13:16:24,719 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.ratis.grpc.server.GrpcService:
om131: shutdown server GrpcServerProtocolService successfully
2024-04-22 13:16:24,720 INFO
[JvmPauseMonitor0]-org.apache.ratis.util.JvmPauseMonitor:
JvmPauseMonitor-om131: Stopped
2024-04-22 13:16:24,720 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.hdds.utils.BackgroundService:
Shutting down service KeyDeletingService
2024-04-22 13:16:46,960 WARN
[SnapshotDeletingService#0]-org.apache.hadoop.hdds.utils.BackgroundService:
Background task execution failed
java.lang.NullPointerException
at
org.apache.hadoop.ozone.om.OzoneManager.isLeaderReady(OzoneManager.java:4028)
at
org.apache.hadoop.ozone.om.service.SnapshotDeletingService.shouldRun(SnapshotDeletingService.java:671)
at
org.apache.hadoop.ozone.om.service.SnapshotDeletingService.access$0(SnapshotDeletingService.java:670)
at
org.apache.hadoop.ozone.om.service.SnapshotDeletingService$SnapshotDeletingTask.call(SnapshotDeletingService.java:141)
at
org.apache.hadoop.hdds.utils.BackgroundService$PeriodicalTask.lambda$run$0(BackgroundService.java:121)
at
java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1640)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
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:748)
...
{code}
Finally "Unable to process metadata snapshot request" after shutting down all
the background services.
{code}
...
2024-04-22 13:18:24,723 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.hdds.utils.BackgroundService:
Shutting down service SnapshotDeletingService
2024-04-22 13:18:24,723 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.OzoneManager:
Stopping secret key client.
2024-04-22 13:18:24,724 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.apache.hadoop.ozone.om.OzoneManager:
Stopping OM delegation token secret manager.
2024-04-22 13:18:24,726 INFO
[Thread[Thread-17752,5,main]]-org.apache.hadoop.ozone.security.OzoneDelegationTokenSecretManager:
ExpiredTokenRemover was interrupted.
java.lang.InterruptedException: sleep interrupted
at java.lang.Thread.sleep(Native Method)
at
org.apache.hadoop.ozone.security.OzoneDelegationTokenSecretManager$ExpiredTokenRemover.run(OzoneDelegationTokenSecretManager.java:631)
at java.lang.Thread.run(Thread.java:748)
2024-04-22 13:18:24,749 WARN
[SstFilteringService#0]-org.apache.hadoop.hdds.utils.BackgroundService:
Background task execution failed
java.lang.InterruptedException
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:998)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
at java.util.concurrent.Semaphore.acquire(Semaphore.java:312)
at
org.apache.hadoop.ozone.lock.BootstrapStateHandler$Lock.lock(BootstrapStateHandler.java:31)
at
org.apache.hadoop.ozone.om.SstFilteringService$SstFilteringTask.call(SstFilteringService.java:192)
at
org.apache.hadoop.hdds.utils.BackgroundService$PeriodicalTask.lambda$run$0(BackgroundService.java:121)
at
java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1640)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
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:748)
2024-04-22 13:18:24,750 WARN
[SstFilteringService#0]-org.apache.hadoop.hdds.utils.BackgroundService:
SstFilteringService Background task execution took 9788381000748ns >
300000000000ns(timeout)
2024-04-22 13:18:25,048 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.handler.ContextHandler:
Stopped
o.e.j.w.WebAppContext@5ad0c7df{ozoneManager,/,null,STOPPED}{jar:file:/opt/cloudera/parcels/CDH-7.1.9-1.cdh7.1.9.p1.52243585/jars/ozone-manager-1.4.0.7.1.9.1-232.jar!/webapps/ozoneManager}
2024-04-22 13:18:25,069 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.AbstractConnector:
Stopped ServerConnector@3e7f090b{SSL, (ssl, http/1.1)}{0.0.0.0:9875}
2024-04-22 13:18:25,069 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.session:
node0 Stopped scavenging
2024-04-22 13:18:25,070 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.handler.ContextHandler:
Stopped
o.e.j.s.ServletContextHandler@4ad8bc3{static,/static,jar:file:/opt/cloudera/parcels/CDH-7.1.9-1.cdh7.1.9.p1.52243585/jars/ozone-manager-1.4.0.7.1.9.1-232.jar!/webapps/static,STOPPED}
2024-04-22 13:18:25,071 INFO
[om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.handler.ContextHandler:
Stopped
o.e.j.s.ServletContextHandler@74e87f03{logs,/logs,file:///var/log/hadoop-ozone/,STOPPED}
2024-04-22 13:18:40,074 ERROR
[qtp560715723-676269]-org.apache.hadoop.hdds.utils.DBCheckpointServlet: Unable
to process metadata snapshot request.
java.lang.InterruptedException
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:998)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
at java.util.concurrent.Semaphore.acquire(Semaphore.java:312)
at
org.apache.hadoop.ozone.lock.BootstrapStateHandler$Lock.lock(BootstrapStateHandler.java:31)
at
org.apache.hadoop.ozone.om.OMDBCheckpointServlet$Lock.lock(OMDBCheckpointServlet.java:654)
at
org.apache.hadoop.hdds.utils.DBCheckpointServlet.generateSnapshotCheckpoint(DBCheckpointServlet.java:197)
at
org.apache.hadoop.hdds.utils.DBCheckpointServlet.doGet(DBCheckpointServlet.java:303)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:687)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at
org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799)
at
org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656)
at
org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter.doFilter(StaticUserWebFilter.java:110)
at
org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
at
org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
at
org.apache.hadoop.hdds.server.http.HttpServer2$QuotingInputFilter.doFilter(HttpServer2.java:1681)
at
org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
at
org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
at
org.apache.hadoop.hdds.server.http.NoCacheFilter.doFilter(NoCacheFilter.java:48)
at
org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
at
org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:552)
at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at
org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)
at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235)
at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505)
at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355)
at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at
org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146)
at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
at org.eclipse.jetty.server.Server.handle(Server.java:516)
at
org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487)
at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479)
at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
at
org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555)
at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410)
at
org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.lang.Thread.run(Thread.java:748)
{code}
> OM down to InterruptedException 'Unable to process metadata snapshot request'
> -----------------------------------------------------------------------------
>
> Key: HDDS-10739
> URL: https://issues.apache.org/jira/browse/HDDS-10739
> Project: Apache Ozone
> Issue Type: Bug
> Components: OM, Snapshot
> Reporter: Jyotirmoy Sinha
> Priority: Major
> Labels: ozone-snapshot
>
> Scenario :
> * Generate data over parallel threads over various volume/buckets
> * Perform parallel snapshot create/delete/list operations over above buckets
> * Perform parallel snapdiff operations over each bucket
> * Perform parallel read operations of snapshot contents
> * Introduce OM and cluster restarts in between along with DN decommissioning
> and balancer restarts.
> OM Error Stacktrace -
> {code:java}
> 2024-04-22 13:18:25,071 INFO
> [om131@group-2BC026ED99AC-StateMachineUpdater]-org.eclipse.jetty.server.handler.ContextHandler:
> Stopped
> o.e.j.s.ServletContextHandler@74e87f03{logs,/logs,file:///var/log/hadoop-ozone/,STOPPED}
> 2024-04-22 13:18:40,074 ERROR
> [qtp560715723-676269]-org.apache.hadoop.hdds.utils.DBCheckpointServlet:
> Unable to process metadata snapshot request.
> java.lang.InterruptedException
> at
> java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:998)
> at
> java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
> at java.util.concurrent.Semaphore.acquire(Semaphore.java:312)
> at
> org.apache.hadoop.ozone.lock.BootstrapStateHandler$Lock.lock(BootstrapStateHandler.java:31)
> at
> org.apache.hadoop.ozone.om.OMDBCheckpointServlet$Lock.lock(OMDBCheckpointServlet.java:654)
> at
> org.apache.hadoop.hdds.utils.DBCheckpointServlet.generateSnapshotCheckpoint(DBCheckpointServlet.java:197)
> at
> org.apache.hadoop.hdds.utils.DBCheckpointServlet.doGet(DBCheckpointServlet.java:303)
> at javax.servlet.http.HttpServlet.service(HttpServlet.java:687)
> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
> at
> org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799)
> at
> org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656)
> at
> org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter.doFilter(StaticUserWebFilter.java:110)
> at
> org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
> at
> org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
> at
> org.apache.hadoop.hdds.server.http.HttpServer2$QuotingInputFilter.doFilter(HttpServer2.java:1681)
> at
> org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
> at
> org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
> at
> org.apache.hadoop.hdds.server.http.NoCacheFilter.doFilter(NoCacheFilter.java:48)
> at
> org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
> at
> org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626)
> at
> org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:552)
> at
> org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
> at
> org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)
> at
> org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
> at
> org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235)
> at
> org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
> at
> org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
> at
> org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440)
> at
> org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
> at
> org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505)
> at
> org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
> at
> org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
> at
> org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355)
> at
> org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
> at
> org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146)
> at
> org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
> at org.eclipse.jetty.server.Server.handle(Server.java:516)
> at
> org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487)
> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732)
> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479)
> at
> org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
> at
> org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
> at
> org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555)
> at
> org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410)
> at
> org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164)
> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
> at
> org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
> at
> org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338)
> at
> org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315)
> at
> org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
> at
> org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
> at
> org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409)
> at
> org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
> at
> org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
> at java.lang.Thread.run(Thread.java:748)
> 2024-04-22 13:18:40,074 ERROR
> [qtp560715723-676994]-org.apache.hadoop.hdds.utils.DBCheckpointServlet:
> Unable to process metadata snapshot request.
> java.lang.InterruptedException {code}
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
