[2025-12-17T22:05:56.993Z] Running test renaissance-als_0 ... [2025-12-17T22:05:56.993Z] =============================================== [2025-12-17T22:05:56.993Z] renaissance-als_0 Start Time: Wed Dec 17 22:05:56 2025 Epoch Time (ms): 1766009156906 [2025-12-17T22:05:56.993Z] variation: NoOptions [2025-12-17T22:05:56.993Z] JVM_OPTIONS: [2025-12-17T22:05:56.993Z] { \ [2025-12-17T22:05:56.993Z] echo ""; echo "TEST SETUP:"; \ [2025-12-17T22:05:56.993Z] echo "Nothing to be done for setup."; \ [2025-12-17T22:05:56.993Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17660091562547/renaissance-als_0"; \ [2025-12-17T22:05:56.993Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17660091562547/renaissance-als_0"; \ [2025-12-17T22:05:56.993Z] echo ""; echo "TESTING:"; \ [2025-12-17T22:05:56.993Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17660091562547/renaissance-als_0"/als.json" als; \ [2025-12-17T22:05:56.993Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17660091562547/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-17T22:05:56.993Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-17T22:05:56.993Z] echo "Nothing to be done for teardown."; \ [2025-12-17T22:05:56.993Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17660091562547/TestTargetResult"; [2025-12-17T22:05:56.993Z] [2025-12-17T22:05:56.993Z] TEST SETUP: [2025-12-17T22:05:56.993Z] Nothing to be done for setup. [2025-12-17T22:05:56.993Z] [2025-12-17T22:05:56.993Z] TESTING: [2025-12-17T22:06:05.254Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-17T22:06:12.137Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-17T22:06:12.903Z] GC before operation: completed in 378.587 ms, heap usage 90.926 MB -> 34.964 MB. [2025-12-17T22:07:01.807Z] ====== als (apache-spark) [default], iteration 0 completed (47936.277 ms) ====== [2025-12-17T22:07:01.807Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-17T22:07:01.807Z] GC before operation: completed in 151.370 ms, heap usage 278.063 MB -> 61.739 MB. [2025-12-17T22:07:20.703Z] ====== als (apache-spark) [default], iteration 1 completed (17121.206 ms) ====== [2025-12-17T22:07:20.703Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-17T22:07:20.703Z] GC before operation: completed in 142.141 ms, heap usage 243.716 MB -> 62.533 MB. [2025-12-17T22:07:32.396Z] ====== als (apache-spark) [default], iteration 2 completed (12698.174 ms) ====== [2025-12-17T22:07:32.396Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-17T22:07:32.396Z] GC before operation: completed in 146.782 ms, heap usage 190.528 MB -> 63.204 MB. [2025-12-17T22:07:46.153Z] ====== als (apache-spark) [default], iteration 3 completed (12532.140 ms) ====== [2025-12-17T22:07:46.153Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-17T22:07:46.153Z] GC before operation: completed in 115.975 ms, heap usage 450.949 MB -> 64.081 MB. [2025-12-17T22:07:57.892Z] ====== als (apache-spark) [default], iteration 4 completed (11601.819 ms) ====== [2025-12-17T22:07:57.892Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-17T22:07:57.892Z] GC before operation: completed in 138.397 ms, heap usage 141.637 MB -> 64.192 MB. [2025-12-17T22:08:09.523Z] ====== als (apache-spark) [default], iteration 5 completed (10987.030 ms) ====== [2025-12-17T22:08:09.523Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-17T22:08:09.523Z] GC before operation: completed in 125.165 ms, heap usage 382.325 MB -> 65.085 MB. [2025-12-17T22:08:21.719Z] ====== als (apache-spark) [default], iteration 6 completed (11348.551 ms) ====== [2025-12-17T22:08:21.719Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-17T22:08:21.719Z] GC before operation: completed in 200.047 ms, heap usage 456.193 MB -> 65.770 MB. [2025-12-17T22:08:37.869Z] ====== als (apache-spark) [default], iteration 7 completed (14728.769 ms) ====== [2025-12-17T22:08:37.869Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-17T22:08:37.869Z] GC before operation: completed in 288.531 ms, heap usage 521.309 MB -> 66.505 MB. [2025-12-17T22:08:51.663Z] ====== als (apache-spark) [default], iteration 8 completed (14408.240 ms) ====== [2025-12-17T22:08:51.663Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-17T22:08:51.663Z] GC before operation: completed in 177.280 ms, heap usage 483.350 MB -> 66.882 MB. [2025-12-17T22:09:05.414Z] ====== als (apache-spark) [default], iteration 9 completed (12033.683 ms) ====== [2025-12-17T22:09:05.414Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-17T22:09:05.414Z] GC before operation: completed in 190.778 ms, heap usage 320.087 MB -> 66.948 MB. [2025-12-17T22:09:17.109Z] ====== als (apache-spark) [default], iteration 10 completed (11510.366 ms) ====== [2025-12-17T22:09:17.109Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-17T22:09:17.109Z] GC before operation: completed in 196.209 ms, heap usage 409.541 MB -> 67.465 MB. [2025-12-17T22:09:27.563Z] ====== als (apache-spark) [default], iteration 11 completed (11081.059 ms) ====== [2025-12-17T22:09:27.563Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-17T22:09:27.563Z] GC before operation: completed in 161.577 ms, heap usage 195.463 MB -> 67.299 MB. [2025-12-17T22:09:39.266Z] ====== als (apache-spark) [default], iteration 12 completed (11035.414 ms) ====== [2025-12-17T22:09:39.266Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-17T22:09:39.266Z] GC before operation: completed in 145.273 ms, heap usage 394.759 MB -> 67.874 MB. [2025-12-17T22:09:50.919Z] ====== als (apache-spark) [default], iteration 13 completed (11207.443 ms) ====== [2025-12-17T22:09:50.919Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-17T22:09:50.919Z] GC before operation: completed in 167.243 ms, heap usage 276.885 MB -> 67.898 MB. [2025-12-17T22:10:07.055Z] ====== als (apache-spark) [default], iteration 14 completed (14271.627 ms) ====== [2025-12-17T22:10:07.055Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-17T22:10:07.055Z] GC before operation: completed in 190.602 ms, heap usage 267.517 MB -> 68.151 MB. [2025-12-17T22:10:23.200Z] ====== als (apache-spark) [default], iteration 15 completed (15317.826 ms) ====== [2025-12-17T22:10:23.200Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-17T22:10:23.200Z] GC before operation: completed in 307.395 ms, heap usage 308.298 MB -> 68.593 MB. [2025-12-17T22:10:33.264Z] ====== als (apache-spark) [default], iteration 16 completed (10451.281 ms) ====== [2025-12-17T22:10:33.264Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-17T22:10:33.264Z] GC before operation: completed in 141.088 ms, heap usage 353.912 MB -> 68.783 MB. [2025-12-17T22:10:43.126Z] ====== als (apache-spark) [default], iteration 17 completed (10199.540 ms) ====== [2025-12-17T22:10:43.127Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-17T22:10:43.127Z] GC before operation: completed in 162.876 ms, heap usage 147.599 MB -> 68.987 MB. [2025-12-17T22:10:54.896Z] ====== als (apache-spark) [default], iteration 18 completed (10308.301 ms) ====== [2025-12-17T22:10:54.896Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-17T22:10:54.896Z] GC before operation: completed in 167.739 ms, heap usage 186.370 MB -> 69.147 MB. [2025-12-17T22:11:06.741Z] ====== als (apache-spark) [default], iteration 19 completed (11064.233 ms) ====== [2025-12-17T22:11:06.741Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-17T22:11:06.741Z] GC before operation: completed in 220.294 ms, heap usage 259.710 MB -> 69.647 MB. [2025-12-17T22:11:18.573Z] ====== als (apache-spark) [default], iteration 20 completed (12257.613 ms) ====== [2025-12-17T22:11:18.573Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-17T22:11:18.573Z] GC before operation: completed in 176.645 ms, heap usage 438.510 MB -> 70.236 MB. [2025-12-17T22:11:32.352Z] ====== als (apache-spark) [default], iteration 21 completed (12673.354 ms) ====== [2025-12-17T22:11:32.352Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-17T22:11:32.352Z] GC before operation: completed in 189.822 ms, heap usage 291.873 MB -> 70.209 MB. [2025-12-17T22:11:46.609Z] ====== als (apache-spark) [default], iteration 22 completed (13995.893 ms) ====== [2025-12-17T22:11:46.609Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-17T22:11:46.609Z] GC before operation: completed in 204.930 ms, heap usage 199.822 MB -> 70.264 MB. [2025-12-17T22:12:00.370Z] ====== als (apache-spark) [default], iteration 23 completed (12264.566 ms) ====== [2025-12-17T22:12:00.370Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-17T22:12:00.370Z] GC before operation: completed in 196.138 ms, heap usage 132.697 MB -> 70.575 MB. [2025-12-17T22:12:12.118Z] ====== als (apache-spark) [default], iteration 24 completed (12556.540 ms) ====== [2025-12-17T22:12:12.118Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-17T22:12:12.118Z] GC before operation: completed in 193.554 ms, heap usage 242.286 MB -> 70.833 MB. [2025-12-17T22:12:23.818Z] ====== als (apache-spark) [default], iteration 25 completed (11185.877 ms) ====== [2025-12-17T22:12:23.818Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-17T22:12:23.818Z] GC before operation: completed in 177.975 ms, heap usage 132.008 MB -> 70.989 MB. [2025-12-17T22:12:35.554Z] ====== als (apache-spark) [default], iteration 26 completed (10500.901 ms) ====== [2025-12-17T22:12:35.554Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-17T22:12:35.554Z] GC before operation: completed in 165.434 ms, heap usage 356.800 MB -> 71.701 MB. [2025-12-17T22:12:45.920Z] ====== als (apache-spark) [default], iteration 27 completed (11186.901 ms) ====== [2025-12-17T22:12:45.920Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-17T22:12:45.920Z] GC before operation: completed in 158.242 ms, heap usage 286.211 MB -> 71.350 MB. [2025-12-17T22:12:57.623Z] ====== als (apache-spark) [default], iteration 28 completed (10176.408 ms) ====== [2025-12-17T22:12:57.623Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-17T22:12:57.623Z] GC before operation: completed in 211.822 ms, heap usage 381.319 MB -> 71.590 MB. [2025-12-17T22:13:11.353Z] ====== als (apache-spark) [default], iteration 29 completed (13535.856 ms) ====== [2025-12-17T22:13:11.354Z] ----------------------------------- [2025-12-17T22:13:11.354Z] renaissance-als_0_PASSED [2025-12-17T22:13:11.354Z] ----------------------------------- [2025-12-17T22:13:11.354Z] [2025-12-17T22:13:11.354Z] TEST TEARDOWN: [2025-12-17T22:13:11.354Z] Nothing to be done for teardown. [2025-12-17T22:13:11.354Z] renaissance-als_0 Finish Time: Wed Dec 17 22:13:10 2025 Epoch Time (ms): 1766009590845