[2025-06-11T20:08:43.088Z] Running test renaissance-als_0 ... [2025-06-11T20:08:43.088Z] =============================================== [2025-06-11T20:08:43.088Z] renaissance-als_0 Start Time: Wed Jun 11 20:08:42 2025 Epoch Time (ms): 1749672522991 [2025-06-11T20:08:43.088Z] variation: NoOptions [2025-06-11T20:08:43.088Z] JVM_OPTIONS: [2025-06-11T20:08:43.088Z] { \ [2025-06-11T20:08:43.088Z] echo ""; echo "TEST SETUP:"; \ [2025-06-11T20:08:43.088Z] echo "Nothing to be done for setup."; \ [2025-06-11T20:08:43.088Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17496724797197/renaissance-als_0"; \ [2025-06-11T20:08:43.088Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17496724797197/renaissance-als_0"; \ [2025-06-11T20:08:43.088Z] echo ""; echo "TESTING:"; \ [2025-06-11T20:08:43.088Z] "/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_17496724797197/renaissance-als_0"/als.json" als; \ [2025-06-11T20:08:43.088Z] 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_17496724797197/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-11T20:08:43.088Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-11T20:08:43.088Z] echo "Nothing to be done for teardown."; \ [2025-06-11T20:08:43.088Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17496724797197/TestTargetResult"; [2025-06-11T20:08:43.088Z] [2025-06-11T20:08:43.088Z] TEST SETUP: [2025-06-11T20:08:43.088Z] Nothing to be done for setup. [2025-06-11T20:08:43.088Z] [2025-06-11T20:08:43.088Z] TESTING: [2025-06-11T20:08:50.794Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-11T20:08:55.915Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-11T20:08:55.915Z] GC before operation: completed in 47.568 ms, heap usage 153.000 MB -> 35.183 MB. [2025-06-11T20:09:24.752Z] ====== als (apache-spark) [default], iteration 0 completed (23600.830 ms) ====== [2025-06-11T20:09:24.752Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-11T20:09:24.752Z] GC before operation: completed in 93.848 ms, heap usage 111.256 MB -> 61.994 MB. [2025-06-11T20:09:31.264Z] ====== als (apache-spark) [default], iteration 1 completed (9791.852 ms) ====== [2025-06-11T20:09:31.264Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-11T20:09:31.264Z] GC before operation: completed in 90.917 ms, heap usage 166.037 MB -> 62.724 MB. [2025-06-11T20:09:40.871Z] ====== als (apache-spark) [default], iteration 2 completed (9094.406 ms) ====== [2025-06-11T20:09:40.871Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-11T20:09:40.871Z] GC before operation: completed in 101.867 ms, heap usage 379.950 MB -> 63.566 MB. [2025-06-11T20:09:50.494Z] ====== als (apache-spark) [default], iteration 3 completed (8917.106 ms) ====== [2025-06-11T20:09:50.494Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-11T20:09:50.494Z] GC before operation: completed in 92.825 ms, heap usage 325.682 MB -> 64.150 MB. [2025-06-11T20:10:00.218Z] ====== als (apache-spark) [default], iteration 4 completed (8768.684 ms) ====== [2025-06-11T20:10:00.218Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-11T20:10:00.218Z] GC before operation: completed in 98.480 ms, heap usage 218.054 MB -> 64.552 MB. [2025-06-11T20:10:08.418Z] ====== als (apache-spark) [default], iteration 5 completed (8519.884 ms) ====== [2025-06-11T20:10:08.418Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-11T20:10:08.418Z] GC before operation: completed in 104.365 ms, heap usage 169.622 MB -> 65.080 MB. [2025-06-11T20:10:18.054Z] ====== als (apache-spark) [default], iteration 6 completed (8824.351 ms) ====== [2025-06-11T20:10:18.054Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-11T20:10:18.054Z] GC before operation: completed in 114.171 ms, heap usage 496.139 MB -> 66.003 MB. [2025-06-11T20:10:26.084Z] ====== als (apache-spark) [default], iteration 7 completed (8376.525 ms) ====== [2025-06-11T20:10:26.084Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-11T20:10:26.084Z] GC before operation: completed in 135.411 ms, heap usage 186.356 MB -> 66.129 MB. [2025-06-11T20:10:35.688Z] ====== als (apache-spark) [default], iteration 8 completed (8495.407 ms) ====== [2025-06-11T20:10:35.688Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-11T20:10:35.688Z] GC before operation: completed in 101.764 ms, heap usage 156.238 MB -> 66.682 MB. [2025-06-11T20:10:45.203Z] ====== als (apache-spark) [default], iteration 9 completed (8393.063 ms) ====== [2025-06-11T20:10:45.203Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-11T20:10:45.203Z] GC before operation: completed in 102.409 ms, heap usage 185.594 MB -> 66.904 MB. [2025-06-11T20:10:53.138Z] ====== als (apache-spark) [default], iteration 10 completed (8463.430 ms) ====== [2025-06-11T20:10:53.138Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-11T20:10:53.138Z] GC before operation: completed in 109.965 ms, heap usage 337.355 MB -> 67.368 MB. [2025-06-11T20:11:02.708Z] ====== als (apache-spark) [default], iteration 11 completed (8301.516 ms) ====== [2025-06-11T20:11:02.708Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-11T20:11:02.708Z] GC before operation: completed in 107.883 ms, heap usage 188.658 MB -> 67.361 MB. [2025-06-11T20:11:10.978Z] ====== als (apache-spark) [default], iteration 12 completed (8296.593 ms) ====== [2025-06-11T20:11:10.978Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-11T20:11:10.978Z] GC before operation: completed in 104.188 ms, heap usage 326.130 MB -> 67.996 MB. [2025-06-11T20:11:18.991Z] ====== als (apache-spark) [default], iteration 13 completed (8187.032 ms) ====== [2025-06-11T20:11:18.991Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-11T20:11:18.991Z] GC before operation: completed in 114.152 ms, heap usage 259.287 MB -> 68.016 MB. [2025-06-11T20:11:28.739Z] ====== als (apache-spark) [default], iteration 14 completed (8341.288 ms) ====== [2025-06-11T20:11:28.739Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-11T20:11:28.739Z] GC before operation: completed in 111.550 ms, heap usage 283.820 MB -> 68.248 MB. [2025-06-11T20:11:36.643Z] ====== als (apache-spark) [default], iteration 15 completed (8311.732 ms) ====== [2025-06-11T20:11:36.643Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-11T20:11:36.643Z] GC before operation: completed in 109.458 ms, heap usage 360.389 MB -> 68.652 MB. [2025-06-11T20:11:44.603Z] ====== als (apache-spark) [default], iteration 16 completed (8155.335 ms) ====== [2025-06-11T20:11:44.603Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-11T20:11:44.603Z] GC before operation: completed in 111.187 ms, heap usage 431.926 MB -> 69.105 MB. [2025-06-11T20:11:54.137Z] ====== als (apache-spark) [default], iteration 17 completed (8325.787 ms) ====== [2025-06-11T20:11:54.137Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-11T20:11:54.137Z] GC before operation: completed in 108.420 ms, heap usage 185.031 MB -> 68.941 MB. [2025-06-11T20:12:02.123Z] ====== als (apache-spark) [default], iteration 18 completed (8145.418 ms) ====== [2025-06-11T20:12:02.123Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-11T20:12:02.123Z] GC before operation: completed in 110.956 ms, heap usage 327.975 MB -> 69.422 MB. [2025-06-11T20:12:11.858Z] ====== als (apache-spark) [default], iteration 19 completed (8219.441 ms) ====== [2025-06-11T20:12:11.858Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-11T20:12:11.858Z] GC before operation: completed in 107.452 ms, heap usage 201.805 MB -> 69.440 MB. [2025-06-11T20:12:19.832Z] ====== als (apache-spark) [default], iteration 20 completed (8420.910 ms) ====== [2025-06-11T20:12:19.832Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-11T20:12:19.832Z] GC before operation: completed in 99.097 ms, heap usage 468.568 MB -> 70.187 MB. [2025-06-11T20:12:27.882Z] ====== als (apache-spark) [default], iteration 21 completed (8212.633 ms) ====== [2025-06-11T20:12:27.882Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-11T20:12:27.882Z] GC before operation: completed in 97.967 ms, heap usage 187.610 MB -> 69.984 MB. [2025-06-11T20:12:37.581Z] ====== als (apache-spark) [default], iteration 22 completed (8176.746 ms) ====== [2025-06-11T20:12:37.581Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-11T20:12:37.581Z] GC before operation: completed in 100.863 ms, heap usage 255.589 MB -> 70.392 MB. [2025-06-11T20:12:45.625Z] ====== als (apache-spark) [default], iteration 23 completed (8276.543 ms) ====== [2025-06-11T20:12:45.625Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-11T20:12:45.625Z] GC before operation: completed in 99.061 ms, heap usage 100.062 MB -> 71.100 MB. [2025-06-11T20:12:53.704Z] ====== als (apache-spark) [default], iteration 24 completed (8144.862 ms) ====== [2025-06-11T20:12:53.704Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-11T20:12:53.704Z] GC before operation: completed in 101.745 ms, heap usage 378.304 MB -> 71.025 MB. [2025-06-11T20:13:03.541Z] ====== als (apache-spark) [default], iteration 25 completed (8187.008 ms) ====== [2025-06-11T20:13:03.541Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-11T20:13:03.541Z] GC before operation: completed in 105.016 ms, heap usage 190.041 MB -> 71.119 MB. [2025-06-11T20:13:11.360Z] ====== als (apache-spark) [default], iteration 26 completed (8091.313 ms) ====== [2025-06-11T20:13:11.360Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-11T20:13:11.360Z] GC before operation: completed in 105.040 ms, heap usage 502.627 MB -> 71.710 MB. [2025-06-11T20:13:19.418Z] ====== als (apache-spark) [default], iteration 27 completed (8400.943 ms) ====== [2025-06-11T20:13:19.418Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-11T20:13:19.418Z] GC before operation: completed in 104.973 ms, heap usage 201.209 MB -> 71.535 MB. [2025-06-11T20:13:29.342Z] ====== als (apache-spark) [default], iteration 28 completed (8216.565 ms) ====== [2025-06-11T20:13:29.342Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-11T20:13:29.342Z] GC before operation: completed in 106.516 ms, heap usage 118.797 MB -> 71.784 MB. [2025-06-11T20:13:37.309Z] ====== als (apache-spark) [default], iteration 29 completed (8106.763 ms) ====== [2025-06-11T20:13:37.309Z] ----------------------------------- [2025-06-11T20:13:37.309Z] renaissance-als_0_PASSED [2025-06-11T20:13:37.309Z] ----------------------------------- [2025-06-11T20:13:37.309Z] [2025-06-11T20:13:37.309Z] TEST TEARDOWN: [2025-06-11T20:13:37.309Z] Nothing to be done for teardown. [2025-06-11T20:13:37.309Z] renaissance-als_0 Finish Time: Wed Jun 11 20:13:36 2025 Epoch Time (ms): 1749672816637