[2025-11-26T22:43:21.995Z] Running test renaissance-als_0 ... [2025-11-26T22:43:21.995Z] =============================================== [2025-11-26T22:43:22.357Z] renaissance-als_0 Start Time: Wed Nov 26 22:43:21 2025 Epoch Time (ms): 1764197001982 [2025-11-26T22:43:22.357Z] variation: NoOptions [2025-11-26T22:43:22.357Z] JVM_OPTIONS: [2025-11-26T22:43:22.357Z] { \ [2025-11-26T22:43:22.357Z] echo ""; echo "TEST SETUP:"; \ [2025-11-26T22:43:22.357Z] echo "Nothing to be done for setup."; \ [2025-11-26T22:43:22.357Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17641968171826/renaissance-als_0"; \ [2025-11-26T22:43:22.357Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17641968171826/renaissance-als_0"; \ [2025-11-26T22:43:22.357Z] echo ""; echo "TESTING:"; \ [2025-11-26T22:43:22.357Z] "/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_17641968171826/renaissance-als_0"/als.json" als; \ [2025-11-26T22:43:22.357Z] 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_17641968171826/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-26T22:43:22.357Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-26T22:43:22.357Z] echo "Nothing to be done for teardown."; \ [2025-11-26T22:43:22.357Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17641968171826/TestTargetResult"; [2025-11-26T22:43:22.357Z] [2025-11-26T22:43:22.357Z] TEST SETUP: [2025-11-26T22:43:22.357Z] Nothing to be done for setup. [2025-11-26T22:43:22.357Z] [2025-11-26T22:43:22.357Z] TESTING: [2025-11-26T22:43:29.888Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-26T22:43:34.902Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-26T22:43:34.902Z] GC before operation: completed in 50.784 ms, heap usage 170.511 MB -> 35.170 MB. [2025-11-26T22:44:03.353Z] ====== als (apache-spark) [default], iteration 0 completed (23036.759 ms) ====== [2025-11-26T22:44:03.353Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-26T22:44:03.353Z] GC before operation: completed in 103.397 ms, heap usage 274.061 MB -> 62.162 MB. [2025-11-26T22:44:09.627Z] ====== als (apache-spark) [default], iteration 1 completed (9859.936 ms) ====== [2025-11-26T22:44:09.627Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-26T22:44:09.627Z] GC before operation: completed in 89.471 ms, heap usage 456.397 MB -> 63.175 MB. [2025-11-26T22:44:19.148Z] ====== als (apache-spark) [default], iteration 2 completed (8958.503 ms) ====== [2025-11-26T22:44:19.148Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-26T22:44:19.148Z] GC before operation: completed in 104.108 ms, heap usage 552.792 MB -> 63.906 MB. [2025-11-26T22:44:28.578Z] ====== als (apache-spark) [default], iteration 3 completed (8768.417 ms) ====== [2025-11-26T22:44:28.578Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-26T22:44:28.578Z] GC before operation: completed in 93.143 ms, heap usage 353.734 MB -> 64.110 MB. [2025-11-26T22:44:38.007Z] ====== als (apache-spark) [default], iteration 4 completed (8484.075 ms) ====== [2025-11-26T22:44:38.007Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-26T22:44:38.007Z] GC before operation: completed in 108.484 ms, heap usage 995.018 MB -> 65.458 MB. [2025-11-26T22:44:45.694Z] ====== als (apache-spark) [default], iteration 5 completed (8643.688 ms) ====== [2025-11-26T22:44:45.694Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-26T22:44:46.068Z] GC before operation: completed in 113.583 ms, heap usage 438.937 MB -> 65.504 MB. [2025-11-26T22:44:55.447Z] ====== als (apache-spark) [default], iteration 6 completed (8595.687 ms) ====== [2025-11-26T22:44:55.447Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-26T22:44:55.447Z] GC before operation: completed in 101.274 ms, heap usage 256.524 MB -> 65.752 MB. [2025-11-26T22:45:04.933Z] ====== als (apache-spark) [default], iteration 7 completed (8532.585 ms) ====== [2025-11-26T22:45:04.933Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-26T22:45:04.933Z] GC before operation: completed in 107.834 ms, heap usage 492.266 MB -> 66.533 MB. [2025-11-26T22:45:12.721Z] ====== als (apache-spark) [default], iteration 8 completed (8496.522 ms) ====== [2025-11-26T22:45:12.721Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-26T22:45:12.721Z] GC before operation: completed in 102.098 ms, heap usage 360.704 MB -> 66.942 MB. [2025-11-26T22:45:22.037Z] ====== als (apache-spark) [default], iteration 9 completed (8359.757 ms) ====== [2025-11-26T22:45:22.037Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-26T22:45:22.037Z] GC before operation: completed in 105.538 ms, heap usage 534.481 MB -> 67.343 MB. [2025-11-26T22:45:30.660Z] ====== als (apache-spark) [default], iteration 10 completed (8331.410 ms) ====== [2025-11-26T22:45:30.660Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-26T22:45:30.660Z] GC before operation: completed in 121.769 ms, heap usage 305.910 MB -> 67.396 MB. [2025-11-26T22:45:40.123Z] ====== als (apache-spark) [default], iteration 11 completed (8250.289 ms) ====== [2025-11-26T22:45:40.123Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-26T22:45:40.123Z] GC before operation: completed in 109.936 ms, heap usage 323.894 MB -> 67.593 MB. [2025-11-26T22:45:47.968Z] ====== als (apache-spark) [default], iteration 12 completed (8289.504 ms) ====== [2025-11-26T22:45:47.968Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-26T22:45:47.968Z] GC before operation: completed in 108.354 ms, heap usage 123.863 MB -> 67.910 MB. [2025-11-26T22:45:55.966Z] ====== als (apache-spark) [default], iteration 13 completed (8114.565 ms) ====== [2025-11-26T22:45:55.966Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-26T22:45:55.966Z] GC before operation: completed in 108.829 ms, heap usage 190.015 MB -> 68.054 MB. [2025-11-26T22:46:05.328Z] ====== als (apache-spark) [default], iteration 14 completed (8127.094 ms) ====== [2025-11-26T22:46:05.328Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-26T22:46:05.328Z] GC before operation: completed in 109.547 ms, heap usage 291.414 MB -> 68.344 MB. [2025-11-26T22:46:13.107Z] ====== als (apache-spark) [default], iteration 15 completed (8181.809 ms) ====== [2025-11-26T22:46:13.107Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-26T22:46:13.107Z] GC before operation: completed in 113.383 ms, heap usage 112.308 MB -> 68.479 MB. [2025-11-26T22:46:22.430Z] ====== als (apache-spark) [default], iteration 16 completed (8138.898 ms) ====== [2025-11-26T22:46:22.430Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-26T22:46:22.430Z] GC before operation: completed in 117.432 ms, heap usage 305.148 MB -> 68.934 MB. [2025-11-26T22:46:30.267Z] ====== als (apache-spark) [default], iteration 17 completed (8290.238 ms) ====== [2025-11-26T22:46:30.267Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-26T22:46:30.267Z] GC before operation: completed in 112.632 ms, heap usage 454.238 MB -> 69.408 MB. [2025-11-26T22:46:39.721Z] ====== als (apache-spark) [default], iteration 18 completed (8148.108 ms) ====== [2025-11-26T22:46:39.721Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-26T22:46:39.721Z] GC before operation: completed in 113.036 ms, heap usage 218.194 MB -> 69.237 MB. [2025-11-26T22:46:47.888Z] ====== als (apache-spark) [default], iteration 19 completed (8139.243 ms) ====== [2025-11-26T22:46:47.888Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-26T22:46:47.888Z] GC before operation: completed in 116.594 ms, heap usage 518.865 MB -> 69.892 MB. [2025-11-26T22:46:57.228Z] ====== als (apache-spark) [default], iteration 20 completed (8364.719 ms) ====== [2025-11-26T22:46:57.228Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-26T22:46:57.228Z] GC before operation: completed in 121.210 ms, heap usage 109.603 MB -> 69.695 MB. [2025-11-26T22:47:04.976Z] ====== als (apache-spark) [default], iteration 21 completed (8140.530 ms) ====== [2025-11-26T22:47:04.976Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-26T22:47:04.976Z] GC before operation: completed in 118.520 ms, heap usage 497.182 MB -> 70.431 MB. [2025-11-26T22:47:12.949Z] ====== als (apache-spark) [default], iteration 22 completed (8250.558 ms) ====== [2025-11-26T22:47:12.949Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-26T22:47:13.327Z] GC before operation: completed in 118.529 ms, heap usage 535.585 MB -> 70.807 MB. [2025-11-26T22:47:22.717Z] ====== als (apache-spark) [default], iteration 23 completed (8125.357 ms) ====== [2025-11-26T22:47:22.717Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-26T22:47:22.717Z] GC before operation: completed in 115.928 ms, heap usage 357.428 MB -> 70.762 MB. [2025-11-26T22:47:30.552Z] ====== als (apache-spark) [default], iteration 24 completed (8179.154 ms) ====== [2025-11-26T22:47:30.552Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-26T22:47:30.552Z] GC before operation: completed in 118.895 ms, heap usage 208.021 MB -> 70.846 MB. [2025-11-26T22:47:38.839Z] ====== als (apache-spark) [default], iteration 25 completed (8425.393 ms) ====== [2025-11-26T22:47:38.839Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-26T22:47:38.839Z] GC before operation: completed in 120.437 ms, heap usage 505.082 MB -> 71.520 MB. [2025-11-26T22:47:48.262Z] ====== als (apache-spark) [default], iteration 26 completed (8011.336 ms) ====== [2025-11-26T22:47:48.262Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-26T22:47:48.262Z] GC before operation: completed in 117.063 ms, heap usage 159.997 MB -> 71.320 MB. [2025-11-26T22:47:56.017Z] ====== als (apache-spark) [default], iteration 27 completed (8298.607 ms) ====== [2025-11-26T22:47:56.017Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-26T22:47:56.017Z] GC before operation: completed in 121.920 ms, heap usage 291.182 MB -> 71.205 MB. [2025-11-26T22:48:05.507Z] ====== als (apache-spark) [default], iteration 28 completed (8013.328 ms) ====== [2025-11-26T22:48:05.507Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-26T22:48:05.507Z] GC before operation: completed in 117.292 ms, heap usage 455.070 MB -> 72.399 MB. [2025-11-26T22:48:13.288Z] ====== als (apache-spark) [default], iteration 29 completed (7952.850 ms) ====== [2025-11-26T22:48:13.288Z] ----------------------------------- [2025-11-26T22:48:13.288Z] renaissance-als_0_PASSED [2025-11-26T22:48:13.288Z] ----------------------------------- [2025-11-26T22:48:13.288Z] [2025-11-26T22:48:13.288Z] TEST TEARDOWN: [2025-11-26T22:48:13.288Z] Nothing to be done for teardown. [2025-11-26T22:48:13.288Z] renaissance-als_0 Finish Time: Wed Nov 26 22:48:12 2025 Epoch Time (ms): 1764197292805