[2025-07-01T17:57:28.754Z] Running test renaissance-als_0 ... [2025-07-01T17:57:28.754Z] =============================================== [2025-07-01T17:57:28.754Z] renaissance-als_0 Start Time: Tue Jul 1 17:57:28 2025 Epoch Time (ms): 1751392648071 [2025-07-01T17:57:28.754Z] variation: NoOptions [2025-07-01T17:57:28.754Z] JVM_OPTIONS: [2025-07-01T17:57:28.754Z] { \ [2025-07-01T17:57:28.754Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T17:57:28.754Z] echo "Nothing to be done for setup."; \ [2025-07-01T17:57:28.754Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17513923674795/renaissance-als_0"; \ [2025-07-01T17:57:28.754Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17513923674795/renaissance-als_0"; \ [2025-07-01T17:57:28.754Z] echo ""; echo "TESTING:"; \ [2025-07-01T17:57:28.754Z] "/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_17513923674795/renaissance-als_0"/als.json" als; \ [2025-07-01T17:57:28.754Z] 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_17513923674795/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T17:57:28.754Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T17:57:28.754Z] echo "Nothing to be done for teardown."; \ [2025-07-01T17:57:28.754Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17513923674795/TestTargetResult"; [2025-07-01T17:57:28.754Z] [2025-07-01T17:57:28.754Z] TEST SETUP: [2025-07-01T17:57:28.754Z] Nothing to be done for setup. [2025-07-01T17:57:28.754Z] [2025-07-01T17:57:28.754Z] TESTING: [2025-07-01T17:57:42.412Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-07-01T17:57:54.712Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T17:57:54.712Z] GC before operation: completed in 358.309 ms, heap usage 284.511 MB -> 35.226 MB. [2025-07-01T17:59:29.264Z] ====== als (apache-spark) [default], iteration 0 completed (80488.558 ms) ====== [2025-07-01T17:59:29.264Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T17:59:29.264Z] GC before operation: completed in 318.309 ms, heap usage 295.558 MB -> 62.059 MB. [2025-07-01T18:00:05.258Z] ====== als (apache-spark) [default], iteration 1 completed (44084.106 ms) ====== [2025-07-01T18:00:05.258Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T18:00:05.258Z] GC before operation: completed in 248.740 ms, heap usage 158.466 MB -> 62.319 MB. [2025-07-01T18:00:30.926Z] ====== als (apache-spark) [default], iteration 2 completed (29148.935 ms) ====== [2025-07-01T18:00:30.926Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T18:00:30.926Z] GC before operation: completed in 158.462 ms, heap usage 127.199 MB -> 63.273 MB. [2025-07-01T18:01:12.068Z] ====== als (apache-spark) [default], iteration 3 completed (39791.047 ms) ====== [2025-07-01T18:01:12.068Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T18:01:12.068Z] GC before operation: completed in 140.169 ms, heap usage 170.473 MB -> 63.971 MB. [2025-07-01T18:01:40.880Z] ====== als (apache-spark) [default], iteration 4 completed (26849.816 ms) ====== [2025-07-01T18:01:40.880Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T18:01:40.880Z] GC before operation: completed in 208.540 ms, heap usage 143.676 MB -> 64.452 MB. [2025-07-01T18:02:23.142Z] ====== als (apache-spark) [default], iteration 5 completed (41429.903 ms) ====== [2025-07-01T18:02:23.142Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T18:02:23.142Z] GC before operation: completed in 401.736 ms, heap usage 249.899 MB -> 64.969 MB. [2025-07-01T18:03:00.144Z] ====== als (apache-spark) [default], iteration 6 completed (36575.845 ms) ====== [2025-07-01T18:03:00.144Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T18:03:00.144Z] GC before operation: completed in 523.846 ms, heap usage 497.831 MB -> 65.890 MB. [2025-07-01T18:03:42.107Z] ====== als (apache-spark) [default], iteration 7 completed (37491.830 ms) ====== [2025-07-01T18:03:42.107Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T18:03:42.107Z] GC before operation: completed in 383.572 ms, heap usage 318.433 MB -> 66.202 MB. [2025-07-01T18:04:25.109Z] ====== als (apache-spark) [default], iteration 8 completed (42646.565 ms) ====== [2025-07-01T18:04:25.109Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T18:04:25.109Z] GC before operation: completed in 189.084 ms, heap usage 246.553 MB -> 66.723 MB. [2025-07-01T18:05:00.880Z] ====== als (apache-spark) [default], iteration 9 completed (35913.248 ms) ====== [2025-07-01T18:05:00.880Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T18:05:00.880Z] GC before operation: completed in 204.486 ms, heap usage 223.434 MB -> 67.191 MB. [2025-07-01T18:05:32.636Z] ====== als (apache-spark) [default], iteration 10 completed (35007.097 ms) ====== [2025-07-01T18:05:32.636Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T18:05:34.194Z] GC before operation: completed in 740.585 ms, heap usage 124.153 MB -> 66.472 MB. [2025-07-01T18:06:32.234Z] ====== als (apache-spark) [default], iteration 11 completed (51155.499 ms) ====== [2025-07-01T18:06:32.234Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T18:06:32.234Z] GC before operation: completed in 212.124 ms, heap usage 142.658 MB -> 67.157 MB. [2025-07-01T18:07:28.166Z] ====== als (apache-spark) [default], iteration 12 completed (54863.363 ms) ====== [2025-07-01T18:07:28.166Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T18:07:28.166Z] GC before operation: completed in 405.556 ms, heap usage 145.882 MB -> 67.406 MB. [2025-07-01T18:07:57.577Z] ====== als (apache-spark) [default], iteration 13 completed (30776.102 ms) ====== [2025-07-01T18:07:57.577Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T18:07:57.578Z] GC before operation: completed in 913.029 ms, heap usage 95.385 MB -> 67.348 MB. [2025-07-01T18:08:38.927Z] ====== als (apache-spark) [default], iteration 14 completed (38761.102 ms) ====== [2025-07-01T18:08:38.927Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T18:08:38.927Z] GC before operation: completed in 330.584 ms, heap usage 214.841 MB -> 67.918 MB. [2025-07-01T18:09:23.033Z] ====== als (apache-spark) [default], iteration 15 completed (42251.806 ms) ====== [2025-07-01T18:09:23.033Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T18:09:23.033Z] GC before operation: completed in 1110.243 ms, heap usage 190.526 MB -> 68.123 MB. [2025-07-01T18:10:02.035Z] ====== als (apache-spark) [default], iteration 16 completed (40723.030 ms) ====== [2025-07-01T18:10:02.035Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T18:10:02.035Z] GC before operation: completed in 232.473 ms, heap usage 310.364 MB -> 68.630 MB. [2025-07-01T18:10:32.034Z] ====== als (apache-spark) [default], iteration 17 completed (30560.979 ms) ====== [2025-07-01T18:10:32.034Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T18:10:32.034Z] GC before operation: completed in 416.142 ms, heap usage 490.501 MB -> 69.107 MB. [2025-07-01T18:11:12.710Z] ====== als (apache-spark) [default], iteration 18 completed (38409.404 ms) ====== [2025-07-01T18:11:12.710Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T18:11:12.710Z] GC before operation: completed in 159.332 ms, heap usage 205.721 MB -> 68.930 MB. [2025-07-01T18:12:03.714Z] ====== als (apache-spark) [default], iteration 19 completed (46981.608 ms) ====== [2025-07-01T18:12:03.714Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T18:12:03.714Z] GC before operation: completed in 363.923 ms, heap usage 278.378 MB -> 68.185 MB. [2025-07-01T18:12:54.584Z] ====== als (apache-spark) [default], iteration 20 completed (47375.336 ms) ====== [2025-07-01T18:12:54.584Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T18:12:54.584Z] GC before operation: completed in 643.242 ms, heap usage 266.651 MB -> 66.914 MB. [2025-07-01T18:13:30.834Z] ====== als (apache-spark) [default], iteration 21 completed (39427.016 ms) ====== [2025-07-01T18:13:30.834Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T18:13:30.834Z] GC before operation: completed in 186.027 ms, heap usage 390.714 MB -> 67.401 MB. [2025-07-01T18:13:55.645Z] ====== als (apache-spark) [default], iteration 22 completed (23036.613 ms) ====== [2025-07-01T18:13:55.645Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T18:13:55.645Z] GC before operation: completed in 250.926 ms, heap usage 252.512 MB -> 67.081 MB. [2025-07-01T18:14:55.284Z] ====== als (apache-spark) [default], iteration 23 completed (53200.797 ms) ====== [2025-07-01T18:14:55.284Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T18:14:55.284Z] GC before operation: completed in 530.323 ms, heap usage 96.478 MB -> 67.672 MB. [2025-07-01T18:15:39.245Z] ====== als (apache-spark) [default], iteration 24 completed (50050.483 ms) ====== [2025-07-01T18:15:39.245Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T18:15:39.954Z] GC before operation: completed in 630.623 ms, heap usage 109.095 MB -> 67.095 MB. [2025-07-01T18:16:55.402Z] ====== als (apache-spark) [default], iteration 25 completed (67913.943 ms) ====== [2025-07-01T18:16:55.402Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T18:16:55.402Z] GC before operation: completed in 358.244 ms, heap usage 520.530 MB -> 68.261 MB. [2025-07-01T18:17:24.677Z] ====== als (apache-spark) [default], iteration 26 completed (33306.720 ms) ====== [2025-07-01T18:17:24.677Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T18:17:24.677Z] GC before operation: completed in 235.561 ms, heap usage 478.241 MB -> 68.592 MB. [2025-07-01T18:18:01.228Z] ====== als (apache-spark) [default], iteration 27 completed (34010.388 ms) ====== [2025-07-01T18:18:01.228Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T18:18:01.228Z] GC before operation: completed in 806.983 ms, heap usage 82.026 MB -> 68.458 MB. [2025-07-01T18:18:41.731Z] ====== als (apache-spark) [default], iteration 28 completed (37789.286 ms) ====== [2025-07-01T18:18:41.731Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T18:18:41.731Z] GC before operation: completed in 308.578 ms, heap usage 395.457 MB -> 68.880 MB. [2025-07-01T18:19:32.175Z] ====== als (apache-spark) [default], iteration 29 completed (47326.959 ms) ====== [2025-07-01T18:19:32.175Z] ----------------------------------- [2025-07-01T18:19:32.175Z] renaissance-als_0_PASSED [2025-07-01T18:19:32.175Z] ----------------------------------- [2025-07-01T18:19:32.175Z] [2025-07-01T18:19:32.175Z] TEST TEARDOWN: [2025-07-01T18:19:32.175Z] Nothing to be done for teardown. [2025-07-01T18:19:32.175Z] renaissance-als_0 Finish Time: Tue Jul 1 18:19:26 2025 Epoch Time (ms): 1751393966347