[2025-09-16T22:11:56.464Z] Running test renaissance-als_0 ... [2025-09-16T22:11:56.464Z] =============================================== [2025-09-16T22:11:56.464Z] renaissance-als_0 Start Time: Tue Sep 16 22:11:55 2025 Epoch Time (ms): 1758060715951 [2025-09-16T22:11:56.464Z] variation: NoOptions [2025-09-16T22:11:56.464Z] JVM_OPTIONS: [2025-09-16T22:11:56.464Z] { \ [2025-09-16T22:11:56.464Z] echo ""; echo "TEST SETUP:"; \ [2025-09-16T22:11:56.464Z] echo "Nothing to be done for setup."; \ [2025-09-16T22:11:56.464Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17580604993454/renaissance-als_0"; \ [2025-09-16T22:11:56.464Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17580604993454/renaissance-als_0"; \ [2025-09-16T22:11:56.464Z] echo ""; echo "TESTING:"; \ [2025-09-16T22:11:56.464Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17580604993454/renaissance-als_0"/als.json" als; \ [2025-09-16T22:11:56.464Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17580604993454/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-16T22:11:56.464Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-16T22:11:56.464Z] echo "Nothing to be done for teardown."; \ [2025-09-16T22:11:56.464Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17580604993454/TestTargetResult"; [2025-09-16T22:11:56.464Z] [2025-09-16T22:11:56.464Z] TEST SETUP: [2025-09-16T22:11:56.464Z] Nothing to be done for setup. [2025-09-16T22:11:56.465Z] [2025-09-16T22:11:56.465Z] TESTING: [2025-09-16T22:12:01.355Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-09-16T22:12:06.216Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-16T22:12:06.216Z] GC before operation: completed in 47.807 ms, heap usage 125.926 MB -> 34.775 MB. [2025-09-16T22:12:39.378Z] ====== als (apache-spark) [default], iteration 0 completed (30095.537 ms) ====== [2025-09-16T22:12:39.378Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-16T22:12:39.378Z] GC before operation: completed in 94.850 ms, heap usage 150.678 MB -> 61.526 MB. [2025-09-16T22:12:54.011Z] ====== als (apache-spark) [default], iteration 1 completed (15114.024 ms) ====== [2025-09-16T22:12:54.011Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-16T22:12:54.011Z] GC before operation: completed in 92.951 ms, heap usage 314.057 MB -> 62.585 MB. [2025-09-16T22:13:08.669Z] ====== als (apache-spark) [default], iteration 2 completed (14187.165 ms) ====== [2025-09-16T22:13:08.669Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-16T22:13:08.669Z] GC before operation: completed in 93.217 ms, heap usage 219.801 MB -> 63.025 MB. [2025-09-16T22:13:21.602Z] ====== als (apache-spark) [default], iteration 3 completed (14012.830 ms) ====== [2025-09-16T22:13:21.602Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-16T22:13:21.602Z] GC before operation: completed in 103.316 ms, heap usage 251.910 MB -> 63.716 MB. [2025-09-16T22:13:36.314Z] ====== als (apache-spark) [default], iteration 4 completed (13254.941 ms) ====== [2025-09-16T22:13:36.314Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-16T22:13:36.314Z] GC before operation: completed in 97.894 ms, heap usage 163.485 MB -> 64.043 MB. [2025-09-16T22:13:48.705Z] ====== als (apache-spark) [default], iteration 5 completed (13248.979 ms) ====== [2025-09-16T22:13:48.705Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-16T22:13:48.705Z] GC before operation: completed in 101.708 ms, heap usage 387.574 MB -> 64.789 MB. [2025-09-16T22:14:03.338Z] ====== als (apache-spark) [default], iteration 6 completed (13059.600 ms) ====== [2025-09-16T22:14:03.338Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-16T22:14:03.338Z] GC before operation: completed in 98.419 ms, heap usage 137.686 MB -> 65.246 MB. [2025-09-16T22:14:15.722Z] ====== als (apache-spark) [default], iteration 7 completed (12914.182 ms) ====== [2025-09-16T22:14:15.722Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-16T22:14:15.722Z] GC before operation: completed in 104.139 ms, heap usage 277.298 MB -> 65.891 MB. [2025-09-16T22:14:30.448Z] ====== als (apache-spark) [default], iteration 8 completed (13153.392 ms) ====== [2025-09-16T22:14:30.448Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-16T22:14:30.448Z] GC before operation: completed in 99.178 ms, heap usage 203.921 MB -> 66.267 MB. [2025-09-16T22:14:43.313Z] ====== als (apache-spark) [default], iteration 9 completed (13208.582 ms) ====== [2025-09-16T22:14:43.313Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-16T22:14:43.313Z] GC before operation: completed in 104.147 ms, heap usage 253.497 MB -> 66.673 MB. [2025-09-16T22:14:57.954Z] ====== als (apache-spark) [default], iteration 10 completed (13286.335 ms) ====== [2025-09-16T22:14:57.954Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-16T22:14:57.954Z] GC before operation: completed in 117.979 ms, heap usage 280.596 MB -> 66.896 MB. [2025-09-16T22:15:10.345Z] ====== als (apache-spark) [default], iteration 11 completed (13512.906 ms) ====== [2025-09-16T22:15:10.345Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-16T22:15:10.345Z] GC before operation: completed in 106.481 ms, heap usage 207.186 MB -> 67.018 MB. [2025-09-16T22:15:27.596Z] ====== als (apache-spark) [default], iteration 12 completed (16919.406 ms) ====== [2025-09-16T22:15:27.596Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-16T22:15:27.596Z] GC before operation: completed in 109.765 ms, heap usage 365.093 MB -> 67.408 MB. [2025-09-16T22:15:42.245Z] ====== als (apache-spark) [default], iteration 13 completed (13334.857 ms) ====== [2025-09-16T22:15:42.245Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-16T22:15:42.245Z] GC before operation: completed in 114.890 ms, heap usage 155.423 MB -> 67.508 MB. [2025-09-16T22:15:54.635Z] ====== als (apache-spark) [default], iteration 14 completed (13505.907 ms) ====== [2025-09-16T22:15:54.635Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-16T22:15:54.635Z] GC before operation: completed in 102.829 ms, heap usage 386.888 MB -> 67.995 MB. [2025-09-16T22:16:09.273Z] ====== als (apache-spark) [default], iteration 15 completed (13188.871 ms) ====== [2025-09-16T22:16:09.273Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-16T22:16:09.273Z] GC before operation: completed in 105.184 ms, heap usage 248.325 MB -> 68.169 MB. [2025-09-16T22:16:23.911Z] ====== als (apache-spark) [default], iteration 16 completed (13648.913 ms) ====== [2025-09-16T22:16:23.911Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-16T22:16:23.911Z] GC before operation: completed in 108.131 ms, heap usage 448.566 MB -> 68.681 MB. [2025-09-16T22:16:36.809Z] ====== als (apache-spark) [default], iteration 17 completed (13269.266 ms) ====== [2025-09-16T22:16:36.809Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-16T22:16:36.809Z] GC before operation: completed in 104.685 ms, heap usage 168.622 MB -> 68.634 MB. [2025-09-16T22:16:49.200Z] ====== als (apache-spark) [default], iteration 18 completed (13265.840 ms) ====== [2025-09-16T22:16:49.200Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-16T22:16:49.854Z] GC before operation: completed in 112.645 ms, heap usage 505.284 MB -> 69.266 MB. [2025-09-16T22:17:04.491Z] ====== als (apache-spark) [default], iteration 19 completed (13233.285 ms) ====== [2025-09-16T22:17:04.491Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-16T22:17:04.491Z] GC before operation: completed in 109.948 ms, heap usage 501.277 MB -> 69.386 MB. [2025-09-16T22:17:19.143Z] ====== als (apache-spark) [default], iteration 20 completed (13881.620 ms) ====== [2025-09-16T22:17:19.143Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-16T22:17:19.143Z] GC before operation: completed in 110.414 ms, heap usage 274.496 MB -> 69.442 MB. [2025-09-16T22:17:31.533Z] ====== als (apache-spark) [default], iteration 21 completed (13843.477 ms) ====== [2025-09-16T22:17:31.533Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-16T22:17:31.533Z] GC before operation: completed in 108.034 ms, heap usage 357.892 MB -> 69.787 MB. [2025-09-16T22:17:46.588Z] ====== als (apache-spark) [default], iteration 22 completed (13420.970 ms) ====== [2025-09-16T22:17:46.588Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-16T22:17:46.588Z] GC before operation: completed in 110.934 ms, heap usage 201.394 MB -> 69.979 MB. [2025-09-16T22:17:58.983Z] ====== als (apache-spark) [default], iteration 23 completed (13349.648 ms) ====== [2025-09-16T22:17:58.983Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-16T22:17:58.983Z] GC before operation: completed in 108.121 ms, heap usage 226.667 MB -> 70.200 MB. [2025-09-16T22:18:13.626Z] ====== als (apache-spark) [default], iteration 24 completed (13179.108 ms) ====== [2025-09-16T22:18:13.626Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-16T22:18:13.626Z] GC before operation: completed in 109.862 ms, heap usage 159.749 MB -> 70.372 MB. [2025-09-16T22:18:26.140Z] ====== als (apache-spark) [default], iteration 25 completed (13218.674 ms) ====== [2025-09-16T22:18:26.140Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-16T22:18:26.140Z] GC before operation: completed in 109.430 ms, heap usage 353.507 MB -> 70.757 MB. [2025-09-16T22:18:40.784Z] ====== als (apache-spark) [default], iteration 26 completed (13114.996 ms) ====== [2025-09-16T22:18:40.784Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-16T22:18:40.784Z] GC before operation: completed in 110.810 ms, heap usage 494.020 MB -> 71.254 MB. [2025-09-16T22:18:53.178Z] ====== als (apache-spark) [default], iteration 27 completed (13417.240 ms) ====== [2025-09-16T22:18:53.178Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-16T22:18:53.178Z] GC before operation: completed in 109.859 ms, heap usage 186.547 MB -> 71.173 MB. [2025-09-16T22:19:11.132Z] ====== als (apache-spark) [default], iteration 28 completed (17097.715 ms) ====== [2025-09-16T22:19:11.132Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-16T22:19:11.132Z] GC before operation: completed in 109.435 ms, heap usage 366.173 MB -> 71.117 MB. [2025-09-16T22:19:25.768Z] ====== als (apache-spark) [default], iteration 29 completed (13239.540 ms) ====== [2025-09-16T22:19:25.768Z] ----------------------------------- [2025-09-16T22:19:25.768Z] renaissance-als_0_PASSED [2025-09-16T22:19:25.768Z] ----------------------------------- [2025-09-16T22:19:25.768Z] [2025-09-16T22:19:25.768Z] TEST TEARDOWN: [2025-09-16T22:19:25.768Z] Nothing to be done for teardown. [2025-09-16T22:19:25.768Z] renaissance-als_0 Finish Time: Tue Sep 16 22:19:23 2025 Epoch Time (ms): 1758061163928