[2026-01-19T21:59:02.951Z] Running test renaissance-als_0 ... [2026-01-19T21:59:02.951Z] =============================================== [2026-01-19T21:59:03.391Z] renaissance-als_0 Start Time: Mon Jan 19 16:59:02 2026 Epoch Time (ms): 1768859942886 [2026-01-19T21:59:03.391Z] variation: NoOptions [2026-01-19T21:59:03.391Z] JVM_OPTIONS: [2026-01-19T21:59:03.391Z] { \ [2026-01-19T21:59:03.391Z] echo ""; echo "TEST SETUP:"; \ [2026-01-19T21:59:03.391Z] echo "Nothing to be done for setup."; \ [2026-01-19T21:59:03.391Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17688599406036/renaissance-als_0"; \ [2026-01-19T21:59:03.391Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17688599406036/renaissance-als_0"; \ [2026-01-19T21:59:03.391Z] echo ""; echo "TESTING:"; \ [2026-01-19T21:59:03.391Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/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_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17688599406036/renaissance-als_0"/als.json" als; \ [2026-01-19T21:59:03.391Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17688599406036/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-19T21:59:03.391Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-19T21:59:03.391Z] echo "Nothing to be done for teardown."; \ [2026-01-19T21:59:03.391Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17688599406036/TestTargetResult"; [2026-01-19T21:59:03.391Z] [2026-01-19T21:59:03.391Z] TEST SETUP: [2026-01-19T21:59:03.391Z] Nothing to be done for setup. [2026-01-19T21:59:03.391Z] [2026-01-19T21:59:03.391Z] TESTING: [2026-01-19T21:59:17.949Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-19T21:59:26.693Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-19T21:59:26.693Z] GC before operation: completed in 149.966 ms, heap usage 253.834 MB -> 34.004 MB. [2026-01-19T22:00:18.646Z] ====== als (apache-spark) [default], iteration 0 completed (44955.395 ms) ====== [2026-01-19T22:00:18.646Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-19T22:00:18.646Z] GC before operation: completed in 351.487 ms, heap usage 142.539 MB -> 61.675 MB. [2026-01-19T22:00:49.807Z] ====== als (apache-spark) [default], iteration 1 completed (35564.730 ms) ====== [2026-01-19T22:00:49.807Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-19T22:00:49.807Z] GC before operation: completed in 206.877 ms, heap usage 738.005 MB -> 62.626 MB. [2026-01-19T22:01:15.647Z] ====== als (apache-spark) [default], iteration 2 completed (25318.293 ms) ====== [2026-01-19T22:01:15.647Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-19T22:01:15.647Z] GC before operation: completed in 196.328 ms, heap usage 378.316 MB -> 62.719 MB. [2026-01-19T22:01:37.292Z] ====== als (apache-spark) [default], iteration 3 completed (20075.258 ms) ====== [2026-01-19T22:01:37.292Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-19T22:01:37.292Z] GC before operation: completed in 559.018 ms, heap usage 290.244 MB -> 63.320 MB. [2026-01-19T22:01:59.082Z] ====== als (apache-spark) [default], iteration 4 completed (19872.709 ms) ====== [2026-01-19T22:01:59.082Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-19T22:01:59.082Z] GC before operation: completed in 318.020 ms, heap usage 782.426 MB -> 64.533 MB. [2026-01-19T22:02:20.750Z] ====== als (apache-spark) [default], iteration 5 completed (22747.975 ms) ====== [2026-01-19T22:02:20.750Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-19T22:02:20.750Z] GC before operation: completed in 192.128 ms, heap usage 591.745 MB -> 64.876 MB. [2026-01-19T22:02:39.169Z] ====== als (apache-spark) [default], iteration 6 completed (16627.657 ms) ====== [2026-01-19T22:02:39.169Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-19T22:02:39.169Z] GC before operation: completed in 212.979 ms, heap usage 291.933 MB -> 64.848 MB. [2026-01-19T22:02:56.734Z] ====== als (apache-spark) [default], iteration 7 completed (17137.320 ms) ====== [2026-01-19T22:02:56.734Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-19T22:02:56.734Z] GC before operation: completed in 159.592 ms, heap usage 618.235 MB -> 65.877 MB. [2026-01-19T22:03:18.354Z] ====== als (apache-spark) [default], iteration 8 completed (22753.850 ms) ====== [2026-01-19T22:03:18.354Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-19T22:03:19.434Z] GC before operation: completed in 944.734 ms, heap usage 494.615 MB -> 66.258 MB. [2026-01-19T22:03:45.182Z] ====== als (apache-spark) [default], iteration 9 completed (23607.165 ms) ====== [2026-01-19T22:03:45.182Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-19T22:03:45.182Z] GC before operation: completed in 146.703 ms, heap usage 654.503 MB -> 66.820 MB. [2026-01-19T22:04:03.246Z] ====== als (apache-spark) [default], iteration 10 completed (17558.213 ms) ====== [2026-01-19T22:04:03.246Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-19T22:04:03.246Z] GC before operation: completed in 167.744 ms, heap usage 870.294 MB -> 67.433 MB. [2026-01-19T22:04:24.351Z] ====== als (apache-spark) [default], iteration 11 completed (19767.652 ms) ====== [2026-01-19T22:04:24.351Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-19T22:04:24.351Z] GC before operation: completed in 195.291 ms, heap usage 1.029 GB -> 67.565 MB. [2026-01-19T22:04:46.470Z] ====== als (apache-spark) [default], iteration 12 completed (23232.267 ms) ====== [2026-01-19T22:04:46.470Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-19T22:04:46.470Z] GC before operation: completed in 175.076 ms, heap usage 1.061 GB -> 68.171 MB. [2026-01-19T22:05:04.477Z] ====== als (apache-spark) [default], iteration 13 completed (18338.955 ms) ====== [2026-01-19T22:05:04.477Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-19T22:05:05.041Z] GC before operation: completed in 420.450 ms, heap usage 795.438 MB -> 68.061 MB. [2026-01-19T22:05:23.130Z] ====== als (apache-spark) [default], iteration 14 completed (18207.122 ms) ====== [2026-01-19T22:05:23.130Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-19T22:05:23.655Z] GC before operation: completed in 194.031 ms, heap usage 826.244 MB -> 68.331 MB. [2026-01-19T22:05:45.153Z] ====== als (apache-spark) [default], iteration 15 completed (21445.677 ms) ====== [2026-01-19T22:05:45.153Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-19T22:05:45.659Z] GC before operation: completed in 246.465 ms, heap usage 1.144 GB -> 68.882 MB. [2026-01-19T22:06:07.332Z] ====== als (apache-spark) [default], iteration 16 completed (20979.311 ms) ====== [2026-01-19T22:06:07.332Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-19T22:06:07.332Z] GC before operation: completed in 161.446 ms, heap usage 206.485 MB -> 68.039 MB. [2026-01-19T22:06:25.184Z] ====== als (apache-spark) [default], iteration 17 completed (17577.898 ms) ====== [2026-01-19T22:06:25.184Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-19T22:06:25.184Z] GC before operation: completed in 195.462 ms, heap usage 159.654 MB -> 67.927 MB. [2026-01-19T22:06:46.697Z] ====== als (apache-spark) [default], iteration 18 completed (19318.017 ms) ====== [2026-01-19T22:06:46.697Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-19T22:06:46.697Z] GC before operation: completed in 207.268 ms, heap usage 895.859 MB -> 69.442 MB. [2026-01-19T22:07:08.262Z] ====== als (apache-spark) [default], iteration 19 completed (20284.421 ms) ====== [2026-01-19T22:07:08.262Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-19T22:07:08.262Z] GC before operation: completed in 280.379 ms, heap usage 842.940 MB -> 69.637 MB. [2026-01-19T22:07:30.211Z] ====== als (apache-spark) [default], iteration 20 completed (22642.406 ms) ====== [2026-01-19T22:07:30.211Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-19T22:07:30.211Z] GC before operation: completed in 207.245 ms, heap usage 1.117 GB -> 70.241 MB. [2026-01-19T22:07:48.473Z] ====== als (apache-spark) [default], iteration 21 completed (18437.944 ms) ====== [2026-01-19T22:07:48.473Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-19T22:07:48.473Z] GC before operation: completed in 459.819 ms, heap usage 1.267 GB -> 70.394 MB. [2026-01-19T22:08:09.690Z] ====== als (apache-spark) [default], iteration 22 completed (18609.514 ms) ====== [2026-01-19T22:08:09.690Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-19T22:08:09.690Z] GC before operation: completed in 212.384 ms, heap usage 722.241 MB -> 70.045 MB. [2026-01-19T22:08:27.733Z] ====== als (apache-spark) [default], iteration 23 completed (19240.992 ms) ====== [2026-01-19T22:08:27.733Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-19T22:08:27.733Z] GC before operation: completed in 618.762 ms, heap usage 677.369 MB -> 70.432 MB. [2026-01-19T22:08:53.461Z] ====== als (apache-spark) [default], iteration 24 completed (23329.743 ms) ====== [2026-01-19T22:08:53.461Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-19T22:08:53.461Z] GC before operation: completed in 220.824 ms, heap usage 890.010 MB -> 70.941 MB. [2026-01-19T22:09:11.418Z] ====== als (apache-spark) [default], iteration 25 completed (18535.542 ms) ====== [2026-01-19T22:09:11.418Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-19T22:09:11.418Z] GC before operation: completed in 643.732 ms, heap usage 1.068 GB -> 71.436 MB. [2026-01-19T22:09:29.362Z] ====== als (apache-spark) [default], iteration 26 completed (15969.349 ms) ====== [2026-01-19T22:09:29.363Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-19T22:09:29.363Z] GC before operation: completed in 555.109 ms, heap usage 697.279 MB -> 71.379 MB. [2026-01-19T22:09:50.877Z] ====== als (apache-spark) [default], iteration 27 completed (21342.745 ms) ====== [2026-01-19T22:09:50.877Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-19T22:09:50.877Z] GC before operation: completed in 299.850 ms, heap usage 902.485 MB -> 71.484 MB. [2026-01-19T22:10:12.404Z] ====== als (apache-spark) [default], iteration 28 completed (20514.871 ms) ====== [2026-01-19T22:10:12.404Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-19T22:10:12.404Z] GC before operation: completed in 187.970 ms, heap usage 497.306 MB -> 71.763 MB. [2026-01-19T22:10:27.513Z] ====== als (apache-spark) [default], iteration 29 completed (16850.892 ms) ====== [2026-01-19T22:10:29.532Z] ----------------------------------- [2026-01-19T22:10:29.532Z] renaissance-als_0_PASSED [2026-01-19T22:10:29.532Z] ----------------------------------- [2026-01-19T22:10:29.532Z] [2026-01-19T22:10:29.532Z] TEST TEARDOWN: [2026-01-19T22:10:29.532Z] Nothing to be done for teardown. [2026-01-19T22:10:29.966Z] renaissance-als_0 Finish Time: Mon Jan 19 17:10:29 2026 Epoch Time (ms): 1768860629552