[2026-01-03T12:43:15.640Z] Running test renaissance-als_0 ... [2026-01-03T12:43:15.640Z] =============================================== [2026-01-03T12:43:15.640Z] renaissance-als_0 Start Time: Sat Jan 3 07:43:15 2026 Epoch Time (ms): 1767444195289 [2026-01-03T12:43:15.640Z] variation: NoOptions [2026-01-03T12:43:15.640Z] JVM_OPTIONS: [2026-01-03T12:43:15.640Z] { \ [2026-01-03T12:43:15.640Z] echo ""; echo "TEST SETUP:"; \ [2026-01-03T12:43:15.640Z] echo "Nothing to be done for setup."; \ [2026-01-03T12:43:15.640Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_176744419518/renaissance-als_0"; \ [2026-01-03T12:43:15.640Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_176744419518/renaissance-als_0"; \ [2026-01-03T12:43:15.640Z] echo ""; echo "TESTING:"; \ [2026-01-03T12:43:15.640Z] "/Users/admin/workspace/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_176744419518/renaissance-als_0"/als.json" als; \ [2026-01-03T12:43:15.640Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_176744419518/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-03T12:43:15.640Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-03T12:43:15.640Z] echo "Nothing to be done for teardown."; \ [2026-01-03T12:43:15.640Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_176744419518/TestTargetResult"; [2026-01-03T12:43:15.640Z] [2026-01-03T12:43:15.640Z] TEST SETUP: [2026-01-03T12:43:15.640Z] Nothing to be done for setup. [2026-01-03T12:43:15.640Z] [2026-01-03T12:43:15.640Z] TESTING: [2026-01-03T12:43:15.640Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-03T12:43:15.640Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/output_176744419518/renaissance-als_0/launcher-074315-13863659482061035776/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-03T12:43:15.640Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-03T12:43:15.640Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-03T12:43:18.764Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-03T12:43:20.520Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-03T12:43:20.520Z] GC before operation: completed in 12.686 ms, heap usage 61.516 MB -> 35.343 MB. [2026-01-03T12:43:26.784Z] ====== als (apache-spark) [default], iteration 0 completed (6192.143 ms) ====== [2026-01-03T12:43:26.784Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-03T12:43:26.784Z] GC before operation: completed in 32.555 ms, heap usage 179.743 MB -> 61.872 MB. [2026-01-03T12:43:29.953Z] ====== als (apache-spark) [default], iteration 1 completed (2692.279 ms) ====== [2026-01-03T12:43:29.953Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-03T12:43:29.953Z] GC before operation: completed in 29.737 ms, heap usage 187.418 MB -> 62.763 MB. [2026-01-03T12:43:32.358Z] ====== als (apache-spark) [default], iteration 2 completed (2500.121 ms) ====== [2026-01-03T12:43:32.358Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-03T12:43:32.358Z] GC before operation: completed in 29.722 ms, heap usage 319.818 MB -> 63.661 MB. [2026-01-03T12:43:34.755Z] ====== als (apache-spark) [default], iteration 3 completed (2453.804 ms) ====== [2026-01-03T12:43:34.755Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-03T12:43:34.755Z] GC before operation: completed in 30.916 ms, heap usage 485.770 MB -> 64.466 MB. [2026-01-03T12:43:37.173Z] ====== als (apache-spark) [default], iteration 4 completed (2376.292 ms) ====== [2026-01-03T12:43:37.173Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-03T12:43:37.173Z] GC before operation: completed in 32.236 ms, heap usage 349.138 MB -> 64.759 MB. [2026-01-03T12:43:39.612Z] ====== als (apache-spark) [default], iteration 5 completed (2463.671 ms) ====== [2026-01-03T12:43:39.612Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-03T12:43:39.612Z] GC before operation: completed in 42.299 ms, heap usage 495.724 MB -> 65.565 MB. [2026-01-03T12:43:42.780Z] ====== als (apache-spark) [default], iteration 6 completed (2411.991 ms) ====== [2026-01-03T12:43:42.780Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-03T12:43:42.780Z] GC before operation: completed in 32.001 ms, heap usage 179.930 MB -> 65.695 MB. [2026-01-03T12:43:45.168Z] ====== als (apache-spark) [default], iteration 7 completed (2700.644 ms) ====== [2026-01-03T12:43:45.168Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-03T12:43:45.168Z] GC before operation: completed in 49.741 ms, heap usage 124.040 MB -> 66.430 MB. [2026-01-03T12:43:48.303Z] ====== als (apache-spark) [default], iteration 8 completed (2692.113 ms) ====== [2026-01-03T12:43:48.303Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-03T12:43:48.303Z] GC before operation: completed in 53.920 ms, heap usage 482.459 MB -> 67.128 MB. [2026-01-03T12:43:50.726Z] ====== als (apache-spark) [default], iteration 9 completed (2826.159 ms) ====== [2026-01-03T12:43:50.726Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-03T12:43:51.081Z] GC before operation: completed in 66.346 ms, heap usage 492.965 MB -> 67.470 MB. [2026-01-03T12:43:56.343Z] ====== als (apache-spark) [default], iteration 10 completed (4992.974 ms) ====== [2026-01-03T12:43:56.343Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-03T12:43:56.343Z] GC before operation: completed in 71.001 ms, heap usage 324.565 MB -> 67.424 MB. [2026-01-03T12:43:59.578Z] ====== als (apache-spark) [default], iteration 11 completed (3273.694 ms) ====== [2026-01-03T12:43:59.578Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-03T12:43:59.578Z] GC before operation: completed in 88.879 ms, heap usage 524.556 MB -> 68.078 MB. [2026-01-03T12:44:03.699Z] ====== als (apache-spark) [default], iteration 12 completed (3462.895 ms) ====== [2026-01-03T12:44:03.699Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-03T12:44:03.699Z] GC before operation: completed in 57.675 ms, heap usage 447.957 MB -> 68.248 MB. [2026-01-03T12:44:06.136Z] ====== als (apache-spark) [default], iteration 13 completed (3023.446 ms) ====== [2026-01-03T12:44:06.136Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-03T12:44:06.503Z] GC before operation: completed in 51.533 ms, heap usage 255.643 MB -> 68.160 MB. [2026-01-03T12:44:09.688Z] ====== als (apache-spark) [default], iteration 14 completed (2983.186 ms) ====== [2026-01-03T12:44:09.688Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-03T12:44:09.688Z] GC before operation: completed in 65.826 ms, heap usage 232.699 MB -> 68.505 MB. [2026-01-03T12:44:12.888Z] ====== als (apache-spark) [default], iteration 15 completed (3021.721 ms) ====== [2026-01-03T12:44:12.888Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-03T12:44:12.888Z] GC before operation: completed in 54.341 ms, heap usage 527.399 MB -> 69.136 MB. [2026-01-03T12:44:16.047Z] ====== als (apache-spark) [default], iteration 16 completed (2910.616 ms) ====== [2026-01-03T12:44:16.047Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-03T12:44:16.047Z] GC before operation: completed in 40.427 ms, heap usage 223.779 MB -> 68.966 MB. [2026-01-03T12:44:18.466Z] ====== als (apache-spark) [default], iteration 17 completed (2604.531 ms) ====== [2026-01-03T12:44:18.466Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-03T12:44:18.466Z] GC before operation: completed in 40.623 ms, heap usage 307.670 MB -> 69.423 MB. [2026-01-03T12:44:21.605Z] ====== als (apache-spark) [default], iteration 18 completed (2647.204 ms) ====== [2026-01-03T12:44:21.605Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-03T12:44:21.605Z] GC before operation: completed in 45.328 ms, heap usage 522.172 MB -> 70.021 MB. [2026-01-03T12:44:24.098Z] ====== als (apache-spark) [default], iteration 19 completed (2765.588 ms) ====== [2026-01-03T12:44:24.098Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-03T12:44:24.098Z] GC before operation: completed in 41.971 ms, heap usage 319.895 MB -> 69.827 MB. [2026-01-03T12:44:27.230Z] ====== als (apache-spark) [default], iteration 20 completed (2934.060 ms) ====== [2026-01-03T12:44:27.230Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-03T12:44:27.230Z] GC before operation: completed in 40.124 ms, heap usage 269.073 MB -> 69.933 MB. [2026-01-03T12:44:29.650Z] ====== als (apache-spark) [default], iteration 21 completed (2535.375 ms) ====== [2026-01-03T12:44:29.650Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-03T12:44:29.650Z] GC before operation: completed in 43.690 ms, heap usage 160.464 MB -> 70.109 MB. [2026-01-03T12:44:32.873Z] ====== als (apache-spark) [default], iteration 22 completed (2755.443 ms) ====== [2026-01-03T12:44:32.873Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-03T12:44:32.873Z] GC before operation: completed in 45.678 ms, heap usage 489.111 MB -> 70.874 MB. [2026-01-03T12:44:36.054Z] ====== als (apache-spark) [default], iteration 23 completed (3150.858 ms) ====== [2026-01-03T12:44:36.054Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-03T12:44:36.054Z] GC before operation: completed in 52.327 ms, heap usage 387.374 MB -> 70.802 MB. [2026-01-03T12:44:39.218Z] ====== als (apache-spark) [default], iteration 24 completed (2990.431 ms) ====== [2026-01-03T12:44:39.218Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-03T12:44:39.218Z] GC before operation: completed in 39.970 ms, heap usage 436.125 MB -> 71.353 MB. [2026-01-03T12:44:42.450Z] ====== als (apache-spark) [default], iteration 25 completed (3455.399 ms) ====== [2026-01-03T12:44:42.451Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-03T12:44:42.451Z] GC before operation: completed in 86.139 ms, heap usage 322.456 MB -> 71.308 MB. [2026-01-03T12:44:47.546Z] ====== als (apache-spark) [default], iteration 26 completed (4668.198 ms) ====== [2026-01-03T12:44:47.546Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-03T12:44:47.546Z] GC before operation: completed in 73.763 ms, heap usage 480.742 MB -> 71.810 MB. [2026-01-03T12:44:52.628Z] ====== als (apache-spark) [default], iteration 27 completed (4399.505 ms) ====== [2026-01-03T12:44:52.628Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-03T12:44:52.628Z] GC before operation: completed in 48.139 ms, heap usage 227.031 MB -> 71.730 MB. [2026-01-03T12:44:56.782Z] ====== als (apache-spark) [default], iteration 28 completed (4127.328 ms) ====== [2026-01-03T12:44:56.782Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-03T12:44:56.782Z] GC before operation: completed in 70.677 ms, heap usage 340.351 MB -> 71.673 MB. [2026-01-03T12:45:00.899Z] ====== als (apache-spark) [default], iteration 29 completed (4092.692 ms) ====== [2026-01-03T12:45:00.899Z] ----------------------------------- [2026-01-03T12:45:00.899Z] renaissance-als_0_PASSED [2026-01-03T12:45:00.899Z] ----------------------------------- [2026-01-03T12:45:00.899Z] [2026-01-03T12:45:00.899Z] TEST TEARDOWN: [2026-01-03T12:45:00.899Z] Nothing to be done for teardown. [2026-01-03T12:45:00.899Z] renaissance-als_0 Finish Time: Sat Jan 3 07:45:00 2026 Epoch Time (ms): 1767444300840