[2025-10-01T21:13:49.444Z] Running test renaissance-als_0 ... [2025-10-01T21:13:49.444Z] =============================================== [2025-10-01T21:13:49.835Z] renaissance-als_0 Start Time: Wed Oct 1 21:13:49 2025 Epoch Time (ms): 1759353229440 [2025-10-01T21:13:49.835Z] variation: NoOptions [2025-10-01T21:13:49.835Z] JVM_OPTIONS: [2025-10-01T21:13:49.835Z] { \ [2025-10-01T21:13:49.835Z] echo ""; echo "TEST SETUP:"; \ [2025-10-01T21:13:49.835Z] echo "Nothing to be done for setup."; \ [2025-10-01T21:13:49.835Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1759353045636/renaissance-als_0"; \ [2025-10-01T21:13:49.835Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1759353045636/renaissance-als_0"; \ [2025-10-01T21:13:49.835Z] echo ""; echo "TESTING:"; \ [2025-10-01T21:13:49.835Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1759353045636/renaissance-als_0"/als.json" als; \ [2025-10-01T21:13:49.835Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1759353045636/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-01T21:13:49.835Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-01T21:13:49.835Z] echo "Nothing to be done for teardown."; \ [2025-10-01T21:13:49.835Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1759353045636/TestTargetResult"; [2025-10-01T21:13:49.835Z] [2025-10-01T21:13:49.835Z] TEST SETUP: [2025-10-01T21:13:49.835Z] Nothing to be done for setup. [2025-10-01T21:13:49.835Z] [2025-10-01T21:13:49.835Z] TESTING: [2025-10-01T21:13:57.564Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-01T21:14:02.740Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-01T21:14:02.740Z] GC before operation: completed in 49.846 ms, heap usage 274.000 MB -> 35.085 MB. [2025-10-01T21:14:27.213Z] ====== als (apache-spark) [default], iteration 0 completed (23380.872 ms) ====== [2025-10-01T21:14:27.213Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-01T21:14:27.213Z] GC before operation: completed in 90.388 ms, heap usage 110.239 MB -> 61.815 MB. [2025-10-01T21:14:38.680Z] ====== als (apache-spark) [default], iteration 1 completed (10004.854 ms) ====== [2025-10-01T21:14:38.680Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-01T21:14:38.680Z] GC before operation: completed in 89.764 ms, heap usage 320.708 MB -> 62.897 MB. [2025-10-01T21:14:48.354Z] ====== als (apache-spark) [default], iteration 2 completed (9149.810 ms) ====== [2025-10-01T21:14:48.354Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-01T21:14:48.354Z] GC before operation: completed in 92.729 ms, heap usage 301.640 MB -> 63.318 MB. [2025-10-01T21:14:57.931Z] ====== als (apache-spark) [default], iteration 3 completed (9195.588 ms) ====== [2025-10-01T21:14:57.931Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-01T21:14:57.931Z] GC before operation: completed in 97.578 ms, heap usage 148.065 MB -> 63.891 MB. [2025-10-01T21:15:05.867Z] ====== als (apache-spark) [default], iteration 4 completed (8721.997 ms) ====== [2025-10-01T21:15:05.867Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-01T21:15:06.284Z] GC before operation: completed in 96.480 ms, heap usage 122.188 MB -> 64.443 MB. [2025-10-01T21:15:16.008Z] ====== als (apache-spark) [default], iteration 5 completed (8585.015 ms) ====== [2025-10-01T21:15:16.008Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-01T21:15:16.009Z] GC before operation: completed in 99.255 ms, heap usage 463.792 MB -> 65.407 MB. [2025-10-01T21:15:25.540Z] ====== als (apache-spark) [default], iteration 6 completed (8652.690 ms) ====== [2025-10-01T21:15:25.540Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-01T21:15:25.540Z] GC before operation: completed in 96.452 ms, heap usage 345.068 MB -> 65.777 MB. [2025-10-01T21:15:33.386Z] ====== als (apache-spark) [default], iteration 7 completed (8438.880 ms) ====== [2025-10-01T21:15:33.386Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-01T21:15:33.386Z] GC before operation: completed in 119.619 ms, heap usage 109.260 MB -> 65.882 MB. [2025-10-01T21:15:42.897Z] ====== als (apache-spark) [default], iteration 8 completed (8654.328 ms) ====== [2025-10-01T21:15:42.897Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-01T21:15:42.897Z] GC before operation: completed in 103.979 ms, heap usage 514.383 MB -> 67.005 MB. [2025-10-01T21:15:50.814Z] ====== als (apache-spark) [default], iteration 9 completed (8502.896 ms) ====== [2025-10-01T21:15:50.814Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-01T21:15:50.814Z] GC before operation: completed in 107.566 ms, heap usage 330.510 MB -> 67.023 MB. [2025-10-01T21:16:00.547Z] ====== als (apache-spark) [default], iteration 10 completed (8479.995 ms) ====== [2025-10-01T21:16:00.547Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-01T21:16:00.547Z] GC before operation: completed in 110.887 ms, heap usage 135.986 MB -> 67.009 MB. [2025-10-01T21:16:10.138Z] ====== als (apache-spark) [default], iteration 11 completed (8403.904 ms) ====== [2025-10-01T21:16:10.138Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-01T21:16:10.138Z] GC before operation: completed in 113.744 ms, heap usage 187.075 MB -> 67.303 MB. [2025-10-01T21:16:18.226Z] ====== als (apache-spark) [default], iteration 12 completed (8279.765 ms) ====== [2025-10-01T21:16:18.226Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-01T21:16:18.226Z] GC before operation: completed in 106.294 ms, heap usage 429.357 MB -> 67.964 MB. [2025-10-01T21:16:26.236Z] ====== als (apache-spark) [default], iteration 13 completed (8198.099 ms) ====== [2025-10-01T21:16:26.236Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-01T21:16:26.236Z] GC before operation: completed in 103.988 ms, heap usage 166.907 MB -> 67.892 MB. [2025-10-01T21:16:34.207Z] ====== als (apache-spark) [default], iteration 14 completed (8087.258 ms) ====== [2025-10-01T21:16:34.207Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-01T21:16:34.610Z] GC before operation: completed in 103.674 ms, heap usage 109.038 MB -> 68.137 MB. [2025-10-01T21:16:44.187Z] ====== als (apache-spark) [default], iteration 15 completed (8150.575 ms) ====== [2025-10-01T21:16:44.187Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-01T21:16:44.187Z] GC before operation: completed in 90.485 ms, heap usage 236.842 MB -> 68.413 MB. [2025-10-01T21:16:52.248Z] ====== als (apache-spark) [default], iteration 16 completed (8180.772 ms) ====== [2025-10-01T21:16:52.248Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-01T21:16:52.248Z] GC before operation: completed in 92.894 ms, heap usage 529.722 MB -> 69.137 MB. [2025-10-01T21:17:00.125Z] ====== als (apache-spark) [default], iteration 17 completed (8149.049 ms) ====== [2025-10-01T21:17:00.125Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-01T21:17:00.125Z] GC before operation: completed in 97.871 ms, heap usage 450.059 MB -> 69.344 MB. [2025-10-01T21:17:08.124Z] ====== als (apache-spark) [default], iteration 18 completed (8001.881 ms) ====== [2025-10-01T21:17:08.124Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-01T21:17:08.507Z] GC before operation: completed in 91.164 ms, heap usage 156.988 MB -> 69.080 MB. [2025-10-01T21:17:17.991Z] ====== als (apache-spark) [default], iteration 19 completed (8040.664 ms) ====== [2025-10-01T21:17:17.991Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-01T21:17:17.991Z] GC before operation: completed in 94.072 ms, heap usage 233.943 MB -> 69.416 MB. [2025-10-01T21:17:25.876Z] ====== als (apache-spark) [default], iteration 20 completed (8173.014 ms) ====== [2025-10-01T21:17:25.876Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-01T21:17:25.876Z] GC before operation: completed in 90.588 ms, heap usage 205.350 MB -> 69.816 MB. [2025-10-01T21:17:34.054Z] ====== als (apache-spark) [default], iteration 21 completed (8198.814 ms) ====== [2025-10-01T21:17:34.054Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-01T21:17:34.054Z] GC before operation: completed in 91.967 ms, heap usage 344.301 MB -> 70.146 MB. [2025-10-01T21:17:41.978Z] ====== als (apache-spark) [default], iteration 22 completed (8086.252 ms) ====== [2025-10-01T21:17:41.978Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-01T21:17:42.367Z] GC before operation: completed in 95.644 ms, heap usage 378.296 MB -> 70.511 MB. [2025-10-01T21:17:51.826Z] ====== als (apache-spark) [default], iteration 23 completed (8164.549 ms) ====== [2025-10-01T21:17:51.826Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-01T21:17:51.826Z] GC before operation: completed in 89.022 ms, heap usage 188.215 MB -> 70.461 MB. [2025-10-01T21:17:59.788Z] ====== als (apache-spark) [default], iteration 24 completed (8355.553 ms) ====== [2025-10-01T21:17:59.788Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-01T21:17:59.788Z] GC before operation: completed in 94.135 ms, heap usage 196.026 MB -> 70.724 MB. [2025-10-01T21:18:08.307Z] ====== als (apache-spark) [default], iteration 25 completed (8065.100 ms) ====== [2025-10-01T21:18:08.307Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-01T21:18:08.307Z] GC before operation: completed in 96.098 ms, heap usage 254.756 MB -> 71.075 MB. [2025-10-01T21:18:16.577Z] ====== als (apache-spark) [default], iteration 26 completed (7988.668 ms) ====== [2025-10-01T21:18:16.577Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-01T21:18:16.577Z] GC before operation: completed in 95.340 ms, heap usage 269.918 MB -> 71.254 MB. [2025-10-01T21:18:24.687Z] ====== als (apache-spark) [default], iteration 27 completed (8058.141 ms) ====== [2025-10-01T21:18:24.687Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-01T21:18:24.687Z] GC before operation: completed in 99.067 ms, heap usage 99.741 MB -> 71.935 MB. [2025-10-01T21:18:34.281Z] ====== als (apache-spark) [default], iteration 28 completed (8136.300 ms) ====== [2025-10-01T21:18:34.281Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-01T21:18:34.281Z] GC before operation: completed in 94.683 ms, heap usage 408.791 MB -> 71.458 MB. [2025-10-01T21:18:42.362Z] ====== als (apache-spark) [default], iteration 29 completed (7962.458 ms) ====== [2025-10-01T21:18:42.362Z] ----------------------------------- [2025-10-01T21:18:42.362Z] renaissance-als_0_PASSED [2025-10-01T21:18:42.362Z] ----------------------------------- [2025-10-01T21:18:42.362Z] [2025-10-01T21:18:42.362Z] TEST TEARDOWN: [2025-10-01T21:18:42.362Z] Nothing to be done for teardown. [2025-10-01T21:18:42.362Z] renaissance-als_0 Finish Time: Wed Oct 1 21:18:41 2025 Epoch Time (ms): 1759353521779