[2026-01-10T04:43:30.881Z] Running test renaissance-als_0 ... [2026-01-10T04:43:30.881Z] =============================================== [2026-01-10T04:43:30.881Z] renaissance-als_0 Start Time: Sat Jan 10 04:43:30 2026 Epoch Time (ms): 1768020210714 [2026-01-10T04:43:30.881Z] variation: NoOptions [2026-01-10T04:43:30.881Z] JVM_OPTIONS: [2026-01-10T04:43:30.881Z] { \ [2026-01-10T04:43:30.881Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T04:43:30.881Z] echo "Nothing to be done for setup."; \ [2026-01-10T04:43:30.881Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17680202106966/renaissance-als_0"; \ [2026-01-10T04:43:30.881Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17680202106966/renaissance-als_0"; \ [2026-01-10T04:43:30.881Z] echo ""; echo "TESTING:"; \ [2026-01-10T04:43:30.881Z] "/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_17680202106966/renaissance-als_0"/als.json" als; \ [2026-01-10T04:43:30.881Z] 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_17680202106966/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T04:43:30.881Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T04:43:30.881Z] echo "Nothing to be done for teardown."; \ [2026-01-10T04:43:30.881Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17680202106966/TestTargetResult"; [2026-01-10T04:43:30.881Z] [2026-01-10T04:43:30.881Z] TEST SETUP: [2026-01-10T04:43:30.881Z] Nothing to be done for setup. [2026-01-10T04:43:30.881Z] [2026-01-10T04:43:30.881Z] TESTING: [2026-01-10T04:43:31.499Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-10T04:43:31.499Z] 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_17680202106966/renaissance-als_0/launcher-044330-2017451857876408958/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-10T04:43:31.499Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-10T04:43:31.499Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-10T04:43:35.190Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-10T04:43:38.005Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T04:43:38.619Z] GC before operation: completed in 61.069 ms, heap usage 54.500 MB -> 35.153 MB. [2026-01-10T04:43:59.174Z] ====== als (apache-spark) [default], iteration 0 completed (18439.487 ms) ====== [2026-01-10T04:43:59.174Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T04:43:59.174Z] GC before operation: completed in 122.185 ms, heap usage 103.914 MB -> 61.517 MB. [2026-01-10T04:44:07.671Z] ====== als (apache-spark) [default], iteration 1 completed (9388.243 ms) ====== [2026-01-10T04:44:07.672Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T04:44:07.672Z] GC before operation: completed in 85.526 ms, heap usage 187.644 MB -> 62.318 MB. [2026-01-10T04:44:16.164Z] ====== als (apache-spark) [default], iteration 2 completed (8698.679 ms) ====== [2026-01-10T04:44:16.164Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T04:44:16.164Z] GC before operation: completed in 78.939 ms, heap usage 362.721 MB -> 63.120 MB. [2026-01-10T04:44:26.328Z] ====== als (apache-spark) [default], iteration 3 completed (9098.130 ms) ====== [2026-01-10T04:44:26.328Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T04:44:26.328Z] GC before operation: completed in 91.610 ms, heap usage 440.997 MB -> 63.946 MB. [2026-01-10T04:44:34.834Z] ====== als (apache-spark) [default], iteration 4 completed (8771.881 ms) ====== [2026-01-10T04:44:34.834Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T04:44:34.834Z] GC before operation: completed in 89.922 ms, heap usage 207.128 MB -> 63.986 MB. [2026-01-10T04:44:43.354Z] ====== als (apache-spark) [default], iteration 5 completed (8656.977 ms) ====== [2026-01-10T04:44:43.354Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T04:44:43.354Z] GC before operation: completed in 122.760 ms, heap usage 209.923 MB -> 64.553 MB. [2026-01-10T04:44:53.503Z] ====== als (apache-spark) [default], iteration 6 completed (8998.311 ms) ====== [2026-01-10T04:44:53.503Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T04:44:53.503Z] GC before operation: completed in 79.135 ms, heap usage 188.664 MB -> 65.209 MB. [2026-01-10T04:45:02.207Z] ====== als (apache-spark) [default], iteration 7 completed (8297.008 ms) ====== [2026-01-10T04:45:02.207Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T04:45:02.207Z] GC before operation: completed in 92.980 ms, heap usage 262.736 MB -> 65.753 MB. [2026-01-10T04:45:10.744Z] ====== als (apache-spark) [default], iteration 8 completed (8958.692 ms) ====== [2026-01-10T04:45:10.744Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T04:45:10.744Z] GC before operation: completed in 97.946 ms, heap usage 348.473 MB -> 66.441 MB. [2026-01-10T04:45:20.882Z] ====== als (apache-spark) [default], iteration 9 completed (9509.978 ms) ====== [2026-01-10T04:45:20.882Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T04:45:20.882Z] GC before operation: completed in 129.955 ms, heap usage 186.536 MB -> 66.458 MB. [2026-01-10T04:45:31.058Z] ====== als (apache-spark) [default], iteration 10 completed (9699.389 ms) ====== [2026-01-10T04:45:31.058Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T04:45:31.058Z] GC before operation: completed in 137.348 ms, heap usage 204.577 MB -> 66.670 MB. [2026-01-10T04:45:41.199Z] ====== als (apache-spark) [default], iteration 11 completed (9819.802 ms) ====== [2026-01-10T04:45:41.199Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T04:45:41.199Z] GC before operation: completed in 102.415 ms, heap usage 485.156 MB -> 67.317 MB. [2026-01-10T04:45:49.693Z] ====== als (apache-spark) [default], iteration 12 completed (9281.712 ms) ====== [2026-01-10T04:45:49.693Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T04:45:49.693Z] GC before operation: completed in 95.397 ms, heap usage 186.308 MB -> 67.176 MB. [2026-01-10T04:46:00.242Z] ====== als (apache-spark) [default], iteration 13 completed (9847.597 ms) ====== [2026-01-10T04:46:00.242Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T04:46:00.242Z] GC before operation: completed in 99.944 ms, heap usage 440.336 MB -> 67.888 MB. [2026-01-10T04:46:10.400Z] ====== als (apache-spark) [default], iteration 14 completed (9587.188 ms) ====== [2026-01-10T04:46:10.400Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T04:46:10.400Z] GC before operation: completed in 85.619 ms, heap usage 94.311 MB -> 67.744 MB. [2026-01-10T04:46:18.909Z] ====== als (apache-spark) [default], iteration 15 completed (8980.946 ms) ====== [2026-01-10T04:46:18.909Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T04:46:18.909Z] GC before operation: completed in 111.233 ms, heap usage 342.769 MB -> 68.096 MB. [2026-01-10T04:46:29.059Z] ====== als (apache-spark) [default], iteration 16 completed (9351.064 ms) ====== [2026-01-10T04:46:29.059Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T04:46:29.059Z] GC before operation: completed in 114.158 ms, heap usage 106.935 MB -> 68.214 MB. [2026-01-10T04:46:37.608Z] ====== als (apache-spark) [default], iteration 17 completed (8832.851 ms) ====== [2026-01-10T04:46:37.608Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T04:46:37.608Z] GC before operation: completed in 95.054 ms, heap usage 106.518 MB -> 68.401 MB. [2026-01-10T04:46:47.773Z] ====== als (apache-spark) [default], iteration 18 completed (9021.326 ms) ====== [2026-01-10T04:46:47.773Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T04:46:47.773Z] GC before operation: completed in 113.477 ms, heap usage 227.235 MB -> 68.777 MB. [2026-01-10T04:46:56.689Z] ====== als (apache-spark) [default], iteration 19 completed (9028.223 ms) ====== [2026-01-10T04:46:56.690Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T04:46:56.690Z] GC before operation: completed in 104.703 ms, heap usage 486.373 MB -> 69.453 MB. [2026-01-10T04:47:06.843Z] ====== als (apache-spark) [default], iteration 20 completed (9164.483 ms) ====== [2026-01-10T04:47:06.843Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T04:47:06.843Z] GC before operation: completed in 105.563 ms, heap usage 526.458 MB -> 69.777 MB. [2026-01-10T04:47:15.341Z] ====== als (apache-spark) [default], iteration 21 completed (9141.145 ms) ====== [2026-01-10T04:47:15.341Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T04:47:15.341Z] GC before operation: completed in 118.608 ms, heap usage 105.709 MB -> 69.509 MB. [2026-01-10T04:47:25.499Z] ====== als (apache-spark) [default], iteration 22 completed (9216.480 ms) ====== [2026-01-10T04:47:25.499Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T04:47:25.499Z] GC before operation: completed in 137.203 ms, heap usage 88.724 MB -> 69.243 MB. [2026-01-10T04:47:33.993Z] ====== als (apache-spark) [default], iteration 23 completed (8656.193 ms) ====== [2026-01-10T04:47:33.993Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T04:47:33.993Z] GC before operation: completed in 103.374 ms, heap usage 229.077 MB -> 70.168 MB. [2026-01-10T04:47:42.501Z] ====== als (apache-spark) [default], iteration 24 completed (8453.453 ms) ====== [2026-01-10T04:47:42.501Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T04:47:42.501Z] GC before operation: completed in 118.939 ms, heap usage 127.340 MB -> 70.311 MB. [2026-01-10T04:47:53.043Z] ====== als (apache-spark) [default], iteration 25 completed (9025.436 ms) ====== [2026-01-10T04:47:53.043Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T04:47:53.043Z] GC before operation: completed in 99.645 ms, heap usage 366.203 MB -> 70.756 MB. [2026-01-10T04:48:01.564Z] ====== als (apache-spark) [default], iteration 26 completed (9586.666 ms) ====== [2026-01-10T04:48:01.564Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T04:48:01.564Z] GC before operation: completed in 122.381 ms, heap usage 237.243 MB -> 70.969 MB. [2026-01-10T04:48:11.745Z] ====== als (apache-spark) [default], iteration 27 completed (9262.826 ms) ====== [2026-01-10T04:48:11.745Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T04:48:11.745Z] GC before operation: completed in 79.285 ms, heap usage 183.905 MB -> 71.101 MB. [2026-01-10T04:48:20.246Z] ====== als (apache-spark) [default], iteration 28 completed (8990.595 ms) ====== [2026-01-10T04:48:20.246Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T04:48:20.246Z] GC before operation: completed in 93.945 ms, heap usage 361.465 MB -> 71.113 MB. [2026-01-10T04:48:30.383Z] ====== als (apache-spark) [default], iteration 29 completed (9049.707 ms) ====== [2026-01-10T04:48:30.383Z] ----------------------------------- [2026-01-10T04:48:30.383Z] renaissance-als_0_PASSED [2026-01-10T04:48:30.383Z] ----------------------------------- [2026-01-10T04:48:30.383Z] [2026-01-10T04:48:30.383Z] TEST TEARDOWN: [2026-01-10T04:48:30.383Z] Nothing to be done for teardown. [2026-01-10T04:48:30.383Z] renaissance-als_0 Finish Time: Sat Jan 10 04:48:29 2026 Epoch Time (ms): 1768020509713