[2025-11-19T22:08:52.673Z] Running test renaissance-als_0 ... [2025-11-19T22:08:53.035Z] =============================================== [2025-11-19T22:08:53.035Z] renaissance-als_0 Start Time: Wed Nov 19 17:08:52 2025 Epoch Time (ms): 1763590132639 [2025-11-19T22:08:53.035Z] variation: NoOptions [2025-11-19T22:08:53.035Z] JVM_OPTIONS: [2025-11-19T22:08:53.035Z] { \ [2025-11-19T22:08:53.035Z] echo ""; echo "TEST SETUP:"; \ [2025-11-19T22:08:53.035Z] echo "Nothing to be done for setup."; \ [2025-11-19T22:08:53.035Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635901321245/renaissance-als_0"; \ [2025-11-19T22:08:53.035Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635901321245/renaissance-als_0"; \ [2025-11-19T22:08:53.035Z] echo ""; echo "TESTING:"; \ [2025-11-19T22:08:53.035Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635901321245/renaissance-als_0"/als.json" als; \ [2025-11-19T22:08:53.035Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635901321245/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-19T22:08:53.035Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-19T22:08:53.035Z] echo "Nothing to be done for teardown."; \ [2025-11-19T22:08:53.035Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17635901321245/TestTargetResult"; [2025-11-19T22:08:53.035Z] [2025-11-19T22:08:53.035Z] TEST SETUP: [2025-11-19T22:08:53.035Z] Nothing to be done for setup. [2025-11-19T22:08:53.035Z] [2025-11-19T22:08:53.035Z] TESTING: [2025-11-19T22:08:59.347Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-19T22:09:01.878Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-19T22:09:01.878Z] GC before operation: completed in 30.703 ms, heap usage 171.540 MB -> 35.251 MB. [2025-11-19T22:09:13.396Z] ====== als (apache-spark) [default], iteration 0 completed (10156.021 ms) ====== [2025-11-19T22:09:13.396Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-19T22:09:13.396Z] GC before operation: completed in 55.299 ms, heap usage 226.772 MB -> 62.049 MB. [2025-11-19T22:09:16.820Z] ====== als (apache-spark) [default], iteration 1 completed (4182.587 ms) ====== [2025-11-19T22:09:16.820Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-19T22:09:16.820Z] GC before operation: completed in 45.870 ms, heap usage 121.856 MB -> 62.457 MB. [2025-11-19T22:09:20.194Z] ====== als (apache-spark) [default], iteration 2 completed (3522.225 ms) ====== [2025-11-19T22:09:20.194Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-19T22:09:20.194Z] GC before operation: completed in 49.964 ms, heap usage 294.047 MB -> 63.563 MB. [2025-11-19T22:09:24.398Z] ====== als (apache-spark) [default], iteration 3 completed (3545.614 ms) ====== [2025-11-19T22:09:24.398Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-19T22:09:24.398Z] GC before operation: completed in 56.664 ms, heap usage 232.753 MB -> 64.155 MB. [2025-11-19T22:09:28.675Z] ====== als (apache-spark) [default], iteration 4 completed (3726.265 ms) ====== [2025-11-19T22:09:28.675Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-19T22:09:28.675Z] GC before operation: completed in 56.539 ms, heap usage 244.872 MB -> 64.734 MB. [2025-11-19T22:09:32.047Z] ====== als (apache-spark) [default], iteration 5 completed (3461.759 ms) ====== [2025-11-19T22:09:32.047Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-19T22:09:32.047Z] GC before operation: completed in 54.842 ms, heap usage 491.766 MB -> 65.595 MB. [2025-11-19T22:09:35.406Z] ====== als (apache-spark) [default], iteration 6 completed (3586.914 ms) ====== [2025-11-19T22:09:35.406Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-19T22:09:35.406Z] GC before operation: completed in 75.453 ms, heap usage 103.818 MB -> 65.738 MB. [2025-11-19T22:09:39.637Z] ====== als (apache-spark) [default], iteration 7 completed (3451.423 ms) ====== [2025-11-19T22:09:39.637Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-19T22:09:39.637Z] GC before operation: completed in 68.435 ms, heap usage 528.207 MB -> 66.781 MB. [2025-11-19T22:09:42.902Z] ====== als (apache-spark) [default], iteration 8 completed (3384.499 ms) ====== [2025-11-19T22:09:42.902Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-19T22:09:42.902Z] GC before operation: completed in 62.031 ms, heap usage 342.195 MB -> 67.006 MB. [2025-11-19T22:09:47.092Z] ====== als (apache-spark) [default], iteration 9 completed (3799.576 ms) ====== [2025-11-19T22:09:47.092Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-19T22:09:47.092Z] GC before operation: completed in 54.033 ms, heap usage 231.905 MB -> 67.180 MB. [2025-11-19T22:09:50.392Z] ====== als (apache-spark) [default], iteration 10 completed (3504.898 ms) ====== [2025-11-19T22:09:50.392Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-19T22:09:50.392Z] GC before operation: completed in 73.421 ms, heap usage 98.469 MB -> 67.156 MB. [2025-11-19T22:09:53.816Z] ====== als (apache-spark) [default], iteration 11 completed (3560.032 ms) ====== [2025-11-19T22:09:53.816Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-19T22:09:53.816Z] GC before operation: completed in 56.330 ms, heap usage 482.422 MB -> 67.996 MB. [2025-11-19T22:09:58.010Z] ====== als (apache-spark) [default], iteration 12 completed (3623.414 ms) ====== [2025-11-19T22:09:58.010Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-19T22:09:58.010Z] GC before operation: completed in 65.675 ms, heap usage 358.697 MB -> 67.964 MB. [2025-11-19T22:10:01.320Z] ====== als (apache-spark) [default], iteration 13 completed (3532.581 ms) ====== [2025-11-19T22:10:01.320Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-19T22:10:01.320Z] GC before operation: completed in 68.859 ms, heap usage 323.271 MB -> 68.274 MB. [2025-11-19T22:10:04.815Z] ====== als (apache-spark) [default], iteration 14 completed (3385.629 ms) ====== [2025-11-19T22:10:04.815Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-19T22:10:04.815Z] GC before operation: completed in 63.699 ms, heap usage 156.149 MB -> 68.274 MB. [2025-11-19T22:10:09.045Z] ====== als (apache-spark) [default], iteration 15 completed (3330.255 ms) ====== [2025-11-19T22:10:09.045Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-19T22:10:09.045Z] GC before operation: completed in 56.406 ms, heap usage 502.388 MB -> 69.074 MB. [2025-11-19T22:10:12.435Z] ====== als (apache-spark) [default], iteration 16 completed (3488.786 ms) ====== [2025-11-19T22:10:12.435Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-19T22:10:12.435Z] GC before operation: completed in 56.240 ms, heap usage 329.776 MB -> 69.053 MB. [2025-11-19T22:10:14.974Z] ====== als (apache-spark) [default], iteration 17 completed (2990.223 ms) ====== [2025-11-19T22:10:14.974Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-19T22:10:15.351Z] GC before operation: completed in 57.235 ms, heap usage 210.572 MB -> 69.063 MB. [2025-11-19T22:10:17.852Z] ====== als (apache-spark) [default], iteration 18 completed (2713.570 ms) ====== [2025-11-19T22:10:17.852Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-19T22:10:17.852Z] GC before operation: completed in 45.577 ms, heap usage 113.010 MB -> 69.356 MB. [2025-11-19T22:10:21.043Z] ====== als (apache-spark) [default], iteration 19 completed (2567.615 ms) ====== [2025-11-19T22:10:21.043Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-19T22:10:21.043Z] GC before operation: completed in 45.988 ms, heap usage 366.968 MB -> 69.855 MB. [2025-11-19T22:10:23.523Z] ====== als (apache-spark) [default], iteration 20 completed (2620.018 ms) ====== [2025-11-19T22:10:23.523Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-19T22:10:23.523Z] GC before operation: completed in 40.592 ms, heap usage 317.579 MB -> 70.147 MB. [2025-11-19T22:10:26.713Z] ====== als (apache-spark) [default], iteration 21 completed (2997.937 ms) ====== [2025-11-19T22:10:26.713Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-19T22:10:26.713Z] GC before operation: completed in 65.160 ms, heap usage 135.347 MB -> 70.304 MB. [2025-11-19T22:10:29.205Z] ====== als (apache-spark) [default], iteration 22 completed (2514.722 ms) ====== [2025-11-19T22:10:29.205Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-19T22:10:29.205Z] GC before operation: completed in 36.624 ms, heap usage 505.463 MB -> 70.940 MB. [2025-11-19T22:10:32.411Z] ====== als (apache-spark) [default], iteration 23 completed (2778.152 ms) ====== [2025-11-19T22:10:32.411Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-19T22:10:32.411Z] GC before operation: completed in 45.703 ms, heap usage 137.597 MB -> 70.731 MB. [2025-11-19T22:10:35.725Z] ====== als (apache-spark) [default], iteration 24 completed (3416.658 ms) ====== [2025-11-19T22:10:35.725Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-19T22:10:35.725Z] GC before operation: completed in 56.270 ms, heap usage 242.435 MB -> 71.273 MB. [2025-11-19T22:10:39.011Z] ====== als (apache-spark) [default], iteration 25 completed (3454.040 ms) ====== [2025-11-19T22:10:39.011Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-19T22:10:39.403Z] GC before operation: completed in 55.011 ms, heap usage 366.151 MB -> 71.522 MB. [2025-11-19T22:10:42.764Z] ====== als (apache-spark) [default], iteration 26 completed (3516.115 ms) ====== [2025-11-19T22:10:42.764Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-19T22:10:42.764Z] GC before operation: completed in 61.839 ms, heap usage 269.078 MB -> 71.789 MB. [2025-11-19T22:10:46.976Z] ====== als (apache-spark) [default], iteration 27 completed (3720.055 ms) ====== [2025-11-19T22:10:46.976Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-19T22:10:46.976Z] GC before operation: completed in 57.920 ms, heap usage 492.200 MB -> 72.210 MB. [2025-11-19T22:10:50.210Z] ====== als (apache-spark) [default], iteration 28 completed (3487.117 ms) ====== [2025-11-19T22:10:50.210Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-19T22:10:50.210Z] GC before operation: completed in 52.970 ms, heap usage 102.574 MB -> 71.585 MB. [2025-11-19T22:10:54.367Z] ====== als (apache-spark) [default], iteration 29 completed (3664.504 ms) ====== [2025-11-19T22:10:54.367Z] ----------------------------------- [2025-11-19T22:10:54.367Z] renaissance-als_0_PASSED [2025-11-19T22:10:54.367Z] ----------------------------------- [2025-11-19T22:10:54.367Z] [2025-11-19T22:10:54.367Z] TEST TEARDOWN: [2025-11-19T22:10:54.367Z] Nothing to be done for teardown. [2025-11-19T22:10:54.736Z] renaissance-als_0 Finish Time: Wed Nov 19 17:10:54 2025 Epoch Time (ms): 1763590254272