[2026-01-11T17:11:16.184Z] Running test renaissance-als_0 ... [2026-01-11T17:11:16.184Z] =============================================== [2026-01-11T17:11:16.184Z] renaissance-als_0 Start Time: Sun Jan 11 17:11:15 2026 Epoch Time (ms): 1768151475999 [2026-01-11T17:11:16.184Z] variation: NoOptions [2026-01-11T17:11:16.184Z] JVM_OPTIONS: [2026-01-11T17:11:16.184Z] { \ [2026-01-11T17:11:16.184Z] echo ""; echo "TEST SETUP:"; \ [2026-01-11T17:11:16.184Z] echo "Nothing to be done for setup."; \ [2026-01-11T17:11:16.184Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17681514749487/renaissance-als_0"; \ [2026-01-11T17:11:16.184Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17681514749487/renaissance-als_0"; \ [2026-01-11T17:11:16.184Z] echo ""; echo "TESTING:"; \ [2026-01-11T17:11:16.184Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_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_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17681514749487/renaissance-als_0"/als.json" als; \ [2026-01-11T17:11:16.184Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17681514749487/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-11T17:11:16.184Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-11T17:11:16.184Z] echo "Nothing to be done for teardown."; \ [2026-01-11T17:11:16.184Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17681514749487/TestTargetResult"; [2026-01-11T17:11:16.184Z] [2026-01-11T17:11:16.184Z] TEST SETUP: [2026-01-11T17:11:16.184Z] Nothing to be done for setup. [2026-01-11T17:11:16.184Z] [2026-01-11T17:11:16.184Z] TESTING: [2026-01-11T17:11:19.171Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-11T17:11:19.171Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/output_17681514749487/renaissance-als_0/launcher-171116-4702758922160509820/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-11T17:11:19.171Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-11T17:11:19.171Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-11T17:11:42.297Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-11T17:11:58.244Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-11T17:11:58.244Z] GC before operation: completed in 207.714 ms, heap usage 63.872 MB -> 35.563 MB. [2026-01-11T17:13:21.498Z] ====== als (apache-spark) [default], iteration 0 completed (67782.819 ms) ====== [2026-01-11T17:13:21.498Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-11T17:13:21.498Z] GC before operation: completed in 679.219 ms, heap usage 129.966 MB -> 75.575 MB. [2026-01-11T17:13:37.462Z] ====== als (apache-spark) [default], iteration 1 completed (25223.956 ms) ====== [2026-01-11T17:13:37.462Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-11T17:13:37.462Z] GC before operation: completed in 680.037 ms, heap usage 344.707 MB -> 76.502 MB. [2026-01-11T17:14:00.624Z] ====== als (apache-spark) [default], iteration 2 completed (23044.833 ms) ====== [2026-01-11T17:14:00.624Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-11T17:14:00.958Z] GC before operation: completed in 713.695 ms, heap usage 167.777 MB -> 76.976 MB. [2026-01-11T17:14:24.144Z] ====== als (apache-spark) [default], iteration 3 completed (22060.835 ms) ====== [2026-01-11T17:14:24.144Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-11T17:14:24.876Z] GC before operation: completed in 709.970 ms, heap usage 519.284 MB -> 78.085 MB. [2026-01-11T17:14:48.009Z] ====== als (apache-spark) [default], iteration 4 completed (20482.869 ms) ====== [2026-01-11T17:14:48.009Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-11T17:14:48.009Z] GC before operation: completed in 749.356 ms, heap usage 215.279 MB -> 78.032 MB. [2026-01-11T17:15:11.148Z] ====== als (apache-spark) [default], iteration 5 completed (20196.801 ms) ====== [2026-01-11T17:15:11.148Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-11T17:15:11.148Z] GC before operation: completed in 714.094 ms, heap usage 144.623 MB -> 78.471 MB. [2026-01-11T17:15:30.389Z] ====== als (apache-spark) [default], iteration 6 completed (19857.316 ms) ====== [2026-01-11T17:15:30.389Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-11T17:15:30.728Z] GC before operation: completed in 720.725 ms, heap usage 273.385 MB -> 79.215 MB. [2026-01-11T17:15:53.878Z] ====== als (apache-spark) [default], iteration 7 completed (19622.792 ms) ====== [2026-01-11T17:15:53.878Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-11T17:15:53.878Z] GC before operation: completed in 802.920 ms, heap usage 568.452 MB -> 80.265 MB. [2026-01-11T17:16:13.427Z] ====== als (apache-spark) [default], iteration 8 completed (19949.084 ms) ====== [2026-01-11T17:16:13.427Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-11T17:16:13.783Z] GC before operation: completed in 777.467 ms, heap usage 1.492 GB -> 81.710 MB. [2026-01-11T17:16:36.951Z] ====== als (apache-spark) [default], iteration 9 completed (20066.982 ms) ====== [2026-01-11T17:16:36.951Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-11T17:16:36.951Z] GC before operation: completed in 768.126 ms, heap usage 376.423 MB -> 80.927 MB. [2026-01-11T17:16:56.221Z] ====== als (apache-spark) [default], iteration 10 completed (19545.184 ms) ====== [2026-01-11T17:16:56.221Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-11T17:16:56.561Z] GC before operation: completed in 769.391 ms, heap usage 475.093 MB -> 81.076 MB. [2026-01-11T17:17:19.727Z] ====== als (apache-spark) [default], iteration 11 completed (19307.857 ms) ====== [2026-01-11T17:17:19.727Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-11T17:17:19.727Z] GC before operation: completed in 791.403 ms, heap usage 241.912 MB -> 80.903 MB. [2026-01-11T17:17:38.960Z] ====== als (apache-spark) [default], iteration 12 completed (19280.402 ms) ====== [2026-01-11T17:17:38.960Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-11T17:17:38.960Z] GC before operation: completed in 806.866 ms, heap usage 336.929 MB -> 81.476 MB. [2026-01-11T17:18:02.127Z] ====== als (apache-spark) [default], iteration 13 completed (19090.543 ms) ====== [2026-01-11T17:18:02.127Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-11T17:18:02.127Z] GC before operation: completed in 816.388 ms, heap usage 160.849 MB -> 81.601 MB. [2026-01-11T17:18:21.372Z] ====== als (apache-spark) [default], iteration 14 completed (19097.193 ms) ====== [2026-01-11T17:18:21.372Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-11T17:18:21.372Z] GC before operation: completed in 830.310 ms, heap usage 304.381 MB -> 81.810 MB. [2026-01-11T17:18:40.632Z] ====== als (apache-spark) [default], iteration 15 completed (18805.977 ms) ====== [2026-01-11T17:18:40.632Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-11T17:18:40.632Z] GC before operation: completed in 896.564 ms, heap usage 394.604 MB -> 82.298 MB. [2026-01-11T17:19:03.824Z] ====== als (apache-spark) [default], iteration 16 completed (18870.442 ms) ====== [2026-01-11T17:19:03.824Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-11T17:19:03.824Z] GC before operation: completed in 838.288 ms, heap usage 183.896 MB -> 82.262 MB. [2026-01-11T17:19:23.120Z] ====== als (apache-spark) [default], iteration 17 completed (18977.850 ms) ====== [2026-01-11T17:19:23.120Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-11T17:19:23.120Z] GC before operation: completed in 858.443 ms, heap usage 480.175 MB -> 82.863 MB. [2026-01-11T17:19:42.364Z] ====== als (apache-spark) [default], iteration 18 completed (18904.599 ms) ====== [2026-01-11T17:19:42.364Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-11T17:19:42.701Z] GC before operation: completed in 866.496 ms, heap usage 345.881 MB -> 82.911 MB. [2026-01-11T17:20:02.460Z] ====== als (apache-spark) [default], iteration 19 completed (19267.556 ms) ====== [2026-01-11T17:20:02.460Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-11T17:20:03.652Z] GC before operation: completed in 874.265 ms, heap usage 476.406 MB -> 83.516 MB. [2026-01-11T17:20:22.910Z] ====== als (apache-spark) [default], iteration 20 completed (18895.740 ms) ====== [2026-01-11T17:20:22.910Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-11T17:20:24.114Z] GC before operation: completed in 867.003 ms, heap usage 399.740 MB -> 83.597 MB. [2026-01-11T17:20:47.285Z] ====== als (apache-spark) [default], iteration 21 completed (19212.075 ms) ====== [2026-01-11T17:20:47.285Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-11T17:20:47.285Z] GC before operation: completed in 890.364 ms, heap usage 577.015 MB -> 84.126 MB. [2026-01-11T17:21:06.498Z] ====== als (apache-spark) [default], iteration 22 completed (19119.741 ms) ====== [2026-01-11T17:21:06.498Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-11T17:21:06.498Z] GC before operation: completed in 867.101 ms, heap usage 253.275 MB -> 84.016 MB. [2026-01-11T17:21:25.725Z] ====== als (apache-spark) [default], iteration 23 completed (18571.721 ms) ====== [2026-01-11T17:21:25.725Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-11T17:21:26.059Z] GC before operation: completed in 892.714 ms, heap usage 560.752 MB -> 84.602 MB. [2026-01-11T17:21:49.271Z] ====== als (apache-spark) [default], iteration 24 completed (19430.124 ms) ====== [2026-01-11T17:21:49.271Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-11T17:21:49.271Z] GC before operation: completed in 891.462 ms, heap usage 282.055 MB -> 84.474 MB. [2026-01-11T17:22:08.578Z] ====== als (apache-spark) [default], iteration 25 completed (18712.383 ms) ====== [2026-01-11T17:22:08.578Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-11T17:22:08.578Z] GC before operation: completed in 925.136 ms, heap usage 199.164 MB -> 84.631 MB. [2026-01-11T17:22:27.809Z] ====== als (apache-spark) [default], iteration 26 completed (18394.464 ms) ====== [2026-01-11T17:22:27.809Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-11T17:22:27.809Z] GC before operation: completed in 893.495 ms, heap usage 145.643 MB -> 84.434 MB. [2026-01-11T17:22:47.063Z] ====== als (apache-spark) [default], iteration 27 completed (18693.963 ms) ====== [2026-01-11T17:22:47.063Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-11T17:22:47.813Z] GC before operation: completed in 917.050 ms, heap usage 346.197 MB -> 85.366 MB. [2026-01-11T17:23:07.038Z] ====== als (apache-spark) [default], iteration 28 completed (18600.998 ms) ====== [2026-01-11T17:23:07.038Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-11T17:23:08.232Z] GC before operation: completed in 917.808 ms, heap usage 460.614 MB -> 85.853 MB. [2026-01-11T17:23:27.473Z] ====== als (apache-spark) [default], iteration 29 completed (18096.321 ms) ====== [2026-01-11T17:23:29.158Z] ----------------------------------- [2026-01-11T17:23:29.158Z] renaissance-als_0_PASSED [2026-01-11T17:23:29.158Z] ----------------------------------- [2026-01-11T17:23:29.158Z] [2026-01-11T17:23:29.158Z] TEST TEARDOWN: [2026-01-11T17:23:29.158Z] Nothing to be done for teardown. [2026-01-11T17:23:29.490Z] renaissance-als_0 Finish Time: Sun Jan 11 17:23:29 2026 Epoch Time (ms): 1768152209166