[2025-04-30T23:47:04.385Z] Running test renaissance-als_0 ... [2025-04-30T23:47:04.385Z] =============================================== [2025-04-30T23:47:04.385Z] renaissance-als_0 Start Time: Wed Apr 30 19:47:04 2025 Epoch Time (ms): 1746056824301 [2025-04-30T23:47:04.385Z] variation: NoOptions [2025-04-30T23:47:04.385Z] JVM_OPTIONS: [2025-04-30T23:47:04.385Z] { \ [2025-04-30T23:47:04.385Z] echo ""; echo "TEST SETUP:"; \ [2025-04-30T23:47:04.385Z] echo "Nothing to be done for setup."; \ [2025-04-30T23:47:04.385Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17460568245804/renaissance-als_0"; \ [2025-04-30T23:47:04.385Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17460568245804/renaissance-als_0"; \ [2025-04-30T23:47:04.385Z] echo ""; echo "TESTING:"; \ [2025-04-30T23:47:04.385Z] "/Users/admin/workspace/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17460568245804/renaissance-als_0"/als.json" als; \ [2025-04-30T23:47:04.385Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17460568245804/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-30T23:47:04.385Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-30T23:47:04.385Z] echo "Nothing to be done for teardown."; \ [2025-04-30T23:47:04.385Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17460568245804/TestTargetResult"; [2025-04-30T23:47:04.385Z] [2025-04-30T23:47:04.385Z] TEST SETUP: [2025-04-30T23:47:04.385Z] Nothing to be done for setup. [2025-04-30T23:47:04.385Z] [2025-04-30T23:47:04.385Z] TESTING: [2025-04-30T23:47:07.746Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-04-30T23:47:09.104Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-04-30T23:47:09.499Z] GC before operation: completed in 15.925 ms, heap usage 59.906 MB -> 35.072 MB. [2025-04-30T23:47:17.533Z] ====== als (apache-spark) [default], iteration 0 completed (8012.123 ms) ====== [2025-04-30T23:47:17.533Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-04-30T23:47:17.533Z] GC before operation: completed in 53.068 ms, heap usage 607.311 MB -> 62.390 MB. [2025-04-30T23:47:21.836Z] ====== als (apache-spark) [default], iteration 1 completed (3529.661 ms) ====== [2025-04-30T23:47:21.836Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-04-30T23:47:21.836Z] GC before operation: completed in 32.475 ms, heap usage 359.670 MB -> 63.045 MB. [2025-04-30T23:47:24.546Z] ====== als (apache-spark) [default], iteration 2 completed (3170.212 ms) ====== [2025-04-30T23:47:24.546Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-04-30T23:47:24.546Z] GC before operation: completed in 34.215 ms, heap usage 519.809 MB -> 63.805 MB. [2025-04-30T23:47:27.966Z] ====== als (apache-spark) [default], iteration 3 completed (2997.408 ms) ====== [2025-04-30T23:47:27.966Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-04-30T23:47:27.966Z] GC before operation: completed in 36.073 ms, heap usage 613.010 MB -> 64.509 MB. [2025-04-30T23:47:30.653Z] ====== als (apache-spark) [default], iteration 4 completed (3055.350 ms) ====== [2025-04-30T23:47:30.653Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-04-30T23:47:30.653Z] GC before operation: completed in 34.060 ms, heap usage 238.161 MB -> 64.619 MB. [2025-04-30T23:47:34.083Z] ====== als (apache-spark) [default], iteration 5 completed (3084.202 ms) ====== [2025-04-30T23:47:34.083Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-04-30T23:47:34.083Z] GC before operation: completed in 34.478 ms, heap usage 148.509 MB -> 65.019 MB. [2025-04-30T23:47:37.524Z] ====== als (apache-spark) [default], iteration 6 completed (2893.019 ms) ====== [2025-04-30T23:47:37.524Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-04-30T23:47:37.524Z] GC before operation: completed in 36.390 ms, heap usage 356.918 MB -> 65.785 MB. [2025-04-30T23:47:40.224Z] ====== als (apache-spark) [default], iteration 7 completed (2870.036 ms) ====== [2025-04-30T23:47:40.224Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-04-30T23:47:40.224Z] GC before operation: completed in 38.150 ms, heap usage 502.739 MB -> 66.525 MB. [2025-04-30T23:47:43.714Z] ====== als (apache-spark) [default], iteration 8 completed (3089.736 ms) ====== [2025-04-30T23:47:43.714Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-04-30T23:47:43.714Z] GC before operation: completed in 36.193 ms, heap usage 113.427 MB -> 66.739 MB. [2025-04-30T23:47:46.402Z] ====== als (apache-spark) [default], iteration 9 completed (2830.107 ms) ====== [2025-04-30T23:47:46.402Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-04-30T23:47:46.402Z] GC before operation: completed in 38.396 ms, heap usage 358.887 MB -> 67.125 MB. [2025-04-30T23:47:49.079Z] ====== als (apache-spark) [default], iteration 10 completed (2983.647 ms) ====== [2025-04-30T23:47:49.079Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-04-30T23:47:49.079Z] GC before operation: completed in 42.295 ms, heap usage 615.849 MB -> 67.792 MB. [2025-04-30T23:47:52.532Z] ====== als (apache-spark) [default], iteration 11 completed (2911.443 ms) ====== [2025-04-30T23:47:52.532Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-04-30T23:47:52.532Z] GC before operation: completed in 38.952 ms, heap usage 465.042 MB -> 67.944 MB. [2025-04-30T23:47:55.149Z] ====== als (apache-spark) [default], iteration 12 completed (3043.211 ms) ====== [2025-04-30T23:47:55.149Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-04-30T23:47:55.554Z] GC before operation: completed in 40.479 ms, heap usage 471.023 MB -> 68.258 MB. [2025-04-30T23:47:58.222Z] ====== als (apache-spark) [default], iteration 13 completed (2989.377 ms) ====== [2025-04-30T23:47:58.222Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-04-30T23:47:58.623Z] GC before operation: completed in 39.801 ms, heap usage 349.096 MB -> 68.204 MB. [2025-04-30T23:48:02.108Z] ====== als (apache-spark) [default], iteration 14 completed (3056.918 ms) ====== [2025-04-30T23:48:02.108Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-04-30T23:48:02.108Z] GC before operation: completed in 40.620 ms, heap usage 235.581 MB -> 68.375 MB. [2025-04-30T23:48:04.832Z] ====== als (apache-spark) [default], iteration 15 completed (2831.211 ms) ====== [2025-04-30T23:48:04.832Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-04-30T23:48:04.832Z] GC before operation: completed in 40.948 ms, heap usage 180.862 MB -> 68.512 MB. [2025-04-30T23:48:07.498Z] ====== als (apache-spark) [default], iteration 16 completed (2844.484 ms) ====== [2025-04-30T23:48:07.498Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-04-30T23:48:07.498Z] GC before operation: completed in 41.482 ms, heap usage 617.464 MB -> 69.305 MB. [2025-04-30T23:48:11.049Z] ====== als (apache-spark) [default], iteration 17 completed (3153.846 ms) ====== [2025-04-30T23:48:11.049Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-04-30T23:48:11.049Z] GC before operation: completed in 40.005 ms, heap usage 328.073 MB -> 69.203 MB. [2025-04-30T23:48:14.505Z] ====== als (apache-spark) [default], iteration 18 completed (3141.007 ms) ====== [2025-04-30T23:48:14.506Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-04-30T23:48:14.506Z] GC before operation: completed in 46.275 ms, heap usage 548.372 MB -> 69.876 MB. [2025-04-30T23:48:17.159Z] ====== als (apache-spark) [default], iteration 19 completed (2883.468 ms) ====== [2025-04-30T23:48:17.159Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-04-30T23:48:17.159Z] GC before operation: completed in 41.986 ms, heap usage 123.607 MB -> 69.586 MB. [2025-04-30T23:48:19.911Z] ====== als (apache-spark) [default], iteration 20 completed (2984.316 ms) ====== [2025-04-30T23:48:19.911Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-04-30T23:48:19.911Z] GC before operation: completed in 40.870 ms, heap usage 508.553 MB -> 70.291 MB. [2025-04-30T23:48:23.360Z] ====== als (apache-spark) [default], iteration 21 completed (2850.070 ms) ====== [2025-04-30T23:48:23.361Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-04-30T23:48:23.361Z] GC before operation: completed in 42.032 ms, heap usage 593.364 MB -> 70.701 MB. [2025-04-30T23:48:26.044Z] ====== als (apache-spark) [default], iteration 22 completed (2828.462 ms) ====== [2025-04-30T23:48:26.044Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-04-30T23:48:26.044Z] GC before operation: completed in 39.637 ms, heap usage 155.176 MB -> 70.386 MB. [2025-04-30T23:48:29.468Z] ====== als (apache-spark) [default], iteration 23 completed (2986.852 ms) ====== [2025-04-30T23:48:29.468Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-04-30T23:48:29.468Z] GC before operation: completed in 40.546 ms, heap usage 132.128 MB -> 70.592 MB. [2025-04-30T23:48:32.148Z] ====== als (apache-spark) [default], iteration 24 completed (2839.664 ms) ====== [2025-04-30T23:48:32.148Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-04-30T23:48:32.148Z] GC before operation: completed in 42.320 ms, heap usage 167.446 MB -> 70.873 MB. [2025-04-30T23:48:34.834Z] ====== als (apache-spark) [default], iteration 25 completed (2848.550 ms) ====== [2025-04-30T23:48:34.834Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-04-30T23:48:34.834Z] GC before operation: completed in 40.828 ms, heap usage 153.712 MB -> 71.258 MB. [2025-04-30T23:48:38.254Z] ====== als (apache-spark) [default], iteration 26 completed (2967.204 ms) ====== [2025-04-30T23:48:38.254Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-04-30T23:48:38.254Z] GC before operation: completed in 44.152 ms, heap usage 300.882 MB -> 71.569 MB. [2025-04-30T23:48:40.876Z] ====== als (apache-spark) [default], iteration 27 completed (2772.084 ms) ====== [2025-04-30T23:48:40.876Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-04-30T23:48:40.876Z] GC before operation: completed in 43.895 ms, heap usage 309.471 MB -> 71.890 MB. [2025-04-30T23:48:44.436Z] ====== als (apache-spark) [default], iteration 28 completed (3025.280 ms) ====== [2025-04-30T23:48:44.436Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-04-30T23:48:44.436Z] GC before operation: completed in 43.737 ms, heap usage 139.679 MB -> 71.901 MB. [2025-04-30T23:48:47.137Z] ====== als (apache-spark) [default], iteration 29 completed (2843.981 ms) ====== [2025-04-30T23:48:47.137Z] ----------------------------------- [2025-04-30T23:48:47.137Z] renaissance-als_0_PASSED [2025-04-30T23:48:47.137Z] ----------------------------------- [2025-04-30T23:48:47.137Z] [2025-04-30T23:48:47.137Z] TEST TEARDOWN: [2025-04-30T23:48:47.137Z] Nothing to be done for teardown. [2025-04-30T23:48:47.137Z] renaissance-als_0 Finish Time: Wed Apr 30 19:48:46 2025 Epoch Time (ms): 1746056926849