StatementTimeoutTest.testCancelDdlAutocommit is flaky

[olavloite]

[INFO] Running com.google.cloud.spanner.connection.StatementTimeoutTest
Mar 17, 2025 12:43:31 PM com.google.api.gax.grpc.InstantiatingGrpcChannelProvider logDirectPathMisconfig
WARNING: DirectPath is misconfigured. Please make sure the credential is an instance of com.google.auth.oauth2.ComputeEngineCredentials .
Mar 17, 2025 12:43:31 PM com.google.api.gax.grpc.InstantiatingGrpcChannelProvider logDirectPathMisconfig
WARNING: DirectPath is misconfigured. Please make sure the credential is an instance of com.google.auth.oauth2.ComputeEngineCredentials .
Mar 17, 2025 12:43:31 PM com.google.api.gax.grpc.InstantiatingGrpcChannelProvider logDirectPathMisconfig
WARNING: DirectPath is misconfigured. Please make sure the credential is an instance of com.google.auth.oauth2.ComputeEngineCredentials .
Mar 17, 2025 12:43:31 PM com.google.api.gax.grpc.InstantiatingGrpcChannelProvider logDirectPathMisconfig
WARNING: DirectPath is misconfigured. Please make sure the credential is an instance of com.google.auth.oauth2.ComputeEngineCredentials .
Mar 17, 2025 12:43:48 PM com.google.cloud.spanner.SessionPool closeAsync
WARNING: Leaked session. Call SessionOptions.Builder#setTrackStackTraceOfSessionCheckout(true) to start tracking the call stack trace of the thread that checked out the session.
Mar 17, 2025 12:43:48 PM com.google.cloud.spanner.SessionPool closeAsync
WARNING: Leaked session. Call SessionOptions.Builder#setTrackStackTraceOfSessionCheckout(true) to start tracking the call stack trace of the thread that checked out the session.
[ERROR] Tests run: 120, Failures: 0, Errors: 1, Skipped: 12, Time elapsed: 16.85 s <<< FAILURE! -- in com.google.cloud.spanner.connection.StatementTimeoutTest
[ERROR] com.google.cloud.spanner.connection.StatementTimeoutTest.testCancelDdlAutocommit[statementExecutorType = PLATFORM_THREAD] -- Time elapsed: 10.02 s <<< ERROR!
org.junit.runners.model.TestTimedOutException: test timed out after 10 seconds
	at sun.misc.Unsafe.park(Native Method)
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
	at com.google.common.util.concurrent.OverflowAvoidingLockSupport.parkNanos(OverflowAvoidingLockSupport.java:38)
	at com.google.common.util.concurrent.AbstractFuture.get(AbstractFuture.java:464)
	at com.google.common.util.concurrent.FluentFuture$TrustedFuture.get(FluentFuture.java:99)
	at com.google.common.util.concurrent.ForwardingFuture.get(ForwardingFuture.java:75)
	at com.google.cloud.spanner.connection.ConnectionImpl.close(ConnectionImpl.java:417)
	at com.google.cloud.spanner.connection.StatementTimeoutTest.testCancelDdlAutocommit(StatementTimeoutTest.java:1039)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:299)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:293)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.lang.Thread.run(Thread.java:750)

[olavloite]

Error:  Tests run: 120, Failures: 0, Errors: 1, Skipped: 12, Time elapsed: 137.7 s <<< FAILURE! -- in com.google.cloud.spanner.connection.StatementTimeoutTest
Error:  com.google.cloud.spanner.connection.StatementTimeoutTest.testTimeoutExceptionReadWriteAutocommitPartitioned[statementExecutorType = VIRTUAL_THREAD] -- Time elapsed: 4.480 s <<< ERROR!
com.google.cloud.spanner.SpannerException: 
FAILED_PRECONDITION: io.grpc.StatusRuntimeException: FAILED_PRECONDITION: This transaction has been invalidated by a later transaction in the same session.
Transaction id: 4
Expected: 6
	at com.google.cloud.spanner.SpannerExceptionFactory.newSpannerExceptionPreformatted(SpannerExceptionFactory.java:346)
	at com.google.cloud.spanner.SpannerExceptionFactory.fromApiException(SpannerExceptionFactory.java:366)
	at com.google.cloud.spanner.SpannerExceptionFactory.newSpannerException(SpannerExceptionFactory.java:186)
	at com.google.cloud.spanner.SpannerExceptionFactory.newSpannerException(SpannerExceptionFactory.java:115)
	at com.google.cloud.spanner.PartitionedDmlTransaction.executeStreamingPartitionedUpdate(PartitionedDmlTransaction.java:132)
	at com.google.cloud.spanner.SessionImpl.executePartitionedUpdate(SessionImpl.java:212)
	at com.google.cloud.spanner.SessionPool$PooledSession.executePartitionedUpdate(SessionPool.java:1706)
	at com.google.cloud.spanner.SessionPool$PooledSessionFuture.executePartitionedUpdate(SessionPool.java:1491)
	at com.google.cloud.spanner.DatabaseClientImpl.lambda$executePartitionedUpdateWithPooledSession$3(DatabaseClientImpl.java:353)
	at com.google.cloud.spanner.DatabaseClientImpl.runWithSessionRetry(DatabaseClientImpl.java:365)
	at com.google.cloud.spanner.DatabaseClientImpl.executePartitionedUpdateWithPooledSession(DatabaseClientImpl.java:353)
	at com.google.cloud.spanner.DatabaseClientImpl.executePartitionedUpdate(DatabaseClientImpl.java:346)
	at com.google.cloud.spanner.connection.SingleUseTransaction.lambda$executePartitionedUpdateAsync$13(SingleUseTransaction.java:714)
	at io.grpc.Context$2.call(Context.java:583)
	at com.google.common.util.concurrent.TrustedListenableFutureTask$TrustedFutureInterruptibleTask.runInterruptibly(TrustedListenableFutureTask.java:131)
	at com.google.common.util.concurrent.InterruptibleTask.run(InterruptibleTask.java:75)
	at com.google.common.util.concurrent.TrustedListenableFutureTask.run(TrustedListenableFutureTask.java:82)
	at io.opentelemetry.context.Context.lambda$wrap$1(Context.java:241)
	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:750)
Caused by: io.grpc.StatusRuntimeException: FAILED_PRECONDITION: This transaction has been invalidated by a later transaction in the same session.
Transaction id: 4
Expected: 6
	at io.grpc.Status.asRuntimeException(Status.java:532)
	at com.google.api.gax.grpc.GrpcDirectStreamController$ResponseObserverAdapter.onClose(GrpcDirectStreamController.java:148)
	at io.grpc.PartialForwardingClientCallListener.onClose(PartialForwardingClientCallListener.java:39)
	at io.grpc.ForwardingClientCallListener.onClose(ForwardingClientCallListener.java:23)
	at io.grpc.ForwardingClientCallListener$SimpleForwardingClientCallListener.onClose(ForwardingClientCallListener.java:40)
	at com.google.api.gax.grpc.ChannelPool$ReleasingClientCall$1.onClose(ChannelPool.java:569)
	at io.grpc.PartialForwardingClientCallListener.onClose(PartialForwardingClientCallListener.java:39)
	at io.grpc.ForwardingClientCallListener.onClose(ForwardingClientCallListener.java:23)
	at io.grpc.ForwardingClientCallListener$SimpleForwardingClientCallListener.onClose(ForwardingClientCallListener.java:40)
	at io.grpc.PartialForwardingClientCallListener.onClose(PartialForwardingClientCallListener.java:39)
	at io.grpc.ForwardingClientCallListener.onClose(ForwardingClientCallListener.java:23)
	at io.grpc.ForwardingClientCallListener$SimpleForwardingClientCallListener.onClose(ForwardingClientCallListener.java:40)
	at com.google.cloud.spanner.spi.v1.SpannerErrorInterceptor$1$1.onClose(SpannerErrorInterceptor.java:107)
	at io.grpc.PartialForwardingClientCallListener.onClose(PartialForwardingClientCallListener.java:39)
	at io.grpc.ForwardingClientCallListener.onClose(ForwardingClientCallListener.java:23)
	at io.grpc.ForwardingClientCallListener$SimpleForwardingClientCallListener.onClose(ForwardingClientCallListener.java:40)
	at com.google.api.gax.grpc.GrpcLoggingInterceptor$1$1.onClose(GrpcLoggingInterceptor.java:98)
	at io.grpc.internal.DelayedClientCall$DelayedListener$3.run(DelayedClientCall.java:489)
	at io.grpc.internal.DelayedClientCall$DelayedListener.delayOrExecute(DelayedClientCall.java:453)
	at io.grpc.internal.DelayedClientCall$DelayedListener.onClose(DelayedClientCall.java:486)
	at io.grpc.internal.ClientCallImpl.closeObserver(ClientCallImpl.java:564)
	at io.grpc.internal.ClientCallImpl.access$100(ClientCallImpl.java:72)
	at io.grpc.internal.ClientCallImpl$ClientStreamListenerImpl$1StreamClosed.runInternal(ClientCallImpl.java:729)
	at io.grpc.internal.ClientCallImpl$ClientStreamListenerImpl$1StreamClosed.runInContext(ClientCallImpl.java:710)
	at io.grpc.internal.ContextRunnable.run(ContextRunnable.java:37)
	at io.grpc.internal.SerializingExecutor.run(SerializingExecutor.java:133)
	... 3 more