[2026-03-02T12:41:41.032Z] Running test renaissance-als_0 ... [2026-03-02T12:41:41.032Z] =============================================== [2026-03-02T12:41:41.032Z] renaissance-als_0 Start Time: Mon Mar 2 12:41:39 2026 Epoch Time (ms): 1772455299635 [2026-03-02T12:41:41.032Z] variation: NoOptions [2026-03-02T12:41:41.032Z] JVM_OPTIONS: [2026-03-02T12:41:41.032Z] { \ [2026-03-02T12:41:41.032Z] echo ""; echo "TEST SETUP:"; \ [2026-03-02T12:41:41.032Z] echo "Nothing to be done for setup."; \ [2026-03-02T12:41:41.032Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17724551558148/renaissance-als_0"; \ [2026-03-02T12:41:41.032Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17724551558148/renaissance-als_0"; \ [2026-03-02T12:41:41.032Z] echo ""; echo "TESTING:"; \ [2026-03-02T12:41:41.032Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17724551558148/renaissance-als_0"/als.json" als; \ [2026-03-02T12:41:41.032Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17724551558148/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-02T12:41:41.032Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-02T12:41:41.032Z] echo "Nothing to be done for teardown."; \ [2026-03-02T12:41:41.032Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17724551558148/TestTargetResult"; [2026-03-02T12:41:41.032Z] [2026-03-02T12:41:41.032Z] TEST SETUP: [2026-03-02T12:41:41.032Z] Nothing to be done for setup. [2026-03-02T12:41:41.032Z] [2026-03-02T12:41:41.032Z] TESTING: [2026-03-02T12:41:41.032Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-03-02T12:41:41.032Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17724551558148/renaissance-als_0/launcher-124139-4486954616277439084/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-03-02T12:41:41.032Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-03-02T12:41:41.032Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-03-02T12:41:45.233Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-03-02T12:41:49.409Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-02T12:41:49.409Z] GC before operation: completed in 39.616 ms, heap usage 227.322 MB -> 35.041 MB. [2026-03-02T12:42:07.814Z] ====== als (apache-spark) [default], iteration 0 completed (16568.502 ms) ====== [2026-03-02T12:42:07.814Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-02T12:42:07.814Z] GC before operation: completed in 91.719 ms, heap usage 273.000 MB -> 61.859 MB. [2026-03-02T12:42:14.891Z] ====== als (apache-spark) [default], iteration 1 completed (7507.835 ms) ====== [2026-03-02T12:42:14.892Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-02T12:42:14.892Z] GC before operation: completed in 73.630 ms, heap usage 352.594 MB -> 62.814 MB. [2026-03-02T12:42:21.629Z] ====== als (apache-spark) [default], iteration 2 completed (6688.236 ms) ====== [2026-03-02T12:42:21.629Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-02T12:42:21.629Z] GC before operation: completed in 83.621 ms, heap usage 158.856 MB -> 63.042 MB. [2026-03-02T12:42:28.374Z] ====== als (apache-spark) [default], iteration 3 completed (6853.902 ms) ====== [2026-03-02T12:42:28.374Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-02T12:42:28.374Z] GC before operation: completed in 85.809 ms, heap usage 230.438 MB -> 63.751 MB. [2026-03-02T12:42:36.581Z] ====== als (apache-spark) [default], iteration 4 completed (6647.828 ms) ====== [2026-03-02T12:42:36.581Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-02T12:42:36.581Z] GC before operation: completed in 80.321 ms, heap usage 173.835 MB -> 64.181 MB. [2026-03-02T12:42:43.327Z] ====== als (apache-spark) [default], iteration 5 completed (6616.318 ms) ====== [2026-03-02T12:42:43.327Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-02T12:42:43.327Z] GC before operation: completed in 86.612 ms, heap usage 153.289 MB -> 64.714 MB. [2026-03-02T12:42:50.987Z] ====== als (apache-spark) [default], iteration 6 completed (6697.996 ms) ====== [2026-03-02T12:42:50.987Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-02T12:42:50.987Z] GC before operation: completed in 88.155 ms, heap usage 99.450 MB -> 65.126 MB. [2026-03-02T12:42:56.404Z] ====== als (apache-spark) [default], iteration 7 completed (6488.850 ms) ====== [2026-03-02T12:42:56.404Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-02T12:42:56.404Z] GC before operation: completed in 97.817 ms, heap usage 519.368 MB -> 66.383 MB. [2026-03-02T12:43:03.143Z] ====== als (apache-spark) [default], iteration 8 completed (6570.230 ms) ====== [2026-03-02T12:43:03.143Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-02T12:43:03.143Z] GC before operation: completed in 87.117 ms, heap usage 346.335 MB -> 66.645 MB. [2026-03-02T12:43:10.034Z] ====== als (apache-spark) [default], iteration 9 completed (6352.092 ms) ====== [2026-03-02T12:43:10.035Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-02T12:43:10.035Z] GC before operation: completed in 87.168 ms, heap usage 502.244 MB -> 67.486 MB. [2026-03-02T12:43:16.783Z] ====== als (apache-spark) [default], iteration 10 completed (6496.452 ms) ====== [2026-03-02T12:43:16.783Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-02T12:43:16.783Z] GC before operation: completed in 81.984 ms, heap usage 149.840 MB -> 66.929 MB. [2026-03-02T12:43:23.520Z] ====== als (apache-spark) [default], iteration 11 completed (6386.979 ms) ====== [2026-03-02T12:43:23.520Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-02T12:43:23.520Z] GC before operation: completed in 87.444 ms, heap usage 197.867 MB -> 67.191 MB. [2026-03-02T12:43:30.236Z] ====== als (apache-spark) [default], iteration 12 completed (6398.821 ms) ====== [2026-03-02T12:43:30.236Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-02T12:43:30.236Z] GC before operation: completed in 84.958 ms, heap usage 336.317 MB -> 67.621 MB. [2026-03-02T12:43:36.957Z] ====== als (apache-spark) [default], iteration 13 completed (6435.295 ms) ====== [2026-03-02T12:43:36.957Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-02T12:43:36.957Z] GC before operation: completed in 88.196 ms, heap usage 524.348 MB -> 68.278 MB. [2026-03-02T12:43:43.697Z] ====== als (apache-spark) [default], iteration 14 completed (6376.079 ms) ====== [2026-03-02T12:43:43.697Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-02T12:43:43.697Z] GC before operation: completed in 83.148 ms, heap usage 288.538 MB -> 68.106 MB. [2026-03-02T12:43:50.444Z] ====== als (apache-spark) [default], iteration 15 completed (6277.546 ms) ====== [2026-03-02T12:43:50.444Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-02T12:43:50.444Z] GC before operation: completed in 92.523 ms, heap usage 529.346 MB -> 68.718 MB. [2026-03-02T12:43:56.584Z] ====== als (apache-spark) [default], iteration 16 completed (6331.063 ms) ====== [2026-03-02T12:43:56.584Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-02T12:43:56.584Z] GC before operation: completed in 87.513 ms, heap usage 396.375 MB -> 68.699 MB. [2026-03-02T12:44:03.432Z] ====== als (apache-spark) [default], iteration 17 completed (6406.961 ms) ====== [2026-03-02T12:44:03.432Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-02T12:44:03.432Z] GC before operation: completed in 97.054 ms, heap usage 527.001 MB -> 69.302 MB. [2026-03-02T12:44:10.175Z] ====== als (apache-spark) [default], iteration 18 completed (6315.361 ms) ====== [2026-03-02T12:44:10.175Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-02T12:44:10.175Z] GC before operation: completed in 92.837 ms, heap usage 551.513 MB -> 69.579 MB. [2026-03-02T12:44:17.092Z] ====== als (apache-spark) [default], iteration 19 completed (6363.045 ms) ====== [2026-03-02T12:44:17.092Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-02T12:44:17.092Z] GC before operation: completed in 87.979 ms, heap usage 135.069 MB -> 69.400 MB. [2026-03-02T12:44:23.828Z] ====== als (apache-spark) [default], iteration 20 completed (6278.843 ms) ====== [2026-03-02T12:44:23.828Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-02T12:44:23.828Z] GC before operation: completed in 91.448 ms, heap usage 258.012 MB -> 69.652 MB. [2026-03-02T12:44:29.247Z] ====== als (apache-spark) [default], iteration 21 completed (6301.456 ms) ====== [2026-03-02T12:44:29.247Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-02T12:44:30.207Z] GC before operation: completed in 87.897 ms, heap usage 498.173 MB -> 70.234 MB. [2026-03-02T12:44:36.955Z] ====== als (apache-spark) [default], iteration 22 completed (6218.691 ms) ====== [2026-03-02T12:44:36.955Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-02T12:44:36.955Z] GC before operation: completed in 91.666 ms, heap usage 205.485 MB -> 70.039 MB. [2026-03-02T12:44:43.697Z] ====== als (apache-spark) [default], iteration 23 completed (6379.787 ms) ====== [2026-03-02T12:44:43.697Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-02T12:44:43.697Z] GC before operation: completed in 87.336 ms, heap usage 336.238 MB -> 70.564 MB. [2026-03-02T12:44:49.107Z] ====== als (apache-spark) [default], iteration 24 completed (6253.377 ms) ====== [2026-03-02T12:44:49.107Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-02T12:44:49.107Z] GC before operation: completed in 92.004 ms, heap usage 165.254 MB -> 70.634 MB. [2026-03-02T12:44:55.408Z] ====== als (apache-spark) [default], iteration 25 completed (6123.924 ms) ====== [2026-03-02T12:44:55.408Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-02T12:44:56.367Z] GC before operation: completed in 87.615 ms, heap usage 216.393 MB -> 70.891 MB. [2026-03-02T12:45:01.901Z] ====== als (apache-spark) [default], iteration 26 completed (6125.066 ms) ====== [2026-03-02T12:45:01.901Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-02T12:45:01.901Z] GC before operation: completed in 89.043 ms, heap usage 168.082 MB -> 71.118 MB. [2026-03-02T12:45:08.634Z] ====== als (apache-spark) [default], iteration 27 completed (6193.908 ms) ====== [2026-03-02T12:45:08.634Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-02T12:45:08.634Z] GC before operation: completed in 89.308 ms, heap usage 385.509 MB -> 71.694 MB. [2026-03-02T12:45:15.374Z] ====== als (apache-spark) [default], iteration 28 completed (6232.665 ms) ====== [2026-03-02T12:45:15.374Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-02T12:45:15.374Z] GC before operation: completed in 90.271 ms, heap usage 271.849 MB -> 71.362 MB. [2026-03-02T12:45:22.123Z] ====== als (apache-spark) [default], iteration 29 completed (6097.687 ms) ====== [2026-03-02T12:45:22.123Z] ----------------------------------- [2026-03-02T12:45:22.123Z] renaissance-als_0_PASSED [2026-03-02T12:45:22.123Z] ----------------------------------- [2026-03-02T12:45:22.123Z] [2026-03-02T12:45:22.123Z] TEST TEARDOWN: [2026-03-02T12:45:22.123Z] Nothing to be done for teardown. [2026-03-02T12:45:22.123Z] renaissance-als_0 Finish Time: Mon Mar 2 12:45:21 2026 Epoch Time (ms): 1772455521403