[2025-08-29T23:56:22.298Z] Running test renaissance-als_0 ... [2025-08-29T23:56:22.298Z] =============================================== [2025-08-29T23:56:22.298Z] renaissance-als_0 Start Time: Fri Aug 29 19:56:22 2025 Epoch Time (ms): 1756511782020 [2025-08-29T23:56:22.298Z] variation: NoOptions [2025-08-29T23:56:22.298Z] JVM_OPTIONS: [2025-08-29T23:56:22.298Z] { \ [2025-08-29T23:56:22.298Z] echo ""; echo "TEST SETUP:"; \ [2025-08-29T23:56:22.298Z] echo "Nothing to be done for setup."; \ [2025-08-29T23:56:22.298Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17565117812806/renaissance-als_0"; \ [2025-08-29T23:56:22.298Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17565117812806/renaissance-als_0"; \ [2025-08-29T23:56:22.298Z] echo ""; echo "TESTING:"; \ [2025-08-29T23:56:22.298Z] "/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_17565117812806/renaissance-als_0"/als.json" als; \ [2025-08-29T23:56:22.298Z] 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_17565117812806/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-29T23:56:22.298Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-29T23:56:22.298Z] echo "Nothing to be done for teardown."; \ [2025-08-29T23:56:22.298Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17565117812806/TestTargetResult"; [2025-08-29T23:56:22.298Z] [2025-08-29T23:56:22.298Z] TEST SETUP: [2025-08-29T23:56:22.298Z] Nothing to be done for setup. [2025-08-29T23:56:22.298Z] [2025-08-29T23:56:22.298Z] TESTING: [2025-08-29T23:56:26.232Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-29T23:56:27.452Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-29T23:56:27.452Z] GC before operation: completed in 15.356 ms, heap usage 51.000 MB -> 35.025 MB. [2025-08-29T23:56:36.647Z] ====== als (apache-spark) [default], iteration 0 completed (7448.953 ms) ====== [2025-08-29T23:56:36.647Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-29T23:56:36.647Z] GC before operation: completed in 45.967 ms, heap usage 181.440 MB -> 61.571 MB. [2025-08-29T23:56:39.042Z] ====== als (apache-spark) [default], iteration 1 completed (3550.702 ms) ====== [2025-08-29T23:56:39.042Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-29T23:56:39.042Z] GC before operation: completed in 57.136 ms, heap usage 339.033 MB -> 62.715 MB. [2025-08-29T23:56:43.025Z] ====== als (apache-spark) [default], iteration 2 completed (3406.092 ms) ====== [2025-08-29T23:56:43.025Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-29T23:56:43.025Z] GC before operation: completed in 43.321 ms, heap usage 100.241 MB -> 63.438 MB. [2025-08-29T23:56:46.201Z] ====== als (apache-spark) [default], iteration 3 completed (3082.409 ms) ====== [2025-08-29T23:56:46.201Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-29T23:56:46.201Z] GC before operation: completed in 48.918 ms, heap usage 203.311 MB -> 63.784 MB. [2025-08-29T23:56:49.313Z] ====== als (apache-spark) [default], iteration 4 completed (3107.553 ms) ====== [2025-08-29T23:56:49.313Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-29T23:56:49.313Z] GC before operation: completed in 47.402 ms, heap usage 299.811 MB -> 64.516 MB. [2025-08-29T23:56:52.497Z] ====== als (apache-spark) [default], iteration 5 completed (3074.568 ms) ====== [2025-08-29T23:56:52.497Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-29T23:56:52.497Z] GC before operation: completed in 46.694 ms, heap usage 202.683 MB -> 64.880 MB. [2025-08-29T23:56:55.651Z] ====== als (apache-spark) [default], iteration 6 completed (3007.061 ms) ====== [2025-08-29T23:56:55.651Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-29T23:56:55.651Z] GC before operation: completed in 52.416 ms, heap usage 211.329 MB -> 65.398 MB. [2025-08-29T23:56:59.029Z] ====== als (apache-spark) [default], iteration 7 completed (2952.409 ms) ====== [2025-08-29T23:56:59.029Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-29T23:56:59.029Z] GC before operation: completed in 40.681 ms, heap usage 258.208 MB -> 66.021 MB. [2025-08-29T23:57:02.174Z] ====== als (apache-spark) [default], iteration 8 completed (3068.513 ms) ====== [2025-08-29T23:57:02.174Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-29T23:57:02.174Z] GC before operation: completed in 50.616 ms, heap usage 155.621 MB -> 66.418 MB. [2025-08-29T23:57:05.395Z] ====== als (apache-spark) [default], iteration 9 completed (3049.413 ms) ====== [2025-08-29T23:57:05.395Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-29T23:57:05.395Z] GC before operation: completed in 38.665 ms, heap usage 484.604 MB -> 67.224 MB. [2025-08-29T23:57:07.835Z] ====== als (apache-spark) [default], iteration 10 completed (2941.687 ms) ====== [2025-08-29T23:57:07.835Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-29T23:57:07.835Z] GC before operation: completed in 41.881 ms, heap usage 204.573 MB -> 67.070 MB. [2025-08-29T23:57:11.010Z] ====== als (apache-spark) [default], iteration 11 completed (2962.407 ms) ====== [2025-08-29T23:57:11.010Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-29T23:57:11.010Z] GC before operation: completed in 47.642 ms, heap usage 117.774 MB -> 67.248 MB. [2025-08-29T23:57:14.213Z] ====== als (apache-spark) [default], iteration 12 completed (2953.055 ms) ====== [2025-08-29T23:57:14.213Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-29T23:57:14.213Z] GC before operation: completed in 47.252 ms, heap usage 223.453 MB -> 67.600 MB. [2025-08-29T23:57:17.357Z] ====== als (apache-spark) [default], iteration 13 completed (2940.029 ms) ====== [2025-08-29T23:57:17.357Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-29T23:57:17.357Z] GC before operation: completed in 39.481 ms, heap usage 212.048 MB -> 67.779 MB. [2025-08-29T23:57:20.544Z] ====== als (apache-spark) [default], iteration 14 completed (2939.293 ms) ====== [2025-08-29T23:57:20.544Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-29T23:57:20.544Z] GC before operation: completed in 49.996 ms, heap usage 186.406 MB -> 68.075 MB. [2025-08-29T23:57:23.755Z] ====== als (apache-spark) [default], iteration 15 completed (2962.258 ms) ====== [2025-08-29T23:57:23.755Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-29T23:57:23.755Z] GC before operation: completed in 49.920 ms, heap usage 365.741 MB -> 68.475 MB. [2025-08-29T23:57:26.243Z] ====== als (apache-spark) [default], iteration 16 completed (2949.161 ms) ====== [2025-08-29T23:57:26.243Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-29T23:57:26.243Z] GC before operation: completed in 40.937 ms, heap usage 446.551 MB -> 69.020 MB. [2025-08-29T23:57:29.544Z] ====== als (apache-spark) [default], iteration 17 completed (3022.867 ms) ====== [2025-08-29T23:57:29.544Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-29T23:57:29.544Z] GC before operation: completed in 54.945 ms, heap usage 315.925 MB -> 69.094 MB. [2025-08-29T23:57:32.714Z] ====== als (apache-spark) [default], iteration 18 completed (2975.118 ms) ====== [2025-08-29T23:57:32.714Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-29T23:57:32.714Z] GC before operation: completed in 40.575 ms, heap usage 443.472 MB -> 69.551 MB. [2025-08-29T23:57:35.894Z] ====== als (apache-spark) [default], iteration 19 completed (3106.524 ms) ====== [2025-08-29T23:57:35.894Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-29T23:57:35.894Z] GC before operation: completed in 52.299 ms, heap usage 451.951 MB -> 69.783 MB. [2025-08-29T23:57:39.918Z] ====== als (apache-spark) [default], iteration 20 completed (3405.381 ms) ====== [2025-08-29T23:57:39.918Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-29T23:57:39.918Z] GC before operation: completed in 58.407 ms, heap usage 231.834 MB -> 69.697 MB. [2025-08-29T23:57:43.107Z] ====== als (apache-spark) [default], iteration 21 completed (3441.607 ms) ====== [2025-08-29T23:57:43.107Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-29T23:57:43.107Z] GC before operation: completed in 56.049 ms, heap usage 448.223 MB -> 70.210 MB. [2025-08-29T23:57:47.202Z] ====== als (apache-spark) [default], iteration 22 completed (3783.661 ms) ====== [2025-08-29T23:57:47.202Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-29T23:57:47.202Z] GC before operation: completed in 49.967 ms, heap usage 205.191 MB -> 70.289 MB. [2025-08-29T23:57:50.548Z] ====== als (apache-spark) [default], iteration 23 completed (3143.725 ms) ====== [2025-08-29T23:57:50.548Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-29T23:57:50.548Z] GC before operation: completed in 62.636 ms, heap usage 242.971 MB -> 70.459 MB. [2025-08-29T23:57:54.697Z] ====== als (apache-spark) [default], iteration 24 completed (3854.903 ms) ====== [2025-08-29T23:57:54.697Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-29T23:57:54.697Z] GC before operation: completed in 68.402 ms, heap usage 483.360 MB -> 71.581 MB. [2025-08-29T23:57:58.901Z] ====== als (apache-spark) [default], iteration 25 completed (3992.513 ms) ====== [2025-08-29T23:57:58.901Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-29T23:57:58.901Z] GC before operation: completed in 57.797 ms, heap usage 312.113 MB -> 71.142 MB. [2025-08-29T23:58:02.416Z] ====== als (apache-spark) [default], iteration 26 completed (3854.360 ms) ====== [2025-08-29T23:58:02.416Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-29T23:58:02.416Z] GC before operation: completed in 54.637 ms, heap usage 159.302 MB -> 71.211 MB. [2025-08-29T23:58:06.547Z] ====== als (apache-spark) [default], iteration 27 completed (3404.730 ms) ====== [2025-08-29T23:58:06.547Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-29T23:58:06.547Z] GC before operation: completed in 48.996 ms, heap usage 505.087 MB -> 71.804 MB. [2025-08-29T23:58:09.781Z] ====== als (apache-spark) [default], iteration 28 completed (3269.576 ms) ====== [2025-08-29T23:58:09.781Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-29T23:58:09.781Z] GC before operation: completed in 52.601 ms, heap usage 388.152 MB -> 71.854 MB. [2025-08-29T23:58:13.019Z] ====== als (apache-spark) [default], iteration 29 completed (3372.850 ms) ====== [2025-08-29T23:58:13.019Z] ----------------------------------- [2025-08-29T23:58:13.019Z] renaissance-als_0_PASSED [2025-08-29T23:58:13.019Z] ----------------------------------- [2025-08-29T23:58:13.019Z] [2025-08-29T23:58:13.019Z] TEST TEARDOWN: [2025-08-29T23:58:13.019Z] Nothing to be done for teardown. [2025-08-29T23:58:13.019Z] renaissance-als_0 Finish Time: Fri Aug 29 19:58:12 2025 Epoch Time (ms): 1756511892820