renaissance-finagle-http_0
[2026-02-05T09:53:00.513Z] Running test renaissance-finagle-http_0 ...
[2026-02-05T09:53:00.513Z] ===============================================
[2026-02-05T09:53:00.513Z] renaissance-finagle-http_0 Start Time: Thu Feb 5 09:53:00 2026 Epoch Time (ms): 1770285180307
[2026-02-05T09:53:00.513Z] variation: NoOptions
[2026-02-05T09:53:00.513Z] JVM_OPTIONS:
[2026-02-05T09:53:00.513Z] { \
[2026-02-05T09:53:00.513Z] echo ""; echo "TEST SETUP:"; \
[2026-02-05T09:53:00.513Z] echo "Nothing to be done for setup."; \
[2026-02-05T09:53:00.513Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17702851793950/renaissance-finagle-http_0"; \
[2026-02-05T09:53:00.513Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17702851793950/renaissance-finagle-http_0"; \
[2026-02-05T09:53:00.513Z] echo ""; echo "TESTING:"; \
[2026-02-05T09:53:00.513Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/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_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17702851793950/renaissance-finagle-http_0"/finagle-http.json" finagle-http; \
[2026-02-05T09:53:00.513Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-finagle-http_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17702851793950/renaissance-finagle-http_0"; else echo "-----------------------------------"; echo "renaissance-finagle-http_0""_FAILED"; echo "-----------------------------------"; fi; \
[2026-02-05T09:53:00.513Z] echo ""; echo "TEST TEARDOWN:"; \
[2026-02-05T09:53:00.513Z] echo "Nothing to be done for teardown."; \
[2026-02-05T09:53:00.513Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17702851793950/TestTargetResult";
[2026-02-05T09:53:00.513Z]
[2026-02-05T09:53:00.513Z] TEST SETUP:
[2026-02-05T09:53:00.513Z] Nothing to be done for setup.
[2026-02-05T09:53:00.513Z]
[2026-02-05T09:53:00.513Z] TESTING:
[2026-02-05T09:53:16.432Z] finagle-http on :39651 spawning 4 client and default number of server workers.
[2026-02-05T09:53:16.432Z] ====== finagle-http (web) [default], iteration 0 started ======
[2026-02-05T09:53:16.432Z] GC before operation: completed in 125.738 ms, heap usage 24.681 MB -> 11.658 MB.
[2026-02-05T09:55:10.336Z] ====== finagle-http (web) [default], iteration 0 completed (108282.602 ms) ======
[2026-02-05T09:55:10.336Z] ====== finagle-http (web) [default], iteration 1 started ======
[2026-02-05T09:55:10.336Z] GC before operation: completed in 343.993 ms, heap usage 120.551 MB -> 15.427 MB.
[2026-02-05T09:56:08.300Z] ====== finagle-http (web) [default], iteration 1 completed (55950.457 ms) ======
[2026-02-05T09:56:08.301Z] ====== finagle-http (web) [default], iteration 2 started ======
[2026-02-05T09:56:08.301Z] GC before operation: completed in 352.899 ms, heap usage 45.333 MB -> 15.448 MB.
[2026-02-05T09:56:36.108Z] ====== finagle-http (web) [default], iteration 2 completed (35743.487 ms) ======
[2026-02-05T09:56:36.108Z] ====== finagle-http (web) [default], iteration 3 started ======
[2026-02-05T09:56:36.108Z] GC before operation: completed in 351.995 ms, heap usage 87.155 MB -> 15.410 MB.
[2026-02-05T09:57:09.514Z] ====== finagle-http (web) [default], iteration 3 completed (33772.548 ms) ======
[2026-02-05T09:57:09.514Z] ====== finagle-http (web) [default], iteration 4 started ======
[2026-02-05T09:57:09.868Z] GC before operation: completed in 347.534 ms, heap usage 101.203 MB -> 15.532 MB.
[2026-02-05T09:57:50.180Z] ====== finagle-http (web) [default], iteration 4 completed (34991.330 ms) ======
[2026-02-05T09:57:50.180Z] ====== finagle-http (web) [default], iteration 5 started ======
[2026-02-05T09:57:50.180Z] GC before operation: completed in 381.492 ms, heap usage 35.470 MB -> 15.557 MB.
[2026-02-05T09:58:23.603Z] ====== finagle-http (web) [default], iteration 5 completed (33355.391 ms) ======
[2026-02-05T09:58:23.603Z] ====== finagle-http (web) [default], iteration 6 started ======
[2026-02-05T09:58:23.603Z] GC before operation: completed in 373.745 ms, heap usage 85.394 MB -> 15.548 MB.
[2026-02-05T09:58:57.053Z] ====== finagle-http (web) [default], iteration 6 completed (34516.582 ms) ======
[2026-02-05T09:58:57.053Z] ====== finagle-http (web) [default], iteration 7 started ======
[2026-02-05T09:58:57.053Z] GC before operation: completed in 388.304 ms, heap usage 90.705 MB -> 15.543 MB.
[2026-02-05T09:59:30.467Z] ====== finagle-http (web) [default], iteration 7 completed (33738.043 ms) ======
[2026-02-05T09:59:30.467Z] ====== finagle-http (web) [default], iteration 8 started ======
[2026-02-05T09:59:30.467Z] GC before operation: completed in 380.356 ms, heap usage 56.000 MB -> 15.544 MB.
[2026-02-05T10:00:03.980Z] ====== finagle-http (web) [default], iteration 8 completed (32584.208 ms) ======
[2026-02-05T10:00:03.980Z] ====== finagle-http (web) [default], iteration 9 started ======
[2026-02-05T10:00:03.980Z] GC before operation: completed in 380.431 ms, heap usage 64.987 MB -> 15.568 MB.
[2026-02-05T10:00:37.400Z] ====== finagle-http (web) [default], iteration 9 completed (35165.688 ms) ======
[2026-02-05T10:00:37.401Z] ====== finagle-http (web) [default], iteration 10 started ======
[2026-02-05T10:00:37.401Z] GC before operation: completed in 381.006 ms, heap usage 61.456 MB -> 15.518 MB.
[2026-02-05T10:00:56.658Z] [2026-02-05T10:00:56.012+0000] monitor (com.twitter.util.RootMonitor$$anonfun$2 applyOrElse)
[2026-02-05T10:00:56.658Z] SEVERE: Exception propagated to the root monitor!
[2026-02-05T10:00:56.658Z]
[2026-02-05T10:00:56.658Z] java.lang.IllegalMonitorStateException: attempt to unlock read lock, not locked by current thread
[2026-02-05T10:00:56.658Z] at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$Sync.unmatchedUnlockException(ReentrantReadWriteLock.java:448)
[2026-02-05T10:00:56.658Z] at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$Sync.tryReleaseShared(ReentrantReadWriteLock.java:432)
[2026-02-05T10:00:56.658Z] at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer.releaseShared(AbstractQueuedSynchronizer.java:1094)
[2026-02-05T10:00:56.658Z] at java.base/java.util.concurrent.locks.ReentrantReadWriteLock$ReadLock.unlock(ReentrantReadWriteLock.java:897)
[2026-02-05T10:00:56.658Z] at com.twitter.finagle.server.Closables.unregister(Closables.scala:73)
[2026-02-05T10:00:56.658Z] at com.twitter.finagle.server.ListeningStackServer$$anon$1.$anonfun$underlying$2(ListeningStackServer.scala:112)
[2026-02-05T10:00:56.658Z] at com.twitter.util.Future.$anonfun$ensure$1(Future.scala:1565)
[2026-02-05T10:00:56.658Z] at com.twitter.util.Future.$anonfun$ensure$1$adapted(Future.scala:1565)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Promise$Monitored.$anonfun$apply$1(Promise.scala:218)
[2026-02-05T10:00:56.659Z] at scala.runtime.java8.JFunction0$mcV$sp.apply(JFunction0$mcV$sp.scala:18)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Local$.let(Local.scala:7030)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Promise$Monitored.apply(Promise.scala:218)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Promise$WaitQueue.com$twitter$util$Promise$WaitQueue$$$anonfun$runInScheduler$1(Promise.scala:97)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Promise$WaitQueue$$anonfun$runInScheduler$2.doRun(Promise.scala:97)
[2026-02-05T10:00:56.659Z] at com.twitter.util.FiberTask.run(FiberTask.scala:14)
[2026-02-05T10:00:56.659Z] at com.twitter.concurrent.LocalScheduler$Activation.run(Scheduler.scala:167)
[2026-02-05T10:00:56.659Z] at com.twitter.concurrent.LocalScheduler$Activation.submit(Scheduler.scala:126)
[2026-02-05T10:00:56.659Z] at com.twitter.concurrent.LocalScheduler.submit(Scheduler.scala:243)
[2026-02-05T10:00:56.659Z] at com.twitter.concurrent.Scheduler$.submit(Scheduler.scala:78)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Fiber$$anon$1.submitTask(Fiber.scala:22)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Promise$WaitQueue.runInScheduler(Promise.scala:97)
[2026-02-05T10:00:56.659Z] at com.twitter.util.Promise.updateIfEmpty(Promise.scala:816)
[2026-02-05T10:00:56.659Z] at com.twitter.finagle.netty4.transport.ChannelTransport.com$twitter$finagle$netty4$transport$ChannelTransport$$fail(ChannelTransport.scala:93)
[2026-02-05T10:00:56.659Z] at com.twitter.finagle.netty4.transport.ChannelTransport$$anon$2.channelInactive(ChannelTransport.scala:175)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:303)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:281)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:274)
[2026-02-05T10:00:56.659Z] at io.netty.channel.ChannelInboundHandlerAdapter.channelInactive(ChannelInboundHandlerAdapter.java:81)
[2026-02-05T10:00:56.659Z] at io.netty.handler.codec.MessageAggregator.channelInactive(MessageAggregator.java:441)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:303)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:281)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:274)
[2026-02-05T10:00:56.659Z] at io.netty.channel.ChannelInboundHandlerAdapter.channelInactive(ChannelInboundHandlerAdapter.java:81)
[2026-02-05T10:00:56.659Z] at io.netty.handler.codec.http.HttpContentDecoder.channelInactive(HttpContentDecoder.java:235)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:303)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:281)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:274)
[2026-02-05T10:00:56.659Z] at io.netty.channel.DefaultChannelPipeline$HeadContext.channelInactive(DefaultChannelPipeline.java:1352)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:301)
[2026-02-05T10:00:56.659Z] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:281)
[2026-02-05T10:00:56.659Z] at io.netty.channel.DefaultChannelPipeline.fireChannelInactive(DefaultChannelPipeline.java:850)
[2026-02-05T10:00:56.659Z] at io.netty.handler.codec.http2.AbstractHttp2StreamChannel$Http2ChannelUnsafe$2.run(AbstractHttp2StreamChannel.java:797)
[2026-02-05T10:00:56.659Z] at io.netty.util.concurrent.AbstractEventExecutor.runTask(AbstractEventExecutor.java:173)
[2026-02-05T10:00:56.659Z] at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:166)
[2026-02-05T10:00:56.659Z] at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:472)
[2026-02-05T10:00:56.659Z] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:566)
[2026-02-05T10:00:56.659Z] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997)
[2026-02-05T10:00:56.659Z] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
[2026-02-05T10:00:56.659Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
[2026-02-05T10:00:56.659Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[2026-02-05T10:00:56.659Z] at com.twitter.finagle.util.BlockingTimeTrackingThreadFactory$$anon$1.run(BlockingTimeTrackingThreadFactory.scala:23)
[2026-02-05T10:00:56.659Z] at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
[2026-02-05T10:00:56.659Z] at java.base/java.lang.Thread.run(Thread.java:840)
[2026-02-05T10:01:12.579Z] ====== finagle-http (web) [default], iteration 10 completed (33754.297 ms) ======
[2026-02-05T10:01:12.580Z] ====== finagle-http (web) [default], iteration 11 started ======
[2026-02-05T10:01:12.580Z] GC before operation: completed in 385.108 ms, heap usage 98.723 MB -> 15.573 MB.
[2026-02-05T10:01:47.015Z] ====== finagle-http (web) [default], iteration 11 completed (32510.736 ms) ======
[2026-02-05T10:01:47.015Z] -----------------------------------
[2026-02-05T10:01:47.015Z] renaissance-finagle-http_0_PASSED
[2026-02-05T10:01:47.015Z] -----------------------------------
[2026-02-05T10:01:47.015Z]
[2026-02-05T10:01:47.015Z] TEST TEARDOWN:
[2026-02-05T10:01:47.015Z] Nothing to be done for teardown.
[2026-02-05T10:01:47.015Z] renaissance-finagle-http_0 Finish Time: Thu Feb 5 10:01:44 2026 Epoch Time (ms): 1770285704106