[2026-01-03T13:36:19.879Z] Running test renaissance-als_0 ... [2026-01-03T13:36:19.879Z] =============================================== [2026-01-03T13:36:19.879Z] renaissance-als_0 Start Time: Sat Jan 3 08:36:19 2026 Epoch Time (ms): 1767447379626 [2026-01-03T13:36:19.879Z] variation: NoOptions [2026-01-03T13:36:19.879Z] JVM_OPTIONS: [2026-01-03T13:36:19.879Z] { \ [2026-01-03T13:36:19.879Z] echo ""; echo "TEST SETUP:"; \ [2026-01-03T13:36:19.879Z] echo "Nothing to be done for setup."; \ [2026-01-03T13:36:19.879Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1767447213975/renaissance-als_0"; \ [2026-01-03T13:36:19.879Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1767447213975/renaissance-als_0"; \ [2026-01-03T13:36:19.879Z] echo ""; echo "TESTING:"; \ [2026-01-03T13:36:19.879Z] "/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_1767447213975/renaissance-als_0"/als.json" als; \ [2026-01-03T13:36:19.879Z] 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_1767447213975/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-03T13:36:19.879Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-03T13:36:19.879Z] echo "Nothing to be done for teardown."; \ [2026-01-03T13:36:19.879Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1767447213975/TestTargetResult"; [2026-01-03T13:36:19.879Z] [2026-01-03T13:36:19.879Z] TEST SETUP: [2026-01-03T13:36:19.879Z] Nothing to be done for setup. [2026-01-03T13:36:19.879Z] [2026-01-03T13:36:19.879Z] TESTING: [2026-01-03T13:36:20.511Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-03T13:36:20.511Z] 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_1767447213975/renaissance-als_0/launcher-083619-218806992544567062/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-03T13:36:20.511Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-03T13:36:20.511Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-03T13:36:25.361Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-03T13:36:28.402Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-03T13:36:28.402Z] GC before operation: completed in 51.001 ms, heap usage 168.016 MB -> 35.154 MB. [2026-01-03T13:36:52.451Z] ====== als (apache-spark) [default], iteration 0 completed (21170.389 ms) ====== [2026-01-03T13:36:52.451Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-03T13:36:52.451Z] GC before operation: completed in 120.439 ms, heap usage 214.177 MB -> 61.543 MB. [2026-01-03T13:37:03.117Z] ====== als (apache-spark) [default], iteration 1 completed (12525.423 ms) ====== [2026-01-03T13:37:03.117Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-03T13:37:03.117Z] GC before operation: completed in 125.089 ms, heap usage 460.860 MB -> 62.875 MB. [2026-01-03T13:37:15.751Z] ====== als (apache-spark) [default], iteration 2 completed (11113.677 ms) ====== [2026-01-03T13:37:15.751Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-03T13:37:15.751Z] GC before operation: completed in 132.139 ms, heap usage 355.437 MB -> 63.164 MB. [2026-01-03T13:37:26.653Z] ====== als (apache-spark) [default], iteration 3 completed (11100.614 ms) ====== [2026-01-03T13:37:26.653Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-03T13:37:26.653Z] GC before operation: completed in 88.244 ms, heap usage 263.036 MB -> 63.648 MB. [2026-01-03T13:37:37.249Z] ====== als (apache-spark) [default], iteration 4 completed (10664.538 ms) ====== [2026-01-03T13:37:37.249Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-03T13:37:37.249Z] GC before operation: completed in 126.738 ms, heap usage 132.912 MB -> 64.030 MB. [2026-01-03T13:37:47.989Z] ====== als (apache-spark) [default], iteration 5 completed (10575.511 ms) ====== [2026-01-03T13:37:47.989Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-03T13:37:47.989Z] GC before operation: completed in 103.770 ms, heap usage 363.693 MB -> 64.838 MB. [2026-01-03T13:37:58.640Z] ====== als (apache-spark) [default], iteration 6 completed (10196.071 ms) ====== [2026-01-03T13:37:58.640Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-03T13:37:58.640Z] GC before operation: completed in 165.027 ms, heap usage 68.623 MB -> 64.896 MB. [2026-01-03T13:38:09.661Z] ====== als (apache-spark) [default], iteration 7 completed (10752.077 ms) ====== [2026-01-03T13:38:09.661Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-03T13:38:09.661Z] GC before operation: completed in 114.023 ms, heap usage 235.308 MB -> 65.770 MB. [2026-01-03T13:38:20.290Z] ====== als (apache-spark) [default], iteration 8 completed (10130.596 ms) ====== [2026-01-03T13:38:20.290Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-03T13:38:20.290Z] GC before operation: completed in 104.129 ms, heap usage 280.760 MB -> 66.406 MB. [2026-01-03T13:38:30.931Z] ====== als (apache-spark) [default], iteration 9 completed (10261.068 ms) ====== [2026-01-03T13:38:30.931Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-03T13:38:30.931Z] GC before operation: completed in 153.275 ms, heap usage 103.930 MB -> 66.993 MB. [2026-01-03T13:38:41.594Z] ====== als (apache-spark) [default], iteration 10 completed (10487.287 ms) ====== [2026-01-03T13:38:41.594Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-03T13:38:41.594Z] GC before operation: completed in 137.798 ms, heap usage 426.921 MB -> 67.191 MB. [2026-01-03T13:38:52.704Z] ====== als (apache-spark) [default], iteration 11 completed (10178.609 ms) ====== [2026-01-03T13:38:52.704Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-03T13:38:52.704Z] GC before operation: completed in 83.996 ms, heap usage 160.299 MB -> 67.097 MB. [2026-01-03T13:39:01.644Z] ====== als (apache-spark) [default], iteration 12 completed (9028.208 ms) ====== [2026-01-03T13:39:01.644Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-03T13:39:01.644Z] GC before operation: completed in 128.717 ms, heap usage 485.804 MB -> 67.753 MB. [2026-01-03T13:39:12.223Z] ====== als (apache-spark) [default], iteration 13 completed (9796.652 ms) ====== [2026-01-03T13:39:12.224Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-03T13:39:12.224Z] GC before operation: completed in 147.723 ms, heap usage 367.337 MB -> 67.835 MB. [2026-01-03T13:39:22.841Z] ====== als (apache-spark) [default], iteration 14 completed (10753.581 ms) ====== [2026-01-03T13:39:22.841Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-03T13:39:22.841Z] GC before operation: completed in 138.809 ms, heap usage 103.386 MB -> 67.884 MB. [2026-01-03T13:39:33.505Z] ====== als (apache-spark) [default], iteration 15 completed (10154.244 ms) ====== [2026-01-03T13:39:33.505Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-03T13:39:33.505Z] GC before operation: completed in 110.788 ms, heap usage 153.363 MB -> 68.155 MB. [2026-01-03T13:39:43.983Z] ====== als (apache-spark) [default], iteration 16 completed (10345.237 ms) ====== [2026-01-03T13:39:43.983Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-03T13:39:43.983Z] GC before operation: completed in 125.383 ms, heap usage 254.638 MB -> 68.348 MB. [2026-01-03T13:39:54.814Z] ====== als (apache-spark) [default], iteration 17 completed (10674.073 ms) ====== [2026-01-03T13:39:54.814Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-03T13:39:54.814Z] GC before operation: completed in 156.034 ms, heap usage 440.079 MB -> 69.068 MB. [2026-01-03T13:40:05.326Z] ====== als (apache-spark) [default], iteration 18 completed (10313.156 ms) ====== [2026-01-03T13:40:05.326Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-03T13:40:05.326Z] GC before operation: completed in 128.995 ms, heap usage 119.157 MB -> 68.333 MB. [2026-01-03T13:40:15.969Z] ====== als (apache-spark) [default], iteration 19 completed (10396.633 ms) ====== [2026-01-03T13:40:15.969Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-03T13:40:15.969Z] GC before operation: completed in 135.236 ms, heap usage 512.957 MB -> 69.585 MB. [2026-01-03T13:40:27.045Z] ====== als (apache-spark) [default], iteration 20 completed (10590.965 ms) ====== [2026-01-03T13:40:27.045Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-03T13:40:27.045Z] GC before operation: completed in 119.258 ms, heap usage 475.416 MB -> 69.821 MB. [2026-01-03T13:40:37.755Z] ====== als (apache-spark) [default], iteration 21 completed (10301.717 ms) ====== [2026-01-03T13:40:37.755Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-03T13:40:37.755Z] GC before operation: completed in 160.972 ms, heap usage 211.369 MB -> 69.747 MB. [2026-01-03T13:40:48.377Z] ====== als (apache-spark) [default], iteration 22 completed (10570.494 ms) ====== [2026-01-03T13:40:48.377Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-03T13:40:48.377Z] GC before operation: completed in 152.387 ms, heap usage 235.250 MB -> 69.984 MB. [2026-01-03T13:40:59.195Z] ====== als (apache-spark) [default], iteration 23 completed (10558.649 ms) ====== [2026-01-03T13:40:59.195Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-03T13:40:59.195Z] GC before operation: completed in 113.824 ms, heap usage 261.138 MB -> 70.441 MB. [2026-01-03T13:41:10.096Z] ====== als (apache-spark) [default], iteration 24 completed (10571.941 ms) ====== [2026-01-03T13:41:10.096Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-03T13:41:10.096Z] GC before operation: completed in 126.006 ms, heap usage 228.974 MB -> 70.592 MB. [2026-01-03T13:41:20.670Z] ====== als (apache-spark) [default], iteration 25 completed (9673.354 ms) ====== [2026-01-03T13:41:20.670Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-03T13:41:20.670Z] GC before operation: completed in 101.652 ms, heap usage 495.948 MB -> 71.159 MB. [2026-01-03T13:41:31.246Z] ====== als (apache-spark) [default], iteration 26 completed (10042.236 ms) ====== [2026-01-03T13:41:31.246Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-03T13:41:31.246Z] GC before operation: completed in 147.469 ms, heap usage 128.868 MB -> 70.453 MB. [2026-01-03T13:41:41.900Z] ====== als (apache-spark) [default], iteration 27 completed (10047.676 ms) ====== [2026-01-03T13:41:41.900Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-03T13:41:41.900Z] GC before operation: completed in 93.916 ms, heap usage 339.233 MB -> 71.441 MB. [2026-01-03T13:41:49.231Z] ====== als (apache-spark) [default], iteration 28 completed (8464.187 ms) ====== [2026-01-03T13:41:49.231Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-03T13:41:49.231Z] GC before operation: completed in 93.327 ms, heap usage 491.847 MB -> 71.859 MB. [2026-01-03T13:41:58.445Z] ====== als (apache-spark) [default], iteration 29 completed (8428.708 ms) ====== [2026-01-03T13:41:58.445Z] ----------------------------------- [2026-01-03T13:41:58.445Z] renaissance-als_0_PASSED [2026-01-03T13:41:58.445Z] ----------------------------------- [2026-01-03T13:41:58.445Z] [2026-01-03T13:41:58.445Z] TEST TEARDOWN: [2026-01-03T13:41:58.445Z] Nothing to be done for teardown. [2026-01-03T13:41:58.445Z] renaissance-als_0 Finish Time: Sat Jan 3 08:41:57 2026 Epoch Time (ms): 1767447717986