[2025-12-20T12:48:07.206Z] Running test renaissance-als_0 ... [2025-12-20T12:48:07.206Z] =============================================== [2025-12-20T12:48:07.206Z] renaissance-als_0 Start Time: Sat Dec 20 07:48:06 2025 Epoch Time (ms): 1766234886865 [2025-12-20T12:48:07.206Z] variation: NoOptions [2025-12-20T12:48:07.206Z] JVM_OPTIONS: [2025-12-20T12:48:07.206Z] { \ [2025-12-20T12:48:07.206Z] echo ""; echo "TEST SETUP:"; \ [2025-12-20T12:48:07.206Z] echo "Nothing to be done for setup."; \ [2025-12-20T12:48:07.206Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17662348864771/renaissance-als_0"; \ [2025-12-20T12:48:07.206Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17662348864771/renaissance-als_0"; \ [2025-12-20T12:48:07.206Z] echo ""; echo "TESTING:"; \ [2025-12-20T12:48:07.206Z] "/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_17662348864771/renaissance-als_0"/als.json" als; \ [2025-12-20T12:48:07.206Z] 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_17662348864771/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-20T12:48:07.206Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-20T12:48:07.206Z] echo "Nothing to be done for teardown."; \ [2025-12-20T12:48:07.206Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17662348864771/TestTargetResult"; [2025-12-20T12:48:07.206Z] [2025-12-20T12:48:07.206Z] TEST SETUP: [2025-12-20T12:48:07.206Z] Nothing to be done for setup. [2025-12-20T12:48:07.206Z] [2025-12-20T12:48:07.206Z] TESTING: [2025-12-20T12:48:07.206Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-20T12:48:07.206Z] 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_17662348864771/renaissance-als_0/launcher-074806-12226308849703668866/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-20T12:48:07.206Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-20T12:48:07.206Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-20T12:48:10.286Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-20T12:48:12.682Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-20T12:48:12.682Z] GC before operation: completed in 15.205 ms, heap usage 50.951 MB -> 35.351 MB. [2025-12-20T12:48:20.259Z] ====== als (apache-spark) [default], iteration 0 completed (6970.224 ms) ====== [2025-12-20T12:48:20.259Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-20T12:48:20.259Z] GC before operation: completed in 46.633 ms, heap usage 349.466 MB -> 61.990 MB. [2025-12-20T12:48:22.696Z] ====== als (apache-spark) [default], iteration 1 completed (3000.982 ms) ====== [2025-12-20T12:48:22.696Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-20T12:48:22.696Z] GC before operation: completed in 56.601 ms, heap usage 191.580 MB -> 62.672 MB. [2025-12-20T12:48:25.861Z] ====== als (apache-spark) [default], iteration 2 completed (2843.949 ms) ====== [2025-12-20T12:48:25.861Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-20T12:48:25.861Z] GC before operation: completed in 62.350 ms, heap usage 151.273 MB -> 63.276 MB. [2025-12-20T12:48:28.985Z] ====== als (apache-spark) [default], iteration 3 completed (2765.483 ms) ====== [2025-12-20T12:48:28.985Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-20T12:48:28.985Z] GC before operation: completed in 38.876 ms, heap usage 536.874 MB -> 64.391 MB. [2025-12-20T12:48:31.441Z] ====== als (apache-spark) [default], iteration 4 completed (2762.536 ms) ====== [2025-12-20T12:48:31.441Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-20T12:48:31.441Z] GC before operation: completed in 54.778 ms, heap usage 211.644 MB -> 64.542 MB. [2025-12-20T12:48:34.616Z] ====== als (apache-spark) [default], iteration 5 completed (2668.768 ms) ====== [2025-12-20T12:48:34.616Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-20T12:48:34.616Z] GC before operation: completed in 38.457 ms, heap usage 356.953 MB -> 65.245 MB. [2025-12-20T12:48:37.068Z] ====== als (apache-spark) [default], iteration 6 completed (2724.054 ms) ====== [2025-12-20T12:48:37.068Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-20T12:48:37.068Z] GC before operation: completed in 48.917 ms, heap usage 349.790 MB -> 65.781 MB. [2025-12-20T12:48:40.250Z] ====== als (apache-spark) [default], iteration 7 completed (2686.961 ms) ====== [2025-12-20T12:48:40.250Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-20T12:48:40.250Z] GC before operation: completed in 41.470 ms, heap usage 212.227 MB -> 66.087 MB. [2025-12-20T12:48:42.748Z] ====== als (apache-spark) [default], iteration 8 completed (2785.565 ms) ====== [2025-12-20T12:48:42.748Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-20T12:48:42.748Z] GC before operation: completed in 61.554 ms, heap usage 186.841 MB -> 66.676 MB. [2025-12-20T12:48:45.910Z] ====== als (apache-spark) [default], iteration 9 completed (2634.945 ms) ====== [2025-12-20T12:48:45.910Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-20T12:48:45.910Z] GC before operation: completed in 43.721 ms, heap usage 117.331 MB -> 66.807 MB. [2025-12-20T12:48:48.454Z] ====== als (apache-spark) [default], iteration 10 completed (2727.122 ms) ====== [2025-12-20T12:48:48.454Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-20T12:48:48.454Z] GC before operation: completed in 57.118 ms, heap usage 257.917 MB -> 67.233 MB. [2025-12-20T12:48:50.906Z] ====== als (apache-spark) [default], iteration 11 completed (2425.045 ms) ====== [2025-12-20T12:48:50.906Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-20T12:48:50.906Z] GC before operation: completed in 49.138 ms, heap usage 472.230 MB -> 67.802 MB. [2025-12-20T12:48:54.039Z] ====== als (apache-spark) [default], iteration 12 completed (2583.024 ms) ====== [2025-12-20T12:48:54.039Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-20T12:48:54.039Z] GC before operation: completed in 51.318 ms, heap usage 139.633 MB -> 67.603 MB. [2025-12-20T12:48:56.445Z] ====== als (apache-spark) [default], iteration 13 completed (2511.135 ms) ====== [2025-12-20T12:48:56.445Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-20T12:48:56.445Z] GC before operation: completed in 48.940 ms, heap usage 219.652 MB -> 67.968 MB. [2025-12-20T12:48:58.925Z] ====== als (apache-spark) [default], iteration 14 completed (2583.438 ms) ====== [2025-12-20T12:48:58.925Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-20T12:48:58.925Z] GC before operation: completed in 55.877 ms, heap usage 388.967 MB -> 68.324 MB. [2025-12-20T12:49:02.087Z] ====== als (apache-spark) [default], iteration 15 completed (2620.100 ms) ====== [2025-12-20T12:49:02.087Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-20T12:49:02.087Z] GC before operation: completed in 69.740 ms, heap usage 403.339 MB -> 68.655 MB. [2025-12-20T12:49:04.542Z] ====== als (apache-spark) [default], iteration 16 completed (2781.090 ms) ====== [2025-12-20T12:49:04.542Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-20T12:49:04.542Z] GC before operation: completed in 75.278 ms, heap usage 431.834 MB -> 69.115 MB. [2025-12-20T12:49:07.775Z] ====== als (apache-spark) [default], iteration 17 completed (3206.131 ms) ====== [2025-12-20T12:49:07.775Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-20T12:49:08.132Z] GC before operation: completed in 51.121 ms, heap usage 199.061 MB -> 68.881 MB. [2025-12-20T12:49:11.277Z] ====== als (apache-spark) [default], iteration 18 completed (2815.094 ms) ====== [2025-12-20T12:49:11.277Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-20T12:49:11.277Z] GC before operation: completed in 57.520 ms, heap usage 159.298 MB -> 69.184 MB. [2025-12-20T12:49:13.680Z] ====== als (apache-spark) [default], iteration 19 completed (2738.927 ms) ====== [2025-12-20T12:49:13.680Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-20T12:49:13.680Z] GC before operation: completed in 47.578 ms, heap usage 351.214 MB -> 69.608 MB. [2025-12-20T12:49:16.892Z] ====== als (apache-spark) [default], iteration 20 completed (2925.972 ms) ====== [2025-12-20T12:49:16.892Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-20T12:49:16.892Z] GC before operation: completed in 59.018 ms, heap usage 111.914 MB -> 69.637 MB. [2025-12-20T12:49:20.060Z] ====== als (apache-spark) [default], iteration 21 completed (2868.161 ms) ====== [2025-12-20T12:49:20.060Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-20T12:49:20.060Z] GC before operation: completed in 46.613 ms, heap usage 317.195 MB -> 70.145 MB. [2025-12-20T12:49:23.167Z] ====== als (apache-spark) [default], iteration 22 completed (2772.347 ms) ====== [2025-12-20T12:49:23.167Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-20T12:49:23.167Z] GC before operation: completed in 38.530 ms, heap usage 221.015 MB -> 70.205 MB. [2025-12-20T12:49:25.661Z] ====== als (apache-spark) [default], iteration 23 completed (2750.083 ms) ====== [2025-12-20T12:49:25.661Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-20T12:49:25.661Z] GC before operation: completed in 52.912 ms, heap usage 230.417 MB -> 70.636 MB. [2025-12-20T12:49:28.779Z] ====== als (apache-spark) [default], iteration 24 completed (2534.830 ms) ====== [2025-12-20T12:49:28.779Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-20T12:49:28.779Z] GC before operation: completed in 42.154 ms, heap usage 331.512 MB -> 70.999 MB. [2025-12-20T12:49:31.215Z] ====== als (apache-spark) [default], iteration 25 completed (2545.598 ms) ====== [2025-12-20T12:49:31.215Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-20T12:49:31.215Z] GC before operation: completed in 44.940 ms, heap usage 508.234 MB -> 71.431 MB. [2025-12-20T12:49:34.346Z] ====== als (apache-spark) [default], iteration 26 completed (2823.938 ms) ====== [2025-12-20T12:49:34.346Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-20T12:49:34.346Z] GC before operation: completed in 39.342 ms, heap usage 184.600 MB -> 71.297 MB. [2025-12-20T12:49:36.763Z] ====== als (apache-spark) [default], iteration 27 completed (2681.325 ms) ====== [2025-12-20T12:49:36.763Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-20T12:49:36.763Z] GC before operation: completed in 44.574 ms, heap usage 282.700 MB -> 71.671 MB. [2025-12-20T12:49:39.871Z] ====== als (apache-spark) [default], iteration 28 completed (2746.189 ms) ====== [2025-12-20T12:49:39.871Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-20T12:49:39.871Z] GC before operation: completed in 44.187 ms, heap usage 360.485 MB -> 71.545 MB. [2025-12-20T12:49:42.279Z] ====== als (apache-spark) [default], iteration 29 completed (2496.996 ms) ====== [2025-12-20T12:49:42.279Z] ----------------------------------- [2025-12-20T12:49:42.279Z] renaissance-als_0_PASSED [2025-12-20T12:49:42.279Z] ----------------------------------- [2025-12-20T12:49:42.279Z] [2025-12-20T12:49:42.279Z] TEST TEARDOWN: [2025-12-20T12:49:42.279Z] Nothing to be done for teardown. [2025-12-20T12:49:42.279Z] renaissance-als_0 Finish Time: Sat Dec 20 07:49:42 2025 Epoch Time (ms): 1766234982074