[2025-05-29T21:24:08.093Z] Running test renaissance-als_0 ... [2025-05-29T21:24:08.093Z] =============================================== [2025-05-29T21:24:08.449Z] renaissance-als_0 Start Time: Thu May 29 17:24:08 2025 Epoch Time (ms): 1748553848071 [2025-05-29T21:24:08.449Z] variation: NoOptions [2025-05-29T21:24:08.449Z] JVM_OPTIONS: [2025-05-29T21:24:08.449Z] { \ [2025-05-29T21:24:08.449Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T21:24:08.449Z] echo "Nothing to be done for setup."; \ [2025-05-29T21:24:08.449Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17485538472630/renaissance-als_0"; \ [2025-05-29T21:24:08.449Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17485538472630/renaissance-als_0"; \ [2025-05-29T21:24:08.449Z] echo ""; echo "TESTING:"; \ [2025-05-29T21:24:08.449Z] "/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_17485538472630/renaissance-als_0"/als.json" als; \ [2025-05-29T21:24:08.449Z] 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_17485538472630/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T21:24:08.449Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T21:24:08.449Z] echo "Nothing to be done for teardown."; \ [2025-05-29T21:24:08.449Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17485538472630/TestTargetResult"; [2025-05-29T21:24:08.449Z] [2025-05-29T21:24:08.449Z] TEST SETUP: [2025-05-29T21:24:08.449Z] Nothing to be done for setup. [2025-05-29T21:24:08.449Z] [2025-05-29T21:24:08.449Z] TESTING: [2025-05-29T21:24:08.449Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-05-29T21:24:08.449Z] 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_17485538472630/renaissance-als_0/launcher-172408-17241392921639456329/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-05-29T21:24:08.449Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-05-29T21:24:08.449Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-05-29T21:24:13.419Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-29T21:24:15.186Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-29T21:24:15.186Z] GC before operation: completed in 18.175 ms, heap usage 50.000 MB -> 35.214 MB. [2025-05-29T21:24:24.417Z] ====== als (apache-spark) [default], iteration 0 completed (9063.749 ms) ====== [2025-05-29T21:24:24.417Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-29T21:24:24.417Z] GC before operation: completed in 37.981 ms, heap usage 273.730 MB -> 61.775 MB. [2025-05-29T21:24:29.430Z] ====== als (apache-spark) [default], iteration 1 completed (3990.035 ms) ====== [2025-05-29T21:24:29.430Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-29T21:24:29.430Z] GC before operation: completed in 34.520 ms, heap usage 255.704 MB -> 62.639 MB. [2025-05-29T21:24:32.618Z] ====== als (apache-spark) [default], iteration 2 completed (3510.504 ms) ====== [2025-05-29T21:24:32.618Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-29T21:24:32.618Z] GC before operation: completed in 36.734 ms, heap usage 131.179 MB -> 63.029 MB. [2025-05-29T21:24:35.798Z] ====== als (apache-spark) [default], iteration 3 completed (3546.315 ms) ====== [2025-05-29T21:24:35.798Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-29T21:24:35.798Z] GC before operation: completed in 36.868 ms, heap usage 460.240 MB -> 64.130 MB. [2025-05-29T21:24:40.019Z] ====== als (apache-spark) [default], iteration 4 completed (3546.982 ms) ====== [2025-05-29T21:24:40.019Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-29T21:24:40.019Z] GC before operation: completed in 47.267 ms, heap usage 123.288 MB -> 64.266 MB. [2025-05-29T21:24:43.195Z] ====== als (apache-spark) [default], iteration 5 completed (3445.349 ms) ====== [2025-05-29T21:24:43.195Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-29T21:24:43.195Z] GC before operation: completed in 45.523 ms, heap usage 129.703 MB -> 64.662 MB. [2025-05-29T21:24:47.252Z] ====== als (apache-spark) [default], iteration 6 completed (3662.424 ms) ====== [2025-05-29T21:24:47.252Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-29T21:24:47.252Z] GC before operation: completed in 40.769 ms, heap usage 333.996 MB -> 65.574 MB. [2025-05-29T21:24:50.461Z] ====== als (apache-spark) [default], iteration 7 completed (3202.170 ms) ====== [2025-05-29T21:24:50.461Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-29T21:24:50.461Z] GC before operation: completed in 54.364 ms, heap usage 438.677 MB -> 66.471 MB. [2025-05-29T21:24:53.625Z] ====== als (apache-spark) [default], iteration 8 completed (3228.714 ms) ====== [2025-05-29T21:24:53.625Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-29T21:24:53.625Z] GC before operation: completed in 39.214 ms, heap usage 242.654 MB -> 66.538 MB. [2025-05-29T21:24:57.686Z] ====== als (apache-spark) [default], iteration 9 completed (3267.533 ms) ====== [2025-05-29T21:24:57.686Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-29T21:24:57.686Z] GC before operation: completed in 52.353 ms, heap usage 475.589 MB -> 67.256 MB. [2025-05-29T21:25:00.900Z] ====== als (apache-spark) [default], iteration 10 completed (3381.268 ms) ====== [2025-05-29T21:25:00.900Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-29T21:25:00.900Z] GC before operation: completed in 39.228 ms, heap usage 305.428 MB -> 67.156 MB. [2025-05-29T21:25:04.216Z] ====== als (apache-spark) [default], iteration 11 completed (3076.240 ms) ====== [2025-05-29T21:25:04.216Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-29T21:25:04.216Z] GC before operation: completed in 41.288 ms, heap usage 287.768 MB -> 67.335 MB. [2025-05-29T21:25:07.461Z] ====== als (apache-spark) [default], iteration 12 completed (3249.777 ms) ====== [2025-05-29T21:25:07.461Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-29T21:25:07.461Z] GC before operation: completed in 41.927 ms, heap usage 488.544 MB -> 67.942 MB. [2025-05-29T21:25:10.762Z] ====== als (apache-spark) [default], iteration 13 completed (3130.610 ms) ====== [2025-05-29T21:25:10.762Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-29T21:25:10.762Z] GC before operation: completed in 53.250 ms, heap usage 360.040 MB -> 68.085 MB. [2025-05-29T21:25:13.923Z] ====== als (apache-spark) [default], iteration 14 completed (3209.868 ms) ====== [2025-05-29T21:25:13.923Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-29T21:25:13.923Z] GC before operation: completed in 77.142 ms, heap usage 218.213 MB -> 68.179 MB. [2025-05-29T21:25:17.933Z] ====== als (apache-spark) [default], iteration 15 completed (3680.025 ms) ====== [2025-05-29T21:25:17.933Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-29T21:25:17.933Z] GC before operation: completed in 47.031 ms, heap usage 467.778 MB -> 68.765 MB. [2025-05-29T21:25:21.128Z] ====== als (apache-spark) [default], iteration 16 completed (3258.090 ms) ====== [2025-05-29T21:25:21.128Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-29T21:25:21.128Z] GC before operation: completed in 71.298 ms, heap usage 159.348 MB -> 68.654 MB. [2025-05-29T21:25:25.218Z] ====== als (apache-spark) [default], iteration 17 completed (3689.871 ms) ====== [2025-05-29T21:25:25.218Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-29T21:25:25.218Z] GC before operation: completed in 40.467 ms, heap usage 142.143 MB -> 68.784 MB. [2025-05-29T21:25:28.538Z] ====== als (apache-spark) [default], iteration 18 completed (3258.485 ms) ====== [2025-05-29T21:25:28.538Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-29T21:25:28.538Z] GC before operation: completed in 52.735 ms, heap usage 473.056 MB -> 69.532 MB. [2025-05-29T21:25:31.682Z] ====== als (apache-spark) [default], iteration 19 completed (3234.466 ms) ====== [2025-05-29T21:25:31.682Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-29T21:25:31.682Z] GC before operation: completed in 55.671 ms, heap usage 472.215 MB -> 69.790 MB. [2025-05-29T21:25:35.748Z] ====== als (apache-spark) [default], iteration 20 completed (3442.400 ms) ====== [2025-05-29T21:25:35.748Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-29T21:25:35.748Z] GC before operation: completed in 55.313 ms, heap usage 263.181 MB -> 69.681 MB. [2025-05-29T21:25:38.916Z] ====== als (apache-spark) [default], iteration 21 completed (3394.411 ms) ====== [2025-05-29T21:25:38.916Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-29T21:25:38.916Z] GC before operation: completed in 46.039 ms, heap usage 381.165 MB -> 69.978 MB. [2025-05-29T21:25:42.936Z] ====== als (apache-spark) [default], iteration 22 completed (3449.609 ms) ====== [2025-05-29T21:25:42.936Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-29T21:25:42.936Z] GC before operation: completed in 68.832 ms, heap usage 526.257 MB -> 70.593 MB. [2025-05-29T21:25:46.115Z] ====== als (apache-spark) [default], iteration 23 completed (3688.718 ms) ====== [2025-05-29T21:25:46.115Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-29T21:25:46.115Z] GC before operation: completed in 73.394 ms, heap usage 117.389 MB -> 70.331 MB. [2025-05-29T21:25:50.224Z] ====== als (apache-spark) [default], iteration 24 completed (3878.196 ms) ====== [2025-05-29T21:25:50.224Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-29T21:25:50.224Z] GC before operation: completed in 56.259 ms, heap usage 401.904 MB -> 70.831 MB. [2025-05-29T21:25:54.223Z] ====== als (apache-spark) [default], iteration 25 completed (3374.646 ms) ====== [2025-05-29T21:25:54.223Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-29T21:25:54.223Z] GC before operation: completed in 48.539 ms, heap usage 523.350 MB -> 71.404 MB. [2025-05-29T21:25:57.389Z] ====== als (apache-spark) [default], iteration 26 completed (3278.105 ms) ====== [2025-05-29T21:25:57.389Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-29T21:25:57.389Z] GC before operation: completed in 48.640 ms, heap usage 550.055 MB -> 71.639 MB. [2025-05-29T21:26:00.559Z] ====== als (apache-spark) [default], iteration 27 completed (3029.354 ms) ====== [2025-05-29T21:26:00.559Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-29T21:26:00.559Z] GC before operation: completed in 56.452 ms, heap usage 203.857 MB -> 71.457 MB. [2025-05-29T21:26:03.728Z] ====== als (apache-spark) [default], iteration 28 completed (3231.247 ms) ====== [2025-05-29T21:26:03.728Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-29T21:26:03.728Z] GC before operation: completed in 59.192 ms, heap usage 118.993 MB -> 71.997 MB. [2025-05-29T21:26:06.952Z] ====== als (apache-spark) [default], iteration 29 completed (3243.083 ms) ====== [2025-05-29T21:26:07.308Z] ----------------------------------- [2025-05-29T21:26:07.308Z] renaissance-als_0_PASSED [2025-05-29T21:26:07.308Z] ----------------------------------- [2025-05-29T21:26:07.308Z] [2025-05-29T21:26:07.308Z] TEST TEARDOWN: [2025-05-29T21:26:07.308Z] Nothing to be done for teardown. [2025-05-29T21:26:07.308Z] renaissance-als_0 Finish Time: Thu May 29 17:26:06 2025 Epoch Time (ms): 1748553966951