[2025-06-26T22:05:39.642Z] Running test renaissance-als_0 ... [2025-06-26T22:05:39.642Z] =============================================== [2025-06-26T22:05:39.643Z] renaissance-als_0 Start Time: Thu Jun 26 22:05:39 2025 Epoch Time (ms): 1750975539437 [2025-06-26T22:05:39.643Z] variation: NoOptions [2025-06-26T22:05:39.643Z] JVM_OPTIONS: [2025-06-26T22:05:39.643Z] { \ [2025-06-26T22:05:39.643Z] echo ""; echo "TEST SETUP:"; \ [2025-06-26T22:05:39.643Z] echo "Nothing to be done for setup."; \ [2025-06-26T22:05:39.643Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509754695371/renaissance-als_0"; \ [2025-06-26T22:05:39.643Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509754695371/renaissance-als_0"; \ [2025-06-26T22:05:39.643Z] echo ""; echo "TESTING:"; \ [2025-06-26T22:05:39.643Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/jdkbinary/j2sdk-image/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 "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509754695371/renaissance-als_0"/als.json" als; \ [2025-06-26T22:05:39.643Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509754695371/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-26T22:05:39.643Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-26T22:05:39.643Z] echo "Nothing to be done for teardown."; \ [2025-06-26T22:05:39.643Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509754695371/TestTargetResult"; [2025-06-26T22:05:39.643Z] [2025-06-26T22:05:39.643Z] TEST SETUP: [2025-06-26T22:05:39.643Z] Nothing to be done for setup. [2025-06-26T22:05:39.643Z] [2025-06-26T22:05:39.643Z] TESTING: [2025-06-26T22:05:41.557Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-06-26T22:05:41.557Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17509754695371/renaissance-als_0/launcher-220539-16125194538211988989/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-06-26T22:05:41.557Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-06-26T22:05:41.557Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-06-26T22:05:48.092Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-26T22:05:51.042Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-26T22:05:51.042Z] GC before operation: completed in 35.553 ms, heap usage 112.795 MB -> 35.801 MB. [2025-06-26T22:06:04.337Z] ====== als (apache-spark) [default], iteration 0 completed (12810.935 ms) ====== [2025-06-26T22:06:04.338Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-26T22:06:04.338Z] GC before operation: completed in 115.134 ms, heap usage 295.460 MB -> 62.640 MB. [2025-06-26T22:06:08.391Z] ====== als (apache-spark) [default], iteration 1 completed (4055.148 ms) ====== [2025-06-26T22:06:08.391Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-26T22:06:09.332Z] GC before operation: completed in 98.578 ms, heap usage 508.992 MB -> 63.741 MB. [2025-06-26T22:06:13.373Z] ====== als (apache-spark) [default], iteration 2 completed (3762.965 ms) ====== [2025-06-26T22:06:13.373Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-26T22:06:13.373Z] GC before operation: completed in 100.754 ms, heap usage 322.221 MB -> 64.222 MB. [2025-06-26T22:06:17.055Z] ====== als (apache-spark) [default], iteration 3 completed (3848.526 ms) ====== [2025-06-26T22:06:17.055Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-26T22:06:17.055Z] GC before operation: completed in 106.584 ms, heap usage 218.969 MB -> 64.480 MB. [2025-06-26T22:06:23.707Z] ====== als (apache-spark) [default], iteration 4 completed (3998.638 ms) ====== [2025-06-26T22:06:23.707Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-26T22:06:23.707Z] GC before operation: completed in 106.813 ms, heap usage 169.382 MB -> 65.010 MB. [2025-06-26T22:06:25.604Z] ====== als (apache-spark) [default], iteration 5 completed (3794.840 ms) ====== [2025-06-26T22:06:25.604Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-26T22:06:25.604Z] GC before operation: completed in 95.314 ms, heap usage 238.805 MB -> 65.717 MB. [2025-06-26T22:06:29.624Z] ====== als (apache-spark) [default], iteration 6 completed (3721.935 ms) ====== [2025-06-26T22:06:29.624Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-26T22:06:29.624Z] GC before operation: completed in 103.526 ms, heap usage 633.679 MB -> 66.747 MB. [2025-06-26T22:06:34.002Z] ====== als (apache-spark) [default], iteration 7 completed (3529.698 ms) ====== [2025-06-26T22:06:34.002Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-26T22:06:34.002Z] GC before operation: completed in 98.472 ms, heap usage 148.088 MB -> 66.796 MB. [2025-06-26T22:06:37.117Z] ====== als (apache-spark) [default], iteration 8 completed (3633.111 ms) ====== [2025-06-26T22:06:37.117Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-26T22:06:37.117Z] GC before operation: completed in 103.903 ms, heap usage 583.804 MB -> 67.831 MB. [2025-06-26T22:06:40.596Z] ====== als (apache-spark) [default], iteration 9 completed (3298.818 ms) ====== [2025-06-26T22:06:40.596Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-26T22:06:40.596Z] GC before operation: completed in 106.876 ms, heap usage 143.389 MB -> 67.896 MB. [2025-06-26T22:06:44.644Z] ====== als (apache-spark) [default], iteration 10 completed (3703.997 ms) ====== [2025-06-26T22:06:44.644Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-26T22:06:44.644Z] GC before operation: completed in 109.678 ms, heap usage 356.236 MB -> 67.995 MB. [2025-06-26T22:06:48.724Z] ====== als (apache-spark) [default], iteration 11 completed (3557.513 ms) ====== [2025-06-26T22:06:48.724Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-26T22:06:48.724Z] GC before operation: completed in 117.168 ms, heap usage 480.442 MB -> 68.566 MB. [2025-06-26T22:06:52.915Z] ====== als (apache-spark) [default], iteration 12 completed (3555.948 ms) ====== [2025-06-26T22:06:52.915Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-26T22:06:52.916Z] GC before operation: completed in 108.023 ms, heap usage 394.595 MB -> 68.688 MB. [2025-06-26T22:06:55.838Z] ====== als (apache-spark) [default], iteration 13 completed (3218.055 ms) ====== [2025-06-26T22:06:55.838Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-26T22:06:55.838Z] GC before operation: completed in 103.048 ms, heap usage 490.727 MB -> 69.167 MB. [2025-06-26T22:06:59.861Z] ====== als (apache-spark) [default], iteration 14 completed (3437.555 ms) ====== [2025-06-26T22:06:59.861Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-26T22:06:59.861Z] GC before operation: completed in 104.500 ms, heap usage 211.860 MB -> 68.985 MB. [2025-06-26T22:07:02.806Z] ====== als (apache-spark) [default], iteration 15 completed (3418.639 ms) ====== [2025-06-26T22:07:02.806Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-26T22:07:03.725Z] GC before operation: completed in 109.985 ms, heap usage 177.784 MB -> 69.295 MB. [2025-06-26T22:07:06.734Z] ====== als (apache-spark) [default], iteration 16 completed (3413.234 ms) ====== [2025-06-26T22:07:06.734Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-26T22:07:06.734Z] GC before operation: completed in 103.119 ms, heap usage 344.618 MB -> 69.793 MB. [2025-06-26T22:07:10.927Z] ====== als (apache-spark) [default], iteration 17 completed (3351.116 ms) ====== [2025-06-26T22:07:10.927Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-26T22:07:10.927Z] GC before operation: completed in 104.673 ms, heap usage 289.922 MB -> 69.865 MB. [2025-06-26T22:07:13.847Z] ====== als (apache-spark) [default], iteration 18 completed (3341.961 ms) ====== [2025-06-26T22:07:13.847Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-26T22:07:13.847Z] GC before operation: completed in 104.942 ms, heap usage 176.859 MB -> 70.137 MB. [2025-06-26T22:07:17.879Z] ====== als (apache-spark) [default], iteration 19 completed (3545.870 ms) ====== [2025-06-26T22:07:17.879Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-26T22:07:17.879Z] GC before operation: completed in 106.148 ms, heap usage 448.541 MB -> 70.730 MB. [2025-06-26T22:07:21.905Z] ====== als (apache-spark) [default], iteration 20 completed (3390.811 ms) ====== [2025-06-26T22:07:21.905Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-26T22:07:21.905Z] GC before operation: completed in 105.963 ms, heap usage 471.626 MB -> 71.164 MB. [2025-06-26T22:07:24.912Z] ====== als (apache-spark) [default], iteration 21 completed (3199.089 ms) ====== [2025-06-26T22:07:24.912Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-26T22:07:24.912Z] GC before operation: completed in 146.167 ms, heap usage 446.276 MB -> 71.073 MB. [2025-06-26T22:07:29.101Z] ====== als (apache-spark) [default], iteration 22 completed (3484.327 ms) ====== [2025-06-26T22:07:29.101Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-26T22:07:29.101Z] GC before operation: completed in 129.286 ms, heap usage 149.872 MB -> 71.055 MB. [2025-06-26T22:07:32.179Z] ====== als (apache-spark) [default], iteration 23 completed (3237.705 ms) ====== [2025-06-26T22:07:32.179Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-26T22:07:32.179Z] GC before operation: completed in 115.268 ms, heap usage 498.461 MB -> 71.713 MB. [2025-06-26T22:07:36.269Z] ====== als (apache-spark) [default], iteration 24 completed (3401.082 ms) ====== [2025-06-26T22:07:36.269Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-26T22:07:36.269Z] GC before operation: completed in 116.200 ms, heap usage 614.499 MB -> 72.005 MB. [2025-06-26T22:07:39.196Z] ====== als (apache-spark) [default], iteration 25 completed (3390.790 ms) ====== [2025-06-26T22:07:39.196Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-26T22:07:39.196Z] GC before operation: completed in 122.203 ms, heap usage 202.870 MB -> 71.861 MB. [2025-06-26T22:07:43.225Z] ====== als (apache-spark) [default], iteration 26 completed (3375.823 ms) ====== [2025-06-26T22:07:43.225Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-26T22:07:43.225Z] GC before operation: completed in 145.013 ms, heap usage 455.185 MB -> 72.485 MB. [2025-06-26T22:07:46.985Z] ====== als (apache-spark) [default], iteration 27 completed (3173.829 ms) ====== [2025-06-26T22:07:46.985Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-26T22:07:46.985Z] GC before operation: completed in 118.389 ms, heap usage 170.903 MB -> 72.287 MB. [2025-06-26T22:07:49.907Z] ====== als (apache-spark) [default], iteration 28 completed (3231.086 ms) ====== [2025-06-26T22:07:49.907Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-26T22:07:49.907Z] GC before operation: completed in 96.228 ms, heap usage 181.893 MB -> 72.568 MB. [2025-06-26T22:07:52.927Z] ====== als (apache-spark) [default], iteration 29 completed (3307.397 ms) ====== [2025-06-26T22:07:53.848Z] ----------------------------------- [2025-06-26T22:07:53.848Z] renaissance-als_0_PASSED [2025-06-26T22:07:53.848Z] ----------------------------------- [2025-06-26T22:07:53.848Z] [2025-06-26T22:07:53.848Z] TEST TEARDOWN: [2025-06-26T22:07:53.848Z] Nothing to be done for teardown. [2025-06-26T22:07:53.848Z] renaissance-als_0 Finish Time: Thu Jun 26 22:07:53 2025 Epoch Time (ms): 1750975673605