[2025-06-18T21:16:06.380Z] Running test renaissance-als_0 ... [2025-06-18T21:16:06.380Z] =============================================== [2025-06-18T21:16:06.380Z] renaissance-als_0 Start Time: Wed Jun 18 21:16:05 2025 Epoch Time (ms): 1750281365314 [2025-06-18T21:16:06.380Z] variation: NoOptions [2025-06-18T21:16:06.380Z] JVM_OPTIONS: [2025-06-18T21:16:06.380Z] { \ [2025-06-18T21:16:06.380Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T21:16:06.380Z] echo "Nothing to be done for setup."; \ [2025-06-18T21:16:06.380Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/renaissance-als_0"; \ [2025-06-18T21:16:06.380Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/renaissance-als_0"; \ [2025-06-18T21:16:06.380Z] echo ""; echo "TESTING:"; \ [2025-06-18T21:16:06.380Z] "/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_17502813221287/renaissance-als_0"/als.json" als; \ [2025-06-18T21:16:06.380Z] 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_17502813221287/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T21:16:06.380Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T21:16:06.380Z] echo "Nothing to be done for teardown."; \ [2025-06-18T21:16:06.380Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/TestTargetResult"; [2025-06-18T21:16:06.380Z] [2025-06-18T21:16:06.380Z] TEST SETUP: [2025-06-18T21:16:06.380Z] Nothing to be done for setup. [2025-06-18T21:16:06.380Z] [2025-06-18T21:16:06.380Z] TESTING: [2025-06-18T21:16:12.997Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-18T21:16:18.310Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-18T21:16:18.310Z] GC before operation: completed in 38.977 ms, heap usage 121.450 MB -> 36.507 MB. [2025-06-18T21:16:39.378Z] ====== als (apache-spark) [default], iteration 0 completed (19900.994 ms) ====== [2025-06-18T21:16:39.378Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-18T21:16:39.378Z] GC before operation: completed in 140.692 ms, heap usage 1.041 GB -> 64.481 MB. [2025-06-18T21:16:44.840Z] ====== als (apache-spark) [default], iteration 1 completed (5496.345 ms) ====== [2025-06-18T21:16:44.840Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-18T21:16:44.840Z] GC before operation: completed in 167.572 ms, heap usage 861.927 MB -> 65.299 MB. [2025-06-18T21:16:51.752Z] ====== als (apache-spark) [default], iteration 2 completed (6536.744 ms) ====== [2025-06-18T21:16:51.752Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-18T21:16:51.752Z] GC before operation: completed in 147.192 ms, heap usage 227.576 MB -> 65.060 MB. [2025-06-18T21:16:57.350Z] ====== als (apache-spark) [default], iteration 3 completed (4603.850 ms) ====== [2025-06-18T21:16:57.350Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-18T21:16:57.350Z] GC before operation: completed in 115.315 ms, heap usage 974.255 MB -> 66.602 MB. [2025-06-18T21:17:01.449Z] ====== als (apache-spark) [default], iteration 4 completed (4470.554 ms) ====== [2025-06-18T21:17:01.449Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-18T21:17:02.387Z] GC before operation: completed in 119.976 ms, heap usage 550.662 MB -> 66.816 MB. [2025-06-18T21:17:06.491Z] ====== als (apache-spark) [default], iteration 5 completed (4258.365 ms) ====== [2025-06-18T21:17:06.491Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-18T21:17:06.491Z] GC before operation: completed in 124.090 ms, heap usage 1.002 GB -> 67.841 MB. [2025-06-18T21:17:11.838Z] ====== als (apache-spark) [default], iteration 6 completed (4341.972 ms) ====== [2025-06-18T21:17:11.838Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-18T21:17:11.838Z] GC before operation: completed in 125.587 ms, heap usage 1.203 GB -> 68.753 MB. [2025-06-18T21:17:16.014Z] ====== als (apache-spark) [default], iteration 7 completed (4161.005 ms) ====== [2025-06-18T21:17:16.014Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-18T21:17:16.014Z] GC before operation: completed in 115.925 ms, heap usage 684.579 MB -> 68.730 MB. [2025-06-18T21:17:20.118Z] ====== als (apache-spark) [default], iteration 8 completed (4380.487 ms) ====== [2025-06-18T21:17:20.118Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-18T21:17:20.118Z] GC before operation: completed in 138.068 ms, heap usage 237.182 MB -> 68.603 MB. [2025-06-18T21:17:25.432Z] ====== als (apache-spark) [default], iteration 9 completed (4193.958 ms) ====== [2025-06-18T21:17:25.432Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-18T21:17:25.432Z] GC before operation: completed in 132.297 ms, heap usage 1.244 GB -> 69.997 MB. [2025-06-18T21:17:29.534Z] ====== als (apache-spark) [default], iteration 10 completed (4125.744 ms) ====== [2025-06-18T21:17:29.534Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-18T21:17:29.534Z] GC before operation: completed in 126.840 ms, heap usage 632.481 MB -> 69.569 MB. [2025-06-18T21:17:33.761Z] ====== als (apache-spark) [default], iteration 11 completed (4036.214 ms) ====== [2025-06-18T21:17:33.761Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-18T21:17:33.761Z] GC before operation: completed in 129.704 ms, heap usage 864.322 MB -> 69.904 MB. [2025-06-18T21:17:38.006Z] ====== als (apache-spark) [default], iteration 12 completed (4201.650 ms) ====== [2025-06-18T21:17:38.006Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-18T21:17:38.006Z] GC before operation: completed in 131.829 ms, heap usage 1.014 GB -> 70.576 MB. [2025-06-18T21:17:43.361Z] ====== als (apache-spark) [default], iteration 13 completed (4085.756 ms) ====== [2025-06-18T21:17:43.361Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-18T21:17:43.361Z] GC before operation: completed in 141.495 ms, heap usage 1.366 GB -> 71.269 MB. [2025-06-18T21:17:47.468Z] ====== als (apache-spark) [default], iteration 14 completed (3864.424 ms) ====== [2025-06-18T21:17:47.468Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-18T21:17:47.468Z] GC before operation: completed in 133.452 ms, heap usage 826.070 MB -> 70.875 MB. [2025-06-18T21:17:51.596Z] ====== als (apache-spark) [default], iteration 15 completed (4299.030 ms) ====== [2025-06-18T21:17:51.596Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-18T21:17:51.596Z] GC before operation: completed in 141.499 ms, heap usage 553.660 MB -> 70.781 MB. [2025-06-18T21:17:56.381Z] ====== als (apache-spark) [default], iteration 16 completed (3996.955 ms) ====== [2025-06-18T21:17:56.381Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-18T21:17:56.381Z] GC before operation: completed in 154.830 ms, heap usage 1.331 GB -> 71.979 MB. [2025-06-18T21:18:00.570Z] ====== als (apache-spark) [default], iteration 17 completed (4065.174 ms) ====== [2025-06-18T21:18:00.570Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-18T21:18:00.570Z] GC before operation: completed in 129.412 ms, heap usage 827.484 MB -> 71.692 MB. [2025-06-18T21:18:04.731Z] ====== als (apache-spark) [default], iteration 18 completed (4007.329 ms) ====== [2025-06-18T21:18:04.731Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-18T21:18:04.731Z] GC before operation: completed in 137.843 ms, heap usage 316.133 MB -> 71.377 MB. [2025-06-18T21:18:08.831Z] ====== als (apache-spark) [default], iteration 19 completed (4061.462 ms) ====== [2025-06-18T21:18:08.831Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-18T21:18:08.831Z] GC before operation: completed in 139.600 ms, heap usage 1.321 GB -> 72.811 MB. [2025-06-18T21:18:12.939Z] ====== als (apache-spark) [default], iteration 20 completed (3988.567 ms) ====== [2025-06-18T21:18:12.939Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-18T21:18:12.939Z] GC before operation: completed in 143.166 ms, heap usage 670.532 MB -> 72.447 MB. [2025-06-18T21:18:17.084Z] ====== als (apache-spark) [default], iteration 21 completed (3882.641 ms) ====== [2025-06-18T21:18:17.084Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-18T21:18:17.084Z] GC before operation: completed in 140.766 ms, heap usage 1.214 GB -> 73.271 MB. [2025-06-18T21:18:21.191Z] ====== als (apache-spark) [default], iteration 22 completed (3889.496 ms) ====== [2025-06-18T21:18:21.191Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-18T21:18:22.132Z] GC before operation: completed in 141.344 ms, heap usage 925.861 MB -> 73.301 MB. [2025-06-18T21:18:26.244Z] ====== als (apache-spark) [default], iteration 23 completed (4097.719 ms) ====== [2025-06-18T21:18:26.244Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-18T21:18:26.244Z] GC before operation: completed in 227.760 ms, heap usage 1.293 GB -> 73.822 MB. [2025-06-18T21:18:30.352Z] ====== als (apache-spark) [default], iteration 24 completed (3849.544 ms) ====== [2025-06-18T21:18:30.352Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-18T21:18:30.352Z] GC before operation: completed in 176.753 ms, heap usage 238.555 MB -> 72.774 MB. [2025-06-18T21:18:34.487Z] ====== als (apache-spark) [default], iteration 25 completed (3708.178 ms) ====== [2025-06-18T21:18:34.487Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-18T21:18:34.487Z] GC before operation: completed in 146.274 ms, heap usage 1.172 GB -> 74.222 MB. [2025-06-18T21:18:38.819Z] ====== als (apache-spark) [default], iteration 26 completed (3846.835 ms) ====== [2025-06-18T21:18:38.819Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-18T21:18:38.819Z] GC before operation: completed in 160.669 ms, heap usage 618.228 MB -> 73.889 MB. [2025-06-18T21:18:42.928Z] ====== als (apache-spark) [default], iteration 27 completed (3835.404 ms) ====== [2025-06-18T21:18:42.928Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-18T21:18:42.928Z] GC before operation: completed in 146.720 ms, heap usage 1.237 GB -> 74.888 MB. [2025-06-18T21:18:47.051Z] ====== als (apache-spark) [default], iteration 28 completed (3771.497 ms) ====== [2025-06-18T21:18:47.051Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-18T21:18:47.051Z] GC before operation: completed in 138.682 ms, heap usage 260.845 MB -> 73.911 MB. [2025-06-18T21:18:51.169Z] ====== als (apache-spark) [default], iteration 29 completed (3618.418 ms) ====== [2025-06-18T21:18:51.169Z] ----------------------------------- [2025-06-18T21:18:51.169Z] renaissance-als_0_PASSED [2025-06-18T21:18:51.169Z] ----------------------------------- [2025-06-18T21:18:51.169Z] [2025-06-18T21:18:51.169Z] TEST TEARDOWN: [2025-06-18T21:18:51.169Z] Nothing to be done for teardown. [2025-06-18T21:18:51.169Z] renaissance-als_0 Finish Time: Wed Jun 18 21:18:51 2025 Epoch Time (ms): 1750281531063