[2026-04-01T21:08:53.901Z] Running test renaissance-als_0 ... [2026-04-01T21:08:53.901Z] =============================================== [2026-04-01T21:08:53.901Z] renaissance-als_0 Start Time: Wed Apr 1 17:08:53 2026 Epoch Time (ms): 1775077733593 [2026-04-01T21:08:53.901Z] variation: NoOptions [2026-04-01T21:08:53.901Z] JVM_OPTIONS: [2026-04-01T21:08:53.901Z] { \ [2026-04-01T21:08:53.901Z] echo ""; echo "TEST SETUP:"; \ [2026-04-01T21:08:53.901Z] echo "Nothing to be done for setup."; \ [2026-04-01T21:08:53.901Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1775077733882/renaissance-als_0"; \ [2026-04-01T21:08:53.901Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1775077733882/renaissance-als_0"; \ [2026-04-01T21:08:53.901Z] echo ""; echo "TESTING:"; \ [2026-04-01T21:08:53.902Z] "/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_1775077733882/renaissance-als_0"/als.json" als; \ [2026-04-01T21:08:53.902Z] 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_1775077733882/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-01T21:08:53.902Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-01T21:08:53.902Z] echo "Nothing to be done for teardown."; \ [2026-04-01T21:08:53.902Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1775077733882/TestTargetResult"; [2026-04-01T21:08:53.902Z] [2026-04-01T21:08:53.902Z] TEST SETUP: [2026-04-01T21:08:53.902Z] Nothing to be done for setup. [2026-04-01T21:08:53.902Z] [2026-04-01T21:08:53.902Z] TESTING: [2026-04-01T21:08:57.338Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-01T21:08:58.610Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-01T21:08:58.610Z] GC before operation: completed in 13.059 ms, heap usage 63.906 MB -> 35.033 MB. [2026-04-01T21:09:06.327Z] ====== als (apache-spark) [default], iteration 0 completed (7265.082 ms) ====== [2026-04-01T21:09:06.327Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-01T21:09:06.327Z] GC before operation: completed in 34.876 ms, heap usage 546.173 MB -> 62.327 MB. [2026-04-01T21:09:09.529Z] ====== als (apache-spark) [default], iteration 1 completed (2851.872 ms) ====== [2026-04-01T21:09:09.529Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-01T21:09:09.529Z] GC before operation: completed in 31.452 ms, heap usage 161.904 MB -> 62.356 MB. [2026-04-01T21:09:12.085Z] ====== als (apache-spark) [default], iteration 2 completed (2547.509 ms) ====== [2026-04-01T21:09:12.085Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-01T21:09:12.085Z] GC before operation: completed in 30.696 ms, heap usage 372.124 MB -> 63.619 MB. [2026-04-01T21:09:14.797Z] ====== als (apache-spark) [default], iteration 3 completed (2786.996 ms) ====== [2026-04-01T21:09:14.797Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-01T21:09:14.797Z] GC before operation: completed in 33.624 ms, heap usage 406.741 MB -> 64.252 MB. [2026-04-01T21:09:17.319Z] ====== als (apache-spark) [default], iteration 4 completed (2301.615 ms) ====== [2026-04-01T21:09:17.319Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-01T21:09:17.319Z] GC before operation: completed in 33.043 ms, heap usage 625.298 MB -> 65.164 MB. [2026-04-01T21:09:19.809Z] ====== als (apache-spark) [default], iteration 5 completed (2248.606 ms) ====== [2026-04-01T21:09:19.809Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-01T21:09:19.809Z] GC before operation: completed in 33.187 ms, heap usage 569.597 MB -> 65.663 MB. [2026-04-01T21:09:21.637Z] ====== als (apache-spark) [default], iteration 6 completed (2255.334 ms) ====== [2026-04-01T21:09:21.637Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-01T21:09:21.637Z] GC before operation: completed in 34.357 ms, heap usage 630.008 MB -> 66.258 MB. [2026-04-01T21:09:24.212Z] ====== als (apache-spark) [default], iteration 7 completed (2366.082 ms) ====== [2026-04-01T21:09:24.212Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-01T21:09:24.212Z] GC before operation: completed in 35.323 ms, heap usage 197.982 MB -> 66.290 MB. [2026-04-01T21:09:26.706Z] ====== als (apache-spark) [default], iteration 8 completed (2252.149 ms) ====== [2026-04-01T21:09:26.706Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-01T21:09:26.706Z] GC before operation: completed in 35.335 ms, heap usage 407.883 MB -> 66.984 MB. [2026-04-01T21:09:29.241Z] ====== als (apache-spark) [default], iteration 9 completed (2434.350 ms) ====== [2026-04-01T21:09:29.241Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-01T21:09:29.241Z] GC before operation: completed in 34.799 ms, heap usage 274.021 MB -> 67.114 MB. [2026-04-01T21:09:31.771Z] ====== als (apache-spark) [default], iteration 10 completed (2284.203 ms) ====== [2026-04-01T21:09:31.771Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-01T21:09:31.771Z] GC before operation: completed in 36.364 ms, heap usage 466.632 MB -> 67.655 MB. [2026-04-01T21:09:33.758Z] ====== als (apache-spark) [default], iteration 11 completed (2258.700 ms) ====== [2026-04-01T21:09:33.758Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-01T21:09:33.758Z] GC before operation: completed in 34.331 ms, heap usage 255.769 MB -> 67.619 MB. [2026-04-01T21:09:36.212Z] ====== als (apache-spark) [default], iteration 12 completed (2278.247 ms) ====== [2026-04-01T21:09:36.212Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-01T21:09:36.212Z] GC before operation: completed in 35.882 ms, heap usage 618.660 MB -> 68.367 MB. [2026-04-01T21:09:38.671Z] ====== als (apache-spark) [default], iteration 13 completed (2277.470 ms) ====== [2026-04-01T21:09:38.671Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-01T21:09:38.671Z] GC before operation: completed in 48.516 ms, heap usage 583.011 MB -> 68.633 MB. [2026-04-01T21:09:41.169Z] ====== als (apache-spark) [default], iteration 14 completed (2503.357 ms) ====== [2026-04-01T21:09:41.170Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-01T21:09:41.170Z] GC before operation: completed in 39.909 ms, heap usage 244.659 MB -> 68.398 MB. [2026-04-01T21:09:43.709Z] ====== als (apache-spark) [default], iteration 15 completed (2308.372 ms) ====== [2026-04-01T21:09:43.709Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-01T21:09:43.709Z] GC before operation: completed in 40.213 ms, heap usage 518.878 MB -> 69.030 MB. [2026-04-01T21:09:46.429Z] ====== als (apache-spark) [default], iteration 16 completed (2437.866 ms) ====== [2026-04-01T21:09:46.430Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-01T21:09:46.430Z] GC before operation: completed in 39.028 ms, heap usage 240.349 MB -> 68.860 MB. [2026-04-01T21:09:48.731Z] ====== als (apache-spark) [default], iteration 17 completed (2185.958 ms) ====== [2026-04-01T21:09:48.731Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-01T21:09:48.731Z] GC before operation: completed in 37.731 ms, heap usage 191.798 MB -> 68.993 MB. [2026-04-01T21:09:50.596Z] ====== als (apache-spark) [default], iteration 18 completed (2255.595 ms) ====== [2026-04-01T21:09:50.596Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-01T21:09:50.975Z] GC before operation: completed in 37.522 ms, heap usage 400.102 MB -> 69.578 MB. [2026-04-01T21:09:53.241Z] ====== als (apache-spark) [default], iteration 19 completed (2257.595 ms) ====== [2026-04-01T21:09:53.241Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-01T21:09:53.241Z] GC before operation: completed in 36.052 ms, heap usage 406.898 MB -> 69.850 MB. [2026-04-01T21:09:55.952Z] ====== als (apache-spark) [default], iteration 20 completed (2266.696 ms) ====== [2026-04-01T21:09:55.952Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-01T21:09:55.952Z] GC before operation: completed in 36.829 ms, heap usage 455.621 MB -> 70.271 MB. [2026-04-01T21:09:58.031Z] ====== als (apache-spark) [default], iteration 21 completed (2424.447 ms) ====== [2026-04-01T21:09:58.031Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-01T21:09:58.031Z] GC before operation: completed in 37.796 ms, heap usage 291.258 MB -> 70.331 MB. [2026-04-01T21:10:00.874Z] ====== als (apache-spark) [default], iteration 22 completed (2371.606 ms) ====== [2026-04-01T21:10:00.874Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-01T21:10:00.874Z] GC before operation: completed in 43.432 ms, heap usage 492.406 MB -> 70.850 MB. [2026-04-01T21:10:02.857Z] ====== als (apache-spark) [default], iteration 23 completed (2397.921 ms) ====== [2026-04-01T21:10:02.857Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-01T21:10:02.857Z] GC before operation: completed in 41.079 ms, heap usage 347.890 MB -> 71.026 MB. [2026-04-01T21:10:05.549Z] ====== als (apache-spark) [default], iteration 24 completed (2302.841 ms) ====== [2026-04-01T21:10:05.549Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-01T21:10:05.549Z] GC before operation: completed in 38.183 ms, heap usage 364.343 MB -> 71.303 MB. [2026-04-01T21:10:08.158Z] ====== als (apache-spark) [default], iteration 25 completed (2317.813 ms) ====== [2026-04-01T21:10:08.158Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-01T21:10:08.158Z] GC before operation: completed in 41.411 ms, heap usage 393.363 MB -> 71.390 MB. [2026-04-01T21:10:10.212Z] ====== als (apache-spark) [default], iteration 26 completed (2412.575 ms) ====== [2026-04-01T21:10:10.212Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-01T21:10:10.212Z] GC before operation: completed in 37.163 ms, heap usage 142.266 MB -> 71.461 MB. [2026-04-01T21:10:13.082Z] ====== als (apache-spark) [default], iteration 27 completed (2579.772 ms) ====== [2026-04-01T21:10:13.082Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-01T21:10:13.082Z] GC before operation: completed in 42.773 ms, heap usage 678.113 MB -> 72.397 MB. [2026-04-01T21:10:16.444Z] ====== als (apache-spark) [default], iteration 28 completed (2843.673 ms) ====== [2026-04-01T21:10:16.444Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-01T21:10:16.444Z] GC before operation: completed in 59.648 ms, heap usage 391.974 MB -> 72.109 MB. [2026-04-01T21:10:19.856Z] ====== als (apache-spark) [default], iteration 29 completed (3651.540 ms) ====== [2026-04-01T21:10:19.856Z] ----------------------------------- [2026-04-01T21:10:19.856Z] renaissance-als_0_PASSED [2026-04-01T21:10:19.856Z] ----------------------------------- [2026-04-01T21:10:19.856Z] [2026-04-01T21:10:19.856Z] TEST TEARDOWN: [2026-04-01T21:10:19.856Z] Nothing to be done for teardown. [2026-04-01T21:10:20.306Z] renaissance-als_0 Finish Time: Wed Apr 1 17:10:19 2026 Epoch Time (ms): 1775077819840