[2025-08-20T22:03:48.176Z] Running test renaissance-als_0 ... [2025-08-20T22:03:48.176Z] =============================================== [2025-08-20T22:03:48.176Z] renaissance-als_0 Start Time: Wed Aug 20 22:03:48 2025 Epoch Time (ms): 1755727428030 [2025-08-20T22:03:48.176Z] variation: NoOptions [2025-08-20T22:03:48.176Z] JVM_OPTIONS: [2025-08-20T22:03:48.176Z] { \ [2025-08-20T22:03:48.176Z] echo ""; echo "TEST SETUP:"; \ [2025-08-20T22:03:48.176Z] echo "Nothing to be done for setup."; \ [2025-08-20T22:03:48.176Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557268786748/renaissance-als_0"; \ [2025-08-20T22:03:48.176Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557268786748/renaissance-als_0"; \ [2025-08-20T22:03:48.176Z] echo ""; echo "TESTING:"; \ [2025-08-20T22:03:48.176Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/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_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557268786748/renaissance-als_0"/als.json" als; \ [2025-08-20T22:03:48.176Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557268786748/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-20T22:03:48.176Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-20T22:03:48.176Z] echo "Nothing to be done for teardown."; \ [2025-08-20T22:03:48.176Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557268786748/TestTargetResult"; [2025-08-20T22:03:48.176Z] [2025-08-20T22:03:48.176Z] TEST SETUP: [2025-08-20T22:03:48.176Z] Nothing to be done for setup. [2025-08-20T22:03:48.176Z] [2025-08-20T22:03:48.176Z] TESTING: [2025-08-20T22:04:11.190Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-20T22:04:34.216Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-20T22:04:34.216Z] GC before operation: completed in 204.416 ms, heap usage 61.980 MB -> 35.365 MB. [2025-08-20T22:05:57.227Z] ====== als (apache-spark) [default], iteration 0 completed (71944.877 ms) ====== [2025-08-20T22:05:57.227Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-20T22:05:57.227Z] GC before operation: completed in 809.261 ms, heap usage 1.279 GB -> 76.861 MB. [2025-08-20T22:06:16.321Z] ====== als (apache-spark) [default], iteration 1 completed (27920.363 ms) ====== [2025-08-20T22:06:16.321Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-20T22:06:17.040Z] GC before operation: completed in 722.061 ms, heap usage 315.086 MB -> 76.478 MB. [2025-08-20T22:06:44.725Z] ====== als (apache-spark) [default], iteration 2 completed (25399.490 ms) ====== [2025-08-20T22:06:44.725Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-20T22:06:44.725Z] GC before operation: completed in 741.973 ms, heap usage 492.410 MB -> 77.515 MB. [2025-08-20T22:07:12.710Z] ====== als (apache-spark) [default], iteration 3 completed (24533.657 ms) ====== [2025-08-20T22:07:12.710Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-20T22:07:12.710Z] GC before operation: completed in 745.451 ms, heap usage 132.788 MB -> 77.266 MB. [2025-08-20T22:07:40.411Z] ====== als (apache-spark) [default], iteration 4 completed (24310.941 ms) ====== [2025-08-20T22:07:40.411Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-20T22:07:40.411Z] GC before operation: completed in 790.944 ms, heap usage 1.140 GB -> 79.288 MB. [2025-08-20T22:08:03.408Z] ====== als (apache-spark) [default], iteration 5 completed (22906.693 ms) ====== [2025-08-20T22:08:03.408Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-20T22:08:03.408Z] GC before operation: completed in 662.143 ms, heap usage 1.525 GB -> 80.281 MB. [2025-08-20T22:08:31.131Z] ====== als (apache-spark) [default], iteration 6 completed (24177.376 ms) ====== [2025-08-20T22:08:31.131Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-20T22:08:31.131Z] GC before operation: completed in 678.480 ms, heap usage 513.907 MB -> 79.745 MB. [2025-08-20T22:08:54.133Z] ====== als (apache-spark) [default], iteration 7 completed (22242.377 ms) ====== [2025-08-20T22:08:54.133Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-20T22:08:54.133Z] GC before operation: completed in 670.104 ms, heap usage 483.917 MB -> 80.201 MB. [2025-08-20T22:09:17.154Z] ====== als (apache-spark) [default], iteration 8 completed (22872.189 ms) ====== [2025-08-20T22:09:17.154Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-20T22:09:17.154Z] GC before operation: completed in 697.912 ms, heap usage 374.654 MB -> 80.443 MB. [2025-08-20T22:09:40.183Z] ====== als (apache-spark) [default], iteration 9 completed (22602.198 ms) ====== [2025-08-20T22:09:40.183Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-20T22:09:40.536Z] GC before operation: completed in 726.743 ms, heap usage 209.235 MB -> 80.625 MB. [2025-08-20T22:10:08.256Z] ====== als (apache-spark) [default], iteration 10 completed (22372.896 ms) ====== [2025-08-20T22:10:08.256Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-20T22:10:08.256Z] GC before operation: completed in 744.233 ms, heap usage 186.658 MB -> 80.873 MB. [2025-08-20T22:10:31.260Z] ====== als (apache-spark) [default], iteration 11 completed (22377.917 ms) ====== [2025-08-20T22:10:31.261Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-20T22:10:31.261Z] GC before operation: completed in 791.840 ms, heap usage 295.464 MB -> 81.268 MB. [2025-08-20T22:10:54.273Z] ====== als (apache-spark) [default], iteration 12 completed (22064.203 ms) ====== [2025-08-20T22:10:54.273Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-20T22:10:54.273Z] GC before operation: completed in 768.171 ms, heap usage 525.064 MB -> 81.856 MB. [2025-08-20T22:11:17.500Z] ====== als (apache-spark) [default], iteration 13 completed (22060.273 ms) ====== [2025-08-20T22:11:17.500Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-20T22:11:17.500Z] GC before operation: completed in 781.640 ms, heap usage 172.412 MB -> 81.661 MB. [2025-08-20T22:11:40.814Z] ====== als (apache-spark) [default], iteration 14 completed (21411.364 ms) ====== [2025-08-20T22:11:40.814Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-20T22:11:40.814Z] GC before operation: completed in 797.849 ms, heap usage 514.048 MB -> 82.433 MB. [2025-08-20T22:12:03.799Z] ====== als (apache-spark) [default], iteration 15 completed (21633.143 ms) ====== [2025-08-20T22:12:03.799Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-20T22:12:03.799Z] GC before operation: completed in 823.903 ms, heap usage 476.213 MB -> 82.690 MB. [2025-08-20T22:12:26.861Z] ====== als (apache-spark) [default], iteration 16 completed (21554.088 ms) ====== [2025-08-20T22:12:26.861Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-20T22:12:26.861Z] GC before operation: completed in 822.456 ms, heap usage 268.069 MB -> 82.576 MB. [2025-08-20T22:12:49.856Z] ====== als (apache-spark) [default], iteration 17 completed (21096.297 ms) ====== [2025-08-20T22:12:49.856Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-20T22:12:49.856Z] GC before operation: completed in 814.466 ms, heap usage 165.210 MB -> 82.766 MB. [2025-08-20T22:13:12.889Z] ====== als (apache-spark) [default], iteration 18 completed (21453.920 ms) ====== [2025-08-20T22:13:12.889Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-20T22:13:12.889Z] GC before operation: completed in 838.916 ms, heap usage 304.299 MB -> 83.077 MB. [2025-08-20T22:13:35.888Z] ====== als (apache-spark) [default], iteration 19 completed (21604.064 ms) ====== [2025-08-20T22:13:35.888Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-20T22:13:35.888Z] GC before operation: completed in 843.563 ms, heap usage 424.847 MB -> 83.491 MB. [2025-08-20T22:13:58.876Z] ====== als (apache-spark) [default], iteration 20 completed (21105.422 ms) ====== [2025-08-20T22:13:58.876Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-20T22:13:58.876Z] GC before operation: completed in 831.786 ms, heap usage 207.905 MB -> 83.633 MB. [2025-08-20T22:14:21.903Z] ====== als (apache-spark) [default], iteration 21 completed (21518.972 ms) ====== [2025-08-20T22:14:21.903Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-20T22:14:21.903Z] GC before operation: completed in 835.388 ms, heap usage 451.808 MB -> 84.370 MB. [2025-08-20T22:14:44.927Z] ====== als (apache-spark) [default], iteration 22 completed (21718.875 ms) ====== [2025-08-20T22:14:44.927Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-20T22:14:44.927Z] GC before operation: completed in 875.263 ms, heap usage 503.199 MB -> 84.276 MB. [2025-08-20T22:15:08.176Z] ====== als (apache-spark) [default], iteration 23 completed (21869.017 ms) ====== [2025-08-20T22:15:08.176Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-20T22:15:08.522Z] GC before operation: completed in 858.833 ms, heap usage 146.674 MB -> 84.195 MB. [2025-08-20T22:15:31.527Z] ====== als (apache-spark) [default], iteration 24 completed (21604.183 ms) ====== [2025-08-20T22:15:31.527Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-20T22:15:31.864Z] GC before operation: completed in 870.444 ms, heap usage 266.454 MB -> 84.611 MB. [2025-08-20T22:15:54.997Z] ====== als (apache-spark) [default], iteration 25 completed (21326.154 ms) ====== [2025-08-20T22:15:54.997Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-20T22:15:54.997Z] GC before operation: completed in 864.819 ms, heap usage 388.672 MB -> 84.995 MB. [2025-08-20T22:16:18.144Z] ====== als (apache-spark) [default], iteration 26 completed (21119.547 ms) ====== [2025-08-20T22:16:18.144Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-20T22:16:18.144Z] GC before operation: completed in 884.026 ms, heap usage 639.891 MB -> 85.536 MB. [2025-08-20T22:16:41.152Z] ====== als (apache-spark) [default], iteration 27 completed (21017.461 ms) ====== [2025-08-20T22:16:41.152Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-20T22:16:41.152Z] GC before operation: completed in 875.154 ms, heap usage 467.558 MB -> 85.339 MB. [2025-08-20T22:17:04.164Z] ====== als (apache-spark) [default], iteration 28 completed (21122.195 ms) ====== [2025-08-20T22:17:04.164Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-20T22:17:04.164Z] GC before operation: completed in 885.847 ms, heap usage 320.729 MB -> 85.156 MB. [2025-08-20T22:17:27.139Z] ====== als (apache-spark) [default], iteration 29 completed (20378.253 ms) ====== [2025-08-20T22:17:27.465Z] ----------------------------------- [2025-08-20T22:17:27.465Z] renaissance-als_0_PASSED [2025-08-20T22:17:27.465Z] ----------------------------------- [2025-08-20T22:17:27.465Z] [2025-08-20T22:17:27.465Z] TEST TEARDOWN: [2025-08-20T22:17:27.465Z] Nothing to be done for teardown. [2025-08-20T22:17:27.465Z] renaissance-als_0 Finish Time: Wed Aug 20 22:17:27 2025 Epoch Time (ms): 1755728247285