[2026-01-31T10:19:59.464Z] Running test renaissance-als_0 ... [2026-01-31T10:19:59.464Z] =============================================== [2026-01-31T10:19:59.464Z] renaissance-als_0 Start Time: Sat Jan 31 05:19:59 2026 Epoch Time (ms): 1769854799337 [2026-01-31T10:19:59.919Z] variation: NoOptions [2026-01-31T10:19:59.919Z] JVM_OPTIONS: [2026-01-31T10:19:59.919Z] { \ [2026-01-31T10:19:59.919Z] echo ""; echo "TEST SETUP:"; \ [2026-01-31T10:19:59.919Z] echo "Nothing to be done for setup."; \ [2026-01-31T10:19:59.919Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17698547936278/renaissance-als_0"; \ [2026-01-31T10:19:59.919Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17698547936278/renaissance-als_0"; \ [2026-01-31T10:19:59.919Z] echo ""; echo "TESTING:"; \ [2026-01-31T10:19:59.919Z] "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_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_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17698547936278/renaissance-als_0"/als.json" als; \ [2026-01-31T10:19:59.919Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17698547936278/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-31T10:19:59.919Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-31T10:19:59.919Z] echo "Nothing to be done for teardown."; \ [2026-01-31T10:19:59.919Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17698547936278/TestTargetResult"; [2026-01-31T10:19:59.919Z] [2026-01-31T10:19:59.919Z] TEST SETUP: [2026-01-31T10:19:59.919Z] Nothing to be done for setup. [2026-01-31T10:19:59.919Z] [2026-01-31T10:19:59.919Z] TESTING: [2026-01-31T10:20:02.570Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-31T10:20:02.570Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/output_17698547936278/renaissance-als_0/launcher-052000-1110041269819810580/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-31T10:20:02.570Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-31T10:20:02.570Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-31T10:20:34.505Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-31T10:20:57.373Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-31T10:20:57.373Z] GC before operation: completed in 257.653 ms, heap usage 328.710 MB -> 34.715 MB. [2026-01-31T10:22:42.952Z] ====== als (apache-spark) [default], iteration 0 completed (90859.588 ms) ====== [2026-01-31T10:22:42.952Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-31T10:22:42.952Z] GC before operation: completed in 369.579 ms, heap usage 381.011 MB -> 61.152 MB. [2026-01-31T10:23:21.191Z] ====== als (apache-spark) [default], iteration 1 completed (47710.091 ms) ====== [2026-01-31T10:23:21.191Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-31T10:23:21.191Z] GC before operation: completed in 561.483 ms, heap usage 174.851 MB -> 62.789 MB. [2026-01-31T10:24:06.819Z] ====== als (apache-spark) [default], iteration 2 completed (44121.405 ms) ====== [2026-01-31T10:24:06.819Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-31T10:24:06.819Z] GC before operation: completed in 390.949 ms, heap usage 172.516 MB -> 61.949 MB. [2026-01-31T10:24:51.993Z] ====== als (apache-spark) [default], iteration 3 completed (40982.241 ms) ====== [2026-01-31T10:24:51.993Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-31T10:24:51.993Z] GC before operation: completed in 251.828 ms, heap usage 501.434 MB -> 63.316 MB. [2026-01-31T10:25:31.207Z] ====== als (apache-spark) [default], iteration 4 completed (39802.884 ms) ====== [2026-01-31T10:25:31.207Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-31T10:25:31.207Z] GC before operation: completed in 663.394 ms, heap usage 214.816 MB -> 63.359 MB. [2026-01-31T10:26:26.502Z] ====== als (apache-spark) [default], iteration 5 completed (53916.006 ms) ====== [2026-01-31T10:26:26.502Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-31T10:26:26.502Z] GC before operation: completed in 414.868 ms, heap usage 328.132 MB -> 63.658 MB. [2026-01-31T10:27:29.884Z] ====== als (apache-spark) [default], iteration 6 completed (54267.045 ms) ====== [2026-01-31T10:27:29.884Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-31T10:27:29.884Z] GC before operation: completed in 588.839 ms, heap usage 145.406 MB -> 64.519 MB. [2026-01-31T10:28:18.284Z] ====== als (apache-spark) [default], iteration 7 completed (51888.689 ms) ====== [2026-01-31T10:28:18.284Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-31T10:28:18.284Z] GC before operation: completed in 1389.911 ms, heap usage 288.990 MB -> 65.282 MB. [2026-01-31T10:29:23.357Z] ====== als (apache-spark) [default], iteration 8 completed (57153.112 ms) ====== [2026-01-31T10:29:23.357Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-31T10:29:23.357Z] GC before operation: completed in 823.297 ms, heap usage 906.325 MB -> 66.659 MB. [2026-01-31T10:30:08.785Z] ====== als (apache-spark) [default], iteration 9 completed (47023.601 ms) ====== [2026-01-31T10:30:08.785Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-31T10:30:08.785Z] GC before operation: completed in 931.866 ms, heap usage 291.533 MB -> 66.045 MB. [2026-01-31T10:30:41.267Z] ====== als (apache-spark) [default], iteration 10 completed (33924.614 ms) ====== [2026-01-31T10:30:41.268Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-31T10:30:41.268Z] GC before operation: completed in 341.553 ms, heap usage 135.969 MB -> 66.300 MB. [2026-01-31T10:31:25.933Z] ====== als (apache-spark) [default], iteration 11 completed (38608.592 ms) ====== [2026-01-31T10:31:25.933Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-31T10:31:25.933Z] GC before operation: completed in 331.408 ms, heap usage 271.315 MB -> 66.423 MB. [2026-01-31T10:32:04.705Z] ====== als (apache-spark) [default], iteration 12 completed (41816.529 ms) ====== [2026-01-31T10:32:04.705Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-31T10:32:04.705Z] GC before operation: completed in 1298.621 ms, heap usage 604.321 MB -> 67.232 MB. [2026-01-31T10:32:51.257Z] ====== als (apache-spark) [default], iteration 13 completed (46760.153 ms) ====== [2026-01-31T10:32:51.257Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-31T10:32:53.003Z] GC before operation: completed in 1066.378 ms, heap usage 850.298 MB -> 68.283 MB. [2026-01-31T10:33:39.144Z] ====== als (apache-spark) [default], iteration 14 completed (44685.577 ms) ====== [2026-01-31T10:33:39.144Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-31T10:33:39.144Z] GC before operation: completed in 1485.532 ms, heap usage 328.164 MB -> 67.463 MB. [2026-01-31T10:34:33.375Z] ====== als (apache-spark) [default], iteration 15 completed (49898.667 ms) ====== [2026-01-31T10:34:33.375Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-31T10:34:33.375Z] GC before operation: completed in 755.759 ms, heap usage 358.864 MB -> 68.067 MB. [2026-01-31T10:35:20.664Z] ====== als (apache-spark) [default], iteration 16 completed (44036.694 ms) ====== [2026-01-31T10:35:20.664Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-31T10:35:20.664Z] GC before operation: completed in 759.344 ms, heap usage 586.785 MB -> 68.532 MB. [2026-01-31T10:36:15.205Z] ====== als (apache-spark) [default], iteration 17 completed (55175.948 ms) ====== [2026-01-31T10:36:15.205Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-31T10:36:15.205Z] GC before operation: completed in 1419.536 ms, heap usage 442.835 MB -> 68.650 MB. [2026-01-31T10:37:10.234Z] ====== als (apache-spark) [default], iteration 18 completed (49305.718 ms) ====== [2026-01-31T10:37:10.234Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-31T10:37:10.234Z] GC before operation: completed in 1373.575 ms, heap usage 656.074 MB -> 69.277 MB. [2026-01-31T10:37:48.537Z] ====== als (apache-spark) [default], iteration 19 completed (42164.053 ms) ====== [2026-01-31T10:37:48.537Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-31T10:37:49.284Z] GC before operation: completed in 414.591 ms, heap usage 433.706 MB -> 69.207 MB. [2026-01-31T10:38:27.473Z] ====== als (apache-spark) [default], iteration 20 completed (36044.920 ms) ====== [2026-01-31T10:38:27.473Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-31T10:38:27.473Z] GC before operation: completed in 469.199 ms, heap usage 575.301 MB -> 69.468 MB. [2026-01-31T10:39:12.887Z] ====== als (apache-spark) [default], iteration 21 completed (41196.508 ms) ====== [2026-01-31T10:39:12.887Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-31T10:39:12.887Z] GC before operation: completed in 501.788 ms, heap usage 900.877 MB -> 70.276 MB. [2026-01-31T10:39:45.102Z] ====== als (apache-spark) [default], iteration 22 completed (32928.455 ms) ====== [2026-01-31T10:39:45.102Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-31T10:39:45.102Z] GC before operation: completed in 339.821 ms, heap usage 742.352 MB -> 70.207 MB. [2026-01-31T10:40:25.610Z] ====== als (apache-spark) [default], iteration 23 completed (39441.576 ms) ====== [2026-01-31T10:40:25.610Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-31T10:40:25.610Z] GC before operation: completed in 338.162 ms, heap usage 655.464 MB -> 70.581 MB. [2026-01-31T10:40:57.050Z] ====== als (apache-spark) [default], iteration 24 completed (33745.347 ms) ====== [2026-01-31T10:40:57.050Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-31T10:40:57.555Z] GC before operation: completed in 433.549 ms, heap usage 380.843 MB -> 70.261 MB. [2026-01-31T10:41:35.967Z] ====== als (apache-spark) [default], iteration 25 completed (37626.300 ms) ====== [2026-01-31T10:41:35.967Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-31T10:41:36.539Z] GC before operation: completed in 574.500 ms, heap usage 772.764 MB -> 71.362 MB. [2026-01-31T10:42:14.811Z] ====== als (apache-spark) [default], iteration 26 completed (32094.626 ms) ====== [2026-01-31T10:42:14.811Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-31T10:42:14.811Z] GC before operation: completed in 596.814 ms, heap usage 703.947 MB -> 71.258 MB. [2026-01-31T10:42:53.180Z] ====== als (apache-spark) [default], iteration 27 completed (39572.870 ms) ====== [2026-01-31T10:42:53.180Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-31T10:42:53.180Z] GC before operation: completed in 412.536 ms, heap usage 620.177 MB -> 71.694 MB. [2026-01-31T10:43:30.883Z] ====== als (apache-spark) [default], iteration 28 completed (36041.820 ms) ====== [2026-01-31T10:43:30.883Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-31T10:43:30.883Z] GC before operation: completed in 1240.817 ms, heap usage 152.757 MB -> 72.123 MB. [2026-01-31T10:44:15.927Z] ====== als (apache-spark) [default], iteration 29 completed (42296.787 ms) ====== [2026-01-31T10:44:15.927Z] ----------------------------------- [2026-01-31T10:44:15.927Z] renaissance-als_0_PASSED [2026-01-31T10:44:15.927Z] ----------------------------------- [2026-01-31T10:44:15.927Z] [2026-01-31T10:44:15.927Z] TEST TEARDOWN: [2026-01-31T10:44:15.927Z] Nothing to be done for teardown. [2026-01-31T10:44:16.368Z] renaissance-als_0 Finish Time: Sat Jan 31 05:44:15 2026 Epoch Time (ms): 1769856255981