[2026-01-21T11:09:48.781Z] Running test renaissance-als_0 ... [2026-01-21T11:09:48.781Z] =============================================== [2026-01-21T11:09:48.781Z] renaissance-als_0 Start Time: Wed Jan 21 11:09:48 2026 Epoch Time (ms): 1768993788589 [2026-01-21T11:09:48.781Z] variation: NoOptions [2026-01-21T11:09:48.781Z] JVM_OPTIONS: [2026-01-21T11:09:48.781Z] { \ [2026-01-21T11:09:48.781Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T11:09:48.781Z] echo "Nothing to be done for setup."; \ [2026-01-21T11:09:48.781Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1768993597924/renaissance-als_0"; \ [2026-01-21T11:09:48.781Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1768993597924/renaissance-als_0"; \ [2026-01-21T11:09:48.781Z] echo ""; echo "TESTING:"; \ [2026-01-21T11:09:48.781Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1768993597924/renaissance-als_0"/als.json" als; \ [2026-01-21T11:09:48.781Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1768993597924/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T11:09:48.781Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T11:09:48.781Z] echo "Nothing to be done for teardown."; \ [2026-01-21T11:09:48.781Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1768993597924/TestTargetResult"; [2026-01-21T11:09:48.781Z] [2026-01-21T11:09:48.781Z] TEST SETUP: [2026-01-21T11:09:48.781Z] Nothing to be done for setup. [2026-01-21T11:09:48.781Z] [2026-01-21T11:09:48.781Z] TESTING: [2026-01-21T11:09:50.099Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-21T11:09:50.099Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_1768993597924/renaissance-als_0/launcher-110948-13383251074196857492/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-21T11:09:50.099Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-21T11:09:50.099Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-21T11:09:53.857Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-21T11:09:57.701Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T11:09:57.701Z] GC before operation: completed in 45.185 ms, heap usage 60.016 MB -> 35.024 MB. [2026-01-21T11:10:25.633Z] ====== als (apache-spark) [default], iteration 0 completed (23405.293 ms) ====== [2026-01-21T11:10:25.633Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T11:10:25.633Z] GC before operation: completed in 129.234 ms, heap usage 220.641 MB -> 61.476 MB. [2026-01-21T11:10:34.387Z] ====== als (apache-spark) [default], iteration 1 completed (12309.731 ms) ====== [2026-01-21T11:10:34.387Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T11:10:35.026Z] GC before operation: completed in 99.485 ms, heap usage 267.664 MB -> 62.419 MB. [2026-01-21T11:10:47.203Z] ====== als (apache-spark) [default], iteration 2 completed (11999.584 ms) ====== [2026-01-21T11:10:47.203Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T11:10:47.203Z] GC before operation: completed in 121.788 ms, heap usage 386.675 MB -> 63.141 MB. [2026-01-21T11:10:59.372Z] ====== als (apache-spark) [default], iteration 3 completed (11591.269 ms) ====== [2026-01-21T11:10:59.373Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T11:10:59.373Z] GC before operation: completed in 127.522 ms, heap usage 203.928 MB -> 63.522 MB. [2026-01-21T11:11:09.662Z] ====== als (apache-spark) [default], iteration 4 completed (10539.426 ms) ====== [2026-01-21T11:11:09.662Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T11:11:09.662Z] GC before operation: completed in 87.300 ms, heap usage 100.052 MB -> 64.041 MB. [2026-01-21T11:11:21.853Z] ====== als (apache-spark) [default], iteration 5 completed (10397.378 ms) ====== [2026-01-21T11:11:21.853Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T11:11:21.853Z] GC before operation: completed in 108.894 ms, heap usage 496.998 MB -> 65.088 MB. [2026-01-21T11:11:32.116Z] ====== als (apache-spark) [default], iteration 6 completed (10914.659 ms) ====== [2026-01-21T11:11:32.116Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T11:11:32.116Z] GC before operation: completed in 93.223 ms, heap usage 279.484 MB -> 65.248 MB. [2026-01-21T11:11:42.858Z] ====== als (apache-spark) [default], iteration 7 completed (10479.291 ms) ====== [2026-01-21T11:11:42.858Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T11:11:42.858Z] GC before operation: completed in 117.114 ms, heap usage 305.054 MB -> 65.949 MB. [2026-01-21T11:11:55.051Z] ====== als (apache-spark) [default], iteration 8 completed (10867.737 ms) ====== [2026-01-21T11:11:55.051Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T11:11:55.051Z] GC before operation: completed in 108.148 ms, heap usage 435.245 MB -> 66.647 MB. [2026-01-21T11:12:05.326Z] ====== als (apache-spark) [default], iteration 9 completed (11383.017 ms) ====== [2026-01-21T11:12:05.326Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T11:12:05.326Z] GC before operation: completed in 118.701 ms, heap usage 436.726 MB -> 66.960 MB. [2026-01-21T11:12:17.531Z] ====== als (apache-spark) [default], iteration 10 completed (11192.092 ms) ====== [2026-01-21T11:12:17.531Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T11:12:17.531Z] GC before operation: completed in 106.331 ms, heap usage 438.292 MB -> 67.193 MB. [2026-01-21T11:12:27.803Z] ====== als (apache-spark) [default], iteration 11 completed (10009.158 ms) ====== [2026-01-21T11:12:27.803Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T11:12:27.803Z] GC before operation: completed in 108.047 ms, heap usage 201.527 MB -> 67.004 MB. [2026-01-21T11:12:36.860Z] ====== als (apache-spark) [default], iteration 12 completed (9469.201 ms) ====== [2026-01-21T11:12:36.860Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T11:12:36.860Z] GC before operation: completed in 140.495 ms, heap usage 148.375 MB -> 67.263 MB. [2026-01-21T11:12:47.147Z] ====== als (apache-spark) [default], iteration 13 completed (10054.926 ms) ====== [2026-01-21T11:12:47.147Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T11:12:47.147Z] GC before operation: completed in 105.497 ms, heap usage 488.015 MB -> 67.976 MB. [2026-01-21T11:12:57.460Z] ====== als (apache-spark) [default], iteration 14 completed (10018.825 ms) ====== [2026-01-21T11:12:57.460Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T11:12:57.460Z] GC before operation: completed in 139.697 ms, heap usage 255.419 MB -> 67.889 MB. [2026-01-21T11:13:09.633Z] ====== als (apache-spark) [default], iteration 15 completed (10297.146 ms) ====== [2026-01-21T11:13:09.633Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T11:13:09.633Z] GC before operation: completed in 181.477 ms, heap usage 447.170 MB -> 68.376 MB. [2026-01-21T11:13:18.279Z] ====== als (apache-spark) [default], iteration 16 completed (9904.042 ms) ====== [2026-01-21T11:13:18.279Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T11:13:18.279Z] GC before operation: completed in 101.189 ms, heap usage 216.398 MB -> 68.286 MB. [2026-01-21T11:13:28.563Z] ====== als (apache-spark) [default], iteration 17 completed (10041.026 ms) ====== [2026-01-21T11:13:28.563Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T11:13:29.190Z] GC before operation: completed in 129.072 ms, heap usage 306.690 MB -> 68.726 MB. [2026-01-21T11:13:39.496Z] ====== als (apache-spark) [default], iteration 18 completed (10128.302 ms) ====== [2026-01-21T11:13:39.496Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T11:13:39.496Z] GC before operation: completed in 114.682 ms, heap usage 468.963 MB -> 69.263 MB. [2026-01-21T11:13:49.752Z] ====== als (apache-spark) [default], iteration 19 completed (9759.228 ms) ====== [2026-01-21T11:13:49.752Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T11:13:49.752Z] GC before operation: completed in 112.448 ms, heap usage 192.511 MB -> 68.981 MB. [2026-01-21T11:14:00.044Z] ====== als (apache-spark) [default], iteration 20 completed (9483.042 ms) ====== [2026-01-21T11:14:00.044Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T11:14:00.044Z] GC before operation: completed in 82.856 ms, heap usage 310.270 MB -> 69.475 MB. [2026-01-21T11:14:10.283Z] ====== als (apache-spark) [default], iteration 21 completed (10136.626 ms) ====== [2026-01-21T11:14:10.283Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T11:14:10.283Z] GC before operation: completed in 130.426 ms, heap usage 198.382 MB -> 69.523 MB. [2026-01-21T11:14:20.879Z] ====== als (apache-spark) [default], iteration 22 completed (10482.694 ms) ====== [2026-01-21T11:14:20.879Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T11:14:20.879Z] GC before operation: completed in 89.339 ms, heap usage 105.839 MB -> 69.741 MB. [2026-01-21T11:14:31.192Z] ====== als (apache-spark) [default], iteration 23 completed (9994.801 ms) ====== [2026-01-21T11:14:31.192Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T11:14:31.192Z] GC before operation: completed in 113.570 ms, heap usage 504.020 MB -> 70.537 MB. [2026-01-21T11:14:40.179Z] ====== als (apache-spark) [default], iteration 24 completed (9316.594 ms) ====== [2026-01-21T11:14:40.179Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T11:14:40.179Z] GC before operation: completed in 111.364 ms, heap usage 180.673 MB -> 70.303 MB. [2026-01-21T11:14:50.452Z] ====== als (apache-spark) [default], iteration 25 completed (8969.186 ms) ====== [2026-01-21T11:14:50.452Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T11:14:50.452Z] GC before operation: completed in 80.586 ms, heap usage 155.296 MB -> 70.734 MB. [2026-01-21T11:15:00.721Z] ====== als (apache-spark) [default], iteration 26 completed (10266.179 ms) ====== [2026-01-21T11:15:00.721Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T11:15:00.721Z] GC before operation: completed in 137.909 ms, heap usage 454.204 MB -> 71.222 MB. [2026-01-21T11:15:11.024Z] ====== als (apache-spark) [default], iteration 27 completed (10270.357 ms) ====== [2026-01-21T11:15:11.024Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T11:15:11.024Z] GC before operation: completed in 136.004 ms, heap usage 144.331 MB -> 71.507 MB. [2026-01-21T11:15:21.317Z] ====== als (apache-spark) [default], iteration 28 completed (10035.866 ms) ====== [2026-01-21T11:15:21.317Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T11:15:21.317Z] GC before operation: completed in 126.406 ms, heap usage 247.721 MB -> 70.984 MB. [2026-01-21T11:15:31.612Z] ====== als (apache-spark) [default], iteration 29 completed (9991.493 ms) ====== [2026-01-21T11:15:31.612Z] ----------------------------------- [2026-01-21T11:15:31.612Z] renaissance-als_0_PASSED [2026-01-21T11:15:31.612Z] ----------------------------------- [2026-01-21T11:15:31.612Z] [2026-01-21T11:15:31.612Z] TEST TEARDOWN: [2026-01-21T11:15:31.612Z] Nothing to be done for teardown. [2026-01-21T11:15:31.612Z] renaissance-als_0 Finish Time: Wed Jan 21 11:15:30 2026 Epoch Time (ms): 1768994130996