[2025-05-28T19:51:06.073Z] Running test renaissance-als_0 ... [2025-05-28T19:51:06.073Z] =============================================== [2025-05-28T19:51:06.073Z] renaissance-als_0 Start Time: Wed May 28 15:51:05 2025 Epoch Time (ms): 1748461865851 [2025-05-28T19:51:06.073Z] variation: NoOptions [2025-05-28T19:51:06.073Z] JVM_OPTIONS: [2025-05-28T19:51:06.073Z] { \ [2025-05-28T19:51:06.073Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T19:51:06.073Z] echo "Nothing to be done for setup."; \ [2025-05-28T19:51:06.073Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484618652318/renaissance-als_0"; \ [2025-05-28T19:51:06.073Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484618652318/renaissance-als_0"; \ [2025-05-28T19:51:06.073Z] echo ""; echo "TESTING:"; \ [2025-05-28T19:51:06.073Z] "/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_17484618652318/renaissance-als_0"/als.json" als; \ [2025-05-28T19:51:06.073Z] 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_17484618652318/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T19:51:06.073Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T19:51:06.073Z] echo "Nothing to be done for teardown."; \ [2025-05-28T19:51:06.073Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484618652318/TestTargetResult"; [2025-05-28T19:51:06.073Z] [2025-05-28T19:51:06.073Z] TEST SETUP: [2025-05-28T19:51:06.073Z] Nothing to be done for setup. [2025-05-28T19:51:06.073Z] [2025-05-28T19:51:06.073Z] TESTING: [2025-05-28T19:51:11.047Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-28T19:51:13.436Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-28T19:51:13.436Z] GC before operation: completed in 27.137 ms, heap usage 76.500 MB -> 35.168 MB. [2025-05-28T19:51:24.603Z] ====== als (apache-spark) [default], iteration 0 completed (10178.342 ms) ====== [2025-05-28T19:51:24.603Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-28T19:51:24.603Z] GC before operation: completed in 40.875 ms, heap usage 306.291 MB -> 62.011 MB. [2025-05-28T19:51:29.647Z] ====== als (apache-spark) [default], iteration 1 completed (4734.126 ms) ====== [2025-05-28T19:51:29.647Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-28T19:51:29.647Z] GC before operation: completed in 50.688 ms, heap usage 177.191 MB -> 62.639 MB. [2025-05-28T19:51:33.709Z] ====== als (apache-spark) [default], iteration 2 completed (4266.986 ms) ====== [2025-05-28T19:51:33.709Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-28T19:51:33.709Z] GC before operation: completed in 52.869 ms, heap usage 209.355 MB -> 63.307 MB. [2025-05-28T19:51:37.811Z] ====== als (apache-spark) [default], iteration 3 completed (3876.730 ms) ====== [2025-05-28T19:51:37.811Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-28T19:51:37.811Z] GC before operation: completed in 61.297 ms, heap usage 226.326 MB -> 63.897 MB. [2025-05-28T19:51:41.934Z] ====== als (apache-spark) [default], iteration 4 completed (4306.901 ms) ====== [2025-05-28T19:51:41.934Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-28T19:51:41.934Z] GC before operation: completed in 56.481 ms, heap usage 99.396 MB -> 64.302 MB. [2025-05-28T19:51:46.063Z] ====== als (apache-spark) [default], iteration 5 completed (4163.363 ms) ====== [2025-05-28T19:51:46.063Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-28T19:51:46.063Z] GC before operation: completed in 63.528 ms, heap usage 466.718 MB -> 65.310 MB. [2025-05-28T19:51:50.138Z] ====== als (apache-spark) [default], iteration 6 completed (3794.566 ms) ====== [2025-05-28T19:51:50.138Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-28T19:51:50.138Z] GC before operation: completed in 39.848 ms, heap usage 394.130 MB -> 65.719 MB. [2025-05-28T19:51:54.175Z] ====== als (apache-spark) [default], iteration 7 completed (3477.808 ms) ====== [2025-05-28T19:51:54.175Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-28T19:51:54.175Z] GC before operation: completed in 45.117 ms, heap usage 370.287 MB -> 66.258 MB. [2025-05-28T19:51:57.424Z] ====== als (apache-spark) [default], iteration 8 completed (3336.594 ms) ====== [2025-05-28T19:51:57.424Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-28T19:51:57.424Z] GC before operation: completed in 50.015 ms, heap usage 343.918 MB -> 66.821 MB. [2025-05-28T19:52:01.469Z] ====== als (apache-spark) [default], iteration 9 completed (3480.771 ms) ====== [2025-05-28T19:52:01.469Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-28T19:52:01.469Z] GC before operation: completed in 44.724 ms, heap usage 212.435 MB -> 66.864 MB. [2025-05-28T19:52:04.770Z] ====== als (apache-spark) [default], iteration 10 completed (3599.202 ms) ====== [2025-05-28T19:52:04.770Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-28T19:52:04.770Z] GC before operation: completed in 49.719 ms, heap usage 491.945 MB -> 67.573 MB. [2025-05-28T19:52:08.866Z] ====== als (apache-spark) [default], iteration 11 completed (3745.575 ms) ====== [2025-05-28T19:52:08.866Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-28T19:52:08.866Z] GC before operation: completed in 56.311 ms, heap usage 155.937 MB -> 67.383 MB. [2025-05-28T19:52:12.963Z] ====== als (apache-spark) [default], iteration 12 completed (4017.077 ms) ====== [2025-05-28T19:52:12.963Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-28T19:52:12.963Z] GC before operation: completed in 44.935 ms, heap usage 446.791 MB -> 68.143 MB. [2025-05-28T19:52:17.057Z] ====== als (apache-spark) [default], iteration 13 completed (3716.895 ms) ====== [2025-05-28T19:52:17.057Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-28T19:52:17.057Z] GC before operation: completed in 46.990 ms, heap usage 511.406 MB -> 68.417 MB. [2025-05-28T19:52:20.288Z] ====== als (apache-spark) [default], iteration 14 completed (3659.597 ms) ====== [2025-05-28T19:52:20.288Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-28T19:52:20.288Z] GC before operation: completed in 52.664 ms, heap usage 438.717 MB -> 68.675 MB. [2025-05-28T19:52:24.340Z] ====== als (apache-spark) [default], iteration 15 completed (3909.256 ms) ====== [2025-05-28T19:52:24.340Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-28T19:52:24.340Z] GC before operation: completed in 59.572 ms, heap usage 500.510 MB -> 68.859 MB. [2025-05-28T19:52:28.497Z] ====== als (apache-spark) [default], iteration 16 completed (3919.643 ms) ====== [2025-05-28T19:52:28.497Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-28T19:52:28.497Z] GC before operation: completed in 53.832 ms, heap usage 319.706 MB -> 68.863 MB. [2025-05-28T19:52:32.541Z] ====== als (apache-spark) [default], iteration 17 completed (3531.552 ms) ====== [2025-05-28T19:52:32.541Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-28T19:52:32.542Z] GC before operation: completed in 66.914 ms, heap usage 220.463 MB -> 69.113 MB. [2025-05-28T19:52:35.742Z] ====== als (apache-spark) [default], iteration 18 completed (3534.991 ms) ====== [2025-05-28T19:52:35.742Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-28T19:52:35.743Z] GC before operation: completed in 52.406 ms, heap usage 280.630 MB -> 69.326 MB. [2025-05-28T19:52:39.855Z] ====== als (apache-spark) [default], iteration 19 completed (3459.695 ms) ====== [2025-05-28T19:52:39.855Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-28T19:52:39.855Z] GC before operation: completed in 68.171 ms, heap usage 509.771 MB -> 69.971 MB. [2025-05-28T19:52:43.092Z] ====== als (apache-spark) [default], iteration 20 completed (3528.502 ms) ====== [2025-05-28T19:52:43.092Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-28T19:52:43.092Z] GC before operation: completed in 68.447 ms, heap usage 320.083 MB -> 69.989 MB. [2025-05-28T19:52:47.217Z] ====== als (apache-spark) [default], iteration 21 completed (3621.238 ms) ====== [2025-05-28T19:52:47.217Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-28T19:52:47.217Z] GC before operation: completed in 73.078 ms, heap usage 302.129 MB -> 70.323 MB. [2025-05-28T19:52:51.244Z] ====== als (apache-spark) [default], iteration 22 completed (3640.617 ms) ====== [2025-05-28T19:52:51.245Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-28T19:52:51.245Z] GC before operation: completed in 42.666 ms, heap usage 447.779 MB -> 70.707 MB. [2025-05-28T19:52:54.444Z] ====== als (apache-spark) [default], iteration 23 completed (3416.288 ms) ====== [2025-05-28T19:52:54.444Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-28T19:52:54.444Z] GC before operation: completed in 50.946 ms, heap usage 143.047 MB -> 70.533 MB. [2025-05-28T19:52:58.479Z] ====== als (apache-spark) [default], iteration 24 completed (3826.048 ms) ====== [2025-05-28T19:52:58.479Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-28T19:52:58.479Z] GC before operation: completed in 47.001 ms, heap usage 469.939 MB -> 71.209 MB. [2025-05-28T19:53:02.522Z] ====== als (apache-spark) [default], iteration 25 completed (3656.651 ms) ====== [2025-05-28T19:53:02.522Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-28T19:53:02.522Z] GC before operation: completed in 41.131 ms, heap usage 440.038 MB -> 71.466 MB. [2025-05-28T19:53:05.797Z] ====== als (apache-spark) [default], iteration 26 completed (3455.135 ms) ====== [2025-05-28T19:53:05.797Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-28T19:53:05.797Z] GC before operation: completed in 49.271 ms, heap usage 187.592 MB -> 71.288 MB. [2025-05-28T19:53:08.971Z] ====== als (apache-spark) [default], iteration 27 completed (3239.681 ms) ====== [2025-05-28T19:53:08.971Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-28T19:53:08.971Z] GC before operation: completed in 44.199 ms, heap usage 433.483 MB -> 71.967 MB. [2025-05-28T19:53:13.058Z] ====== als (apache-spark) [default], iteration 28 completed (3593.220 ms) ====== [2025-05-28T19:53:13.058Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-28T19:53:13.058Z] GC before operation: completed in 54.049 ms, heap usage 152.056 MB -> 71.791 MB. [2025-05-28T19:53:16.243Z] ====== als (apache-spark) [default], iteration 29 completed (3541.266 ms) ====== [2025-05-28T19:53:16.611Z] ----------------------------------- [2025-05-28T19:53:16.611Z] renaissance-als_0_PASSED [2025-05-28T19:53:16.611Z] ----------------------------------- [2025-05-28T19:53:16.611Z] [2025-05-28T19:53:16.611Z] TEST TEARDOWN: [2025-05-28T19:53:16.611Z] Nothing to be done for teardown. [2025-05-28T19:53:16.963Z] renaissance-als_0 Finish Time: Wed May 28 15:53:16 2025 Epoch Time (ms): 1748461996574