[2025-09-25T00:28:41.930Z] Running test renaissance-als_0 ... [2025-09-25T00:28:41.930Z] =============================================== [2025-09-25T00:28:41.930Z] renaissance-als_0 Start Time: Thu Sep 25 00:28:40 2025 Epoch Time (ms): 1758760120944 [2025-09-25T00:28:41.930Z] variation: NoOptions [2025-09-25T00:28:41.930Z] JVM_OPTIONS: [2025-09-25T00:28:41.930Z] { \ [2025-09-25T00:28:41.930Z] echo ""; echo "TEST SETUP:"; \ [2025-09-25T00:28:41.930Z] echo "Nothing to be done for setup."; \ [2025-09-25T00:28:41.930Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17587599761548/renaissance-als_0"; \ [2025-09-25T00:28:41.930Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17587599761548/renaissance-als_0"; \ [2025-09-25T00:28:41.930Z] echo ""; echo "TESTING:"; \ [2025-09-25T00:28:41.930Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-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_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17587599761548/renaissance-als_0"/als.json" als; \ [2025-09-25T00:28:41.930Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17587599761548/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-25T00:28:41.930Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-25T00:28:41.930Z] echo "Nothing to be done for teardown."; \ [2025-09-25T00:28:41.930Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17587599761548/TestTargetResult"; [2025-09-25T00:28:41.930Z] [2025-09-25T00:28:41.930Z] TEST SETUP: [2025-09-25T00:28:41.930Z] Nothing to be done for setup. [2025-09-25T00:28:41.930Z] [2025-09-25T00:28:41.930Z] TESTING: [2025-09-25T00:28:47.909Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-25T00:28:51.996Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-25T00:28:51.996Z] GC before operation: completed in 46.087 ms, heap usage 47.500 MB -> 35.068 MB. [2025-09-25T00:29:13.096Z] ====== als (apache-spark) [default], iteration 0 completed (18158.972 ms) ====== [2025-09-25T00:29:13.096Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-25T00:29:13.096Z] GC before operation: completed in 107.123 ms, heap usage 192.657 MB -> 61.824 MB. [2025-09-25T00:29:18.397Z] ====== als (apache-spark) [default], iteration 1 completed (6879.006 ms) ====== [2025-09-25T00:29:18.397Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-25T00:29:18.397Z] GC before operation: completed in 123.977 ms, heap usage 328.859 MB -> 62.859 MB. [2025-09-25T00:29:25.032Z] ====== als (apache-spark) [default], iteration 2 completed (5921.175 ms) ====== [2025-09-25T00:29:25.032Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-25T00:29:25.032Z] GC before operation: completed in 122.587 ms, heap usage 293.796 MB -> 63.588 MB. [2025-09-25T00:29:30.330Z] ====== als (apache-spark) [default], iteration 3 completed (5856.142 ms) ====== [2025-09-25T00:29:30.330Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-25T00:29:31.281Z] GC before operation: completed in 126.370 ms, heap usage 292.115 MB -> 64.100 MB. [2025-09-25T00:29:36.577Z] ====== als (apache-spark) [default], iteration 4 completed (5550.770 ms) ====== [2025-09-25T00:29:36.577Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-25T00:29:36.577Z] GC before operation: completed in 138.284 ms, heap usage 252.113 MB -> 64.547 MB. [2025-09-25T00:29:41.883Z] ====== als (apache-spark) [default], iteration 5 completed (5237.979 ms) ====== [2025-09-25T00:29:41.883Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-25T00:29:41.883Z] GC before operation: completed in 119.110 ms, heap usage 98.019 MB -> 64.924 MB. [2025-09-25T00:29:48.494Z] ====== als (apache-spark) [default], iteration 6 completed (5271.417 ms) ====== [2025-09-25T00:29:48.494Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-25T00:29:48.494Z] GC before operation: completed in 127.951 ms, heap usage 436.671 MB -> 65.929 MB. [2025-09-25T00:29:53.792Z] ====== als (apache-spark) [default], iteration 7 completed (5338.357 ms) ====== [2025-09-25T00:29:53.792Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-25T00:29:53.792Z] GC before operation: completed in 144.119 ms, heap usage 431.587 MB -> 66.489 MB. [2025-09-25T00:29:58.576Z] ====== als (apache-spark) [default], iteration 8 completed (5273.447 ms) ====== [2025-09-25T00:29:58.576Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-25T00:29:59.511Z] GC before operation: completed in 134.130 ms, heap usage 389.931 MB -> 66.816 MB. [2025-09-25T00:30:04.832Z] ====== als (apache-spark) [default], iteration 9 completed (5136.632 ms) ====== [2025-09-25T00:30:04.833Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-25T00:30:04.833Z] GC before operation: completed in 157.558 ms, heap usage 323.202 MB -> 67.081 MB. [2025-09-25T00:30:10.125Z] ====== als (apache-spark) [default], iteration 10 completed (5155.697 ms) ====== [2025-09-25T00:30:10.125Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-25T00:30:10.125Z] GC before operation: completed in 144.845 ms, heap usage 202.321 MB -> 66.976 MB. [2025-09-25T00:30:15.416Z] ====== als (apache-spark) [default], iteration 11 completed (5137.284 ms) ====== [2025-09-25T00:30:15.416Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-25T00:30:15.416Z] GC before operation: completed in 151.033 ms, heap usage 330.122 MB -> 67.520 MB. [2025-09-25T00:30:20.710Z] ====== als (apache-spark) [default], iteration 12 completed (5038.119 ms) ====== [2025-09-25T00:30:20.710Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-25T00:30:20.710Z] GC before operation: completed in 139.838 ms, heap usage 128.208 MB -> 67.553 MB. [2025-09-25T00:30:26.039Z] ====== als (apache-spark) [default], iteration 13 completed (5106.680 ms) ====== [2025-09-25T00:30:26.039Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-25T00:30:26.039Z] GC before operation: completed in 167.985 ms, heap usage 150.811 MB -> 67.873 MB. [2025-09-25T00:30:32.666Z] ====== als (apache-spark) [default], iteration 14 completed (5957.713 ms) ====== [2025-09-25T00:30:32.666Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-25T00:30:32.666Z] GC before operation: completed in 171.393 ms, heap usage 432.579 MB -> 68.511 MB. [2025-09-25T00:30:39.274Z] ====== als (apache-spark) [default], iteration 15 completed (5823.163 ms) ====== [2025-09-25T00:30:39.274Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-25T00:30:39.274Z] GC before operation: completed in 199.117 ms, heap usage 542.214 MB -> 68.834 MB. [2025-09-25T00:30:45.871Z] ====== als (apache-spark) [default], iteration 16 completed (5998.808 ms) ====== [2025-09-25T00:30:45.871Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-25T00:30:45.871Z] GC before operation: completed in 170.997 ms, heap usage 504.091 MB -> 69.023 MB. [2025-09-25T00:30:52.476Z] ====== als (apache-spark) [default], iteration 17 completed (6788.385 ms) ====== [2025-09-25T00:30:52.476Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-25T00:30:52.476Z] GC before operation: completed in 143.490 ms, heap usage 325.451 MB -> 69.023 MB. [2025-09-25T00:30:57.911Z] ====== als (apache-spark) [default], iteration 18 completed (5183.293 ms) ====== [2025-09-25T00:30:57.911Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-25T00:30:57.911Z] GC before operation: completed in 139.679 ms, heap usage 306.708 MB -> 69.397 MB. [2025-09-25T00:31:03.482Z] ====== als (apache-spark) [default], iteration 19 completed (5190.006 ms) ====== [2025-09-25T00:31:03.482Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-25T00:31:03.482Z] GC before operation: completed in 149.495 ms, heap usage 322.246 MB -> 69.585 MB. [2025-09-25T00:31:08.818Z] ====== als (apache-spark) [default], iteration 20 completed (5159.255 ms) ====== [2025-09-25T00:31:08.819Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-25T00:31:08.819Z] GC before operation: completed in 152.925 ms, heap usage 210.601 MB -> 69.628 MB. [2025-09-25T00:31:14.206Z] ====== als (apache-spark) [default], iteration 21 completed (5031.554 ms) ====== [2025-09-25T00:31:14.206Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-25T00:31:14.206Z] GC before operation: completed in 147.811 ms, heap usage 261.437 MB -> 70.032 MB. [2025-09-25T00:31:20.979Z] ====== als (apache-spark) [default], iteration 22 completed (5813.274 ms) ====== [2025-09-25T00:31:20.979Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-25T00:31:20.979Z] GC before operation: completed in 165.165 ms, heap usage 292.871 MB -> 70.325 MB. [2025-09-25T00:31:27.637Z] ====== als (apache-spark) [default], iteration 23 completed (5907.388 ms) ====== [2025-09-25T00:31:27.637Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-25T00:31:27.637Z] GC before operation: completed in 176.876 ms, heap usage 214.657 MB -> 70.603 MB. [2025-09-25T00:31:32.946Z] ====== als (apache-spark) [default], iteration 24 completed (5711.783 ms) ====== [2025-09-25T00:31:32.946Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-25T00:31:32.946Z] GC before operation: completed in 183.379 ms, heap usage 532.182 MB -> 71.318 MB. [2025-09-25T00:31:39.569Z] ====== als (apache-spark) [default], iteration 25 completed (5593.472 ms) ====== [2025-09-25T00:31:39.569Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-25T00:31:39.569Z] GC before operation: completed in 171.553 ms, heap usage 223.845 MB -> 71.047 MB. [2025-09-25T00:31:44.918Z] ====== als (apache-spark) [default], iteration 26 completed (5868.123 ms) ====== [2025-09-25T00:31:44.918Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-25T00:31:44.918Z] GC before operation: completed in 170.791 ms, heap usage 497.817 MB -> 71.766 MB. [2025-09-25T00:31:51.518Z] ====== als (apache-spark) [default], iteration 27 completed (5450.097 ms) ====== [2025-09-25T00:31:51.519Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-25T00:31:51.519Z] GC before operation: completed in 215.269 ms, heap usage 625.757 MB -> 72.124 MB. [2025-09-25T00:31:56.824Z] ====== als (apache-spark) [default], iteration 28 completed (5465.550 ms) ====== [2025-09-25T00:31:56.824Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-25T00:31:56.824Z] GC before operation: completed in 156.436 ms, heap usage 359.787 MB -> 71.641 MB. [2025-09-25T00:32:03.482Z] ====== als (apache-spark) [default], iteration 29 completed (6323.677 ms) ====== [2025-09-25T00:32:04.436Z] ----------------------------------- [2025-09-25T00:32:04.436Z] renaissance-als_0_PASSED [2025-09-25T00:32:04.436Z] ----------------------------------- [2025-09-25T00:32:04.436Z] [2025-09-25T00:32:04.436Z] TEST TEARDOWN: [2025-09-25T00:32:04.436Z] Nothing to be done for teardown. [2025-09-25T00:32:04.436Z] renaissance-als_0 Finish Time: Thu Sep 25 00:32:03 2025 Epoch Time (ms): 1758760323981