[2026-02-04T23:13:16.380Z] Running test renaissance-finagle-http_0 ... [2026-02-04T23:13:16.380Z] =============================================== [2026-02-04T23:13:16.380Z] renaissance-finagle-http_0 Start Time: Wed Feb 4 23:13:16 2026 Epoch Time (ms): 1770246796051 [2026-02-04T23:13:16.380Z] variation: NoOptions [2026-02-04T23:13:16.380Z] JVM_OPTIONS: [2026-02-04T23:13:16.380Z] { \ [2026-02-04T23:13:16.380Z] echo ""; echo "TEST SETUP:"; \ [2026-02-04T23:13:16.380Z] echo "Nothing to be done for setup."; \ [2026-02-04T23:13:16.380Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/aqa-tests/TKG/../TKG/output_17702467951457/renaissance-finagle-http_0"; \ [2026-02-04T23:13:16.380Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/aqa-tests/TKG/../TKG/output_17702467951457/renaissance-finagle-http_0"; \ [2026-02-04T23:13:16.380Z] echo ""; echo "TESTING:"; \ [2026-02-04T23:13:16.380Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/jdkbinary/j2sdk-image/bin/java" --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.util=ALL-UNNAMED --add-opens java.base/java.util.concurrent=ALL-UNNAMED --add-opens java.base/java.nio=ALL-UNNAMED --add-opens java.base/sun.nio.ch=ALL-UNNAMED --add-opens java.base/java.lang.invoke=ALL-UNNAMED -jar "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/aqa-tests/TKG/../TKG/output_17702467951457/renaissance-finagle-http_0"/finagle-http.json" finagle-http; \ [2026-02-04T23:13:16.380Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-finagle-http_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/aqa-tests/TKG/../TKG/output_17702467951457/renaissance-finagle-http_0"; else echo "-----------------------------------"; echo "renaissance-finagle-http_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-04T23:13:16.380Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-04T23:13:16.380Z] echo "Nothing to be done for teardown."; \ [2026-02-04T23:13:16.380Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_0/aqa-tests/TKG/../TKG/output_17702467951457/TestTargetResult"; [2026-02-04T23:13:16.380Z] [2026-02-04T23:13:16.380Z] TEST SETUP: [2026-02-04T23:13:16.380Z] Nothing to be done for setup. [2026-02-04T23:13:16.380Z] [2026-02-04T23:13:16.380Z] TESTING: [2026-02-04T23:13:32.024Z] finagle-http on :40945 spawning 4 client and default number of server workers. [2026-02-04T23:13:32.024Z] ====== finagle-http (web) [default], iteration 0 started ====== [2026-02-04T23:13:32.024Z] GC before operation: completed in 121.751 ms, heap usage 13.084 MB -> 11.087 MB. [2026-02-04T23:13:55.509Z] [2026-02-04T23:13:54.752+0000] monitor (com.twitter.util.RootMonitor$$anonfun$2 applyOrElse) [2026-02-04T23:13:55.509Z] SEVERE: Exception propagated to the root monitor! [2026-02-04T23:13:55.509Z] [2026-02-04T23:13:55.509Z] java.lang.IllegalMonitorStateException: attempt to unlock read lock, not locked by current thread [2026-02-04T23:13:55.509Z] at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$Sync.unmatchedUnlockException(ReentrantReadWriteLock.java:448) [2026-02-04T23:13:55.509Z] at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$Sync.tryReleaseShared(ReentrantReadWriteLock.java:432) [2026-02-04T23:13:55.509Z] at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer.releaseShared(AbstractQueuedSynchronizer.java:1180) [2026-02-04T23:13:55.509Z] at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$ReadLock.unlock(ReentrantReadWriteLock.java:897) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.Closables.register(Closables.scala:49) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.ListeningStackServer$$anon$1.$anonfun$underlying$1(ListeningStackServer.scala:110) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.ListeningStackServer$$anon$1.$anonfun$underlying$1$adapted(ListeningStackServer.scala:108) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.StdStackServer.mkSession$1(StdStackServer.scala:97) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.StdStackServer.$anonfun$newListeningServer$3(StdStackServer.scala:103) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.StdStackServer.$anonfun$newListeningServer$3$adapted(StdStackServer.scala:101) [2026-02-04T23:13:55.509Z] at com.twitter.util.ConstFuture.com$twitter$util$ConstFuture$$$anonfun$respond$1(ConstFuture.scala:30) [2026-02-04T23:13:55.509Z] at com.twitter.util.ConstFuture$$anonfun$respond$2.doRun(ConstFuture.scala:21) [2026-02-04T23:13:55.509Z] at com.twitter.util.FiberTask.run(FiberTask.scala:14) [2026-02-04T23:13:55.509Z] at com.twitter.concurrent.LocalScheduler$Activation.run(Scheduler.scala:167) [2026-02-04T23:13:55.509Z] at com.twitter.concurrent.LocalScheduler$Activation.submit(Scheduler.scala:126) [2026-02-04T23:13:55.509Z] at com.twitter.concurrent.LocalScheduler.submit(Scheduler.scala:243) [2026-02-04T23:13:55.509Z] at com.twitter.concurrent.Scheduler$.submit(Scheduler.scala:78) [2026-02-04T23:13:55.509Z] at com.twitter.util.Fiber$$anon$1.submitTask(Fiber.scala:22) [2026-02-04T23:13:55.509Z] at com.twitter.util.ConstFuture.respond(ConstFuture.scala:21) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.StdStackServer.$anonfun$newListeningServer$1(StdStackServer.scala:101) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.server.StdStackServer.$anonfun$newListeningServer$1$adapted(StdStackServer.scala:83) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.netty4.channel.ServerBridge.channelActive(ServerBridge.scala:21) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelActive(AbstractChannelHandlerContext.java:260) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelActive(AbstractChannelHandlerContext.java:238) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.fireChannelActive(AbstractChannelHandlerContext.java:231) [2026-02-04T23:13:55.509Z] at io.netty.channel.DefaultChannelPipeline$HeadContext.channelActive(DefaultChannelPipeline.java:1345) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelActive(AbstractChannelHandlerContext.java:258) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelActive(AbstractChannelHandlerContext.java:238) [2026-02-04T23:13:55.509Z] at io.netty.channel.DefaultChannelPipeline.fireChannelActive(DefaultChannelPipeline.java:844) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.AbstractHttp2StreamChannel$Http2ChannelUnsafe.register(AbstractHttp2StreamChannel.java:686) [2026-02-04T23:13:55.509Z] at io.netty.channel.SingleThreadEventLoop.register(SingleThreadEventLoop.java:89) [2026-02-04T23:13:55.509Z] at io.netty.channel.SingleThreadEventLoop.register(SingleThreadEventLoop.java:83) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.Http2MultiplexHandler.userEventTriggered(Http2MultiplexHandler.java:252) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeUserEventTriggered(AbstractChannelHandlerContext.java:398) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeUserEventTriggered(AbstractChannelHandlerContext.java:376) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.fireUserEventTriggered(AbstractChannelHandlerContext.java:368) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.Http2FrameCodec.onHttp2StreamStateChanged(Http2FrameCodec.java:713) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.Http2FrameCodec.onStreamActive0(Http2FrameCodec.java:503) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.Http2FrameCodec.access$700(Http2FrameCodec.java:146) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.Http2FrameCodec$ConnectionListener.onStreamActive(Http2FrameCodec.java:518) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2Connection$ActiveStreams.addToActiveStreams(DefaultHttp2Connection.java:1022) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2Connection$ActiveStreams.activate(DefaultHttp2Connection.java:977) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2Connection$DefaultStream.activate(DefaultHttp2Connection.java:504) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2Connection$DefaultEndpoint.createStream(DefaultHttp2Connection.java:781) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2Connection$DefaultEndpoint.createStream(DefaultHttp2Connection.java:685) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2ConnectionDecoder$FrameReadListener.onHeadersRead(DefaultHttp2ConnectionDecoder.java:360) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2ConnectionDecoder$FrameReadListener.onHeadersRead(DefaultHttp2ConnectionDecoder.java:350) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2FrameReader$2.processFragment(DefaultHttp2FrameReader.java:475) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2FrameReader.readHeadersFrame(DefaultHttp2FrameReader.java:483) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2FrameReader.processPayloadState(DefaultHttp2FrameReader.java:247) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2FrameReader.readFrame(DefaultHttp2FrameReader.java:164) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DefaultHttp2ConnectionDecoder.decodeFrame(DefaultHttp2ConnectionDecoder.java:186) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DecoratingHttp2ConnectionDecoder.decodeFrame(DecoratingHttp2ConnectionDecoder.java:61) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.DecoratingHttp2ConnectionDecoder.decodeFrame(DecoratingHttp2ConnectionDecoder.java:61) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.Http2ConnectionHandler$FrameDecoder.decode(Http2ConnectionHandler.java:391) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.http2.Http2ConnectionHandler.decode(Http2ConnectionHandler.java:451) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.ByteToMessageDecoder.decodeRemovalReentryProtection(ByteToMessageDecoder.java:530) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:469) [2026-02-04T23:13:55.509Z] at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:290) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) [2026-02-04T23:13:55.509Z] at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1357) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) [2026-02-04T23:13:55.509Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) [2026-02-04T23:13:55.509Z] at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:868) [2026-02-04T23:13:55.509Z] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) [2026-02-04T23:13:55.509Z] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) [2026-02-04T23:13:55.509Z] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) [2026-02-04T23:13:55.509Z] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) [2026-02-04T23:13:55.509Z] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) [2026-02-04T23:13:55.509Z] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) [2026-02-04T23:13:55.509Z] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [2026-02-04T23:13:55.509Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) [2026-02-04T23:13:55.509Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) [2026-02-04T23:13:55.509Z] at com.twitter.finagle.util.BlockingTimeTrackingThreadFactory$$anon$1.run(BlockingTimeTrackingThreadFactory.scala:23) [2026-02-04T23:13:55.509Z] at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [2026-02-04T23:13:55.509Z] at java.base/java.lang.Thread.run(Thread.java:1583) [2026-02-04T23:15:34.119Z] ====== finagle-http (web) [default], iteration 0 completed (109121.443 ms) ====== [2026-02-04T23:15:34.119Z] ====== finagle-http (web) [default], iteration 1 started ====== [2026-02-04T23:15:34.119Z] GC before operation: completed in 547.567 ms, heap usage 61.484 MB -> 14.469 MB. [2026-02-04T23:16:22.681Z] ====== finagle-http (web) [default], iteration 1 completed (59634.415 ms) ====== [2026-02-04T23:16:22.681Z] ====== finagle-http (web) [default], iteration 2 started ====== [2026-02-04T23:16:22.681Z] GC before operation: completed in 568.781 ms, heap usage 36.993 MB -> 14.629 MB. [2026-02-04T23:17:03.219Z] ====== finagle-http (web) [default], iteration 2 completed (37966.584 ms) ====== [2026-02-04T23:17:03.219Z] ====== finagle-http (web) [default], iteration 3 started ====== [2026-02-04T23:17:03.219Z] GC before operation: completed in 405.897 ms, heap usage 28.350 MB -> 14.462 MB. [2026-02-04T23:17:36.983Z] ====== finagle-http (web) [default], iteration 3 completed (34549.899 ms) ====== [2026-02-04T23:17:36.983Z] ====== finagle-http (web) [default], iteration 4 started ====== [2026-02-04T23:17:36.983Z] GC before operation: completed in 375.756 ms, heap usage 29.325 MB -> 14.493 MB. [2026-02-04T23:18:11.110Z] ====== finagle-http (web) [default], iteration 4 completed (35578.784 ms) ====== [2026-02-04T23:18:11.110Z] ====== finagle-http (web) [default], iteration 5 started ====== [2026-02-04T23:18:11.110Z] GC before operation: completed in 377.848 ms, heap usage 95.705 MB -> 14.478 MB. [2026-02-04T23:18:44.781Z] ====== finagle-http (web) [default], iteration 5 completed (34971.185 ms) ====== [2026-02-04T23:18:44.782Z] ====== finagle-http (web) [default], iteration 6 started ====== [2026-02-04T23:18:44.782Z] GC before operation: completed in 368.774 ms, heap usage 82.244 MB -> 14.530 MB. [2026-02-04T23:19:18.402Z] ====== finagle-http (web) [default], iteration 6 completed (32992.491 ms) ====== [2026-02-04T23:19:18.402Z] ====== finagle-http (web) [default], iteration 7 started ====== [2026-02-04T23:19:18.402Z] GC before operation: completed in 368.488 ms, heap usage 41.837 MB -> 14.487 MB. [2026-02-04T23:19:59.042Z] ====== finagle-http (web) [default], iteration 7 completed (35460.370 ms) ====== [2026-02-04T23:19:59.042Z] ====== finagle-http (web) [default], iteration 8 started ====== [2026-02-04T23:19:59.042Z] GC before operation: completed in 385.801 ms, heap usage 27.197 MB -> 14.493 MB. [2026-02-04T23:20:32.702Z] ====== finagle-http (web) [default], iteration 8 completed (33566.780 ms) ====== [2026-02-04T23:20:32.702Z] ====== finagle-http (web) [default], iteration 9 started ====== [2026-02-04T23:20:32.702Z] GC before operation: completed in 349.445 ms, heap usage 44.163 MB -> 15.187 MB. [2026-02-04T23:21:06.352Z] ====== finagle-http (web) [default], iteration 9 completed (35796.393 ms) ====== [2026-02-04T23:21:06.352Z] ====== finagle-http (web) [default], iteration 10 started ====== [2026-02-04T23:21:06.352Z] GC before operation: completed in 369.267 ms, heap usage 35.118 MB -> 14.492 MB. [2026-02-06T00:07:05.904Z] Cancelling nested steps due to timeout [2026-02-06T00:07:05.936Z] Sending interrupt signal to process [2026-02-06T00:07:20.208Z] ----------------------------------- [2026-02-06T00:07:20.208Z] renaissance-finagle-http_0_FAILED [2026-02-06T00:07:20.208Z] ----------------------------------- [2026-02-06T00:07:20.208Z] [2026-02-06T00:07:20.208Z] TEST TEARDOWN: [2026-02-06T00:07:20.208Z] Nothing to be done for teardown. [2026-02-06T00:07:20.208Z] renaissance-finagle-http_0 Finish Time: Fri Feb 6 00:07:07 2026 Epoch Time (ms): 1770336427144