[2026-01-10T13:24:10.322Z] Running test renaissance-als_0 ... [2026-01-10T13:24:10.322Z] =============================================== [2026-01-10T13:24:10.322Z] renaissance-als_0 Start Time: Sat Jan 10 08:24:09 2026 Epoch Time (ms): 1768051449942 [2026-01-10T13:24:10.322Z] variation: NoOptions [2026-01-10T13:24:10.322Z] JVM_OPTIONS: [2026-01-10T13:24:10.322Z] { \ [2026-01-10T13:24:10.322Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T13:24:10.322Z] echo "Nothing to be done for setup."; \ [2026-01-10T13:24:10.322Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1768051449611/renaissance-als_0"; \ [2026-01-10T13:24:10.322Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1768051449611/renaissance-als_0"; \ [2026-01-10T13:24:10.322Z] echo ""; echo "TESTING:"; \ [2026-01-10T13:24:10.322Z] "/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_1768051449611/renaissance-als_0"/als.json" als; \ [2026-01-10T13:24:10.322Z] 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_1768051449611/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T13:24:10.322Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T13:24:10.322Z] echo "Nothing to be done for teardown."; \ [2026-01-10T13:24:10.322Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1768051449611/TestTargetResult"; [2026-01-10T13:24:10.322Z] [2026-01-10T13:24:10.322Z] TEST SETUP: [2026-01-10T13:24:10.322Z] Nothing to be done for setup. [2026-01-10T13:24:10.322Z] [2026-01-10T13:24:10.322Z] TESTING: [2026-01-10T13:24:10.322Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-10T13:24:10.322Z] 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_1768051449611/renaissance-als_0/launcher-082410-1134720276466667694/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-10T13:24:10.322Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-10T13:24:10.322Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-10T13:24:13.414Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-10T13:24:15.157Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T13:24:15.157Z] GC before operation: completed in 13.236 ms, heap usage 62.500 MB -> 35.229 MB. [2026-01-10T13:24:22.741Z] ====== als (apache-spark) [default], iteration 0 completed (6326.182 ms) ====== [2026-01-10T13:24:22.741Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T13:24:22.741Z] GC before operation: completed in 37.366 ms, heap usage 170.053 MB -> 61.736 MB. [2026-01-10T13:24:25.210Z] ====== als (apache-spark) [default], iteration 1 completed (3133.811 ms) ====== [2026-01-10T13:24:25.210Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T13:24:25.210Z] GC before operation: completed in 39.264 ms, heap usage 301.064 MB -> 62.861 MB. [2026-01-10T13:24:28.356Z] ====== als (apache-spark) [default], iteration 2 completed (3227.831 ms) ====== [2026-01-10T13:24:28.356Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T13:24:28.356Z] GC before operation: completed in 41.394 ms, heap usage 174.725 MB -> 63.276 MB. [2026-01-10T13:24:31.571Z] ====== als (apache-spark) [default], iteration 3 completed (2855.526 ms) ====== [2026-01-10T13:24:31.571Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T13:24:31.571Z] GC before operation: completed in 51.607 ms, heap usage 475.540 MB -> 64.335 MB. [2026-01-10T13:24:34.000Z] ====== als (apache-spark) [default], iteration 4 completed (2740.452 ms) ====== [2026-01-10T13:24:34.000Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T13:24:34.000Z] GC before operation: completed in 33.694 ms, heap usage 475.200 MB -> 64.880 MB. [2026-01-10T13:24:37.138Z] ====== als (apache-spark) [default], iteration 5 completed (2796.781 ms) ====== [2026-01-10T13:24:37.138Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T13:24:37.138Z] GC before operation: completed in 45.321 ms, heap usage 498.043 MB -> 65.450 MB. [2026-01-10T13:24:40.269Z] ====== als (apache-spark) [default], iteration 6 completed (2808.569 ms) ====== [2026-01-10T13:24:40.269Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T13:24:40.269Z] GC before operation: completed in 45.755 ms, heap usage 120.788 MB -> 65.581 MB. [2026-01-10T13:24:42.691Z] ====== als (apache-spark) [default], iteration 7 completed (2731.720 ms) ====== [2026-01-10T13:24:42.691Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T13:24:42.691Z] GC before operation: completed in 43.988 ms, heap usage 197.369 MB -> 66.091 MB. [2026-01-10T13:24:45.893Z] ====== als (apache-spark) [default], iteration 8 completed (2810.030 ms) ====== [2026-01-10T13:24:45.893Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T13:24:45.893Z] GC before operation: completed in 37.042 ms, heap usage 473.220 MB -> 67.099 MB. [2026-01-10T13:24:48.340Z] ====== als (apache-spark) [default], iteration 9 completed (2728.448 ms) ====== [2026-01-10T13:24:48.340Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T13:24:48.340Z] GC before operation: completed in 37.360 ms, heap usage 178.904 MB -> 66.893 MB. [2026-01-10T13:24:51.547Z] ====== als (apache-spark) [default], iteration 10 completed (2802.478 ms) ====== [2026-01-10T13:24:51.547Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T13:24:51.547Z] GC before operation: completed in 43.379 ms, heap usage 263.118 MB -> 67.283 MB. [2026-01-10T13:24:54.731Z] ====== als (apache-spark) [default], iteration 11 completed (2812.709 ms) ====== [2026-01-10T13:24:54.731Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T13:24:54.731Z] GC before operation: completed in 52.741 ms, heap usage 320.631 MB -> 67.665 MB. [2026-01-10T13:24:58.831Z] ====== als (apache-spark) [default], iteration 12 completed (4367.164 ms) ====== [2026-01-10T13:24:58.831Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T13:24:58.831Z] GC before operation: completed in 66.435 ms, heap usage 262.745 MB -> 67.784 MB. [2026-01-10T13:25:03.950Z] ====== als (apache-spark) [default], iteration 13 completed (4222.324 ms) ====== [2026-01-10T13:25:03.950Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T13:25:03.950Z] GC before operation: completed in 61.998 ms, heap usage 197.638 MB -> 67.984 MB. [2026-01-10T13:25:08.073Z] ====== als (apache-spark) [default], iteration 14 completed (4069.166 ms) ====== [2026-01-10T13:25:08.073Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T13:25:08.073Z] GC before operation: completed in 62.605 ms, heap usage 107.542 MB -> 68.256 MB. [2026-01-10T13:25:12.198Z] ====== als (apache-spark) [default], iteration 15 completed (3968.053 ms) ====== [2026-01-10T13:25:12.198Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T13:25:12.198Z] GC before operation: completed in 79.136 ms, heap usage 284.890 MB -> 68.544 MB. [2026-01-10T13:25:16.282Z] ====== als (apache-spark) [default], iteration 16 completed (4030.109 ms) ====== [2026-01-10T13:25:16.282Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T13:25:16.282Z] GC before operation: completed in 65.751 ms, heap usage 355.014 MB -> 68.945 MB. [2026-01-10T13:25:20.335Z] ====== als (apache-spark) [default], iteration 17 completed (3960.301 ms) ====== [2026-01-10T13:25:20.335Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T13:25:20.335Z] GC before operation: completed in 77.552 ms, heap usage 318.052 MB -> 69.146 MB. [2026-01-10T13:25:24.435Z] ====== als (apache-spark) [default], iteration 18 completed (3882.640 ms) ====== [2026-01-10T13:25:24.435Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T13:25:24.435Z] GC before operation: completed in 82.394 ms, heap usage 171.574 MB -> 69.256 MB. [2026-01-10T13:25:28.595Z] ====== als (apache-spark) [default], iteration 19 completed (4043.379 ms) ====== [2026-01-10T13:25:28.595Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T13:25:28.595Z] GC before operation: completed in 78.855 ms, heap usage 450.295 MB -> 69.925 MB. [2026-01-10T13:25:32.642Z] ====== als (apache-spark) [default], iteration 20 completed (4152.135 ms) ====== [2026-01-10T13:25:32.642Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T13:25:32.642Z] GC before operation: completed in 72.273 ms, heap usage 475.898 MB -> 70.241 MB. [2026-01-10T13:25:37.703Z] ====== als (apache-spark) [default], iteration 21 completed (4604.405 ms) ====== [2026-01-10T13:25:37.703Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T13:25:37.703Z] GC before operation: completed in 82.301 ms, heap usage 211.677 MB -> 70.083 MB. [2026-01-10T13:25:41.774Z] ====== als (apache-spark) [default], iteration 22 completed (4138.205 ms) ====== [2026-01-10T13:25:41.774Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T13:25:41.774Z] GC before operation: completed in 69.684 ms, heap usage 522.573 MB -> 70.944 MB. [2026-01-10T13:25:45.799Z] ====== als (apache-spark) [default], iteration 23 completed (4029.774 ms) ====== [2026-01-10T13:25:45.799Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T13:25:45.799Z] GC before operation: completed in 75.861 ms, heap usage 259.638 MB -> 70.752 MB. [2026-01-10T13:25:50.860Z] ====== als (apache-spark) [default], iteration 24 completed (4196.080 ms) ====== [2026-01-10T13:25:50.860Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T13:25:50.860Z] GC before operation: completed in 61.489 ms, heap usage 351.036 MB -> 71.107 MB. [2026-01-10T13:25:54.129Z] ====== als (apache-spark) [default], iteration 25 completed (3767.276 ms) ====== [2026-01-10T13:25:54.130Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T13:25:54.130Z] GC before operation: completed in 57.774 ms, heap usage 186.050 MB -> 71.140 MB. [2026-01-10T13:25:59.163Z] ====== als (apache-spark) [default], iteration 26 completed (4180.545 ms) ====== [2026-01-10T13:25:59.164Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T13:25:59.164Z] GC before operation: completed in 77.459 ms, heap usage 477.462 MB -> 71.791 MB. [2026-01-10T13:26:03.313Z] ====== als (apache-spark) [default], iteration 27 completed (4163.629 ms) ====== [2026-01-10T13:26:03.313Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T13:26:03.313Z] GC before operation: completed in 43.860 ms, heap usage 340.811 MB -> 71.852 MB. [2026-01-10T13:26:07.541Z] ====== als (apache-spark) [default], iteration 28 completed (4202.217 ms) ====== [2026-01-10T13:26:07.541Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T13:26:07.541Z] GC before operation: completed in 52.303 ms, heap usage 173.283 MB -> 71.918 MB. [2026-01-10T13:26:11.631Z] ====== als (apache-spark) [default], iteration 29 completed (3821.218 ms) ====== [2026-01-10T13:26:11.631Z] ----------------------------------- [2026-01-10T13:26:11.631Z] renaissance-als_0_PASSED [2026-01-10T13:26:11.631Z] ----------------------------------- [2026-01-10T13:26:11.631Z] [2026-01-10T13:26:11.631Z] TEST TEARDOWN: [2026-01-10T13:26:11.631Z] Nothing to be done for teardown. [2026-01-10T13:26:11.631Z] renaissance-als_0 Finish Time: Sat Jan 10 08:26:11 2026 Epoch Time (ms): 1768051571192