[2026-03-05T22:29:38.575Z] Running test renaissance-als_0 ... [2026-03-05T22:29:38.575Z] =============================================== [2026-03-05T22:29:38.575Z] renaissance-als_0 Start Time: Thu Mar 5 22:29:38 2026 Epoch Time (ms): 1772749778479 [2026-03-05T22:29:38.575Z] variation: NoOptions [2026-03-05T22:29:38.575Z] JVM_OPTIONS: [2026-03-05T22:29:38.575Z] { \ [2026-03-05T22:29:38.575Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T22:29:38.575Z] echo "Nothing to be done for setup."; \ [2026-03-05T22:29:38.575Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17727495825198/renaissance-als_0"; \ [2026-03-05T22:29:38.575Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17727495825198/renaissance-als_0"; \ [2026-03-05T22:29:38.575Z] echo ""; echo "TESTING:"; \ [2026-03-05T22:29:38.575Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17727495825198/renaissance-als_0"/als.json" als; \ [2026-03-05T22:29:38.575Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17727495825198/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T22:29:38.575Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T22:29:38.575Z] echo "Nothing to be done for teardown."; \ [2026-03-05T22:29:38.575Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17727495825198/TestTargetResult"; [2026-03-05T22:29:38.575Z] [2026-03-05T22:29:38.575Z] TEST SETUP: [2026-03-05T22:29:38.575Z] Nothing to be done for setup. [2026-03-05T22:29:38.575Z] [2026-03-05T22:29:38.575Z] TESTING: [2026-03-05T22:29:39.300Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-03-05T22:29:39.300Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17727495825198/renaissance-als_0/launcher-222938-5182797843403581628/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-03-05T22:29:39.300Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-03-05T22:29:39.300Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-03-05T22:29:45.298Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-03-05T22:29:50.160Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-05T22:29:50.160Z] GC before operation: completed in 61.980 ms, heap usage 294.000 MB -> 34.770 MB. [2026-03-05T22:30:18.223Z] ====== als (apache-spark) [default], iteration 0 completed (27688.390 ms) ====== [2026-03-05T22:30:18.223Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-05T22:30:18.223Z] GC before operation: completed in 116.235 ms, heap usage 193.688 MB -> 61.180 MB. [2026-03-05T22:30:34.302Z] ====== als (apache-spark) [default], iteration 1 completed (14422.175 ms) ====== [2026-03-05T22:30:34.302Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-05T22:30:34.302Z] GC before operation: completed in 113.929 ms, heap usage 449.354 MB -> 62.460 MB. [2026-03-05T22:30:47.639Z] ====== als (apache-spark) [default], iteration 2 completed (13074.884 ms) ====== [2026-03-05T22:30:47.639Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-05T22:30:47.639Z] GC before operation: completed in 115.243 ms, heap usage 320.059 MB -> 62.846 MB. [2026-03-05T22:31:01.289Z] ====== als (apache-spark) [default], iteration 3 completed (13107.555 ms) ====== [2026-03-05T22:31:01.289Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-05T22:31:01.289Z] GC before operation: completed in 115.992 ms, heap usage 116.247 MB -> 63.202 MB. [2026-03-05T22:31:12.338Z] ====== als (apache-spark) [default], iteration 4 completed (12052.676 ms) ====== [2026-03-05T22:31:12.338Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-05T22:31:12.696Z] GC before operation: completed in 111.593 ms, heap usage 374.373 MB -> 64.021 MB. [2026-03-05T22:31:26.105Z] ====== als (apache-spark) [default], iteration 5 completed (11968.397 ms) ====== [2026-03-05T22:31:26.105Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-05T22:31:26.105Z] GC before operation: completed in 128.382 ms, heap usage 219.686 MB -> 64.390 MB. [2026-03-05T22:31:39.500Z] ====== als (apache-spark) [default], iteration 6 completed (12213.858 ms) ====== [2026-03-05T22:31:39.500Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-05T22:31:39.500Z] GC before operation: completed in 117.852 ms, heap usage 130.113 MB -> 64.903 MB. [2026-03-05T22:31:50.586Z] ====== als (apache-spark) [default], iteration 7 completed (11934.350 ms) ====== [2026-03-05T22:31:50.586Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-05T22:31:50.586Z] GC before operation: completed in 118.372 ms, heap usage 470.025 MB -> 65.914 MB. [2026-03-05T22:32:04.188Z] ====== als (apache-spark) [default], iteration 8 completed (11847.125 ms) ====== [2026-03-05T22:32:04.188Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-05T22:32:04.188Z] GC before operation: completed in 160.126 ms, heap usage 308.287 MB -> 66.195 MB. [2026-03-05T22:32:15.214Z] ====== als (apache-spark) [default], iteration 9 completed (11572.483 ms) ====== [2026-03-05T22:32:15.214Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-05T22:32:15.214Z] GC before operation: completed in 134.274 ms, heap usage 577.264 MB -> 66.889 MB. [2026-03-05T22:32:26.280Z] ====== als (apache-spark) [default], iteration 10 completed (11847.247 ms) ====== [2026-03-05T22:32:26.280Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-05T22:32:26.280Z] GC before operation: completed in 124.051 ms, heap usage 492.706 MB -> 66.897 MB. [2026-03-05T22:32:39.608Z] ====== als (apache-spark) [default], iteration 11 completed (11713.105 ms) ====== [2026-03-05T22:32:39.608Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-05T22:32:39.608Z] GC before operation: completed in 116.511 ms, heap usage 193.120 MB -> 66.704 MB. [2026-03-05T22:32:52.271Z] ====== als (apache-spark) [default], iteration 12 completed (11411.549 ms) ====== [2026-03-05T22:32:52.271Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-05T22:32:52.271Z] GC before operation: completed in 113.363 ms, heap usage 97.386 MB -> 66.905 MB. [2026-03-05T22:33:03.206Z] ====== als (apache-spark) [default], iteration 13 completed (11576.761 ms) ====== [2026-03-05T22:33:03.206Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-05T22:33:03.206Z] GC before operation: completed in 119.740 ms, heap usage 309.126 MB -> 67.386 MB. [2026-03-05T22:33:14.195Z] ====== als (apache-spark) [default], iteration 14 completed (11595.789 ms) ====== [2026-03-05T22:33:14.195Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-05T22:33:14.195Z] GC before operation: completed in 116.842 ms, heap usage 258.854 MB -> 67.716 MB. [2026-03-05T22:33:27.439Z] ====== als (apache-spark) [default], iteration 15 completed (11452.265 ms) ====== [2026-03-05T22:33:27.439Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-05T22:33:27.439Z] GC before operation: completed in 119.164 ms, heap usage 271.929 MB -> 67.821 MB. [2026-03-05T22:33:38.477Z] ====== als (apache-spark) [default], iteration 16 completed (11328.350 ms) ====== [2026-03-05T22:33:38.477Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-05T22:33:38.477Z] GC before operation: completed in 182.605 ms, heap usage 177.420 MB -> 68.003 MB. [2026-03-05T22:33:49.427Z] ====== als (apache-spark) [default], iteration 17 completed (11508.009 ms) ====== [2026-03-05T22:33:49.427Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-05T22:33:49.427Z] GC before operation: completed in 123.446 ms, heap usage 482.640 MB -> 68.736 MB. [2026-03-05T22:34:02.675Z] ====== als (apache-spark) [default], iteration 18 completed (11600.037 ms) ====== [2026-03-05T22:34:02.675Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-05T22:34:02.675Z] GC before operation: completed in 121.101 ms, heap usage 380.367 MB -> 68.750 MB. [2026-03-05T22:34:13.616Z] ====== als (apache-spark) [default], iteration 19 completed (11399.371 ms) ====== [2026-03-05T22:34:13.616Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-05T22:34:13.616Z] GC before operation: completed in 117.987 ms, heap usage 125.434 MB -> 68.786 MB. [2026-03-05T22:34:27.082Z] ====== als (apache-spark) [default], iteration 20 completed (11697.075 ms) ====== [2026-03-05T22:34:27.082Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-05T22:34:27.082Z] GC before operation: completed in 124.799 ms, heap usage 468.743 MB -> 69.498 MB. [2026-03-05T22:34:38.033Z] ====== als (apache-spark) [default], iteration 21 completed (11814.333 ms) ====== [2026-03-05T22:34:38.033Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-05T22:34:38.033Z] GC before operation: completed in 119.462 ms, heap usage 242.583 MB -> 69.453 MB. [2026-03-05T22:34:49.005Z] ====== als (apache-spark) [default], iteration 22 completed (11642.167 ms) ====== [2026-03-05T22:34:49.005Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-05T22:34:49.005Z] GC before operation: completed in 120.488 ms, heap usage 357.953 MB -> 69.823 MB. [2026-03-05T22:35:02.378Z] ====== als (apache-spark) [default], iteration 23 completed (11240.801 ms) ====== [2026-03-05T22:35:02.378Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-05T22:35:02.378Z] GC before operation: completed in 142.687 ms, heap usage 476.899 MB -> 70.321 MB. [2026-03-05T22:35:13.350Z] ====== als (apache-spark) [default], iteration 24 completed (11463.471 ms) ====== [2026-03-05T22:35:13.350Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-05T22:35:13.350Z] GC before operation: completed in 122.367 ms, heap usage 484.354 MB -> 70.628 MB. [2026-03-05T22:35:24.441Z] ====== als (apache-spark) [default], iteration 25 completed (11723.774 ms) ====== [2026-03-05T22:35:24.441Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-05T22:35:24.441Z] GC before operation: completed in 125.996 ms, heap usage 499.152 MB -> 70.718 MB. [2026-03-05T22:35:37.741Z] ====== als (apache-spark) [default], iteration 26 completed (11455.840 ms) ====== [2026-03-05T22:35:37.741Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-05T22:35:37.741Z] GC before operation: completed in 122.916 ms, heap usage 426.599 MB -> 71.009 MB. [2026-03-05T22:35:48.695Z] ====== als (apache-spark) [default], iteration 27 completed (11307.234 ms) ====== [2026-03-05T22:35:48.695Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-05T22:35:48.695Z] GC before operation: completed in 121.509 ms, heap usage 219.778 MB -> 70.922 MB. [2026-03-05T22:35:59.672Z] ====== als (apache-spark) [default], iteration 28 completed (11722.623 ms) ====== [2026-03-05T22:35:59.672Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-05T22:35:59.672Z] GC before operation: completed in 127.350 ms, heap usage 466.573 MB -> 71.546 MB. [2026-03-05T22:36:13.110Z] ====== als (apache-spark) [default], iteration 29 completed (11453.770 ms) ====== [2026-03-05T22:36:13.110Z] ----------------------------------- [2026-03-05T22:36:13.110Z] renaissance-als_0_PASSED [2026-03-05T22:36:13.110Z] ----------------------------------- [2026-03-05T22:36:13.110Z] [2026-03-05T22:36:13.110Z] TEST TEARDOWN: [2026-03-05T22:36:13.110Z] Nothing to be done for teardown. [2026-03-05T22:36:13.110Z] renaissance-als_0 Finish Time: Thu Mar 5 22:36:11 2026 Epoch Time (ms): 1772750171703