[2026-02-20T07:57:18.334Z] Running test renaissance-als_0 ... [2026-02-20T07:57:18.334Z] =============================================== [2026-02-20T07:57:18.334Z] renaissance-als_0 Start Time: Fri Feb 20 07:57:17 2026 Epoch Time (ms): 1771574237685 [2026-02-20T07:57:18.334Z] variation: NoOptions [2026-02-20T07:57:18.334Z] JVM_OPTIONS: [2026-02-20T07:57:18.335Z] { \ [2026-02-20T07:57:18.335Z] echo ""; echo "TEST SETUP:"; \ [2026-02-20T07:57:18.335Z] echo "Nothing to be done for setup."; \ [2026-02-20T07:57:18.335Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17715742369883/renaissance-als_0"; \ [2026-02-20T07:57:18.335Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17715742369883/renaissance-als_0"; \ [2026-02-20T07:57:18.335Z] echo ""; echo "TESTING:"; \ [2026-02-20T07:57:18.335Z] "/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_17715742369883/renaissance-als_0"/als.json" als; \ [2026-02-20T07:57:18.335Z] 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_17715742369883/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-20T07:57:18.335Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-20T07:57:18.335Z] echo "Nothing to be done for teardown."; \ [2026-02-20T07:57:18.335Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17715742369883/TestTargetResult"; [2026-02-20T07:57:18.335Z] [2026-02-20T07:57:18.335Z] TEST SETUP: [2026-02-20T07:57:18.335Z] Nothing to be done for setup. [2026-02-20T07:57:18.335Z] [2026-02-20T07:57:18.335Z] TESTING: [2026-02-20T07:57:19.895Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-20T07:57:19.895Z] 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_17715742369883/renaissance-als_0/launcher-075718-17800333651428386454/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-20T07:57:19.895Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-20T07:57:19.895Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-20T07:57:45.951Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-20T07:57:54.649Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-20T07:57:54.649Z] GC before operation: completed in 132.377 ms, heap usage 152.500 MB -> 35.107 MB. [2026-02-20T07:58:29.729Z] ====== als (apache-spark) [default], iteration 0 completed (34735.158 ms) ====== [2026-02-20T07:58:29.729Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-20T07:58:34.144Z] GC before operation: completed in 3985.761 ms, heap usage 277.868 MB -> 61.889 MB. [2026-02-20T07:58:51.465Z] ====== als (apache-spark) [default], iteration 1 completed (15867.721 ms) ====== [2026-02-20T07:58:51.465Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-20T07:58:51.465Z] GC before operation: completed in 182.449 ms, heap usage 136.271 MB -> 62.483 MB. [2026-02-20T07:59:01.246Z] ====== als (apache-spark) [default], iteration 2 completed (10555.274 ms) ====== [2026-02-20T07:59:01.246Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-20T07:59:02.003Z] GC before operation: completed in 179.074 ms, heap usage 382.962 MB -> 63.289 MB. [2026-02-20T07:59:11.773Z] ====== als (apache-spark) [default], iteration 3 completed (9820.741 ms) ====== [2026-02-20T07:59:11.773Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-20T07:59:11.773Z] GC before operation: completed in 189.198 ms, heap usage 501.618 MB -> 64.082 MB. [2026-02-20T07:59:22.079Z] ====== als (apache-spark) [default], iteration 4 completed (10028.357 ms) ====== [2026-02-20T07:59:22.079Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-20T07:59:22.079Z] GC before operation: completed in 166.689 ms, heap usage 348.510 MB -> 64.437 MB. [2026-02-20T07:59:33.667Z] ====== als (apache-spark) [default], iteration 5 completed (10394.306 ms) ====== [2026-02-20T07:59:33.667Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-20T07:59:33.667Z] GC before operation: completed in 178.835 ms, heap usage 106.362 MB -> 64.310 MB. [2026-02-20T07:59:43.540Z] ====== als (apache-spark) [default], iteration 6 completed (10319.390 ms) ====== [2026-02-20T07:59:43.540Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-20T07:59:44.303Z] GC before operation: completed in 203.595 ms, heap usage 284.826 MB -> 65.473 MB. [2026-02-20T07:59:56.085Z] ====== als (apache-spark) [default], iteration 7 completed (10728.158 ms) ====== [2026-02-20T07:59:56.085Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-20T07:59:56.085Z] GC before operation: completed in 214.967 ms, heap usage 180.100 MB -> 65.905 MB. [2026-02-20T08:00:05.912Z] ====== als (apache-spark) [default], iteration 8 completed (9474.656 ms) ====== [2026-02-20T08:00:05.912Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-20T08:00:05.912Z] GC before operation: completed in 199.339 ms, heap usage 355.040 MB -> 66.637 MB. [2026-02-20T08:00:15.677Z] ====== als (apache-spark) [default], iteration 9 completed (9576.332 ms) ====== [2026-02-20T08:00:15.677Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-20T08:00:15.677Z] GC before operation: completed in 184.668 ms, heap usage 153.605 MB -> 66.585 MB. [2026-02-20T08:00:25.583Z] ====== als (apache-spark) [default], iteration 10 completed (9500.137 ms) ====== [2026-02-20T08:00:25.583Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-20T08:00:25.583Z] GC before operation: completed in 179.520 ms, heap usage 232.511 MB -> 67.017 MB. [2026-02-20T08:00:35.362Z] ====== als (apache-spark) [default], iteration 11 completed (9598.700 ms) ====== [2026-02-20T08:00:35.362Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-20T08:00:35.362Z] GC before operation: completed in 215.367 ms, heap usage 287.362 MB -> 67.254 MB. [2026-02-20T08:00:46.946Z] ====== als (apache-spark) [default], iteration 12 completed (9926.522 ms) ====== [2026-02-20T08:00:46.946Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-20T08:00:46.946Z] GC before operation: completed in 189.352 ms, heap usage 347.401 MB -> 67.725 MB. [2026-02-20T08:00:56.746Z] ====== als (apache-spark) [default], iteration 13 completed (9449.682 ms) ====== [2026-02-20T08:00:56.746Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-20T08:00:56.746Z] GC before operation: completed in 198.591 ms, heap usage 447.281 MB -> 68.205 MB. [2026-02-20T08:01:06.534Z] ====== als (apache-spark) [default], iteration 14 completed (9682.126 ms) ====== [2026-02-20T08:01:06.534Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-20T08:01:06.534Z] GC before operation: completed in 193.669 ms, heap usage 350.001 MB -> 68.188 MB. [2026-02-20T08:01:16.309Z] ====== als (apache-spark) [default], iteration 15 completed (9221.404 ms) ====== [2026-02-20T08:01:16.309Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-20T08:01:16.309Z] GC before operation: completed in 200.133 ms, heap usage 246.126 MB -> 68.391 MB. [2026-02-20T08:01:26.652Z] ====== als (apache-spark) [default], iteration 16 completed (9562.709 ms) ====== [2026-02-20T08:01:26.652Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-20T08:01:26.652Z] GC before operation: completed in 228.527 ms, heap usage 593.436 MB -> 69.130 MB. [2026-02-20T08:01:34.919Z] ====== als (apache-spark) [default], iteration 17 completed (9115.779 ms) ====== [2026-02-20T08:01:34.919Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-20T08:01:34.919Z] GC before operation: completed in 165.036 ms, heap usage 461.301 MB -> 69.143 MB. [2026-02-20T08:01:44.694Z] ====== als (apache-spark) [default], iteration 18 completed (9480.749 ms) ====== [2026-02-20T08:01:44.694Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-20T08:01:45.459Z] GC before operation: completed in 155.796 ms, heap usage 353.653 MB -> 69.173 MB. [2026-02-20T08:01:55.260Z] ====== als (apache-spark) [default], iteration 19 completed (9431.737 ms) ====== [2026-02-20T08:01:55.260Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-20T08:01:55.260Z] GC before operation: completed in 198.761 ms, heap usage 195.714 MB -> 69.227 MB. [2026-02-20T08:02:05.041Z] ====== als (apache-spark) [default], iteration 20 completed (10080.023 ms) ====== [2026-02-20T08:02:05.041Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-20T08:02:05.811Z] GC before operation: completed in 228.134 ms, heap usage 450.497 MB -> 70.001 MB. [2026-02-20T08:02:15.590Z] ====== als (apache-spark) [default], iteration 21 completed (10004.676 ms) ====== [2026-02-20T08:02:15.590Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-20T08:02:16.475Z] GC before operation: completed in 224.330 ms, heap usage 522.040 MB -> 70.308 MB. [2026-02-20T08:02:26.239Z] ====== als (apache-spark) [default], iteration 22 completed (9526.370 ms) ====== [2026-02-20T08:02:26.239Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-20T08:02:26.239Z] GC before operation: completed in 207.063 ms, heap usage 392.782 MB -> 70.191 MB. [2026-02-20T08:02:36.015Z] ====== als (apache-spark) [default], iteration 23 completed (9746.724 ms) ====== [2026-02-20T08:02:36.015Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-20T08:02:36.015Z] GC before operation: completed in 236.447 ms, heap usage 477.385 MB -> 70.700 MB. [2026-02-20T08:02:47.582Z] ====== als (apache-spark) [default], iteration 24 completed (10428.337 ms) ====== [2026-02-20T08:02:47.582Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-20T08:02:47.582Z] GC before operation: completed in 212.477 ms, heap usage 282.992 MB -> 70.681 MB. [2026-02-20T08:02:57.362Z] ====== als (apache-spark) [default], iteration 25 completed (9812.674 ms) ====== [2026-02-20T08:02:57.362Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-20T08:02:57.362Z] GC before operation: completed in 205.201 ms, heap usage 321.380 MB -> 71.043 MB. [2026-02-20T08:03:07.156Z] ====== als (apache-spark) [default], iteration 26 completed (9377.910 ms) ====== [2026-02-20T08:03:07.157Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-20T08:03:07.157Z] GC before operation: completed in 219.512 ms, heap usage 249.639 MB -> 71.240 MB. [2026-02-20T08:03:17.527Z] ====== als (apache-spark) [default], iteration 27 completed (9255.485 ms) ====== [2026-02-20T08:03:17.527Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-20T08:03:17.527Z] GC before operation: completed in 174.722 ms, heap usage 256.731 MB -> 71.476 MB. [2026-02-20T08:03:27.330Z] ====== als (apache-spark) [default], iteration 28 completed (9151.358 ms) ====== [2026-02-20T08:03:27.330Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-20T08:03:27.330Z] GC before operation: completed in 223.812 ms, heap usage 392.995 MB -> 71.812 MB. [2026-02-20T08:03:35.553Z] ====== als (apache-spark) [default], iteration 29 completed (8568.604 ms) ====== [2026-02-20T08:03:36.308Z] ----------------------------------- [2026-02-20T08:03:36.308Z] renaissance-als_0_PASSED [2026-02-20T08:03:36.308Z] ----------------------------------- [2026-02-20T08:03:36.308Z] [2026-02-20T08:03:36.308Z] TEST TEARDOWN: [2026-02-20T08:03:36.308Z] Nothing to be done for teardown. [2026-02-20T08:03:36.308Z] renaissance-als_0 Finish Time: Fri Feb 20 08:03:35 2026 Epoch Time (ms): 1771574615647