[2025-10-01T23:44:00.034Z] Running test renaissance-als_0 ... [2025-10-01T23:44:00.034Z] =============================================== [2025-10-01T23:44:00.034Z] renaissance-als_0 Start Time: Wed Oct 1 23:43:59 2025 Epoch Time (ms): 1759362239753 [2025-10-01T23:44:00.034Z] variation: NoOptions [2025-10-01T23:44:00.034Z] JVM_OPTIONS: [2025-10-01T23:44:00.034Z] { \ [2025-10-01T23:44:00.034Z] echo ""; echo "TEST SETUP:"; \ [2025-10-01T23:44:00.034Z] echo "Nothing to be done for setup."; \ [2025-10-01T23:44:00.034Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17593622386660/renaissance-als_0"; \ [2025-10-01T23:44:00.034Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17593622386660/renaissance-als_0"; \ [2025-10-01T23:44:00.034Z] echo ""; echo "TESTING:"; \ [2025-10-01T23:44:00.034Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_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_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17593622386660/renaissance-als_0"/als.json" als; \ [2025-10-01T23:44:00.034Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17593622386660/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-01T23:44:00.034Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-01T23:44:00.034Z] echo "Nothing to be done for teardown."; \ [2025-10-01T23:44:00.034Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17593622386660/TestTargetResult"; [2025-10-01T23:44:00.034Z] [2025-10-01T23:44:00.034Z] TEST SETUP: [2025-10-01T23:44:00.034Z] Nothing to be done for setup. [2025-10-01T23:44:00.034Z] [2025-10-01T23:44:00.034Z] TESTING: [2025-10-01T23:44:23.255Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-01T23:44:42.326Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-01T23:44:42.326Z] GC before operation: completed in 198.047 ms, heap usage 58.200 MB -> 35.288 MB. [2025-10-01T23:46:05.338Z] ====== als (apache-spark) [default], iteration 0 completed (74509.882 ms) ====== [2025-10-01T23:46:05.338Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-01T23:46:05.338Z] GC before operation: completed in 633.039 ms, heap usage 366.730 MB -> 75.406 MB. [2025-10-01T23:46:33.044Z] ====== als (apache-spark) [default], iteration 1 completed (29030.713 ms) ====== [2025-10-01T23:46:33.044Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-01T23:46:33.044Z] GC before operation: completed in 657.649 ms, heap usage 204.227 MB -> 76.136 MB. [2025-10-01T23:47:00.859Z] ====== als (apache-spark) [default], iteration 2 completed (24525.315 ms) ====== [2025-10-01T23:47:00.859Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-01T23:47:00.859Z] GC before operation: completed in 732.193 ms, heap usage 451.235 MB -> 77.313 MB. [2025-10-01T23:47:23.865Z] ====== als (apache-spark) [default], iteration 3 completed (23551.314 ms) ====== [2025-10-01T23:47:23.865Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-01T23:47:23.865Z] GC before operation: completed in 794.994 ms, heap usage 871.863 MB -> 78.427 MB. [2025-10-01T23:47:51.764Z] ====== als (apache-spark) [default], iteration 4 completed (23499.864 ms) ====== [2025-10-01T23:47:51.764Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-01T23:47:51.764Z] GC before operation: completed in 732.860 ms, heap usage 363.965 MB -> 78.096 MB. [2025-10-01T23:48:15.159Z] ====== als (apache-spark) [default], iteration 5 completed (22100.984 ms) ====== [2025-10-01T23:48:15.159Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-01T23:48:15.159Z] GC before operation: completed in 799.132 ms, heap usage 626.667 MB -> 79.114 MB. [2025-10-01T23:48:38.168Z] ====== als (apache-spark) [default], iteration 6 completed (22552.904 ms) ====== [2025-10-01T23:48:38.168Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-01T23:48:38.168Z] GC before operation: completed in 790.064 ms, heap usage 365.343 MB -> 79.295 MB. [2025-10-01T23:49:01.190Z] ====== als (apache-spark) [default], iteration 7 completed (21785.183 ms) ====== [2025-10-01T23:49:01.190Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-01T23:49:01.190Z] GC before operation: completed in 895.713 ms, heap usage 526.259 MB -> 80.169 MB. [2025-10-01T23:49:24.190Z] ====== als (apache-spark) [default], iteration 8 completed (22095.651 ms) ====== [2025-10-01T23:49:24.190Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-01T23:49:24.519Z] GC before operation: completed in 887.022 ms, heap usage 1.001 GB -> 81.347 MB. [2025-10-01T23:49:47.528Z] ====== als (apache-spark) [default], iteration 9 completed (21591.486 ms) ====== [2025-10-01T23:49:47.528Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-01T23:49:47.863Z] GC before operation: completed in 863.456 ms, heap usage 703.879 MB -> 81.183 MB. [2025-10-01T23:50:10.955Z] ====== als (apache-spark) [default], iteration 10 completed (22081.954 ms) ====== [2025-10-01T23:50:10.955Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-01T23:50:12.106Z] GC before operation: completed in 889.737 ms, heap usage 372.934 MB -> 80.902 MB. [2025-10-01T23:50:35.093Z] ====== als (apache-spark) [default], iteration 11 completed (21324.851 ms) ====== [2025-10-01T23:50:35.093Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-01T23:50:35.093Z] GC before operation: completed in 902.205 ms, heap usage 515.312 MB -> 81.516 MB. [2025-10-01T23:50:58.817Z] ====== als (apache-spark) [default], iteration 12 completed (21882.058 ms) ====== [2025-10-01T23:50:58.817Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-01T23:50:58.817Z] GC before operation: completed in 881.396 ms, heap usage 158.143 MB -> 81.209 MB. [2025-10-01T23:51:21.842Z] ====== als (apache-spark) [default], iteration 13 completed (21049.049 ms) ====== [2025-10-01T23:51:21.842Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-01T23:51:21.842Z] GC before operation: completed in 881.201 ms, heap usage 179.842 MB -> 81.631 MB. [2025-10-01T23:51:44.882Z] ====== als (apache-spark) [default], iteration 14 completed (20711.508 ms) ====== [2025-10-01T23:51:44.882Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-01T23:51:44.882Z] GC before operation: completed in 796.616 ms, heap usage 268.750 MB -> 82.034 MB. [2025-10-01T23:52:07.875Z] ====== als (apache-spark) [default], iteration 15 completed (20188.031 ms) ====== [2025-10-01T23:52:07.875Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-01T23:52:07.875Z] GC before operation: completed in 811.908 ms, heap usage 702.089 MB -> 82.847 MB. [2025-10-01T23:52:27.165Z] ====== als (apache-spark) [default], iteration 16 completed (20530.323 ms) ====== [2025-10-01T23:52:27.165Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-01T23:52:27.884Z] GC before operation: completed in 835.632 ms, heap usage 768.054 MB -> 83.148 MB. [2025-10-01T23:52:50.860Z] ====== als (apache-spark) [default], iteration 17 completed (20542.154 ms) ====== [2025-10-01T23:52:50.860Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-01T23:52:50.860Z] GC before operation: completed in 844.958 ms, heap usage 1.237 GB -> 83.864 MB. [2025-10-01T23:53:13.856Z] ====== als (apache-spark) [default], iteration 18 completed (20203.506 ms) ====== [2025-10-01T23:53:13.856Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-01T23:53:13.856Z] GC before operation: completed in 841.931 ms, heap usage 1.338 GB -> 84.216 MB. [2025-10-01T23:53:36.877Z] ====== als (apache-spark) [default], iteration 19 completed (20883.009 ms) ====== [2025-10-01T23:53:36.877Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-01T23:53:36.877Z] GC before operation: completed in 889.293 ms, heap usage 275.983 MB -> 83.254 MB. [2025-10-01T23:53:56.032Z] ====== als (apache-spark) [default], iteration 20 completed (20564.253 ms) ====== [2025-10-01T23:53:56.032Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-01T23:53:57.208Z] GC before operation: completed in 804.547 ms, heap usage 160.066 MB -> 83.447 MB. [2025-10-01T23:54:20.345Z] ====== als (apache-spark) [default], iteration 21 completed (19863.951 ms) ====== [2025-10-01T23:54:20.345Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-01T23:54:20.345Z] GC before operation: completed in 792.588 ms, heap usage 139.459 MB -> 83.600 MB. [2025-10-01T23:54:39.447Z] ====== als (apache-spark) [default], iteration 22 completed (19864.377 ms) ====== [2025-10-01T23:54:39.447Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-01T23:54:40.169Z] GC before operation: completed in 815.713 ms, heap usage 274.124 MB -> 84.107 MB. [2025-10-01T23:55:03.182Z] ====== als (apache-spark) [default], iteration 23 completed (19639.552 ms) ====== [2025-10-01T23:55:03.182Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-01T23:55:03.182Z] GC before operation: completed in 869.333 ms, heap usage 779.178 MB -> 84.753 MB. [2025-10-01T23:55:22.283Z] ====== als (apache-spark) [default], iteration 24 completed (20197.270 ms) ====== [2025-10-01T23:55:22.283Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-01T23:55:23.432Z] GC before operation: completed in 833.496 ms, heap usage 388.574 MB -> 84.645 MB. [2025-10-01T23:55:46.526Z] ====== als (apache-spark) [default], iteration 25 completed (19622.855 ms) ====== [2025-10-01T23:55:46.526Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-01T23:55:46.526Z] GC before operation: completed in 806.901 ms, heap usage 334.818 MB -> 84.758 MB. [2025-10-01T23:56:05.596Z] ====== als (apache-spark) [default], iteration 26 completed (19433.347 ms) ====== [2025-10-01T23:56:05.596Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-01T23:56:05.596Z] GC before operation: completed in 808.708 ms, heap usage 149.443 MB -> 84.901 MB. [2025-10-01T23:56:28.575Z] ====== als (apache-spark) [default], iteration 27 completed (19063.142 ms) ====== [2025-10-01T23:56:28.575Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-01T23:56:28.575Z] GC before operation: completed in 819.988 ms, heap usage 243.358 MB -> 85.289 MB. [2025-10-01T23:56:47.698Z] ====== als (apache-spark) [default], iteration 28 completed (19267.308 ms) ====== [2025-10-01T23:56:47.698Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-01T23:56:47.698Z] GC before operation: completed in 846.040 ms, heap usage 646.167 MB -> 85.557 MB. [2025-10-01T23:57:10.663Z] ====== als (apache-spark) [default], iteration 29 completed (19264.621 ms) ====== [2025-10-01T23:57:10.663Z] ----------------------------------- [2025-10-01T23:57:10.663Z] renaissance-als_0_PASSED [2025-10-01T23:57:10.663Z] ----------------------------------- [2025-10-01T23:57:10.663Z] [2025-10-01T23:57:10.663Z] TEST TEARDOWN: [2025-10-01T23:57:10.663Z] Nothing to be done for teardown. [2025-10-01T23:57:10.663Z] renaissance-als_0 Finish Time: Wed Oct 1 23:57:09 2025 Epoch Time (ms): 1759363029854