[2025-12-06T13:45:23.191Z] Running test renaissance-als_0 ... [2025-12-06T13:45:23.191Z] =============================================== [2025-12-06T13:45:23.191Z] renaissance-als_0 Start Time: Sat Dec 6 05:45:22 2025 Epoch Time (ms): 1765028722782 [2025-12-06T13:45:23.191Z] variation: NoOptions [2025-12-06T13:45:23.191Z] JVM_OPTIONS: [2025-12-06T13:45:23.191Z] { \ [2025-12-06T13:45:23.191Z] echo ""; echo "TEST SETUP:"; \ [2025-12-06T13:45:23.191Z] echo "Nothing to be done for setup."; \ [2025-12-06T13:45:23.191Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/renaissance-als_0"; \ [2025-12-06T13:45:23.191Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/renaissance-als_0"; \ [2025-12-06T13:45:23.191Z] echo ""; echo "TESTING:"; \ [2025-12-06T13:45:23.191Z] "/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_17650287207940/renaissance-als_0"/als.json" als; \ [2025-12-06T13:45:23.191Z] 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_17650287207940/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-06T13:45:23.191Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-06T13:45:23.191Z] echo "Nothing to be done for teardown."; \ [2025-12-06T13:45:23.191Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17650287207940/TestTargetResult"; [2025-12-06T13:45:23.191Z] [2025-12-06T13:45:23.191Z] TEST SETUP: [2025-12-06T13:45:23.191Z] Nothing to be done for setup. [2025-12-06T13:45:23.191Z] [2025-12-06T13:45:23.191Z] TESTING: [2025-12-06T13:45:24.060Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-06T13:45:24.060Z] 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_17650287207940/renaissance-als_0/launcher-054523-9944387345785571142/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-06T13:45:24.060Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-06T13:45:24.060Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-06T13:45:35.960Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-06T13:45:41.306Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-06T13:45:41.306Z] GC before operation: completed in 68.884 ms, heap usage 258.965 MB -> 34.119 MB. [2025-12-06T13:46:11.058Z] ====== als (apache-spark) [default], iteration 0 completed (26737.095 ms) ====== [2025-12-06T13:46:11.058Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-06T13:46:11.058Z] GC before operation: completed in 269.366 ms, heap usage 680.761 MB -> 61.187 MB. [2025-12-06T13:46:28.588Z] ====== als (apache-spark) [default], iteration 1 completed (19011.966 ms) ====== [2025-12-06T13:46:28.588Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-06T13:46:28.588Z] GC before operation: completed in 113.661 ms, heap usage 924.883 MB -> 62.814 MB. [2025-12-06T13:46:45.992Z] ====== als (apache-spark) [default], iteration 2 completed (14646.296 ms) ====== [2025-12-06T13:46:45.992Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-06T13:46:45.992Z] GC before operation: completed in 79.897 ms, heap usage 234.446 MB -> 62.402 MB. [2025-12-06T13:46:58.166Z] ====== als (apache-spark) [default], iteration 3 completed (14012.082 ms) ====== [2025-12-06T13:46:58.166Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-06T13:46:58.166Z] GC before operation: completed in 130.291 ms, heap usage 1.388 GB -> 64.648 MB. [2025-12-06T13:47:12.643Z] ====== als (apache-spark) [default], iteration 4 completed (13897.048 ms) ====== [2025-12-06T13:47:12.643Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-06T13:47:12.643Z] GC before operation: completed in 89.398 ms, heap usage 707.631 MB -> 64.465 MB. [2025-12-06T13:47:24.999Z] ====== als (apache-spark) [default], iteration 5 completed (12337.150 ms) ====== [2025-12-06T13:47:24.999Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-06T13:47:24.999Z] GC before operation: completed in 93.433 ms, heap usage 1.222 GB -> 65.465 MB. [2025-12-06T13:47:39.763Z] ====== als (apache-spark) [default], iteration 6 completed (13428.704 ms) ====== [2025-12-06T13:47:39.763Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-06T13:47:39.763Z] GC before operation: completed in 84.570 ms, heap usage 315.829 MB -> 64.811 MB. [2025-12-06T13:47:54.607Z] ====== als (apache-spark) [default], iteration 7 completed (13860.225 ms) ====== [2025-12-06T13:47:54.607Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-06T13:47:54.607Z] GC before operation: completed in 144.025 ms, heap usage 632.178 MB -> 65.850 MB. [2025-12-06T13:48:07.188Z] ====== als (apache-spark) [default], iteration 8 completed (13466.559 ms) ====== [2025-12-06T13:48:07.188Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-06T13:48:07.188Z] GC before operation: completed in 141.659 ms, heap usage 1.512 GB -> 67.334 MB. [2025-12-06T13:48:22.483Z] ====== als (apache-spark) [default], iteration 9 completed (15412.619 ms) ====== [2025-12-06T13:48:22.483Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-06T13:48:22.483Z] GC before operation: completed in 177.185 ms, heap usage 541.354 MB -> 66.535 MB. [2025-12-06T13:48:40.278Z] ====== als (apache-spark) [default], iteration 10 completed (16798.008 ms) ====== [2025-12-06T13:48:40.278Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-06T13:48:40.278Z] GC before operation: completed in 89.336 ms, heap usage 218.593 MB -> 66.408 MB. [2025-12-06T13:48:52.323Z] ====== als (apache-spark) [default], iteration 11 completed (11589.379 ms) ====== [2025-12-06T13:48:52.323Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-06T13:48:52.323Z] GC before operation: completed in 481.727 ms, heap usage 794.394 MB -> 67.420 MB. [2025-12-06T13:49:04.594Z] ====== als (apache-spark) [default], iteration 12 completed (10967.849 ms) ====== [2025-12-06T13:49:04.594Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-06T13:49:04.594Z] GC before operation: completed in 91.840 ms, heap usage 341.017 MB -> 67.058 MB. [2025-12-06T13:49:16.846Z] ====== als (apache-spark) [default], iteration 13 completed (11986.726 ms) ====== [2025-12-06T13:49:16.846Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-06T13:49:16.846Z] GC before operation: completed in 112.455 ms, heap usage 923.954 MB -> 68.121 MB. [2025-12-06T13:49:27.212Z] ====== als (apache-spark) [default], iteration 14 completed (11277.867 ms) ====== [2025-12-06T13:49:27.212Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-06T13:49:27.212Z] GC before operation: completed in 79.955 ms, heap usage 838.054 MB -> 68.241 MB. [2025-12-06T13:49:39.203Z] ====== als (apache-spark) [default], iteration 15 completed (10884.911 ms) ====== [2025-12-06T13:49:39.203Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-06T13:49:39.203Z] GC before operation: completed in 64.743 ms, heap usage 138.201 MB -> 67.374 MB. [2025-12-06T13:49:51.553Z] ====== als (apache-spark) [default], iteration 16 completed (12666.631 ms) ====== [2025-12-06T13:49:51.553Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-06T13:49:51.553Z] GC before operation: completed in 393.507 ms, heap usage 858.320 MB -> 68.671 MB. [2025-12-06T13:50:05.879Z] ====== als (apache-spark) [default], iteration 17 completed (12711.068 ms) ====== [2025-12-06T13:50:05.879Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-06T13:50:05.879Z] GC before operation: completed in 81.574 ms, heap usage 467.116 MB -> 68.587 MB. [2025-12-06T13:50:18.359Z] ====== als (apache-spark) [default], iteration 18 completed (11851.392 ms) ====== [2025-12-06T13:50:18.359Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-06T13:50:18.359Z] GC before operation: completed in 205.993 ms, heap usage 359.714 MB -> 68.635 MB. [2025-12-06T13:50:30.447Z] ====== als (apache-spark) [default], iteration 19 completed (13315.469 ms) ====== [2025-12-06T13:50:30.448Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-06T13:50:30.882Z] GC before operation: completed in 152.850 ms, heap usage 1.224 GB -> 70.114 MB. [2025-12-06T13:50:48.238Z] ====== als (apache-spark) [default], iteration 20 completed (15322.705 ms) ====== [2025-12-06T13:50:48.238Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-06T13:50:48.238Z] GC before operation: completed in 135.213 ms, heap usage 663.416 MB -> 69.687 MB. [2025-12-06T13:51:03.362Z] ====== als (apache-spark) [default], iteration 21 completed (15814.071 ms) ====== [2025-12-06T13:51:03.362Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-06T13:51:03.362Z] GC before operation: completed in 110.261 ms, heap usage 329.160 MB -> 69.422 MB. [2025-12-06T13:51:25.342Z] ====== als (apache-spark) [default], iteration 22 completed (22247.200 ms) ====== [2025-12-06T13:51:25.342Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-06T13:51:25.342Z] GC before operation: completed in 203.862 ms, heap usage 1.254 GB -> 70.987 MB. [2025-12-06T13:51:47.220Z] ====== als (apache-spark) [default], iteration 23 completed (22088.037 ms) ====== [2025-12-06T13:51:47.220Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-06T13:51:47.613Z] GC before operation: completed in 190.308 ms, heap usage 850.862 MB -> 70.645 MB. [2025-12-06T13:52:09.510Z] ====== als (apache-spark) [default], iteration 24 completed (20506.825 ms) ====== [2025-12-06T13:52:09.510Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-06T13:52:09.510Z] GC before operation: completed in 184.242 ms, heap usage 892.194 MB -> 71.071 MB. [2025-12-06T13:52:31.504Z] ====== als (apache-spark) [default], iteration 25 completed (20665.535 ms) ====== [2025-12-06T13:52:31.505Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-06T13:52:31.505Z] GC before operation: completed in 472.621 ms, heap usage 970.343 MB -> 71.384 MB. [2025-12-06T13:52:49.789Z] ====== als (apache-spark) [default], iteration 26 completed (17771.134 ms) ====== [2025-12-06T13:52:49.789Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-06T13:52:49.789Z] GC before operation: completed in 164.817 ms, heap usage 1.013 GB -> 71.624 MB. [2025-12-06T13:53:11.859Z] ====== als (apache-spark) [default], iteration 27 completed (21051.731 ms) ====== [2025-12-06T13:53:11.859Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-06T13:53:11.859Z] GC before operation: completed in 216.049 ms, heap usage 358.995 MB -> 70.665 MB. [2025-12-06T13:53:37.601Z] ====== als (apache-spark) [default], iteration 28 completed (23695.161 ms) ====== [2025-12-06T13:53:37.601Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-06T13:53:37.601Z] GC before operation: completed in 124.707 ms, heap usage 1.443 GB -> 72.157 MB. [2025-12-06T13:53:52.520Z] ====== als (apache-spark) [default], iteration 29 completed (15662.347 ms) ====== [2025-12-06T13:53:52.520Z] ----------------------------------- [2025-12-06T13:53:52.520Z] renaissance-als_0_PASSED [2025-12-06T13:53:52.520Z] ----------------------------------- [2025-12-06T13:53:52.520Z] [2025-12-06T13:53:52.520Z] TEST TEARDOWN: [2025-12-06T13:53:52.520Z] Nothing to be done for teardown. [2025-12-06T13:53:52.520Z] renaissance-als_0 Finish Time: Sat Dec 6 05:53:50 2025 Epoch Time (ms): 1765029230557