[2026-01-14T12:31:48.628Z] Running test renaissance-als_0 ... [2026-01-14T12:31:48.628Z] =============================================== [2026-01-14T12:31:48.628Z] renaissance-als_0 Start Time: Wed Jan 14 07:31:48 2026 Epoch Time (ms): 1768393908439 [2026-01-14T12:31:48.628Z] variation: NoOptions [2026-01-14T12:31:48.628Z] JVM_OPTIONS: [2026-01-14T12:31:48.628Z] { \ [2026-01-14T12:31:48.628Z] echo ""; echo "TEST SETUP:"; \ [2026-01-14T12:31:48.628Z] echo "Nothing to be done for setup."; \ [2026-01-14T12:31:48.628Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17683937009964/renaissance-als_0"; \ [2026-01-14T12:31:48.628Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17683937009964/renaissance-als_0"; \ [2026-01-14T12:31:48.628Z] echo ""; echo "TESTING:"; \ [2026-01-14T12:31:48.628Z] "/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_17683937009964/renaissance-als_0"/als.json" als; \ [2026-01-14T12:31:48.628Z] 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_17683937009964/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-14T12:31:48.628Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-14T12:31:48.628Z] echo "Nothing to be done for teardown."; \ [2026-01-14T12:31:48.628Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17683937009964/TestTargetResult"; [2026-01-14T12:31:48.628Z] [2026-01-14T12:31:48.628Z] TEST SETUP: [2026-01-14T12:31:48.628Z] Nothing to be done for setup. [2026-01-14T12:31:48.628Z] [2026-01-14T12:31:48.628Z] TESTING: [2026-01-14T12:31:49.506Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-14T12:31:49.506Z] 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_17683937009964/renaissance-als_0/launcher-073148-6002817580968826696/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-14T12:31:49.506Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-14T12:31:49.506Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-14T12:31:55.786Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-14T12:32:00.036Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-14T12:32:00.036Z] GC before operation: completed in 58.052 ms, heap usage 156.500 MB -> 35.174 MB. [2026-01-14T12:32:38.966Z] ====== als (apache-spark) [default], iteration 0 completed (33302.899 ms) ====== [2026-01-14T12:32:38.966Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-14T12:32:38.966Z] GC before operation: completed in 299.133 ms, heap usage 436.585 MB -> 61.798 MB. [2026-01-14T12:32:53.392Z] ====== als (apache-spark) [default], iteration 1 completed (18134.463 ms) ====== [2026-01-14T12:32:53.392Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-14T12:32:53.392Z] GC before operation: completed in 146.666 ms, heap usage 346.674 MB -> 62.644 MB. [2026-01-14T12:33:10.470Z] ====== als (apache-spark) [default], iteration 2 completed (16759.837 ms) ====== [2026-01-14T12:33:10.470Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-14T12:33:10.470Z] GC before operation: completed in 168.461 ms, heap usage 457.951 MB -> 63.442 MB. [2026-01-14T12:33:25.996Z] ====== als (apache-spark) [default], iteration 3 completed (15332.358 ms) ====== [2026-01-14T12:33:25.996Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-14T12:33:25.996Z] GC before operation: completed in 133.863 ms, heap usage 105.421 MB -> 64.261 MB. [2026-01-14T12:33:41.946Z] ====== als (apache-spark) [default], iteration 4 completed (15812.608 ms) ====== [2026-01-14T12:33:41.946Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-14T12:33:41.946Z] GC before operation: completed in 124.210 ms, heap usage 113.026 MB -> 64.470 MB. [2026-01-14T12:34:00.754Z] ====== als (apache-spark) [default], iteration 5 completed (16128.043 ms) ====== [2026-01-14T12:34:00.754Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-14T12:34:00.754Z] GC before operation: completed in 136.292 ms, heap usage 485.836 MB -> 65.138 MB. [2026-01-14T12:34:16.475Z] ====== als (apache-spark) [default], iteration 6 completed (17031.921 ms) ====== [2026-01-14T12:34:16.475Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-14T12:34:16.475Z] GC before operation: completed in 172.724 ms, heap usage 324.894 MB -> 65.537 MB. [2026-01-14T12:34:35.323Z] ====== als (apache-spark) [default], iteration 7 completed (17661.623 ms) ====== [2026-01-14T12:34:35.323Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-14T12:34:35.323Z] GC before operation: completed in 210.449 ms, heap usage 227.054 MB -> 66.126 MB. [2026-01-14T12:34:53.844Z] ====== als (apache-spark) [default], iteration 8 completed (18284.080 ms) ====== [2026-01-14T12:34:53.844Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-14T12:34:53.844Z] GC before operation: completed in 200.336 ms, heap usage 189.054 MB -> 66.231 MB. [2026-01-14T12:35:12.244Z] ====== als (apache-spark) [default], iteration 9 completed (17225.909 ms) ====== [2026-01-14T12:35:12.245Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-14T12:35:12.245Z] GC before operation: completed in 159.843 ms, heap usage 462.783 MB -> 67.277 MB. [2026-01-14T12:35:27.641Z] ====== als (apache-spark) [default], iteration 10 completed (15812.594 ms) ====== [2026-01-14T12:35:27.641Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-14T12:35:27.641Z] GC before operation: completed in 150.991 ms, heap usage 151.165 MB -> 66.753 MB. [2026-01-14T12:35:42.882Z] ====== als (apache-spark) [default], iteration 11 completed (15464.315 ms) ====== [2026-01-14T12:35:42.882Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-14T12:35:42.882Z] GC before operation: completed in 156.680 ms, heap usage 194.330 MB -> 67.064 MB. [2026-01-14T12:35:58.235Z] ====== als (apache-spark) [default], iteration 12 completed (15288.619 ms) ====== [2026-01-14T12:35:58.235Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-14T12:35:58.938Z] GC before operation: completed in 147.724 ms, heap usage 451.998 MB -> 67.800 MB. [2026-01-14T12:36:14.160Z] ====== als (apache-spark) [default], iteration 13 completed (15022.539 ms) ====== [2026-01-14T12:36:14.160Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-14T12:36:14.160Z] GC before operation: completed in 160.126 ms, heap usage 474.177 MB -> 67.998 MB. [2026-01-14T12:36:32.247Z] ====== als (apache-spark) [default], iteration 14 completed (16022.208 ms) ====== [2026-01-14T12:36:32.247Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-14T12:36:32.247Z] GC before operation: completed in 152.962 ms, heap usage 188.881 MB -> 67.802 MB. [2026-01-14T12:36:47.546Z] ====== als (apache-spark) [default], iteration 15 completed (16840.299 ms) ====== [2026-01-14T12:36:47.546Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-14T12:36:47.546Z] GC before operation: completed in 199.188 ms, heap usage 207.353 MB -> 68.170 MB. [2026-01-14T12:37:05.577Z] ====== als (apache-spark) [default], iteration 16 completed (16236.546 ms) ====== [2026-01-14T12:37:05.577Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-14T12:37:05.577Z] GC before operation: completed in 240.287 ms, heap usage 118.182 MB -> 67.824 MB. [2026-01-14T12:37:23.671Z] ====== als (apache-spark) [default], iteration 17 completed (17602.143 ms) ====== [2026-01-14T12:37:23.671Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-14T12:37:23.671Z] GC before operation: completed in 233.970 ms, heap usage 315.755 MB -> 68.848 MB. [2026-01-14T12:37:41.809Z] ====== als (apache-spark) [default], iteration 18 completed (17344.433 ms) ====== [2026-01-14T12:37:41.809Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-14T12:37:41.809Z] GC before operation: completed in 128.771 ms, heap usage 126.216 MB -> 68.362 MB. [2026-01-14T12:37:56.969Z] ====== als (apache-spark) [default], iteration 19 completed (16834.435 ms) ====== [2026-01-14T12:37:56.969Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-14T12:37:57.634Z] GC before operation: completed in 188.960 ms, heap usage 527.204 MB -> 69.619 MB. [2026-01-14T12:38:15.486Z] ====== als (apache-spark) [default], iteration 20 completed (15725.250 ms) ====== [2026-01-14T12:38:15.486Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-14T12:38:15.486Z] GC before operation: completed in 154.587 ms, heap usage 200.204 MB -> 69.435 MB. [2026-01-14T12:38:30.666Z] ====== als (apache-spark) [default], iteration 21 completed (15543.262 ms) ====== [2026-01-14T12:38:30.666Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-14T12:38:30.666Z] GC before operation: completed in 177.859 ms, heap usage 211.735 MB -> 69.587 MB. [2026-01-14T12:38:45.841Z] ====== als (apache-spark) [default], iteration 22 completed (15170.431 ms) ====== [2026-01-14T12:38:45.841Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-14T12:38:45.841Z] GC before operation: completed in 148.465 ms, heap usage 367.951 MB -> 70.135 MB. [2026-01-14T12:39:01.092Z] ====== als (apache-spark) [default], iteration 23 completed (14974.285 ms) ====== [2026-01-14T12:39:01.092Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-14T12:39:01.092Z] GC before operation: completed in 204.535 ms, heap usage 316.825 MB -> 70.384 MB. [2026-01-14T12:39:16.314Z] ====== als (apache-spark) [default], iteration 24 completed (15184.424 ms) ====== [2026-01-14T12:39:16.314Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-14T12:39:16.314Z] GC before operation: completed in 173.181 ms, heap usage 433.930 MB -> 70.865 MB. [2026-01-14T12:39:31.267Z] ====== als (apache-spark) [default], iteration 25 completed (14222.015 ms) ====== [2026-01-14T12:39:31.267Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-14T12:39:31.267Z] GC before operation: completed in 172.804 ms, heap usage 488.957 MB -> 71.065 MB. [2026-01-14T12:39:46.245Z] ====== als (apache-spark) [default], iteration 26 completed (13898.813 ms) ====== [2026-01-14T12:39:46.245Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-14T12:39:46.245Z] GC before operation: completed in 166.435 ms, heap usage 141.566 MB -> 70.880 MB. [2026-01-14T12:40:01.473Z] ====== als (apache-spark) [default], iteration 27 completed (14634.781 ms) ====== [2026-01-14T12:40:01.473Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-14T12:40:01.473Z] GC before operation: completed in 146.546 ms, heap usage 175.621 MB -> 71.158 MB. [2026-01-14T12:40:16.632Z] ====== als (apache-spark) [default], iteration 28 completed (15545.862 ms) ====== [2026-01-14T12:40:16.632Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-14T12:40:16.632Z] GC before operation: completed in 141.123 ms, heap usage 213.711 MB -> 71.458 MB. [2026-01-14T12:40:31.964Z] ====== als (apache-spark) [default], iteration 29 completed (15134.094 ms) ====== [2026-01-14T12:40:31.964Z] ----------------------------------- [2026-01-14T12:40:31.964Z] renaissance-als_0_PASSED [2026-01-14T12:40:31.964Z] ----------------------------------- [2026-01-14T12:40:31.964Z] [2026-01-14T12:40:31.964Z] TEST TEARDOWN: [2026-01-14T12:40:31.964Z] Nothing to be done for teardown. [2026-01-14T12:40:31.964Z] renaissance-als_0 Finish Time: Wed Jan 14 07:40:31 2026 Epoch Time (ms): 1768394431797