GCloudPostgresInProcessLifeOfAMeasurementIntegrationTest marked as failed even when it passes

[SanjayVas]

//src/test/kotlin/org/wfanet/measurement/integration/deploy/gcloud:GCloudPostgresInProcessLifeOfAMeasurementIntegrationTest is being marked as failed in the "Build and test" workflow even though the logs show that it passes.

Tail of sample test log:

Aug 18, 2023 10:32:50 PM org.wfanet.measurement.loadtest.measurementconsumer.MeasurementConsumerSimulator executeReachAndFrequency
INFO: Reach and frequency result is equal to the expected result
Aug 18, 2023 10:32:50 PM org.wfanet.measurement.common.LogExceptionsKt logException
SEVERE: Exception:
java.util.concurrent.CancellationException: Stopping LLv2 Mill
	at kotlinx.coroutines.ExceptionsKt.CancellationException(Exceptions.kt:22)
	at kotlinx.coroutines.JobKt__JobKt.cancel(Job.kt:596)
	at kotlinx.coroutines.JobKt.cancel(Unknown Source)
	at kotlinx.coroutines.JobKt__JobKt.cancel$default(Job.kt:596)
	at kotlinx.coroutines.JobKt.cancel$default(Unknown Source)
	at org.wfanet.measurement.integration.common.InProcessDuchy.stopLiquidLegionsV2Mill(InProcessDuchy.kt:240)
	at org.wfanet.measurement.integration.common.InProcessCmmsComponents$stopDuchyDaemons$1.invokeSuspend(InProcessCmmsComponents.kt:175)
	at ���(Coroutine boundary.�(�)
	at io.grpc.kotlin.ClientCalls$rpcImpl$1.invokeSuspend(ClientCalls.kt:274)
	at kotlinx.coroutines.flow.AbstractFlow.collect(Flow.kt:230)
	at io.grpc.kotlin.HelpersKt$singleOrStatusFlow$1.invokeSuspend(Helpers.kt:62)
	at kotlinx.coroutines.flow.AbstractFlow.collect(Flow.kt:230)
	at kotlinx.coroutines.flow.FlowKt__ReduceKt.single(Reduce.kt:57)
	at org.wfanet.measurement.internal.duchy.ComputationsGrpcKt$ComputationsCoroutineStub.claimWork(ComputationsServiceGrpcKt.kt:270)
	at org.wfanet.measurement.duchy.daemon.mill.MillBase.pollAndProcessNextComputation(MillBase.kt:161)
	at org.wfanet.measurement.duchy.daemon.mill.MillBase$continuallyProcessComputationQueue$2$1$1.invokeSuspend(MillBase.kt:144)
	at org.wfanet.measurement.common.LogExceptionsKt.logAndSuppressExceptionSuspend(LogExceptions.kt:35)
	at org.wfanet.measurement.duchy.daemon.mill.MillBase$continuallyProcessComputationQueue$2$1.invokeSuspend(MillBase.kt:144)
	at org.wfanet.measurement.common.throttler.MinimumIntervalThrottler.onReady(MinimumIntervalThrottler.kt:49)
	at org.wfanet.measurement.common.throttler.Throttler$DefaultImpls.loopOnReady(Throttler.kt:33)
	at org.wfanet.measurement.duchy.daemon.mill.MillBase$continuallyProcessComputationQueue$2.invokeSuspend(MillBase.kt:142)
	at org.wfanet.measurement.integration.common.InProcessDuchy$startLiquidLegionsV2mill$1.invokeSuspend(InProcessDuchy.kt:234)
	at ���(Coroutine creation stacktrace.�(�)
	at kotlin.coroutines.intrinsics.IntrinsicsKt__IntrinsicsJvmKt.createCoroutineUnintercepted(IntrinsicsJvm.kt:122)
	at kotlinx.coroutines.intrinsics.CancellableKt.startCoroutineCancellable(Cancellable.kt:30)
	at kotlinx.coroutines.BuildersKt__Builders_commonKt.launch$default(Builders.common.kt:47)
	at kotlinx.coroutines.BuildersKt.launch$default(Unknown Source)
	at org.wfanet.measurement.integration.common.InProcessDuchy.startLiquidLegionsV2mill(InProcessDuchy.kt:208)
	at org.wfanet.measurement.integration.common.InProcessCmmsComponents$startDaemons$1.invokeSuspend(InProcessCmmsComponents.kt:165)
	at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:33)
	at kotlinx.coroutines.internal.ScopeCoroutine.afterResume(Scopes.kt:33)
	at kotlinx.coroutines.AbstractCoroutine.resumeWith(AbstractCoroutine.kt:102)
	at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:46)
	at kotlinx.coroutines.DispatchedTask.run(DispatchedTask.kt:106)
	at kotlinx.coroutines.BuildersKt__BuildersKt.runBlocking$default(Builders.kt:38)
	at kotlinx.coroutines.BuildersKt.runBlocking$default(Unknown Source)
	at org.wfanet.measurement.integration.common.InProcessCmmsComponents.startDaemons(InProcessCmmsComponents.kt:156)
	at org.wfanet.measurement.integration.common.InProcessLifeOfAMeasurementIntegrationTest.startDaemons(InProcessLifeOfAMeasurementIntegrationTest.kt:77)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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.RunBefores.invokeMethod(RunBefores.java:33)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at kotlinx.coroutines.debug.junit4.CoroutinesTimeoutStatement$evaluate$$inlined$runWithTimeoutDumpingCoroutines$1.call(CoroutinesTimeoutImpl.kt:83)
	at kotlinx.coroutines.debug.junit4.CoroutinesTimeoutStatement$evaluate$$inlined$runWithTimeoutDumpingCoroutines$1.call(CoroutinesTimeoutImpl.kt:83)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.lang.Thread.run(Thread.java:829)
Caused by: java.util.concurrent.CancellationException: Stopping LLv2 Mill
	at kotlinx.coroutines.ExceptionsKt.CancellationException(Exceptions.kt:22)
	at kotlinx.coroutines.JobKt__JobKt.cancel(Job.kt:596)
	at kotlinx.coroutines.JobKt.cancel(Unknown Source)
	at kotlinx.coroutines.JobKt__JobKt.cancel$default(Job.kt:596)
	at kotlinx.coroutines.JobKt.cancel$default(Unknown Source)
	at org.wfanet.measurement.integration.common.InProcessDuchy.stopLiquidLegionsV2Mill(InProcessDuchy.kt:240)
	at org.wfanet.measurement.integration.common.InProcessCmmsComponents$stopDuchyDaemons$1.invokeSuspend(InProcessCmmsComponents.kt:175)
	at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:33)
	at kotlinx.coroutines.DispatchedTask.run(DispatchedTask.kt:106)
	at kotlinx.coroutines.EventLoopImplBase.processNextEvent(EventLoop.common.kt:284)
	at kotlinx.coroutines.BlockingCoroutine.joinBlocking(Builders.kt:85)
	at kotlinx.coroutines.BuildersKt__BuildersKt.runBlocking(Builders.kt:59)
	at kotlinx.coroutines.BuildersKt.runBlocking(Unknown Source)
	at kotlinx.coroutines.BuildersKt__BuildersKt.runBlocking$default(Builders.kt:38)
	at kotlinx.coroutines.BuildersKt.runBlocking$default(Unknown Source)
	at org.wfanet.measurement.integration.common.InProcessCmmsComponents.stopDuchyDaemons(InProcessCmmsComponents.kt:172)
	at org.wfanet.measurement.integration.common.InProcessLifeOfAMeasurementIntegrationTest.stopDuchyDaemons(InProcessLifeOfAMeasurementIntegrationTest.kt:115)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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.RunAfters.invokeMethod(RunAfters.java:46)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:33)
	... 3 more


Time: 191.405

OK (6 tests)


BazelTestRunner exiting with a return value of 0
JVM shutdown hooks (if any) will run now.
The JVM will exit once they complete.

-- JVM shutdown starting at 2023-08-18 22:32:51 --

The logged exception shouldn't affect anything, as that cancellation is part of the test tear-down. It's also being logged by a function that suppresses the exception.

I have been unable to reproduce this locally.