[2026-02-26T22:51:14.850Z] Running test renaissance-als_0 ... [2026-02-26T22:51:14.850Z] =============================================== [2026-02-26T22:51:14.850Z] renaissance-als_0 Start Time: Thu Feb 26 22:51:14 2026 Epoch Time (ms): 1772146274058 [2026-02-26T22:51:14.850Z] variation: NoOptions [2026-02-26T22:51:14.850Z] JVM_OPTIONS: [2026-02-26T22:51:14.850Z] { \ [2026-02-26T22:51:14.850Z] echo ""; echo "TEST SETUP:"; \ [2026-02-26T22:51:14.850Z] echo "Nothing to be done for setup."; \ [2026-02-26T22:51:14.850Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17721461256330/renaissance-als_0"; \ [2026-02-26T22:51:14.850Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17721461256330/renaissance-als_0"; \ [2026-02-26T22:51:14.850Z] echo ""; echo "TESTING:"; \ [2026-02-26T22:51:14.850Z] "/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_17721461256330/renaissance-als_0"/als.json" als; \ [2026-02-26T22:51:14.850Z] 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_17721461256330/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-26T22:51:14.850Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-26T22:51:14.850Z] echo "Nothing to be done for teardown."; \ [2026-02-26T22:51:14.850Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17721461256330/TestTargetResult"; [2026-02-26T22:51:14.850Z] [2026-02-26T22:51:14.851Z] TEST SETUP: [2026-02-26T22:51:14.851Z] Nothing to be done for setup. [2026-02-26T22:51:14.851Z] [2026-02-26T22:51:14.851Z] TESTING: [2026-02-26T22:51:14.851Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-26T22:51:14.851Z] 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_17721461256330/renaissance-als_0/launcher-225114-15191728724489120226/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-26T22:51:14.851Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-26T22:51:14.851Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-26T22:51:20.259Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-26T22:51:24.501Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-26T22:51:24.501Z] GC before operation: completed in 49.006 ms, heap usage 275.861 MB -> 35.025 MB. [2026-02-26T22:51:42.969Z] ====== als (apache-spark) [default], iteration 0 completed (16764.725 ms) ====== [2026-02-26T22:51:42.970Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-26T22:51:42.970Z] GC before operation: completed in 119.642 ms, heap usage 474.814 MB -> 61.847 MB. [2026-02-26T22:51:51.270Z] ====== als (apache-spark) [default], iteration 1 completed (7937.080 ms) ====== [2026-02-26T22:51:51.270Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-26T22:51:51.270Z] GC before operation: completed in 95.322 ms, heap usage 428.135 MB -> 62.952 MB. [2026-02-26T22:51:57.700Z] ====== als (apache-spark) [default], iteration 2 completed (6991.542 ms) ====== [2026-02-26T22:51:57.700Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-26T22:51:57.700Z] GC before operation: completed in 96.733 ms, heap usage 99.531 MB -> 63.038 MB. [2026-02-26T22:52:05.829Z] ====== als (apache-spark) [default], iteration 3 completed (6928.635 ms) ====== [2026-02-26T22:52:05.829Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-26T22:52:05.829Z] GC before operation: completed in 91.548 ms, heap usage 500.586 MB -> 64.039 MB. [2026-02-26T22:52:12.484Z] ====== als (apache-spark) [default], iteration 4 completed (6839.845 ms) ====== [2026-02-26T22:52:12.484Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-26T22:52:12.484Z] GC before operation: completed in 96.878 ms, heap usage 139.509 MB -> 64.129 MB. [2026-02-26T22:52:19.163Z] ====== als (apache-spark) [default], iteration 5 completed (6682.398 ms) ====== [2026-02-26T22:52:19.163Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-26T22:52:19.163Z] GC before operation: completed in 94.569 ms, heap usage 136.968 MB -> 64.711 MB. [2026-02-26T22:52:25.967Z] ====== als (apache-spark) [default], iteration 6 completed (6732.988 ms) ====== [2026-02-26T22:52:25.967Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-26T22:52:25.967Z] GC before operation: completed in 93.126 ms, heap usage 524.135 MB -> 65.826 MB. [2026-02-26T22:52:34.065Z] ====== als (apache-spark) [default], iteration 7 completed (6631.496 ms) ====== [2026-02-26T22:52:34.065Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-26T22:52:34.065Z] GC before operation: completed in 92.704 ms, heap usage 230.918 MB -> 65.887 MB. [2026-02-26T22:52:40.778Z] ====== als (apache-spark) [default], iteration 8 completed (6856.614 ms) ====== [2026-02-26T22:52:40.778Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-26T22:52:40.778Z] GC before operation: completed in 99.665 ms, heap usage 434.369 MB -> 66.814 MB. [2026-02-26T22:52:47.454Z] ====== als (apache-spark) [default], iteration 9 completed (6486.046 ms) ====== [2026-02-26T22:52:47.454Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-26T22:52:47.454Z] GC before operation: completed in 108.993 ms, heap usage 199.136 MB -> 67.206 MB. [2026-02-26T22:52:54.109Z] ====== als (apache-spark) [default], iteration 10 completed (6557.781 ms) ====== [2026-02-26T22:52:54.109Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-26T22:52:54.109Z] GC before operation: completed in 102.098 ms, heap usage 254.050 MB -> 66.903 MB. [2026-02-26T22:53:00.813Z] ====== als (apache-spark) [default], iteration 11 completed (6430.738 ms) ====== [2026-02-26T22:53:00.813Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-26T22:53:00.813Z] GC before operation: completed in 95.268 ms, heap usage 246.489 MB -> 67.209 MB. [2026-02-26T22:53:07.512Z] ====== als (apache-spark) [default], iteration 12 completed (6458.734 ms) ====== [2026-02-26T22:53:07.512Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-26T22:53:07.513Z] GC before operation: completed in 87.580 ms, heap usage 213.630 MB -> 67.346 MB. [2026-02-26T22:53:14.175Z] ====== als (apache-spark) [default], iteration 13 completed (6501.317 ms) ====== [2026-02-26T22:53:14.175Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-26T22:53:14.175Z] GC before operation: completed in 101.799 ms, heap usage 121.158 MB -> 67.635 MB. [2026-02-26T22:53:22.310Z] ====== als (apache-spark) [default], iteration 14 completed (6565.095 ms) ====== [2026-02-26T22:53:22.310Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-26T22:53:22.310Z] GC before operation: completed in 120.900 ms, heap usage 148.493 MB -> 67.845 MB. [2026-02-26T22:53:29.009Z] ====== als (apache-spark) [default], iteration 15 completed (6550.490 ms) ====== [2026-02-26T22:53:29.009Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-26T22:53:29.009Z] GC before operation: completed in 98.310 ms, heap usage 476.018 MB -> 68.499 MB. [2026-02-26T22:53:35.621Z] ====== als (apache-spark) [default], iteration 16 completed (6552.951 ms) ====== [2026-02-26T22:53:35.621Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-26T22:53:35.621Z] GC before operation: completed in 100.232 ms, heap usage 231.846 MB -> 68.454 MB. [2026-02-26T22:53:42.278Z] ====== als (apache-spark) [default], iteration 17 completed (6573.999 ms) ====== [2026-02-26T22:53:42.278Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-26T22:53:42.278Z] GC before operation: completed in 101.197 ms, heap usage 352.362 MB -> 68.897 MB. [2026-02-26T22:53:49.161Z] ====== als (apache-spark) [default], iteration 18 completed (6555.992 ms) ====== [2026-02-26T22:53:49.161Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-26T22:53:49.161Z] GC before operation: completed in 103.758 ms, heap usage 209.849 MB -> 68.890 MB. [2026-02-26T22:53:55.821Z] ====== als (apache-spark) [default], iteration 19 completed (6382.021 ms) ====== [2026-02-26T22:53:55.821Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-26T22:53:55.821Z] GC before operation: completed in 99.431 ms, heap usage 132.258 MB -> 69.186 MB. [2026-02-26T22:54:02.477Z] ====== als (apache-spark) [default], iteration 20 completed (6404.091 ms) ====== [2026-02-26T22:54:02.477Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-26T22:54:02.477Z] GC before operation: completed in 98.722 ms, heap usage 154.052 MB -> 69.453 MB. [2026-02-26T22:54:09.193Z] ====== als (apache-spark) [default], iteration 21 completed (6442.662 ms) ====== [2026-02-26T22:54:09.193Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-26T22:54:09.193Z] GC before operation: completed in 95.818 ms, heap usage 429.671 MB -> 70.120 MB. [2026-02-26T22:54:15.863Z] ====== als (apache-spark) [default], iteration 22 completed (6319.970 ms) ====== [2026-02-26T22:54:15.863Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-26T22:54:15.863Z] GC before operation: completed in 92.500 ms, heap usage 267.378 MB -> 70.061 MB. [2026-02-26T22:54:22.510Z] ====== als (apache-spark) [default], iteration 23 completed (6416.622 ms) ====== [2026-02-26T22:54:22.510Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-26T22:54:22.510Z] GC before operation: completed in 109.565 ms, heap usage 449.909 MB -> 70.670 MB. [2026-02-26T22:54:29.182Z] ====== als (apache-spark) [default], iteration 24 completed (6455.110 ms) ====== [2026-02-26T22:54:29.182Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-26T22:54:29.182Z] GC before operation: completed in 99.095 ms, heap usage 492.840 MB -> 70.891 MB. [2026-02-26T22:54:35.846Z] ====== als (apache-spark) [default], iteration 25 completed (6293.814 ms) ====== [2026-02-26T22:54:35.846Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-26T22:54:35.846Z] GC before operation: completed in 94.584 ms, heap usage 218.333 MB -> 70.694 MB. [2026-02-26T22:54:42.512Z] ====== als (apache-spark) [default], iteration 26 completed (6281.487 ms) ====== [2026-02-26T22:54:42.512Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-26T22:54:42.512Z] GC before operation: completed in 94.909 ms, heap usage 498.706 MB -> 71.401 MB. [2026-02-26T22:54:49.206Z] ====== als (apache-spark) [default], iteration 27 completed (6363.566 ms) ====== [2026-02-26T22:54:49.206Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-26T22:54:49.206Z] GC before operation: completed in 93.038 ms, heap usage 559.846 MB -> 71.256 MB. [2026-02-26T22:54:55.891Z] ====== als (apache-spark) [default], iteration 28 completed (6268.938 ms) ====== [2026-02-26T22:54:55.891Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-26T22:54:55.891Z] GC before operation: completed in 92.984 ms, heap usage 459.359 MB -> 71.472 MB. [2026-02-26T22:55:02.549Z] ====== als (apache-spark) [default], iteration 29 completed (6347.862 ms) ====== [2026-02-26T22:55:02.549Z] ----------------------------------- [2026-02-26T22:55:02.549Z] renaissance-als_0_PASSED [2026-02-26T22:55:02.549Z] ----------------------------------- [2026-02-26T22:55:02.549Z] [2026-02-26T22:55:02.549Z] TEST TEARDOWN: [2026-02-26T22:55:02.549Z] Nothing to be done for teardown. [2026-02-26T22:55:02.549Z] renaissance-als_0 Finish Time: Thu Feb 26 22:55:01 2026 Epoch Time (ms): 1772146501897