[2026-01-21T21:10:57.836Z] Running test renaissance-als_0 ... [2026-01-21T21:10:57.836Z] =============================================== [2026-01-21T21:10:57.836Z] renaissance-als_0 Start Time: Wed Jan 21 16:10:57 2026 Epoch Time (ms): 1769029857566 [2026-01-21T21:10:57.836Z] variation: NoOptions [2026-01-21T21:10:57.836Z] JVM_OPTIONS: [2026-01-21T21:10:57.836Z] { \ [2026-01-21T21:10:57.836Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T21:10:57.836Z] echo "Nothing to be done for setup."; \ [2026-01-21T21:10:57.836Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-als_0"; \ [2026-01-21T21:10:57.836Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-als_0"; \ [2026-01-21T21:10:57.836Z] echo ""; echo "TESTING:"; \ [2026-01-21T21:10:57.836Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-als_0"/als.json" als; \ [2026-01-21T21:10:57.836Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T21:10:57.836Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T21:10:57.836Z] echo "Nothing to be done for teardown."; \ [2026-01-21T21:10:57.836Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1769029854654/TestTargetResult"; [2026-01-21T21:10:57.836Z] [2026-01-21T21:10:57.836Z] TEST SETUP: [2026-01-21T21:10:57.836Z] Nothing to be done for setup. [2026-01-21T21:10:57.836Z] [2026-01-21T21:10:57.836Z] TESTING: [2026-01-21T21:11:28.882Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-21T21:11:44.789Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T21:11:44.789Z] GC before operation: completed in 209.870 ms, heap usage 318.919 MB -> 34.200 MB. [2026-01-21T21:13:12.710Z] ====== als (apache-spark) [default], iteration 0 completed (75269.147 ms) ====== [2026-01-21T21:13:12.710Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T21:13:12.710Z] GC before operation: completed in 227.271 ms, heap usage 204.199 MB -> 60.902 MB. [2026-01-21T21:13:36.637Z] ====== als (apache-spark) [default], iteration 1 completed (33424.887 ms) ====== [2026-01-21T21:13:36.637Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T21:13:37.118Z] GC before operation: completed in 869.206 ms, heap usage 176.925 MB -> 61.558 MB. [2026-01-21T21:14:22.172Z] ====== als (apache-spark) [default], iteration 2 completed (37889.070 ms) ====== [2026-01-21T21:14:22.172Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T21:14:22.172Z] GC before operation: completed in 380.317 ms, heap usage 647.606 MB -> 63.138 MB. [2026-01-21T21:14:48.801Z] ====== als (apache-spark) [default], iteration 3 completed (29559.320 ms) ====== [2026-01-21T21:14:48.801Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T21:14:48.801Z] GC before operation: completed in 205.114 ms, heap usage 353.005 MB -> 62.995 MB. [2026-01-21T21:15:25.821Z] ====== als (apache-spark) [default], iteration 4 completed (33661.932 ms) ====== [2026-01-21T21:15:25.821Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T21:15:25.821Z] GC before operation: completed in 412.503 ms, heap usage 138.669 MB -> 64.713 MB. [2026-01-21T21:15:57.225Z] ====== als (apache-spark) [default], iteration 5 completed (31946.483 ms) ====== [2026-01-21T21:15:57.225Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T21:15:57.225Z] GC before operation: completed in 290.885 ms, heap usage 245.237 MB -> 64.326 MB. [2026-01-21T21:16:28.827Z] ====== als (apache-spark) [default], iteration 6 completed (29776.785 ms) ====== [2026-01-21T21:16:28.827Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T21:16:28.827Z] GC before operation: completed in 289.026 ms, heap usage 688.687 MB -> 65.387 MB. [2026-01-21T21:17:00.141Z] ====== als (apache-spark) [default], iteration 7 completed (30697.325 ms) ====== [2026-01-21T21:17:00.141Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T21:17:00.141Z] GC before operation: completed in 445.545 ms, heap usage 1.060 GB -> 66.373 MB. [2026-01-21T21:17:37.442Z] ====== als (apache-spark) [default], iteration 8 completed (35717.976 ms) ====== [2026-01-21T21:17:37.442Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T21:17:37.442Z] GC before operation: completed in 312.131 ms, heap usage 578.262 MB -> 66.183 MB. [2026-01-21T21:18:14.310Z] ====== als (apache-spark) [default], iteration 9 completed (33014.918 ms) ====== [2026-01-21T21:18:14.310Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T21:18:14.310Z] GC before operation: completed in 439.199 ms, heap usage 552.701 MB -> 66.547 MB. [2026-01-21T21:18:41.016Z] ====== als (apache-spark) [default], iteration 10 completed (28863.542 ms) ====== [2026-01-21T21:18:41.016Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T21:18:41.016Z] GC before operation: completed in 353.522 ms, heap usage 1.030 GB -> 67.186 MB. [2026-01-21T21:19:18.780Z] ====== als (apache-spark) [default], iteration 11 completed (32966.356 ms) ====== [2026-01-21T21:19:18.780Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T21:19:18.780Z] GC before operation: completed in 332.038 ms, heap usage 962.698 MB -> 67.527 MB. [2026-01-21T21:19:51.183Z] ====== als (apache-spark) [default], iteration 12 completed (34869.383 ms) ====== [2026-01-21T21:19:51.183Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T21:19:51.638Z] GC before operation: completed in 326.340 ms, heap usage 340.665 MB -> 66.849 MB. [2026-01-21T21:20:23.311Z] ====== als (apache-spark) [default], iteration 13 completed (27452.487 ms) ====== [2026-01-21T21:20:23.311Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T21:20:23.311Z] GC before operation: completed in 335.026 ms, heap usage 710.885 MB -> 67.817 MB. [2026-01-21T21:21:00.537Z] ====== als (apache-spark) [default], iteration 14 completed (38757.654 ms) ====== [2026-01-21T21:21:00.537Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T21:21:00.537Z] GC before operation: completed in 992.042 ms, heap usage 936.399 MB -> 68.348 MB. [2026-01-21T21:21:32.143Z] ====== als (apache-spark) [default], iteration 15 completed (31313.791 ms) ====== [2026-01-21T21:21:32.143Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T21:21:32.143Z] GC before operation: completed in 344.777 ms, heap usage 352.425 MB -> 67.659 MB. [2026-01-21T21:22:09.807Z] ====== als (apache-spark) [default], iteration 16 completed (30996.424 ms) ====== [2026-01-21T21:22:09.807Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T21:22:09.807Z] GC before operation: completed in 395.578 ms, heap usage 605.540 MB -> 68.457 MB. [2026-01-21T21:22:36.505Z] ====== als (apache-spark) [default], iteration 17 completed (29456.676 ms) ====== [2026-01-21T21:22:36.505Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T21:22:36.505Z] GC before operation: completed in 412.837 ms, heap usage 614.577 MB -> 68.701 MB. [2026-01-21T21:23:08.051Z] ====== als (apache-spark) [default], iteration 18 completed (30281.578 ms) ====== [2026-01-21T21:23:08.051Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T21:23:08.051Z] GC before operation: completed in 914.290 ms, heap usage 158.950 MB -> 68.502 MB. [2026-01-21T21:23:46.158Z] ====== als (apache-spark) [default], iteration 19 completed (35255.743 ms) ====== [2026-01-21T21:23:46.158Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T21:23:46.158Z] GC before operation: completed in 353.491 ms, heap usage 255.684 MB -> 68.760 MB. [2026-01-21T21:24:17.963Z] ====== als (apache-spark) [default], iteration 20 completed (33619.470 ms) ====== [2026-01-21T21:24:17.963Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T21:24:18.622Z] GC before operation: completed in 488.590 ms, heap usage 898.869 MB -> 69.705 MB. [2026-01-21T21:24:56.216Z] ====== als (apache-spark) [default], iteration 21 completed (32571.004 ms) ====== [2026-01-21T21:24:56.216Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T21:24:56.216Z] GC before operation: completed in 337.549 ms, heap usage 405.436 MB -> 69.252 MB. [2026-01-21T21:25:33.825Z] ====== als (apache-spark) [default], iteration 22 completed (37277.398 ms) ====== [2026-01-21T21:25:33.825Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T21:25:33.825Z] GC before operation: completed in 290.099 ms, heap usage 155.145 MB -> 69.345 MB. [2026-01-21T21:26:00.677Z] ====== als (apache-spark) [default], iteration 23 completed (30923.212 ms) ====== [2026-01-21T21:26:00.677Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T21:26:01.234Z] GC before operation: completed in 293.083 ms, heap usage 454.696 MB -> 70.021 MB. [2026-01-21T21:26:38.171Z] ====== als (apache-spark) [default], iteration 24 completed (34071.983 ms) ====== [2026-01-21T21:26:38.171Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T21:26:38.171Z] GC before operation: completed in 266.612 ms, heap usage 751.627 MB -> 70.833 MB. [2026-01-21T21:27:10.505Z] ====== als (apache-spark) [default], iteration 25 completed (31739.774 ms) ====== [2026-01-21T21:27:10.506Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T21:27:10.506Z] GC before operation: completed in 276.181 ms, heap usage 491.998 MB -> 72.567 MB. [2026-01-21T21:27:42.162Z] ====== als (apache-spark) [default], iteration 26 completed (29789.483 ms) ====== [2026-01-21T21:27:42.162Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T21:27:42.162Z] GC before operation: completed in 944.421 ms, heap usage 1.384 GB -> 71.892 MB. [2026-01-21T21:28:13.645Z] ====== als (apache-spark) [default], iteration 27 completed (31295.328 ms) ====== [2026-01-21T21:28:13.645Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T21:28:13.645Z] GC before operation: completed in 299.149 ms, heap usage 1.084 GB -> 71.308 MB. [2026-01-21T21:28:45.205Z] ====== als (apache-spark) [default], iteration 28 completed (29823.780 ms) ====== [2026-01-21T21:28:45.205Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T21:28:45.205Z] GC before operation: completed in 294.262 ms, heap usage 392.507 MB -> 70.617 MB. [2026-01-21T21:29:23.239Z] ====== als (apache-spark) [default], iteration 29 completed (35545.696 ms) ====== [2026-01-21T21:29:23.239Z] ----------------------------------- [2026-01-21T21:29:23.239Z] renaissance-als_0_PASSED [2026-01-21T21:29:23.239Z] ----------------------------------- [2026-01-21T21:29:23.239Z] [2026-01-21T21:29:23.239Z] TEST TEARDOWN: [2026-01-21T21:29:23.239Z] Nothing to be done for teardown. [2026-01-21T21:29:24.315Z] renaissance-als_0 Finish Time: Wed Jan 21 16:29:23 2026 Epoch Time (ms): 1769030963758