[2025-08-30T11:16:39.144Z] Running test renaissance-log-regression_0 ... [2025-08-30T11:16:39.144Z] =============================================== [2025-08-30T11:16:39.144Z] renaissance-log-regression_0 Start Time: Sat Aug 30 11:16:38 2025 Epoch Time (ms): 1756552598226 [2025-08-30T11:16:39.144Z] variation: NoOptions [2025-08-30T11:16:39.144Z] JVM_OPTIONS: [2025-08-30T11:16:39.144Z] { \ [2025-08-30T11:16:39.144Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T11:16:39.144Z] echo "Nothing to be done for setup."; \ [2025-08-30T11:16:39.144Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17565519492103/renaissance-log-regression_0"; \ [2025-08-30T11:16:39.144Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17565519492103/renaissance-log-regression_0"; \ [2025-08-30T11:16:39.144Z] echo ""; echo "TESTING:"; \ [2025-08-30T11:16:39.144Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux_rerun/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_rerun/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17565519492103/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-30T11:16:39.144Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17565519492103/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T11:16:39.144Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T11:16:39.144Z] echo "Nothing to be done for teardown."; \ [2025-08-30T11:16:39.144Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17565519492103/TestTargetResult"; [2025-08-30T11:16:39.144Z] [2025-08-30T11:16:39.144Z] TEST SETUP: [2025-08-30T11:16:39.144Z] Nothing to be done for setup. [2025-08-30T11:16:39.144Z] [2025-08-30T11:16:39.144Z] TESTING: [2025-08-30T11:16:48.933Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-30T11:16:54.362Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-30T11:16:54.362Z] GC before operation: completed in 75.804 ms, heap usage 70.984 MB -> 35.140 MB. [2025-08-30T11:17:15.699Z] ====== log-regression (apache-spark) [default], iteration 0 completed (20492.451 ms) ====== [2025-08-30T11:17:15.699Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-30T11:17:15.699Z] GC before operation: completed in 211.960 ms, heap usage 330.978 MB -> 119.758 MB. [2025-08-30T11:17:19.880Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5089.997 ms) ====== [2025-08-30T11:17:19.880Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-30T11:17:19.880Z] GC before operation: completed in 132.581 ms, heap usage 795.587 MB -> 121.674 MB. [2025-08-30T11:17:24.076Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4345.121 ms) ====== [2025-08-30T11:17:24.076Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-30T11:17:24.076Z] GC before operation: completed in 152.461 ms, heap usage 404.094 MB -> 120.935 MB. [2025-08-30T11:17:28.267Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3521.459 ms) ====== [2025-08-30T11:17:28.267Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-30T11:17:28.267Z] GC before operation: completed in 148.980 ms, heap usage 580.357 MB -> 121.762 MB. [2025-08-30T11:17:31.310Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3277.373 ms) ====== [2025-08-30T11:17:31.310Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-30T11:17:31.310Z] GC before operation: completed in 144.002 ms, heap usage 546.878 MB -> 121.978 MB. [2025-08-30T11:17:34.320Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3026.264 ms) ====== [2025-08-30T11:17:34.320Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-30T11:17:34.320Z] GC before operation: completed in 166.837 ms, heap usage 370.749 MB -> 121.588 MB. [2025-08-30T11:17:37.345Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2550.083 ms) ====== [2025-08-30T11:17:37.345Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-30T11:17:37.345Z] GC before operation: completed in 201.562 ms, heap usage 602.971 MB -> 122.572 MB. [2025-08-30T11:17:40.358Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2644.078 ms) ====== [2025-08-30T11:17:40.358Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-30T11:17:40.358Z] GC before operation: completed in 189.721 ms, heap usage 565.914 MB -> 122.556 MB. [2025-08-30T11:17:43.391Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2824.314 ms) ====== [2025-08-30T11:17:43.391Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-30T11:17:43.391Z] GC before operation: completed in 185.543 ms, heap usage 341.488 MB -> 122.127 MB. [2025-08-30T11:17:46.414Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3141.590 ms) ====== [2025-08-30T11:17:46.414Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-30T11:17:46.414Z] GC before operation: completed in 186.599 ms, heap usage 524.532 MB -> 122.881 MB. [2025-08-30T11:17:49.442Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2932.745 ms) ====== [2025-08-30T11:17:49.442Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-30T11:17:49.442Z] GC before operation: completed in 176.425 ms, heap usage 571.550 MB -> 123.284 MB. [2025-08-30T11:17:52.457Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2752.976 ms) ====== [2025-08-30T11:17:52.457Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-30T11:17:52.457Z] GC before operation: completed in 192.341 ms, heap usage 336.364 MB -> 122.885 MB. [2025-08-30T11:17:54.403Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2333.205 ms) ====== [2025-08-30T11:17:54.403Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-30T11:17:55.354Z] GC before operation: completed in 224.427 ms, heap usage 544.848 MB -> 123.635 MB. [2025-08-30T11:17:58.389Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2824.578 ms) ====== [2025-08-30T11:17:58.389Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-30T11:17:58.389Z] GC before operation: completed in 213.662 ms, heap usage 507.535 MB -> 123.631 MB. [2025-08-30T11:18:01.037Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2626.899 ms) ====== [2025-08-30T11:18:01.037Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-30T11:18:01.999Z] GC before operation: completed in 177.076 ms, heap usage 542.170 MB -> 123.987 MB. [2025-08-30T11:18:03.987Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2579.694 ms) ====== [2025-08-30T11:18:03.987Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-30T11:18:03.987Z] GC before operation: completed in 206.486 ms, heap usage 458.054 MB -> 123.858 MB. [2025-08-30T11:18:05.956Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2504.510 ms) ====== [2025-08-30T11:18:05.956Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-30T11:18:05.956Z] GC before operation: completed in 175.810 ms, heap usage 389.309 MB -> 123.839 MB. [2025-08-30T11:18:08.971Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2478.728 ms) ====== [2025-08-30T11:18:08.971Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-30T11:18:08.971Z] GC before operation: completed in 245.890 ms, heap usage 386.301 MB -> 124.054 MB. [2025-08-30T11:18:12.006Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2498.885 ms) ====== [2025-08-30T11:18:12.007Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-30T11:18:12.007Z] GC before operation: completed in 226.039 ms, heap usage 386.086 MB -> 124.250 MB. [2025-08-30T11:18:13.984Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2445.770 ms) ====== [2025-08-30T11:18:14.940Z] ----------------------------------- [2025-08-30T11:18:14.940Z] renaissance-log-regression_0_PASSED [2025-08-30T11:18:14.940Z] ----------------------------------- [2025-08-30T11:18:14.940Z] [2025-08-30T11:18:14.940Z] TEST TEARDOWN: [2025-08-30T11:18:14.940Z] Nothing to be done for teardown. [2025-08-30T11:18:14.940Z] renaissance-log-regression_0 Finish Time: Sat Aug 30 11:18:14 2025 Epoch Time (ms): 1756552694313