[2025-10-15T20:32:32.539Z] Running test renaissance-als_0 ... [2025-10-15T20:32:32.539Z] =============================================== [2025-10-15T20:32:32.539Z] renaissance-als_0 Start Time: Wed Oct 15 16:32:32 2025 Epoch Time (ms): 1760560352358 [2025-10-15T20:32:32.539Z] variation: NoOptions [2025-10-15T20:32:32.539Z] JVM_OPTIONS: [2025-10-15T20:32:32.539Z] { \ [2025-10-15T20:32:32.539Z] echo ""; echo "TEST SETUP:"; \ [2025-10-15T20:32:32.539Z] echo "Nothing to be done for setup."; \ [2025-10-15T20:32:32.539Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17605603527613/renaissance-als_0"; \ [2025-10-15T20:32:32.539Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17605603527613/renaissance-als_0"; \ [2025-10-15T20:32:32.539Z] echo ""; echo "TESTING:"; \ [2025-10-15T20:32:32.539Z] "/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_17605603527613/renaissance-als_0"/als.json" als; \ [2025-10-15T20:32:32.539Z] 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_17605603527613/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-15T20:32:32.539Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-15T20:32:32.539Z] echo "Nothing to be done for teardown."; \ [2025-10-15T20:32:32.539Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17605603527613/TestTargetResult"; [2025-10-15T20:32:32.539Z] [2025-10-15T20:32:32.539Z] TEST SETUP: [2025-10-15T20:32:32.539Z] Nothing to be done for setup. [2025-10-15T20:32:32.539Z] [2025-10-15T20:32:32.539Z] TESTING: [2025-10-15T20:32:32.901Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-15T20:32:32.901Z] 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_17605603527613/renaissance-als_0/launcher-163232-7625291291676206476/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-15T20:32:32.901Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-15T20:32:32.901Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-15T20:32:36.871Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-15T20:32:38.653Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-15T20:32:38.653Z] GC before operation: completed in 31.733 ms, heap usage 236.000 MB -> 35.137 MB. [2025-10-15T20:32:47.845Z] ====== als (apache-spark) [default], iteration 0 completed (8200.844 ms) ====== [2025-10-15T20:32:47.845Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-15T20:32:47.845Z] GC before operation: completed in 52.251 ms, heap usage 152.967 MB -> 61.607 MB. [2025-10-15T20:32:50.991Z] ====== als (apache-spark) [default], iteration 1 completed (3785.823 ms) ====== [2025-10-15T20:32:50.991Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-15T20:32:51.345Z] GC before operation: completed in 52.081 ms, heap usage 430.256 MB -> 62.864 MB. [2025-10-15T20:32:55.357Z] ====== als (apache-spark) [default], iteration 2 completed (3478.581 ms) ====== [2025-10-15T20:32:55.357Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-15T20:32:55.357Z] GC before operation: completed in 55.114 ms, heap usage 120.373 MB -> 62.671 MB. [2025-10-15T20:32:59.419Z] ====== als (apache-spark) [default], iteration 3 completed (3857.005 ms) ====== [2025-10-15T20:32:59.419Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-15T20:32:59.419Z] GC before operation: completed in 50.308 ms, heap usage 156.587 MB -> 63.673 MB. [2025-10-15T20:33:02.641Z] ====== als (apache-spark) [default], iteration 4 completed (3348.117 ms) ====== [2025-10-15T20:33:02.641Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-15T20:33:02.641Z] GC before operation: completed in 51.105 ms, heap usage 261.663 MB -> 64.350 MB. [2025-10-15T20:33:08.455Z] ====== als (apache-spark) [default], iteration 5 completed (3552.423 ms) ====== [2025-10-15T20:33:08.455Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-15T20:33:08.455Z] GC before operation: completed in 61.427 ms, heap usage 204.593 MB -> 64.855 MB. [2025-10-15T20:33:09.776Z] ====== als (apache-spark) [default], iteration 6 completed (3641.993 ms) ====== [2025-10-15T20:33:09.776Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-15T20:33:09.776Z] GC before operation: completed in 57.359 ms, heap usage 330.813 MB -> 65.527 MB. [2025-10-15T20:33:13.887Z] ====== als (apache-spark) [default], iteration 7 completed (3460.152 ms) ====== [2025-10-15T20:33:13.887Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-15T20:33:13.887Z] GC before operation: completed in 60.313 ms, heap usage 126.467 MB -> 65.807 MB. [2025-10-15T20:33:17.931Z] ====== als (apache-spark) [default], iteration 8 completed (3696.613 ms) ====== [2025-10-15T20:33:17.931Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-15T20:33:17.931Z] GC before operation: completed in 52.418 ms, heap usage 208.986 MB -> 66.297 MB. [2025-10-15T20:33:21.122Z] ====== als (apache-spark) [default], iteration 9 completed (3531.585 ms) ====== [2025-10-15T20:33:21.122Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-15T20:33:21.122Z] GC before operation: completed in 45.473 ms, heap usage 261.213 MB -> 66.779 MB. [2025-10-15T20:33:25.181Z] ====== als (apache-spark) [default], iteration 10 completed (3491.614 ms) ====== [2025-10-15T20:33:25.181Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-15T20:33:25.181Z] GC before operation: completed in 54.474 ms, heap usage 215.300 MB -> 66.948 MB. [2025-10-15T20:33:28.364Z] ====== als (apache-spark) [default], iteration 11 completed (3618.354 ms) ====== [2025-10-15T20:33:28.364Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-15T20:33:28.364Z] GC before operation: completed in 57.449 ms, heap usage 464.580 MB -> 67.612 MB. [2025-10-15T20:33:32.490Z] ====== als (apache-spark) [default], iteration 12 completed (3592.024 ms) ====== [2025-10-15T20:33:32.490Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-15T20:33:32.491Z] GC before operation: completed in 61.978 ms, heap usage 190.303 MB -> 67.433 MB. [2025-10-15T20:33:35.660Z] ====== als (apache-spark) [default], iteration 13 completed (3477.499 ms) ====== [2025-10-15T20:33:35.660Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-15T20:33:35.660Z] GC before operation: completed in 53.700 ms, heap usage 199.507 MB -> 67.662 MB. [2025-10-15T20:33:39.727Z] ====== als (apache-spark) [default], iteration 14 completed (3514.408 ms) ====== [2025-10-15T20:33:39.727Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-15T20:33:39.727Z] GC before operation: completed in 67.187 ms, heap usage 134.225 MB -> 67.993 MB. [2025-10-15T20:33:43.751Z] ====== als (apache-spark) [default], iteration 15 completed (3504.014 ms) ====== [2025-10-15T20:33:43.751Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-15T20:33:43.751Z] GC before operation: completed in 72.798 ms, heap usage 228.264 MB -> 68.358 MB. [2025-10-15T20:33:46.897Z] ====== als (apache-spark) [default], iteration 16 completed (3657.521 ms) ====== [2025-10-15T20:33:46.897Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-15T20:33:46.897Z] GC before operation: completed in 74.503 ms, heap usage 276.194 MB -> 68.642 MB. [2025-10-15T20:33:50.934Z] ====== als (apache-spark) [default], iteration 17 completed (3492.444 ms) ====== [2025-10-15T20:33:50.934Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-15T20:33:50.934Z] GC before operation: completed in 61.316 ms, heap usage 450.186 MB -> 69.226 MB. [2025-10-15T20:33:54.110Z] ====== als (apache-spark) [default], iteration 18 completed (3431.370 ms) ====== [2025-10-15T20:33:54.110Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-15T20:33:54.110Z] GC before operation: completed in 54.285 ms, heap usage 264.522 MB -> 69.157 MB. [2025-10-15T20:33:57.323Z] ====== als (apache-spark) [default], iteration 19 completed (3271.163 ms) ====== [2025-10-15T20:33:57.324Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-15T20:33:57.683Z] GC before operation: completed in 53.795 ms, heap usage 207.754 MB -> 69.180 MB. [2025-10-15T20:34:00.909Z] ====== als (apache-spark) [default], iteration 20 completed (3036.199 ms) ====== [2025-10-15T20:34:00.909Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-15T20:34:00.909Z] GC before operation: completed in 59.345 ms, heap usage 107.677 MB -> 69.471 MB. [2025-10-15T20:34:04.186Z] ====== als (apache-spark) [default], iteration 21 completed (3406.865 ms) ====== [2025-10-15T20:34:04.186Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-15T20:34:04.186Z] GC before operation: completed in 65.091 ms, heap usage 198.036 MB -> 69.729 MB. [2025-10-15T20:34:08.382Z] ====== als (apache-spark) [default], iteration 22 completed (3803.980 ms) ====== [2025-10-15T20:34:08.382Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-15T20:34:08.382Z] GC before operation: completed in 56.236 ms, heap usage 334.268 MB -> 70.230 MB. [2025-10-15T20:34:12.534Z] ====== als (apache-spark) [default], iteration 23 completed (3790.040 ms) ====== [2025-10-15T20:34:12.535Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-15T20:34:12.535Z] GC before operation: completed in 68.355 ms, heap usage 380.610 MB -> 70.566 MB. [2025-10-15T20:34:15.730Z] ====== als (apache-spark) [default], iteration 24 completed (3169.314 ms) ====== [2025-10-15T20:34:15.731Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-15T20:34:15.731Z] GC before operation: completed in 61.987 ms, heap usage 108.505 MB -> 70.867 MB. [2025-10-15T20:34:18.963Z] ====== als (apache-spark) [default], iteration 25 completed (3373.699 ms) ====== [2025-10-15T20:34:18.963Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-15T20:34:18.963Z] GC before operation: completed in 59.104 ms, heap usage 230.726 MB -> 70.939 MB. [2025-10-15T20:34:23.023Z] ====== als (apache-spark) [default], iteration 26 completed (3586.778 ms) ====== [2025-10-15T20:34:23.023Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-15T20:34:23.023Z] GC before operation: completed in 70.676 ms, heap usage 289.974 MB -> 71.235 MB. [2025-10-15T20:34:27.108Z] ====== als (apache-spark) [default], iteration 27 completed (4075.343 ms) ====== [2025-10-15T20:34:27.108Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-15T20:34:27.108Z] GC before operation: completed in 79.642 ms, heap usage 140.982 MB -> 71.372 MB. [2025-10-15T20:34:31.180Z] ====== als (apache-spark) [default], iteration 28 completed (3729.453 ms) ====== [2025-10-15T20:34:31.180Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-15T20:34:31.180Z] GC before operation: completed in 52.114 ms, heap usage 301.754 MB -> 71.363 MB. [2025-10-15T20:34:34.375Z] ====== als (apache-spark) [default], iteration 29 completed (3297.438 ms) ====== [2025-10-15T20:34:34.375Z] ----------------------------------- [2025-10-15T20:34:34.375Z] renaissance-als_0_PASSED [2025-10-15T20:34:34.375Z] ----------------------------------- [2025-10-15T20:34:34.375Z] [2025-10-15T20:34:34.375Z] TEST TEARDOWN: [2025-10-15T20:34:34.375Z] Nothing to be done for teardown. [2025-10-15T20:34:34.375Z] renaissance-als_0 Finish Time: Wed Oct 15 16:34:34 2025 Epoch Time (ms): 1760560474244