[
https://issues.apache.org/jira/browse/IGNITE-25434?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Alexander Lapin updated IGNITE-25434:
-------------------------------------
Description:
[TCLink|https://ci.ignite.apache.org/buildConfiguration/ApacheIgnite3xGradle_Test_RunAllTests/9147150?showLog=9147128_28271_107.1223&logFilter=debug&logView=flowAware]
{code:java}
[2025-05-19T16:22:13,606][INFO ][Test
worker][ItDisasterRecoveryControllerRestartPartitionsTest] >>> Stopping test:
ItDisasterRecoveryControllerRestartPartitionsTest#testRestartPartitionsIllegalPartitionNegative,
displayName: testRestartPartitionsIllegalPartitionNegative(), cost: 22ms.
19:22:13 org.apache.ignite.internal.failure.StackTraceCapturingException:
Failed to process the lease granted message [msg=LeaseGrantedMessageImpl
[force=false, groupId=19_part_1, leaseExpirationTime=HybridTimestamp
[physical=2025-05-19 16:24:13:616 +0000, logical=0,
composite=114535422598578176], leaseStartTime=HybridTimestamp
[physical=2025-05-19 16:22:13:616 +0000, logical=2,
composite=114535414734258178]]].
19:22:13 at
org.apache.ignite.internal.failure.FailureManager.process(FailureManager.java:191)
~[ignite-failure-handler-3.1.0-SNAPSHOT.jar:?]
19:22:13 at
org.apache.ignite.internal.failure.FailureManager.process(FailureManager.java:168)
~[ignite-failure-handler-3.1.0-SNAPSHOT.jar:?]
19:22:13 [2025-05-19T16:22:13,608][INFO ][Test
worker][ItDisasterRecoveryControllerRestartPartitionsTest] >>> Starting test:
ItDisasterRecoveryControllerRestartPartitionsTest#testRestartSpecifiedPartitions,
displayName: testRestartSpecifiedPartitions()
19:22:13 at
org.apache.ignite.internal.replicator.PlacementDriverMessageProcessor.lambda$processPlacementDriverMessage$0(PlacementDriverMessageProcessor.java:153)
~[ignite-replicator-3.1.0-SNAPSHOT.jar:?]
19:22:13 at
java.base/java.util.concurrent.CompletableFuture.uniHandle(CompletableFuture.java:934)
[?:?]
19:22:13 at
java.base/java.util.concurrent.CompletableFuture$UniHandle.tryFire(CompletableFuture.java:911)
[?:?]
19:22:13 [2025-05-19T16:22:13,618][INFO
][%idrcrpt_n_3344%JRaft-FSMCaller-Disruptor-metastorage_group_stripe_0-0][PlacementDriverMessageProcessor]
Received LeaseGrantedMessage for replica [groupId=19_part_5,
leaseStartTime=HybridTimestamp [physical=2025-05-19 16:22:13:616 +0000,
logical=1, composite=114535414734258177], force=false].
19:22:13 at
java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510)
[?:?]
19:22:13 at
java.base/java.util.concurrent.CompletableFuture.postFire(CompletableFuture.java:614)
[?:?]
19:22:13 [2025-05-19T16:22:13,620][INFO
][%idrcrpt_n_3344%partition-operations-9][PlacementDriverMessageProcessor]
Waiting for actual storage state, group=19_part_5
19:22:13 at
java.base/java.util.concurrent.CompletableFuture$UniCompose.tryFire(CompletableFuture.java:1163)
[?:?]
19:22:13 at
java.base/java.util.concurrent.CompletableFuture$Completion.run(CompletableFuture.java:482)
[?:?]
19:22:13 at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
[?:?]
19:22:13 [2025-05-19T16:22:13,620][INFO
][%idrcrpt_n_3344%JRaft-FSMCaller-Disruptor-metastorage_group_stripe_0-0][PlacementDriverMessageProcessor]
Received LeaseGrantedMessage for replica [groupId=19_part_17,
leaseStartTime=HybridTimestamp [physical=2025-05-19 16:22:13:616 +0000,
logical=4, composite=114535414734258180], force=false].
19:22:13 at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[?:?]
19:22:13 at java.base/java.lang.Thread.run(Thread.java:833) [?:?]
19:22:13 Caused by: java.util.concurrent.CompletionException:
java.lang.IllegalStateException: Replica reservation failed [groupId=19_part_1,
leaseStartTime=HybridTimestamp [physical=2025-05-19 16:22:13:616 +0000,
logical=2, composite=114535414734258178]].
19:22:13 [2025-05-19T16:22:13,620][INFO
][%idrcrpt_n_3344%JRaft-FSMCaller-Disruptor-metastorage_group_stripe_0-0][PlacementDriverMessageProcessor]
Received LeaseGrantedMessage for replica [groupId=19_part_15,
leaseStartTime=HybridTimestamp [physical=2025-05-19 16:22:13:616 +0000,
logical=5, composite=114535414734258181], force=false].
19:22:13 at
java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315)
~[?:?]
19:22:13 at
java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320)
~[?:?] {code}
was:[TCLink|https://ci.ignite.apache.org/buildConfiguration/ApacheIgnite3xGradle_Test_RunAllTests/9147150?showLog=9147128_28271_107.1223&logFilter=debug&logView=flowAware]
> Critical system error on processPlacementDriverMessage
> ------------------------------------------------------
>
> Key: IGNITE-25434
> URL: https://issues.apache.org/jira/browse/IGNITE-25434
> Project: Ignite
> Issue Type: Bug
> Reporter: Alexander Lapin
> Priority: Major
> Labels: MakeTeamcityGreenAgain, ignite-3
>
> [TCLink|https://ci.ignite.apache.org/buildConfiguration/ApacheIgnite3xGradle_Test_RunAllTests/9147150?showLog=9147128_28271_107.1223&logFilter=debug&logView=flowAware]
>
> {code:java}
> [2025-05-19T16:22:13,606][INFO ][Test
> worker][ItDisasterRecoveryControllerRestartPartitionsTest] >>> Stopping test:
> ItDisasterRecoveryControllerRestartPartitionsTest#testRestartPartitionsIllegalPartitionNegative,
> displayName: testRestartPartitionsIllegalPartitionNegative(), cost: 22ms.
> 19:22:13 org.apache.ignite.internal.failure.StackTraceCapturingException:
> Failed to process the lease granted message [msg=LeaseGrantedMessageImpl
> [force=false, groupId=19_part_1, leaseExpirationTime=HybridTimestamp
> [physical=2025-05-19 16:24:13:616 +0000, logical=0,
> composite=114535422598578176], leaseStartTime=HybridTimestamp
> [physical=2025-05-19 16:22:13:616 +0000, logical=2,
> composite=114535414734258178]]].
> 19:22:13 at
> org.apache.ignite.internal.failure.FailureManager.process(FailureManager.java:191)
> ~[ignite-failure-handler-3.1.0-SNAPSHOT.jar:?]
> 19:22:13 at
> org.apache.ignite.internal.failure.FailureManager.process(FailureManager.java:168)
> ~[ignite-failure-handler-3.1.0-SNAPSHOT.jar:?]
> 19:22:13 [2025-05-19T16:22:13,608][INFO ][Test
> worker][ItDisasterRecoveryControllerRestartPartitionsTest] >>> Starting test:
> ItDisasterRecoveryControllerRestartPartitionsTest#testRestartSpecifiedPartitions,
> displayName: testRestartSpecifiedPartitions()
> 19:22:13 at
> org.apache.ignite.internal.replicator.PlacementDriverMessageProcessor.lambda$processPlacementDriverMessage$0(PlacementDriverMessageProcessor.java:153)
> ~[ignite-replicator-3.1.0-SNAPSHOT.jar:?]
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture.uniHandle(CompletableFuture.java:934)
> [?:?]
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture$UniHandle.tryFire(CompletableFuture.java:911)
> [?:?]
> 19:22:13 [2025-05-19T16:22:13,618][INFO
> ][%idrcrpt_n_3344%JRaft-FSMCaller-Disruptor-metastorage_group_stripe_0-0][PlacementDriverMessageProcessor]
> Received LeaseGrantedMessage for replica [groupId=19_part_5,
> leaseStartTime=HybridTimestamp [physical=2025-05-19 16:22:13:616 +0000,
> logical=1, composite=114535414734258177], force=false].
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510)
> [?:?]
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture.postFire(CompletableFuture.java:614)
> [?:?]
> 19:22:13 [2025-05-19T16:22:13,620][INFO
> ][%idrcrpt_n_3344%partition-operations-9][PlacementDriverMessageProcessor]
> Waiting for actual storage state, group=19_part_5
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture$UniCompose.tryFire(CompletableFuture.java:1163)
> [?:?]
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture$Completion.run(CompletableFuture.java:482)
> [?:?]
> 19:22:13 at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
> [?:?]
> 19:22:13 [2025-05-19T16:22:13,620][INFO
> ][%idrcrpt_n_3344%JRaft-FSMCaller-Disruptor-metastorage_group_stripe_0-0][PlacementDriverMessageProcessor]
> Received LeaseGrantedMessage for replica [groupId=19_part_17,
> leaseStartTime=HybridTimestamp [physical=2025-05-19 16:22:13:616 +0000,
> logical=4, composite=114535414734258180], force=false].
> 19:22:13 at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
> [?:?]
> 19:22:13 at java.base/java.lang.Thread.run(Thread.java:833) [?:?]
> 19:22:13 Caused by: java.util.concurrent.CompletionException:
> java.lang.IllegalStateException: Replica reservation failed
> [groupId=19_part_1, leaseStartTime=HybridTimestamp [physical=2025-05-19
> 16:22:13:616 +0000, logical=2, composite=114535414734258178]].
> 19:22:13 [2025-05-19T16:22:13,620][INFO
> ][%idrcrpt_n_3344%JRaft-FSMCaller-Disruptor-metastorage_group_stripe_0-0][PlacementDriverMessageProcessor]
> Received LeaseGrantedMessage for replica [groupId=19_part_15,
> leaseStartTime=HybridTimestamp [physical=2025-05-19 16:22:13:616 +0000,
> logical=5, composite=114535414734258181], force=false].
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315)
> ~[?:?]
> 19:22:13 at
> java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320)
> ~[?:?] {code}
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
