[2026-02-25T22:17:02.130Z] Running test renaissance-als_0 ... [2026-02-25T22:17:02.130Z] =============================================== [2026-02-25T22:17:02.130Z] renaissance-als_0 Start Time: Wed Feb 25 22:17:01 2026 Epoch Time (ms): 1772057821484 [2026-02-25T22:17:02.130Z] variation: NoOptions [2026-02-25T22:17:02.130Z] JVM_OPTIONS: [2026-02-25T22:17:02.131Z] { \ [2026-02-25T22:17:02.131Z] echo ""; echo "TEST SETUP:"; \ [2026-02-25T22:17:02.131Z] echo "Nothing to be done for setup."; \ [2026-02-25T22:17:02.131Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1772057657945/renaissance-als_0"; \ [2026-02-25T22:17:02.131Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1772057657945/renaissance-als_0"; \ [2026-02-25T22:17:02.131Z] echo ""; echo "TESTING:"; \ [2026-02-25T22:17:02.131Z] "/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_1772057657945/renaissance-als_0"/als.json" als; \ [2026-02-25T22:17:02.131Z] 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_1772057657945/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-25T22:17:02.131Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-25T22:17:02.131Z] echo "Nothing to be done for teardown."; \ [2026-02-25T22:17:02.131Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1772057657945/TestTargetResult"; [2026-02-25T22:17:02.131Z] [2026-02-25T22:17:02.131Z] TEST SETUP: [2026-02-25T22:17:02.131Z] Nothing to be done for setup. [2026-02-25T22:17:02.131Z] [2026-02-25T22:17:02.131Z] TESTING: [2026-02-25T22:17:07.615Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-25T22:17:11.773Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-25T22:17:11.773Z] GC before operation: completed in 43.856 ms, heap usage 232.801 MB -> 34.985 MB. [2026-02-25T22:17:30.062Z] ====== als (apache-spark) [default], iteration 0 completed (16361.967 ms) ====== [2026-02-25T22:17:30.062Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-25T22:17:30.062Z] GC before operation: completed in 99.330 ms, heap usage 625.783 MB -> 62.485 MB. [2026-02-25T22:17:36.786Z] ====== als (apache-spark) [default], iteration 1 completed (7699.363 ms) ====== [2026-02-25T22:17:36.786Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-25T22:17:36.786Z] GC before operation: completed in 88.580 ms, heap usage 1000.708 MB -> 63.839 MB. [2026-02-25T22:17:43.479Z] ====== als (apache-spark) [default], iteration 2 completed (7140.862 ms) ====== [2026-02-25T22:17:43.479Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-25T22:17:43.479Z] GC before operation: completed in 84.397 ms, heap usage 375.672 MB -> 63.771 MB. [2026-02-25T22:17:51.669Z] ====== als (apache-spark) [default], iteration 3 completed (7006.955 ms) ====== [2026-02-25T22:17:51.669Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-25T22:17:51.669Z] GC before operation: completed in 87.917 ms, heap usage 420.813 MB -> 64.304 MB. [2026-02-25T22:17:59.413Z] ====== als (apache-spark) [default], iteration 4 completed (6843.522 ms) ====== [2026-02-25T22:17:59.413Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-25T22:17:59.413Z] GC before operation: completed in 86.298 ms, heap usage 798.127 MB -> 65.286 MB. [2026-02-25T22:18:06.251Z] ====== als (apache-spark) [default], iteration 5 completed (6825.845 ms) ====== [2026-02-25T22:18:06.251Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-25T22:18:06.251Z] GC before operation: completed in 87.395 ms, heap usage 667.960 MB -> 65.680 MB. [2026-02-25T22:18:12.935Z] ====== als (apache-spark) [default], iteration 6 completed (6824.559 ms) ====== [2026-02-25T22:18:12.935Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-25T22:18:12.935Z] GC before operation: completed in 89.163 ms, heap usage 133.451 MB -> 65.759 MB. [2026-02-25T22:18:19.636Z] ====== als (apache-spark) [default], iteration 7 completed (6728.967 ms) ====== [2026-02-25T22:18:19.636Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-25T22:18:19.636Z] GC before operation: completed in 83.847 ms, heap usage 720.496 MB -> 66.963 MB. [2026-02-25T22:18:26.324Z] ====== als (apache-spark) [default], iteration 8 completed (6681.212 ms) ====== [2026-02-25T22:18:26.324Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-25T22:18:26.324Z] GC before operation: completed in 96.818 ms, heap usage 563.187 MB -> 67.386 MB. [2026-02-25T22:18:34.458Z] ====== als (apache-spark) [default], iteration 9 completed (6757.353 ms) ====== [2026-02-25T22:18:34.458Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-25T22:18:34.458Z] GC before operation: completed in 94.980 ms, heap usage 261.665 MB -> 67.466 MB. [2026-02-25T22:18:41.145Z] ====== als (apache-spark) [default], iteration 10 completed (6667.104 ms) ====== [2026-02-25T22:18:41.145Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-25T22:18:41.145Z] GC before operation: completed in 102.625 ms, heap usage 265.549 MB -> 67.363 MB. [2026-02-25T22:18:47.847Z] ====== als (apache-spark) [default], iteration 11 completed (6645.898 ms) ====== [2026-02-25T22:18:47.847Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-25T22:18:47.847Z] GC before operation: completed in 88.365 ms, heap usage 668.209 MB -> 68.087 MB. [2026-02-25T22:18:54.537Z] ====== als (apache-spark) [default], iteration 12 completed (6661.174 ms) ====== [2026-02-25T22:18:54.537Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-25T22:18:54.537Z] GC before operation: completed in 100.272 ms, heap usage 386.017 MB -> 68.046 MB. [2026-02-25T22:19:01.240Z] ====== als (apache-spark) [default], iteration 13 completed (6630.381 ms) ====== [2026-02-25T22:19:01.240Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-25T22:19:01.240Z] GC before operation: completed in 96.312 ms, heap usage 435.617 MB -> 68.263 MB. [2026-02-25T22:19:09.005Z] ====== als (apache-spark) [default], iteration 14 completed (6573.271 ms) ====== [2026-02-25T22:19:09.005Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-25T22:19:09.005Z] GC before operation: completed in 89.470 ms, heap usage 464.559 MB -> 68.781 MB. [2026-02-25T22:19:15.700Z] ====== als (apache-spark) [default], iteration 15 completed (6586.423 ms) ====== [2026-02-25T22:19:15.700Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-25T22:19:15.700Z] GC before operation: completed in 89.374 ms, heap usage 200.259 MB -> 68.650 MB. [2026-02-25T22:19:22.605Z] ====== als (apache-spark) [default], iteration 16 completed (6592.313 ms) ====== [2026-02-25T22:19:22.605Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-25T22:19:22.606Z] GC before operation: completed in 97.600 ms, heap usage 310.592 MB -> 69.180 MB. [2026-02-25T22:19:29.297Z] ====== als (apache-spark) [default], iteration 17 completed (6601.842 ms) ====== [2026-02-25T22:19:29.297Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-25T22:19:29.297Z] GC before operation: completed in 93.238 ms, heap usage 144.570 MB -> 69.154 MB. [2026-02-25T22:19:35.995Z] ====== als (apache-spark) [default], iteration 18 completed (6571.026 ms) ====== [2026-02-25T22:19:35.996Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-25T22:19:35.996Z] GC before operation: completed in 95.729 ms, heap usage 703.343 MB -> 70.012 MB. [2026-02-25T22:19:42.685Z] ====== als (apache-spark) [default], iteration 19 completed (6592.574 ms) ====== [2026-02-25T22:19:42.685Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-25T22:19:42.685Z] GC before operation: completed in 99.590 ms, heap usage 203.868 MB -> 69.612 MB. [2026-02-25T22:19:49.382Z] ====== als (apache-spark) [default], iteration 20 completed (6622.580 ms) ====== [2026-02-25T22:19:49.382Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-25T22:19:49.382Z] GC before operation: completed in 96.292 ms, heap usage 389.142 MB -> 70.228 MB. [2026-02-25T22:19:56.240Z] ====== als (apache-spark) [default], iteration 21 completed (6512.329 ms) ====== [2026-02-25T22:19:56.240Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-25T22:19:56.240Z] GC before operation: completed in 96.921 ms, heap usage 377.537 MB -> 70.413 MB. [2026-02-25T22:20:02.959Z] ====== als (apache-spark) [default], iteration 22 completed (6474.543 ms) ====== [2026-02-25T22:20:02.959Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-25T22:20:02.959Z] GC before operation: completed in 95.324 ms, heap usage 431.911 MB -> 70.647 MB. [2026-02-25T22:20:10.591Z] ====== als (apache-spark) [default], iteration 23 completed (6664.373 ms) ====== [2026-02-25T22:20:10.591Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-25T22:20:10.591Z] GC before operation: completed in 93.719 ms, heap usage 353.212 MB -> 70.942 MB. [2026-02-25T22:20:17.270Z] ====== als (apache-spark) [default], iteration 24 completed (6499.747 ms) ====== [2026-02-25T22:20:17.270Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-25T22:20:17.270Z] GC before operation: completed in 90.455 ms, heap usage 620.311 MB -> 71.560 MB. [2026-02-25T22:20:24.072Z] ====== als (apache-spark) [default], iteration 25 completed (6423.821 ms) ====== [2026-02-25T22:20:24.072Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-25T22:20:24.072Z] GC before operation: completed in 95.501 ms, heap usage 588.367 MB -> 71.860 MB. [2026-02-25T22:20:30.779Z] ====== als (apache-spark) [default], iteration 26 completed (6408.174 ms) ====== [2026-02-25T22:20:30.779Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-25T22:20:30.779Z] GC before operation: completed in 91.074 ms, heap usage 298.446 MB -> 71.645 MB. [2026-02-25T22:20:37.103Z] ====== als (apache-spark) [default], iteration 27 completed (6470.070 ms) ====== [2026-02-25T22:20:37.103Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-25T22:20:37.103Z] GC before operation: completed in 97.908 ms, heap usage 521.318 MB -> 72.463 MB. [2026-02-25T22:20:43.792Z] ====== als (apache-spark) [default], iteration 28 completed (6433.443 ms) ====== [2026-02-25T22:20:43.792Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-25T22:20:43.792Z] GC before operation: completed in 92.319 ms, heap usage 751.825 MB -> 72.326 MB. [2026-02-25T22:20:50.405Z] ====== als (apache-spark) [default], iteration 29 completed (6479.009 ms) ====== [2026-02-25T22:20:51.344Z] ----------------------------------- [2026-02-25T22:20:51.344Z] renaissance-als_0_PASSED [2026-02-25T22:20:51.344Z] ----------------------------------- [2026-02-25T22:20:51.344Z] [2026-02-25T22:20:51.344Z] TEST TEARDOWN: [2026-02-25T22:20:51.344Z] Nothing to be done for teardown. [2026-02-25T22:20:51.344Z] renaissance-als_0 Finish Time: Wed Feb 25 22:20:50 2026 Epoch Time (ms): 1772058050408