[2026-01-24T12:47:28.615Z] Running test renaissance-als_0 ... [2026-01-24T12:47:28.615Z] =============================================== [2026-01-24T12:47:28.615Z] renaissance-als_0 Start Time: Sat Jan 24 12:47:28 2026 Epoch Time (ms): 1769258848138 [2026-01-24T12:47:28.615Z] variation: NoOptions [2026-01-24T12:47:28.615Z] JVM_OPTIONS: [2026-01-24T12:47:28.615Z] { \ [2026-01-24T12:47:28.616Z] echo ""; echo "TEST SETUP:"; \ [2026-01-24T12:47:28.616Z] echo "Nothing to be done for setup."; \ [2026-01-24T12:47:28.616Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17692586428216/renaissance-als_0"; \ [2026-01-24T12:47:28.616Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17692586428216/renaissance-als_0"; \ [2026-01-24T12:47:28.616Z] echo ""; echo "TESTING:"; \ [2026-01-24T12:47:28.616Z] "/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_17692586428216/renaissance-als_0"/als.json" als; \ [2026-01-24T12:47:28.616Z] 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_17692586428216/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-24T12:47:28.616Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-24T12:47:28.616Z] echo "Nothing to be done for teardown."; \ [2026-01-24T12:47:28.616Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17692586428216/TestTargetResult"; [2026-01-24T12:47:28.616Z] [2026-01-24T12:47:28.616Z] TEST SETUP: [2026-01-24T12:47:28.616Z] Nothing to be done for setup. [2026-01-24T12:47:28.616Z] [2026-01-24T12:47:28.616Z] TESTING: [2026-01-24T12:47:28.616Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-24T12:47:28.616Z] 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_17692586428216/renaissance-als_0/launcher-124728-256815719430463581/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-24T12:47:28.616Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-24T12:47:28.616Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-24T12:47:33.512Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-24T12:47:37.325Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-24T12:47:37.325Z] GC before operation: completed in 62.671 ms, heap usage 170.500 MB -> 35.033 MB. [2026-01-24T12:48:05.458Z] ====== als (apache-spark) [default], iteration 0 completed (24207.428 ms) ====== [2026-01-24T12:48:05.458Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-24T12:48:05.458Z] GC before operation: completed in 115.315 ms, heap usage 195.913 MB -> 61.488 MB. [2026-01-24T12:48:17.827Z] ====== als (apache-spark) [default], iteration 1 completed (14778.673 ms) ====== [2026-01-24T12:48:17.828Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-24T12:48:17.828Z] GC before operation: completed in 123.183 ms, heap usage 263.652 MB -> 62.465 MB. [2026-01-24T12:48:32.565Z] ====== als (apache-spark) [default], iteration 2 completed (15352.665 ms) ====== [2026-01-24T12:48:32.565Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-24T12:48:33.268Z] GC before operation: completed in 160.652 ms, heap usage 217.499 MB -> 63.001 MB. [2026-01-24T12:48:47.776Z] ====== als (apache-spark) [default], iteration 3 completed (14371.923 ms) ====== [2026-01-24T12:48:47.776Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-24T12:48:47.776Z] GC before operation: completed in 112.001 ms, heap usage 441.382 MB -> 63.909 MB. [2026-01-24T12:49:02.256Z] ====== als (apache-spark) [default], iteration 4 completed (12621.177 ms) ====== [2026-01-24T12:49:02.256Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-24T12:49:02.256Z] GC before operation: completed in 128.233 ms, heap usage 202.373 MB -> 64.066 MB. [2026-01-24T12:49:14.633Z] ====== als (apache-spark) [default], iteration 5 completed (12766.637 ms) ====== [2026-01-24T12:49:14.633Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-24T12:49:14.633Z] GC before operation: completed in 125.013 ms, heap usage 484.152 MB -> 65.105 MB. [2026-01-24T12:49:27.442Z] ====== als (apache-spark) [default], iteration 6 completed (12369.760 ms) ====== [2026-01-24T12:49:27.442Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-24T12:49:27.442Z] GC before operation: completed in 133.263 ms, heap usage 217.607 MB -> 65.293 MB. [2026-01-24T12:49:39.726Z] ====== als (apache-spark) [default], iteration 7 completed (12583.645 ms) ====== [2026-01-24T12:49:39.726Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-24T12:49:39.726Z] GC before operation: completed in 122.567 ms, heap usage 368.353 MB -> 65.948 MB. [2026-01-24T12:49:51.941Z] ====== als (apache-spark) [default], iteration 8 completed (12156.932 ms) ====== [2026-01-24T12:49:51.941Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-24T12:49:51.941Z] GC before operation: completed in 117.224 ms, heap usage 154.080 MB -> 66.296 MB. [2026-01-24T12:50:06.454Z] ====== als (apache-spark) [default], iteration 9 completed (12549.373 ms) ====== [2026-01-24T12:50:06.454Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-24T12:50:06.454Z] GC before operation: completed in 122.034 ms, heap usage 464.014 MB -> 67.073 MB. [2026-01-24T12:50:18.769Z] ====== als (apache-spark) [default], iteration 10 completed (12717.411 ms) ====== [2026-01-24T12:50:18.769Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-24T12:50:18.769Z] GC before operation: completed in 113.381 ms, heap usage 91.521 MB -> 66.966 MB. [2026-01-24T12:50:31.221Z] ====== als (apache-spark) [default], iteration 11 completed (13160.917 ms) ====== [2026-01-24T12:50:31.221Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-24T12:50:31.221Z] GC before operation: completed in 108.086 ms, heap usage 283.835 MB -> 67.223 MB. [2026-01-24T12:50:45.930Z] ====== als (apache-spark) [default], iteration 12 completed (13163.407 ms) ====== [2026-01-24T12:50:45.930Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-24T12:50:45.930Z] GC before operation: completed in 106.650 ms, heap usage 331.796 MB -> 67.529 MB. [2026-01-24T12:50:58.379Z] ====== als (apache-spark) [default], iteration 13 completed (12359.074 ms) ====== [2026-01-24T12:50:58.379Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-24T12:50:58.379Z] GC before operation: completed in 188.139 ms, heap usage 449.594 MB -> 68.087 MB. [2026-01-24T12:51:10.565Z] ====== als (apache-spark) [default], iteration 14 completed (11667.431 ms) ====== [2026-01-24T12:51:10.565Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-24T12:51:10.565Z] GC before operation: completed in 126.672 ms, heap usage 318.971 MB -> 68.054 MB. [2026-01-24T12:51:20.814Z] ====== als (apache-spark) [default], iteration 15 completed (11458.518 ms) ====== [2026-01-24T12:51:20.814Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-24T12:51:20.814Z] GC before operation: completed in 112.918 ms, heap usage 309.371 MB -> 68.348 MB. [2026-01-24T12:51:33.186Z] ====== als (apache-spark) [default], iteration 16 completed (11334.753 ms) ====== [2026-01-24T12:51:33.186Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-24T12:51:33.186Z] GC before operation: completed in 136.217 ms, heap usage 347.556 MB -> 68.555 MB. [2026-01-24T12:51:45.325Z] ====== als (apache-spark) [default], iteration 17 completed (10854.935 ms) ====== [2026-01-24T12:51:45.325Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-24T12:51:45.325Z] GC before operation: completed in 115.540 ms, heap usage 374.864 MB -> 68.774 MB. [2026-01-24T12:51:55.596Z] ====== als (apache-spark) [default], iteration 18 completed (11158.116 ms) ====== [2026-01-24T12:51:55.596Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-24T12:51:55.596Z] GC before operation: completed in 150.987 ms, heap usage 244.267 MB -> 69.007 MB. [2026-01-24T12:52:07.771Z] ====== als (apache-spark) [default], iteration 19 completed (11239.606 ms) ====== [2026-01-24T12:52:07.771Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-24T12:52:07.771Z] GC before operation: completed in 131.811 ms, heap usage 100.881 MB -> 69.075 MB. [2026-01-24T12:52:19.892Z] ====== als (apache-spark) [default], iteration 20 completed (11626.509 ms) ====== [2026-01-24T12:52:19.892Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-24T12:52:19.892Z] GC before operation: completed in 142.436 ms, heap usage 236.745 MB -> 69.502 MB. [2026-01-24T12:52:30.121Z] ====== als (apache-spark) [default], iteration 21 completed (10948.367 ms) ====== [2026-01-24T12:52:30.121Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-24T12:52:30.121Z] GC before operation: completed in 118.881 ms, heap usage 300.370 MB -> 69.874 MB. [2026-01-24T12:52:42.276Z] ====== als (apache-spark) [default], iteration 22 completed (10478.421 ms) ====== [2026-01-24T12:52:42.276Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-24T12:52:42.276Z] GC before operation: completed in 165.623 ms, heap usage 461.855 MB -> 70.316 MB. [2026-01-24T12:52:54.649Z] ====== als (apache-spark) [default], iteration 23 completed (12035.343 ms) ====== [2026-01-24T12:52:54.649Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-24T12:52:54.649Z] GC before operation: completed in 125.926 ms, heap usage 301.057 MB -> 70.370 MB. [2026-01-24T12:53:07.020Z] ====== als (apache-spark) [default], iteration 24 completed (12533.320 ms) ====== [2026-01-24T12:53:07.020Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-24T12:53:07.020Z] GC before operation: completed in 122.573 ms, heap usage 79.973 MB -> 70.438 MB. [2026-01-24T12:53:19.917Z] ====== als (apache-spark) [default], iteration 25 completed (12003.002 ms) ====== [2026-01-24T12:53:19.917Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-24T12:53:19.917Z] GC before operation: completed in 116.664 ms, heap usage 486.342 MB -> 71.121 MB. [2026-01-24T12:53:32.162Z] ====== als (apache-spark) [default], iteration 26 completed (11930.403 ms) ====== [2026-01-24T12:53:32.162Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-24T12:53:32.162Z] GC before operation: completed in 97.100 ms, heap usage 331.812 MB -> 71.201 MB. [2026-01-24T12:53:42.441Z] ====== als (apache-spark) [default], iteration 27 completed (11418.471 ms) ====== [2026-01-24T12:53:42.441Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-24T12:53:42.441Z] GC before operation: completed in 99.718 ms, heap usage 330.281 MB -> 71.533 MB. [2026-01-24T12:53:57.155Z] ====== als (apache-spark) [default], iteration 28 completed (12801.189 ms) ====== [2026-01-24T12:53:57.155Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-24T12:53:57.155Z] GC before operation: completed in 150.809 ms, heap usage 276.424 MB -> 71.682 MB. [2026-01-24T12:54:09.679Z] ====== als (apache-spark) [default], iteration 29 completed (12753.629 ms) ====== [2026-01-24T12:54:09.679Z] ----------------------------------- [2026-01-24T12:54:09.679Z] renaissance-als_0_PASSED [2026-01-24T12:54:09.679Z] ----------------------------------- [2026-01-24T12:54:09.679Z] [2026-01-24T12:54:09.679Z] TEST TEARDOWN: [2026-01-24T12:54:09.679Z] Nothing to be done for teardown. [2026-01-24T12:54:09.679Z] renaissance-als_0 Finish Time: Sat Jan 24 12:54:08 2026 Epoch Time (ms): 1769259248605