[2026-02-28T22:39:40.406Z] Running test renaissance-als_0 ... [2026-02-28T22:39:40.406Z] =============================================== [2026-02-28T22:39:40.406Z] renaissance-als_0 Start Time: Sat Feb 28 22:39:39 2026 Epoch Time (ms): 1772318379665 [2026-02-28T22:39:40.406Z] variation: NoOptions [2026-02-28T22:39:40.406Z] JVM_OPTIONS: [2026-02-28T22:39:40.406Z] { \ [2026-02-28T22:39:40.406Z] echo ""; echo "TEST SETUP:"; \ [2026-02-28T22:39:40.406Z] echo "Nothing to be done for setup."; \ [2026-02-28T22:39:40.406Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17723183792777/renaissance-als_0"; \ [2026-02-28T22:39:40.406Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17723183792777/renaissance-als_0"; \ [2026-02-28T22:39:40.406Z] echo ""; echo "TESTING:"; \ [2026-02-28T22:39:40.406Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17723183792777/renaissance-als_0"/als.json" als; \ [2026-02-28T22:39:40.406Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17723183792777/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-28T22:39:40.406Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-28T22:39:40.406Z] echo "Nothing to be done for teardown."; \ [2026-02-28T22:39:40.406Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17723183792777/TestTargetResult"; [2026-02-28T22:39:40.406Z] [2026-02-28T22:39:40.406Z] TEST SETUP: [2026-02-28T22:39:40.406Z] Nothing to be done for setup. [2026-02-28T22:39:40.406Z] [2026-02-28T22:39:40.406Z] TESTING: [2026-02-28T22:39:41.158Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-28T22:39:41.158Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17723183792777/renaissance-als_0/launcher-223939-994714100282440816/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-28T22:39:41.158Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-28T22:39:41.158Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-28T22:39:55.239Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-28T22:40:01.994Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-28T22:40:01.994Z] GC before operation: completed in 119.364 ms, heap usage 55.525 MB -> 34.990 MB. [2026-02-28T22:40:58.724Z] ====== als (apache-spark) [default], iteration 0 completed (54426.199 ms) ====== [2026-02-28T22:40:58.724Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-28T22:40:58.724Z] GC before operation: completed in 180.304 ms, heap usage 277.045 MB -> 61.411 MB. [2026-02-28T22:41:17.450Z] ====== als (apache-spark) [default], iteration 1 completed (17964.255 ms) ====== [2026-02-28T22:41:17.450Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-28T22:41:17.450Z] GC before operation: completed in 218.989 ms, heap usage 492.586 MB -> 62.608 MB. [2026-02-28T22:41:33.851Z] ====== als (apache-spark) [default], iteration 2 completed (16451.143 ms) ====== [2026-02-28T22:41:33.851Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-28T22:41:33.851Z] GC before operation: completed in 192.083 ms, heap usage 471.874 MB -> 63.184 MB. [2026-02-28T22:41:49.896Z] ====== als (apache-spark) [default], iteration 3 completed (15548.256 ms) ====== [2026-02-28T22:41:49.896Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-28T22:41:49.896Z] GC before operation: completed in 203.022 ms, heap usage 100.835 MB -> 63.307 MB. [2026-02-28T22:42:05.917Z] ====== als (apache-spark) [default], iteration 4 completed (15471.359 ms) ====== [2026-02-28T22:42:05.917Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-28T22:42:05.917Z] GC before operation: completed in 270.232 ms, heap usage 505.706 MB -> 64.386 MB. [2026-02-28T22:42:21.943Z] ====== als (apache-spark) [default], iteration 5 completed (14413.464 ms) ====== [2026-02-28T22:42:21.943Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-28T22:42:21.943Z] GC before operation: completed in 249.858 ms, heap usage 499.686 MB -> 64.936 MB. [2026-02-28T22:42:37.959Z] ====== als (apache-spark) [default], iteration 6 completed (14848.673 ms) ====== [2026-02-28T22:42:37.959Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-28T22:42:37.959Z] GC before operation: completed in 229.859 ms, heap usage 161.969 MB -> 64.981 MB. [2026-02-28T22:42:52.055Z] ====== als (apache-spark) [default], iteration 7 completed (14934.318 ms) ====== [2026-02-28T22:42:52.055Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-28T22:42:52.055Z] GC before operation: completed in 295.338 ms, heap usage 376.610 MB -> 65.809 MB. [2026-02-28T22:43:08.079Z] ====== als (apache-spark) [default], iteration 8 completed (15460.050 ms) ====== [2026-02-28T22:43:08.079Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-28T22:43:08.079Z] GC before operation: completed in 215.699 ms, heap usage 173.079 MB -> 66.094 MB. [2026-02-28T22:43:24.251Z] ====== als (apache-spark) [default], iteration 9 completed (15690.040 ms) ====== [2026-02-28T22:43:24.251Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-28T22:43:24.251Z] GC before operation: completed in 205.034 ms, heap usage 340.816 MB -> 66.647 MB. [2026-02-28T22:43:40.295Z] ====== als (apache-spark) [default], iteration 10 completed (15504.808 ms) ====== [2026-02-28T22:43:40.295Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-28T22:43:40.295Z] GC before operation: completed in 200.852 ms, heap usage 525.749 MB -> 67.159 MB. [2026-02-28T22:43:56.290Z] ====== als (apache-spark) [default], iteration 11 completed (15040.026 ms) ====== [2026-02-28T22:43:56.290Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-28T22:43:56.290Z] GC before operation: completed in 283.954 ms, heap usage 352.038 MB -> 67.118 MB. [2026-02-28T22:44:12.807Z] ====== als (apache-spark) [default], iteration 12 completed (14369.800 ms) ====== [2026-02-28T22:44:12.807Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-28T22:44:12.807Z] GC before operation: completed in 225.845 ms, heap usage 341.742 MB -> 67.382 MB. [2026-02-28T22:44:26.432Z] ====== als (apache-spark) [default], iteration 13 completed (13564.903 ms) ====== [2026-02-28T22:44:26.432Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-28T22:44:26.432Z] GC before operation: completed in 225.979 ms, heap usage 344.074 MB -> 67.584 MB. [2026-02-28T22:44:37.994Z] ====== als (apache-spark) [default], iteration 14 completed (12376.140 ms) ====== [2026-02-28T22:44:37.994Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-28T22:44:37.994Z] GC before operation: completed in 212.556 ms, heap usage 483.345 MB -> 68.077 MB. [2026-02-28T22:44:49.808Z] ====== als (apache-spark) [default], iteration 15 completed (11037.591 ms) ====== [2026-02-28T22:44:49.808Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-28T22:44:49.808Z] GC before operation: completed in 205.207 ms, heap usage 466.183 MB -> 68.374 MB. [2026-02-28T22:45:03.542Z] ====== als (apache-spark) [default], iteration 16 completed (13064.249 ms) ====== [2026-02-28T22:45:03.542Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-28T22:45:03.542Z] GC before operation: completed in 237.497 ms, heap usage 228.193 MB -> 68.327 MB. [2026-02-28T22:45:19.542Z] ====== als (apache-spark) [default], iteration 17 completed (13774.598 ms) ====== [2026-02-28T22:45:19.542Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-28T22:45:19.542Z] GC before operation: completed in 210.185 ms, heap usage 350.028 MB -> 68.581 MB. [2026-02-28T22:45:31.124Z] ====== als (apache-spark) [default], iteration 18 completed (13135.140 ms) ====== [2026-02-28T22:45:31.124Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-28T22:45:31.124Z] GC before operation: completed in 197.899 ms, heap usage 522.201 MB -> 69.225 MB. [2026-02-28T22:45:54.607Z] ====== als (apache-spark) [default], iteration 19 completed (21660.111 ms) ====== [2026-02-28T22:45:54.607Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-28T22:45:54.607Z] GC before operation: completed in 216.785 ms, heap usage 255.756 MB -> 69.078 MB. [2026-02-28T22:46:08.236Z] ====== als (apache-spark) [default], iteration 20 completed (13735.447 ms) ====== [2026-02-28T22:46:08.236Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-28T22:46:08.236Z] GC before operation: completed in 295.920 ms, heap usage 274.319 MB -> 69.332 MB. [2026-02-28T22:46:22.417Z] ====== als (apache-spark) [default], iteration 21 completed (13070.042 ms) ====== [2026-02-28T22:46:22.417Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-28T22:46:22.417Z] GC before operation: completed in 295.353 ms, heap usage 190.168 MB -> 69.512 MB. [2026-02-28T22:46:36.058Z] ====== als (apache-spark) [default], iteration 22 completed (13524.455 ms) ====== [2026-02-28T22:46:36.058Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-28T22:46:36.058Z] GC before operation: completed in 247.081 ms, heap usage 422.706 MB -> 70.211 MB. [2026-02-28T22:46:50.002Z] ====== als (apache-spark) [default], iteration 23 completed (13343.706 ms) ====== [2026-02-28T22:46:50.002Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-28T22:46:50.002Z] GC before operation: completed in 228.995 ms, heap usage 212.542 MB -> 70.102 MB. [2026-02-28T22:47:07.275Z] ====== als (apache-spark) [default], iteration 24 completed (15616.230 ms) ====== [2026-02-28T22:47:07.275Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-28T22:47:07.275Z] GC before operation: completed in 230.318 ms, heap usage 391.129 MB -> 70.499 MB. [2026-02-28T22:47:26.073Z] ====== als (apache-spark) [default], iteration 25 completed (17907.570 ms) ====== [2026-02-28T22:47:26.073Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-28T22:47:26.073Z] GC before operation: completed in 184.008 ms, heap usage 98.991 MB -> 70.585 MB. [2026-02-28T22:47:38.167Z] ====== als (apache-spark) [default], iteration 26 completed (13046.662 ms) ====== [2026-02-28T22:47:38.167Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-28T22:47:38.167Z] GC before operation: completed in 217.757 ms, heap usage 551.500 MB -> 71.342 MB. [2026-02-28T22:47:51.877Z] ====== als (apache-spark) [default], iteration 27 completed (13335.841 ms) ====== [2026-02-28T22:47:51.877Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-28T22:47:51.877Z] GC before operation: completed in 199.201 ms, heap usage 214.872 MB -> 71.099 MB. [2026-02-28T22:48:05.540Z] ====== als (apache-spark) [default], iteration 28 completed (13163.573 ms) ====== [2026-02-28T22:48:05.540Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-28T22:48:05.540Z] GC before operation: completed in 221.450 ms, heap usage 489.586 MB -> 71.375 MB. [2026-02-28T22:48:19.172Z] ====== als (apache-spark) [default], iteration 29 completed (12954.887 ms) ====== [2026-02-28T22:48:19.172Z] ----------------------------------- [2026-02-28T22:48:19.172Z] renaissance-als_0_PASSED [2026-02-28T22:48:19.172Z] ----------------------------------- [2026-02-28T22:48:19.172Z] [2026-02-28T22:48:19.172Z] TEST TEARDOWN: [2026-02-28T22:48:19.172Z] Nothing to be done for teardown. [2026-02-28T22:48:19.172Z] renaissance-als_0 Finish Time: Sat Feb 28 22:48:18 2026 Epoch Time (ms): 1772318898813