[2025-10-03T20:55:38.587Z] Running test renaissance-als_0 ... [2025-10-03T20:55:38.587Z] =============================================== [2025-10-03T20:55:38.587Z] renaissance-als_0 Start Time: Fri Oct 3 20:55:38 2025 Epoch Time (ms): 1759524938212 [2025-10-03T20:55:38.587Z] variation: NoOptions [2025-10-03T20:55:38.587Z] JVM_OPTIONS: [2025-10-03T20:55:38.587Z] { \ [2025-10-03T20:55:38.587Z] echo ""; echo "TEST SETUP:"; \ [2025-10-03T20:55:38.587Z] echo "Nothing to be done for setup."; \ [2025-10-03T20:55:38.587Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17595249389101/renaissance-als_0"; \ [2025-10-03T20:55:38.587Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17595249389101/renaissance-als_0"; \ [2025-10-03T20:55:38.587Z] echo ""; echo "TESTING:"; \ [2025-10-03T20:55:38.587Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17595249389101/renaissance-als_0"/als.json" als; \ [2025-10-03T20:55:38.587Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17595249389101/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-03T20:55:38.587Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-03T20:55:38.587Z] echo "Nothing to be done for teardown."; \ [2025-10-03T20:55:38.587Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17595249389101/TestTargetResult"; [2025-10-03T20:55:38.587Z] [2025-10-03T20:55:38.587Z] TEST SETUP: [2025-10-03T20:55:38.587Z] Nothing to be done for setup. [2025-10-03T20:55:38.587Z] [2025-10-03T20:55:38.587Z] TESTING: [2025-10-03T20:55:45.312Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-03T20:55:50.829Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-03T20:55:50.829Z] GC before operation: completed in 49.683 ms, heap usage 215.500 MB -> 35.215 MB. [2025-10-03T20:56:09.216Z] ====== als (apache-spark) [default], iteration 0 completed (17859.645 ms) ====== [2025-10-03T20:56:09.216Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-03T20:56:09.216Z] GC before operation: completed in 92.229 ms, heap usage 259.608 MB -> 62.279 MB. [2025-10-03T20:56:17.415Z] ====== als (apache-spark) [default], iteration 1 completed (7955.209 ms) ====== [2025-10-03T20:56:17.415Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-03T20:56:17.415Z] GC before operation: completed in 75.665 ms, heap usage 404.254 MB -> 63.129 MB. [2025-10-03T20:56:25.711Z] ====== als (apache-spark) [default], iteration 2 completed (7289.813 ms) ====== [2025-10-03T20:56:25.711Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-03T20:56:25.711Z] GC before operation: completed in 113.152 ms, heap usage 471.137 MB -> 63.896 MB. [2025-10-03T20:56:32.459Z] ====== als (apache-spark) [default], iteration 3 completed (6888.400 ms) ====== [2025-10-03T20:56:32.459Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-03T20:56:32.459Z] GC before operation: completed in 73.903 ms, heap usage 270.749 MB -> 64.203 MB. [2025-10-03T20:56:39.283Z] ====== als (apache-spark) [default], iteration 4 completed (6484.572 ms) ====== [2025-10-03T20:56:39.283Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-03T20:56:39.283Z] GC before operation: completed in 81.045 ms, heap usage 342.406 MB -> 65.020 MB. [2025-10-03T20:56:46.035Z] ====== als (apache-spark) [default], iteration 5 completed (6717.377 ms) ====== [2025-10-03T20:56:46.035Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-03T20:56:46.035Z] GC before operation: completed in 96.967 ms, heap usage 97.843 MB -> 64.844 MB. [2025-10-03T20:56:54.248Z] ====== als (apache-spark) [default], iteration 6 completed (7081.776 ms) ====== [2025-10-03T20:56:54.248Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-03T20:56:54.248Z] GC before operation: completed in 78.830 ms, heap usage 107.879 MB -> 66.346 MB. [2025-10-03T20:57:01.000Z] ====== als (apache-spark) [default], iteration 7 completed (7043.671 ms) ====== [2025-10-03T20:57:01.000Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-03T20:57:01.000Z] GC before operation: completed in 87.881 ms, heap usage 213.279 MB -> 66.411 MB. [2025-10-03T20:57:07.739Z] ====== als (apache-spark) [default], iteration 8 completed (6573.262 ms) ====== [2025-10-03T20:57:07.739Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-03T20:57:07.739Z] GC before operation: completed in 96.948 ms, heap usage 356.632 MB -> 67.110 MB. [2025-10-03T20:57:14.494Z] ====== als (apache-spark) [default], iteration 9 completed (6441.877 ms) ====== [2025-10-03T20:57:14.494Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-03T20:57:14.494Z] GC before operation: completed in 112.189 ms, heap usage 96.074 MB -> 67.239 MB. [2025-10-03T20:57:21.234Z] ====== als (apache-spark) [default], iteration 10 completed (6448.351 ms) ====== [2025-10-03T20:57:21.234Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-03T20:57:21.234Z] GC before operation: completed in 86.378 ms, heap usage 409.630 MB -> 67.509 MB. [2025-10-03T20:57:28.872Z] ====== als (apache-spark) [default], iteration 11 completed (6694.543 ms) ====== [2025-10-03T20:57:28.872Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-03T20:57:28.872Z] GC before operation: completed in 101.191 ms, heap usage 101.056 MB -> 67.159 MB. [2025-10-03T20:57:35.627Z] ====== als (apache-spark) [default], iteration 12 completed (6576.245 ms) ====== [2025-10-03T20:57:35.627Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-03T20:57:35.627Z] GC before operation: completed in 116.897 ms, heap usage 308.407 MB -> 68.025 MB. [2025-10-03T20:57:42.371Z] ====== als (apache-spark) [default], iteration 13 completed (6545.051 ms) ====== [2025-10-03T20:57:42.371Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-03T20:57:42.371Z] GC before operation: completed in 91.828 ms, heap usage 190.700 MB -> 68.021 MB. [2025-10-03T20:57:49.171Z] ====== als (apache-spark) [default], iteration 14 completed (6533.083 ms) ====== [2025-10-03T20:57:49.171Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-03T20:57:49.171Z] GC before operation: completed in 92.558 ms, heap usage 460.327 MB -> 68.791 MB. [2025-10-03T20:57:56.219Z] ====== als (apache-spark) [default], iteration 15 completed (6417.093 ms) ====== [2025-10-03T20:57:56.219Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-03T20:57:56.219Z] GC before operation: completed in 82.594 ms, heap usage 541.580 MB -> 69.173 MB. [2025-10-03T20:58:03.031Z] ====== als (apache-spark) [default], iteration 16 completed (6391.072 ms) ====== [2025-10-03T20:58:03.031Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-03T20:58:03.031Z] GC before operation: completed in 97.254 ms, heap usage 301.874 MB -> 68.995 MB. [2025-10-03T20:58:09.783Z] ====== als (apache-spark) [default], iteration 17 completed (6524.291 ms) ====== [2025-10-03T20:58:09.783Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-03T20:58:09.783Z] GC before operation: completed in 102.382 ms, heap usage 215.982 MB -> 69.138 MB. [2025-10-03T20:58:16.619Z] ====== als (apache-spark) [default], iteration 18 completed (6646.029 ms) ====== [2025-10-03T20:58:16.619Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-03T20:58:16.619Z] GC before operation: completed in 106.312 ms, heap usage 118.648 MB -> 69.383 MB. [2025-10-03T20:58:23.396Z] ====== als (apache-spark) [default], iteration 19 completed (6516.849 ms) ====== [2025-10-03T20:58:23.396Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-03T20:58:23.396Z] GC before operation: completed in 97.109 ms, heap usage 440.542 MB -> 70.142 MB. [2025-10-03T20:58:30.155Z] ====== als (apache-spark) [default], iteration 20 completed (6805.840 ms) ====== [2025-10-03T20:58:30.155Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-03T20:58:30.155Z] GC before operation: completed in 97.681 ms, heap usage 481.149 MB -> 70.339 MB. [2025-10-03T20:58:36.903Z] ====== als (apache-spark) [default], iteration 21 completed (6313.861 ms) ====== [2025-10-03T20:58:36.903Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-03T20:58:36.903Z] GC before operation: completed in 92.817 ms, heap usage 463.918 MB -> 70.621 MB. [2025-10-03T20:58:43.641Z] ====== als (apache-spark) [default], iteration 22 completed (5976.007 ms) ====== [2025-10-03T20:58:43.641Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-03T20:58:43.641Z] GC before operation: completed in 96.494 ms, heap usage 242.117 MB -> 70.704 MB. [2025-10-03T20:58:50.383Z] ====== als (apache-spark) [default], iteration 23 completed (6377.880 ms) ====== [2025-10-03T20:58:50.383Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-03T20:58:50.383Z] GC before operation: completed in 110.331 ms, heap usage 401.967 MB -> 71.024 MB. [2025-10-03T20:58:57.123Z] ====== als (apache-spark) [default], iteration 24 completed (6419.267 ms) ====== [2025-10-03T20:58:57.123Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-03T20:58:57.123Z] GC before operation: completed in 111.471 ms, heap usage 498.323 MB -> 71.399 MB. [2025-10-03T20:59:03.859Z] ====== als (apache-spark) [default], iteration 25 completed (6506.196 ms) ====== [2025-10-03T20:59:03.859Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-03T20:59:03.859Z] GC before operation: completed in 103.424 ms, heap usage 335.411 MB -> 71.457 MB. [2025-10-03T20:59:10.597Z] ====== als (apache-spark) [default], iteration 26 completed (6686.577 ms) ====== [2025-10-03T20:59:10.597Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-03T20:59:10.597Z] GC before operation: completed in 87.303 ms, heap usage 561.519 MB -> 72.106 MB. [2025-10-03T20:59:17.335Z] ====== als (apache-spark) [default], iteration 27 completed (6549.513 ms) ====== [2025-10-03T20:59:17.335Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-03T20:59:17.335Z] GC before operation: completed in 101.848 ms, heap usage 442.914 MB -> 71.803 MB. [2025-10-03T20:59:25.079Z] ====== als (apache-spark) [default], iteration 28 completed (6438.587 ms) ====== [2025-10-03T20:59:25.079Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-03T20:59:25.079Z] GC before operation: completed in 89.062 ms, heap usage 200.242 MB -> 71.586 MB. [2025-10-03T20:59:30.507Z] ====== als (apache-spark) [default], iteration 29 completed (6342.144 ms) ====== [2025-10-03T20:59:30.507Z] ----------------------------------- [2025-10-03T20:59:30.507Z] renaissance-als_0_PASSED [2025-10-03T20:59:30.507Z] ----------------------------------- [2025-10-03T20:59:30.507Z] [2025-10-03T20:59:30.507Z] TEST TEARDOWN: [2025-10-03T20:59:30.507Z] Nothing to be done for teardown. [2025-10-03T20:59:30.507Z] renaissance-als_0 Finish Time: Fri Oct 3 20:59:30 2025 Epoch Time (ms): 1759525170243