[
https://issues.apache.org/jira/browse/IGNITE-27487?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Pavel Tupitsyn updated IGNITE-27487:
------------------------------------
Ignite Flags: (was: Docs Required,Release Notes Required)
> .NET:
> Apache.Ignite.Tests.PartitionAwarenessRealClusterTests.TestPutRoutesRequestToPrimaryNode(True)
> is flaky
> -------------------------------------------------------------------------------------------------------------
>
> Key: IGNITE-27487
> URL: https://issues.apache.org/jira/browse/IGNITE-27487
> 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
>
>
> Still flaky after IGNITE-27070
> https://ci.ignite.apache.org/test/-4966612307254363927?currentProjectId=ApacheIgnite3x_Test_PlatformTests
> {code}
> Expected string length 60 but was 62. Strings differ at index 60.
> Expected: "org.apache.ignite.internal.runner.app.PlatformTestNodeRunner"
> But was: "org.apache.ignite.internal.runner.app.PlatformTestNodeRunner_2"
> -----------------------------------------------------------------------^
> at
> Apache.Ignite.Tests.PartitionAwarenessRealClusterTests.TestPutRoutesRequestToPrimaryNode(Boolean
> withTx) in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/PartitionAwarenessRealClusterTests.cs:line
> 81
> at
> Apache.Ignite.Tests.PartitionAwarenessRealClusterTests.TestPutRoutesRequestToPrimaryNode(Boolean
> withTx) in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/PartitionAwarenessRealClusterTests.cs:line
> 87
> 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.PartitionAwarenessRealClusterTests.TestPutRoutesRequestToPrimaryNode(Boolean
> withTx) in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/PartitionAwarenessRealClusterTests.cs:line
> 81
> at
> System.Runtime.CompilerServices.AsyncTaskMethodBuilder`1.AsyncStateMachineBox`1.ExecutionContextCallback(Object
> s)
> ------- Stdout: -------
> SetUp: TestPutRoutesRequestToPrimaryNode(True)
> Fake server started [port=43419, test=TestPutRoutesRequestToPrimaryNode(True)]
> Accepted connection [port=43419,
> test=TestPutRoutesRequestToPrimaryNode(True), endpoint=127.0.0.1:50540]
> [11:19:06] [info] [Apache.Ignite.Internal.ClientFailoverSocket] Ignite.NET
> client version 3.2.0.0-snapshot+18255ad767 is starting
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientFailoverSocket] Endpoints
> updated [added=[127.0.0.1:43419], removed=[]]
> [11:19:06] [dbug] [Apache.Ignite.Internal.ClientSocket-318] Connection
> established [remoteAddress=[::ffff:127.0.0.1]:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientFailoverSocket] ObservableTs
> updated [prev=0, current=115768606489706496]
> [11:19:06] [dbug] [Apache.Ignite.Internal.ClientSocket-318] Handshake
> succeeded [remoteAddress=[::ffff:127.0.0.1]:43419, context=ConnectionContext
> { Version = ClientProtocolVersion { Version = 3.0.0 }, IdleTimeout =
> 00:00:06, ClusterNode = ClusterNode { Id =
> 8b5a3f90-3721-4540-9550-826c5f0aa526, Name =
> org.apache.ignite.internal.runner.app.PlatformTestNodeRunner_2, Address =
> 127.0.0.1:43419 }, ClusterIds = System.Guid[], ClusterName = cluster, SslInfo
> = , Features = TableReqsUseQualifiedName, PlatformComputeJob,
> StreamerReceiverExecutionOptions, ClusterId =
> b2ddf420-f7ae-40aa-ad08-804faeb03ae3 }]
> [11:19:06] [warn] [Apache.Ignite.Internal.ClientSocket-318] 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
> [11:19:06] [dbug] [Apache.Ignite.Internal.ClientFailoverSocket] Trying to
> establish secondary connections - awaiting 1 tasks...
> [11:19:06] [dbug] [Apache.Ignite.Internal.ClientFailoverSocket] 1 secondary
> connections established, 0 failed.
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=1, op=TableGetQualified, remoteAddress=127.0.0.1:43419]
> [11:19:06] [info] [Apache.Ignite.Internal.ClientSocket-318] Partition
> assignment change notification received [remoteAddress=127.0.0.1:43419,
> timestamp=115768601059852322]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=1, op=TableGetQualified, flags=PartitionAssignmentChanged,
> remoteAddress=127.0.0.1:43419, duration=00:00:00.0006602, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=2, op=SchemasGet, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=2, op=SchemasGet, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0005932, observableTs=0]
> [11:19:06] [dbug] [Apache.Ignite.Internal.Table.Table] Schema loaded
> [tableId=21, schemaVersion=1]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=3, op=PartitionAssignmentGet, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=3, op=PartitionAssignmentGet, flags=0,
> remoteAddress=127.0.0.1:43419, duration=00:00:00.0005398, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=4, op=TupleGet, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientFailoverSocket] ObservableTs
> updated [prev=115768606489706496, current=115768606490034177]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=4, op=TupleGet, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0012776, observableTs=115768606490034177]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=5, op=TableGetQualified, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=5, op=TableGetQualified, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0004512, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=6, op=ComputeExecuteColocated, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=6, op=ComputeExecuteColocated, flags=0,
> remoteAddress=127.0.0.1:43419, duration=00:00:00.0014620, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientFailoverSocket] ObservableTs
> updated [prev=115768606490034177, current=115768606490230784]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=6, op=None, flags=Notification, remoteAddress=127.0.0.1:43419,
> duration=(null), observableTs=115768606490230784]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Lazy tx
> created [options=TransactionOptions { ReadOnly = False, TimeoutMillis = 0 },
> readTimestamp=115768606490230784]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=7, op=TxBegin, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=7, op=TxBegin, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0007153, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Tx
> started [options=TransactionOptions { ReadOnly = False, TimeoutMillis = 0 },
> readTimestamp=115768606490230784, txId=1]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=8, op=TupleUpsert, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=8, op=TupleUpsert, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0033154, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=9, op=TxRollback, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=9, op=TxRollback, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0032313, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Tx
> rolled back [txId=1]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=10, op=ComputeExecuteColocated, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=10, op=ComputeExecuteColocated, flags=0,
> remoteAddress=127.0.0.1:43419, duration=00:00:00.0011977, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientFailoverSocket] ObservableTs
> updated [prev=115768606490230784, current=115768606490820608]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=10, op=None, flags=Notification, remoteAddress=127.0.0.1:43419,
> duration=(null), observableTs=115768606490820608]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Lazy tx
> created [options=TransactionOptions { ReadOnly = False, TimeoutMillis = 0 },
> readTimestamp=115768606490820608]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=11, op=TxBegin, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=11, op=TxBegin, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0005700, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Tx
> started [options=TransactionOptions { ReadOnly = False, TimeoutMillis = 0 },
> readTimestamp=115768606490820608, txId=2]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=12, op=TupleUpsert, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=12, op=TupleUpsert, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0024011, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=13, op=TxRollback, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=13, op=TxRollback, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0021887, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Tx
> rolled back [txId=2]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=14, op=ComputeExecuteColocated, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=14, op=ComputeExecuteColocated, flags=0,
> remoteAddress=127.0.0.1:43419, duration=00:00:00.0026296, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientFailoverSocket] ObservableTs
> updated [prev=115768606490820608, current=115768606491410432]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=14, op=None, flags=Notification, remoteAddress=127.0.0.1:43419,
> duration=(null), observableTs=115768606491410432]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Lazy tx
> created [options=TransactionOptions { ReadOnly = False, TimeoutMillis = 0 },
> readTimestamp=115768606491410432]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=15, op=TxBegin, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=15, op=TxBegin, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0005875, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Tx
> started [options=TransactionOptions { ReadOnly = False, TimeoutMillis = 0 },
> readTimestamp=115768606491410432, txId=3]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=16, op=TupleUpsert, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=16, op=TupleUpsert, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0026641, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Sending request
> [requestId=17, op=TxRollback, remoteAddress=127.0.0.1:43419]
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientSocket-318] Received response
> [requestId=17, op=TxRollback, flags=0, remoteAddress=127.0.0.1:43419,
> duration=00:00:00.0044355, observableTs=0]
> [11:19:06] [trce] [Apache.Ignite.Internal.Transactions.Transactions] Tx
> rolled back [txId=3]
> [11:19:06] [dbug] [Apache.Ignite.Internal.ClientSocket-318] Connection closed
> gracefully [remoteAddress=127.0.0.1:43419]
> Error in IgniteProxy Client -> Server relay (lastOp = TxRollback:
> Apache.Ignite.Tests.IgniteServerBase+ConnectionLostException: Exception of
> type 'Apache.Ignite.Tests.IgniteServerBase+ConnectionLostException' was
> thrown.
> at Apache.Ignite.Tests.IgniteServerBase.ReceiveBytes(Socket socket, Int32
> size) in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/IgniteServerBase.cs:line
> 106
> at Apache.Ignite.Tests.IgniteServerBase.ReceiveMessageSize(Socket handler)
> in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/IgniteServerBase.cs:line
> 89
> at Apache.Ignite.Tests.IgniteProxy.<>c__DisplayClass14_0.<Handle>b__0() in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/IgniteProxy.cs:line
> 98
> [11:19:06] [trce] [Apache.Ignite.Internal.ClientFailoverSocket] ObservableTs
> updated [prev=115768606489051136, current=115768606489378816]
> [11:19:06] [dbug] [Apache.Ignite.Internal.ClientSocket-317] Handshake
> succeeded [remoteAddress=[::ffff:127.0.0.1]:10942, context=ConnectionContext
> { Version = ClientProtocolVersion { Version = 3.0.0 }, IdleTimeout =
> 00:00:06, ClusterNode = ClusterNode { Id =
> 61eec039-93d9-4d12-8fd0-24d947052989, 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 =
> b2ddf420-f7ae-40aa-ad08-804faeb03ae3 }]
> [11:19:06] [warn] [Apache.Ignite.Internal.ClientSocket-317] 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
> [11:19:06] [dbug] [Apache.Ignite.Internal.ClientFailoverSocket] 2 secondary
> connections established, 0 failed.
> TearDown start: TestPutRoutesRequestToPrimaryNode(True)
> TearDown end: TestPutRoutesRequestToPrimaryNode(True)
> Error in IgniteProxy Server -> Client relay (lastOp = TxRollback:
> System.Net.Sockets.SocketException (103): Software caused connection abort
> at Apache.Ignite.Tests.IgniteServerBase.ReceiveBytes(Socket socket, Int32
> size) in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/IgniteServerBase.cs:line
> 102
> at Apache.Ignite.Tests.IgniteServerBase.ReceiveMessageSize(Socket handler)
> in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/IgniteServerBase.cs:line
> 89
> at Apache.Ignite.Tests.IgniteProxy.<>c__DisplayClass14_0.<Handle>b__1() in
> /opt/buildagent/work/69bdbe372c424384/ignite-3/modules/platforms/dotnet/Apache.Ignite.Tests/IgniteProxy.cs:line
> 124
> {code}
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
