[2025-01-22T03:35:09.204Z] Running test renaissance-finagle-http_0 ... [2025-01-22T03:35:09.204Z] =============================================== [2025-01-22T03:35:09.204Z] renaissance-finagle-http_0 Start Time: Tue Jan 21 22:35:08 2025 Epoch Time (ms): 1737516908908 [2025-01-22T03:35:09.204Z] variation: NoOptions [2025-01-22T03:35:09.204Z] JVM_OPTIONS: [2025-01-22T03:35:09.204Z] { \ [2025-01-22T03:35:09.204Z] echo ""; echo "TEST SETUP:"; \ [2025-01-22T03:35:09.204Z] echo "Nothing to be done for setup."; \ [2025-01-22T03:35:09.204Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17375166921655/renaissance-finagle-http_0"; \ [2025-01-22T03:35:09.204Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17375166921655/renaissance-finagle-http_0"; \ [2025-01-22T03:35:09.204Z] echo ""; echo "TESTING:"; \ [2025-01-22T03:35:09.204Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17375166921655/renaissance-finagle-http_0"/finagle-http.json" finagle-http; \ [2025-01-22T03:35:09.204Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-finagle-http_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17375166921655/renaissance-finagle-http_0"; else echo "-----------------------------------"; echo "renaissance-finagle-http_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-22T03:35:09.204Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-22T03:35:09.204Z] echo "Nothing to be done for teardown."; \ [2025-01-22T03:35:09.204Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17375166921655/TestTargetResult"; [2025-01-22T03:35:09.204Z] [2025-01-22T03:35:09.204Z] TEST SETUP: [2025-01-22T03:35:09.204Z] Nothing to be done for setup. [2025-01-22T03:35:09.204Z] [2025-01-22T03:35:09.204Z] TESTING: [2025-01-22T03:35:10.036Z] [2025-01-21T22:35:09.992-0500] com.twitter.finagle.http.HttpMuxer$ (com.twitter.finagle.http.HttpMuxer$ $anonfun$new$1) [2025-01-22T03:35:10.036Z] INFO: HttpMuxer[/admin/metrics.json] = com.twitter.finagle.stats.MetricsExporter(com.twitter.finagle.stats.MetricsExporter) [2025-01-22T03:35:10.037Z] [2025-01-21T22:35:10.000-0500] com.twitter.finagle.http.HttpMuxer$ (com.twitter.finagle.http.HttpMuxer$ $anonfun$new$1) [2025-01-22T03:35:10.037Z] INFO: HttpMuxer[/admin/per_host_metrics.json] = com.twitter.finagle.stats.HostMetricsExporter(com.twitter.finagle.stats.HostMetricsExporter) [2025-01-22T03:35:10.428Z] [2025-01-21T22:35:10.189-0500] com.twitter.finagle (com.twitter.finagle.Init$ $anonfun$once$1) [2025-01-22T03:35:10.428Z] INFO: Finagle version 21.12.0 (rev=95ec9bcb3d4db4553bdb7d90f07a034a6b6be5c0) built at 20211216-002149 [2025-01-22T03:35:10.428Z] finagle-http on :49242 spawning 3 client and default number of server workers. [2025-01-22T03:35:10.428Z] ====== finagle-http (web) [default], iteration 0 started ====== [2025-01-22T03:35:10.428Z] GC before operation: completed in 21.380 ms, heap usage 24.547 MB -> 9.301 MB. [2025-01-22T03:35:10.428Z] [2025-01-21T22:35:10.314-0500] com.twitter.finagle.BaseResolver (com.twitter.finagle.BaseResolver inetResolver$lzycompute) [2025-01-22T03:35:10.428Z] INFO: Using default inet resolver [2025-01-22T03:35:15.646Z] ====== finagle-http (web) [default], iteration 0 completed (4317.194 ms) ====== [2025-01-22T03:35:15.646Z] ====== finagle-http (web) [default], iteration 1 started ====== [2025-01-22T03:35:15.646Z] GC before operation: completed in 30.114 ms, heap usage 49.801 MB -> 12.524 MB. [2025-01-22T03:35:18.191Z] ====== finagle-http (web) [default], iteration 1 completed (3012.781 ms) ====== [2025-01-22T03:35:18.191Z] ====== finagle-http (web) [default], iteration 2 started ====== [2025-01-22T03:35:18.191Z] GC before operation: completed in 28.150 ms, heap usage 41.152 MB -> 12.859 MB. [2025-01-22T03:35:20.758Z] ====== finagle-http (web) [default], iteration 2 completed (2609.217 ms) ====== [2025-01-22T03:35:20.758Z] ====== finagle-http (web) [default], iteration 3 started ====== [2025-01-22T03:35:20.758Z] GC before operation: completed in 24.486 ms, heap usage 44.052 MB -> 12.963 MB. [2025-01-22T03:35:23.354Z] ====== finagle-http (web) [default], iteration 3 completed (2636.558 ms) ====== [2025-01-22T03:35:23.354Z] ====== finagle-http (web) [default], iteration 4 started ====== [2025-01-22T03:35:23.354Z] GC before operation: completed in 25.958 ms, heap usage 31.384 MB -> 13.012 MB. [2025-01-22T03:35:25.895Z] ====== finagle-http (web) [default], iteration 4 completed (2408.158 ms) ====== [2025-01-22T03:35:25.895Z] ====== finagle-http (web) [default], iteration 5 started ====== [2025-01-22T03:35:25.895Z] GC before operation: completed in 23.196 ms, heap usage 15.016 MB -> 13.002 MB. [2025-01-22T03:35:27.788Z] ====== finagle-http (web) [default], iteration 5 completed (2262.828 ms) ====== [2025-01-22T03:35:27.788Z] ====== finagle-http (web) [default], iteration 6 started ====== [2025-01-22T03:35:27.788Z] GC before operation: completed in 24.846 ms, heap usage 25.026 MB -> 13.279 MB. [2025-01-22T03:35:30.328Z] ====== finagle-http (web) [default], iteration 6 completed (2245.963 ms) ====== [2025-01-22T03:35:30.328Z] ====== finagle-http (web) [default], iteration 7 started ====== [2025-01-22T03:35:30.328Z] GC before operation: completed in 23.348 ms, heap usage 31.505 MB -> 13.126 MB. [2025-01-22T03:35:32.274Z] ====== finagle-http (web) [default], iteration 7 completed (2198.426 ms) ====== [2025-01-22T03:35:32.274Z] ====== finagle-http (web) [default], iteration 8 started ====== [2025-01-22T03:35:32.666Z] GC before operation: completed in 27.662 ms, heap usage 16.328 MB -> 13.145 MB. [2025-01-22T03:35:34.555Z] ====== finagle-http (web) [default], iteration 8 completed (1974.925 ms) ====== [2025-01-22T03:35:34.555Z] ====== finagle-http (web) [default], iteration 9 started ====== [2025-01-22T03:35:34.555Z] GC before operation: completed in 25.385 ms, heap usage 27.993 MB -> 13.093 MB. [2025-01-22T03:35:36.446Z] ====== finagle-http (web) [default], iteration 9 completed (1971.015 ms) ====== [2025-01-22T03:35:36.446Z] ====== finagle-http (web) [default], iteration 10 started ====== [2025-01-22T03:35:36.446Z] GC before operation: completed in 29.535 ms, heap usage 69.735 MB -> 13.125 MB. [2025-01-22T03:35:38.996Z] ====== finagle-http (web) [default], iteration 10 completed (2232.459 ms) ====== [2025-01-22T03:35:38.996Z] ====== finagle-http (web) [default], iteration 11 started ====== [2025-01-22T03:35:38.996Z] GC before operation: completed in 27.824 ms, heap usage 50.070 MB -> 13.150 MB. [2025-01-22T03:35:40.885Z] ====== finagle-http (web) [default], iteration 11 completed (2023.547 ms) ====== [2025-01-22T03:35:41.272Z] ----------------------------------- [2025-01-22T03:35:41.272Z] renaissance-finagle-http_0_PASSED [2025-01-22T03:35:41.272Z] ----------------------------------- [2025-01-22T03:35:41.272Z] [2025-01-22T03:35:41.272Z] TEST TEARDOWN: [2025-01-22T03:35:41.272Z] Nothing to be done for teardown. [2025-01-22T03:35:41.272Z] renaissance-finagle-http_0 Finish Time: Tue Jan 21 22:35:41 2025 Epoch Time (ms): 1737516941115