[2025-11-06T02:24:28.744Z] Running test renaissance-log-regression_0 ... [2025-11-06T02:24:28.744Z] =============================================== [2025-11-06T02:24:28.744Z] renaissance-log-regression_0 Start Time: Thu Nov 6 02:24:28 2025 Epoch Time (ms): 1762395868710 [2025-11-06T02:24:28.744Z] variation: NoOptions [2025-11-06T02:24:28.744Z] JVM_OPTIONS: [2025-11-06T02:24:28.744Z] { \ [2025-11-06T02:24:28.744Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T02:24:28.744Z] echo "Nothing to be done for setup."; \ [2025-11-06T02:24:28.744Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623940021347/renaissance-log-regression_0"; \ [2025-11-06T02:24:28.744Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623940021347/renaissance-log-regression_0"; \ [2025-11-06T02:24:28.744Z] echo ""; echo "TESTING:"; \ [2025-11-06T02:24:28.744Z] "/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_17623940021347/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-06T02:24:28.744Z] 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_17623940021347/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T02:24:28.744Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T02:24:28.744Z] echo "Nothing to be done for teardown."; \ [2025-11-06T02:24:28.744Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623940021347/TestTargetResult"; [2025-11-06T02:24:28.744Z] [2025-11-06T02:24:28.744Z] TEST SETUP: [2025-11-06T02:24:28.745Z] Nothing to be done for setup. [2025-11-06T02:24:28.745Z] [2025-11-06T02:24:28.745Z] TESTING: [2025-11-06T02:24:36.548Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-06T02:24:39.826Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-06T02:24:39.826Z] GC before operation: completed in 58.902 ms, heap usage 54.425 MB -> 34.942 MB. [2025-11-06T02:24:56.730Z] ====== log-regression (apache-spark) [default], iteration 0 completed (15939.552 ms) ====== [2025-11-06T02:24:56.730Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-06T02:24:56.730Z] GC before operation: completed in 88.713 ms, heap usage 299.177 MB -> 119.545 MB. [2025-11-06T02:24:59.426Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3631.400 ms) ====== [2025-11-06T02:24:59.426Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-06T02:24:59.887Z] GC before operation: completed in 99.256 ms, heap usage 288.047 MB -> 120.064 MB. [2025-11-06T02:25:02.555Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2863.067 ms) ====== [2025-11-06T02:25:02.555Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-06T02:25:02.555Z] GC before operation: completed in 103.245 ms, heap usage 290.855 MB -> 120.493 MB. [2025-11-06T02:25:05.143Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2536.792 ms) ====== [2025-11-06T02:25:05.143Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-06T02:25:05.143Z] GC before operation: completed in 84.111 ms, heap usage 344.381 MB -> 120.880 MB. [2025-11-06T02:25:07.742Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2221.975 ms) ====== [2025-11-06T02:25:07.742Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-06T02:25:07.742Z] GC before operation: completed in 79.295 ms, heap usage 347.723 MB -> 121.230 MB. [2025-11-06T02:25:09.634Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2121.201 ms) ====== [2025-11-06T02:25:09.634Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-06T02:25:09.634Z] GC before operation: completed in 82.002 ms, heap usage 289.069 MB -> 121.228 MB. [2025-11-06T02:25:12.212Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2192.965 ms) ====== [2025-11-06T02:25:12.212Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-06T02:25:12.212Z] GC before operation: completed in 83.639 ms, heap usage 352.509 MB -> 121.572 MB. [2025-11-06T02:25:14.166Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1957.043 ms) ====== [2025-11-06T02:25:14.166Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-06T02:25:14.166Z] GC before operation: completed in 82.549 ms, heap usage 270.841 MB -> 121.605 MB. [2025-11-06T02:25:16.127Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1910.507 ms) ====== [2025-11-06T02:25:16.127Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-06T02:25:16.127Z] GC before operation: completed in 84.279 ms, heap usage 429.840 MB -> 122.282 MB. [2025-11-06T02:25:18.249Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2108.393 ms) ====== [2025-11-06T02:25:18.249Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-06T02:25:18.249Z] GC before operation: completed in 85.186 ms, heap usage 264.452 MB -> 121.950 MB. [2025-11-06T02:25:20.256Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1945.637 ms) ====== [2025-11-06T02:25:20.256Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-06T02:25:20.256Z] GC before operation: completed in 87.484 ms, heap usage 266.781 MB -> 122.166 MB. [2025-11-06T02:25:22.260Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1922.320 ms) ====== [2025-11-06T02:25:22.260Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-06T02:25:22.260Z] GC before operation: completed in 87.895 ms, heap usage 276.833 MB -> 122.348 MB. [2025-11-06T02:25:24.188Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1979.450 ms) ====== [2025-11-06T02:25:24.188Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-06T02:25:24.188Z] GC before operation: completed in 87.031 ms, heap usage 275.578 MB -> 122.610 MB. [2025-11-06T02:25:26.809Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1945.274 ms) ====== [2025-11-06T02:25:26.809Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-06T02:25:26.809Z] GC before operation: completed in 85.347 ms, heap usage 431.771 MB -> 123.436 MB. [2025-11-06T02:25:28.717Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2043.073 ms) ====== [2025-11-06T02:25:28.717Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-06T02:25:28.717Z] GC before operation: completed in 88.431 ms, heap usage 258.101 MB -> 122.881 MB. [2025-11-06T02:25:30.622Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1829.949 ms) ====== [2025-11-06T02:25:30.622Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-06T02:25:30.622Z] GC before operation: completed in 89.947 ms, heap usage 426.544 MB -> 123.673 MB. [2025-11-06T02:25:32.612Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1976.936 ms) ====== [2025-11-06T02:25:32.612Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-06T02:25:32.612Z] GC before operation: completed in 86.528 ms, heap usage 258.542 MB -> 123.411 MB. [2025-11-06T02:25:34.562Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1871.434 ms) ====== [2025-11-06T02:25:34.562Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-06T02:25:34.562Z] GC before operation: completed in 89.714 ms, heap usage 239.081 MB -> 123.521 MB. [2025-11-06T02:25:36.482Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1816.181 ms) ====== [2025-11-06T02:25:36.483Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-06T02:25:36.483Z] GC before operation: completed in 95.192 ms, heap usage 406.515 MB -> 124.261 MB. [2025-11-06T02:25:38.423Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1850.336 ms) ====== [2025-11-06T02:25:38.423Z] ----------------------------------- [2025-11-06T02:25:38.423Z] renaissance-log-regression_0_PASSED [2025-11-06T02:25:38.423Z] ----------------------------------- [2025-11-06T02:25:38.423Z] [2025-11-06T02:25:38.423Z] TEST TEARDOWN: [2025-11-06T02:25:38.423Z] Nothing to be done for teardown. [2025-11-06T02:25:38.423Z] renaissance-log-regression_0 Finish Time: Thu Nov 6 02:25:38 2025 Epoch Time (ms): 1762395938378