[2026-03-05T22:27:52.434Z] Running test renaissance-als_0 ... [2026-03-05T22:27:52.434Z] =============================================== [2026-03-05T22:27:52.434Z] renaissance-als_0 Start Time: Thu Mar 5 22:27:51 2026 Epoch Time (ms): 1772749671640 [2026-03-05T22:27:52.434Z] variation: NoOptions [2026-03-05T22:27:52.434Z] JVM_OPTIONS: [2026-03-05T22:27:52.434Z] { \ [2026-03-05T22:27:52.434Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T22:27:52.434Z] echo "Nothing to be done for setup."; \ [2026-03-05T22:27:52.434Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17727494349955/renaissance-als_0"; \ [2026-03-05T22:27:52.434Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17727494349955/renaissance-als_0"; \ [2026-03-05T22:27:52.434Z] echo ""; echo "TESTING:"; \ [2026-03-05T22:27:52.434Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17727494349955/renaissance-als_0"/als.json" als; \ [2026-03-05T22:27:52.434Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17727494349955/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T22:27:52.434Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T22:27:52.434Z] echo "Nothing to be done for teardown."; \ [2026-03-05T22:27:52.434Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17727494349955/TestTargetResult"; [2026-03-05T22:27:52.434Z] [2026-03-05T22:27:52.434Z] TEST SETUP: [2026-03-05T22:27:52.434Z] Nothing to be done for setup. [2026-03-05T22:27:52.434Z] [2026-03-05T22:27:52.434Z] TESTING: [2026-03-05T22:27:53.394Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-03-05T22:27:53.394Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17727494349955/renaissance-als_0/launcher-222751-221219031216825422/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-03-05T22:27:53.394Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-03-05T22:27:53.394Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-03-05T22:28:03.198Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-03-05T22:28:09.915Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-05T22:28:09.915Z] GC before operation: completed in 72.407 ms, heap usage 277.000 MB -> 35.268 MB. [2026-03-05T22:28:43.136Z] ====== als (apache-spark) [default], iteration 0 completed (29729.582 ms) ====== [2026-03-05T22:28:43.136Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-05T22:28:43.136Z] GC before operation: completed in 122.543 ms, heap usage 269.726 MB -> 61.845 MB. [2026-03-05T22:28:54.735Z] ====== als (apache-spark) [default], iteration 1 completed (11861.910 ms) ====== [2026-03-05T22:28:54.735Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-05T22:28:54.735Z] GC before operation: completed in 115.362 ms, heap usage 503.505 MB -> 63.003 MB. [2026-03-05T22:29:04.729Z] ====== als (apache-spark) [default], iteration 2 completed (10804.067 ms) ====== [2026-03-05T22:29:04.729Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-05T22:29:04.729Z] GC before operation: completed in 127.647 ms, heap usage 529.413 MB -> 63.678 MB. [2026-03-05T22:29:17.061Z] ====== als (apache-spark) [default], iteration 3 completed (10599.786 ms) ====== [2026-03-05T22:29:17.061Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-05T22:29:17.061Z] GC before operation: completed in 122.712 ms, heap usage 243.983 MB -> 63.874 MB. [2026-03-05T22:29:26.863Z] ====== als (apache-spark) [default], iteration 4 completed (10343.429 ms) ====== [2026-03-05T22:29:26.863Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-05T22:29:26.863Z] GC before operation: completed in 137.204 ms, heap usage 230.435 MB -> 64.391 MB. [2026-03-05T22:29:41.035Z] ====== als (apache-spark) [default], iteration 5 completed (10048.603 ms) ====== [2026-03-05T22:29:41.035Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-05T22:29:41.035Z] GC before operation: completed in 127.762 ms, heap usage 283.028 MB -> 65.041 MB. [2026-03-05T22:29:50.521Z] ====== als (apache-spark) [default], iteration 6 completed (10166.609 ms) ====== [2026-03-05T22:29:50.521Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-05T22:29:50.521Z] GC before operation: completed in 133.296 ms, heap usage 215.977 MB -> 65.418 MB. [2026-03-05T22:29:59.264Z] ====== als (apache-spark) [default], iteration 7 completed (10297.695 ms) ====== [2026-03-05T22:29:59.264Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-05T22:29:59.264Z] GC before operation: completed in 156.862 ms, heap usage 394.714 MB -> 66.160 MB. [2026-03-05T22:30:09.120Z] ====== als (apache-spark) [default], iteration 8 completed (9641.794 ms) ====== [2026-03-05T22:30:09.121Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-05T22:30:09.121Z] GC before operation: completed in 137.349 ms, heap usage 489.405 MB -> 66.928 MB. [2026-03-05T22:30:19.018Z] ====== als (apache-spark) [default], iteration 9 completed (9420.802 ms) ====== [2026-03-05T22:30:19.018Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-05T22:30:19.018Z] GC before operation: completed in 135.326 ms, heap usage 316.320 MB -> 67.031 MB. [2026-03-05T22:30:29.194Z] ====== als (apache-spark) [default], iteration 10 completed (9855.925 ms) ====== [2026-03-05T22:30:29.194Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-05T22:30:30.154Z] GC before operation: completed in 143.954 ms, heap usage 454.766 MB -> 67.488 MB. [2026-03-05T22:30:39.990Z] ====== als (apache-spark) [default], iteration 11 completed (9361.022 ms) ====== [2026-03-05T22:30:39.990Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-05T22:30:39.990Z] GC before operation: completed in 135.878 ms, heap usage 219.541 MB -> 67.407 MB. [2026-03-05T22:30:50.057Z] ====== als (apache-spark) [default], iteration 12 completed (9402.503 ms) ====== [2026-03-05T22:30:50.057Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-05T22:30:50.057Z] GC before operation: completed in 138.714 ms, heap usage 133.018 MB -> 67.619 MB. [2026-03-05T22:31:00.110Z] ====== als (apache-spark) [default], iteration 13 completed (9405.299 ms) ====== [2026-03-05T22:31:00.110Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-05T22:31:00.110Z] GC before operation: completed in 177.815 ms, heap usage 158.848 MB -> 67.872 MB. [2026-03-05T22:31:10.035Z] ====== als (apache-spark) [default], iteration 14 completed (9577.114 ms) ====== [2026-03-05T22:31:10.035Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-05T22:31:10.035Z] GC before operation: completed in 139.001 ms, heap usage 351.669 MB -> 68.356 MB. [2026-03-05T22:31:19.913Z] ====== als (apache-spark) [default], iteration 15 completed (9166.566 ms) ====== [2026-03-05T22:31:19.913Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-05T22:31:19.913Z] GC before operation: completed in 157.364 ms, heap usage 205.124 MB -> 68.446 MB. [2026-03-05T22:31:30.015Z] ====== als (apache-spark) [default], iteration 16 completed (9767.987 ms) ====== [2026-03-05T22:31:30.015Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-05T22:31:30.015Z] GC before operation: completed in 144.123 ms, heap usage 215.070 MB -> 68.737 MB. [2026-03-05T22:31:39.870Z] ====== als (apache-spark) [default], iteration 17 completed (9930.820 ms) ====== [2026-03-05T22:31:39.870Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-05T22:31:39.870Z] GC before operation: completed in 140.420 ms, heap usage 95.597 MB -> 69.520 MB. [2026-03-05T22:31:49.734Z] ====== als (apache-spark) [default], iteration 18 completed (9528.166 ms) ====== [2026-03-05T22:31:49.734Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-05T22:31:49.734Z] GC before operation: completed in 155.159 ms, heap usage 273.020 MB -> 69.301 MB. [2026-03-05T22:31:59.568Z] ====== als (apache-spark) [default], iteration 19 completed (9402.171 ms) ====== [2026-03-05T22:31:59.568Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-05T22:31:59.568Z] GC before operation: completed in 135.260 ms, heap usage 298.455 MB -> 69.638 MB. [2026-03-05T22:32:10.124Z] ====== als (apache-spark) [default], iteration 20 completed (9397.888 ms) ====== [2026-03-05T22:32:10.124Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-05T22:32:10.124Z] GC before operation: completed in 147.002 ms, heap usage 122.163 MB -> 69.681 MB. [2026-03-05T22:32:19.985Z] ====== als (apache-spark) [default], iteration 21 completed (9694.978 ms) ====== [2026-03-05T22:32:19.985Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-05T22:32:19.985Z] GC before operation: completed in 153.352 ms, heap usage 264.391 MB -> 70.109 MB. [2026-03-05T22:32:29.996Z] ====== als (apache-spark) [default], iteration 22 completed (9895.070 ms) ====== [2026-03-05T22:32:29.996Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-05T22:32:29.996Z] GC before operation: completed in 151.318 ms, heap usage 364.825 MB -> 70.431 MB. [2026-03-05T22:32:41.649Z] ====== als (apache-spark) [default], iteration 23 completed (9766.608 ms) ====== [2026-03-05T22:32:41.649Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-05T22:32:41.649Z] GC before operation: completed in 146.382 ms, heap usage 241.433 MB -> 70.653 MB. [2026-03-05T22:32:49.907Z] ====== als (apache-spark) [default], iteration 24 completed (9274.234 ms) ====== [2026-03-05T22:32:49.907Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-05T22:32:52.736Z] GC before operation: completed in 140.121 ms, heap usage 347.349 MB -> 71.052 MB. [2026-03-05T22:33:00.923Z] ====== als (apache-spark) [default], iteration 25 completed (9554.683 ms) ====== [2026-03-05T22:33:00.923Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-05T22:33:00.923Z] GC before operation: completed in 153.449 ms, heap usage 595.487 MB -> 71.598 MB. [2026-03-05T22:33:10.719Z] ====== als (apache-spark) [default], iteration 26 completed (9382.473 ms) ====== [2026-03-05T22:33:10.719Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-05T22:33:10.719Z] GC before operation: completed in 150.652 ms, heap usage 463.355 MB -> 71.735 MB. [2026-03-05T22:33:19.830Z] ====== als (apache-spark) [default], iteration 27 completed (9260.432 ms) ====== [2026-03-05T22:33:19.830Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-05T22:33:19.830Z] GC before operation: completed in 153.604 ms, heap usage 159.989 MB -> 71.625 MB. [2026-03-05T22:33:29.626Z] ====== als (apache-spark) [default], iteration 28 completed (9500.325 ms) ====== [2026-03-05T22:33:29.626Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-05T22:33:29.626Z] GC before operation: completed in 149.820 ms, heap usage 131.613 MB -> 71.754 MB. [2026-03-05T22:33:41.219Z] ====== als (apache-spark) [default], iteration 29 completed (9873.154 ms) ====== [2026-03-05T22:33:41.219Z] ----------------------------------- [2026-03-05T22:33:41.219Z] renaissance-als_0_PASSED [2026-03-05T22:33:41.219Z] ----------------------------------- [2026-03-05T22:33:41.219Z] [2026-03-05T22:33:41.219Z] TEST TEARDOWN: [2026-03-05T22:33:41.219Z] Nothing to be done for teardown. [2026-03-05T22:33:41.219Z] renaissance-als_0 Finish Time: Thu Mar 5 22:33:40 2026 Epoch Time (ms): 1772750020816