[2025-09-03T22:16:12.203Z] Running test renaissance-als_0 ... [2025-09-03T22:16:12.203Z] =============================================== [2025-09-03T22:16:12.203Z] renaissance-als_0 Start Time: Wed Sep 3 22:16:12 2025 Epoch Time (ms): 1756937772068 [2025-09-03T22:16:12.203Z] variation: NoOptions [2025-09-03T22:16:12.532Z] JVM_OPTIONS: [2025-09-03T22:16:12.532Z] { \ [2025-09-03T22:16:12.532Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T22:16:12.532Z] echo "Nothing to be done for setup."; \ [2025-09-03T22:16:12.532Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\renaissance-als_0"; \ [2025-09-03T22:16:12.532Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\renaissance-als_0"; \ [2025-09-03T22:16:12.532Z] echo ""; echo "TESTING:"; \ [2025-09-03T22:16:12.532Z] "c:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\renaissance-als_0"\\als.json" als; \ [2025-09-03T22:16:12.532Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T22:16:12.532Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T22:16:12.532Z] echo "Nothing to be done for teardown."; \ [2025-09-03T22:16:12.532Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\TestTargetResult"; [2025-09-03T22:16:12.532Z] [2025-09-03T22:16:12.532Z] TEST SETUP: [2025-09-03T22:16:12.532Z] Nothing to be done for setup. [2025-09-03T22:16:12.532Z] [2025-09-03T22:16:12.532Z] TESTING: [2025-09-03T22:16:28.112Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-03T22:16:30.305Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-03T22:16:30.305Z] GC before operation: completed in 36.916 ms, heap usage 48.863 MB -> 35.460 MB. [2025-09-03T22:17:03.749Z] ====== als (apache-spark) [default], iteration 0 completed (27536.135 ms) ====== [2025-09-03T22:17:03.749Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-03T22:17:03.749Z] GC before operation: completed in 136.017 ms, heap usage 305.325 MB -> 62.437 MB. [2025-09-03T22:17:16.672Z] ====== als (apache-spark) [default], iteration 1 completed (15872.357 ms) ====== [2025-09-03T22:17:16.672Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-03T22:17:16.672Z] GC before operation: completed in 74.766 ms, heap usage 299.240 MB -> 63.231 MB. [2025-09-03T22:17:32.326Z] ====== als (apache-spark) [default], iteration 2 completed (15143.649 ms) ====== [2025-09-03T22:17:32.326Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-03T22:17:32.326Z] GC before operation: completed in 76.886 ms, heap usage 328.868 MB -> 63.929 MB. [2025-09-03T22:17:45.479Z] ====== als (apache-spark) [default], iteration 3 completed (15044.856 ms) ====== [2025-09-03T22:17:45.479Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-03T22:17:45.479Z] GC before operation: completed in 90.270 ms, heap usage 468.885 MB -> 64.710 MB. [2025-09-03T22:18:01.345Z] ====== als (apache-spark) [default], iteration 4 completed (14915.469 ms) ====== [2025-09-03T22:18:01.345Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-03T22:18:01.345Z] GC before operation: completed in 88.018 ms, heap usage 484.708 MB -> 65.286 MB. [2025-09-03T22:18:17.185Z] ====== als (apache-spark) [default], iteration 5 completed (14857.205 ms) ====== [2025-09-03T22:18:17.185Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-03T22:18:17.185Z] GC before operation: completed in 83.413 ms, heap usage 356.960 MB -> 65.607 MB. [2025-09-03T22:18:33.179Z] ====== als (apache-spark) [default], iteration 6 completed (15211.157 ms) ====== [2025-09-03T22:18:33.179Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-03T22:18:33.179Z] GC before operation: completed in 83.174 ms, heap usage 506.456 MB -> 66.367 MB. [2025-09-03T22:18:46.139Z] ====== als (apache-spark) [default], iteration 7 completed (14797.472 ms) ====== [2025-09-03T22:18:46.139Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-03T22:18:46.139Z] GC before operation: completed in 89.831 ms, heap usage 522.515 MB -> 66.904 MB. [2025-09-03T22:19:01.913Z] ====== als (apache-spark) [default], iteration 8 completed (14729.680 ms) ====== [2025-09-03T22:19:01.913Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-03T22:19:01.913Z] GC before operation: completed in 87.623 ms, heap usage 213.671 MB -> 67.094 MB. [2025-09-03T22:19:17.685Z] ====== als (apache-spark) [default], iteration 9 completed (14794.446 ms) ====== [2025-09-03T22:19:17.685Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-03T22:19:17.685Z] GC before operation: completed in 101.080 ms, heap usage 171.629 MB -> 67.263 MB. [2025-09-03T22:19:33.337Z] ====== als (apache-spark) [default], iteration 10 completed (14632.774 ms) ====== [2025-09-03T22:19:33.337Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-03T22:19:33.337Z] GC before operation: completed in 89.285 ms, heap usage 357.201 MB -> 67.751 MB. [2025-09-03T22:19:46.315Z] ====== als (apache-spark) [default], iteration 11 completed (14328.208 ms) ====== [2025-09-03T22:19:46.315Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-03T22:19:46.315Z] GC before operation: completed in 106.626 ms, heap usage 300.937 MB -> 68.067 MB. [2025-09-03T22:20:02.130Z] ====== als (apache-spark) [default], iteration 12 completed (14522.710 ms) ====== [2025-09-03T22:20:02.130Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-03T22:20:02.130Z] GC before operation: completed in 88.381 ms, heap usage 123.928 MB -> 68.056 MB. [2025-09-03T22:20:17.857Z] ====== als (apache-spark) [default], iteration 13 completed (14798.430 ms) ====== [2025-09-03T22:20:17.857Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-03T22:20:17.857Z] GC before operation: completed in 81.650 ms, heap usage 279.435 MB -> 68.328 MB. [2025-09-03T22:20:30.971Z] ====== als (apache-spark) [default], iteration 14 completed (14751.162 ms) ====== [2025-09-03T22:20:30.971Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-03T22:20:30.971Z] GC before operation: completed in 86.653 ms, heap usage 327.909 MB -> 68.811 MB. [2025-09-03T22:20:46.792Z] ====== als (apache-spark) [default], iteration 15 completed (14370.483 ms) ====== [2025-09-03T22:20:46.792Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-03T22:20:46.792Z] GC before operation: completed in 77.197 ms, heap usage 435.129 MB -> 69.224 MB. [2025-09-03T22:20:59.803Z] ====== als (apache-spark) [default], iteration 16 completed (14305.171 ms) ====== [2025-09-03T22:20:59.804Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-03T22:20:59.804Z] GC before operation: completed in 80.187 ms, heap usage 122.464 MB -> 69.096 MB. [2025-09-03T22:21:15.378Z] ====== als (apache-spark) [default], iteration 17 completed (14396.435 ms) ====== [2025-09-03T22:21:15.378Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-03T22:21:15.378Z] GC before operation: completed in 75.296 ms, heap usage 131.653 MB -> 69.260 MB. [2025-09-03T22:21:31.087Z] ====== als (apache-spark) [default], iteration 18 completed (14266.359 ms) ====== [2025-09-03T22:21:31.087Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-03T22:21:31.087Z] GC before operation: completed in 77.136 ms, heap usage 311.364 MB -> 69.781 MB. [2025-09-03T22:21:44.203Z] ====== als (apache-spark) [default], iteration 19 completed (14184.426 ms) ====== [2025-09-03T22:21:44.203Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-03T22:21:44.203Z] GC before operation: completed in 77.107 ms, heap usage 498.740 MB -> 70.271 MB. [2025-09-03T22:21:59.877Z] ====== als (apache-spark) [default], iteration 20 completed (14709.143 ms) ====== [2025-09-03T22:21:59.877Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-03T22:21:59.877Z] GC before operation: completed in 79.565 ms, heap usage 269.433 MB -> 70.255 MB. [2025-09-03T22:22:12.908Z] ====== als (apache-spark) [default], iteration 21 completed (14170.740 ms) ====== [2025-09-03T22:22:12.908Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-03T22:22:12.908Z] GC before operation: completed in 75.452 ms, heap usage 202.520 MB -> 70.390 MB. [2025-09-03T22:22:28.657Z] ====== als (apache-spark) [default], iteration 22 completed (14579.649 ms) ====== [2025-09-03T22:22:28.658Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-03T22:22:28.658Z] GC before operation: completed in 77.315 ms, heap usage 203.678 MB -> 70.742 MB. [2025-09-03T22:22:44.355Z] ====== als (apache-spark) [default], iteration 23 completed (14362.332 ms) ====== [2025-09-03T22:22:44.355Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-03T22:22:44.355Z] GC before operation: completed in 95.623 ms, heap usage 271.617 MB -> 71.107 MB. [2025-09-03T22:22:57.409Z] ====== als (apache-spark) [default], iteration 24 completed (13999.592 ms) ====== [2025-09-03T22:22:57.409Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-03T22:22:57.409Z] GC before operation: completed in 77.725 ms, heap usage 132.433 MB -> 71.170 MB. [2025-09-03T22:23:10.520Z] ====== als (apache-spark) [default], iteration 25 completed (14040.252 ms) ====== [2025-09-03T22:23:10.520Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-03T22:23:10.863Z] GC before operation: completed in 91.307 ms, heap usage 498.729 MB -> 71.904 MB. [2025-09-03T22:23:26.687Z] ====== als (apache-spark) [default], iteration 26 completed (14133.827 ms) ====== [2025-09-03T22:23:26.687Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-03T22:23:26.687Z] GC before operation: completed in 77.665 ms, heap usage 221.564 MB -> 71.735 MB. [2025-09-03T22:23:39.746Z] ====== als (apache-spark) [default], iteration 27 completed (14259.115 ms) ====== [2025-09-03T22:23:39.746Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-03T22:23:39.746Z] GC before operation: completed in 86.892 ms, heap usage 238.831 MB -> 72.068 MB. [2025-09-03T22:23:55.435Z] ====== als (apache-spark) [default], iteration 28 completed (14345.884 ms) ====== [2025-09-03T22:23:55.435Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-03T22:23:55.435Z] GC before operation: completed in 81.956 ms, heap usage 253.218 MB -> 71.888 MB. [2025-09-03T22:24:08.454Z] ====== als (apache-spark) [default], iteration 29 completed (14189.411 ms) ====== [2025-09-03T22:24:09.130Z] ----------------------------------- [2025-09-03T22:24:09.130Z] renaissance-als_0_PASSED [2025-09-03T22:24:09.130Z] ----------------------------------- [2025-09-03T22:24:09.451Z] [2025-09-03T22:24:09.451Z] TEST TEARDOWN: [2025-09-03T22:24:09.451Z] Nothing to be done for teardown. [2025-09-03T22:24:09.451Z] renaissance-als_0 Finish Time: Wed Sep 3 22:24:09 2025 Epoch Time (ms): 1756938249318