[2025-12-27T14:05:40.429Z] Running test renaissance-als_0 ... [2025-12-27T14:05:40.429Z] =============================================== [2025-12-27T14:05:40.429Z] renaissance-als_0 Start Time: Sat Dec 27 14:05:39 2025 Epoch Time (ms): 1766844339860 [2025-12-27T14:05:40.429Z] variation: NoOptions [2025-12-27T14:05:40.429Z] JVM_OPTIONS: [2025-12-27T14:05:40.429Z] { \ [2025-12-27T14:05:40.429Z] echo ""; echo "TEST SETUP:"; \ [2025-12-27T14:05:40.429Z] echo "Nothing to be done for setup."; \ [2025-12-27T14:05:40.429Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-als_0"; \ [2025-12-27T14:05:40.429Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-als_0"; \ [2025-12-27T14:05:40.429Z] echo ""; echo "TESTING:"; \ [2025-12-27T14:05:40.429Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/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_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-als_0"/als.json" als; \ [2025-12-27T14:05:40.429Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-27T14:05:40.429Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-27T14:05:40.429Z] echo "Nothing to be done for teardown."; \ [2025-12-27T14:05:40.429Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17668443397974/TestTargetResult"; [2025-12-27T14:05:40.429Z] [2025-12-27T14:05:40.429Z] TEST SETUP: [2025-12-27T14:05:40.429Z] Nothing to be done for setup. [2025-12-27T14:05:40.429Z] [2025-12-27T14:05:40.429Z] TESTING: [2025-12-27T14:05:41.390Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-27T14:05:41.391Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/output_17668443397974/renaissance-als_0/launcher-140540-8940614927714271512/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-27T14:05:41.391Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-27T14:05:41.391Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-27T14:05:45.637Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-27T14:05:51.080Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-27T14:05:51.080Z] GC before operation: completed in 49.834 ms, heap usage 249.335 MB -> 35.191 MB. [2025-12-27T14:06:06.987Z] ====== als (apache-spark) [default], iteration 0 completed (16228.051 ms) ====== [2025-12-27T14:06:06.987Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-27T14:06:07.951Z] GC before operation: completed in 91.632 ms, heap usage 276.850 MB -> 61.700 MB. [2025-12-27T14:06:16.178Z] ====== als (apache-spark) [default], iteration 1 completed (7476.401 ms) ====== [2025-12-27T14:06:16.178Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-27T14:06:16.178Z] GC before operation: completed in 95.610 ms, heap usage 554.396 MB -> 63.021 MB. [2025-12-27T14:06:22.517Z] ====== als (apache-spark) [default], iteration 2 completed (6942.370 ms) ====== [2025-12-27T14:06:22.519Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-27T14:06:22.519Z] GC before operation: completed in 97.615 ms, heap usage 501.093 MB -> 63.515 MB. [2025-12-27T14:06:29.293Z] ====== als (apache-spark) [default], iteration 3 completed (6808.047 ms) ====== [2025-12-27T14:06:29.293Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-27T14:06:30.266Z] GC before operation: completed in 85.566 ms, heap usage 306.140 MB -> 63.902 MB. [2025-12-27T14:06:37.045Z] ====== als (apache-spark) [default], iteration 4 completed (6751.752 ms) ====== [2025-12-27T14:06:37.045Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-27T14:06:37.045Z] GC before operation: completed in 77.581 ms, heap usage 217.468 MB -> 64.203 MB. [2025-12-27T14:06:43.809Z] ====== als (apache-spark) [default], iteration 5 completed (6550.266 ms) ====== [2025-12-27T14:06:43.809Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-27T14:06:43.809Z] GC before operation: completed in 85.745 ms, heap usage 365.146 MB -> 65.012 MB. [2025-12-27T14:06:50.571Z] ====== als (apache-spark) [default], iteration 6 completed (6608.017 ms) ====== [2025-12-27T14:06:50.571Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-27T14:06:50.571Z] GC before operation: completed in 76.810 ms, heap usage 457.814 MB -> 65.792 MB. [2025-12-27T14:06:57.346Z] ====== als (apache-spark) [default], iteration 7 completed (6446.370 ms) ====== [2025-12-27T14:06:57.346Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-27T14:06:57.346Z] GC before operation: completed in 84.865 ms, heap usage 483.995 MB -> 66.386 MB. [2025-12-27T14:07:04.223Z] ====== als (apache-spark) [default], iteration 8 completed (6542.718 ms) ====== [2025-12-27T14:07:04.223Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-27T14:07:04.223Z] GC before operation: completed in 81.974 ms, heap usage 379.104 MB -> 66.624 MB. [2025-12-27T14:07:10.985Z] ====== als (apache-spark) [default], iteration 9 completed (6332.351 ms) ====== [2025-12-27T14:07:10.985Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-27T14:07:10.985Z] GC before operation: completed in 81.891 ms, heap usage 387.274 MB -> 67.035 MB. [2025-12-27T14:07:17.750Z] ====== als (apache-spark) [default], iteration 10 completed (6350.031 ms) ====== [2025-12-27T14:07:17.750Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-27T14:07:17.750Z] GC before operation: completed in 85.664 ms, heap usage 387.992 MB -> 67.183 MB. [2025-12-27T14:07:24.513Z] ====== als (apache-spark) [default], iteration 11 completed (6635.405 ms) ====== [2025-12-27T14:07:24.513Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-27T14:07:24.513Z] GC before operation: completed in 107.115 ms, heap usage 527.848 MB -> 67.748 MB. [2025-12-27T14:07:31.287Z] ====== als (apache-spark) [default], iteration 12 completed (6657.764 ms) ====== [2025-12-27T14:07:31.287Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-27T14:07:31.287Z] GC before operation: completed in 113.837 ms, heap usage 109.524 MB -> 67.454 MB. [2025-12-27T14:07:38.062Z] ====== als (apache-spark) [default], iteration 13 completed (6569.787 ms) ====== [2025-12-27T14:07:38.062Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-27T14:07:38.062Z] GC before operation: completed in 97.323 ms, heap usage 489.388 MB -> 68.102 MB. [2025-12-27T14:07:44.840Z] ====== als (apache-spark) [default], iteration 14 completed (6454.222 ms) ====== [2025-12-27T14:07:44.840Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-27T14:07:44.840Z] GC before operation: completed in 94.179 ms, heap usage 498.035 MB -> 68.470 MB. [2025-12-27T14:07:51.604Z] ====== als (apache-spark) [default], iteration 15 completed (6419.979 ms) ====== [2025-12-27T14:07:51.604Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-27T14:07:51.604Z] GC before operation: completed in 105.024 ms, heap usage 474.971 MB -> 68.775 MB. [2025-12-27T14:07:58.362Z] ====== als (apache-spark) [default], iteration 16 completed (6432.549 ms) ====== [2025-12-27T14:07:58.362Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-27T14:07:58.362Z] GC before operation: completed in 94.650 ms, heap usage 206.999 MB -> 68.599 MB. [2025-12-27T14:08:05.488Z] ====== als (apache-spark) [default], iteration 17 completed (6508.373 ms) ====== [2025-12-27T14:08:05.488Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-27T14:08:05.488Z] GC before operation: completed in 95.700 ms, heap usage 217.691 MB -> 68.729 MB. [2025-12-27T14:08:12.244Z] ====== als (apache-spark) [default], iteration 18 completed (6491.233 ms) ====== [2025-12-27T14:08:12.244Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-27T14:08:12.245Z] GC before operation: completed in 103.777 ms, heap usage 481.306 MB -> 69.478 MB. [2025-12-27T14:08:18.999Z] ====== als (apache-spark) [default], iteration 19 completed (6544.298 ms) ====== [2025-12-27T14:08:18.999Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-27T14:08:18.999Z] GC before operation: completed in 102.195 ms, heap usage 558.942 MB -> 69.789 MB. [2025-12-27T14:08:25.762Z] ====== als (apache-spark) [default], iteration 20 completed (6495.043 ms) ====== [2025-12-27T14:08:25.762Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-27T14:08:25.762Z] GC before operation: completed in 98.440 ms, heap usage 331.778 MB -> 69.784 MB. [2025-12-27T14:08:32.520Z] ====== als (apache-spark) [default], iteration 21 completed (6281.779 ms) ====== [2025-12-27T14:08:32.520Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-27T14:08:32.520Z] GC before operation: completed in 91.111 ms, heap usage 457.600 MB -> 70.214 MB. [2025-12-27T14:08:39.283Z] ====== als (apache-spark) [default], iteration 22 completed (6296.186 ms) ====== [2025-12-27T14:08:39.283Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-27T14:08:39.283Z] GC before operation: completed in 106.296 ms, heap usage 536.588 MB -> 70.614 MB. [2025-12-27T14:08:46.053Z] ====== als (apache-spark) [default], iteration 23 completed (6371.230 ms) ====== [2025-12-27T14:08:46.053Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-27T14:08:46.053Z] GC before operation: completed in 97.841 ms, heap usage 274.524 MB -> 70.517 MB. [2025-12-27T14:08:51.480Z] ====== als (apache-spark) [default], iteration 24 completed (6286.511 ms) ====== [2025-12-27T14:08:51.480Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-27T14:08:51.480Z] GC before operation: completed in 90.780 ms, heap usage 476.211 MB -> 71.070 MB. [2025-12-27T14:08:58.247Z] ====== als (apache-spark) [default], iteration 25 completed (6175.626 ms) ====== [2025-12-27T14:08:58.247Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-27T14:08:58.247Z] GC before operation: completed in 90.199 ms, heap usage 449.337 MB -> 71.320 MB. [2025-12-27T14:09:05.060Z] ====== als (apache-spark) [default], iteration 26 completed (6162.108 ms) ====== [2025-12-27T14:09:05.060Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-27T14:09:05.060Z] GC before operation: completed in 88.112 ms, heap usage 393.162 MB -> 71.382 MB. [2025-12-27T14:09:11.866Z] ====== als (apache-spark) [default], iteration 27 completed (6137.720 ms) ====== [2025-12-27T14:09:11.866Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-27T14:09:11.866Z] GC before operation: completed in 87.319 ms, heap usage 249.009 MB -> 71.039 MB. [2025-12-27T14:09:17.295Z] ====== als (apache-spark) [default], iteration 28 completed (6129.861 ms) ====== [2025-12-27T14:09:17.295Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-27T14:09:17.295Z] GC before operation: completed in 89.190 ms, heap usage 383.792 MB -> 71.449 MB. [2025-12-27T14:09:24.052Z] ====== als (apache-spark) [default], iteration 29 completed (6112.132 ms) ====== [2025-12-27T14:09:24.052Z] ----------------------------------- [2025-12-27T14:09:24.052Z] renaissance-als_0_PASSED [2025-12-27T14:09:24.052Z] ----------------------------------- [2025-12-27T14:09:24.052Z] [2025-12-27T14:09:24.052Z] TEST TEARDOWN: [2025-12-27T14:09:24.052Z] Nothing to be done for teardown. [2025-12-27T14:09:24.052Z] renaissance-als_0 Finish Time: Sat Dec 27 14:09:23 2025 Epoch Time (ms): 1766844563624