[2025-11-19T22:22:23.619Z] Running test renaissance-als_0 ... [2025-11-19T22:22:23.619Z] =============================================== [2025-11-19T22:22:23.619Z] renaissance-als_0 Start Time: Wed Nov 19 22:22:22 2025 Epoch Time (ms): 1763590942774 [2025-11-19T22:22:23.619Z] variation: NoOptions [2025-11-19T22:22:23.619Z] JVM_OPTIONS: [2025-11-19T22:22:23.619Z] { \ [2025-11-19T22:22:23.619Z] echo ""; echo "TEST SETUP:"; \ [2025-11-19T22:22:23.619Z] echo "Nothing to be done for setup."; \ [2025-11-19T22:22:23.619Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17635907621163/renaissance-als_0"; \ [2025-11-19T22:22:23.619Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17635907621163/renaissance-als_0"; \ [2025-11-19T22:22:23.619Z] echo ""; echo "TESTING:"; \ [2025-11-19T22:22:23.619Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-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_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17635907621163/renaissance-als_0"/als.json" als; \ [2025-11-19T22:22:23.619Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17635907621163/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-19T22:22:23.619Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-19T22:22:23.619Z] echo "Nothing to be done for teardown."; \ [2025-11-19T22:22:23.619Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17635907621163/TestTargetResult"; [2025-11-19T22:22:23.619Z] [2025-11-19T22:22:23.619Z] TEST SETUP: [2025-11-19T22:22:23.619Z] Nothing to be done for setup. [2025-11-19T22:22:23.619Z] [2025-11-19T22:22:23.619Z] TESTING: [2025-11-19T22:22:33.389Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-19T22:22:39.004Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-19T22:22:39.004Z] GC before operation: completed in 48.460 ms, heap usage 49.615 MB -> 35.044 MB. [2025-11-19T22:23:03.654Z] ====== als (apache-spark) [default], iteration 0 completed (22243.695 ms) ====== [2025-11-19T22:23:03.654Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-19T22:23:03.654Z] GC before operation: completed in 131.215 ms, heap usage 279.355 MB -> 61.876 MB. [2025-11-19T22:23:11.095Z] ====== als (apache-spark) [default], iteration 1 completed (8160.764 ms) ====== [2025-11-19T22:23:11.095Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-19T22:23:11.095Z] GC before operation: completed in 124.815 ms, heap usage 215.251 MB -> 62.682 MB. [2025-11-19T22:23:21.083Z] ====== als (apache-spark) [default], iteration 2 completed (8627.067 ms) ====== [2025-11-19T22:23:21.083Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-19T22:23:21.083Z] GC before operation: completed in 166.509 ms, heap usage 503.261 MB -> 63.746 MB. [2025-11-19T22:23:29.371Z] ====== als (apache-spark) [default], iteration 3 completed (8201.004 ms) ====== [2025-11-19T22:23:29.371Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-19T22:23:29.371Z] GC before operation: completed in 199.326 ms, heap usage 191.182 MB -> 63.909 MB. [2025-11-19T22:23:36.098Z] ====== als (apache-spark) [default], iteration 4 completed (6451.581 ms) ====== [2025-11-19T22:23:36.098Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-19T22:23:36.098Z] GC before operation: completed in 125.934 ms, heap usage 131.839 MB -> 64.469 MB. [2025-11-19T22:23:43.509Z] ====== als (apache-spark) [default], iteration 5 completed (6954.386 ms) ====== [2025-11-19T22:23:43.509Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-19T22:23:43.509Z] GC before operation: completed in 192.526 ms, heap usage 455.674 MB -> 65.435 MB. [2025-11-19T22:23:51.279Z] ====== als (apache-spark) [default], iteration 6 completed (7662.672 ms) ====== [2025-11-19T22:23:51.279Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-19T22:23:51.279Z] GC before operation: completed in 140.245 ms, heap usage 324.610 MB -> 65.805 MB. [2025-11-19T22:23:59.051Z] ====== als (apache-spark) [default], iteration 7 completed (6794.588 ms) ====== [2025-11-19T22:23:59.051Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-19T22:23:59.051Z] GC before operation: completed in 195.805 ms, heap usage 120.271 MB -> 66.069 MB. [2025-11-19T22:24:06.669Z] ====== als (apache-spark) [default], iteration 8 completed (7715.335 ms) ====== [2025-11-19T22:24:06.669Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-19T22:24:06.669Z] GC before operation: completed in 146.987 ms, heap usage 435.649 MB -> 67.089 MB. [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 9 completed (6277.785 ms) ====== [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-19T22:24:34.592Z] GC before operation: completed in 159.756 ms, heap usage 182.033 MB -> 67.260 MB. [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 10 completed (6398.739 ms) ====== [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-19T22:24:34.592Z] GC before operation: completed in 142.679 ms, heap usage 436.417 MB -> 67.511 MB. [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 11 completed (6768.595 ms) ====== [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-19T22:24:34.592Z] GC before operation: completed in 147.708 ms, heap usage 583.022 MB -> 68.449 MB. [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 12 completed (6527.129 ms) ====== [2025-11-19T22:24:34.592Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-19T22:24:34.592Z] GC before operation: completed in 158.685 ms, heap usage 269.517 MB -> 67.908 MB. [2025-11-19T22:24:40.294Z] ====== als (apache-spark) [default], iteration 13 completed (6217.718 ms) ====== [2025-11-19T22:24:40.294Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-19T22:24:40.294Z] GC before operation: completed in 155.164 ms, heap usage 149.803 MB -> 67.832 MB. [2025-11-19T22:24:47.051Z] ====== als (apache-spark) [default], iteration 14 completed (5997.295 ms) ====== [2025-11-19T22:24:47.051Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-19T22:24:47.051Z] GC before operation: completed in 166.117 ms, heap usage 435.918 MB -> 68.511 MB. [2025-11-19T22:24:53.925Z] ====== als (apache-spark) [default], iteration 15 completed (6091.872 ms) ====== [2025-11-19T22:24:53.925Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-19T22:24:53.925Z] GC before operation: completed in 148.445 ms, heap usage 194.181 MB -> 68.406 MB. [2025-11-19T22:24:59.505Z] ====== als (apache-spark) [default], iteration 16 completed (5646.575 ms) ====== [2025-11-19T22:24:59.505Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-19T22:24:59.505Z] GC before operation: completed in 146.695 ms, heap usage 496.633 MB -> 69.018 MB. [2025-11-19T22:25:04.947Z] ====== als (apache-spark) [default], iteration 17 completed (5700.410 ms) ====== [2025-11-19T22:25:04.947Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-19T22:25:04.947Z] GC before operation: completed in 162.951 ms, heap usage 478.207 MB -> 69.340 MB. [2025-11-19T22:25:10.385Z] ====== als (apache-spark) [default], iteration 18 completed (5067.036 ms) ====== [2025-11-19T22:25:10.385Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-19T22:25:10.385Z] GC before operation: completed in 138.382 ms, heap usage 467.334 MB -> 69.599 MB. [2025-11-19T22:25:15.830Z] ====== als (apache-spark) [default], iteration 19 completed (4864.187 ms) ====== [2025-11-19T22:25:15.830Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-19T22:25:15.830Z] GC before operation: completed in 133.085 ms, heap usage 159.100 MB -> 69.410 MB. [2025-11-19T22:25:21.679Z] ====== als (apache-spark) [default], iteration 20 completed (5318.511 ms) ====== [2025-11-19T22:25:21.679Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-19T22:25:21.679Z] GC before operation: completed in 150.282 ms, heap usage 482.994 MB -> 70.160 MB. [2025-11-19T22:25:27.181Z] ====== als (apache-spark) [default], iteration 21 completed (4708.685 ms) ====== [2025-11-19T22:25:27.182Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-19T22:25:27.182Z] GC before operation: completed in 135.305 ms, heap usage 100.329 MB -> 69.528 MB. [2025-11-19T22:25:31.434Z] ====== als (apache-spark) [default], iteration 22 completed (4892.648 ms) ====== [2025-11-19T22:25:31.434Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-19T22:25:31.434Z] GC before operation: completed in 140.534 ms, heap usage 235.480 MB -> 70.807 MB. [2025-11-19T22:25:36.945Z] ====== als (apache-spark) [default], iteration 23 completed (4844.814 ms) ====== [2025-11-19T22:25:36.945Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-19T22:25:36.945Z] GC before operation: completed in 136.342 ms, heap usage 291.910 MB -> 70.582 MB. [2025-11-19T22:25:43.785Z] ====== als (apache-spark) [default], iteration 24 completed (5910.861 ms) ====== [2025-11-19T22:25:43.785Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-19T22:25:43.785Z] GC before operation: completed in 135.852 ms, heap usage 203.175 MB -> 70.737 MB. [2025-11-19T22:25:48.003Z] ====== als (apache-spark) [default], iteration 25 completed (5015.168 ms) ====== [2025-11-19T22:25:48.003Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-19T22:25:48.003Z] GC before operation: completed in 140.795 ms, heap usage 562.350 MB -> 71.552 MB. [2025-11-19T22:25:53.477Z] ====== als (apache-spark) [default], iteration 26 completed (4862.174 ms) ====== [2025-11-19T22:25:53.477Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-19T22:25:53.477Z] GC before operation: completed in 139.555 ms, heap usage 221.460 MB -> 71.346 MB. [2025-11-19T22:25:58.969Z] ====== als (apache-spark) [default], iteration 27 completed (4807.614 ms) ====== [2025-11-19T22:25:58.969Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-19T22:25:58.969Z] GC before operation: completed in 147.091 ms, heap usage 154.651 MB -> 71.594 MB. [2025-11-19T22:26:04.751Z] ====== als (apache-spark) [default], iteration 28 completed (5077.467 ms) ====== [2025-11-19T22:26:04.751Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-19T22:26:04.751Z] GC before operation: completed in 145.135 ms, heap usage 340.397 MB -> 71.572 MB. [2025-11-19T22:26:09.001Z] ====== als (apache-spark) [default], iteration 29 completed (4824.788 ms) ====== [2025-11-19T22:26:11.049Z] ----------------------------------- [2025-11-19T22:26:11.049Z] renaissance-als_0_PASSED [2025-11-19T22:26:11.049Z] ----------------------------------- [2025-11-19T22:26:11.049Z] [2025-11-19T22:26:11.049Z] TEST TEARDOWN: [2025-11-19T22:26:11.049Z] Nothing to be done for teardown. [2025-11-19T22:26:11.049Z] renaissance-als_0 Finish Time: Wed Nov 19 22:26:09 2025 Epoch Time (ms): 1763591169927