[2025-08-27T22:14:09.046Z] Running test renaissance-als_0 ... [2025-08-27T22:14:09.046Z] =============================================== [2025-08-27T22:14:09.360Z] renaissance-als_0 Start Time: Wed Aug 27 22:14:09 2025 Epoch Time (ms): 1756332849091 [2025-08-27T22:14:09.360Z] variation: NoOptions [2025-08-27T22:14:09.360Z] JVM_OPTIONS: [2025-08-27T22:14:09.360Z] { \ [2025-08-27T22:14:09.360Z] echo ""; echo "TEST SETUP:"; \ [2025-08-27T22:14:09.360Z] echo "Nothing to be done for setup."; \ [2025-08-27T22:14:09.360Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\renaissance-als_0"; \ [2025-08-27T22:14:09.360Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\renaissance-als_0"; \ [2025-08-27T22:14:09.360Z] echo ""; echo "TESTING:"; \ [2025-08-27T22:14:09.360Z] "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_17563326569832\\renaissance-als_0"\\als.json" als; \ [2025-08-27T22:14:09.360Z] 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_17563326569832\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-27T22:14:09.360Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-27T22:14:09.360Z] echo "Nothing to be done for teardown."; \ [2025-08-27T22:14:09.360Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\TestTargetResult"; [2025-08-27T22:14:09.360Z] [2025-08-27T22:14:09.360Z] TEST SETUP: [2025-08-27T22:14:09.360Z] Nothing to be done for setup. [2025-08-27T22:14:09.360Z] [2025-08-27T22:14:09.360Z] TESTING: [2025-08-27T22:14:22.182Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-27T22:14:26.907Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-27T22:14:26.907Z] GC before operation: completed in 34.381 ms, heap usage 233.133 MB -> 35.504 MB. [2025-08-27T22:14:54.910Z] ====== als (apache-spark) [default], iteration 0 completed (26404.525 ms) ====== [2025-08-27T22:14:54.910Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-27T22:14:54.910Z] GC before operation: completed in 124.536 ms, heap usage 308.259 MB -> 62.522 MB. [2025-08-27T22:15:10.648Z] ====== als (apache-spark) [default], iteration 1 completed (15117.163 ms) ====== [2025-08-27T22:15:10.648Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-27T22:15:10.648Z] GC before operation: completed in 75.788 ms, heap usage 232.064 MB -> 63.244 MB. [2025-08-27T22:15:23.732Z] ====== als (apache-spark) [default], iteration 2 completed (14801.752 ms) ====== [2025-08-27T22:15:23.732Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-27T22:15:24.066Z] GC before operation: completed in 98.628 ms, heap usage 353.986 MB -> 64.031 MB. [2025-08-27T22:15:39.807Z] ====== als (apache-spark) [default], iteration 3 completed (14258.696 ms) ====== [2025-08-27T22:15:39.807Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-27T22:15:39.807Z] GC before operation: completed in 91.451 ms, heap usage 222.347 MB -> 64.528 MB. [2025-08-27T22:15:52.748Z] ====== als (apache-spark) [default], iteration 4 completed (14062.043 ms) ====== [2025-08-27T22:15:52.748Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-27T22:15:53.105Z] GC before operation: completed in 79.472 ms, heap usage 302.030 MB -> 65.298 MB. [2025-08-27T22:16:08.843Z] ====== als (apache-spark) [default], iteration 5 completed (14020.279 ms) ====== [2025-08-27T22:16:08.843Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-27T22:16:08.843Z] GC before operation: completed in 83.189 ms, heap usage 304.637 MB -> 65.836 MB. [2025-08-27T22:16:21.912Z] ====== als (apache-spark) [default], iteration 6 completed (14417.135 ms) ====== [2025-08-27T22:16:21.913Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-27T22:16:21.913Z] GC before operation: completed in 87.387 ms, heap usage 333.087 MB -> 66.317 MB. [2025-08-27T22:16:37.919Z] ====== als (apache-spark) [default], iteration 7 completed (13986.383 ms) ====== [2025-08-27T22:16:37.919Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-27T22:16:37.919Z] GC before operation: completed in 92.029 ms, heap usage 261.677 MB -> 66.613 MB. [2025-08-27T22:16:50.951Z] ====== als (apache-spark) [default], iteration 8 completed (14279.708 ms) ====== [2025-08-27T22:16:50.951Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-27T22:16:50.951Z] GC before operation: completed in 71.241 ms, heap usage 352.435 MB -> 67.338 MB. [2025-08-27T22:17:06.675Z] ====== als (apache-spark) [default], iteration 9 completed (13979.752 ms) ====== [2025-08-27T22:17:06.675Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-27T22:17:06.675Z] GC before operation: completed in 73.832 ms, heap usage 353.313 MB -> 67.975 MB. [2025-08-27T22:17:19.555Z] ====== als (apache-spark) [default], iteration 10 completed (13813.887 ms) ====== [2025-08-27T22:17:19.555Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-27T22:17:19.555Z] GC before operation: completed in 85.073 ms, heap usage 131.248 MB -> 67.527 MB. [2025-08-27T22:17:35.347Z] ====== als (apache-spark) [default], iteration 11 completed (13833.386 ms) ====== [2025-08-27T22:17:35.347Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-27T22:17:35.347Z] GC before operation: completed in 72.565 ms, heap usage 322.680 MB -> 68.054 MB. [2025-08-27T22:17:48.311Z] ====== als (apache-spark) [default], iteration 12 completed (13661.947 ms) ====== [2025-08-27T22:17:48.311Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-27T22:17:48.311Z] GC before operation: completed in 79.216 ms, heap usage 211.921 MB -> 68.069 MB. [2025-08-27T22:18:01.433Z] ====== als (apache-spark) [default], iteration 13 completed (13690.529 ms) ====== [2025-08-27T22:18:01.433Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-27T22:18:01.433Z] GC before operation: completed in 71.795 ms, heap usage 151.342 MB -> 68.286 MB. [2025-08-27T22:18:17.298Z] ====== als (apache-spark) [default], iteration 14 completed (14392.578 ms) ====== [2025-08-27T22:18:17.298Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-27T22:18:17.298Z] GC before operation: completed in 75.197 ms, heap usage 248.139 MB -> 68.708 MB. [2025-08-27T22:18:30.151Z] ====== als (apache-spark) [default], iteration 15 completed (13858.132 ms) ====== [2025-08-27T22:18:30.151Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-27T22:18:30.151Z] GC before operation: completed in 72.240 ms, heap usage 303.366 MB -> 69.104 MB. [2025-08-27T22:18:45.775Z] ====== als (apache-spark) [default], iteration 16 completed (13936.251 ms) ====== [2025-08-27T22:18:45.775Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-27T22:18:45.775Z] GC before operation: completed in 75.861 ms, heap usage 318.890 MB -> 69.356 MB. [2025-08-27T22:18:58.781Z] ====== als (apache-spark) [default], iteration 17 completed (14053.038 ms) ====== [2025-08-27T22:18:58.781Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-27T22:18:58.781Z] GC before operation: completed in 75.195 ms, heap usage 206.009 MB -> 69.357 MB. [2025-08-27T22:19:14.387Z] ====== als (apache-spark) [default], iteration 18 completed (13933.649 ms) ====== [2025-08-27T22:19:14.387Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-27T22:19:14.387Z] GC before operation: completed in 76.604 ms, heap usage 242.046 MB -> 69.744 MB. [2025-08-27T22:19:27.202Z] ====== als (apache-spark) [default], iteration 19 completed (13847.896 ms) ====== [2025-08-27T22:19:27.202Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-27T22:19:27.202Z] GC before operation: completed in 77.284 ms, heap usage 309.187 MB -> 70.121 MB. [2025-08-27T22:19:42.880Z] ====== als (apache-spark) [default], iteration 20 completed (14149.006 ms) ====== [2025-08-27T22:19:42.880Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-27T22:19:42.880Z] GC before operation: completed in 75.466 ms, heap usage 239.686 MB -> 70.285 MB. [2025-08-27T22:19:55.781Z] ====== als (apache-spark) [default], iteration 21 completed (13886.302 ms) ====== [2025-08-27T22:19:55.781Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-27T22:19:55.781Z] GC before operation: completed in 76.210 ms, heap usage 356.574 MB -> 70.617 MB. [2025-08-27T22:20:11.366Z] ====== als (apache-spark) [default], iteration 22 completed (13862.447 ms) ====== [2025-08-27T22:20:11.366Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-27T22:20:11.366Z] GC before operation: completed in 77.771 ms, heap usage 304.154 MB -> 70.831 MB. [2025-08-27T22:20:24.269Z] ====== als (apache-spark) [default], iteration 23 completed (13769.077 ms) ====== [2025-08-27T22:20:24.269Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-27T22:20:24.269Z] GC before operation: completed in 78.246 ms, heap usage 254.342 MB -> 70.970 MB. [2025-08-27T22:20:39.829Z] ====== als (apache-spark) [default], iteration 24 completed (14051.836 ms) ====== [2025-08-27T22:20:39.829Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-27T22:20:39.829Z] GC before operation: completed in 74.282 ms, heap usage 273.994 MB -> 71.242 MB. [2025-08-27T22:20:52.693Z] ====== als (apache-spark) [default], iteration 25 completed (13838.260 ms) ====== [2025-08-27T22:20:52.693Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-27T22:20:52.693Z] GC before operation: completed in 74.982 ms, heap usage 162.166 MB -> 71.458 MB. [2025-08-27T22:21:05.786Z] ====== als (apache-spark) [default], iteration 26 completed (13824.721 ms) ====== [2025-08-27T22:21:05.786Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-27T22:21:05.786Z] GC before operation: completed in 75.822 ms, heap usage 137.980 MB -> 71.682 MB. [2025-08-27T22:21:21.533Z] ====== als (apache-spark) [default], iteration 27 completed (13782.536 ms) ====== [2025-08-27T22:21:21.534Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-27T22:21:21.534Z] GC before operation: completed in 85.282 ms, heap usage 562.671 MB -> 72.481 MB. [2025-08-27T22:21:34.347Z] ====== als (apache-spark) [default], iteration 28 completed (13780.841 ms) ====== [2025-08-27T22:21:34.347Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-27T22:21:34.347Z] GC before operation: completed in 78.012 ms, heap usage 183.590 MB -> 72.273 MB. [2025-08-27T22:21:47.310Z] ====== als (apache-spark) [default], iteration 29 completed (13546.171 ms) ====== [2025-08-27T22:21:47.984Z] ----------------------------------- [2025-08-27T22:21:47.984Z] renaissance-als_0_PASSED [2025-08-27T22:21:47.984Z] ----------------------------------- [2025-08-27T22:21:48.292Z] [2025-08-27T22:21:48.292Z] TEST TEARDOWN: [2025-08-27T22:21:48.292Z] Nothing to be done for teardown. [2025-08-27T22:21:48.292Z] renaissance-als_0 Finish Time: Wed Aug 27 22:21:48 2025 Epoch Time (ms): 1756333308121