[2026-01-31T10:16:14.786Z] Running test renaissance-als_0 ... [2026-01-31T10:16:14.786Z] =============================================== [2026-01-31T10:16:14.786Z] renaissance-als_0 Start Time: Sat Jan 31 10:16:14 2026 Epoch Time (ms): 1769854574166 [2026-01-31T10:16:14.786Z] variation: NoOptions [2026-01-31T10:16:14.786Z] JVM_OPTIONS: [2026-01-31T10:16:14.786Z] { \ [2026-01-31T10:16:14.786Z] echo ""; echo "TEST SETUP:"; \ [2026-01-31T10:16:14.786Z] echo "Nothing to be done for setup."; \ [2026-01-31T10:16:14.786Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698545732452/renaissance-als_0"; \ [2026-01-31T10:16:14.786Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698545732452/renaissance-als_0"; \ [2026-01-31T10:16:14.786Z] echo ""; echo "TESTING:"; \ [2026-01-31T10:16:14.786Z] "/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_17698545732452/renaissance-als_0"/als.json" als; \ [2026-01-31T10:16:14.786Z] 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_17698545732452/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-31T10:16:14.786Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-31T10:16:14.786Z] echo "Nothing to be done for teardown."; \ [2026-01-31T10:16:14.786Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698545732452/TestTargetResult"; [2026-01-31T10:16:14.786Z] [2026-01-31T10:16:14.786Z] TEST SETUP: [2026-01-31T10:16:14.786Z] Nothing to be done for setup. [2026-01-31T10:16:14.787Z] [2026-01-31T10:16:14.787Z] TESTING: [2026-01-31T10:16:16.940Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-31T10:16:16.940Z] 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_17698545732452/renaissance-als_0/launcher-101614-693353928870181455/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-31T10:16:16.940Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-31T10:16:16.940Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-31T10:16:27.755Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-31T10:16:35.388Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-31T10:16:35.388Z] GC before operation: completed in 101.769 ms, heap usage 56.500 MB -> 35.225 MB. [2026-01-31T10:17:15.121Z] ====== als (apache-spark) [default], iteration 0 completed (35436.848 ms) ====== [2026-01-31T10:17:15.121Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-31T10:17:15.121Z] GC before operation: completed in 248.963 ms, heap usage 275.873 MB -> 61.683 MB. [2026-01-31T10:17:32.735Z] ====== als (apache-spark) [default], iteration 1 completed (20214.118 ms) ====== [2026-01-31T10:17:32.735Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-31T10:17:32.735Z] GC before operation: completed in 101.216 ms, heap usage 224.023 MB -> 62.552 MB. [2026-01-31T10:17:53.938Z] ====== als (apache-spark) [default], iteration 2 completed (18472.795 ms) ====== [2026-01-31T10:17:53.938Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-31T10:17:53.938Z] GC before operation: completed in 214.351 ms, heap usage 208.782 MB -> 63.012 MB. [2026-01-31T10:18:08.641Z] ====== als (apache-spark) [default], iteration 3 completed (16646.320 ms) ====== [2026-01-31T10:18:08.641Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-31T10:18:08.641Z] GC before operation: completed in 148.984 ms, heap usage 155.393 MB -> 63.605 MB. [2026-01-31T10:18:26.442Z] ====== als (apache-spark) [default], iteration 4 completed (15230.145 ms) ====== [2026-01-31T10:18:26.442Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-31T10:18:26.442Z] GC before operation: completed in 136.489 ms, heap usage 103.959 MB -> 64.186 MB. [2026-01-31T10:18:41.237Z] ====== als (apache-spark) [default], iteration 5 completed (16649.228 ms) ====== [2026-01-31T10:18:41.237Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-31T10:18:41.237Z] GC before operation: completed in 224.928 ms, heap usage 203.377 MB -> 64.739 MB. [2026-01-31T10:18:58.637Z] ====== als (apache-spark) [default], iteration 6 completed (16759.666 ms) ====== [2026-01-31T10:18:58.637Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-31T10:18:58.637Z] GC before operation: completed in 183.599 ms, heap usage 429.549 MB -> 65.783 MB. [2026-01-31T10:19:19.427Z] ====== als (apache-spark) [default], iteration 7 completed (18265.077 ms) ====== [2026-01-31T10:19:19.427Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-31T10:19:19.427Z] GC before operation: completed in 252.002 ms, heap usage 282.181 MB -> 66.020 MB. [2026-01-31T10:19:36.968Z] ====== als (apache-spark) [default], iteration 8 completed (17723.502 ms) ====== [2026-01-31T10:19:36.968Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-31T10:19:36.968Z] GC before operation: completed in 119.752 ms, heap usage 341.773 MB -> 66.593 MB. [2026-01-31T10:19:54.642Z] ====== als (apache-spark) [default], iteration 9 completed (18942.606 ms) ====== [2026-01-31T10:19:54.642Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-31T10:19:55.365Z] GC before operation: completed in 212.239 ms, heap usage 377.433 MB -> 67.226 MB. [2026-01-31T10:20:14.222Z] ====== als (apache-spark) [default], iteration 10 completed (18765.813 ms) ====== [2026-01-31T10:20:14.222Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-31T10:20:14.222Z] GC before operation: completed in 284.478 ms, heap usage 92.890 MB -> 66.833 MB. [2026-01-31T10:20:35.020Z] ====== als (apache-spark) [default], iteration 11 completed (18934.402 ms) ====== [2026-01-31T10:20:35.020Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-31T10:20:35.020Z] GC before operation: completed in 198.783 ms, heap usage 209.649 MB -> 67.070 MB. [2026-01-31T10:20:55.691Z] ====== als (apache-spark) [default], iteration 12 completed (19295.087 ms) ====== [2026-01-31T10:20:55.691Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-31T10:20:55.691Z] GC before operation: completed in 142.444 ms, heap usage 198.936 MB -> 67.366 MB. [2026-01-31T10:21:13.263Z] ====== als (apache-spark) [default], iteration 13 completed (17621.862 ms) ====== [2026-01-31T10:21:13.263Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-31T10:21:13.263Z] GC before operation: completed in 174.132 ms, heap usage 185.321 MB -> 67.513 MB. [2026-01-31T10:21:30.887Z] ====== als (apache-spark) [default], iteration 14 completed (17314.886 ms) ====== [2026-01-31T10:21:30.887Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-31T10:21:30.887Z] GC before operation: completed in 184.453 ms, heap usage 236.071 MB -> 67.944 MB. [2026-01-31T10:21:48.391Z] ====== als (apache-spark) [default], iteration 15 completed (16643.047 ms) ====== [2026-01-31T10:21:48.391Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-31T10:21:48.391Z] GC before operation: completed in 308.635 ms, heap usage 490.545 MB -> 68.484 MB. [2026-01-31T10:22:06.611Z] ====== als (apache-spark) [default], iteration 16 completed (18010.622 ms) ====== [2026-01-31T10:22:06.611Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-31T10:22:06.611Z] GC before operation: completed in 227.309 ms, heap usage 367.048 MB -> 68.564 MB. [2026-01-31T10:22:24.127Z] ====== als (apache-spark) [default], iteration 17 completed (18201.732 ms) ====== [2026-01-31T10:22:24.127Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-31T10:22:24.127Z] GC before operation: completed in 125.627 ms, heap usage 245.733 MB -> 68.666 MB. [2026-01-31T10:22:44.919Z] ====== als (apache-spark) [default], iteration 18 completed (17919.303 ms) ====== [2026-01-31T10:22:44.919Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-31T10:22:44.919Z] GC before operation: completed in 256.028 ms, heap usage 262.560 MB -> 68.962 MB. [2026-01-31T10:23:02.570Z] ====== als (apache-spark) [default], iteration 19 completed (19523.918 ms) ====== [2026-01-31T10:23:02.570Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-31T10:23:02.570Z] GC before operation: completed in 181.116 ms, heap usage 118.536 MB -> 69.208 MB. [2026-01-31T10:23:23.757Z] ====== als (apache-spark) [default], iteration 20 completed (20809.100 ms) ====== [2026-01-31T10:23:23.757Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-31T10:23:24.454Z] GC before operation: completed in 181.610 ms, heap usage 197.931 MB -> 69.379 MB. [2026-01-31T10:23:45.349Z] ====== als (apache-spark) [default], iteration 21 completed (19237.253 ms) ====== [2026-01-31T10:23:45.349Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-31T10:23:45.349Z] GC before operation: completed in 300.086 ms, heap usage 201.746 MB -> 69.619 MB. [2026-01-31T10:24:03.117Z] ====== als (apache-spark) [default], iteration 22 completed (18982.124 ms) ====== [2026-01-31T10:24:03.117Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-31T10:24:03.817Z] GC before operation: completed in 258.370 ms, heap usage 346.997 MB -> 70.100 MB. [2026-01-31T10:24:22.311Z] ====== als (apache-spark) [default], iteration 23 completed (18337.102 ms) ====== [2026-01-31T10:24:22.311Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-31T10:24:22.311Z] GC before operation: completed in 227.239 ms, heap usage 214.743 MB -> 70.149 MB. [2026-01-31T10:24:43.208Z] ====== als (apache-spark) [default], iteration 24 completed (18719.636 ms) ====== [2026-01-31T10:24:43.208Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-31T10:24:43.208Z] GC before operation: completed in 214.950 ms, heap usage 389.676 MB -> 70.679 MB. [2026-01-31T10:25:01.037Z] ====== als (apache-spark) [default], iteration 25 completed (18819.161 ms) ====== [2026-01-31T10:25:01.037Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-31T10:25:01.037Z] GC before operation: completed in 229.605 ms, heap usage 523.433 MB -> 71.258 MB. [2026-01-31T10:25:22.038Z] ====== als (apache-spark) [default], iteration 26 completed (18903.718 ms) ====== [2026-01-31T10:25:22.038Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-31T10:25:22.038Z] GC before operation: completed in 195.536 ms, heap usage 433.713 MB -> 71.428 MB. [2026-01-31T10:25:43.248Z] ====== als (apache-spark) [default], iteration 27 completed (20500.061 ms) ====== [2026-01-31T10:25:43.248Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-31T10:25:43.248Z] GC before operation: completed in 177.871 ms, heap usage 219.724 MB -> 71.370 MB. [2026-01-31T10:26:00.870Z] ====== als (apache-spark) [default], iteration 28 completed (18765.918 ms) ====== [2026-01-31T10:26:00.870Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-31T10:26:00.870Z] GC before operation: completed in 246.302 ms, heap usage 231.669 MB -> 71.563 MB. [2026-01-31T10:26:18.429Z] ====== als (apache-spark) [default], iteration 29 completed (17202.941 ms) ====== [2026-01-31T10:26:18.429Z] ----------------------------------- [2026-01-31T10:26:18.429Z] renaissance-als_0_PASSED [2026-01-31T10:26:18.429Z] ----------------------------------- [2026-01-31T10:26:18.429Z] [2026-01-31T10:26:18.429Z] TEST TEARDOWN: [2026-01-31T10:26:18.429Z] Nothing to be done for teardown. [2026-01-31T10:26:18.429Z] renaissance-als_0 Finish Time: Sat Jan 31 10:26:18 2026 Epoch Time (ms): 1769855178351