[ 
https://issues.apache.org/jira/browse/IGNITE-27115?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=18045092#comment-18045092
 ] 

Pavel Tupitsyn commented on IGNITE-27115:
-----------------------------------------

>From the log above we can see:
* "Old read-only tx" is initialized with a correct old timestamp 
115700824823955457
* BUT when the lazy tx actually starts on the server, somehow it gets a newer 
timestamp on the server: 115700824825987072 (while the client still thinks it 
uses the old one - see "Tx started" log entry)

> .NET: 
> Apache.Ignite.Tests.Transactions.TransactionsTests.TestReadOnlyTxSeesOldDataAfterUpdate(False)
>  is still flaky
> -------------------------------------------------------------------------------------------------------------------
>
>                 Key: IGNITE-27115
>                 URL: https://issues.apache.org/jira/browse/IGNITE-27115
>             Project: Ignite
>          Issue Type: Bug
>          Components: platforms ai3, thin clients ai3
>            Reporter: Pavel Tupitsyn
>            Assignee: Pavel Tupitsyn
>            Priority: Major
>              Labels: MakeTeamcityGreenAgain, ignite-3
>             Fix For: 3.2
>
>          Time Spent: 10m
>  Remaining Estimate: 0h
>
> [https://ci.ignite.apache.org/test/-5751543147116258763?currentProjectId=ApacheIgnite3x_Test_PlatformTests&branch=]
>  
> * Workaround from IGNITE-26495 did not help, so something else is wrong with 
> read-only transactions.
> * All failures are on Windows:
>  
> [https://ci.ignite.apache.org/buildConfiguration/ApacheIgnite3x_Test_PlatformTests_PlatformDotnetTestsWindows/9659377]
> [https://ci.ignite.apache.org/buildConfiguration/ApacheIgnite3x_Test_PlatformTests_PlatformDotnetTestsWindows/9640986]
> [https://ci.ignite.apache.org/buildConfiguration/ApacheIgnite3x_Test_PlatformTests_PlatformDotnetTestsWindows/9634505]
> [https://ci.ignite.apache.org/buildConfiguration/ApacheIgnite3x_Test_PlatformTests_PlatformDotnetTestsWindows/9634042]
>  
> {code}
> String lengths are both 2. Strings differ at index 0.
>   Expected: "11"
>   But was:  "22"
>   -----------^
>    at 
> Apache.Ignite.Tests.Transactions.TransactionsTests.TestReadOnlyTxSeesOldDataAfterUpdate(Boolean
>  readBeforeUpdate) in 
> C:\w\69bdbe372c424384\ignite-3\modules\platforms\dotnet\Apache.Ignite.Tests\Transactions\TransactionsTests.cs:line
>  239
>    at 
> Apache.Ignite.Tests.Transactions.TransactionsTests.TestReadOnlyTxSeesOldDataAfterUpdate(Boolean
>  readBeforeUpdate) in 
> C:\w\69bdbe372c424384\ignite-3\modules\platforms\dotnet\Apache.Ignite.Tests\Transactions\TransactionsTests.cs:line
>  243
>    at 
> NUnit.Framework.Internal.TaskAwaitAdapter.GenericAdapter`1.BlockUntilCompleted()
>    at 
> NUnit.Framework.Internal.MessagePumpStrategy.NoMessagePumpStrategy.WaitForCompletion(AwaitAdapter
>  awaiter)
>    at NUnit.Framework.Internal.AsyncToSyncAdapter.Await(Func`1 invoke)
>    at 
> NUnit.Framework.Internal.Commands.TestMethodCommand.RunTestMethod(TestExecutionContext
>  context)
>    at 
> NUnit.Framework.Internal.Commands.TestMethodCommand.Execute(TestExecutionContext
>  context)
>    at 
> NUnit.Framework.Internal.Commands.BeforeAndAfterTestCommand.<>c__DisplayClass1_0.<Execute>b__0()
>    at 
> NUnit.Framework.Internal.Commands.DelegatingTestCommand.RunTestMethodInThreadAbortSafeZone(TestExecutionContext
>  context, Action action)
> 1)    at 
> Apache.Ignite.Tests.Transactions.TransactionsTests.TestReadOnlyTxSeesOldDataAfterUpdate(Boolean
>  readBeforeUpdate) in 
> C:\w\69bdbe372c424384\ignite-3\modules\platforms\dotnet\Apache.Ignite.Tests\Transactions\TransactionsTests.cs:line
>  239
>    at 
> System.Runtime.CompilerServices.AsyncTaskMethodBuilder`1.AsyncStateMachineBox`1.ExecutionContextCallback(Object
>  s)
> ------- Stdout: -------
> SetUp: TestReadOnlyTxSeesOldDataAfterUpdate(False)
> [08:35:39] [info] [Apache.Ignite.Internal.ClientFailoverSocket] Ignite.NET 
> client version 3.2.0.0-snapshot+f777c2a56c is starting
> [08:35:39] [dbug] [Apache.Ignite.Internal.ClientSocket-4414] Connection 
> established [remoteAddress=[::ffff:127.0.0.1]:10942]
> [08:35:39] [dbug] [Apache.Ignite.Internal.ClientSocket-4414] Handshake 
> succeeded [remoteAddress=[::ffff:127.0.0.1]:10942, context=ConnectionContext 
> { Version = ClientProtocolVersion { Version = 3.0.0 }, IdleTimeout = 
> 00:00:06, ClusterNode = ClusterNode { Id = 
> 4c312728-570d-4444-9261-4853f75bdbf8, Name = 
> org.apache.ignite.internal.runner.app.PlatformTestNodeRunner, Address = 
> 127.0.0.1:10942 }, ClusterIds = System.Guid[], ClusterName = cluster, SslInfo 
> = , Features = TableReqsUseQualifiedName, PlatformComputeJob, 
> StreamerReceiverExecutionOptions, ClusterId = 
> d71c73a7-c25e-441a-ba3d-a6b01f121c56 }]
> [08:35:39] [warn] [Apache.Ignite.Internal.ClientSocket-4414] Server-side 
> IdleTimeout is 00:00:06, configured 
> IgniteClientConfiguration.HeartbeatInterval is 00:00:30, which is longer than 
> recommended IdleTimeout / 3. Overriding heartbeat interval with 
> max(IdleTimeout / 3, 500ms): 00:00:02
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=1, op=TableGetQualified, remoteAddress=127.0.0.1:10942]
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=1, flags=PartitionAssignmentChanged, 
> remoteAddress=127.0.0.1:10942]
> [08:35:39] [info] [Apache.Ignite.Internal.ClientSocket-4414] Partition 
> assignment change notification received [remoteAddress=127.0.0.1:10942, 
> timestamp=115610815418204193
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=2, op=SchemasGet, remoteAddress=127.0.0.1:10942]
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=2, flags=0, remoteAddress=127.0.0.1:10942]
> [08:35:39] [dbug] [Apache.Ignite.Internal.Table.Table] Schema loaded 
> [tableId=21, schemaVersion=1]
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=3, op=PartitionAssignmentGet, remoteAddress=127.0.0.1:10942]
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=3, flags=0, remoteAddress=127.0.0.1:10942]
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=4, op=TupleUpsert, remoteAddress=127.0.0.1:10942]
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=4, flags=0, remoteAddress=127.0.0.1:10942]
> [08:35:39] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=5, op=TupleUpsert, remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=5, flags=0, remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=6, op=TxBegin, remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=6, flags=0, remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=7, op=TupleGet, remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=7, flags=0, remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Sending request 
> [requestId=8, op=TxRollback, remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4414] Received 
> response [requestId=8, flags=0, remoteAddress=127.0.0.1:10942]
> [08:35:40] [dbug] [Apache.Ignite.Internal.ClientSocket-4414] Connection 
> closed gracefully [remoteAddress=127.0.0.1:10942]
> [08:35:40] [trce] [Apache.Ignite.Internal.ClientSocket-4407] Sending request 
> [requestId=36, op=TupleDeleteAll, remoteAddress=127.0.0.1:10943]
> TearDown start: TestReadOnlyTxSeesOldDataAfterUpdate(False)
> TearDown end: TestReadOnlyTxSeesOldDataAfterUpdate(False)
> {code}
>  



--
This message was sent by Atlassian Jira
(v8.20.10#820010)

Reply via email to