[2025-09-04T06:08:21.263Z] Running test renaissance-als_0 ... [2025-09-04T06:08:21.263Z] =============================================== [2025-09-04T06:08:21.263Z] renaissance-als_0 Start Time: Thu Sep 4 06:08:21 2025 Epoch Time (ms): 1756966101114 [2025-09-04T06:08:21.263Z] variation: NoOptions [2025-09-04T06:08:21.263Z] JVM_OPTIONS: [2025-09-04T06:08:21.263Z] { \ [2025-09-04T06:08:21.263Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T06:08:21.263Z] echo "Nothing to be done for setup."; \ [2025-09-04T06:08:21.263Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17569661009691/renaissance-als_0"; \ [2025-09-04T06:08:21.263Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17569661009691/renaissance-als_0"; \ [2025-09-04T06:08:21.263Z] echo ""; echo "TESTING:"; \ [2025-09-04T06:08:21.263Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17569661009691/renaissance-als_0"/als.json" als; \ [2025-09-04T06:08:21.263Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17569661009691/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T06:08:21.263Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T06:08:21.263Z] echo "Nothing to be done for teardown."; \ [2025-09-04T06:08:21.263Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17569661009691/TestTargetResult"; [2025-09-04T06:08:21.263Z] [2025-09-04T06:08:21.263Z] TEST SETUP: [2025-09-04T06:08:21.263Z] Nothing to be done for setup. [2025-09-04T06:08:21.263Z] [2025-09-04T06:08:21.263Z] TESTING: [2025-09-04T06:08:33.103Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-04T06:08:37.595Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-04T06:08:38.378Z] GC before operation: completed in 84.069 ms, heap usage 53.568 MB -> 35.122 MB. [2025-09-04T06:09:14.190Z] ====== als (apache-spark) [default], iteration 0 completed (32218.604 ms) ====== [2025-09-04T06:09:14.190Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-04T06:09:14.190Z] GC before operation: completed in 185.252 ms, heap usage 184.113 MB -> 61.764 MB. [2025-09-04T06:09:24.201Z] ====== als (apache-spark) [default], iteration 1 completed (12216.506 ms) ====== [2025-09-04T06:09:24.201Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-04T06:09:24.201Z] GC before operation: completed in 139.034 ms, heap usage 236.985 MB -> 62.701 MB. [2025-09-04T06:09:38.129Z] ====== als (apache-spark) [default], iteration 2 completed (12004.355 ms) ====== [2025-09-04T06:09:38.129Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-04T06:09:38.129Z] GC before operation: completed in 155.939 ms, heap usage 99.256 MB -> 62.815 MB. [2025-09-04T06:09:49.894Z] ====== als (apache-spark) [default], iteration 3 completed (11277.473 ms) ====== [2025-09-04T06:09:49.894Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-04T06:09:49.894Z] GC before operation: completed in 132.729 ms, heap usage 259.520 MB -> 63.864 MB. [2025-09-04T06:09:59.864Z] ====== als (apache-spark) [default], iteration 4 completed (10081.069 ms) ====== [2025-09-04T06:09:59.864Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-04T06:09:59.864Z] GC before operation: completed in 186.563 ms, heap usage 124.499 MB -> 64.363 MB. [2025-09-04T06:10:14.263Z] ====== als (apache-spark) [default], iteration 5 completed (13190.849 ms) ====== [2025-09-04T06:10:14.263Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-04T06:10:14.263Z] GC before operation: completed in 185.212 ms, heap usage 542.428 MB -> 65.974 MB. [2025-09-04T06:10:24.237Z] ====== als (apache-spark) [default], iteration 6 completed (10130.607 ms) ====== [2025-09-04T06:10:24.237Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-04T06:10:24.237Z] GC before operation: completed in 164.230 ms, heap usage 364.352 MB -> 65.607 MB. [2025-09-04T06:10:36.124Z] ====== als (apache-spark) [default], iteration 7 completed (10422.237 ms) ====== [2025-09-04T06:10:36.124Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-04T06:10:36.124Z] GC before operation: completed in 179.981 ms, heap usage 267.248 MB -> 66.108 MB. [2025-09-04T06:10:46.104Z] ====== als (apache-spark) [default], iteration 8 completed (10294.088 ms) ====== [2025-09-04T06:10:46.104Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-04T06:10:46.104Z] GC before operation: completed in 224.624 ms, heap usage 363.361 MB -> 66.730 MB. [2025-09-04T06:10:56.059Z] ====== als (apache-spark) [default], iteration 9 completed (10045.226 ms) ====== [2025-09-04T06:10:56.060Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-04T06:10:56.060Z] GC before operation: completed in 122.572 ms, heap usage 157.960 MB -> 66.847 MB. [2025-09-04T06:11:07.803Z] ====== als (apache-spark) [default], iteration 10 completed (11413.099 ms) ====== [2025-09-04T06:11:07.803Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-04T06:11:08.600Z] GC before operation: completed in 172.401 ms, heap usage 448.346 MB -> 67.547 MB. [2025-09-04T06:11:19.088Z] ====== als (apache-spark) [default], iteration 11 completed (9883.259 ms) ====== [2025-09-04T06:11:19.088Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-04T06:11:19.088Z] GC before operation: completed in 182.965 ms, heap usage 187.060 MB -> 67.381 MB. [2025-09-04T06:11:29.210Z] ====== als (apache-spark) [default], iteration 12 completed (10120.051 ms) ====== [2025-09-04T06:11:29.210Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-04T06:11:29.210Z] GC before operation: completed in 137.679 ms, heap usage 290.834 MB -> 67.747 MB. [2025-09-04T06:11:41.934Z] ====== als (apache-spark) [default], iteration 13 completed (10818.873 ms) ====== [2025-09-04T06:11:41.934Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-04T06:11:41.934Z] GC before operation: completed in 185.216 ms, heap usage 361.209 MB -> 68.139 MB. [2025-09-04T06:11:53.729Z] ====== als (apache-spark) [default], iteration 14 completed (12568.526 ms) ====== [2025-09-04T06:11:53.729Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-04T06:11:53.729Z] GC before operation: completed in 142.460 ms, heap usage 490.180 MB -> 68.548 MB. [2025-09-04T06:12:09.712Z] ====== als (apache-spark) [default], iteration 15 completed (14563.643 ms) ====== [2025-09-04T06:12:09.712Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-04T06:12:09.712Z] GC before operation: completed in 186.660 ms, heap usage 512.094 MB -> 68.828 MB. [2025-09-04T06:12:18.095Z] ====== als (apache-spark) [default], iteration 16 completed (8713.460 ms) ====== [2025-09-04T06:12:18.095Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-04T06:12:18.095Z] GC before operation: completed in 121.967 ms, heap usage 347.702 MB -> 68.974 MB. [2025-09-04T06:12:28.080Z] ====== als (apache-spark) [default], iteration 17 completed (9493.963 ms) ====== [2025-09-04T06:12:28.080Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-04T06:12:28.080Z] GC before operation: completed in 133.019 ms, heap usage 514.579 MB -> 69.313 MB. [2025-09-04T06:12:38.043Z] ====== als (apache-spark) [default], iteration 18 completed (9724.048 ms) ====== [2025-09-04T06:12:38.043Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-04T06:12:38.043Z] GC before operation: completed in 224.676 ms, heap usage 265.869 MB -> 69.292 MB. [2025-09-04T06:12:47.991Z] ====== als (apache-spark) [default], iteration 19 completed (9881.309 ms) ====== [2025-09-04T06:12:47.991Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-04T06:12:47.991Z] GC before operation: completed in 189.271 ms, heap usage 163.272 MB -> 69.412 MB. [2025-09-04T06:12:58.085Z] ====== als (apache-spark) [default], iteration 20 completed (10096.323 ms) ====== [2025-09-04T06:12:58.085Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-04T06:12:58.870Z] GC before operation: completed in 215.276 ms, heap usage 432.361 MB -> 70.139 MB. [2025-09-04T06:13:08.839Z] ====== als (apache-spark) [default], iteration 21 completed (8991.691 ms) ====== [2025-09-04T06:13:08.839Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-04T06:13:08.839Z] GC before operation: completed in 200.197 ms, heap usage 381.740 MB -> 70.136 MB. [2025-09-04T06:13:21.156Z] ====== als (apache-spark) [default], iteration 22 completed (11498.777 ms) ====== [2025-09-04T06:13:21.156Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-04T06:13:21.156Z] GC before operation: completed in 139.043 ms, heap usage 253.447 MB -> 70.367 MB. [2025-09-04T06:13:31.331Z] ====== als (apache-spark) [default], iteration 23 completed (10570.435 ms) ====== [2025-09-04T06:13:31.331Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-04T06:13:31.331Z] GC before operation: completed in 129.665 ms, heap usage 253.477 MB -> 70.639 MB. [2025-09-04T06:13:41.290Z] ====== als (apache-spark) [default], iteration 24 completed (9957.075 ms) ====== [2025-09-04T06:13:41.291Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-04T06:13:41.291Z] GC before operation: completed in 122.031 ms, heap usage 228.150 MB -> 70.793 MB. [2025-09-04T06:13:51.331Z] ====== als (apache-spark) [default], iteration 25 completed (9647.659 ms) ====== [2025-09-04T06:13:51.331Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-04T06:13:51.331Z] GC before operation: completed in 130.786 ms, heap usage 360.712 MB -> 71.326 MB. [2025-09-04T06:14:01.277Z] ====== als (apache-spark) [default], iteration 26 completed (9493.135 ms) ====== [2025-09-04T06:14:01.277Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-04T06:14:01.277Z] GC before operation: completed in 139.292 ms, heap usage 513.194 MB -> 71.730 MB. [2025-09-04T06:14:11.250Z] ====== als (apache-spark) [default], iteration 27 completed (9806.693 ms) ====== [2025-09-04T06:14:11.250Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-04T06:14:11.250Z] GC before operation: completed in 173.385 ms, heap usage 351.026 MB -> 71.752 MB. [2025-09-04T06:14:21.733Z] ====== als (apache-spark) [default], iteration 28 completed (9472.627 ms) ====== [2025-09-04T06:14:21.733Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-04T06:14:21.733Z] GC before operation: completed in 142.010 ms, heap usage 110.611 MB -> 71.350 MB. [2025-09-04T06:14:30.119Z] ====== als (apache-spark) [default], iteration 29 completed (8865.946 ms) ====== [2025-09-04T06:14:30.898Z] ----------------------------------- [2025-09-04T06:14:30.898Z] renaissance-als_0_PASSED [2025-09-04T06:14:30.898Z] ----------------------------------- [2025-09-04T06:14:30.898Z] [2025-09-04T06:14:30.898Z] TEST TEARDOWN: [2025-09-04T06:14:30.898Z] Nothing to be done for teardown. [2025-09-04T06:14:30.898Z] renaissance-als_0 Finish Time: Thu Sep 4 06:14:30 2025 Epoch Time (ms): 1756966470452