[2025-08-28T05:36:06.239Z] Running test renaissance-als_0 ... [2025-08-28T05:36:06.239Z] =============================================== [2025-08-28T05:36:06.239Z] renaissance-als_0 Start Time: Thu Aug 28 05:36:05 2025 Epoch Time (ms): 1756359365832 [2025-08-28T05:36:06.239Z] variation: NoOptions [2025-08-28T05:36:06.239Z] JVM_OPTIONS: [2025-08-28T05:36:06.239Z] { \ [2025-08-28T05:36:06.239Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T05:36:06.239Z] echo "Nothing to be done for setup."; \ [2025-08-28T05:36:06.239Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-als_0"; \ [2025-08-28T05:36:06.239Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-als_0"; \ [2025-08-28T05:36:06.239Z] echo ""; echo "TESTING:"; \ [2025-08-28T05:36:06.239Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-als_0"/als.json" als; \ [2025-08-28T05:36:06.239Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T05:36:06.239Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T05:36:06.239Z] echo "Nothing to be done for teardown."; \ [2025-08-28T05:36:06.239Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563593656363/TestTargetResult"; [2025-08-28T05:36:06.239Z] [2025-08-28T05:36:06.239Z] TEST SETUP: [2025-08-28T05:36:06.239Z] Nothing to be done for setup. [2025-08-28T05:36:06.239Z] [2025-08-28T05:36:06.239Z] TESTING: [2025-08-28T05:36:19.725Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-28T05:36:27.775Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-28T05:36:27.775Z] GC before operation: completed in 207.953 ms, heap usage 56.862 MB -> 35.291 MB. [2025-08-28T05:37:12.329Z] ====== als (apache-spark) [default], iteration 0 completed (38481.736 ms) ====== [2025-08-28T05:37:12.329Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-28T05:37:12.329Z] GC before operation: completed in 222.614 ms, heap usage 182.085 MB -> 61.990 MB. [2025-08-28T05:37:23.756Z] ====== als (apache-spark) [default], iteration 1 completed (15120.468 ms) ====== [2025-08-28T05:37:23.756Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-28T05:37:23.756Z] GC before operation: completed in 173.377 ms, heap usage 194.898 MB -> 62.799 MB. [2025-08-28T05:37:35.199Z] ====== als (apache-spark) [default], iteration 2 completed (11455.996 ms) ====== [2025-08-28T05:37:35.199Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-28T05:37:36.144Z] GC before operation: completed in 209.925 ms, heap usage 468.188 MB -> 63.848 MB. [2025-08-28T05:37:47.727Z] ====== als (apache-spark) [default], iteration 3 completed (10394.066 ms) ====== [2025-08-28T05:37:47.727Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-28T05:37:47.727Z] GC before operation: completed in 199.214 ms, heap usage 549.559 MB -> 64.529 MB. [2025-08-28T05:37:57.413Z] ====== als (apache-spark) [default], iteration 4 completed (9493.041 ms) ====== [2025-08-28T05:37:57.413Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-28T05:37:57.413Z] GC before operation: completed in 193.088 ms, heap usage 143.397 MB -> 64.535 MB. [2025-08-28T05:38:07.128Z] ====== als (apache-spark) [default], iteration 5 completed (9394.194 ms) ====== [2025-08-28T05:38:07.128Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-28T05:38:07.128Z] GC before operation: completed in 213.508 ms, heap usage 175.685 MB -> 65.109 MB. [2025-08-28T05:38:16.801Z] ====== als (apache-spark) [default], iteration 6 completed (9629.755 ms) ====== [2025-08-28T05:38:16.801Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-28T05:38:16.801Z] GC before operation: completed in 206.109 ms, heap usage 488.630 MB -> 66.148 MB. [2025-08-28T05:38:28.266Z] ====== als (apache-spark) [default], iteration 7 completed (9700.794 ms) ====== [2025-08-28T05:38:28.266Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-28T05:38:28.266Z] GC before operation: completed in 239.642 ms, heap usage 290.940 MB -> 66.244 MB. [2025-08-28T05:38:37.986Z] ====== als (apache-spark) [default], iteration 8 completed (9571.664 ms) ====== [2025-08-28T05:38:37.986Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-28T05:38:37.986Z] GC before operation: completed in 229.157 ms, heap usage 258.375 MB -> 66.824 MB. [2025-08-28T05:38:47.705Z] ====== als (apache-spark) [default], iteration 9 completed (9368.625 ms) ====== [2025-08-28T05:38:47.705Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-28T05:38:47.705Z] GC before operation: completed in 233.479 ms, heap usage 306.058 MB -> 67.214 MB. [2025-08-28T05:38:57.368Z] ====== als (apache-spark) [default], iteration 10 completed (9262.011 ms) ====== [2025-08-28T05:38:57.368Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-28T05:38:58.306Z] GC before operation: completed in 239.177 ms, heap usage 236.049 MB -> 67.394 MB. [2025-08-28T05:39:08.035Z] ====== als (apache-spark) [default], iteration 11 completed (9622.706 ms) ====== [2025-08-28T05:39:08.035Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-28T05:39:08.035Z] GC before operation: completed in 269.491 ms, heap usage 347.283 MB -> 67.633 MB. [2025-08-28T05:39:17.728Z] ====== als (apache-spark) [default], iteration 12 completed (9274.608 ms) ====== [2025-08-28T05:39:17.728Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-28T05:39:17.728Z] GC before operation: completed in 219.140 ms, heap usage 585.069 MB -> 68.257 MB. [2025-08-28T05:39:27.411Z] ====== als (apache-spark) [default], iteration 13 completed (8871.672 ms) ====== [2025-08-28T05:39:27.411Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-28T05:39:27.411Z] GC before operation: completed in 215.058 ms, heap usage 221.757 MB -> 67.941 MB. [2025-08-28T05:39:37.804Z] ====== als (apache-spark) [default], iteration 14 completed (9102.750 ms) ====== [2025-08-28T05:39:37.804Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-28T05:39:37.804Z] GC before operation: completed in 240.890 ms, heap usage 185.650 MB -> 68.243 MB. [2025-08-28T05:39:47.539Z] ====== als (apache-spark) [default], iteration 15 completed (8801.529 ms) ====== [2025-08-28T05:39:47.539Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-28T05:39:47.539Z] GC before operation: completed in 241.796 ms, heap usage 453.774 MB -> 68.977 MB. [2025-08-28T05:39:55.613Z] ====== als (apache-spark) [default], iteration 16 completed (8871.958 ms) ====== [2025-08-28T05:39:55.613Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-28T05:39:56.560Z] GC before operation: completed in 225.689 ms, heap usage 306.493 MB -> 68.891 MB. [2025-08-28T05:40:06.288Z] ====== als (apache-spark) [default], iteration 17 completed (9004.701 ms) ====== [2025-08-28T05:40:06.288Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-28T05:40:06.288Z] GC before operation: completed in 255.522 ms, heap usage 370.688 MB -> 69.174 MB. [2025-08-28T05:40:15.987Z] ====== als (apache-spark) [default], iteration 18 completed (10102.287 ms) ====== [2025-08-28T05:40:15.987Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-28T05:40:16.929Z] GC before operation: completed in 328.010 ms, heap usage 464.866 MB -> 69.700 MB. [2025-08-28T05:40:26.603Z] ====== als (apache-spark) [default], iteration 19 completed (9903.294 ms) ====== [2025-08-28T05:40:26.603Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-28T05:40:27.546Z] GC before operation: completed in 313.993 ms, heap usage 486.938 MB -> 69.937 MB. [2025-08-28T05:40:39.067Z] ====== als (apache-spark) [default], iteration 20 completed (10891.406 ms) ====== [2025-08-28T05:40:39.067Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-28T05:40:39.067Z] GC before operation: completed in 314.718 ms, heap usage 568.565 MB -> 70.383 MB. [2025-08-28T05:40:48.958Z] ====== als (apache-spark) [default], iteration 21 completed (9389.271 ms) ====== [2025-08-28T05:40:48.958Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-28T05:40:48.958Z] GC before operation: completed in 247.376 ms, heap usage 299.405 MB -> 70.172 MB. [2025-08-28T05:40:57.443Z] ====== als (apache-spark) [default], iteration 22 completed (8631.759 ms) ====== [2025-08-28T05:40:57.444Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-28T05:40:57.444Z] GC before operation: completed in 244.260 ms, heap usage 359.983 MB -> 70.567 MB. [2025-08-28T05:41:09.065Z] ====== als (apache-spark) [default], iteration 23 completed (9733.607 ms) ====== [2025-08-28T05:41:09.065Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-28T05:41:09.065Z] GC before operation: completed in 278.319 ms, heap usage 271.980 MB -> 70.793 MB. [2025-08-28T05:41:18.778Z] ====== als (apache-spark) [default], iteration 24 completed (9424.153 ms) ====== [2025-08-28T05:41:18.778Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-28T05:41:18.778Z] GC before operation: completed in 237.898 ms, heap usage 235.065 MB -> 71.022 MB. [2025-08-28T05:41:28.479Z] ====== als (apache-spark) [default], iteration 25 completed (9362.384 ms) ====== [2025-08-28T05:41:28.479Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-28T05:41:28.479Z] GC before operation: completed in 233.470 ms, heap usage 225.916 MB -> 71.125 MB. [2025-08-28T05:41:38.174Z] ====== als (apache-spark) [default], iteration 26 completed (9514.002 ms) ====== [2025-08-28T05:41:38.174Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-28T05:41:38.174Z] GC before operation: completed in 277.502 ms, heap usage 568.285 MB -> 71.978 MB. [2025-08-28T05:41:47.864Z] ====== als (apache-spark) [default], iteration 27 completed (9522.711 ms) ====== [2025-08-28T05:41:47.864Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-28T05:41:48.819Z] GC before operation: completed in 226.861 ms, heap usage 382.838 MB -> 71.901 MB. [2025-08-28T05:41:58.488Z] ====== als (apache-spark) [default], iteration 28 completed (9076.922 ms) ====== [2025-08-28T05:41:58.488Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-28T05:41:58.488Z] GC before operation: completed in 224.515 ms, heap usage 380.700 MB -> 72.225 MB. [2025-08-28T05:42:06.564Z] ====== als (apache-spark) [default], iteration 29 completed (8274.081 ms) ====== [2025-08-28T05:42:07.502Z] ----------------------------------- [2025-08-28T05:42:07.502Z] renaissance-als_0_PASSED [2025-08-28T05:42:07.502Z] ----------------------------------- [2025-08-28T05:42:07.502Z] [2025-08-28T05:42:07.502Z] TEST TEARDOWN: [2025-08-28T05:42:07.502Z] Nothing to be done for teardown. [2025-08-28T05:42:07.502Z] renaissance-als_0 Finish Time: Thu Aug 28 05:42:07 2025 Epoch Time (ms): 1756359727163