[2025-12-11T23:23:02.188Z] Running test renaissance-log-regression_0 ... [2025-12-11T23:23:02.188Z] =============================================== [2025-12-11T23:23:02.188Z] renaissance-log-regression_0 Start Time: Thu Dec 11 23:23:01 2025 Epoch Time (ms): 1765495381841 [2025-12-11T23:23:02.188Z] variation: NoOptions [2025-12-11T23:23:02.188Z] JVM_OPTIONS: [2025-12-11T23:23:02.188Z] { \ [2025-12-11T23:23:02.188Z] echo ""; echo "TEST SETUP:"; \ [2025-12-11T23:23:02.188Z] echo "Nothing to be done for setup."; \ [2025-12-11T23:23:02.188Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-log-regression_0"; \ [2025-12-11T23:23:02.188Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-log-regression_0"; \ [2025-12-11T23:23:02.188Z] echo ""; echo "TESTING:"; \ [2025-12-11T23:23:02.188Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-11T23:23:02.188Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-11T23:23:02.188Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-11T23:23:02.188Z] echo "Nothing to be done for teardown."; \ [2025-12-11T23:23:02.188Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/TestTargetResult"; [2025-12-11T23:23:02.188Z] [2025-12-11T23:23:02.188Z] TEST SETUP: [2025-12-11T23:23:02.188Z] Nothing to be done for setup. [2025-12-11T23:23:02.188Z] [2025-12-11T23:23:02.188Z] TESTING: [2025-12-11T23:23:13.811Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-11T23:23:19.212Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-11T23:23:19.212Z] GC before operation: completed in 114.215 ms, heap usage 63.913 MB -> 35.080 MB. [2025-12-11T23:23:44.834Z] ====== log-regression (apache-spark) [default], iteration 0 completed (25865.207 ms) ====== [2025-12-11T23:23:44.834Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-11T23:23:44.834Z] GC before operation: completed in 155.978 ms, heap usage 508.296 MB -> 120.322 MB. [2025-12-11T23:23:50.454Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5636.582 ms) ====== [2025-12-11T23:23:50.454Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-11T23:23:50.454Z] GC before operation: completed in 209.167 ms, heap usage 239.664 MB -> 120.224 MB. [2025-12-11T23:23:55.923Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4470.988 ms) ====== [2025-12-11T23:23:55.923Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-11T23:23:55.923Z] GC before operation: completed in 148.602 ms, heap usage 462.690 MB -> 121.151 MB. [2025-12-11T23:23:58.995Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3653.256 ms) ====== [2025-12-11T23:23:58.995Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-11T23:23:58.995Z] GC before operation: completed in 114.967 ms, heap usage 427.246 MB -> 121.343 MB. [2025-12-11T23:24:03.252Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3809.354 ms) ====== [2025-12-11T23:24:03.252Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-11T23:24:03.252Z] GC before operation: completed in 140.064 ms, heap usage 268.544 MB -> 121.234 MB. [2025-12-11T23:24:06.556Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3633.942 ms) ====== [2025-12-11T23:24:06.556Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-11T23:24:06.556Z] GC before operation: completed in 159.694 ms, heap usage 235.884 MB -> 121.306 MB. [2025-12-11T23:24:10.824Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3491.187 ms) ====== [2025-12-11T23:24:10.824Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-11T23:24:10.824Z] GC before operation: completed in 154.703 ms, heap usage 448.974 MB -> 122.102 MB. [2025-12-11T23:24:13.896Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3227.940 ms) ====== [2025-12-11T23:24:13.896Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-11T23:24:13.896Z] GC before operation: completed in 157.608 ms, heap usage 275.759 MB -> 121.776 MB. [2025-12-11T23:24:16.967Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3245.971 ms) ====== [2025-12-11T23:24:16.967Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-11T23:24:16.967Z] GC before operation: completed in 181.751 ms, heap usage 236.089 MB -> 121.953 MB. [2025-12-11T23:24:21.191Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3400.831 ms) ====== [2025-12-11T23:24:21.191Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-11T23:24:21.191Z] GC before operation: completed in 185.857 ms, heap usage 467.231 MB -> 122.777 MB. [2025-12-11T23:24:25.452Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3109.775 ms) ====== [2025-12-11T23:24:25.452Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-11T23:24:25.452Z] GC before operation: completed in 186.391 ms, heap usage 426.447 MB -> 122.940 MB. [2025-12-11T23:24:27.425Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3181.742 ms) ====== [2025-12-11T23:24:27.425Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-11T23:24:27.425Z] GC before operation: completed in 153.968 ms, heap usage 441.671 MB -> 123.102 MB. [2025-12-11T23:24:31.700Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3789.279 ms) ====== [2025-12-11T23:24:31.700Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-11T23:24:31.700Z] GC before operation: completed in 189.872 ms, heap usage 238.756 MB -> 122.797 MB. [2025-12-11T23:24:34.832Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3336.713 ms) ====== [2025-12-11T23:24:34.833Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-11T23:24:34.833Z] GC before operation: completed in 190.801 ms, heap usage 290.781 MB -> 123.138 MB. [2025-12-11T23:24:39.115Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3558.018 ms) ====== [2025-12-11T23:24:39.115Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-11T23:24:39.115Z] GC before operation: completed in 200.773 ms, heap usage 444.926 MB -> 123.674 MB. [2025-12-11T23:24:42.383Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3418.506 ms) ====== [2025-12-11T23:24:42.383Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-11T23:24:42.383Z] GC before operation: completed in 152.808 ms, heap usage 236.005 MB -> 123.389 MB. [2025-12-11T23:24:45.463Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3333.408 ms) ====== [2025-12-11T23:24:45.463Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-11T23:24:46.753Z] GC before operation: completed in 218.597 ms, heap usage 439.720 MB -> 124.264 MB. [2025-12-11T23:24:49.851Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3438.787 ms) ====== [2025-12-11T23:24:49.851Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-11T23:24:49.851Z] GC before operation: completed in 215.443 ms, heap usage 431.550 MB -> 124.393 MB. [2025-12-11T23:24:52.962Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3068.979 ms) ====== [2025-12-11T23:24:52.962Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-11T23:24:52.962Z] GC before operation: completed in 180.171 ms, heap usage 369.438 MB -> 124.347 MB. [2025-12-11T23:24:56.071Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3275.027 ms) ====== [2025-12-11T23:24:57.053Z] ----------------------------------- [2025-12-11T23:24:57.053Z] renaissance-log-regression_0_PASSED [2025-12-11T23:24:57.053Z] ----------------------------------- [2025-12-11T23:24:57.053Z] [2025-12-11T23:24:57.053Z] TEST TEARDOWN: [2025-12-11T23:24:57.053Z] Nothing to be done for teardown. [2025-12-11T23:24:57.053Z] renaissance-log-regression_0 Finish Time: Thu Dec 11 23:24:56 2025 Epoch Time (ms): 1765495496499