[2025-09-04T01:57:50.699Z] Running test renaissance-als_0 ... [2025-09-04T01:57:50.699Z] =============================================== [2025-09-04T01:57:50.699Z] renaissance-als_0 Start Time: Thu Sep 4 01:57:50 2025 Epoch Time (ms): 1756951070255 [2025-09-04T01:57:50.699Z] variation: NoOptions [2025-09-04T01:57:50.699Z] JVM_OPTIONS: [2025-09-04T01:57:50.699Z] { \ [2025-09-04T01:57:50.699Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T01:57:50.699Z] echo "Nothing to be done for setup."; \ [2025-09-04T01:57:50.699Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-als_0"; \ [2025-09-04T01:57:50.699Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-als_0"; \ [2025-09-04T01:57:50.699Z] echo ""; echo "TESTING:"; \ [2025-09-04T01:57:50.700Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/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_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-als_0"/als.json" als; \ [2025-09-04T01:57:50.700Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T01:57:50.700Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T01:57:50.700Z] echo "Nothing to be done for teardown."; \ [2025-09-04T01:57:50.700Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/TestTargetResult"; [2025-09-04T01:57:50.700Z] [2025-09-04T01:57:50.700Z] TEST SETUP: [2025-09-04T01:57:50.700Z] Nothing to be done for setup. [2025-09-04T01:57:50.700Z] [2025-09-04T01:57:50.700Z] TESTING: [2025-09-04T01:57:58.808Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-04T01:58:05.524Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-04T01:58:05.524Z] GC before operation: completed in 78.852 ms, heap usage 52.869 MB -> 35.109 MB. [2025-09-04T01:58:39.019Z] ====== als (apache-spark) [default], iteration 0 completed (29059.810 ms) ====== [2025-09-04T01:58:39.019Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-04T01:58:39.019Z] GC before operation: completed in 105.696 ms, heap usage 330.896 MB -> 62.290 MB. [2025-09-04T01:58:47.138Z] ====== als (apache-spark) [default], iteration 1 completed (11033.158 ms) ====== [2025-09-04T01:58:47.138Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-04T01:58:47.138Z] GC before operation: completed in 110.233 ms, heap usage 771.270 MB -> 63.627 MB. [2025-09-04T01:58:56.854Z] ====== als (apache-spark) [default], iteration 2 completed (9563.955 ms) ====== [2025-09-04T01:58:56.854Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-04T01:58:56.854Z] GC before operation: completed in 101.209 ms, heap usage 730.576 MB -> 64.331 MB. [2025-09-04T01:59:06.564Z] ====== als (apache-spark) [default], iteration 3 completed (9361.165 ms) ====== [2025-09-04T01:59:06.564Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-04T01:59:06.564Z] GC before operation: completed in 104.945 ms, heap usage 373.551 MB -> 64.433 MB. [2025-09-04T01:59:16.431Z] ====== als (apache-spark) [default], iteration 4 completed (9059.587 ms) ====== [2025-09-04T01:59:16.431Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-04T01:59:16.431Z] GC before operation: completed in 105.204 ms, heap usage 354.452 MB -> 64.920 MB. [2025-09-04T01:59:26.318Z] ====== als (apache-spark) [default], iteration 5 completed (8944.747 ms) ====== [2025-09-04T01:59:26.318Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-04T01:59:26.318Z] GC before operation: completed in 107.684 ms, heap usage 645.792 MB -> 65.835 MB. [2025-09-04T01:59:34.426Z] ====== als (apache-spark) [default], iteration 6 completed (8734.708 ms) ====== [2025-09-04T01:59:34.426Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-04T01:59:34.426Z] GC before operation: completed in 110.317 ms, heap usage 691.513 MB -> 66.478 MB. [2025-09-04T01:59:44.677Z] ====== als (apache-spark) [default], iteration 7 completed (8789.017 ms) ====== [2025-09-04T01:59:44.677Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-04T01:59:44.677Z] GC before operation: completed in 125.807 ms, heap usage 251.135 MB -> 66.371 MB. [2025-09-04T01:59:52.778Z] ====== als (apache-spark) [default], iteration 8 completed (8853.622 ms) ====== [2025-09-04T01:59:52.778Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-04T01:59:53.726Z] GC before operation: completed in 119.599 ms, heap usage 592.965 MB -> 67.370 MB. [2025-09-04T02:00:03.453Z] ====== als (apache-spark) [default], iteration 9 completed (8868.267 ms) ====== [2025-09-04T02:00:03.453Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-04T02:00:03.453Z] GC before operation: completed in 116.345 ms, heap usage 687.152 MB -> 67.796 MB. [2025-09-04T02:00:11.558Z] ====== als (apache-spark) [default], iteration 10 completed (8859.516 ms) ====== [2025-09-04T02:00:11.558Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-04T02:00:11.558Z] GC before operation: completed in 115.739 ms, heap usage 241.070 MB -> 67.393 MB. [2025-09-04T02:00:21.268Z] ====== als (apache-spark) [default], iteration 11 completed (8678.635 ms) ====== [2025-09-04T02:00:21.268Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-04T02:00:21.268Z] GC before operation: completed in 113.015 ms, heap usage 253.925 MB -> 67.739 MB. [2025-09-04T02:00:31.013Z] ====== als (apache-spark) [default], iteration 12 completed (8709.866 ms) ====== [2025-09-04T02:00:31.013Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-04T02:00:31.013Z] GC before operation: completed in 127.807 ms, heap usage 470.248 MB -> 68.461 MB. [2025-09-04T02:00:40.924Z] ====== als (apache-spark) [default], iteration 13 completed (9599.907 ms) ====== [2025-09-04T02:00:40.924Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-04T02:00:40.924Z] GC before operation: completed in 144.827 ms, heap usage 582.053 MB -> 68.819 MB. [2025-09-04T02:00:51.168Z] ====== als (apache-spark) [default], iteration 14 completed (9566.271 ms) ====== [2025-09-04T02:00:51.168Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-04T02:00:51.168Z] GC before operation: completed in 147.446 ms, heap usage 724.994 MB -> 69.230 MB. [2025-09-04T02:01:00.883Z] ====== als (apache-spark) [default], iteration 15 completed (9298.911 ms) ====== [2025-09-04T02:01:00.883Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-04T02:01:00.883Z] GC before operation: completed in 145.258 ms, heap usage 338.162 MB -> 68.958 MB. [2025-09-04T02:01:10.600Z] ====== als (apache-spark) [default], iteration 16 completed (9377.318 ms) ====== [2025-09-04T02:01:10.600Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-04T02:01:10.600Z] GC before operation: completed in 150.341 ms, heap usage 556.629 MB -> 69.494 MB. [2025-09-04T02:01:18.716Z] ====== als (apache-spark) [default], iteration 17 completed (8943.940 ms) ====== [2025-09-04T02:01:18.716Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-04T02:01:19.670Z] GC before operation: completed in 125.079 ms, heap usage 909.961 MB -> 70.163 MB. [2025-09-04T02:01:27.770Z] ====== als (apache-spark) [default], iteration 18 completed (8590.370 ms) ====== [2025-09-04T02:01:27.770Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-04T02:01:28.716Z] GC before operation: completed in 121.904 ms, heap usage 370.448 MB -> 69.725 MB. [2025-09-04T02:01:36.842Z] ====== als (apache-spark) [default], iteration 19 completed (8590.528 ms) ====== [2025-09-04T02:01:36.843Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-04T02:01:36.843Z] GC before operation: completed in 127.489 ms, heap usage 515.006 MB -> 70.195 MB. [2025-09-04T02:01:47.239Z] ====== als (apache-spark) [default], iteration 20 completed (8936.586 ms) ====== [2025-09-04T02:01:47.239Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-04T02:01:47.239Z] GC before operation: completed in 126.457 ms, heap usage 586.205 MB -> 70.594 MB. [2025-09-04T02:01:55.340Z] ====== als (apache-spark) [default], iteration 21 completed (8656.502 ms) ====== [2025-09-04T02:01:55.340Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-04T02:01:55.340Z] GC before operation: completed in 124.570 ms, heap usage 170.826 MB -> 70.335 MB. [2025-09-04T02:02:05.031Z] ====== als (apache-spark) [default], iteration 22 completed (8552.863 ms) ====== [2025-09-04T02:02:05.031Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-04T02:02:05.031Z] GC before operation: completed in 120.998 ms, heap usage 182.489 MB -> 70.611 MB. [2025-09-04T02:02:13.133Z] ====== als (apache-spark) [default], iteration 23 completed (8578.966 ms) ====== [2025-09-04T02:02:13.133Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-04T02:02:13.133Z] GC before operation: completed in 121.557 ms, heap usage 547.423 MB -> 71.477 MB. [2025-09-04T02:02:22.865Z] ====== als (apache-spark) [default], iteration 24 completed (8663.421 ms) ====== [2025-09-04T02:02:22.865Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-04T02:02:22.865Z] GC before operation: completed in 123.504 ms, heap usage 740.464 MB -> 71.898 MB. [2025-09-04T02:02:31.115Z] ====== als (apache-spark) [default], iteration 25 completed (8280.605 ms) ====== [2025-09-04T02:02:31.115Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-04T02:02:31.115Z] GC before operation: completed in 123.094 ms, heap usage 169.515 MB -> 71.236 MB. [2025-09-04T02:02:40.862Z] ====== als (apache-spark) [default], iteration 26 completed (8546.250 ms) ====== [2025-09-04T02:02:40.862Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-04T02:02:40.862Z] GC before operation: completed in 124.673 ms, heap usage 142.067 MB -> 71.467 MB. [2025-09-04T02:02:49.186Z] ====== als (apache-spark) [default], iteration 27 completed (8519.086 ms) ====== [2025-09-04T02:02:49.186Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-04T02:02:49.186Z] GC before operation: completed in 142.897 ms, heap usage 420.830 MB -> 72.113 MB. [2025-09-04T02:02:58.934Z] ====== als (apache-spark) [default], iteration 28 completed (8458.561 ms) ====== [2025-09-04T02:02:58.934Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-04T02:02:58.934Z] GC before operation: completed in 122.049 ms, heap usage 180.248 MB -> 71.670 MB. [2025-09-04T02:03:07.036Z] ====== als (apache-spark) [default], iteration 29 completed (8394.696 ms) ====== [2025-09-04T02:03:07.036Z] ----------------------------------- [2025-09-04T02:03:07.036Z] renaissance-als_0_PASSED [2025-09-04T02:03:07.036Z] ----------------------------------- [2025-09-04T02:03:07.036Z] [2025-09-04T02:03:07.036Z] TEST TEARDOWN: [2025-09-04T02:03:07.036Z] Nothing to be done for teardown. [2025-09-04T02:03:07.036Z] renaissance-als_0 Finish Time: Thu Sep 4 02:03:06 2025 Epoch Time (ms): 1756951386897