[2026-04-02T03:50:12.038Z] Running test renaissance-als_0 ... [2026-04-02T03:50:12.038Z] =============================================== [2026-04-02T03:50:12.390Z] renaissance-als_0 Start Time: Thu Apr 2 03:50:12 2026 Epoch Time (ms): 1775101812031 [2026-04-02T03:50:12.390Z] variation: NoOptions [2026-04-02T03:50:12.390Z] JVM_OPTIONS: [2026-04-02T03:50:12.390Z] { \ [2026-04-02T03:50:12.390Z] echo ""; echo "TEST SETUP:"; \ [2026-04-02T03:50:12.390Z] echo "Nothing to be done for setup."; \ [2026-04-02T03:50:12.390Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-als_0"; \ [2026-04-02T03:50:12.390Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-als_0"; \ [2026-04-02T03:50:12.390Z] echo ""; echo "TESTING:"; \ [2026-04-02T03:50:12.390Z] "c:/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-als_0"\\als.json" als; \ [2026-04-02T03:50:12.390Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-02T03:50:12.390Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-02T03:50:12.390Z] echo "Nothing to be done for teardown."; \ [2026-04-02T03:50:12.390Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\TestTargetResult"; [2026-04-02T03:50:12.390Z] [2026-04-02T03:50:12.390Z] TEST SETUP: [2026-04-02T03:50:12.390Z] Nothing to be done for setup. [2026-04-02T03:50:12.740Z] [2026-04-02T03:50:12.740Z] TESTING: [2026-04-02T03:50:25.999Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-02T03:50:29.862Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-02T03:50:29.862Z] GC before operation: completed in 37.928 ms, heap usage 184.000 MB -> 35.275 MB. [2026-04-02T03:50:58.301Z] ====== als (apache-spark) [default], iteration 0 completed (27345.256 ms) ====== [2026-04-02T03:50:58.301Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-02T03:50:58.301Z] GC before operation: completed in 83.889 ms, heap usage 313.573 MB -> 62.451 MB. [2026-04-02T03:51:14.531Z] ====== als (apache-spark) [default], iteration 1 completed (15421.273 ms) ====== [2026-04-02T03:51:14.531Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-02T03:51:14.531Z] GC before operation: completed in 73.850 ms, heap usage 297.553 MB -> 63.231 MB. [2026-04-02T03:51:30.708Z] ====== als (apache-spark) [default], iteration 2 completed (15934.871 ms) ====== [2026-04-02T03:51:30.708Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-02T03:51:30.708Z] GC before operation: completed in 75.133 ms, heap usage 168.717 MB -> 63.769 MB. [2026-04-02T03:51:46.779Z] ====== als (apache-spark) [default], iteration 3 completed (15098.391 ms) ====== [2026-04-02T03:51:46.779Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-02T03:51:46.779Z] GC before operation: completed in 81.267 ms, heap usage 133.020 MB -> 63.928 MB. [2026-04-02T03:52:00.198Z] ====== als (apache-spark) [default], iteration 4 completed (15385.947 ms) ====== [2026-04-02T03:52:00.198Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-02T03:52:00.198Z] GC before operation: completed in 86.678 ms, heap usage 171.624 MB -> 64.813 MB. [2026-04-02T03:52:16.331Z] ====== als (apache-spark) [default], iteration 5 completed (14650.793 ms) ====== [2026-04-02T03:52:16.331Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-02T03:52:16.331Z] GC before operation: completed in 85.786 ms, heap usage 520.594 MB -> 65.839 MB. [2026-04-02T03:52:32.518Z] ====== als (apache-spark) [default], iteration 6 completed (15486.357 ms) ====== [2026-04-02T03:52:32.518Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-02T03:52:32.518Z] GC before operation: completed in 95.526 ms, heap usage 556.645 MB -> 66.495 MB. [2026-04-02T03:52:46.162Z] ====== als (apache-spark) [default], iteration 7 completed (14525.654 ms) ====== [2026-04-02T03:52:46.162Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-02T03:52:46.162Z] GC before operation: completed in 105.618 ms, heap usage 292.623 MB -> 66.436 MB. [2026-04-02T03:53:02.331Z] ====== als (apache-spark) [default], iteration 8 completed (15088.229 ms) ====== [2026-04-02T03:53:02.331Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-02T03:53:02.331Z] GC before operation: completed in 82.278 ms, heap usage 172.047 MB -> 67.066 MB. [2026-04-02T03:53:18.609Z] ====== als (apache-spark) [default], iteration 9 completed (14864.942 ms) ====== [2026-04-02T03:53:18.609Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-02T03:53:18.609Z] GC before operation: completed in 90.805 ms, heap usage 248.268 MB -> 67.262 MB. [2026-04-02T03:53:32.009Z] ====== als (apache-spark) [default], iteration 10 completed (15151.446 ms) ====== [2026-04-02T03:53:32.009Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-02T03:53:32.009Z] GC before operation: completed in 80.830 ms, heap usage 250.454 MB -> 67.671 MB. [2026-04-02T03:53:48.082Z] ====== als (apache-spark) [default], iteration 11 completed (15213.069 ms) ====== [2026-04-02T03:53:48.082Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-02T03:53:48.082Z] GC before operation: completed in 106.749 ms, heap usage 199.985 MB -> 67.139 MB. [2026-04-02T03:54:04.212Z] ====== als (apache-spark) [default], iteration 12 completed (14857.133 ms) ====== [2026-04-02T03:54:04.212Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-02T03:54:04.212Z] GC before operation: completed in 82.471 ms, heap usage 165.024 MB -> 68.172 MB. [2026-04-02T03:54:20.460Z] ====== als (apache-spark) [default], iteration 13 completed (15594.700 ms) ====== [2026-04-02T03:54:20.460Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-02T03:54:20.461Z] GC before operation: completed in 86.982 ms, heap usage 363.546 MB -> 68.429 MB. [2026-04-02T03:54:33.747Z] ====== als (apache-spark) [default], iteration 14 completed (14859.161 ms) ====== [2026-04-02T03:54:33.747Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-02T03:54:33.747Z] GC before operation: completed in 97.928 ms, heap usage 267.557 MB -> 68.795 MB. [2026-04-02T03:54:49.847Z] ====== als (apache-spark) [default], iteration 15 completed (14822.571 ms) ====== [2026-04-02T03:54:49.847Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-02T03:54:49.847Z] GC before operation: completed in 90.344 ms, heap usage 269.868 MB -> 68.933 MB. [2026-04-02T03:55:03.257Z] ====== als (apache-spark) [default], iteration 16 completed (14255.069 ms) ====== [2026-04-02T03:55:03.257Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-02T03:55:03.257Z] GC before operation: completed in 106.686 ms, heap usage 242.563 MB -> 69.239 MB. [2026-04-02T03:55:19.514Z] ====== als (apache-spark) [default], iteration 17 completed (14834.957 ms) ====== [2026-04-02T03:55:19.514Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-02T03:55:19.514Z] GC before operation: completed in 87.760 ms, heap usage 205.482 MB -> 69.465 MB. [2026-04-02T03:55:32.923Z] ====== als (apache-spark) [default], iteration 18 completed (14597.929 ms) ====== [2026-04-02T03:55:32.923Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-02T03:55:32.923Z] GC before operation: completed in 87.370 ms, heap usage 286.288 MB -> 69.871 MB. [2026-04-02T03:55:49.340Z] ====== als (apache-spark) [default], iteration 19 completed (15035.981 ms) ====== [2026-04-02T03:55:49.340Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-02T03:55:49.340Z] GC before operation: completed in 88.548 ms, heap usage 501.602 MB -> 70.328 MB. [2026-04-02T03:56:08.819Z] ====== als (apache-spark) [default], iteration 20 completed (18131.162 ms) ====== [2026-04-02T03:56:08.819Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-02T03:56:08.819Z] GC before operation: completed in 87.939 ms, heap usage 272.426 MB -> 70.191 MB. [2026-04-02T03:56:22.184Z] ====== als (apache-spark) [default], iteration 21 completed (14594.116 ms) ====== [2026-04-02T03:56:22.184Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-02T03:56:22.184Z] GC before operation: completed in 86.159 ms, heap usage 265.447 MB -> 70.538 MB. [2026-04-02T03:56:38.403Z] ====== als (apache-spark) [default], iteration 22 completed (14773.786 ms) ====== [2026-04-02T03:56:38.403Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-02T03:56:38.403Z] GC before operation: completed in 87.989 ms, heap usage 203.424 MB -> 70.725 MB. [2026-04-02T03:56:51.330Z] ====== als (apache-spark) [default], iteration 23 completed (14804.534 ms) ====== [2026-04-02T03:56:51.330Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-02T03:56:51.330Z] GC before operation: completed in 91.133 ms, heap usage 504.651 MB -> 71.341 MB. [2026-04-02T03:57:07.596Z] ====== als (apache-spark) [default], iteration 24 completed (14236.714 ms) ====== [2026-04-02T03:57:07.596Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-02T03:57:07.596Z] GC before operation: completed in 87.102 ms, heap usage 432.324 MB -> 71.617 MB. [2026-04-02T03:57:21.050Z] ====== als (apache-spark) [default], iteration 25 completed (15034.564 ms) ====== [2026-04-02T03:57:21.050Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-02T03:57:21.050Z] GC before operation: completed in 89.225 ms, heap usage 309.449 MB -> 71.654 MB. [2026-04-02T03:57:37.235Z] ====== als (apache-spark) [default], iteration 26 completed (14603.325 ms) ====== [2026-04-02T03:57:37.235Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-02T03:57:37.235Z] GC before operation: completed in 89.502 ms, heap usage 502.912 MB -> 72.150 MB. [2026-04-02T03:57:53.354Z] ====== als (apache-spark) [default], iteration 27 completed (15030.904 ms) ====== [2026-04-02T03:57:53.355Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-02T03:57:53.355Z] GC before operation: completed in 97.072 ms, heap usage 194.969 MB -> 72.018 MB. [2026-04-02T03:58:06.850Z] ====== als (apache-spark) [default], iteration 28 completed (14329.226 ms) ====== [2026-04-02T03:58:06.850Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-02T03:58:06.850Z] GC before operation: completed in 94.802 ms, heap usage 246.568 MB -> 71.868 MB. [2026-04-02T03:58:22.966Z] ====== als (apache-spark) [default], iteration 29 completed (15153.192 ms) ====== [2026-04-02T03:58:22.966Z] ----------------------------------- [2026-04-02T03:58:22.966Z] renaissance-als_0_PASSED [2026-04-02T03:58:22.966Z] ----------------------------------- [2026-04-02T03:58:22.966Z] [2026-04-02T03:58:22.966Z] TEST TEARDOWN: [2026-04-02T03:58:22.966Z] Nothing to be done for teardown. [2026-04-02T03:58:22.966Z] renaissance-als_0 Finish Time: Thu Apr 2 03:58:21 2026 Epoch Time (ms): 1775102301540