[2025-10-16T20:55:53.689Z] Running test renaissance-als_0 ... [2025-10-16T20:55:53.689Z] =============================================== [2025-10-16T20:55:53.689Z] renaissance-als_0 Start Time: Thu Oct 16 16:55:53 2025 Epoch Time (ms): 1760648153306 [2025-10-16T20:55:53.689Z] variation: NoOptions [2025-10-16T20:55:53.689Z] JVM_OPTIONS: [2025-10-16T20:55:53.689Z] { \ [2025-10-16T20:55:53.689Z] echo ""; echo "TEST SETUP:"; \ [2025-10-16T20:55:53.689Z] echo "Nothing to be done for setup."; \ [2025-10-16T20:55:53.689Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17606481536612/renaissance-als_0"; \ [2025-10-16T20:55:53.689Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17606481536612/renaissance-als_0"; \ [2025-10-16T20:55:53.689Z] echo ""; echo "TESTING:"; \ [2025-10-16T20:55:53.689Z] "/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_17606481536612/renaissance-als_0"/als.json" als; \ [2025-10-16T20:55:53.689Z] 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_17606481536612/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-16T20:55:53.689Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-16T20:55:53.689Z] echo "Nothing to be done for teardown."; \ [2025-10-16T20:55:53.689Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17606481536612/TestTargetResult"; [2025-10-16T20:55:53.689Z] [2025-10-16T20:55:53.689Z] TEST SETUP: [2025-10-16T20:55:53.689Z] Nothing to be done for setup. [2025-10-16T20:55:53.689Z] [2025-10-16T20:55:53.689Z] TESTING: [2025-10-16T20:55:53.689Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-16T20:55:53.689Z] 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_17606481536612/renaissance-als_0/launcher-165553-17339822779274438024/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-16T20:55:53.689Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-16T20:55:53.689Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-16T20:55:56.818Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-16T20:55:58.580Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-16T20:55:58.580Z] GC before operation: completed in 16.522 ms, heap usage 62.460 MB -> 35.221 MB. [2025-10-16T20:56:06.270Z] ====== als (apache-spark) [default], iteration 0 completed (7736.284 ms) ====== [2025-10-16T20:56:06.270Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-16T20:56:06.270Z] GC before operation: completed in 37.796 ms, heap usage 117.508 MB -> 61.556 MB. [2025-10-16T20:56:10.351Z] ====== als (apache-spark) [default], iteration 1 completed (3504.520 ms) ====== [2025-10-16T20:56:10.351Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-16T20:56:10.351Z] GC before operation: completed in 43.811 ms, heap usage 80.308 MB -> 62.489 MB. [2025-10-16T20:56:13.500Z] ====== als (apache-spark) [default], iteration 2 completed (3073.255 ms) ====== [2025-10-16T20:56:13.500Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-16T20:56:13.500Z] GC before operation: completed in 41.037 ms, heap usage 505.662 MB -> 63.549 MB. [2025-10-16T20:56:16.737Z] ====== als (apache-spark) [default], iteration 3 completed (3132.415 ms) ====== [2025-10-16T20:56:16.737Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-16T20:56:16.737Z] GC before operation: completed in 35.278 ms, heap usage 229.761 MB -> 63.866 MB. [2025-10-16T20:56:19.922Z] ====== als (apache-spark) [default], iteration 4 completed (3223.756 ms) ====== [2025-10-16T20:56:19.922Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-16T20:56:19.922Z] GC before operation: completed in 40.022 ms, heap usage 151.512 MB -> 64.283 MB. [2025-10-16T20:56:23.084Z] ====== als (apache-spark) [default], iteration 5 completed (3120.519 ms) ====== [2025-10-16T20:56:23.084Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-16T20:56:23.084Z] GC before operation: completed in 36.499 ms, heap usage 209.131 MB -> 65.023 MB. [2025-10-16T20:56:26.284Z] ====== als (apache-spark) [default], iteration 6 completed (3080.438 ms) ====== [2025-10-16T20:56:26.284Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-16T20:56:26.284Z] GC before operation: completed in 46.847 ms, heap usage 129.071 MB -> 65.283 MB. [2025-10-16T20:56:29.481Z] ====== als (apache-spark) [default], iteration 7 completed (3157.343 ms) ====== [2025-10-16T20:56:29.481Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-16T20:56:29.481Z] GC before operation: completed in 52.654 ms, heap usage 448.661 MB -> 66.322 MB. [2025-10-16T20:56:32.663Z] ====== als (apache-spark) [default], iteration 8 completed (3153.536 ms) ====== [2025-10-16T20:56:32.663Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-16T20:56:32.663Z] GC before operation: completed in 44.151 ms, heap usage 104.997 MB -> 66.877 MB. [2025-10-16T20:56:35.881Z] ====== als (apache-spark) [default], iteration 9 completed (3104.394 ms) ====== [2025-10-16T20:56:35.881Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-16T20:56:35.881Z] GC before operation: completed in 46.077 ms, heap usage 202.702 MB -> 66.767 MB. [2025-10-16T20:56:39.085Z] ====== als (apache-spark) [default], iteration 10 completed (3050.304 ms) ====== [2025-10-16T20:56:39.085Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-16T20:56:39.085Z] GC before operation: completed in 52.471 ms, heap usage 125.304 MB -> 66.938 MB. [2025-10-16T20:56:42.274Z] ====== als (apache-spark) [default], iteration 11 completed (3122.628 ms) ====== [2025-10-16T20:56:42.274Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-16T20:56:42.274Z] GC before operation: completed in 51.133 ms, heap usage 502.410 MB -> 67.737 MB. [2025-10-16T20:56:45.505Z] ====== als (apache-spark) [default], iteration 12 completed (3065.713 ms) ====== [2025-10-16T20:56:45.505Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-16T20:56:45.505Z] GC before operation: completed in 42.476 ms, heap usage 149.174 MB -> 67.552 MB. [2025-10-16T20:56:48.678Z] ====== als (apache-spark) [default], iteration 13 completed (2985.593 ms) ====== [2025-10-16T20:56:48.678Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-16T20:56:48.678Z] GC before operation: completed in 41.423 ms, heap usage 247.483 MB -> 67.905 MB. [2025-10-16T20:56:51.869Z] ====== als (apache-spark) [default], iteration 14 completed (2943.040 ms) ====== [2025-10-16T20:56:51.869Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-16T20:56:51.869Z] GC before operation: completed in 53.983 ms, heap usage 439.331 MB -> 68.522 MB. [2025-10-16T20:56:55.074Z] ====== als (apache-spark) [default], iteration 15 completed (2892.545 ms) ====== [2025-10-16T20:56:55.074Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-16T20:56:55.074Z] GC before operation: completed in 74.742 ms, heap usage 193.865 MB -> 68.307 MB. [2025-10-16T20:56:58.272Z] ====== als (apache-spark) [default], iteration 16 completed (3007.988 ms) ====== [2025-10-16T20:56:58.272Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-16T20:56:58.272Z] GC before operation: completed in 44.172 ms, heap usage 356.240 MB -> 68.833 MB. [2025-10-16T20:57:00.717Z] ====== als (apache-spark) [default], iteration 17 completed (2970.790 ms) ====== [2025-10-16T20:57:00.717Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-16T20:57:01.095Z] GC before operation: completed in 42.320 ms, heap usage 183.809 MB -> 68.822 MB. [2025-10-16T20:57:04.323Z] ====== als (apache-spark) [default], iteration 18 completed (2896.996 ms) ====== [2025-10-16T20:57:04.323Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-16T20:57:04.323Z] GC before operation: completed in 43.517 ms, heap usage 487.324 MB -> 69.535 MB. [2025-10-16T20:57:06.808Z] ====== als (apache-spark) [default], iteration 19 completed (2938.874 ms) ====== [2025-10-16T20:57:06.808Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-16T20:57:06.808Z] GC before operation: completed in 56.118 ms, heap usage 356.800 MB -> 69.615 MB. [2025-10-16T20:57:10.048Z] ====== als (apache-spark) [default], iteration 20 completed (3001.563 ms) ====== [2025-10-16T20:57:10.048Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-16T20:57:10.048Z] GC before operation: completed in 38.032 ms, heap usage 494.053 MB -> 70.104 MB. [2025-10-16T20:57:13.246Z] ====== als (apache-spark) [default], iteration 21 completed (2950.023 ms) ====== [2025-10-16T20:57:13.246Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-16T20:57:13.246Z] GC before operation: completed in 48.259 ms, heap usage 211.446 MB -> 69.957 MB. [2025-10-16T20:57:16.448Z] ====== als (apache-spark) [default], iteration 22 completed (3093.340 ms) ====== [2025-10-16T20:57:16.448Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-16T20:57:16.448Z] GC before operation: completed in 48.837 ms, heap usage 471.824 MB -> 70.607 MB. [2025-10-16T20:57:19.599Z] ====== als (apache-spark) [default], iteration 23 completed (2914.718 ms) ====== [2025-10-16T20:57:19.599Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-16T20:57:19.599Z] GC before operation: completed in 37.498 ms, heap usage 426.971 MB -> 70.891 MB. [2025-10-16T20:57:22.771Z] ====== als (apache-spark) [default], iteration 24 completed (2879.957 ms) ====== [2025-10-16T20:57:22.771Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-16T20:57:22.771Z] GC before operation: completed in 38.304 ms, heap usage 495.934 MB -> 71.068 MB. [2025-10-16T20:57:25.262Z] ====== als (apache-spark) [default], iteration 25 completed (2901.189 ms) ====== [2025-10-16T20:57:25.262Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-16T20:57:25.262Z] GC before operation: completed in 50.423 ms, heap usage 338.908 MB -> 71.093 MB. [2025-10-16T20:57:28.503Z] ====== als (apache-spark) [default], iteration 26 completed (2952.265 ms) ====== [2025-10-16T20:57:28.503Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-16T20:57:28.503Z] GC before operation: completed in 38.160 ms, heap usage 165.653 MB -> 71.137 MB. [2025-10-16T20:57:31.686Z] ====== als (apache-spark) [default], iteration 27 completed (3024.788 ms) ====== [2025-10-16T20:57:31.686Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-16T20:57:31.686Z] GC before operation: completed in 39.102 ms, heap usage 473.836 MB -> 71.797 MB. [2025-10-16T20:57:34.949Z] ====== als (apache-spark) [default], iteration 28 completed (2937.280 ms) ====== [2025-10-16T20:57:34.949Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-16T20:57:34.949Z] GC before operation: completed in 46.469 ms, heap usage 447.242 MB -> 72.097 MB. [2025-10-16T20:57:37.359Z] ====== als (apache-spark) [default], iteration 29 completed (2941.391 ms) ====== [2025-10-16T20:57:37.714Z] ----------------------------------- [2025-10-16T20:57:37.714Z] renaissance-als_0_PASSED [2025-10-16T20:57:37.714Z] ----------------------------------- [2025-10-16T20:57:37.714Z] [2025-10-16T20:57:37.714Z] TEST TEARDOWN: [2025-10-16T20:57:37.714Z] Nothing to be done for teardown. [2025-10-16T20:57:37.714Z] renaissance-als_0 Finish Time: Thu Oct 16 16:57:37 2025 Epoch Time (ms): 1760648257483