[2026-02-19T22:31:06.141Z] Running test renaissance-als_0 ... [2026-02-19T22:31:06.141Z] =============================================== [2026-02-19T22:31:06.141Z] renaissance-als_0 Start Time: Thu Feb 19 22:31:05 2026 Epoch Time (ms): 1771540265944 [2026-02-19T22:31:06.141Z] variation: NoOptions [2026-02-19T22:31:06.141Z] JVM_OPTIONS: [2026-02-19T22:31:06.141Z] { \ [2026-02-19T22:31:06.141Z] echo ""; echo "TEST SETUP:"; \ [2026-02-19T22:31:06.141Z] echo "Nothing to be done for setup."; \ [2026-02-19T22:31:06.141Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17715401207692/renaissance-als_0"; \ [2026-02-19T22:31:06.141Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17715401207692/renaissance-als_0"; \ [2026-02-19T22:31:06.141Z] echo ""; echo "TESTING:"; \ [2026-02-19T22:31:06.141Z] "/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_17715401207692/renaissance-als_0"/als.json" als; \ [2026-02-19T22:31:06.141Z] 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_17715401207692/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-19T22:31:06.141Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-19T22:31:06.141Z] echo "Nothing to be done for teardown."; \ [2026-02-19T22:31:06.141Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17715401207692/TestTargetResult"; [2026-02-19T22:31:06.141Z] [2026-02-19T22:31:06.141Z] TEST SETUP: [2026-02-19T22:31:06.141Z] Nothing to be done for setup. [2026-02-19T22:31:06.141Z] [2026-02-19T22:31:06.141Z] TESTING: [2026-02-19T22:31:07.100Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-19T22:31:07.100Z] 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_17715401207692/renaissance-als_0/launcher-223106-17825802850889865055/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-19T22:31:07.100Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-19T22:31:07.100Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-19T22:31:11.346Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-19T22:31:16.762Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-19T22:31:16.762Z] GC before operation: completed in 41.821 ms, heap usage 60.456 MB -> 35.093 MB. [2026-02-19T22:31:35.171Z] ====== als (apache-spark) [default], iteration 0 completed (16444.548 ms) ====== [2026-02-19T22:31:35.171Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-19T22:31:35.171Z] GC before operation: completed in 100.870 ms, heap usage 260.970 MB -> 61.708 MB. [2026-02-19T22:31:41.904Z] ====== als (apache-spark) [default], iteration 1 completed (7488.224 ms) ====== [2026-02-19T22:31:41.904Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-19T22:31:41.904Z] GC before operation: completed in 75.881 ms, heap usage 239.803 MB -> 62.608 MB. [2026-02-19T22:31:48.661Z] ====== als (apache-spark) [default], iteration 2 completed (7214.683 ms) ====== [2026-02-19T22:31:48.661Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-19T22:31:48.661Z] GC before operation: completed in 79.642 ms, heap usage 504.790 MB -> 63.498 MB. [2026-02-19T22:31:57.793Z] ====== als (apache-spark) [default], iteration 3 completed (6961.233 ms) ====== [2026-02-19T22:31:57.793Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-19T22:31:57.793Z] GC before operation: completed in 90.581 ms, heap usage 491.642 MB -> 64.082 MB. [2026-02-19T22:32:03.237Z] ====== als (apache-spark) [default], iteration 4 completed (6843.787 ms) ====== [2026-02-19T22:32:03.237Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-19T22:32:03.237Z] GC before operation: completed in 91.156 ms, heap usage 196.303 MB -> 64.174 MB. [2026-02-19T22:32:10.113Z] ====== als (apache-spark) [default], iteration 5 completed (6748.369 ms) ====== [2026-02-19T22:32:10.113Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-19T22:32:10.113Z] GC before operation: completed in 89.217 ms, heap usage 324.212 MB -> 64.977 MB. [2026-02-19T22:32:16.876Z] ====== als (apache-spark) [default], iteration 6 completed (6545.452 ms) ====== [2026-02-19T22:32:16.876Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-19T22:32:16.876Z] GC before operation: completed in 87.449 ms, heap usage 480.892 MB -> 65.791 MB. [2026-02-19T22:32:23.792Z] ====== als (apache-spark) [default], iteration 7 completed (6405.297 ms) ====== [2026-02-19T22:32:23.792Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-19T22:32:23.792Z] GC before operation: completed in 92.848 ms, heap usage 192.417 MB -> 65.956 MB. [2026-02-19T22:32:30.574Z] ====== als (apache-spark) [default], iteration 8 completed (6574.772 ms) ====== [2026-02-19T22:32:30.574Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-19T22:32:30.574Z] GC before operation: completed in 88.628 ms, heap usage 231.804 MB -> 66.529 MB. [2026-02-19T22:32:37.425Z] ====== als (apache-spark) [default], iteration 9 completed (6470.658 ms) ====== [2026-02-19T22:32:37.425Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-19T22:32:37.425Z] GC before operation: completed in 93.995 ms, heap usage 482.655 MB -> 67.499 MB. [2026-02-19T22:32:44.196Z] ====== als (apache-spark) [default], iteration 10 completed (6571.989 ms) ====== [2026-02-19T22:32:44.197Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-19T22:32:44.197Z] GC before operation: completed in 110.840 ms, heap usage 284.284 MB -> 67.029 MB. [2026-02-19T22:32:51.849Z] ====== als (apache-spark) [default], iteration 11 completed (6524.256 ms) ====== [2026-02-19T22:32:51.849Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-19T22:32:51.849Z] GC before operation: completed in 96.517 ms, heap usage 507.703 MB -> 67.562 MB. [2026-02-19T22:32:58.699Z] ====== als (apache-spark) [default], iteration 12 completed (6428.708 ms) ====== [2026-02-19T22:32:58.699Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-19T22:32:58.699Z] GC before operation: completed in 98.078 ms, heap usage 254.839 MB -> 67.499 MB. [2026-02-19T22:33:06.556Z] ====== als (apache-spark) [default], iteration 13 completed (6420.296 ms) ====== [2026-02-19T22:33:06.556Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-19T22:33:06.556Z] GC before operation: completed in 89.351 ms, heap usage 490.397 MB -> 68.081 MB. [2026-02-19T22:33:11.969Z] ====== als (apache-spark) [default], iteration 14 completed (6403.522 ms) ====== [2026-02-19T22:33:11.969Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-19T22:33:11.969Z] GC before operation: completed in 104.909 ms, heap usage 538.873 MB -> 68.450 MB. [2026-02-19T22:33:18.798Z] ====== als (apache-spark) [default], iteration 15 completed (6477.692 ms) ====== [2026-02-19T22:33:18.798Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-19T22:33:18.798Z] GC before operation: completed in 100.953 ms, heap usage 462.343 MB -> 68.612 MB. [2026-02-19T22:33:25.553Z] ====== als (apache-spark) [default], iteration 16 completed (6390.040 ms) ====== [2026-02-19T22:33:25.553Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-19T22:33:25.553Z] GC before operation: completed in 95.308 ms, heap usage 496.954 MB -> 68.828 MB. [2026-02-19T22:33:32.305Z] ====== als (apache-spark) [default], iteration 17 completed (6507.622 ms) ====== [2026-02-19T22:33:32.305Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-19T22:33:32.305Z] GC before operation: completed in 93.432 ms, heap usage 534.770 MB -> 69.216 MB. [2026-02-19T22:33:39.041Z] ====== als (apache-spark) [default], iteration 18 completed (6436.142 ms) ====== [2026-02-19T22:33:39.041Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-19T22:33:39.041Z] GC before operation: completed in 92.309 ms, heap usage 306.201 MB -> 69.225 MB. [2026-02-19T22:33:45.530Z] ====== als (apache-spark) [default], iteration 19 completed (6445.992 ms) ====== [2026-02-19T22:33:45.530Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-19T22:33:45.530Z] GC before operation: completed in 99.761 ms, heap usage 355.651 MB -> 69.538 MB. [2026-02-19T22:33:52.354Z] ====== als (apache-spark) [default], iteration 20 completed (6427.712 ms) ====== [2026-02-19T22:33:52.354Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-19T22:33:52.354Z] GC before operation: completed in 109.295 ms, heap usage 381.974 MB -> 69.771 MB. [2026-02-19T22:33:59.137Z] ====== als (apache-spark) [default], iteration 21 completed (6428.229 ms) ====== [2026-02-19T22:33:59.137Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-19T22:33:59.137Z] GC before operation: completed in 99.138 ms, heap usage 495.244 MB -> 70.220 MB. [2026-02-19T22:34:05.878Z] ====== als (apache-spark) [default], iteration 22 completed (6390.864 ms) ====== [2026-02-19T22:34:05.878Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-19T22:34:05.878Z] GC before operation: completed in 94.328 ms, heap usage 470.602 MB -> 70.492 MB. [2026-02-19T22:34:12.618Z] ====== als (apache-spark) [default], iteration 23 completed (6332.550 ms) ====== [2026-02-19T22:34:12.618Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-19T22:34:12.618Z] GC before operation: completed in 92.337 ms, heap usage 390.062 MB -> 70.525 MB. [2026-02-19T22:34:18.105Z] ====== als (apache-spark) [default], iteration 24 completed (6419.235 ms) ====== [2026-02-19T22:34:18.105Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-19T22:34:19.065Z] GC before operation: completed in 89.192 ms, heap usage 460.651 MB -> 71.075 MB. [2026-02-19T22:34:25.794Z] ====== als (apache-spark) [default], iteration 25 completed (6346.617 ms) ====== [2026-02-19T22:34:25.794Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-19T22:34:25.794Z] GC before operation: completed in 94.054 ms, heap usage 354.537 MB -> 71.048 MB. [2026-02-19T22:34:31.294Z] ====== als (apache-spark) [default], iteration 26 completed (6217.626 ms) ====== [2026-02-19T22:34:31.294Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-19T22:34:31.294Z] GC before operation: completed in 92.215 ms, heap usage 216.103 MB -> 71.148 MB. [2026-02-19T22:34:39.079Z] ====== als (apache-spark) [default], iteration 27 completed (6315.870 ms) ====== [2026-02-19T22:34:39.079Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-19T22:34:39.079Z] GC before operation: completed in 107.674 ms, heap usage 198.122 MB -> 71.387 MB. [2026-02-19T22:34:44.483Z] ====== als (apache-spark) [default], iteration 28 completed (6271.059 ms) ====== [2026-02-19T22:34:44.483Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-19T22:34:44.483Z] GC before operation: completed in 96.391 ms, heap usage 443.613 MB -> 71.648 MB. [2026-02-19T22:34:51.223Z] ====== als (apache-spark) [default], iteration 29 completed (6251.436 ms) ====== [2026-02-19T22:34:52.182Z] ----------------------------------- [2026-02-19T22:34:52.182Z] renaissance-als_0_PASSED [2026-02-19T22:34:52.182Z] ----------------------------------- [2026-02-19T22:34:52.182Z] [2026-02-19T22:34:52.182Z] TEST TEARDOWN: [2026-02-19T22:34:52.182Z] Nothing to be done for teardown. [2026-02-19T22:34:52.182Z] renaissance-als_0 Finish Time: Thu Feb 19 22:34:51 2026 Epoch Time (ms): 1771540491171