[2025-05-28T20:48:07.072Z] Running test renaissance-als_0 ... [2025-05-28T20:48:07.072Z] =============================================== [2025-05-28T20:48:07.072Z] renaissance-als_0 Start Time: Wed May 28 20:48:06 2025 Epoch Time (ms): 1748465286304 [2025-05-28T20:48:07.072Z] variation: NoOptions [2025-05-28T20:48:07.072Z] JVM_OPTIONS: [2025-05-28T20:48:07.072Z] { \ [2025-05-28T20:48:07.072Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T20:48:07.072Z] echo "Nothing to be done for setup."; \ [2025-05-28T20:48:07.072Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-als_0"; \ [2025-05-28T20:48:07.072Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-als_0"; \ [2025-05-28T20:48:07.072Z] echo ""; echo "TESTING:"; \ [2025-05-28T20:48:07.072Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-als_0"/als.json" als; \ [2025-05-28T20:48:07.072Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T20:48:07.072Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T20:48:07.072Z] echo "Nothing to be done for teardown."; \ [2025-05-28T20:48:07.072Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484652381328/TestTargetResult"; [2025-05-28T20:48:07.072Z] [2025-05-28T20:48:07.072Z] TEST SETUP: [2025-05-28T20:48:07.072Z] Nothing to be done for setup. [2025-05-28T20:48:07.072Z] [2025-05-28T20:48:07.072Z] TESTING: [2025-05-28T20:48:16.777Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-05-28T20:48:24.932Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-28T20:48:24.932Z] GC before operation: completed in 47.919 ms, heap usage 59.901 MB -> 36.578 MB. [2025-05-28T20:48:53.323Z] ====== als (apache-spark) [default], iteration 0 completed (24941.252 ms) ====== [2025-05-28T20:48:53.323Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-28T20:48:53.323Z] GC before operation: completed in 237.116 ms, heap usage 292.202 MB -> 63.725 MB. [2025-05-28T20:48:58.168Z] ====== als (apache-spark) [default], iteration 1 completed (6915.375 ms) ====== [2025-05-28T20:48:58.168Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-28T20:48:58.168Z] GC before operation: completed in 135.012 ms, heap usage 307.621 MB -> 64.809 MB. [2025-05-28T20:49:04.827Z] ====== als (apache-spark) [default], iteration 2 completed (6251.720 ms) ====== [2025-05-28T20:49:04.827Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-28T20:49:04.827Z] GC before operation: completed in 194.717 ms, heap usage 496.029 MB -> 65.750 MB. [2025-05-28T20:49:11.467Z] ====== als (apache-spark) [default], iteration 3 completed (5347.254 ms) ====== [2025-05-28T20:49:11.467Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-28T20:49:11.467Z] GC before operation: completed in 167.677 ms, heap usage 945.556 MB -> 66.887 MB. [2025-05-28T20:49:15.615Z] ====== als (apache-spark) [default], iteration 4 completed (4876.155 ms) ====== [2025-05-28T20:49:15.615Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-28T20:49:16.563Z] GC before operation: completed in 156.798 ms, heap usage 1.093 GB -> 67.574 MB. [2025-05-28T20:49:21.909Z] ====== als (apache-spark) [default], iteration 5 completed (5415.105 ms) ====== [2025-05-28T20:49:21.909Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-28T20:49:21.909Z] GC before operation: completed in 170.833 ms, heap usage 619.133 MB -> 67.698 MB. [2025-05-28T20:49:27.225Z] ====== als (apache-spark) [default], iteration 6 completed (5046.590 ms) ====== [2025-05-28T20:49:27.225Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-28T20:49:27.225Z] GC before operation: completed in 181.625 ms, heap usage 1.108 GB -> 68.795 MB. [2025-05-28T20:49:32.543Z] ====== als (apache-spark) [default], iteration 7 completed (4820.265 ms) ====== [2025-05-28T20:49:32.543Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-28T20:49:32.543Z] GC before operation: completed in 170.075 ms, heap usage 1.048 GB -> 69.203 MB. [2025-05-28T20:49:37.860Z] ====== als (apache-spark) [default], iteration 8 completed (4735.152 ms) ====== [2025-05-28T20:49:37.860Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-28T20:49:37.860Z] GC before operation: completed in 174.499 ms, heap usage 217.638 MB -> 68.890 MB. [2025-05-28T20:49:43.204Z] ====== als (apache-spark) [default], iteration 9 completed (4420.220 ms) ====== [2025-05-28T20:49:43.204Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-28T20:49:43.204Z] GC before operation: completed in 173.966 ms, heap usage 165.701 MB -> 68.899 MB. [2025-05-28T20:49:48.532Z] ====== als (apache-spark) [default], iteration 10 completed (5221.030 ms) ====== [2025-05-28T20:49:48.532Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-28T20:49:48.532Z] GC before operation: completed in 209.853 ms, heap usage 829.049 MB -> 69.974 MB. [2025-05-28T20:49:53.856Z] ====== als (apache-spark) [default], iteration 11 completed (4807.707 ms) ====== [2025-05-28T20:49:53.856Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-28T20:49:53.856Z] GC before operation: completed in 188.984 ms, heap usage 920.512 MB -> 70.402 MB. [2025-05-28T20:49:59.175Z] ====== als (apache-spark) [default], iteration 12 completed (5294.760 ms) ====== [2025-05-28T20:49:59.175Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-28T20:50:00.116Z] GC before operation: completed in 204.383 ms, heap usage 1.317 GB -> 71.227 MB. [2025-05-28T20:50:05.088Z] ====== als (apache-spark) [default], iteration 13 completed (5053.231 ms) ====== [2025-05-28T20:50:05.088Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-28T20:50:05.088Z] GC before operation: completed in 215.055 ms, heap usage 299.066 MB -> 70.148 MB. [2025-05-28T20:50:11.747Z] ====== als (apache-spark) [default], iteration 14 completed (5426.972 ms) ====== [2025-05-28T20:50:11.747Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-28T20:50:11.747Z] GC before operation: completed in 368.827 ms, heap usage 906.087 MB -> 71.309 MB. [2025-05-28T20:50:17.194Z] ====== als (apache-spark) [default], iteration 15 completed (5179.310 ms) ====== [2025-05-28T20:50:17.194Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-28T20:50:17.194Z] GC before operation: completed in 194.024 ms, heap usage 805.214 MB -> 71.322 MB. [2025-05-28T20:50:22.512Z] ====== als (apache-spark) [default], iteration 16 completed (4855.451 ms) ====== [2025-05-28T20:50:22.512Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-28T20:50:22.512Z] GC before operation: completed in 218.206 ms, heap usage 935.963 MB -> 71.729 MB. [2025-05-28T20:50:27.866Z] ====== als (apache-spark) [default], iteration 17 completed (5369.484 ms) ====== [2025-05-28T20:50:27.866Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-28T20:50:27.866Z] GC before operation: completed in 253.565 ms, heap usage 1.373 GB -> 72.490 MB. [2025-05-28T20:50:34.597Z] ====== als (apache-spark) [default], iteration 18 completed (5283.120 ms) ====== [2025-05-28T20:50:34.597Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-28T20:50:34.597Z] GC before operation: completed in 265.944 ms, heap usage 449.199 MB -> 71.588 MB. [2025-05-28T20:50:39.914Z] ====== als (apache-spark) [default], iteration 19 completed (5220.720 ms) ====== [2025-05-28T20:50:39.914Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-28T20:50:39.914Z] GC before operation: completed in 218.719 ms, heap usage 481.984 MB -> 72.106 MB. [2025-05-28T20:50:45.298Z] ====== als (apache-spark) [default], iteration 20 completed (5027.184 ms) ====== [2025-05-28T20:50:45.298Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-28T20:50:45.298Z] GC before operation: completed in 215.558 ms, heap usage 311.248 MB -> 71.938 MB. [2025-05-28T20:50:50.986Z] ====== als (apache-spark) [default], iteration 21 completed (5712.195 ms) ====== [2025-05-28T20:50:50.986Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-28T20:50:51.930Z] GC before operation: completed in 229.227 ms, heap usage 605.478 MB -> 72.669 MB. [2025-05-28T20:50:57.257Z] ====== als (apache-spark) [default], iteration 22 completed (5517.569 ms) ====== [2025-05-28T20:50:57.257Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-28T20:50:57.257Z] GC before operation: completed in 226.168 ms, heap usage 1.113 GB -> 73.502 MB. [2025-05-28T20:51:02.592Z] ====== als (apache-spark) [default], iteration 23 completed (4595.940 ms) ====== [2025-05-28T20:51:02.592Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-28T20:51:02.592Z] GC before operation: completed in 179.930 ms, heap usage 833.745 MB -> 73.443 MB. [2025-05-28T20:51:07.921Z] ====== als (apache-spark) [default], iteration 24 completed (4532.740 ms) ====== [2025-05-28T20:51:07.922Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-28T20:51:07.922Z] GC before operation: completed in 178.113 ms, heap usage 1.238 GB -> 74.165 MB. [2025-05-28T20:51:12.038Z] ====== als (apache-spark) [default], iteration 25 completed (4586.488 ms) ====== [2025-05-28T20:51:12.038Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-28T20:51:12.983Z] GC before operation: completed in 193.746 ms, heap usage 1.073 GB -> 74.356 MB. [2025-05-28T20:51:17.794Z] ====== als (apache-spark) [default], iteration 26 completed (4654.283 ms) ====== [2025-05-28T20:51:17.794Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-28T20:51:17.794Z] GC before operation: completed in 201.599 ms, heap usage 1.290 GB -> 74.820 MB. [2025-05-28T20:51:23.150Z] ====== als (apache-spark) [default], iteration 27 completed (4612.033 ms) ====== [2025-05-28T20:51:23.150Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-28T20:51:23.150Z] GC before operation: completed in 192.317 ms, heap usage 791.138 MB -> 74.561 MB. [2025-05-28T20:51:27.298Z] ====== als (apache-spark) [default], iteration 28 completed (4623.713 ms) ====== [2025-05-28T20:51:27.298Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-28T20:51:27.298Z] GC before operation: completed in 206.002 ms, heap usage 1.193 GB -> 74.749 MB. [2025-05-28T20:51:32.695Z] ====== als (apache-spark) [default], iteration 29 completed (4475.716 ms) ====== [2025-05-28T20:51:33.749Z] ----------------------------------- [2025-05-28T20:51:33.749Z] renaissance-als_0_PASSED [2025-05-28T20:51:33.749Z] ----------------------------------- [2025-05-28T20:51:33.749Z] [2025-05-28T20:51:33.749Z] TEST TEARDOWN: [2025-05-28T20:51:33.749Z] Nothing to be done for teardown. [2025-05-28T20:51:33.749Z] renaissance-als_0 Finish Time: Wed May 28 20:51:32 2025 Epoch Time (ms): 1748465492770