[2025-07-15T22:13:26.175Z] Running test renaissance-als_0 ... [2025-07-15T22:13:26.175Z] =============================================== [2025-07-15T22:13:26.175Z] renaissance-als_0 Start Time: Tue Jul 15 22:13:25 2025 Epoch Time (ms): 1752617605438 [2025-07-15T22:13:26.175Z] variation: NoOptions [2025-07-15T22:13:26.175Z] JVM_OPTIONS: [2025-07-15T22:13:26.175Z] { \ [2025-07-15T22:13:26.175Z] echo ""; echo "TEST SETUP:"; \ [2025-07-15T22:13:26.175Z] echo "Nothing to be done for setup."; \ [2025-07-15T22:13:26.175Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_175261754917/renaissance-als_0"; \ [2025-07-15T22:13:26.175Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_175261754917/renaissance-als_0"; \ [2025-07-15T22:13:26.175Z] echo ""; echo "TESTING:"; \ [2025-07-15T22:13:26.175Z] "/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_175261754917/renaissance-als_0"/als.json" als; \ [2025-07-15T22:13:26.175Z] 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_175261754917/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-15T22:13:26.175Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-15T22:13:26.175Z] echo "Nothing to be done for teardown."; \ [2025-07-15T22:13:26.175Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_175261754917/TestTargetResult"; [2025-07-15T22:13:26.175Z] [2025-07-15T22:13:26.175Z] TEST SETUP: [2025-07-15T22:13:26.175Z] Nothing to be done for setup. [2025-07-15T22:13:26.175Z] [2025-07-15T22:13:26.175Z] TESTING: [2025-07-15T22:13:34.168Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-07-15T22:13:40.721Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-15T22:13:40.721Z] GC before operation: completed in 57.543 ms, heap usage 76.341 MB -> 36.613 MB. [2025-07-15T22:14:01.693Z] ====== als (apache-spark) [default], iteration 0 completed (21175.456 ms) ====== [2025-07-15T22:14:01.694Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-15T22:14:02.624Z] GC before operation: completed in 153.911 ms, heap usage 437.141 MB -> 64.020 MB. [2025-07-15T22:14:07.991Z] ====== als (apache-spark) [default], iteration 1 completed (5763.701 ms) ====== [2025-07-15T22:14:07.991Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-15T22:14:08.935Z] GC before operation: completed in 131.984 ms, heap usage 838.538 MB -> 65.299 MB. [2025-07-15T22:14:14.221Z] ====== als (apache-spark) [default], iteration 2 completed (5096.805 ms) ====== [2025-07-15T22:14:14.221Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-15T22:14:14.221Z] GC before operation: completed in 140.233 ms, heap usage 811.817 MB -> 66.027 MB. [2025-07-15T22:14:19.477Z] ====== als (apache-spark) [default], iteration 3 completed (4892.489 ms) ====== [2025-07-15T22:14:19.477Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-15T22:14:19.477Z] GC before operation: completed in 141.587 ms, heap usage 551.269 MB -> 66.444 MB. [2025-07-15T22:14:24.822Z] ====== als (apache-spark) [default], iteration 4 completed (4873.591 ms) ====== [2025-07-15T22:14:24.822Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-15T22:14:24.822Z] GC before operation: completed in 148.300 ms, heap usage 569.468 MB -> 67.215 MB. [2025-07-15T22:14:30.071Z] ====== als (apache-spark) [default], iteration 5 completed (4730.210 ms) ====== [2025-07-15T22:14:30.071Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-15T22:14:30.071Z] GC before operation: completed in 156.840 ms, heap usage 641.072 MB -> 67.807 MB. [2025-07-15T22:14:35.322Z] ====== als (apache-spark) [default], iteration 6 completed (4749.866 ms) ====== [2025-07-15T22:14:35.322Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-15T22:14:35.322Z] GC before operation: completed in 149.333 ms, heap usage 793.352 MB -> 68.588 MB. [2025-07-15T22:14:39.384Z] ====== als (apache-spark) [default], iteration 7 completed (4569.552 ms) ====== [2025-07-15T22:14:39.384Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-15T22:14:40.312Z] GC before operation: completed in 153.755 ms, heap usage 544.349 MB -> 68.837 MB. [2025-07-15T22:14:44.469Z] ====== als (apache-spark) [default], iteration 8 completed (4598.298 ms) ====== [2025-07-15T22:14:44.469Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-15T22:14:44.469Z] GC before operation: completed in 156.167 ms, heap usage 318.650 MB -> 69.042 MB. [2025-07-15T22:14:49.761Z] ====== als (apache-spark) [default], iteration 9 completed (4677.919 ms) ====== [2025-07-15T22:14:49.761Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-15T22:14:49.761Z] GC before operation: completed in 168.451 ms, heap usage 1.162 GB -> 70.181 MB. [2025-07-15T22:14:55.049Z] ====== als (apache-spark) [default], iteration 10 completed (4513.240 ms) ====== [2025-07-15T22:14:55.049Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-15T22:14:55.049Z] GC before operation: completed in 168.409 ms, heap usage 431.108 MB -> 69.526 MB. [2025-07-15T22:15:00.297Z] ====== als (apache-spark) [default], iteration 11 completed (4423.493 ms) ====== [2025-07-15T22:15:00.297Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-15T22:15:00.297Z] GC before operation: completed in 171.504 ms, heap usage 1.274 GB -> 70.782 MB. [2025-07-15T22:15:03.946Z] ====== als (apache-spark) [default], iteration 12 completed (4151.421 ms) ====== [2025-07-15T22:15:03.946Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-15T22:15:03.946Z] GC before operation: completed in 158.428 ms, heap usage 764.164 MB -> 70.510 MB. [2025-07-15T22:15:09.202Z] ====== als (apache-spark) [default], iteration 13 completed (4225.099 ms) ====== [2025-07-15T22:15:09.202Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-15T22:15:09.202Z] GC before operation: completed in 155.100 ms, heap usage 1.195 GB -> 71.462 MB. [2025-07-15T22:15:13.266Z] ====== als (apache-spark) [default], iteration 14 completed (4231.920 ms) ====== [2025-07-15T22:15:13.267Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-15T22:15:13.267Z] GC before operation: completed in 156.574 ms, heap usage 719.064 MB -> 71.150 MB. [2025-07-15T22:15:17.323Z] ====== als (apache-spark) [default], iteration 15 completed (3947.171 ms) ====== [2025-07-15T22:15:17.323Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-15T22:15:17.323Z] GC before operation: completed in 146.618 ms, heap usage 781.870 MB -> 71.314 MB. [2025-07-15T22:15:22.578Z] ====== als (apache-spark) [default], iteration 16 completed (3957.903 ms) ====== [2025-07-15T22:15:22.578Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-15T22:15:22.578Z] GC before operation: completed in 221.121 ms, heap usage 1.002 GB -> 71.881 MB. [2025-07-15T22:15:26.636Z] ====== als (apache-spark) [default], iteration 17 completed (4150.511 ms) ====== [2025-07-15T22:15:26.636Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-15T22:15:26.636Z] GC before operation: completed in 163.222 ms, heap usage 417.010 MB -> 71.429 MB. [2025-07-15T22:15:30.733Z] ====== als (apache-spark) [default], iteration 18 completed (4014.946 ms) ====== [2025-07-15T22:15:30.733Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-15T22:15:30.733Z] GC before operation: completed in 164.050 ms, heap usage 1.265 GB -> 72.697 MB. [2025-07-15T22:15:36.036Z] ====== als (apache-spark) [default], iteration 19 completed (4194.884 ms) ====== [2025-07-15T22:15:36.036Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-15T22:15:36.036Z] GC before operation: completed in 168.478 ms, heap usage 1.352 GB -> 73.119 MB. [2025-07-15T22:15:40.096Z] ====== als (apache-spark) [default], iteration 20 completed (4099.814 ms) ====== [2025-07-15T22:15:40.096Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-15T22:15:40.096Z] GC before operation: completed in 156.338 ms, heap usage 425.104 MB -> 72.290 MB. [2025-07-15T22:15:44.157Z] ====== als (apache-spark) [default], iteration 21 completed (3979.955 ms) ====== [2025-07-15T22:15:44.157Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-15T22:15:44.157Z] GC before operation: completed in 153.998 ms, heap usage 987.756 MB -> 73.282 MB. [2025-07-15T22:15:48.252Z] ====== als (apache-spark) [default], iteration 22 completed (4208.849 ms) ====== [2025-07-15T22:15:48.252Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-15T22:15:49.181Z] GC before operation: completed in 159.698 ms, heap usage 526.221 MB -> 73.098 MB. [2025-07-15T22:15:53.246Z] ====== als (apache-spark) [default], iteration 23 completed (4127.486 ms) ====== [2025-07-15T22:15:53.246Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-15T22:15:53.246Z] GC before operation: completed in 186.075 ms, heap usage 1.199 GB -> 74.001 MB. [2025-07-15T22:15:57.308Z] ====== als (apache-spark) [default], iteration 24 completed (4039.135 ms) ====== [2025-07-15T22:15:57.308Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-15T22:15:57.308Z] GC before operation: completed in 167.691 ms, heap usage 1.382 GB -> 74.616 MB. [2025-07-15T22:16:02.564Z] ====== als (apache-spark) [default], iteration 25 completed (3948.283 ms) ====== [2025-07-15T22:16:02.564Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-15T22:16:02.564Z] GC before operation: completed in 167.492 ms, heap usage 377.697 MB -> 73.557 MB. [2025-07-15T22:16:06.619Z] ====== als (apache-spark) [default], iteration 26 completed (4117.309 ms) ====== [2025-07-15T22:16:06.619Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-15T22:16:06.619Z] GC before operation: completed in 197.477 ms, heap usage 921.188 MB -> 74.686 MB. [2025-07-15T22:16:10.672Z] ====== als (apache-spark) [default], iteration 27 completed (4038.965 ms) ====== [2025-07-15T22:16:10.672Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-15T22:16:10.672Z] GC before operation: completed in 167.903 ms, heap usage 1.104 GB -> 74.515 MB. [2025-07-15T22:16:15.955Z] ====== als (apache-spark) [default], iteration 28 completed (4172.441 ms) ====== [2025-07-15T22:16:15.956Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-15T22:16:15.956Z] GC before operation: completed in 173.868 ms, heap usage 1.004 GB -> 74.642 MB. [2025-07-15T22:16:20.030Z] ====== als (apache-spark) [default], iteration 29 completed (4134.378 ms) ====== [2025-07-15T22:16:21.019Z] ----------------------------------- [2025-07-15T22:16:21.019Z] renaissance-als_0_PASSED [2025-07-15T22:16:21.019Z] ----------------------------------- [2025-07-15T22:16:21.019Z] [2025-07-15T22:16:21.019Z] TEST TEARDOWN: [2025-07-15T22:16:21.019Z] Nothing to be done for teardown. [2025-07-15T22:16:21.019Z] renaissance-als_0 Finish Time: Tue Jul 15 22:16:20 2025 Epoch Time (ms): 1752617780075