[2025-12-17T21:41:35.310Z] Running test renaissance-als_0 ... [2025-12-17T21:41:35.310Z] =============================================== [2025-12-17T21:41:35.310Z] renaissance-als_0 Start Time: Wed Dec 17 16:41:34 2025 Epoch Time (ms): 1766007694843 [2025-12-17T21:41:35.310Z] variation: NoOptions [2025-12-17T21:41:35.310Z] JVM_OPTIONS: [2025-12-17T21:41:35.310Z] { \ [2025-12-17T21:41:35.310Z] echo ""; echo "TEST SETUP:"; \ [2025-12-17T21:41:35.310Z] echo "Nothing to be done for setup."; \ [2025-12-17T21:41:35.310Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17660076942923/renaissance-als_0"; \ [2025-12-17T21:41:35.310Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17660076942923/renaissance-als_0"; \ [2025-12-17T21:41:35.310Z] echo ""; echo "TESTING:"; \ [2025-12-17T21:41:35.310Z] "/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_17660076942923/renaissance-als_0"/als.json" als; \ [2025-12-17T21:41:35.310Z] 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_17660076942923/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-17T21:41:35.310Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-17T21:41:35.310Z] echo "Nothing to be done for teardown."; \ [2025-12-17T21:41:35.310Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17660076942923/TestTargetResult"; [2025-12-17T21:41:35.310Z] [2025-12-17T21:41:35.310Z] TEST SETUP: [2025-12-17T21:41:35.310Z] Nothing to be done for setup. [2025-12-17T21:41:35.310Z] [2025-12-17T21:41:35.310Z] TESTING: [2025-12-17T21:41:39.486Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-17T21:41:41.965Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-17T21:41:41.965Z] GC before operation: completed in 13.924 ms, heap usage 72.846 MB -> 35.115 MB. [2025-12-17T21:41:53.394Z] ====== als (apache-spark) [default], iteration 0 completed (9809.912 ms) ====== [2025-12-17T21:41:53.394Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-17T21:41:53.394Z] GC before operation: completed in 44.048 ms, heap usage 284.017 MB -> 62.242 MB. [2025-12-17T21:41:56.009Z] ====== als (apache-spark) [default], iteration 1 completed (3892.017 ms) ====== [2025-12-17T21:41:56.009Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-17T21:41:56.009Z] GC before operation: completed in 45.378 ms, heap usage 999.614 MB -> 63.846 MB. [2025-12-17T21:42:00.198Z] ====== als (apache-spark) [default], iteration 2 completed (3724.623 ms) ====== [2025-12-17T21:42:00.198Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-17T21:42:00.198Z] GC before operation: completed in 40.955 ms, heap usage 382.210 MB -> 63.800 MB. [2025-12-17T21:42:02.824Z] ====== als (apache-spark) [default], iteration 3 completed (2816.064 ms) ====== [2025-12-17T21:42:02.825Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-17T21:42:02.825Z] GC before operation: completed in 43.813 ms, heap usage 224.080 MB -> 64.104 MB. [2025-12-17T21:42:05.290Z] ====== als (apache-spark) [default], iteration 4 completed (2607.647 ms) ====== [2025-12-17T21:42:05.290Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-17T21:42:05.290Z] GC before operation: completed in 35.719 ms, heap usage 237.132 MB -> 64.776 MB. [2025-12-17T21:42:08.485Z] ====== als (apache-spark) [default], iteration 5 completed (2769.600 ms) ====== [2025-12-17T21:42:08.485Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-17T21:42:08.485Z] GC before operation: completed in 37.450 ms, heap usage 233.614 MB -> 65.341 MB. [2025-12-17T21:42:11.008Z] ====== als (apache-spark) [default], iteration 6 completed (2563.082 ms) ====== [2025-12-17T21:42:11.008Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-17T21:42:11.008Z] GC before operation: completed in 36.708 ms, heap usage 175.138 MB -> 65.838 MB. [2025-12-17T21:42:13.558Z] ====== als (apache-spark) [default], iteration 7 completed (2558.764 ms) ====== [2025-12-17T21:42:13.558Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-17T21:42:13.558Z] GC before operation: completed in 41.950 ms, heap usage 246.296 MB -> 66.443 MB. [2025-12-17T21:42:16.801Z] ====== als (apache-spark) [default], iteration 8 completed (2591.676 ms) ====== [2025-12-17T21:42:16.801Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-17T21:42:16.801Z] GC before operation: completed in 38.976 ms, heap usage 292.428 MB -> 67.020 MB. [2025-12-17T21:42:19.294Z] ====== als (apache-spark) [default], iteration 9 completed (2592.596 ms) ====== [2025-12-17T21:42:19.294Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-17T21:42:19.294Z] GC before operation: completed in 40.811 ms, heap usage 723.997 MB -> 67.868 MB. [2025-12-17T21:42:22.440Z] ====== als (apache-spark) [default], iteration 10 completed (2847.961 ms) ====== [2025-12-17T21:42:22.440Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-17T21:42:22.440Z] GC before operation: completed in 44.593 ms, heap usage 340.939 MB -> 67.617 MB. [2025-12-17T21:42:24.884Z] ====== als (apache-spark) [default], iteration 11 completed (2551.941 ms) ====== [2025-12-17T21:42:24.884Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-17T21:42:24.884Z] GC before operation: completed in 41.895 ms, heap usage 681.404 MB -> 68.323 MB. [2025-12-17T21:42:27.325Z] ====== als (apache-spark) [default], iteration 12 completed (2581.277 ms) ====== [2025-12-17T21:42:27.325Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-17T21:42:27.325Z] GC before operation: completed in 44.914 ms, heap usage 399.244 MB -> 68.180 MB. [2025-12-17T21:42:30.618Z] ====== als (apache-spark) [default], iteration 13 completed (2822.557 ms) ====== [2025-12-17T21:42:30.618Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-17T21:42:30.618Z] GC before operation: completed in 54.293 ms, heap usage 281.001 MB -> 68.315 MB. [2025-12-17T21:42:33.108Z] ====== als (apache-spark) [default], iteration 14 completed (2535.632 ms) ====== [2025-12-17T21:42:33.108Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-17T21:42:33.108Z] GC before operation: completed in 38.118 ms, heap usage 206.267 MB -> 68.419 MB. [2025-12-17T21:42:35.592Z] ====== als (apache-spark) [default], iteration 15 completed (2745.429 ms) ====== [2025-12-17T21:42:35.592Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-17T21:42:35.592Z] GC before operation: completed in 41.016 ms, heap usage 593.663 MB -> 69.345 MB. [2025-12-17T21:42:38.826Z] ====== als (apache-spark) [default], iteration 16 completed (2937.682 ms) ====== [2025-12-17T21:42:38.826Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-17T21:42:38.826Z] GC before operation: completed in 39.754 ms, heap usage 382.208 MB -> 69.342 MB. [2025-12-17T21:42:41.306Z] ====== als (apache-spark) [default], iteration 17 completed (2370.835 ms) ====== [2025-12-17T21:42:41.306Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-17T21:42:41.306Z] GC before operation: completed in 39.113 ms, heap usage 741.753 MB -> 69.970 MB. [2025-12-17T21:42:43.797Z] ====== als (apache-spark) [default], iteration 18 completed (2409.454 ms) ====== [2025-12-17T21:42:43.797Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-17T21:42:43.797Z] GC before operation: completed in 43.626 ms, heap usage 523.488 MB -> 70.017 MB. [2025-12-17T21:42:46.871Z] ====== als (apache-spark) [default], iteration 19 completed (2651.145 ms) ====== [2025-12-17T21:42:46.871Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-17T21:42:46.871Z] GC before operation: completed in 48.338 ms, heap usage 166.347 MB -> 69.748 MB. [2025-12-17T21:42:49.415Z] ====== als (apache-spark) [default], iteration 20 completed (2699.752 ms) ====== [2025-12-17T21:42:49.415Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-17T21:42:49.415Z] GC before operation: completed in 42.034 ms, heap usage 808.815 MB -> 70.842 MB. [2025-12-17T21:42:51.932Z] ====== als (apache-spark) [default], iteration 21 completed (2636.854 ms) ====== [2025-12-17T21:42:51.932Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-17T21:42:51.932Z] GC before operation: completed in 39.994 ms, heap usage 213.033 MB -> 70.353 MB. [2025-12-17T21:42:55.225Z] ====== als (apache-spark) [default], iteration 22 completed (2580.819 ms) ====== [2025-12-17T21:42:55.225Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-17T21:42:55.225Z] GC before operation: completed in 36.757 ms, heap usage 309.264 MB -> 70.786 MB. [2025-12-17T21:42:57.128Z] ====== als (apache-spark) [default], iteration 23 completed (2352.921 ms) ====== [2025-12-17T21:42:57.128Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-17T21:42:57.128Z] GC before operation: completed in 40.578 ms, heap usage 143.033 MB -> 70.918 MB. [2025-12-17T21:42:59.659Z] ====== als (apache-spark) [default], iteration 24 completed (2480.461 ms) ====== [2025-12-17T21:42:59.659Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-17T21:42:59.659Z] GC before operation: completed in 41.449 ms, heap usage 268.168 MB -> 71.309 MB. [2025-12-17T21:43:02.235Z] ====== als (apache-spark) [default], iteration 25 completed (2542.072 ms) ====== [2025-12-17T21:43:02.235Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-17T21:43:02.630Z] GC before operation: completed in 48.204 ms, heap usage 643.616 MB -> 72.183 MB. [2025-12-17T21:43:05.157Z] ====== als (apache-spark) [default], iteration 26 completed (2722.102 ms) ====== [2025-12-17T21:43:05.157Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-17T21:43:05.157Z] GC before operation: completed in 41.414 ms, heap usage 344.051 MB -> 71.969 MB. [2025-12-17T21:43:08.526Z] ====== als (apache-spark) [default], iteration 27 completed (3087.852 ms) ====== [2025-12-17T21:43:08.526Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-17T21:43:08.526Z] GC before operation: completed in 82.859 ms, heap usage 694.424 MB -> 72.675 MB. [2025-12-17T21:43:11.761Z] ====== als (apache-spark) [default], iteration 28 completed (2691.691 ms) ====== [2025-12-17T21:43:11.761Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-17T21:43:11.761Z] GC before operation: completed in 41.846 ms, heap usage 283.010 MB -> 72.375 MB. [2025-12-17T21:43:14.283Z] ====== als (apache-spark) [default], iteration 29 completed (2909.808 ms) ====== [2025-12-17T21:43:14.652Z] ----------------------------------- [2025-12-17T21:43:14.652Z] renaissance-als_0_PASSED [2025-12-17T21:43:14.652Z] ----------------------------------- [2025-12-17T21:43:14.652Z] [2025-12-17T21:43:14.652Z] TEST TEARDOWN: [2025-12-17T21:43:14.652Z] Nothing to be done for teardown. [2025-12-17T21:43:14.652Z] renaissance-als_0 Finish Time: Wed Dec 17 16:43:14 2025 Epoch Time (ms): 1766007794379