[2026-04-01T21:36:26.191Z] Running test renaissance-als_0 ... [2026-04-01T21:36:26.191Z] =============================================== [2026-04-01T21:36:26.191Z] renaissance-als_0 Start Time: Wed Apr 1 21:36:25 2026 Epoch Time (ms): 1775079385573 [2026-04-01T21:36:26.191Z] variation: NoOptions [2026-04-01T21:36:26.191Z] JVM_OPTIONS: [2026-04-01T21:36:26.191Z] { \ [2026-04-01T21:36:26.191Z] echo ""; echo "TEST SETUP:"; \ [2026-04-01T21:36:26.191Z] echo "Nothing to be done for setup."; \ [2026-04-01T21:36:26.191Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17750792164262/renaissance-als_0"; \ [2026-04-01T21:36:26.191Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17750792164262/renaissance-als_0"; \ [2026-04-01T21:36:26.191Z] echo ""; echo "TESTING:"; \ [2026-04-01T21:36:26.191Z] "/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_17750792164262/renaissance-als_0"/als.json" als; \ [2026-04-01T21:36:26.192Z] 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_17750792164262/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-01T21:36:26.192Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-01T21:36:26.192Z] echo "Nothing to be done for teardown."; \ [2026-04-01T21:36:26.192Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17750792164262/TestTargetResult"; [2026-04-01T21:36:26.192Z] [2026-04-01T21:36:26.192Z] TEST SETUP: [2026-04-01T21:36:26.192Z] Nothing to be done for setup. [2026-04-01T21:36:26.192Z] [2026-04-01T21:36:26.192Z] TESTING: [2026-04-01T21:36:31.700Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-01T21:36:34.720Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-01T21:36:34.720Z] GC before operation: completed in 37.016 ms, heap usage 87.000 MB -> 35.041 MB. [2026-04-01T21:36:51.191Z] ====== als (apache-spark) [default], iteration 0 completed (15414.692 ms) ====== [2026-04-01T21:36:51.191Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-01T21:36:51.191Z] GC before operation: completed in 104.438 ms, heap usage 418.868 MB -> 62.341 MB. [2026-04-01T21:36:59.333Z] ====== als (apache-spark) [default], iteration 1 completed (7860.701 ms) ====== [2026-04-01T21:36:59.333Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-01T21:36:59.333Z] GC before operation: completed in 76.635 ms, heap usage 410.218 MB -> 62.915 MB. [2026-04-01T21:37:07.542Z] ====== als (apache-spark) [default], iteration 2 completed (7316.963 ms) ====== [2026-04-01T21:37:07.542Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-01T21:37:07.542Z] GC before operation: completed in 70.125 ms, heap usage 214.840 MB -> 63.488 MB. [2026-04-01T21:37:15.058Z] ====== als (apache-spark) [default], iteration 3 completed (7310.458 ms) ====== [2026-04-01T21:37:15.058Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-01T21:37:15.058Z] GC before operation: completed in 83.250 ms, heap usage 649.389 MB -> 64.401 MB. [2026-04-01T21:37:21.796Z] ====== als (apache-spark) [default], iteration 4 completed (6836.285 ms) ====== [2026-04-01T21:37:21.796Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-01T21:37:21.796Z] GC before operation: completed in 75.349 ms, heap usage 291.724 MB -> 64.730 MB. [2026-04-01T21:37:28.515Z] ====== als (apache-spark) [default], iteration 5 completed (6827.080 ms) ====== [2026-04-01T21:37:28.515Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-01T21:37:29.478Z] GC before operation: completed in 81.010 ms, heap usage 615.120 MB -> 65.566 MB. [2026-04-01T21:37:36.278Z] ====== als (apache-spark) [default], iteration 6 completed (7036.441 ms) ====== [2026-04-01T21:37:36.278Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-01T21:37:36.278Z] GC before operation: completed in 96.971 ms, heap usage 494.635 MB -> 66.010 MB. [2026-04-01T21:37:44.526Z] ====== als (apache-spark) [default], iteration 7 completed (7363.708 ms) ====== [2026-04-01T21:37:44.526Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-01T21:37:44.526Z] GC before operation: completed in 78.040 ms, heap usage 329.682 MB -> 66.390 MB. [2026-04-01T21:37:51.804Z] ====== als (apache-spark) [default], iteration 8 completed (6748.189 ms) ====== [2026-04-01T21:37:51.805Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-01T21:37:51.805Z] GC before operation: completed in 91.209 ms, heap usage 292.597 MB -> 66.960 MB. [2026-04-01T21:37:58.549Z] ====== als (apache-spark) [default], iteration 9 completed (6798.070 ms) ====== [2026-04-01T21:37:58.549Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-01T21:37:58.549Z] GC before operation: completed in 89.940 ms, heap usage 531.260 MB -> 67.484 MB. [2026-04-01T21:38:05.285Z] ====== als (apache-spark) [default], iteration 10 completed (6676.139 ms) ====== [2026-04-01T21:38:05.285Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-01T21:38:05.285Z] GC before operation: completed in 93.288 ms, heap usage 699.738 MB -> 67.861 MB. [2026-04-01T21:38:12.015Z] ====== als (apache-spark) [default], iteration 11 completed (6736.681 ms) ====== [2026-04-01T21:38:12.015Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-01T21:38:12.015Z] GC before operation: completed in 82.340 ms, heap usage 170.316 MB -> 67.575 MB. [2026-04-01T21:38:18.751Z] ====== als (apache-spark) [default], iteration 12 completed (6666.716 ms) ====== [2026-04-01T21:38:18.751Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-01T21:38:18.751Z] GC before operation: completed in 87.814 ms, heap usage 188.778 MB -> 67.977 MB. [2026-04-01T21:38:26.208Z] ====== als (apache-spark) [default], iteration 13 completed (6758.684 ms) ====== [2026-04-01T21:38:26.208Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-01T21:38:26.208Z] GC before operation: completed in 94.127 ms, heap usage 153.410 MB -> 68.060 MB. [2026-04-01T21:38:32.955Z] ====== als (apache-spark) [default], iteration 14 completed (6647.254 ms) ====== [2026-04-01T21:38:32.955Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-01T21:38:32.955Z] GC before operation: completed in 92.420 ms, heap usage 237.716 MB -> 68.440 MB. [2026-04-01T21:38:39.878Z] ====== als (apache-spark) [default], iteration 15 completed (6715.462 ms) ====== [2026-04-01T21:38:39.878Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-01T21:38:39.878Z] GC before operation: completed in 112.796 ms, heap usage 380.960 MB -> 68.671 MB. [2026-04-01T21:38:48.141Z] ====== als (apache-spark) [default], iteration 16 completed (6753.022 ms) ====== [2026-04-01T21:38:48.141Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-01T21:38:48.141Z] GC before operation: completed in 95.553 ms, heap usage 180.326 MB -> 68.902 MB. [2026-04-01T21:38:54.958Z] ====== als (apache-spark) [default], iteration 17 completed (6781.187 ms) ====== [2026-04-01T21:38:54.958Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-01T21:38:54.958Z] GC before operation: completed in 104.181 ms, heap usage 745.466 MB -> 69.758 MB. [2026-04-01T21:39:02.279Z] ====== als (apache-spark) [default], iteration 18 completed (8034.669 ms) ====== [2026-04-01T21:39:02.279Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-01T21:39:02.279Z] GC before operation: completed in 91.670 ms, heap usage 387.714 MB -> 69.603 MB. [2026-04-01T21:39:10.484Z] ====== als (apache-spark) [default], iteration 19 completed (6745.355 ms) ====== [2026-04-01T21:39:10.484Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-01T21:39:10.484Z] GC before operation: completed in 110.480 ms, heap usage 476.012 MB -> 70.134 MB. [2026-04-01T21:39:17.271Z] ====== als (apache-spark) [default], iteration 20 completed (6750.857 ms) ====== [2026-04-01T21:39:17.271Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-01T21:39:17.271Z] GC before operation: completed in 92.418 ms, heap usage 179.112 MB -> 70.016 MB. [2026-04-01T21:39:24.079Z] ====== als (apache-spark) [default], iteration 21 completed (6591.368 ms) ====== [2026-04-01T21:39:24.079Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-01T21:39:24.079Z] GC before operation: completed in 101.015 ms, heap usage 169.303 MB -> 70.286 MB. [2026-04-01T21:39:30.240Z] ====== als (apache-spark) [default], iteration 22 completed (6617.220 ms) ====== [2026-04-01T21:39:30.240Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-01T21:39:30.240Z] GC before operation: completed in 111.925 ms, heap usage 445.677 MB -> 70.450 MB. [2026-04-01T21:39:36.957Z] ====== als (apache-spark) [default], iteration 23 completed (6684.861 ms) ====== [2026-04-01T21:39:36.957Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-01T21:39:37.908Z] GC before operation: completed in 83.216 ms, heap usage 143.762 MB -> 70.751 MB. [2026-04-01T21:39:46.064Z] ====== als (apache-spark) [default], iteration 24 completed (7937.937 ms) ====== [2026-04-01T21:39:46.064Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-01T21:39:46.064Z] GC before operation: completed in 91.703 ms, heap usage 321.192 MB -> 71.048 MB. [2026-04-01T21:39:52.745Z] ====== als (apache-spark) [default], iteration 25 completed (6502.843 ms) ====== [2026-04-01T21:39:52.745Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-01T21:39:52.745Z] GC before operation: completed in 105.624 ms, heap usage 297.348 MB -> 71.295 MB. [2026-04-01T21:39:59.455Z] ====== als (apache-spark) [default], iteration 26 completed (6689.300 ms) ====== [2026-04-01T21:39:59.455Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-01T21:39:59.455Z] GC before operation: completed in 97.260 ms, heap usage 244.158 MB -> 71.552 MB. [2026-04-01T21:40:06.536Z] ====== als (apache-spark) [default], iteration 27 completed (6664.634 ms) ====== [2026-04-01T21:40:06.536Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-01T21:40:06.536Z] GC before operation: completed in 100.387 ms, heap usage 311.238 MB -> 71.746 MB. [2026-04-01T21:40:13.818Z] ====== als (apache-spark) [default], iteration 28 completed (6696.604 ms) ====== [2026-04-01T21:40:13.818Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-01T21:40:13.818Z] GC before operation: completed in 92.606 ms, heap usage 184.168 MB -> 72.189 MB. [2026-04-01T21:40:19.786Z] ====== als (apache-spark) [default], iteration 29 completed (6540.438 ms) ====== [2026-04-01T21:40:20.740Z] ----------------------------------- [2026-04-01T21:40:20.740Z] renaissance-als_0_PASSED [2026-04-01T21:40:20.740Z] ----------------------------------- [2026-04-01T21:40:20.740Z] [2026-04-01T21:40:20.740Z] TEST TEARDOWN: [2026-04-01T21:40:20.740Z] Nothing to be done for teardown. [2026-04-01T21:40:20.740Z] renaissance-als_0 Finish Time: Wed Apr 1 21:40:19 2026 Epoch Time (ms): 1775079620006