[2025-08-29T09:05:28.521Z] Running test renaissance-als_0 ... [2025-08-29T09:05:28.521Z] =============================================== [2025-08-29T09:05:28.521Z] renaissance-als_0 Start Time: Fri Aug 29 09:05:27 2025 Epoch Time (ms): 1756458327172 [2025-08-29T09:05:28.521Z] variation: NoOptions [2025-08-29T09:05:28.521Z] JVM_OPTIONS: [2025-08-29T09:05:28.521Z] { \ [2025-08-29T09:05:28.521Z] echo ""; echo "TEST SETUP:"; \ [2025-08-29T09:05:28.521Z] echo "Nothing to be done for setup."; \ [2025-08-29T09:05:28.521Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17564581767989/renaissance-als_0"; \ [2025-08-29T09:05:28.521Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17564581767989/renaissance-als_0"; \ [2025-08-29T09:05:28.521Z] echo ""; echo "TESTING:"; \ [2025-08-29T09:05:28.521Z] "/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_17564581767989/renaissance-als_0"/als.json" als; \ [2025-08-29T09:05:28.521Z] 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_17564581767989/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-29T09:05:28.521Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-29T09:05:28.521Z] echo "Nothing to be done for teardown."; \ [2025-08-29T09:05:28.521Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17564581767989/TestTargetResult"; [2025-08-29T09:05:28.521Z] [2025-08-29T09:05:28.521Z] TEST SETUP: [2025-08-29T09:05:28.521Z] Nothing to be done for setup. [2025-08-29T09:05:28.521Z] [2025-08-29T09:05:28.521Z] TESTING: [2025-08-29T09:05:32.699Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-29T09:05:36.870Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-29T09:05:36.870Z] GC before operation: completed in 52.423 ms, heap usage 162.000 MB -> 35.175 MB. [2025-08-29T09:05:55.250Z] ====== als (apache-spark) [default], iteration 0 completed (17166.455 ms) ====== [2025-08-29T09:05:55.250Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-29T09:05:55.250Z] GC before operation: completed in 109.134 ms, heap usage 308.716 MB -> 61.998 MB. [2025-08-29T09:06:03.463Z] ====== als (apache-spark) [default], iteration 1 completed (7459.610 ms) ====== [2025-08-29T09:06:03.463Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-29T09:06:03.463Z] GC before operation: completed in 71.432 ms, heap usage 224.290 MB -> 62.840 MB. [2025-08-29T09:06:10.195Z] ====== als (apache-spark) [default], iteration 2 completed (6957.496 ms) ====== [2025-08-29T09:06:10.195Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-29T09:06:10.195Z] GC before operation: completed in 70.571 ms, heap usage 360.291 MB -> 63.508 MB. [2025-08-29T09:06:16.936Z] ====== als (apache-spark) [default], iteration 3 completed (6831.838 ms) ====== [2025-08-29T09:06:16.936Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-29T09:06:16.936Z] GC before operation: completed in 68.742 ms, heap usage 363.050 MB -> 64.136 MB. [2025-08-29T09:06:23.676Z] ====== als (apache-spark) [default], iteration 4 completed (6605.517 ms) ====== [2025-08-29T09:06:23.676Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-29T09:06:23.676Z] GC before operation: completed in 71.490 ms, heap usage 357.490 MB -> 64.719 MB. [2025-08-29T09:06:30.412Z] ====== als (apache-spark) [default], iteration 5 completed (6525.608 ms) ====== [2025-08-29T09:06:30.412Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-29T09:06:32.427Z] GC before operation: completed in 84.110 ms, heap usage 514.106 MB -> 65.424 MB. [2025-08-29T09:06:37.831Z] ====== als (apache-spark) [default], iteration 6 completed (6593.308 ms) ====== [2025-08-29T09:06:37.831Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-29T09:06:37.831Z] GC before operation: completed in 73.610 ms, heap usage 400.240 MB -> 65.798 MB. [2025-08-29T09:06:44.562Z] ====== als (apache-spark) [default], iteration 7 completed (6504.155 ms) ====== [2025-08-29T09:06:44.562Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-29T09:06:44.562Z] GC before operation: completed in 76.787 ms, heap usage 448.271 MB -> 66.527 MB. [2025-08-29T09:06:51.292Z] ====== als (apache-spark) [default], iteration 8 completed (6514.886 ms) ====== [2025-08-29T09:06:51.292Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-29T09:06:51.292Z] GC before operation: completed in 105.040 ms, heap usage 521.180 MB -> 67.124 MB. [2025-08-29T09:06:58.012Z] ====== als (apache-spark) [default], iteration 9 completed (6505.807 ms) ====== [2025-08-29T09:06:58.012Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-29T09:06:58.012Z] GC before operation: completed in 97.171 ms, heap usage 296.314 MB -> 67.585 MB. [2025-08-29T09:07:04.738Z] ====== als (apache-spark) [default], iteration 10 completed (6458.568 ms) ====== [2025-08-29T09:07:04.738Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-29T09:07:04.738Z] GC before operation: completed in 90.149 ms, heap usage 121.687 MB -> 67.222 MB. [2025-08-29T09:07:11.490Z] ====== als (apache-spark) [default], iteration 11 completed (6483.695 ms) ====== [2025-08-29T09:07:11.491Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-29T09:07:11.491Z] GC before operation: completed in 93.691 ms, heap usage 276.845 MB -> 67.522 MB. [2025-08-29T09:07:18.221Z] ====== als (apache-spark) [default], iteration 12 completed (6451.964 ms) ====== [2025-08-29T09:07:18.221Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-29T09:07:18.221Z] GC before operation: completed in 89.568 ms, heap usage 339.932 MB -> 67.800 MB. [2025-08-29T09:07:24.978Z] ====== als (apache-spark) [default], iteration 13 completed (6433.561 ms) ====== [2025-08-29T09:07:24.978Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-29T09:07:24.978Z] GC before operation: completed in 75.428 ms, heap usage 578.985 MB -> 68.449 MB. [2025-08-29T09:07:31.962Z] ====== als (apache-spark) [default], iteration 14 completed (6470.439 ms) ====== [2025-08-29T09:07:31.963Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-29T09:07:31.963Z] GC before operation: completed in 87.063 ms, heap usage 221.451 MB -> 68.159 MB. [2025-08-29T09:07:38.678Z] ====== als (apache-spark) [default], iteration 15 completed (6435.001 ms) ====== [2025-08-29T09:07:38.678Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-29T09:07:38.678Z] GC before operation: completed in 84.888 ms, heap usage 209.495 MB -> 68.400 MB. [2025-08-29T09:07:45.469Z] ====== als (apache-spark) [default], iteration 16 completed (6387.167 ms) ====== [2025-08-29T09:07:45.469Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-29T09:07:45.469Z] GC before operation: completed in 79.667 ms, heap usage 127.941 MB -> 68.618 MB. [2025-08-29T09:07:52.209Z] ====== als (apache-spark) [default], iteration 17 completed (6495.424 ms) ====== [2025-08-29T09:07:52.209Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-29T09:07:52.209Z] GC before operation: completed in 81.661 ms, heap usage 361.095 MB -> 69.063 MB. [2025-08-29T09:07:59.119Z] ====== als (apache-spark) [default], iteration 18 completed (6402.675 ms) ====== [2025-08-29T09:07:59.119Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-29T09:07:59.119Z] GC before operation: completed in 91.452 ms, heap usage 423.126 MB -> 69.340 MB. [2025-08-29T09:08:05.841Z] ====== als (apache-spark) [default], iteration 19 completed (6609.622 ms) ====== [2025-08-29T09:08:05.841Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-29T09:08:05.842Z] GC before operation: completed in 94.436 ms, heap usage 223.992 MB -> 69.456 MB. [2025-08-29T09:08:12.578Z] ====== als (apache-spark) [default], iteration 20 completed (6753.798 ms) ====== [2025-08-29T09:08:12.578Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-29T09:08:12.578Z] GC before operation: completed in 106.675 ms, heap usage 294.500 MB -> 69.823 MB. [2025-08-29T09:08:19.320Z] ====== als (apache-spark) [default], iteration 21 completed (6602.695 ms) ====== [2025-08-29T09:08:19.320Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-29T09:08:19.320Z] GC before operation: completed in 99.057 ms, heap usage 127.731 MB -> 69.929 MB. [2025-08-29T09:08:27.553Z] ====== als (apache-spark) [default], iteration 22 completed (6681.453 ms) ====== [2025-08-29T09:08:27.553Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-29T09:08:27.553Z] GC before operation: completed in 92.046 ms, heap usage 200.563 MB -> 70.191 MB. [2025-08-29T09:08:33.426Z] ====== als (apache-spark) [default], iteration 23 completed (6762.390 ms) ====== [2025-08-29T09:08:33.426Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-29T09:08:33.426Z] GC before operation: completed in 91.034 ms, heap usage 364.643 MB -> 70.665 MB. [2025-08-29T09:08:40.163Z] ====== als (apache-spark) [default], iteration 24 completed (6460.404 ms) ====== [2025-08-29T09:08:40.163Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-29T09:08:40.163Z] GC before operation: completed in 109.580 ms, heap usage 163.114 MB -> 70.700 MB. [2025-08-29T09:08:46.931Z] ====== als (apache-spark) [default], iteration 25 completed (6507.117 ms) ====== [2025-08-29T09:08:46.931Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-29T09:08:46.931Z] GC before operation: completed in 91.727 ms, heap usage 274.565 MB -> 71.070 MB. [2025-08-29T09:08:53.699Z] ====== als (apache-spark) [default], iteration 26 completed (6575.137 ms) ====== [2025-08-29T09:08:53.699Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-29T09:08:54.664Z] GC before operation: completed in 88.122 ms, heap usage 259.425 MB -> 71.313 MB. [2025-08-29T09:09:01.402Z] ====== als (apache-spark) [default], iteration 27 completed (6401.389 ms) ====== [2025-08-29T09:09:01.402Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-29T09:09:01.402Z] GC before operation: completed in 101.655 ms, heap usage 239.209 MB -> 71.607 MB. [2025-08-29T09:09:08.154Z] ====== als (apache-spark) [default], iteration 28 completed (6500.235 ms) ====== [2025-08-29T09:09:08.154Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-29T09:09:08.154Z] GC before operation: completed in 90.454 ms, heap usage 458.315 MB -> 71.804 MB. [2025-08-29T09:09:14.899Z] ====== als (apache-spark) [default], iteration 29 completed (6440.247 ms) ====== [2025-08-29T09:09:14.899Z] ----------------------------------- [2025-08-29T09:09:14.899Z] renaissance-als_0_PASSED [2025-08-29T09:09:14.899Z] ----------------------------------- [2025-08-29T09:09:14.899Z] [2025-08-29T09:09:14.899Z] TEST TEARDOWN: [2025-08-29T09:09:14.899Z] Nothing to be done for teardown. [2025-08-29T09:09:14.899Z] renaissance-als_0 Finish Time: Fri Aug 29 09:09:14 2025 Epoch Time (ms): 1756458554084