[2026-04-24T09:13:05.645Z] Running test renaissance-als_0 ... [2026-04-24T09:13:05.645Z] =============================================== [2026-04-24T09:13:05.645Z] renaissance-als_0 Start Time: Fri Apr 24 09:13:05 2026 Epoch Time (ms): 1777021985284 [2026-04-24T09:13:05.645Z] variation: NoOptions [2026-04-24T09:13:05.645Z] JVM_OPTIONS: [2026-04-24T09:13:05.645Z] { \ [2026-04-24T09:13:05.645Z] echo ""; echo "TEST SETUP:"; \ [2026-04-24T09:13:05.645Z] echo "Nothing to be done for setup."; \ [2026-04-24T09:13:05.645Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777021984442/renaissance-als_0"; \ [2026-04-24T09:13:05.645Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777021984442/renaissance-als_0"; \ [2026-04-24T09:13:05.645Z] echo ""; echo "TESTING:"; \ [2026-04-24T09:13:05.645Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777021984442/renaissance-als_0"/als.json" als; \ [2026-04-24T09:13:05.645Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777021984442/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-24T09:13:05.645Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-24T09:13:05.645Z] echo "Nothing to be done for teardown."; \ [2026-04-24T09:13:05.645Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777021984442/TestTargetResult"; [2026-04-24T09:13:05.645Z] [2026-04-24T09:13:05.645Z] TEST SETUP: [2026-04-24T09:13:05.645Z] Nothing to be done for setup. [2026-04-24T09:13:05.645Z] [2026-04-24T09:13:05.645Z] TESTING: [2026-04-24T09:13:16.603Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-24T09:13:21.824Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-24T09:13:22.548Z] GC before operation: completed in 142.466 ms, heap usage 52.500 MB -> 34.981 MB. [2026-04-24T09:13:56.874Z] ====== als (apache-spark) [default], iteration 0 completed (32046.165 ms) ====== [2026-04-24T09:13:56.874Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-24T09:13:56.874Z] GC before operation: completed in 229.775 ms, heap usage 265.093 MB -> 61.701 MB. [2026-04-24T09:14:14.810Z] ====== als (apache-spark) [default], iteration 1 completed (16950.604 ms) ====== [2026-04-24T09:14:14.810Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-24T09:14:14.810Z] GC before operation: completed in 272.904 ms, heap usage 155.537 MB -> 62.494 MB. [2026-04-24T09:14:30.084Z] ====== als (apache-spark) [default], iteration 2 completed (15329.063 ms) ====== [2026-04-24T09:14:30.084Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-24T09:14:30.084Z] GC before operation: completed in 153.275 ms, heap usage 266.438 MB -> 63.204 MB. [2026-04-24T09:14:46.080Z] ====== als (apache-spark) [default], iteration 3 completed (15886.810 ms) ====== [2026-04-24T09:14:46.080Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-24T09:14:46.080Z] GC before operation: completed in 218.404 ms, heap usage 221.652 MB -> 63.741 MB. [2026-04-24T09:15:01.319Z] ====== als (apache-spark) [default], iteration 4 completed (14435.643 ms) ====== [2026-04-24T09:15:01.319Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-24T09:15:01.319Z] GC before operation: completed in 201.479 ms, heap usage 166.774 MB -> 64.387 MB. [2026-04-24T09:15:16.764Z] ====== als (apache-spark) [default], iteration 5 completed (15415.681 ms) ====== [2026-04-24T09:15:16.764Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-24T09:15:16.764Z] GC before operation: completed in 237.340 ms, heap usage 304.723 MB -> 64.897 MB. [2026-04-24T09:15:32.304Z] ====== als (apache-spark) [default], iteration 6 completed (15519.628 ms) ====== [2026-04-24T09:15:32.304Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-24T09:15:33.074Z] GC before operation: completed in 223.685 ms, heap usage 125.935 MB -> 64.739 MB. [2026-04-24T09:15:48.504Z] ====== als (apache-spark) [default], iteration 7 completed (15520.453 ms) ====== [2026-04-24T09:15:48.504Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-24T09:15:48.504Z] GC before operation: completed in 157.561 ms, heap usage 248.620 MB -> 65.980 MB. [2026-04-24T09:16:03.870Z] ====== als (apache-spark) [default], iteration 8 completed (14370.224 ms) ====== [2026-04-24T09:16:03.870Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-24T09:16:03.870Z] GC before operation: completed in 254.463 ms, heap usage 255.667 MB -> 66.460 MB. [2026-04-24T09:16:19.363Z] ====== als (apache-spark) [default], iteration 9 completed (15376.335 ms) ====== [2026-04-24T09:16:19.363Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-24T09:16:20.053Z] GC before operation: completed in 203.967 ms, heap usage 363.561 MB -> 67.228 MB. [2026-04-24T09:16:35.536Z] ====== als (apache-spark) [default], iteration 10 completed (15074.123 ms) ====== [2026-04-24T09:16:35.536Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-24T09:16:35.536Z] GC before operation: completed in 185.036 ms, heap usage 277.667 MB -> 66.940 MB. [2026-04-24T09:16:52.013Z] ====== als (apache-spark) [default], iteration 11 completed (14635.920 ms) ====== [2026-04-24T09:16:52.014Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-24T09:16:52.014Z] GC before operation: completed in 197.688 ms, heap usage 219.483 MB -> 67.020 MB. [2026-04-24T09:17:05.084Z] ====== als (apache-spark) [default], iteration 12 completed (14083.975 ms) ====== [2026-04-24T09:17:05.084Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-24T09:17:05.084Z] GC before operation: completed in 150.614 ms, heap usage 276.188 MB -> 67.472 MB. [2026-04-24T09:17:20.344Z] ====== als (apache-spark) [default], iteration 13 completed (14114.145 ms) ====== [2026-04-24T09:17:20.344Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-24T09:17:20.344Z] GC before operation: completed in 145.594 ms, heap usage 170.176 MB -> 67.639 MB. [2026-04-24T09:17:35.694Z] ====== als (apache-spark) [default], iteration 14 completed (14572.718 ms) ====== [2026-04-24T09:17:35.694Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-24T09:17:35.694Z] GC before operation: completed in 174.027 ms, heap usage 487.248 MB -> 68.287 MB. [2026-04-24T09:17:50.864Z] ====== als (apache-spark) [default], iteration 15 completed (14767.754 ms) ====== [2026-04-24T09:17:50.864Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-24T09:17:50.864Z] GC before operation: completed in 191.304 ms, heap usage 227.395 MB -> 68.093 MB. [2026-04-24T09:18:06.464Z] ====== als (apache-spark) [default], iteration 16 completed (15226.139 ms) ====== [2026-04-24T09:18:06.464Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-24T09:18:06.464Z] GC before operation: completed in 261.946 ms, heap usage 182.850 MB -> 68.305 MB. [2026-04-24T09:18:21.844Z] ====== als (apache-spark) [default], iteration 17 completed (14847.066 ms) ====== [2026-04-24T09:18:21.844Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-24T09:18:21.844Z] GC before operation: completed in 237.058 ms, heap usage 285.024 MB -> 68.708 MB. [2026-04-24T09:18:34.965Z] ====== als (apache-spark) [default], iteration 18 completed (13854.125 ms) ====== [2026-04-24T09:18:34.965Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-24T09:18:35.670Z] GC before operation: completed in 249.387 ms, heap usage 279.131 MB -> 68.977 MB. [2026-04-24T09:18:51.035Z] ====== als (apache-spark) [default], iteration 19 completed (15199.756 ms) ====== [2026-04-24T09:18:51.035Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-24T09:18:51.035Z] GC before operation: completed in 158.892 ms, heap usage 216.480 MB -> 69.129 MB. [2026-04-24T09:19:07.289Z] ====== als (apache-spark) [default], iteration 20 completed (14289.332 ms) ====== [2026-04-24T09:19:07.289Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-24T09:19:07.289Z] GC before operation: completed in 194.274 ms, heap usage 220.808 MB -> 69.396 MB. [2026-04-24T09:19:22.614Z] ====== als (apache-spark) [default], iteration 21 completed (14394.517 ms) ====== [2026-04-24T09:19:22.614Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-24T09:19:22.614Z] GC before operation: completed in 191.495 ms, heap usage 217.661 MB -> 69.697 MB. [2026-04-24T09:19:35.513Z] ====== als (apache-spark) [default], iteration 22 completed (13744.561 ms) ====== [2026-04-24T09:19:35.513Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-24T09:19:35.513Z] GC before operation: completed in 269.968 ms, heap usage 152.001 MB -> 69.968 MB. [2026-04-24T09:19:51.101Z] ====== als (apache-spark) [default], iteration 23 completed (14161.751 ms) ====== [2026-04-24T09:19:51.101Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-24T09:19:51.101Z] GC before operation: completed in 210.921 ms, heap usage 136.121 MB -> 69.784 MB. [2026-04-24T09:20:06.162Z] ====== als (apache-spark) [default], iteration 24 completed (13582.411 ms) ====== [2026-04-24T09:20:06.162Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-24T09:20:06.162Z] GC before operation: completed in 225.426 ms, heap usage 220.783 MB -> 70.508 MB. [2026-04-24T09:20:19.192Z] ====== als (apache-spark) [default], iteration 25 completed (13821.711 ms) ====== [2026-04-24T09:20:19.192Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-24T09:20:19.192Z] GC before operation: completed in 157.760 ms, heap usage 262.519 MB -> 70.822 MB. [2026-04-24T09:20:32.409Z] ====== als (apache-spark) [default], iteration 26 completed (12937.385 ms) ====== [2026-04-24T09:20:32.409Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-24T09:20:32.409Z] GC before operation: completed in 222.288 ms, heap usage 180.728 MB -> 71.062 MB. [2026-04-24T09:20:47.764Z] ====== als (apache-spark) [default], iteration 27 completed (14045.931 ms) ====== [2026-04-24T09:20:47.764Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-24T09:20:47.764Z] GC before operation: completed in 123.200 ms, heap usage 176.220 MB -> 71.254 MB. [2026-04-24T09:21:01.198Z] ====== als (apache-spark) [default], iteration 28 completed (14262.357 ms) ====== [2026-04-24T09:21:01.198Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-24T09:21:01.893Z] GC before operation: completed in 132.033 ms, heap usage 313.941 MB -> 71.760 MB. [2026-04-24T09:21:17.124Z] ====== als (apache-spark) [default], iteration 29 completed (13270.759 ms) ====== [2026-04-24T09:21:17.124Z] ----------------------------------- [2026-04-24T09:21:17.124Z] renaissance-als_0_PASSED [2026-04-24T09:21:17.124Z] ----------------------------------- [2026-04-24T09:21:17.124Z] [2026-04-24T09:21:17.124Z] TEST TEARDOWN: [2026-04-24T09:21:17.124Z] Nothing to be done for teardown. [2026-04-24T09:21:17.124Z] renaissance-als_0 Finish Time: Fri Apr 24 09:21:15 2026 Epoch Time (ms): 1777022475542