[2025-12-25T00:11:27.234Z] Running test renaissance-als_0 ... [2025-12-25T00:11:27.234Z] =============================================== [2025-12-25T00:11:27.234Z] renaissance-als_0 Start Time: Thu Dec 25 00:11:26 2025 Epoch Time (ms): 1766621486721 [2025-12-25T00:11:27.234Z] variation: NoOptions [2025-12-25T00:11:27.234Z] JVM_OPTIONS: [2025-12-25T00:11:27.234Z] { \ [2025-12-25T00:11:27.234Z] echo ""; echo "TEST SETUP:"; \ [2025-12-25T00:11:27.234Z] echo "Nothing to be done for setup."; \ [2025-12-25T00:11:27.234Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666213189860/renaissance-als_0"; \ [2025-12-25T00:11:27.234Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666213189860/renaissance-als_0"; \ [2025-12-25T00:11:27.234Z] echo ""; echo "TESTING:"; \ [2025-12-25T00:11:27.234Z] "/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_17666213189860/renaissance-als_0"/als.json" als; \ [2025-12-25T00:11:27.234Z] 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_17666213189860/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-25T00:11:27.234Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-25T00:11:27.234Z] echo "Nothing to be done for teardown."; \ [2025-12-25T00:11:27.234Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666213189860/TestTargetResult"; [2025-12-25T00:11:27.234Z] [2025-12-25T00:11:27.234Z] TEST SETUP: [2025-12-25T00:11:27.234Z] Nothing to be done for setup. [2025-12-25T00:11:27.234Z] [2025-12-25T00:11:27.234Z] TESTING: [2025-12-25T00:11:32.683Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-25T00:11:36.882Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-25T00:11:36.882Z] GC before operation: completed in 39.459 ms, heap usage 98.000 MB -> 35.121 MB. [2025-12-25T00:11:55.355Z] ====== als (apache-spark) [default], iteration 0 completed (16679.610 ms) ====== [2025-12-25T00:11:55.355Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-25T00:11:55.355Z] GC before operation: completed in 90.376 ms, heap usage 133.280 MB -> 62.034 MB. [2025-12-25T00:12:02.077Z] ====== als (apache-spark) [default], iteration 1 completed (7868.719 ms) ====== [2025-12-25T00:12:02.077Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-25T00:12:02.077Z] GC before operation: completed in 81.706 ms, heap usage 364.027 MB -> 63.067 MB. [2025-12-25T00:12:10.940Z] ====== als (apache-spark) [default], iteration 2 completed (7651.026 ms) ====== [2025-12-25T00:12:10.940Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-25T00:12:10.940Z] GC before operation: completed in 81.797 ms, heap usage 248.081 MB -> 63.390 MB. [2025-12-25T00:12:17.927Z] ====== als (apache-spark) [default], iteration 3 completed (7906.653 ms) ====== [2025-12-25T00:12:17.927Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-25T00:12:18.882Z] GC before operation: completed in 82.570 ms, heap usage 379.556 MB -> 64.037 MB. [2025-12-25T00:12:27.048Z] ====== als (apache-spark) [default], iteration 4 completed (7428.657 ms) ====== [2025-12-25T00:12:27.048Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-25T00:12:27.048Z] GC before operation: completed in 83.845 ms, heap usage 310.143 MB -> 64.621 MB. [2025-12-25T00:12:33.804Z] ====== als (apache-spark) [default], iteration 5 completed (7142.025 ms) ====== [2025-12-25T00:12:33.804Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-25T00:12:33.804Z] GC before operation: completed in 125.510 ms, heap usage 158.909 MB -> 65.117 MB. [2025-12-25T00:12:41.978Z] ====== als (apache-spark) [default], iteration 6 completed (7934.376 ms) ====== [2025-12-25T00:12:41.978Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-25T00:12:41.978Z] GC before operation: completed in 87.534 ms, heap usage 176.368 MB -> 65.739 MB. [2025-12-25T00:12:50.158Z] ====== als (apache-spark) [default], iteration 7 completed (6964.206 ms) ====== [2025-12-25T00:12:50.158Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-25T00:12:50.159Z] GC before operation: completed in 94.362 ms, heap usage 199.737 MB -> 66.353 MB. [2025-12-25T00:12:56.318Z] ====== als (apache-spark) [default], iteration 8 completed (6983.330 ms) ====== [2025-12-25T00:12:56.318Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-25T00:12:56.318Z] GC before operation: completed in 92.532 ms, heap usage 296.400 MB -> 66.915 MB. [2025-12-25T00:13:04.485Z] ====== als (apache-spark) [default], iteration 9 completed (7021.943 ms) ====== [2025-12-25T00:13:04.485Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-25T00:13:04.485Z] GC before operation: completed in 95.822 ms, heap usage 246.062 MB -> 67.013 MB. [2025-12-25T00:13:11.208Z] ====== als (apache-spark) [default], iteration 10 completed (7033.192 ms) ====== [2025-12-25T00:13:11.208Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-25T00:13:11.208Z] GC before operation: completed in 101.717 ms, heap usage 450.252 MB -> 67.596 MB. [2025-12-25T00:13:19.556Z] ====== als (apache-spark) [default], iteration 11 completed (7165.185 ms) ====== [2025-12-25T00:13:19.556Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-25T00:13:19.556Z] GC before operation: completed in 95.822 ms, heap usage 175.049 MB -> 67.535 MB. [2025-12-25T00:13:26.315Z] ====== als (apache-spark) [default], iteration 12 completed (7097.071 ms) ====== [2025-12-25T00:13:26.315Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-25T00:13:26.315Z] GC before operation: completed in 101.737 ms, heap usage 522.731 MB -> 68.130 MB. [2025-12-25T00:13:34.491Z] ====== als (apache-spark) [default], iteration 13 completed (7159.750 ms) ====== [2025-12-25T00:13:34.491Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-25T00:13:34.491Z] GC before operation: completed in 104.803 ms, heap usage 639.672 MB -> 68.460 MB. [2025-12-25T00:13:41.395Z] ====== als (apache-spark) [default], iteration 14 completed (7718.805 ms) ====== [2025-12-25T00:13:41.395Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-25T00:13:41.395Z] GC before operation: completed in 100.190 ms, heap usage 192.080 MB -> 68.458 MB. [2025-12-25T00:13:49.581Z] ====== als (apache-spark) [default], iteration 15 completed (6842.533 ms) ====== [2025-12-25T00:13:49.581Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-25T00:13:49.581Z] GC before operation: completed in 100.474 ms, heap usage 294.918 MB -> 68.639 MB. [2025-12-25T00:13:56.303Z] ====== als (apache-spark) [default], iteration 16 completed (6866.012 ms) ====== [2025-12-25T00:13:56.303Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-25T00:13:56.303Z] GC before operation: completed in 97.054 ms, heap usage 187.221 MB -> 68.895 MB. [2025-12-25T00:14:03.129Z] ====== als (apache-spark) [default], iteration 17 completed (6799.733 ms) ====== [2025-12-25T00:14:03.129Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-25T00:14:03.129Z] GC before operation: completed in 96.254 ms, heap usage 156.072 MB -> 69.024 MB. [2025-12-25T00:14:09.864Z] ====== als (apache-spark) [default], iteration 18 completed (6989.471 ms) ====== [2025-12-25T00:14:09.864Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-25T00:14:10.829Z] GC before operation: completed in 95.009 ms, heap usage 644.671 MB -> 69.839 MB. [2025-12-25T00:14:17.564Z] ====== als (apache-spark) [default], iteration 19 completed (6938.269 ms) ====== [2025-12-25T00:14:17.564Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-25T00:14:17.564Z] GC before operation: completed in 97.210 ms, heap usage 217.354 MB -> 69.641 MB. [2025-12-25T00:14:26.484Z] ====== als (apache-spark) [default], iteration 20 completed (8210.450 ms) ====== [2025-12-25T00:14:26.485Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-25T00:14:26.485Z] GC before operation: completed in 97.123 ms, heap usage 360.223 MB -> 70.089 MB. [2025-12-25T00:14:33.210Z] ====== als (apache-spark) [default], iteration 21 completed (6771.883 ms) ====== [2025-12-25T00:14:33.210Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-25T00:14:33.210Z] GC before operation: completed in 106.791 ms, heap usage 342.140 MB -> 70.382 MB. [2025-12-25T00:14:39.929Z] ====== als (apache-spark) [default], iteration 22 completed (6827.192 ms) ====== [2025-12-25T00:14:39.929Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-25T00:14:39.929Z] GC before operation: completed in 100.561 ms, heap usage 275.947 MB -> 70.602 MB. [2025-12-25T00:14:48.116Z] ====== als (apache-spark) [default], iteration 23 completed (7115.543 ms) ====== [2025-12-25T00:14:48.116Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-25T00:14:48.116Z] GC before operation: completed in 93.008 ms, heap usage 144.899 MB -> 70.576 MB. [2025-12-25T00:14:54.839Z] ====== als (apache-spark) [default], iteration 24 completed (6670.290 ms) ====== [2025-12-25T00:14:54.839Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-25T00:14:54.839Z] GC before operation: completed in 93.196 ms, heap usage 191.822 MB -> 71.113 MB. [2025-12-25T00:15:01.584Z] ====== als (apache-spark) [default], iteration 25 completed (6874.461 ms) ====== [2025-12-25T00:15:01.584Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-25T00:15:01.584Z] GC before operation: completed in 97.084 ms, heap usage 230.115 MB -> 71.291 MB. [2025-12-25T00:15:09.766Z] ====== als (apache-spark) [default], iteration 26 completed (6810.865 ms) ====== [2025-12-25T00:15:09.766Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-25T00:15:09.766Z] GC before operation: completed in 93.245 ms, heap usage 131.255 MB -> 71.378 MB. [2025-12-25T00:15:16.596Z] ====== als (apache-spark) [default], iteration 27 completed (6850.145 ms) ====== [2025-12-25T00:15:16.596Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-25T00:15:16.596Z] GC before operation: completed in 95.503 ms, heap usage 278.269 MB -> 71.809 MB. [2025-12-25T00:15:23.326Z] ====== als (apache-spark) [default], iteration 28 completed (6892.739 ms) ====== [2025-12-25T00:15:23.326Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-25T00:15:23.326Z] GC before operation: completed in 97.931 ms, heap usage 204.580 MB -> 71.553 MB. [2025-12-25T00:15:30.053Z] ====== als (apache-spark) [default], iteration 29 completed (6775.691 ms) ====== [2025-12-25T00:15:31.010Z] ----------------------------------- [2025-12-25T00:15:31.010Z] renaissance-als_0_PASSED [2025-12-25T00:15:31.010Z] ----------------------------------- [2025-12-25T00:15:31.010Z] [2025-12-25T00:15:31.010Z] TEST TEARDOWN: [2025-12-25T00:15:31.010Z] Nothing to be done for teardown. [2025-12-25T00:15:31.010Z] renaissance-als_0 Finish Time: Thu Dec 25 00:15:30 2025 Epoch Time (ms): 1766621730165