[2025-07-02T14:05:00.473Z] Running test renaissance-als_0 ... [2025-07-02T14:05:00.473Z] =============================================== [2025-07-02T14:05:00.473Z] renaissance-als_0 Start Time: Wed Jul 2 14:05:00 2025 Epoch Time (ms): 1751465100091 [2025-07-02T14:05:00.473Z] variation: NoOptions [2025-07-02T14:05:00.473Z] JVM_OPTIONS: [2025-07-02T14:05:00.473Z] { \ [2025-07-02T14:05:00.473Z] echo ""; echo "TEST SETUP:"; \ [2025-07-02T14:05:00.473Z] echo "Nothing to be done for setup."; \ [2025-07-02T14:05:00.473Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514649883512/renaissance-als_0"; \ [2025-07-02T14:05:00.473Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514649883512/renaissance-als_0"; \ [2025-07-02T14:05:00.473Z] echo ""; echo "TESTING:"; \ [2025-07-02T14:05:00.473Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/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_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514649883512/renaissance-als_0"/als.json" als; \ [2025-07-02T14:05:00.473Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514649883512/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-02T14:05:00.473Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-02T14:05:00.473Z] echo "Nothing to be done for teardown."; \ [2025-07-02T14:05:00.473Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514649883512/TestTargetResult"; [2025-07-02T14:05:00.473Z] [2025-07-02T14:05:00.473Z] TEST SETUP: [2025-07-02T14:05:00.473Z] Nothing to be done for setup. [2025-07-02T14:05:00.473Z] [2025-07-02T14:05:00.473Z] TESTING: [2025-07-02T14:05:35.775Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-07-02T14:05:38.253Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-02T14:05:38.253Z] GC before operation: completed in 68.039 ms, heap usage 315.068 MB -> 34.371 MB. [2025-07-02T14:06:01.880Z] ====== als (apache-spark) [default], iteration 0 completed (22107.672 ms) ====== [2025-07-02T14:06:01.880Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-02T14:06:01.880Z] GC before operation: completed in 128.767 ms, heap usage 1.114 GB -> 62.351 MB. [2025-07-02T14:06:20.791Z] ====== als (apache-spark) [default], iteration 1 completed (18708.161 ms) ====== [2025-07-02T14:06:20.791Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-02T14:06:20.791Z] GC before operation: completed in 105.881 ms, heap usage 716.958 MB -> 63.006 MB. [2025-07-02T14:06:34.581Z] ====== als (apache-spark) [default], iteration 2 completed (13684.572 ms) ====== [2025-07-02T14:06:34.581Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-02T14:06:34.581Z] GC before operation: completed in 105.290 ms, heap usage 1.132 GB -> 63.963 MB. [2025-07-02T14:06:51.009Z] ====== als (apache-spark) [default], iteration 3 completed (16426.880 ms) ====== [2025-07-02T14:06:51.009Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-02T14:06:51.009Z] GC before operation: completed in 141.600 ms, heap usage 933.942 MB -> 64.383 MB. [2025-07-02T14:07:07.180Z] ====== als (apache-spark) [default], iteration 4 completed (14924.346 ms) ====== [2025-07-02T14:07:07.180Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-02T14:07:07.180Z] GC before operation: completed in 109.204 ms, heap usage 933.783 MB -> 65.118 MB. [2025-07-02T14:07:26.073Z] ====== als (apache-spark) [default], iteration 5 completed (18355.262 ms) ====== [2025-07-02T14:07:26.073Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-02T14:07:26.073Z] GC before operation: completed in 159.918 ms, heap usage 1.003 GB -> 65.784 MB. [2025-07-02T14:07:39.883Z] ====== als (apache-spark) [default], iteration 6 completed (13764.380 ms) ====== [2025-07-02T14:07:39.883Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-02T14:07:39.883Z] GC before operation: completed in 123.023 ms, heap usage 959.587 MB -> 66.294 MB. [2025-07-02T14:07:58.803Z] ====== als (apache-spark) [default], iteration 7 completed (17373.899 ms) ====== [2025-07-02T14:07:58.803Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-02T14:07:58.803Z] GC before operation: completed in 118.546 ms, heap usage 744.204 MB -> 66.595 MB. [2025-07-02T14:08:12.833Z] ====== als (apache-spark) [default], iteration 8 completed (15492.940 ms) ====== [2025-07-02T14:08:12.833Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-02T14:08:12.833Z] GC before operation: completed in 121.550 ms, heap usage 269.992 MB -> 66.508 MB. [2025-07-02T14:08:29.069Z] ====== als (apache-spark) [default], iteration 9 completed (15937.417 ms) ====== [2025-07-02T14:08:29.069Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-02T14:08:29.069Z] GC before operation: completed in 152.009 ms, heap usage 751.625 MB -> 67.099 MB. [2025-07-02T14:08:48.021Z] ====== als (apache-spark) [default], iteration 10 completed (18261.758 ms) ====== [2025-07-02T14:08:48.021Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-02T14:08:48.021Z] GC before operation: completed in 121.261 ms, heap usage 616.146 MB -> 67.232 MB. [2025-07-02T14:09:01.801Z] ====== als (apache-spark) [default], iteration 11 completed (13656.917 ms) ====== [2025-07-02T14:09:01.801Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-02T14:09:01.801Z] GC before operation: completed in 131.239 ms, heap usage 1.246 GB -> 68.041 MB. [2025-07-02T14:09:23.971Z] ====== als (apache-spark) [default], iteration 12 completed (19566.961 ms) ====== [2025-07-02T14:09:23.971Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-02T14:09:23.971Z] GC before operation: completed in 136.469 ms, heap usage 425.842 MB -> 67.409 MB. [2025-07-02T14:09:37.868Z] ====== als (apache-spark) [default], iteration 13 completed (14839.277 ms) ====== [2025-07-02T14:09:37.868Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-02T14:09:37.868Z] GC before operation: completed in 129.354 ms, heap usage 487.324 MB -> 67.862 MB. [2025-07-02T14:09:56.775Z] ====== als (apache-spark) [default], iteration 14 completed (18252.949 ms) ====== [2025-07-02T14:09:56.775Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-02T14:09:56.775Z] GC before operation: completed in 122.379 ms, heap usage 214.210 MB -> 67.820 MB. [2025-07-02T14:10:13.332Z] ====== als (apache-spark) [default], iteration 15 completed (15958.972 ms) ====== [2025-07-02T14:10:13.332Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-02T14:10:13.332Z] GC before operation: completed in 141.147 ms, heap usage 612.138 MB -> 68.596 MB. [2025-07-02T14:10:29.486Z] ====== als (apache-spark) [default], iteration 16 completed (17212.530 ms) ====== [2025-07-02T14:10:29.486Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-02T14:10:29.486Z] GC before operation: completed in 126.223 ms, heap usage 1.182 GB -> 69.701 MB. [2025-07-02T14:10:43.277Z] ====== als (apache-spark) [default], iteration 17 completed (13220.768 ms) ====== [2025-07-02T14:10:43.277Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-02T14:10:43.277Z] GC before operation: completed in 140.296 ms, heap usage 494.965 MB -> 69.321 MB. [2025-07-02T14:11:02.195Z] ====== als (apache-spark) [default], iteration 18 completed (17865.541 ms) ====== [2025-07-02T14:11:02.195Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-02T14:11:02.195Z] GC before operation: completed in 148.594 ms, heap usage 930.336 MB -> 69.799 MB. [2025-07-02T14:11:18.374Z] ====== als (apache-spark) [default], iteration 19 completed (16236.785 ms) ====== [2025-07-02T14:11:18.374Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-02T14:11:18.374Z] GC before operation: completed in 129.701 ms, heap usage 618.645 MB -> 69.802 MB. [2025-07-02T14:11:34.609Z] ====== als (apache-spark) [default], iteration 20 completed (15059.481 ms) ====== [2025-07-02T14:11:34.609Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-02T14:11:34.609Z] GC before operation: completed in 145.471 ms, heap usage 1.028 GB -> 70.368 MB. [2025-07-02T14:11:46.587Z] ====== als (apache-spark) [default], iteration 21 completed (13171.001 ms) ====== [2025-07-02T14:11:46.587Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-02T14:11:46.587Z] GC before operation: completed in 148.550 ms, heap usage 985.166 MB -> 70.787 MB. [2025-07-02T14:12:00.447Z] ====== als (apache-spark) [default], iteration 22 completed (13271.476 ms) ====== [2025-07-02T14:12:00.447Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-02T14:12:00.447Z] GC before operation: completed in 136.199 ms, heap usage 966.299 MB -> 71.071 MB. [2025-07-02T14:12:14.307Z] ====== als (apache-spark) [default], iteration 23 completed (14283.231 ms) ====== [2025-07-02T14:12:14.307Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-02T14:12:14.307Z] GC before operation: completed in 132.927 ms, heap usage 681.511 MB -> 70.947 MB. [2025-07-02T14:12:28.102Z] ====== als (apache-spark) [default], iteration 24 completed (13019.048 ms) ====== [2025-07-02T14:12:28.102Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-02T14:12:28.102Z] GC before operation: completed in 122.875 ms, heap usage 498.241 MB -> 70.995 MB. [2025-07-02T14:12:41.891Z] ====== als (apache-spark) [default], iteration 25 completed (12857.082 ms) ====== [2025-07-02T14:12:41.891Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-02T14:12:41.891Z] GC before operation: completed in 126.609 ms, heap usage 231.193 MB -> 70.772 MB. [2025-07-02T14:12:55.689Z] ====== als (apache-spark) [default], iteration 26 completed (13080.409 ms) ====== [2025-07-02T14:12:55.689Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-02T14:12:55.689Z] GC before operation: completed in 127.904 ms, heap usage 255.669 MB -> 71.149 MB. [2025-07-02T14:13:09.463Z] ====== als (apache-spark) [default], iteration 27 completed (13507.684 ms) ====== [2025-07-02T14:13:09.463Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-02T14:13:09.463Z] GC before operation: completed in 127.948 ms, heap usage 210.332 MB -> 71.449 MB. [2025-07-02T14:13:23.619Z] ====== als (apache-spark) [default], iteration 28 completed (13885.636 ms) ====== [2025-07-02T14:13:23.619Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-02T14:13:23.619Z] GC before operation: completed in 139.388 ms, heap usage 487.992 MB -> 71.884 MB. [2025-07-02T14:13:37.435Z] ====== als (apache-spark) [default], iteration 29 completed (14893.286 ms) ====== [2025-07-02T14:13:38.208Z] ----------------------------------- [2025-07-02T14:13:38.208Z] renaissance-als_0_PASSED [2025-07-02T14:13:38.208Z] ----------------------------------- [2025-07-02T14:13:38.208Z] [2025-07-02T14:13:38.208Z] TEST TEARDOWN: [2025-07-02T14:13:38.208Z] Nothing to be done for teardown. [2025-07-02T14:13:38.209Z] renaissance-als_0 Finish Time: Wed Jul 2 14:13:37 2025 Epoch Time (ms): 1751465617454