[2026-04-22T00:28:52.430Z] Running test renaissance-als_0 ... [2026-04-22T00:28:52.430Z] =============================================== [2026-04-22T00:28:52.430Z] renaissance-als_0 Start Time: Wed Apr 22 00:28:51 2026 Epoch Time (ms): 1776817731964 [2026-04-22T00:28:52.430Z] variation: NoOptions [2026-04-22T00:28:52.430Z] JVM_OPTIONS: [2026-04-22T00:28:52.430Z] { \ [2026-04-22T00:28:52.430Z] echo ""; echo "TEST SETUP:"; \ [2026-04-22T00:28:52.430Z] echo "Nothing to be done for setup."; \ [2026-04-22T00:28:52.430Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17768175535558/renaissance-als_0"; \ [2026-04-22T00:28:52.430Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17768175535558/renaissance-als_0"; \ [2026-04-22T00:28:52.430Z] echo ""; echo "TESTING:"; \ [2026-04-22T00:28:52.430Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17768175535558/renaissance-als_0"/als.json" als; \ [2026-04-22T00:28:52.430Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17768175535558/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-22T00:28:52.430Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-22T00:28:52.430Z] echo "Nothing to be done for teardown."; \ [2026-04-22T00:28:52.430Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17768175535558/TestTargetResult"; [2026-04-22T00:28:52.430Z] [2026-04-22T00:28:52.430Z] TEST SETUP: [2026-04-22T00:28:52.430Z] Nothing to be done for setup. [2026-04-22T00:28:52.430Z] [2026-04-22T00:28:52.430Z] TESTING: [2026-04-22T00:28:57.895Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-22T00:29:02.062Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-22T00:29:02.062Z] GC before operation: completed in 37.109 ms, heap usage 51.948 MB -> 35.056 MB. [2026-04-22T00:29:17.825Z] ====== als (apache-spark) [default], iteration 0 completed (15978.610 ms) ====== [2026-04-22T00:29:17.825Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-22T00:29:18.779Z] GC before operation: completed in 82.531 ms, heap usage 128.044 MB -> 62.283 MB. [2026-04-22T00:29:26.932Z] ====== als (apache-spark) [default], iteration 1 completed (7424.401 ms) ====== [2026-04-22T00:29:26.932Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-22T00:29:26.932Z] GC before operation: completed in 74.458 ms, heap usage 279.421 MB -> 62.985 MB. [2026-04-22T00:29:33.733Z] ====== als (apache-spark) [default], iteration 2 completed (6909.368 ms) ====== [2026-04-22T00:29:33.733Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-22T00:29:33.733Z] GC before operation: completed in 91.196 ms, heap usage 239.088 MB -> 63.487 MB. [2026-04-22T00:29:40.442Z] ====== als (apache-spark) [default], iteration 3 completed (6781.834 ms) ====== [2026-04-22T00:29:40.442Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-22T00:29:40.442Z] GC before operation: completed in 75.807 ms, heap usage 305.390 MB -> 64.213 MB. [2026-04-22T00:29:47.898Z] ====== als (apache-spark) [default], iteration 4 completed (6678.033 ms) ====== [2026-04-22T00:29:47.898Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-22T00:29:47.898Z] GC before operation: completed in 73.637 ms, heap usage 291.684 MB -> 64.715 MB. [2026-04-22T00:29:54.600Z] ====== als (apache-spark) [default], iteration 5 completed (6550.422 ms) ====== [2026-04-22T00:29:54.600Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-22T00:29:54.600Z] GC before operation: completed in 77.047 ms, heap usage 559.324 MB -> 65.667 MB. [2026-04-22T00:30:01.300Z] ====== als (apache-spark) [default], iteration 6 completed (6469.846 ms) ====== [2026-04-22T00:30:01.300Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-22T00:30:01.300Z] GC before operation: completed in 80.770 ms, heap usage 521.801 MB -> 66.210 MB. [2026-04-22T00:30:08.007Z] ====== als (apache-spark) [default], iteration 7 completed (6441.518 ms) ====== [2026-04-22T00:30:08.007Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-22T00:30:08.007Z] GC before operation: completed in 80.940 ms, heap usage 839.512 MB -> 67.046 MB. [2026-04-22T00:30:14.738Z] ====== als (apache-spark) [default], iteration 8 completed (6574.931 ms) ====== [2026-04-22T00:30:14.738Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-22T00:30:14.738Z] GC before operation: completed in 92.766 ms, heap usage 902.983 MB -> 67.720 MB. [2026-04-22T00:30:21.452Z] ====== als (apache-spark) [default], iteration 9 completed (6439.184 ms) ====== [2026-04-22T00:30:21.452Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-22T00:30:21.452Z] GC before operation: completed in 83.407 ms, heap usage 415.859 MB -> 67.335 MB. [2026-04-22T00:30:28.153Z] ====== als (apache-spark) [default], iteration 10 completed (6489.305 ms) ====== [2026-04-22T00:30:28.153Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-22T00:30:28.153Z] GC before operation: completed in 124.057 ms, heap usage 450.584 MB -> 67.523 MB. [2026-04-22T00:30:34.909Z] ====== als (apache-spark) [default], iteration 11 completed (6450.718 ms) ====== [2026-04-22T00:30:34.909Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-22T00:30:34.909Z] GC before operation: completed in 93.983 ms, heap usage 594.864 MB -> 68.074 MB. [2026-04-22T00:30:42.339Z] ====== als (apache-spark) [default], iteration 12 completed (6434.030 ms) ====== [2026-04-22T00:30:42.339Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-22T00:30:42.339Z] GC before operation: completed in 121.828 ms, heap usage 464.224 MB -> 68.324 MB. [2026-04-22T00:30:49.039Z] ====== als (apache-spark) [default], iteration 13 completed (6538.796 ms) ====== [2026-04-22T00:30:49.039Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-22T00:30:49.039Z] GC before operation: completed in 93.458 ms, heap usage 377.342 MB -> 68.384 MB. [2026-04-22T00:30:54.738Z] ====== als (apache-spark) [default], iteration 14 completed (6510.323 ms) ====== [2026-04-22T00:30:54.738Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-22T00:30:55.691Z] GC before operation: completed in 108.204 ms, heap usage 146.168 MB -> 68.378 MB. [2026-04-22T00:31:02.408Z] ====== als (apache-spark) [default], iteration 15 completed (6355.864 ms) ====== [2026-04-22T00:31:02.408Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-22T00:31:02.408Z] GC before operation: completed in 79.022 ms, heap usage 312.310 MB -> 68.837 MB. [2026-04-22T00:31:09.112Z] ====== als (apache-spark) [default], iteration 16 completed (6409.047 ms) ====== [2026-04-22T00:31:09.112Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-22T00:31:09.112Z] GC before operation: completed in 85.482 ms, heap usage 510.842 MB -> 69.466 MB. [2026-04-22T00:31:15.813Z] ====== als (apache-spark) [default], iteration 17 completed (6642.359 ms) ====== [2026-04-22T00:31:15.813Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-22T00:31:15.813Z] GC before operation: completed in 91.072 ms, heap usage 625.842 MB -> 69.552 MB. [2026-04-22T00:31:22.565Z] ====== als (apache-spark) [default], iteration 18 completed (6847.046 ms) ====== [2026-04-22T00:31:22.565Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-22T00:31:22.565Z] GC before operation: completed in 88.183 ms, heap usage 342.817 MB -> 69.522 MB. [2026-04-22T00:31:29.273Z] ====== als (apache-spark) [default], iteration 19 completed (6791.715 ms) ====== [2026-04-22T00:31:29.273Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-22T00:31:30.923Z] GC before operation: completed in 121.753 ms, heap usage 255.255 MB -> 69.744 MB. [2026-04-22T00:31:37.634Z] ====== als (apache-spark) [default], iteration 20 completed (6848.716 ms) ====== [2026-04-22T00:31:37.634Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-22T00:31:37.634Z] GC before operation: completed in 89.161 ms, heap usage 181.178 MB -> 70.093 MB. [2026-04-22T00:31:44.417Z] ====== als (apache-spark) [default], iteration 21 completed (6700.977 ms) ====== [2026-04-22T00:31:44.417Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-22T00:31:44.417Z] GC before operation: completed in 96.566 ms, heap usage 290.099 MB -> 70.180 MB. [2026-04-22T00:31:51.123Z] ====== als (apache-spark) [default], iteration 22 completed (6763.491 ms) ====== [2026-04-22T00:31:51.123Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-22T00:31:51.123Z] GC before operation: completed in 118.581 ms, heap usage 462.850 MB -> 70.869 MB. [2026-04-22T00:31:57.832Z] ====== als (apache-spark) [default], iteration 23 completed (6650.796 ms) ====== [2026-04-22T00:31:57.833Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-22T00:31:57.833Z] GC before operation: completed in 98.107 ms, heap usage 468.937 MB -> 71.115 MB. [2026-04-22T00:32:04.540Z] ====== als (apache-spark) [default], iteration 24 completed (6578.845 ms) ====== [2026-04-22T00:32:04.540Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-22T00:32:04.540Z] GC before operation: completed in 97.341 ms, heap usage 187.581 MB -> 71.022 MB. [2026-04-22T00:32:11.249Z] ====== als (apache-spark) [default], iteration 25 completed (6536.330 ms) ====== [2026-04-22T00:32:11.249Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-22T00:32:11.249Z] GC before operation: completed in 97.907 ms, heap usage 210.313 MB -> 71.406 MB. [2026-04-22T00:32:19.410Z] ====== als (apache-spark) [default], iteration 26 completed (6587.805 ms) ====== [2026-04-22T00:32:19.411Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-22T00:32:19.411Z] GC before operation: completed in 89.383 ms, heap usage 176.493 MB -> 71.054 MB. [2026-04-22T00:32:25.681Z] ====== als (apache-spark) [default], iteration 27 completed (6602.593 ms) ====== [2026-04-22T00:32:25.681Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-22T00:32:25.681Z] GC before operation: completed in 97.320 ms, heap usage 166.964 MB -> 71.558 MB. [2026-04-22T00:32:33.838Z] ====== als (apache-spark) [default], iteration 28 completed (7308.071 ms) ====== [2026-04-22T00:32:33.838Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-22T00:32:33.838Z] GC before operation: completed in 97.446 ms, heap usage 205.050 MB -> 72.010 MB. [2026-04-22T00:32:40.551Z] ====== als (apache-spark) [default], iteration 29 completed (6674.639 ms) ====== [2026-04-22T00:32:40.551Z] ----------------------------------- [2026-04-22T00:32:40.551Z] renaissance-als_0_PASSED [2026-04-22T00:32:40.551Z] ----------------------------------- [2026-04-22T00:32:40.551Z] [2026-04-22T00:32:40.551Z] TEST TEARDOWN: [2026-04-22T00:32:40.551Z] Nothing to be done for teardown. [2026-04-22T00:32:40.551Z] renaissance-als_0 Finish Time: Wed Apr 22 00:32:39 2026 Epoch Time (ms): 1776817959769