[2025-06-11T20:47:12.840Z] Running test renaissance-als_0 ... [2025-06-11T20:47:12.840Z] =============================================== [2025-06-11T20:47:12.840Z] renaissance-als_0 Start Time: Wed Jun 11 20:47:12 2025 Epoch Time (ms): 1749674832203 [2025-06-11T20:47:12.840Z] variation: NoOptions [2025-06-11T20:47:12.840Z] JVM_OPTIONS: [2025-06-11T20:47:12.840Z] { \ [2025-06-11T20:47:12.840Z] echo ""; echo "TEST SETUP:"; \ [2025-06-11T20:47:12.840Z] echo "Nothing to be done for setup."; \ [2025-06-11T20:47:12.840Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496747995774/renaissance-als_0"; \ [2025-06-11T20:47:12.840Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496747995774/renaissance-als_0"; \ [2025-06-11T20:47:12.840Z] echo ""; echo "TESTING:"; \ [2025-06-11T20:47:12.840Z] "/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_17496747995774/renaissance-als_0"/als.json" als; \ [2025-06-11T20:47:12.840Z] 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_17496747995774/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-11T20:47:12.840Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-11T20:47:12.840Z] echo "Nothing to be done for teardown."; \ [2025-06-11T20:47:12.840Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17496747995774/TestTargetResult"; [2025-06-11T20:47:12.840Z] [2025-06-11T20:47:12.840Z] TEST SETUP: [2025-06-11T20:47:12.840Z] Nothing to be done for setup. [2025-06-11T20:47:12.840Z] [2025-06-11T20:47:12.840Z] TESTING: [2025-06-11T20:47:18.171Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-11T20:47:22.299Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-11T20:47:22.299Z] GC before operation: completed in 51.640 ms, heap usage 335.000 MB -> 35.194 MB. [2025-06-11T20:47:40.542Z] ====== als (apache-spark) [default], iteration 0 completed (16587.025 ms) ====== [2025-06-11T20:47:40.542Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-11T20:47:40.542Z] GC before operation: completed in 100.603 ms, heap usage 226.788 MB -> 62.088 MB. [2025-06-11T20:47:48.690Z] ====== als (apache-spark) [default], iteration 1 completed (7815.794 ms) ====== [2025-06-11T20:47:48.690Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-11T20:47:48.690Z] GC before operation: completed in 92.240 ms, heap usage 227.953 MB -> 62.904 MB. [2025-06-11T20:47:55.345Z] ====== als (apache-spark) [default], iteration 2 completed (7076.417 ms) ====== [2025-06-11T20:47:55.345Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-11T20:47:55.345Z] GC before operation: completed in 103.649 ms, heap usage 157.175 MB -> 63.419 MB. [2025-06-11T20:48:02.705Z] ====== als (apache-spark) [default], iteration 3 completed (7109.805 ms) ====== [2025-06-11T20:48:02.705Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-11T20:48:02.705Z] GC before operation: completed in 93.496 ms, heap usage 353.434 MB -> 64.240 MB. [2025-06-11T20:48:10.832Z] ====== als (apache-spark) [default], iteration 4 completed (7005.179 ms) ====== [2025-06-11T20:48:10.832Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-11T20:48:10.832Z] GC before operation: completed in 93.780 ms, heap usage 388.004 MB -> 64.789 MB. [2025-06-11T20:48:17.538Z] ====== als (apache-spark) [default], iteration 5 completed (6885.586 ms) ====== [2025-06-11T20:48:17.538Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-11T20:48:17.538Z] GC before operation: completed in 90.763 ms, heap usage 183.718 MB -> 65.138 MB. [2025-06-11T20:48:24.233Z] ====== als (apache-spark) [default], iteration 6 completed (6691.811 ms) ====== [2025-06-11T20:48:24.233Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-11T20:48:24.233Z] GC before operation: completed in 89.262 ms, heap usage 160.680 MB -> 65.778 MB. [2025-06-11T20:48:32.360Z] ====== als (apache-spark) [default], iteration 7 completed (6776.887 ms) ====== [2025-06-11T20:48:32.360Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-11T20:48:32.360Z] GC before operation: completed in 98.747 ms, heap usage 324.631 MB -> 66.531 MB. [2025-06-11T20:48:39.070Z] ====== als (apache-spark) [default], iteration 8 completed (6769.949 ms) ====== [2025-06-11T20:48:39.070Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-11T20:48:39.070Z] GC before operation: completed in 110.735 ms, heap usage 286.416 MB -> 66.901 MB. [2025-06-11T20:48:45.842Z] ====== als (apache-spark) [default], iteration 9 completed (6756.719 ms) ====== [2025-06-11T20:48:45.842Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-11T20:48:45.842Z] GC before operation: completed in 103.272 ms, heap usage 208.831 MB -> 67.615 MB. [2025-06-11T20:48:53.373Z] ====== als (apache-spark) [default], iteration 10 completed (6786.606 ms) ====== [2025-06-11T20:48:53.373Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-11T20:48:53.373Z] GC before operation: completed in 124.589 ms, heap usage 167.022 MB -> 67.250 MB. [2025-06-11T20:49:00.239Z] ====== als (apache-spark) [default], iteration 11 completed (6639.377 ms) ====== [2025-06-11T20:49:00.239Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-11T20:49:00.239Z] GC before operation: completed in 118.565 ms, heap usage 125.962 MB -> 67.641 MB. [2025-06-11T20:49:07.110Z] ====== als (apache-spark) [default], iteration 12 completed (6547.148 ms) ====== [2025-06-11T20:49:07.110Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-11T20:49:07.110Z] GC before operation: completed in 108.241 ms, heap usage 283.546 MB -> 68.177 MB. [2025-06-11T20:49:13.839Z] ====== als (apache-spark) [default], iteration 13 completed (6618.689 ms) ====== [2025-06-11T20:49:13.839Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-11T20:49:13.839Z] GC before operation: completed in 120.892 ms, heap usage 246.607 MB -> 68.323 MB. [2025-06-11T20:49:20.579Z] ====== als (apache-spark) [default], iteration 14 completed (6638.594 ms) ====== [2025-06-11T20:49:20.579Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-11T20:49:20.579Z] GC before operation: completed in 90.338 ms, heap usage 172.887 MB -> 68.429 MB. [2025-06-11T20:49:27.288Z] ====== als (apache-spark) [default], iteration 15 completed (6675.258 ms) ====== [2025-06-11T20:49:27.288Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-11T20:49:28.249Z] GC before operation: completed in 86.334 ms, heap usage 204.822 MB -> 68.677 MB. [2025-06-11T20:49:35.040Z] ====== als (apache-spark) [default], iteration 16 completed (6603.297 ms) ====== [2025-06-11T20:49:35.040Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-11T20:49:35.040Z] GC before operation: completed in 99.624 ms, heap usage 164.854 MB -> 68.790 MB. [2025-06-11T20:49:41.806Z] ====== als (apache-spark) [default], iteration 17 completed (6615.100 ms) ====== [2025-06-11T20:49:41.806Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-11T20:49:41.806Z] GC before operation: completed in 113.381 ms, heap usage 439.086 MB -> 69.515 MB. [2025-06-11T20:49:48.547Z] ====== als (apache-spark) [default], iteration 18 completed (6610.644 ms) ====== [2025-06-11T20:49:48.547Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-11T20:49:48.547Z] GC before operation: completed in 86.433 ms, heap usage 326.111 MB -> 69.597 MB. [2025-06-11T20:49:55.977Z] ====== als (apache-spark) [default], iteration 19 completed (6685.142 ms) ====== [2025-06-11T20:49:55.977Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-11T20:49:55.977Z] GC before operation: completed in 100.423 ms, heap usage 486.126 MB -> 70.365 MB. [2025-06-11T20:50:02.724Z] ====== als (apache-spark) [default], iteration 20 completed (6581.264 ms) ====== [2025-06-11T20:50:02.724Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-11T20:50:02.724Z] GC before operation: completed in 86.231 ms, heap usage 485.882 MB -> 70.301 MB. [2025-06-11T20:50:09.382Z] ====== als (apache-spark) [default], iteration 21 completed (6564.009 ms) ====== [2025-06-11T20:50:09.382Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-11T20:50:09.382Z] GC before operation: completed in 88.095 ms, heap usage 365.553 MB -> 70.417 MB. [2025-06-11T20:50:16.185Z] ====== als (apache-spark) [default], iteration 22 completed (6517.557 ms) ====== [2025-06-11T20:50:16.185Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-11T20:50:16.185Z] GC before operation: completed in 85.394 ms, heap usage 504.430 MB -> 70.866 MB. [2025-06-11T20:50:22.954Z] ====== als (apache-spark) [default], iteration 23 completed (6533.687 ms) ====== [2025-06-11T20:50:22.954Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-11T20:50:22.954Z] GC before operation: completed in 96.733 ms, heap usage 542.907 MB -> 71.389 MB. [2025-06-11T20:50:29.648Z] ====== als (apache-spark) [default], iteration 24 completed (6474.436 ms) ====== [2025-06-11T20:50:29.648Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-11T20:50:29.648Z] GC before operation: completed in 84.884 ms, heap usage 295.191 MB -> 71.012 MB. [2025-06-11T20:50:36.494Z] ====== als (apache-spark) [default], iteration 25 completed (6462.150 ms) ====== [2025-06-11T20:50:36.494Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-11T20:50:36.494Z] GC before operation: completed in 84.958 ms, heap usage 333.215 MB -> 71.390 MB. [2025-06-11T20:50:43.169Z] ====== als (apache-spark) [default], iteration 26 completed (6533.585 ms) ====== [2025-06-11T20:50:43.169Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-11T20:50:43.169Z] GC before operation: completed in 90.773 ms, heap usage 408.123 MB -> 71.605 MB. [2025-06-11T20:50:49.661Z] ====== als (apache-spark) [default], iteration 27 completed (6493.315 ms) ====== [2025-06-11T20:50:49.661Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-11T20:50:49.661Z] GC before operation: completed in 94.348 ms, heap usage 604.290 MB -> 71.787 MB. [2025-06-11T20:50:56.332Z] ====== als (apache-spark) [default], iteration 28 completed (6511.228 ms) ====== [2025-06-11T20:50:56.332Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-11T20:50:56.332Z] GC before operation: completed in 89.869 ms, heap usage 134.188 MB -> 71.431 MB. [2025-06-11T20:51:02.996Z] ====== als (apache-spark) [default], iteration 29 completed (6370.126 ms) ====== [2025-06-11T20:51:03.939Z] ----------------------------------- [2025-06-11T20:51:03.939Z] renaissance-als_0_PASSED [2025-06-11T20:51:03.939Z] ----------------------------------- [2025-06-11T20:51:03.939Z] [2025-06-11T20:51:03.939Z] TEST TEARDOWN: [2025-06-11T20:51:03.939Z] Nothing to be done for teardown. [2025-06-11T20:51:03.939Z] renaissance-als_0 Finish Time: Wed Jun 11 20:51:03 2025 Epoch Time (ms): 1749675063066