[2026-01-10T07:13:59.573Z] Running test renaissance-als_0 ... [2026-01-10T07:13:59.573Z] =============================================== [2026-01-10T07:13:59.573Z] renaissance-als_0 Start Time: Sat Jan 10 07:13:59 2026 Epoch Time (ms): 1768029239432 [2026-01-10T07:13:59.573Z] variation: NoOptions [2026-01-10T07:13:59.934Z] JVM_OPTIONS: [2026-01-10T07:13:59.934Z] { \ [2026-01-10T07:13:59.934Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T07:13:59.934Z] echo "Nothing to be done for setup."; \ [2026-01-10T07:13:59.935Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680290144173\\renaissance-als_0"; \ [2026-01-10T07:13:59.935Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680290144173\\renaissance-als_0"; \ [2026-01-10T07:13:59.935Z] echo ""; echo "TESTING:"; \ [2026-01-10T07:13:59.935Z] "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_17680290144173\\renaissance-als_0"\\als.json" als; \ [2026-01-10T07:13:59.935Z] 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_17680290144173\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T07:13:59.935Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T07:13:59.935Z] echo "Nothing to be done for teardown."; \ [2026-01-10T07:13:59.935Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680290144173\\TestTargetResult"; [2026-01-10T07:13:59.935Z] [2026-01-10T07:13:59.935Z] TEST SETUP: [2026-01-10T07:13:59.935Z] Nothing to be done for setup. [2026-01-10T07:13:59.935Z] [2026-01-10T07:13:59.935Z] TESTING: [2026-01-10T07:14:15.926Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-10T07:14:18.957Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T07:14:18.957Z] GC before operation: completed in 37.189 ms, heap usage 54.500 MB -> 35.188 MB. [2026-01-10T07:14:53.400Z] ====== als (apache-spark) [default], iteration 0 completed (29825.285 ms) ====== [2026-01-10T07:14:53.400Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T07:14:53.400Z] GC before operation: completed in 78.546 ms, heap usage 224.546 MB -> 62.142 MB. [2026-01-10T07:15:06.913Z] ====== als (apache-spark) [default], iteration 1 completed (16758.865 ms) ====== [2026-01-10T07:15:06.913Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T07:15:06.913Z] GC before operation: completed in 75.247 ms, heap usage 135.020 MB -> 62.625 MB. [2026-01-10T07:15:23.037Z] ====== als (apache-spark) [default], iteration 2 completed (16637.310 ms) ====== [2026-01-10T07:15:23.037Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T07:15:23.037Z] GC before operation: completed in 92.937 ms, heap usage 736.147 MB -> 64.320 MB. [2026-01-10T07:15:39.276Z] ====== als (apache-spark) [default], iteration 3 completed (16031.227 ms) ====== [2026-01-10T07:15:39.276Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T07:15:39.276Z] GC before operation: completed in 82.531 ms, heap usage 564.677 MB -> 64.779 MB. [2026-01-10T07:15:58.861Z] ====== als (apache-spark) [default], iteration 4 completed (16551.325 ms) ====== [2026-01-10T07:15:58.861Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T07:15:58.861Z] GC before operation: completed in 89.907 ms, heap usage 598.593 MB -> 65.328 MB. [2026-01-10T07:16:14.892Z] ====== als (apache-spark) [default], iteration 5 completed (16077.714 ms) ====== [2026-01-10T07:16:14.892Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T07:16:14.892Z] GC before operation: completed in 74.774 ms, heap usage 299.574 MB -> 65.542 MB. [2026-01-10T07:16:31.018Z] ====== als (apache-spark) [default], iteration 6 completed (16657.949 ms) ====== [2026-01-10T07:16:31.018Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T07:16:31.018Z] GC before operation: completed in 111.360 ms, heap usage 778.366 MB -> 66.605 MB. [2026-01-10T07:16:47.163Z] ====== als (apache-spark) [default], iteration 7 completed (15614.224 ms) ====== [2026-01-10T07:16:47.163Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T07:16:47.163Z] GC before operation: completed in 91.600 ms, heap usage 754.725 MB -> 67.224 MB. [2026-01-10T07:17:03.359Z] ====== als (apache-spark) [default], iteration 8 completed (15905.685 ms) ====== [2026-01-10T07:17:03.359Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T07:17:03.359Z] GC before operation: completed in 83.424 ms, heap usage 159.278 MB -> 67.063 MB. [2026-01-10T07:17:19.633Z] ====== als (apache-spark) [default], iteration 9 completed (16559.850 ms) ====== [2026-01-10T07:17:19.633Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T07:17:19.633Z] GC before operation: completed in 86.115 ms, heap usage 474.058 MB -> 67.735 MB. [2026-01-10T07:17:35.743Z] ====== als (apache-spark) [default], iteration 10 completed (15549.077 ms) ====== [2026-01-10T07:17:35.743Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T07:17:35.743Z] GC before operation: completed in 86.509 ms, heap usage 708.417 MB -> 68.126 MB. [2026-01-10T07:17:51.771Z] ====== als (apache-spark) [default], iteration 11 completed (16034.194 ms) ====== [2026-01-10T07:17:51.771Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T07:17:51.771Z] GC before operation: completed in 86.667 ms, heap usage 369.811 MB -> 68.039 MB. [2026-01-10T07:18:07.857Z] ====== als (apache-spark) [default], iteration 12 completed (15476.242 ms) ====== [2026-01-10T07:18:07.857Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T07:18:07.857Z] GC before operation: completed in 84.003 ms, heap usage 698.665 MB -> 68.686 MB. [2026-01-10T07:18:23.970Z] ====== als (apache-spark) [default], iteration 13 completed (16393.946 ms) ====== [2026-01-10T07:18:23.970Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T07:18:23.970Z] GC before operation: completed in 90.164 ms, heap usage 765.517 MB -> 69.053 MB. [2026-01-10T07:18:40.114Z] ====== als (apache-spark) [default], iteration 14 completed (15677.784 ms) ====== [2026-01-10T07:18:40.114Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T07:18:40.114Z] GC before operation: completed in 92.981 ms, heap usage 308.971 MB -> 68.775 MB. [2026-01-10T07:18:56.286Z] ====== als (apache-spark) [default], iteration 15 completed (15711.362 ms) ====== [2026-01-10T07:18:56.287Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T07:18:56.287Z] GC before operation: completed in 88.579 ms, heap usage 564.809 MB -> 69.453 MB. [2026-01-10T07:19:12.487Z] ====== als (apache-spark) [default], iteration 16 completed (15930.297 ms) ====== [2026-01-10T07:19:12.487Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T07:19:12.487Z] GC before operation: completed in 94.516 ms, heap usage 723.110 MB -> 69.803 MB. [2026-01-10T07:19:28.622Z] ====== als (apache-spark) [default], iteration 17 completed (15656.081 ms) ====== [2026-01-10T07:19:28.622Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T07:19:28.622Z] GC before operation: completed in 88.156 ms, heap usage 640.147 MB -> 70.017 MB. [2026-01-10T07:19:44.720Z] ====== als (apache-spark) [default], iteration 18 completed (16105.697 ms) ====== [2026-01-10T07:19:44.720Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T07:19:44.720Z] GC before operation: completed in 91.176 ms, heap usage 783.960 MB -> 70.478 MB. [2026-01-10T07:20:00.861Z] ====== als (apache-spark) [default], iteration 19 completed (15664.786 ms) ====== [2026-01-10T07:20:00.861Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T07:20:00.861Z] GC before operation: completed in 97.587 ms, heap usage 251.037 MB -> 69.918 MB. [2026-01-10T07:20:17.137Z] ====== als (apache-spark) [default], iteration 20 completed (16257.682 ms) ====== [2026-01-10T07:20:17.137Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T07:20:17.137Z] GC before operation: completed in 88.216 ms, heap usage 382.968 MB -> 70.485 MB. [2026-01-10T07:20:33.337Z] ====== als (apache-spark) [default], iteration 21 completed (15580.876 ms) ====== [2026-01-10T07:20:33.337Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T07:20:33.337Z] GC before operation: completed in 96.450 ms, heap usage 446.571 MB -> 70.734 MB. [2026-01-10T07:20:49.502Z] ====== als (apache-spark) [default], iteration 22 completed (16020.156 ms) ====== [2026-01-10T07:20:49.502Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T07:20:49.502Z] GC before operation: completed in 99.313 ms, heap usage 652.353 MB -> 71.381 MB. [2026-01-10T07:21:05.654Z] ====== als (apache-spark) [default], iteration 23 completed (16289.602 ms) ====== [2026-01-10T07:21:05.654Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T07:21:05.654Z] GC before operation: completed in 96.883 ms, heap usage 758.004 MB -> 71.833 MB. [2026-01-10T07:21:21.796Z] ====== als (apache-spark) [default], iteration 24 completed (15623.716 ms) ====== [2026-01-10T07:21:21.796Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T07:21:21.796Z] GC before operation: completed in 91.218 ms, heap usage 646.250 MB -> 71.914 MB. [2026-01-10T07:21:38.013Z] ====== als (apache-spark) [default], iteration 25 completed (16106.309 ms) ====== [2026-01-10T07:21:38.013Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T07:21:38.013Z] GC before operation: completed in 95.492 ms, heap usage 547.446 MB -> 71.992 MB. [2026-01-10T07:21:51.337Z] ====== als (apache-spark) [default], iteration 26 completed (14927.481 ms) ====== [2026-01-10T07:21:51.337Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T07:21:51.337Z] GC before operation: completed in 92.265 ms, heap usage 194.003 MB -> 71.755 MB. [2026-01-10T07:22:07.769Z] ====== als (apache-spark) [default], iteration 27 completed (16352.772 ms) ====== [2026-01-10T07:22:07.769Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T07:22:07.769Z] GC before operation: completed in 109.943 ms, heap usage 522.136 MB -> 72.464 MB. [2026-01-10T07:22:23.831Z] ====== als (apache-spark) [default], iteration 28 completed (15323.863 ms) ====== [2026-01-10T07:22:23.831Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T07:22:23.831Z] GC before operation: completed in 89.098 ms, heap usage 289.095 MB -> 72.461 MB. [2026-01-10T07:22:40.172Z] ====== als (apache-spark) [default], iteration 29 completed (16696.038 ms) ====== [2026-01-10T07:22:40.924Z] ----------------------------------- [2026-01-10T07:22:40.924Z] renaissance-als_0_PASSED [2026-01-10T07:22:40.924Z] ----------------------------------- [2026-01-10T07:22:41.284Z] [2026-01-10T07:22:41.285Z] TEST TEARDOWN: [2026-01-10T07:22:41.285Z] Nothing to be done for teardown. [2026-01-10T07:22:41.285Z] renaissance-als_0 Finish Time: Sat Jan 10 07:22:41 2026 Epoch Time (ms): 1768029761045