[2025-06-29T22:18:30.765Z] Running test renaissance-als_0 ... [2025-06-29T22:18:31.072Z] =============================================== [2025-06-29T22:18:31.072Z] renaissance-als_0 Start Time: Sun Jun 29 22:18:30 2025 Epoch Time (ms): 1751235510935 [2025-06-29T22:18:31.072Z] variation: NoOptions [2025-06-29T22:18:31.393Z] JVM_OPTIONS: [2025-06-29T22:18:31.393Z] { \ [2025-06-29T22:18:31.393Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T22:18:31.393Z] echo "Nothing to be done for setup."; \ [2025-06-29T22:18:31.393Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17512353965472\\renaissance-als_0"; \ [2025-06-29T22:18:31.393Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17512353965472\\renaissance-als_0"; \ [2025-06-29T22:18:31.393Z] echo ""; echo "TESTING:"; \ [2025-06-29T22:18:31.393Z] "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_17512353965472\\renaissance-als_0"\\als.json" als; \ [2025-06-29T22:18:31.394Z] 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_17512353965472\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T22:18:31.394Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T22:18:31.394Z] echo "Nothing to be done for teardown."; \ [2025-06-29T22:18:31.394Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17512353965472\\TestTargetResult"; [2025-06-29T22:18:31.394Z] [2025-06-29T22:18:31.394Z] TEST SETUP: [2025-06-29T22:18:31.394Z] Nothing to be done for setup. [2025-06-29T22:18:31.394Z] [2025-06-29T22:18:31.394Z] TESTING: [2025-06-29T22:18:46.964Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-29T22:18:49.818Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-29T22:18:49.818Z] GC before operation: completed in 34.172 ms, heap usage 57.500 MB -> 35.392 MB. [2025-06-29T22:19:17.906Z] ====== als (apache-spark) [default], iteration 0 completed (27462.695 ms) ====== [2025-06-29T22:19:17.906Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-29T22:19:17.906Z] GC before operation: completed in 88.171 ms, heap usage 193.054 MB -> 61.883 MB. [2025-06-29T22:19:33.681Z] ====== als (apache-spark) [default], iteration 1 completed (15951.280 ms) ====== [2025-06-29T22:19:33.681Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-29T22:19:34.062Z] GC before operation: completed in 71.920 ms, heap usage 358.665 MB -> 63.401 MB. [2025-06-29T22:19:49.896Z] ====== als (apache-spark) [default], iteration 2 completed (15190.522 ms) ====== [2025-06-29T22:19:49.896Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-29T22:19:49.896Z] GC before operation: completed in 74.430 ms, heap usage 197.398 MB -> 63.798 MB. [2025-06-29T22:20:05.874Z] ====== als (apache-spark) [default], iteration 3 completed (15143.710 ms) ====== [2025-06-29T22:20:05.874Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-29T22:20:05.874Z] GC before operation: completed in 83.103 ms, heap usage 304.631 MB -> 64.587 MB. [2025-06-29T22:20:21.618Z] ====== als (apache-spark) [default], iteration 4 completed (14809.689 ms) ====== [2025-06-29T22:20:21.618Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-29T22:20:21.618Z] GC before operation: completed in 80.546 ms, heap usage 403.416 MB -> 65.086 MB. [2025-06-29T22:20:34.790Z] ====== als (apache-spark) [default], iteration 5 completed (14883.111 ms) ====== [2025-06-29T22:20:34.790Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-29T22:20:35.225Z] GC before operation: completed in 84.908 ms, heap usage 352.126 MB -> 65.657 MB. [2025-06-29T22:20:51.143Z] ====== als (apache-spark) [default], iteration 6 completed (14728.140 ms) ====== [2025-06-29T22:20:51.143Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-29T22:20:51.143Z] GC before operation: completed in 82.102 ms, heap usage 443.362 MB -> 66.497 MB. [2025-06-29T22:21:06.906Z] ====== als (apache-spark) [default], iteration 7 completed (14640.983 ms) ====== [2025-06-29T22:21:06.906Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-29T22:21:06.906Z] GC before operation: completed in 80.220 ms, heap usage 225.188 MB -> 66.566 MB. [2025-06-29T22:21:19.981Z] ====== als (apache-spark) [default], iteration 8 completed (14509.750 ms) ====== [2025-06-29T22:21:19.981Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-29T22:21:19.981Z] GC before operation: completed in 81.246 ms, heap usage 154.662 MB -> 67.219 MB. [2025-06-29T22:21:35.657Z] ====== als (apache-spark) [default], iteration 9 completed (14637.450 ms) ====== [2025-06-29T22:21:35.657Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-29T22:21:35.657Z] GC before operation: completed in 83.797 ms, heap usage 468.303 MB -> 67.822 MB. [2025-06-29T22:21:51.340Z] ====== als (apache-spark) [default], iteration 10 completed (14614.613 ms) ====== [2025-06-29T22:21:51.340Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-29T22:21:51.340Z] GC before operation: completed in 75.946 ms, heap usage 444.010 MB -> 67.920 MB. [2025-06-29T22:22:04.402Z] ====== als (apache-spark) [default], iteration 11 completed (14492.425 ms) ====== [2025-06-29T22:22:04.402Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-29T22:22:04.402Z] GC before operation: completed in 93.275 ms, heap usage 238.621 MB -> 67.874 MB. [2025-06-29T22:22:20.194Z] ====== als (apache-spark) [default], iteration 12 completed (14454.865 ms) ====== [2025-06-29T22:22:20.194Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-29T22:22:20.194Z] GC before operation: completed in 77.165 ms, heap usage 208.802 MB -> 68.031 MB. [2025-06-29T22:22:35.875Z] ====== als (apache-spark) [default], iteration 13 completed (14547.499 ms) ====== [2025-06-29T22:22:35.876Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-29T22:22:35.876Z] GC before operation: completed in 78.011 ms, heap usage 438.475 MB -> 68.739 MB. [2025-06-29T22:22:48.854Z] ====== als (apache-spark) [default], iteration 14 completed (14698.247 ms) ====== [2025-06-29T22:22:48.854Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-29T22:22:48.854Z] GC before operation: completed in 77.799 ms, heap usage 289.737 MB -> 68.652 MB. [2025-06-29T22:23:04.549Z] ====== als (apache-spark) [default], iteration 15 completed (14556.906 ms) ====== [2025-06-29T22:23:04.549Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-29T22:23:04.549Z] GC before operation: completed in 83.633 ms, heap usage 355.924 MB -> 69.075 MB. [2025-06-29T22:23:20.225Z] ====== als (apache-spark) [default], iteration 16 completed (14391.606 ms) ====== [2025-06-29T22:23:20.225Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-29T22:23:20.226Z] GC before operation: completed in 85.881 ms, heap usage 210.121 MB -> 69.120 MB. [2025-06-29T22:23:33.245Z] ====== als (apache-spark) [default], iteration 17 completed (14536.663 ms) ====== [2025-06-29T22:23:33.245Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-29T22:23:33.245Z] GC before operation: completed in 78.324 ms, heap usage 239.318 MB -> 69.454 MB. [2025-06-29T22:23:49.112Z] ====== als (apache-spark) [default], iteration 18 completed (14458.073 ms) ====== [2025-06-29T22:23:49.112Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-29T22:23:49.112Z] GC before operation: completed in 76.262 ms, heap usage 337.316 MB -> 69.838 MB. [2025-06-29T22:24:04.911Z] ====== als (apache-spark) [default], iteration 19 completed (14681.294 ms) ====== [2025-06-29T22:24:04.911Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-29T22:24:04.911Z] GC before operation: completed in 80.421 ms, heap usage 278.655 MB -> 69.968 MB. [2025-06-29T22:24:18.044Z] ====== als (apache-spark) [default], iteration 20 completed (14838.313 ms) ====== [2025-06-29T22:24:18.044Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-29T22:24:18.044Z] GC before operation: completed in 78.839 ms, heap usage 388.612 MB -> 70.358 MB. [2025-06-29T22:24:33.798Z] ====== als (apache-spark) [default], iteration 21 completed (14350.229 ms) ====== [2025-06-29T22:24:33.798Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-29T22:24:33.798Z] GC before operation: completed in 80.738 ms, heap usage 495.949 MB -> 70.780 MB. [2025-06-29T22:24:46.793Z] ====== als (apache-spark) [default], iteration 22 completed (14562.599 ms) ====== [2025-06-29T22:24:46.793Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-29T22:24:46.793Z] GC before operation: completed in 87.256 ms, heap usage 530.585 MB -> 71.124 MB. [2025-06-29T22:25:02.403Z] ====== als (apache-spark) [default], iteration 23 completed (14401.831 ms) ====== [2025-06-29T22:25:02.403Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-29T22:25:02.403Z] GC before operation: completed in 78.645 ms, heap usage 245.099 MB -> 71.069 MB. [2025-06-29T22:25:18.109Z] ====== als (apache-spark) [default], iteration 24 completed (14302.137 ms) ====== [2025-06-29T22:25:18.109Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-29T22:25:18.109Z] GC before operation: completed in 79.511 ms, heap usage 252.471 MB -> 71.209 MB. [2025-06-29T22:25:31.094Z] ====== als (apache-spark) [default], iteration 25 completed (14426.793 ms) ====== [2025-06-29T22:25:31.094Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-29T22:25:31.094Z] GC before operation: completed in 79.639 ms, heap usage 236.614 MB -> 71.588 MB. [2025-06-29T22:25:46.891Z] ====== als (apache-spark) [default], iteration 26 completed (14447.153 ms) ====== [2025-06-29T22:25:46.891Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-29T22:25:46.891Z] GC before operation: completed in 80.139 ms, heap usage 391.852 MB -> 71.917 MB. [2025-06-29T22:26:02.522Z] ====== als (apache-spark) [default], iteration 27 completed (14334.175 ms) ====== [2025-06-29T22:26:02.522Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-29T22:26:02.522Z] GC before operation: completed in 80.609 ms, heap usage 266.615 MB -> 72.082 MB. [2025-06-29T22:26:15.578Z] ====== als (apache-spark) [default], iteration 28 completed (14397.678 ms) ====== [2025-06-29T22:26:15.578Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-29T22:26:15.578Z] GC before operation: completed in 79.789 ms, heap usage 161.126 MB -> 72.190 MB. [2025-06-29T22:26:31.537Z] ====== als (apache-spark) [default], iteration 29 completed (14367.139 ms) ====== [2025-06-29T22:26:31.537Z] ----------------------------------- [2025-06-29T22:26:31.537Z] renaissance-als_0_PASSED [2025-06-29T22:26:31.537Z] ----------------------------------- [2025-06-29T22:26:31.537Z] [2025-06-29T22:26:31.537Z] TEST TEARDOWN: [2025-06-29T22:26:31.537Z] Nothing to be done for teardown. [2025-06-29T22:26:31.537Z] renaissance-als_0 Finish Time: Sun Jun 29 22:26:30 2025 Epoch Time (ms): 1751235990031