[2025-11-13T06:01:02.610Z] Running test renaissance-log-regression_0 ... [2025-11-13T06:01:02.610Z] =============================================== [2025-11-13T06:01:02.610Z] renaissance-log-regression_0 Start Time: Thu Nov 13 06:01:02 2025 Epoch Time (ms): 1763013662427 [2025-11-13T06:01:02.610Z] variation: NoOptions [2025-11-13T06:01:02.610Z] JVM_OPTIONS: [2025-11-13T06:01:02.610Z] { \ [2025-11-13T06:01:02.610Z] echo ""; echo "TEST SETUP:"; \ [2025-11-13T06:01:02.610Z] echo "Nothing to be done for setup."; \ [2025-11-13T06:01:02.610Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17630116881135/renaissance-log-regression_0"; \ [2025-11-13T06:01:02.610Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17630116881135/renaissance-log-regression_0"; \ [2025-11-13T06:01:02.610Z] echo ""; echo "TESTING:"; \ [2025-11-13T06:01:02.610Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/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_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17630116881135/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-13T06:01:02.610Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17630116881135/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-13T06:01:02.610Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-13T06:01:02.610Z] echo "Nothing to be done for teardown."; \ [2025-11-13T06:01:02.610Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17630116881135/TestTargetResult"; [2025-11-13T06:01:02.610Z] [2025-11-13T06:01:02.610Z] TEST SETUP: [2025-11-13T06:01:02.610Z] Nothing to be done for setup. [2025-11-13T06:01:02.610Z] [2025-11-13T06:01:02.610Z] TESTING: [2025-11-13T06:01:09.377Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-13T06:01:12.439Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-13T06:01:12.439Z] GC before operation: completed in 74.719 ms, heap usage 61.415 MB -> 35.199 MB. [2025-11-13T06:01:31.793Z] ====== log-regression (apache-spark) [default], iteration 0 completed (18806.041 ms) ====== [2025-11-13T06:01:31.793Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-13T06:01:31.793Z] GC before operation: completed in 175.240 ms, heap usage 462.575 MB -> 120.223 MB. [2025-11-13T06:01:36.004Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4639.873 ms) ====== [2025-11-13T06:01:36.005Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-13T06:01:36.005Z] GC before operation: completed in 186.800 ms, heap usage 530.224 MB -> 120.876 MB. [2025-11-13T06:01:40.260Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4155.460 ms) ====== [2025-11-13T06:01:40.260Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-13T06:01:40.260Z] GC before operation: completed in 214.160 ms, heap usage 286.623 MB -> 120.646 MB. [2025-11-13T06:01:44.499Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3397.236 ms) ====== [2025-11-13T06:01:44.499Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-13T06:01:44.499Z] GC before operation: completed in 173.849 ms, heap usage 645.648 MB -> 122.013 MB. [2025-11-13T06:01:47.580Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3317.103 ms) ====== [2025-11-13T06:01:47.580Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-13T06:01:47.580Z] GC before operation: completed in 184.472 ms, heap usage 343.680 MB -> 121.332 MB. [2025-11-13T06:01:50.637Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2652.974 ms) ====== [2025-11-13T06:01:50.637Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-13T06:01:50.637Z] GC before operation: completed in 182.792 ms, heap usage 720.112 MB -> 122.697 MB. [2025-11-13T06:01:52.635Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2622.205 ms) ====== [2025-11-13T06:01:52.635Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-13T06:01:53.613Z] GC before operation: completed in 190.968 ms, heap usage 819.414 MB -> 123.269 MB. [2025-11-13T06:01:56.681Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3402.327 ms) ====== [2025-11-13T06:01:56.681Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-13T06:01:56.681Z] GC before operation: completed in 195.968 ms, heap usage 470.850 MB -> 122.398 MB. [2025-11-13T06:01:59.752Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2864.271 ms) ====== [2025-11-13T06:01:59.752Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-13T06:01:59.752Z] GC before operation: completed in 208.769 ms, heap usage 338.051 MB -> 122.188 MB. [2025-11-13T06:02:01.736Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1898.572 ms) ====== [2025-11-13T06:02:01.736Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-13T06:02:01.736Z] GC before operation: completed in 141.473 ms, heap usage 699.522 MB -> 123.586 MB. [2025-11-13T06:02:04.535Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1730.016 ms) ====== [2025-11-13T06:02:04.535Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-13T06:02:04.535Z] GC before operation: completed in 159.202 ms, heap usage 654.778 MB -> 123.599 MB. [2025-11-13T06:02:05.550Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1594.396 ms) ====== [2025-11-13T06:02:05.550Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-13T06:02:05.550Z] GC before operation: completed in 138.806 ms, heap usage 487.988 MB -> 123.333 MB. [2025-11-13T06:02:07.627Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1697.388 ms) ====== [2025-11-13T06:02:07.627Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-13T06:02:07.627Z] GC before operation: completed in 145.764 ms, heap usage 743.908 MB -> 124.291 MB. [2025-11-13T06:02:08.605Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1485.301 ms) ====== [2025-11-13T06:02:08.605Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-13T06:02:09.590Z] GC before operation: completed in 143.702 ms, heap usage 277.468 MB -> 123.115 MB. [2025-11-13T06:02:10.559Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1572.100 ms) ====== [2025-11-13T06:02:10.559Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-13T06:02:10.559Z] GC before operation: completed in 132.577 ms, heap usage 311.933 MB -> 123.414 MB. [2025-11-13T06:02:12.552Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1599.713 ms) ====== [2025-11-13T06:02:12.552Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-13T06:02:12.552Z] GC before operation: completed in 148.280 ms, heap usage 127.548 MB -> 124.943 MB. [2025-11-13T06:02:14.554Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1544.517 ms) ====== [2025-11-13T06:02:14.554Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-13T06:02:14.554Z] GC before operation: completed in 137.901 ms, heap usage 615.737 MB -> 124.829 MB. [2025-11-13T06:02:15.527Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1637.815 ms) ====== [2025-11-13T06:02:15.527Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-13T06:02:16.510Z] GC before operation: completed in 150.290 ms, heap usage 407.444 MB -> 124.396 MB. [2025-11-13T06:02:18.524Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1963.576 ms) ====== [2025-11-13T06:02:18.524Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-13T06:02:18.524Z] GC before operation: completed in 178.973 ms, heap usage 438.181 MB -> 124.662 MB. [2025-11-13T06:02:20.237Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1936.147 ms) ====== [2025-11-13T06:02:21.209Z] ----------------------------------- [2025-11-13T06:02:21.209Z] renaissance-log-regression_0_PASSED [2025-11-13T06:02:21.209Z] ----------------------------------- [2025-11-13T06:02:21.209Z] [2025-11-13T06:02:21.209Z] TEST TEARDOWN: [2025-11-13T06:02:21.209Z] Nothing to be done for teardown. [2025-11-13T06:02:21.209Z] renaissance-log-regression_0 Finish Time: Thu Nov 13 06:02:20 2025 Epoch Time (ms): 1763013740244