[
https://issues.apache.org/jira/browse/IGNITE-26734?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=18038412#comment-18038412
]
Vladislav Pyatkov commented on IGNITE-26734:
--------------------------------------------
Wait for reproduction with the new logs introduced in the ticket above.
> ItDurableFinishTest.testCommitAlreadyAbortedTx may fail with AssertionError
> ---------------------------------------------------------------------------
>
> Key: IGNITE-26734
> URL: https://issues.apache.org/jira/browse/IGNITE-26734
> Project: Ignite
> Issue Type: Bug
> Components: transactions ai3
> Reporter: Vyacheslav Koptilin
> Priority: Major
> Labels: MakeTeamcityGreenAgain, ignite-3
> Attachments: ItDurableFinishTestLog.txt
>
>
> The test failure:
> {code:java}
> org.apache.ignite.internal.table.ItDurableFinishTest.testCommitAlreadyAbortedTx()
> java.lang.AssertionError: java.util.concurrent.ExecutionException:
> org.apache.ignite.internal.placementdriver.PrimaryReplicaAwaitTimeoutException:
> IGN-PLACEMENTDRIVER-1 The primary replica await timed out
> [replicationGroupId=22_part_0, referenceTimestamp=HybridTimestamp
> [physical=2025-10-15 06:53:56:645 +0000, logical=2,
> composite=115376864404766722], currentLease=Lease
> [leaseholder=idft_tcaat_3344,
> leaseholderId=e1dd67fe-63c1-4b0b-a455-92daf836ec41, accepted=false,
> startTime=HybridTimestamp [physical=2025-10-15 06:53:56:502 +0000, logical=0,
> composite=115376864395395072], expirationTime=HybridTimestamp
> [physical=2025-10-15 06:55:56:502 +0000, logical=0,
> composite=115376872259715072], prolongable=true, proposedCandidate=null,
> replicationGroupId=22_part_0]] TraceId:83c18584
> java.lang.AssertionError: java.util.concurrent.ExecutionException:
> org.apache.ignite.internal.placementdriver.PrimaryReplicaAwaitTimeoutException:
> IGN-PLACEMENTDRIVER-1 The primary replica await timed out
> [replicationGroupId=22_part_0, referenceTimestamp=HybridTimestamp
> [physical=2025-10-15 06:53:56:645 +0000, logical=2,
> composite=115376864404766722], currentLease=Lease
> [leaseholder=idft_tcaat_3344,
> leaseholderId=e1dd67fe-63c1-4b0b-a455-92daf836ec41, accepted=false,
> startTime=HybridTimestamp [physical=2025-10-15 06:53:56:502 +0000, logical=0,
> composite=115376864395395072], expirationTime=HybridTimestamp
> [physical=2025-10-15 06:55:56:502 +0000, logical=0,
> composite=115376872259715072], prolongable=true, proposedCandidate=null,
> replicationGroupId=22_part_0]] TraceId:83c18584
> at
> org.apache.ignite.internal.testframework.matchers.CompletableFutureMatcher.matchesSafely(CompletableFutureMatcher.java:71)
> at
> org.apache.ignite.internal.testframework.matchers.CompletableFutureMatcher.matchesSafely(CompletableFutureMatcher.java:28)
> at org.hamcrest.TypeSafeMatcher.matches(TypeSafeMatcher.java:83)
> at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:10)
> at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:6)
> at
> org.apache.ignite.internal.table.ItDurableFinishTest.prepareTransactionData(ItDurableFinishTest.java:103)
> at
> org.apache.ignite.internal.table.ItDurableFinishTest.testCommitAlreadyAbortedTx(ItDurableFinishTest.java:320)
> at java.base/java.lang.reflect.Method.invoke(Method.java:568)
> at java.base/java.util.ArrayList.forEach(ArrayList.java:1511)
> at java.base/java.util.ArrayList.forEach(ArrayList.java:1511)
> Caused by: java.util.concurrent.ExecutionException:
> org.apache.ignite.internal.placementdriver.PrimaryReplicaAwaitTimeoutException:
> IGN-PLACEMENTDRIVER-1 The primary replica await timed out
> [replicationGroupId=22_part_0, referenceTimestamp=HybridTimestamp
> [physical=2025-10-15 06:53:56:645 +0000, logical=2,
> composite=115376864404766722], currentLease=Lease
> [leaseholder=idft_tcaat_3344,
> leaseholderId=e1dd67fe-63c1-4b0b-a455-92daf836ec41, accepted=false,
> startTime=HybridTimestamp [physical=2025-10-15 06:53:56:502 +0000, logical=0,
> composite=115376864395395072], expirationTime=HybridTimestamp
> [physical=2025-10-15 06:55:56:502 +0000, logical=0,
> composite=115376872259715072], prolongable=true, proposedCandidate=null,
> replicationGroupId=22_part_0]] TraceId:83c18584
> at
> java.base/java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:396)
> at
> java.base/java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2096)
> at
> org.apache.ignite.internal.testframework.matchers.CompletableFutureMatcher.matchesSafely(CompletableFutureMatcher.java:67)
> ... 9 more
> Caused by:
> org.apache.ignite.internal.placementdriver.PrimaryReplicaAwaitTimeoutException:
> IGN-PLACEMENTDRIVER-1 The primary replica await timed out
> [replicationGroupId=22_part_0, referenceTimestamp=HybridTimestamp
> [physical=2025-10-15 06:53:56:645 +0000, logical=2,
> composite=115376864404766722], currentLease=Lease
> [leaseholder=idft_tcaat_3344,
> leaseholderId=e1dd67fe-63c1-4b0b-a455-92daf836ec41, accepted=false,
> startTime=HybridTimestamp [physical=2025-10-15 06:53:56:502 +0000, logical=0,
> composite=115376864395395072], expirationTime=HybridTimestamp
> [physical=2025-10-15 06:55:56:502 +0000, logical=0,
> composite=115376872259715072], prolongable=true, proposedCandidate=null,
> replicationGroupId=22_part_0]] TraceId:83c18584
> at
> app//org.apache.ignite.internal.placementdriver.leases.LeaseTracker.lambda$awaitPrimaryReplicaImpl$6(LeaseTracker.java:311)
> at
> [email protected]/java.util.concurrent.CompletableFuture$UniRun.tryFire(CompletableFuture.java:787)
> at
> [email protected]/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510)
> at
> [email protected]/java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2147)
> at
> app//org.apache.ignite.internal.raft.RaftGroupServiceImpl.lambda$sendWithRetry$50(RaftGroupServiceImpl.java:717)
> at
> [email protected]/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:863)
> at
> [email protected]/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:841)
> at
> [email protected]/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510)
> at
> [email protected]/java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2147)
> at
> app//org.apache.ignite.internal.network.DefaultMessagingService.onInvokeResponse(DefaultMessagingService.java:632)
> at
> app//org.apache.ignite.internal.network.DefaultMessagingService.send0(DefaultMessagingService.java:292)
> at
> app//org.apache.ignite.internal.network.DefaultMessagingService.respond(DefaultMessagingService.java:227)
> at
> app//org.apache.ignite.internal.network.MessagingService.respond(MessagingService.java:138)
> at
> app//org.apache.ignite.raft.jraft.rpc.impl.IgniteRpcServer$NetworkRpcContext.sendResponse(IgniteRpcServer.java:240)
> at
> app//org.apache.ignite.raft.jraft.rpc.impl.ActionRequestProcessor.sendResponse(ActionRequestProcessor.java:268)
> at
> app//org.apache.ignite.raft.jraft.rpc.impl.ActionRequestProcessor$2$1.result(ActionRequestProcessor.java:228)
> at
> app//org.apache.ignite.internal.metastorage.server.raft.MetaStorageListener.onReadBusy(MetaStorageListener.java:115)
> at
> app//org.apache.ignite.internal.metastorage.server.raft.MetaStorageListener.onRead(MetaStorageListener.java:95)
> at
> app//org.apache.ignite.raft.jraft.rpc.impl.ActionRequestProcessor$2.run(ActionRequestProcessor.java:222)
> at
> app//org.apache.ignite.raft.jraft.closure.ReadIndexClosure.run(ReadIndexClosure.java:87)
> at
> app//org.apache.ignite.raft.jraft.core.ReadOnlyServiceImpl.notifySuccess(ReadOnlyServiceImpl.java:452)
> at
> app//org.apache.ignite.raft.jraft.core.ReadOnlyServiceImpl$ReadIndexResponseClosure.run(ReadOnlyServiceImpl.java:179)
> at
> app//org.apache.ignite.raft.jraft.core.NodeImpl.readLeader(NodeImpl.java:1980)
> at
> app//org.apache.ignite.raft.jraft.core.NodeImpl.handleReadIndexRequest(NodeImpl.java:1827)
> at
> app//org.apache.ignite.raft.jraft.core.ReadOnlyServiceImpl.executeReadIndexEvents(ReadOnlyServiceImpl.java:239)
> at
> app//org.apache.ignite.raft.jraft.core.ReadOnlyServiceImpl$ReadIndexEventHandler.onEvent(ReadOnlyServiceImpl.java:123)
> at
> app//org.apache.ignite.raft.jraft.core.ReadOnlyServiceImpl$ReadIndexEventHandler.onEvent(ReadOnlyServiceImpl.java:106)
> at
> app//org.apache.ignite.raft.jraft.disruptor.StripedDisruptor$StripeEntryHandler.onEvent(StripedDisruptor.java:322)
> at
> app//org.apache.ignite.raft.jraft.disruptor.StripedDisruptor$StripeEntryHandler.onEvent(StripedDisruptor.java:279)
> at
> app//com.lmax.disruptor.BatchEventProcessor.processEvents(BatchEventProcessor.java:167)
> at
> app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:122)
> at [email protected]/java.lang.Thread.run(Thread.java:833)
> Caused by: java.util.concurrent.CompletionException:
> java.util.concurrent.TimeoutException
> at
> java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:332)
> at
> java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:347)
> at
> java.base/java.util.concurrent.CompletableFuture$UniCompose.tryFire(CompletableFuture.java:1141)
> at
> java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510)
> at
> java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2162)
> at
> java.base/java.util.concurrent.CompletableFuture$Timeout.run(CompletableFuture.java:2874)
> at
> java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
> at
> java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
> at
> java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
> at
> java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
> ... 1 more
> Caused by: java.util.concurrent.TimeoutException
> ... 7 more {code}
> Need to find the root cause. Full log attached.
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
