[2025-11-20T00:32:00.942Z] Running test renaissance-als_0 ... [2025-11-20T00:32:00.942Z] =============================================== [2025-11-20T00:32:00.942Z] renaissance-als_0 Start Time: Thu Nov 20 00:32:00 2025 Epoch Time (ms): 1763598720848 [2025-11-20T00:32:01.709Z] variation: NoOptions [2025-11-20T00:32:01.709Z] JVM_OPTIONS: [2025-11-20T00:32:01.709Z] { \ [2025-11-20T00:32:01.709Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T00:32:01.709Z] echo "Nothing to be done for setup."; \ [2025-11-20T00:32:01.709Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-als_0"; \ [2025-11-20T00:32:01.709Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-als_0"; \ [2025-11-20T00:32:01.709Z] echo ""; echo "TESTING:"; \ [2025-11-20T00:32:01.709Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-als_0"/als.json" als; \ [2025-11-20T00:32:01.709Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T00:32:01.709Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T00:32:01.709Z] echo "Nothing to be done for teardown."; \ [2025-11-20T00:32:01.709Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/TestTargetResult"; [2025-11-20T00:32:01.709Z] [2025-11-20T00:32:01.709Z] TEST SETUP: [2025-11-20T00:32:01.709Z] Nothing to be done for setup. [2025-11-20T00:32:01.709Z] [2025-11-20T00:32:01.709Z] TESTING: [2025-11-20T00:32:09.986Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-11-20T00:32:14.464Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-20T00:32:14.464Z] GC before operation: completed in 65.674 ms, heap usage 250.651 MB -> 34.714 MB. [2025-11-20T00:32:28.206Z] ====== als (apache-spark) [default], iteration 0 completed (12952.750 ms) ====== [2025-11-20T00:32:28.206Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-20T00:32:28.206Z] GC before operation: completed in 121.619 ms, heap usage 980.124 MB -> 62.452 MB. [2025-11-20T00:32:32.660Z] ====== als (apache-spark) [default], iteration 1 completed (4850.059 ms) ====== [2025-11-20T00:32:32.660Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-20T00:32:32.660Z] GC before operation: completed in 112.984 ms, heap usage 506.118 MB -> 63.022 MB. [2025-11-20T00:32:38.417Z] ====== als (apache-spark) [default], iteration 2 completed (4756.278 ms) ====== [2025-11-20T00:32:38.417Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-20T00:32:38.417Z] GC before operation: completed in 109.714 ms, heap usage 612.302 MB -> 63.584 MB. [2025-11-20T00:32:42.880Z] ====== als (apache-spark) [default], iteration 3 completed (4536.831 ms) ====== [2025-11-20T00:32:42.880Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-20T00:32:42.880Z] GC before operation: completed in 129.585 ms, heap usage 1.908 GB -> 65.666 MB. [2025-11-20T00:32:47.330Z] ====== als (apache-spark) [default], iteration 4 completed (4566.707 ms) ====== [2025-11-20T00:32:47.330Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-20T00:32:47.330Z] GC before operation: completed in 121.244 ms, heap usage 486.989 MB -> 64.608 MB. [2025-11-20T00:32:52.919Z] ====== als (apache-spark) [default], iteration 5 completed (4647.521 ms) ====== [2025-11-20T00:32:52.919Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-20T00:32:52.919Z] GC before operation: completed in 119.653 ms, heap usage 1.364 GB -> 66.165 MB. [2025-11-20T00:32:57.371Z] ====== als (apache-spark) [default], iteration 6 completed (4456.048 ms) ====== [2025-11-20T00:32:57.371Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-20T00:32:57.371Z] GC before operation: completed in 121.991 ms, heap usage 1.255 GB -> 66.542 MB. [2025-11-20T00:33:01.815Z] ====== als (apache-spark) [default], iteration 7 completed (4562.747 ms) ====== [2025-11-20T00:33:01.815Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-20T00:33:02.581Z] GC before operation: completed in 128.707 ms, heap usage 182.558 MB -> 65.784 MB. [2025-11-20T00:33:07.044Z] ====== als (apache-spark) [default], iteration 8 completed (4619.431 ms) ====== [2025-11-20T00:33:07.044Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-20T00:33:07.044Z] GC before operation: completed in 124.971 ms, heap usage 567.071 MB -> 66.707 MB. [2025-11-20T00:33:12.652Z] ====== als (apache-spark) [default], iteration 9 completed (4585.640 ms) ====== [2025-11-20T00:33:12.652Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-20T00:33:12.652Z] GC before operation: completed in 129.745 ms, heap usage 193.419 MB -> 66.624 MB. [2025-11-20T00:33:17.116Z] ====== als (apache-spark) [default], iteration 10 completed (4704.236 ms) ====== [2025-11-20T00:33:17.116Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-20T00:33:17.116Z] GC before operation: completed in 127.728 ms, heap usage 500.413 MB -> 67.314 MB. [2025-11-20T00:33:21.575Z] ====== als (apache-spark) [default], iteration 11 completed (4364.236 ms) ====== [2025-11-20T00:33:21.575Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-20T00:33:21.575Z] GC before operation: completed in 131.533 ms, heap usage 513.703 MB -> 67.573 MB. [2025-11-20T00:33:27.197Z] ====== als (apache-spark) [default], iteration 12 completed (4598.142 ms) ====== [2025-11-20T00:33:27.197Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-20T00:33:27.197Z] GC before operation: completed in 134.084 ms, heap usage 365.581 MB -> 67.673 MB. [2025-11-20T00:33:31.651Z] ====== als (apache-spark) [default], iteration 13 completed (4412.130 ms) ====== [2025-11-20T00:33:31.652Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-20T00:33:31.652Z] GC before operation: completed in 118.614 ms, heap usage 180.718 MB -> 68.292 MB. [2025-11-20T00:33:36.112Z] ====== als (apache-spark) [default], iteration 14 completed (4524.841 ms) ====== [2025-11-20T00:33:36.112Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-20T00:33:36.112Z] GC before operation: completed in 124.111 ms, heap usage 521.808 MB -> 68.367 MB. [2025-11-20T00:33:40.748Z] ====== als (apache-spark) [default], iteration 15 completed (4377.157 ms) ====== [2025-11-20T00:33:40.748Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-20T00:33:40.748Z] GC before operation: completed in 132.327 ms, heap usage 1.299 GB -> 69.710 MB. [2025-11-20T00:33:46.372Z] ====== als (apache-spark) [default], iteration 16 completed (4630.207 ms) ====== [2025-11-20T00:33:46.372Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-20T00:33:46.372Z] GC before operation: completed in 124.581 ms, heap usage 1.326 GB -> 69.899 MB. [2025-11-20T00:33:50.832Z] ====== als (apache-spark) [default], iteration 17 completed (4532.008 ms) ====== [2025-11-20T00:33:50.832Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-20T00:33:50.832Z] GC before operation: completed in 130.019 ms, heap usage 392.771 MB -> 68.845 MB. [2025-11-20T00:33:55.373Z] ====== als (apache-spark) [default], iteration 18 completed (4558.866 ms) ====== [2025-11-20T00:33:55.373Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-20T00:33:55.373Z] GC before operation: completed in 129.560 ms, heap usage 396.715 MB -> 69.195 MB. [2025-11-20T00:33:59.849Z] ====== als (apache-spark) [default], iteration 19 completed (4603.123 ms) ====== [2025-11-20T00:33:59.849Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-20T00:34:00.617Z] GC before operation: completed in 133.465 ms, heap usage 494.516 MB -> 69.669 MB. [2025-11-20T00:34:05.069Z] ====== als (apache-spark) [default], iteration 20 completed (4405.919 ms) ====== [2025-11-20T00:34:05.069Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-20T00:34:05.069Z] GC before operation: completed in 127.831 ms, heap usage 295.819 MB -> 69.546 MB. [2025-11-20T00:34:09.529Z] ====== als (apache-spark) [default], iteration 21 completed (4602.465 ms) ====== [2025-11-20T00:34:09.529Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-20T00:34:10.297Z] GC before operation: completed in 136.401 ms, heap usage 200.061 MB -> 69.869 MB. [2025-11-20T00:34:14.762Z] ====== als (apache-spark) [default], iteration 22 completed (4425.983 ms) ====== [2025-11-20T00:34:14.762Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-20T00:34:14.762Z] GC before operation: completed in 142.462 ms, heap usage 1.277 GB -> 71.587 MB. [2025-11-20T00:34:19.257Z] ====== als (apache-spark) [default], iteration 23 completed (4486.191 ms) ====== [2025-11-20T00:34:19.257Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-20T00:34:19.257Z] GC before operation: completed in 129.220 ms, heap usage 305.552 MB -> 70.267 MB. [2025-11-20T00:34:24.873Z] ====== als (apache-spark) [default], iteration 24 completed (4583.992 ms) ====== [2025-11-20T00:34:24.873Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-20T00:34:24.873Z] GC before operation: completed in 130.478 ms, heap usage 514.342 MB -> 70.927 MB. [2025-11-20T00:34:29.324Z] ====== als (apache-spark) [default], iteration 25 completed (4364.542 ms) ====== [2025-11-20T00:34:29.324Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-20T00:34:29.324Z] GC before operation: completed in 132.695 ms, heap usage 703.613 MB -> 71.535 MB. [2025-11-20T00:34:33.773Z] ====== als (apache-spark) [default], iteration 26 completed (4299.540 ms) ====== [2025-11-20T00:34:33.773Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-20T00:34:33.773Z] GC before operation: completed in 135.040 ms, heap usage 771.430 MB -> 71.805 MB. [2025-11-20T00:34:38.235Z] ====== als (apache-spark) [default], iteration 27 completed (4421.070 ms) ====== [2025-11-20T00:34:38.235Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-20T00:34:38.235Z] GC before operation: completed in 133.358 ms, heap usage 646.838 MB -> 72.000 MB. [2025-11-20T00:34:42.725Z] ====== als (apache-spark) [default], iteration 28 completed (4401.313 ms) ====== [2025-11-20T00:34:42.725Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-20T00:34:42.725Z] GC before operation: completed in 133.204 ms, heap usage 1.081 GB -> 72.346 MB. [2025-11-20T00:34:47.168Z] ====== als (apache-spark) [default], iteration 29 completed (4337.933 ms) ====== [2025-11-20T00:34:47.934Z] ----------------------------------- [2025-11-20T00:34:47.934Z] renaissance-als_0_PASSED [2025-11-20T00:34:47.934Z] ----------------------------------- [2025-11-20T00:34:47.934Z] [2025-11-20T00:34:47.934Z] TEST TEARDOWN: [2025-11-20T00:34:47.934Z] Nothing to be done for teardown. [2025-11-20T00:34:47.934Z] renaissance-als_0 Finish Time: Thu Nov 20 00:34:47 2025 Epoch Time (ms): 1763598887645