[2026-02-27T02:07:45.272Z] Running test renaissance-als_0 ... [2026-02-27T02:07:45.272Z] =============================================== [2026-02-27T02:07:45.272Z] renaissance-als_0 Start Time: Thu Feb 26 21:07:44 2026 Epoch Time (ms): 1772158064912 [2026-02-27T02:07:45.272Z] variation: NoOptions [2026-02-27T02:07:45.272Z] JVM_OPTIONS: [2026-02-27T02:07:45.272Z] { \ [2026-02-27T02:07:45.272Z] echo ""; echo "TEST SETUP:"; \ [2026-02-27T02:07:45.272Z] echo "Nothing to be done for setup."; \ [2026-02-27T02:07:45.272Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17721580634063/renaissance-als_0"; \ [2026-02-27T02:07:45.272Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17721580634063/renaissance-als_0"; \ [2026-02-27T02:07:45.272Z] echo ""; echo "TESTING:"; \ [2026-02-27T02:07:45.272Z] "/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_17721580634063/renaissance-als_0"/als.json" als; \ [2026-02-27T02:07:45.272Z] 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_17721580634063/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-27T02:07:45.272Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-27T02:07:45.272Z] echo "Nothing to be done for teardown."; \ [2026-02-27T02:07:45.272Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17721580634063/TestTargetResult"; [2026-02-27T02:07:45.272Z] [2026-02-27T02:07:45.272Z] TEST SETUP: [2026-02-27T02:07:45.272Z] Nothing to be done for setup. [2026-02-27T02:07:45.272Z] [2026-02-27T02:07:45.272Z] TESTING: [2026-02-27T02:07:46.852Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-27T02:07:46.852Z] 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_17721580634063/renaissance-als_0/launcher-210745-15373584510538036350/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-27T02:07:46.852Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-27T02:07:46.852Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-27T02:08:01.554Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-27T02:08:08.751Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-27T02:08:08.751Z] GC before operation: completed in 191.456 ms, heap usage 282.867 MB -> 34.047 MB. [2026-02-27T02:08:52.765Z] ====== als (apache-spark) [default], iteration 0 completed (41488.364 ms) ====== [2026-02-27T02:08:52.765Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-27T02:08:52.765Z] GC before operation: completed in 182.256 ms, heap usage 249.821 MB -> 60.796 MB. [2026-02-27T02:09:24.317Z] ====== als (apache-spark) [default], iteration 1 completed (29006.379 ms) ====== [2026-02-27T02:09:24.317Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-27T02:09:24.317Z] GC before operation: completed in 820.711 ms, heap usage 654.302 MB -> 62.183 MB. [2026-02-27T02:09:39.637Z] ====== als (apache-spark) [default], iteration 2 completed (17608.866 ms) ====== [2026-02-27T02:09:39.637Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-27T02:09:39.637Z] GC before operation: completed in 240.797 ms, heap usage 137.411 MB -> 62.368 MB. [2026-02-27T02:10:01.156Z] ====== als (apache-spark) [default], iteration 3 completed (19133.486 ms) ====== [2026-02-27T02:10:01.156Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-27T02:10:01.156Z] GC before operation: completed in 253.406 ms, heap usage 631.974 MB -> 63.161 MB. [2026-02-27T02:10:22.747Z] ====== als (apache-spark) [default], iteration 4 completed (22890.898 ms) ====== [2026-02-27T02:10:22.747Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-27T02:10:22.747Z] GC before operation: completed in 323.152 ms, heap usage 426.893 MB -> 63.541 MB. [2026-02-27T02:10:41.119Z] ====== als (apache-spark) [default], iteration 5 completed (17161.025 ms) ====== [2026-02-27T02:10:41.119Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-27T02:10:41.119Z] GC before operation: completed in 187.709 ms, heap usage 674.888 MB -> 64.670 MB. [2026-02-27T02:10:59.089Z] ====== als (apache-spark) [default], iteration 6 completed (18144.604 ms) ====== [2026-02-27T02:10:59.089Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-27T02:10:59.581Z] GC before operation: completed in 794.580 ms, heap usage 512.477 MB -> 64.857 MB. [2026-02-27T02:11:17.689Z] ====== als (apache-spark) [default], iteration 7 completed (17501.949 ms) ====== [2026-02-27T02:11:17.689Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-27T02:11:17.689Z] GC before operation: completed in 227.774 ms, heap usage 989.907 MB -> 66.016 MB. [2026-02-27T02:11:39.525Z] ====== als (apache-spark) [default], iteration 8 completed (20713.238 ms) ====== [2026-02-27T02:11:39.525Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-27T02:11:39.525Z] GC before operation: completed in 406.534 ms, heap usage 573.401 MB -> 66.161 MB. [2026-02-27T02:12:00.565Z] ====== als (apache-spark) [default], iteration 9 completed (18660.716 ms) ====== [2026-02-27T02:12:00.565Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-27T02:12:00.565Z] GC before operation: completed in 287.335 ms, heap usage 1.311 GB -> 67.024 MB. [2026-02-27T02:12:18.211Z] ====== als (apache-spark) [default], iteration 10 completed (16827.774 ms) ====== [2026-02-27T02:12:18.211Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-27T02:12:18.211Z] GC before operation: completed in 201.367 ms, heap usage 590.178 MB -> 66.715 MB. [2026-02-27T02:12:39.989Z] ====== als (apache-spark) [default], iteration 11 completed (20146.525 ms) ====== [2026-02-27T02:12:39.989Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-27T02:12:39.989Z] GC before operation: completed in 720.286 ms, heap usage 756.065 MB -> 66.937 MB. [2026-02-27T02:13:06.321Z] ====== als (apache-spark) [default], iteration 12 completed (26447.832 ms) ====== [2026-02-27T02:13:06.321Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-27T02:13:06.321Z] GC before operation: completed in 292.626 ms, heap usage 452.137 MB -> 66.768 MB. [2026-02-27T02:13:32.684Z] ====== als (apache-spark) [default], iteration 13 completed (24053.683 ms) ====== [2026-02-27T02:13:32.684Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-27T02:13:32.684Z] GC before operation: completed in 663.686 ms, heap usage 880.298 MB -> 67.984 MB. [2026-02-27T02:13:54.859Z] ====== als (apache-spark) [default], iteration 14 completed (22076.365 ms) ====== [2026-02-27T02:13:54.859Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-27T02:13:54.859Z] GC before operation: completed in 223.818 ms, heap usage 228.132 MB -> 67.159 MB. [2026-02-27T02:14:21.241Z] ====== als (apache-spark) [default], iteration 15 completed (27504.888 ms) ====== [2026-02-27T02:14:21.241Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-27T02:14:21.241Z] GC before operation: completed in 835.420 ms, heap usage 655.181 MB -> 68.110 MB. [2026-02-27T02:14:43.664Z] ====== als (apache-spark) [default], iteration 16 completed (21429.921 ms) ====== [2026-02-27T02:14:43.664Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-27T02:14:43.664Z] GC before operation: completed in 203.928 ms, heap usage 826.788 MB -> 68.633 MB. [2026-02-27T02:15:05.741Z] ====== als (apache-spark) [default], iteration 17 completed (20473.035 ms) ====== [2026-02-27T02:15:05.741Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-27T02:15:05.741Z] GC before operation: completed in 270.342 ms, heap usage 1.019 GB -> 69.121 MB. [2026-02-27T02:15:32.222Z] ====== als (apache-spark) [default], iteration 18 completed (23900.139 ms) ====== [2026-02-27T02:15:32.222Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-27T02:15:32.222Z] GC before operation: completed in 279.786 ms, heap usage 282.232 MB -> 68.437 MB. [2026-02-27T02:15:55.100Z] ====== als (apache-spark) [default], iteration 19 completed (24356.903 ms) ====== [2026-02-27T02:15:55.100Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-27T02:15:55.100Z] GC before operation: completed in 256.816 ms, heap usage 715.541 MB -> 69.259 MB. [2026-02-27T02:16:20.988Z] ====== als (apache-spark) [default], iteration 20 completed (22753.561 ms) ====== [2026-02-27T02:16:20.988Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-27T02:16:20.988Z] GC before operation: completed in 790.375 ms, heap usage 850.249 MB -> 69.707 MB. [2026-02-27T02:16:42.494Z] ====== als (apache-spark) [default], iteration 21 completed (23174.677 ms) ====== [2026-02-27T02:16:42.494Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-27T02:16:42.971Z] GC before operation: completed in 315.922 ms, heap usage 379.419 MB -> 69.304 MB. [2026-02-27T02:17:14.230Z] ====== als (apache-spark) [default], iteration 22 completed (26522.659 ms) ====== [2026-02-27T02:17:14.230Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-27T02:17:14.230Z] GC before operation: completed in 280.702 ms, heap usage 365.724 MB -> 69.518 MB. [2026-02-27T02:17:32.324Z] ====== als (apache-spark) [default], iteration 23 completed (21201.236 ms) ====== [2026-02-27T02:17:32.324Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-27T02:17:32.324Z] GC before operation: completed in 224.345 ms, heap usage 222.364 MB -> 69.594 MB. [2026-02-27T02:17:54.224Z] ====== als (apache-spark) [default], iteration 24 completed (19361.137 ms) ====== [2026-02-27T02:17:54.224Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-27T02:17:54.224Z] GC before operation: completed in 203.284 ms, heap usage 525.851 MB -> 70.364 MB. [2026-02-27T02:18:16.654Z] ====== als (apache-spark) [default], iteration 25 completed (24614.704 ms) ====== [2026-02-27T02:18:16.654Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-27T02:18:17.094Z] GC before operation: completed in 273.616 ms, heap usage 899.433 MB -> 71.127 MB. [2026-02-27T02:18:43.274Z] ====== als (apache-spark) [default], iteration 26 completed (22711.748 ms) ====== [2026-02-27T02:18:43.274Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-27T02:18:43.274Z] GC before operation: completed in 234.946 ms, heap usage 639.549 MB -> 70.921 MB. [2026-02-27T02:19:01.828Z] ====== als (apache-spark) [default], iteration 27 completed (21158.801 ms) ====== [2026-02-27T02:19:01.828Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-27T02:19:02.405Z] GC before operation: completed in 342.184 ms, heap usage 1.325 GB -> 72.059 MB. [2026-02-27T02:19:28.025Z] ====== als (apache-spark) [default], iteration 28 completed (25266.957 ms) ====== [2026-02-27T02:19:28.025Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-27T02:19:28.671Z] GC before operation: completed in 757.016 ms, heap usage 909.950 MB -> 71.364 MB. [2026-02-27T02:19:54.678Z] ====== als (apache-spark) [default], iteration 29 completed (23699.204 ms) ====== [2026-02-27T02:19:54.678Z] ----------------------------------- [2026-02-27T02:19:54.678Z] renaissance-als_0_PASSED [2026-02-27T02:19:54.678Z] ----------------------------------- [2026-02-27T02:19:54.678Z] [2026-02-27T02:19:54.678Z] TEST TEARDOWN: [2026-02-27T02:19:54.678Z] Nothing to be done for teardown. [2026-02-27T02:19:55.747Z] renaissance-als_0 Finish Time: Thu Feb 26 21:19:54 2026 Epoch Time (ms): 1772158795074