[2026-04-22T05:26:22.600Z] Running test renaissance-als_0 ... [2026-04-22T05:26:22.937Z] =============================================== [2026-04-22T05:26:22.937Z] renaissance-als_0 Start Time: Wed Apr 22 05:26:22 2026 Epoch Time (ms): 1776835582717 [2026-04-22T05:26:22.937Z] variation: NoOptions [2026-04-22T05:26:23.296Z] JVM_OPTIONS: [2026-04-22T05:26:23.296Z] { \ [2026-04-22T05:26:23.296Z] echo ""; echo "TEST SETUP:"; \ [2026-04-22T05:26:23.296Z] echo "Nothing to be done for setup."; \ [2026-04-22T05:26:23.296Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17768353759296\\renaissance-als_0"; \ [2026-04-22T05:26:23.296Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17768353759296\\renaissance-als_0"; \ [2026-04-22T05:26:23.296Z] echo ""; echo "TESTING:"; \ [2026-04-22T05:26:23.296Z] "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_17768353759296\\renaissance-als_0"\\als.json" als; \ [2026-04-22T05:26:23.296Z] 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_17768353759296\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-22T05:26:23.296Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-22T05:26:23.296Z] echo "Nothing to be done for teardown."; \ [2026-04-22T05:26:23.296Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17768353759296\\TestTargetResult"; [2026-04-22T05:26:23.296Z] [2026-04-22T05:26:23.296Z] TEST SETUP: [2026-04-22T05:26:23.296Z] Nothing to be done for setup. [2026-04-22T05:26:23.296Z] [2026-04-22T05:26:23.296Z] TESTING: [2026-04-22T05:26:36.546Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-22T05:26:39.646Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-22T05:26:39.646Z] GC before operation: completed in 30.783 ms, heap usage 60.011 MB -> 35.328 MB. [2026-04-22T05:27:08.329Z] ====== als (apache-spark) [default], iteration 0 completed (27534.661 ms) ====== [2026-04-22T05:27:08.329Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-22T05:27:08.329Z] GC before operation: completed in 74.938 ms, heap usage 152.043 MB -> 62.305 MB. [2026-04-22T05:27:24.567Z] ====== als (apache-spark) [default], iteration 1 completed (15779.167 ms) ====== [2026-04-22T05:27:24.567Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-22T05:27:24.567Z] GC before operation: completed in 71.287 ms, heap usage 270.562 MB -> 63.299 MB. [2026-04-22T05:27:40.888Z] ====== als (apache-spark) [default], iteration 2 completed (15021.882 ms) ====== [2026-04-22T05:27:40.888Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-22T05:27:40.888Z] GC before operation: completed in 73.909 ms, heap usage 366.240 MB -> 64.118 MB. [2026-04-22T05:27:54.319Z] ====== als (apache-spark) [default], iteration 3 completed (14873.859 ms) ====== [2026-04-22T05:27:54.319Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-22T05:27:54.319Z] GC before operation: completed in 81.476 ms, heap usage 263.252 MB -> 64.561 MB. [2026-04-22T05:28:10.594Z] ====== als (apache-spark) [default], iteration 4 completed (15577.260 ms) ====== [2026-04-22T05:28:10.594Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-22T05:28:10.594Z] GC before operation: completed in 77.429 ms, heap usage 494.898 MB -> 65.408 MB. [2026-04-22T05:28:26.876Z] ====== als (apache-spark) [default], iteration 5 completed (14911.957 ms) ====== [2026-04-22T05:28:26.877Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-22T05:28:26.877Z] GC before operation: completed in 78.074 ms, heap usage 494.661 MB -> 65.879 MB. [2026-04-22T05:28:43.039Z] ====== als (apache-spark) [default], iteration 6 completed (15412.042 ms) ====== [2026-04-22T05:28:43.039Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-22T05:28:43.039Z] GC before operation: completed in 78.563 ms, heap usage 187.468 MB -> 66.188 MB. [2026-04-22T05:28:56.372Z] ====== als (apache-spark) [default], iteration 7 completed (14386.978 ms) ====== [2026-04-22T05:28:56.372Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-22T05:28:56.372Z] GC before operation: completed in 85.995 ms, heap usage 164.700 MB -> 66.709 MB. [2026-04-22T05:29:12.540Z] ====== als (apache-spark) [default], iteration 8 completed (14600.237 ms) ====== [2026-04-22T05:29:12.540Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-22T05:29:12.540Z] GC before operation: completed in 83.467 ms, heap usage 250.804 MB -> 67.326 MB. [2026-04-22T05:29:25.940Z] ====== als (apache-spark) [default], iteration 9 completed (15147.575 ms) ====== [2026-04-22T05:29:25.940Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-22T05:29:25.940Z] GC before operation: completed in 82.477 ms, heap usage 496.858 MB -> 68.258 MB. [2026-04-22T05:29:42.131Z] ====== als (apache-spark) [default], iteration 10 completed (14459.720 ms) ====== [2026-04-22T05:29:42.131Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-22T05:29:42.131Z] GC before operation: completed in 85.603 ms, heap usage 195.951 MB -> 67.660 MB. [2026-04-22T05:29:58.431Z] ====== als (apache-spark) [default], iteration 11 completed (15197.077 ms) ====== [2026-04-22T05:29:58.431Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-22T05:29:58.431Z] GC before operation: completed in 82.715 ms, heap usage 266.757 MB -> 67.839 MB. [2026-04-22T05:30:12.006Z] ====== als (apache-spark) [default], iteration 12 completed (14720.717 ms) ====== [2026-04-22T05:30:12.006Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-22T05:30:12.006Z] GC before operation: completed in 89.385 ms, heap usage 368.427 MB -> 68.320 MB. [2026-04-22T05:30:28.194Z] ====== als (apache-spark) [default], iteration 13 completed (14983.479 ms) ====== [2026-04-22T05:30:28.194Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-22T05:30:28.194Z] GC before operation: completed in 81.108 ms, heap usage 309.683 MB -> 68.630 MB. [2026-04-22T05:30:41.630Z] ====== als (apache-spark) [default], iteration 14 completed (14350.079 ms) ====== [2026-04-22T05:30:41.630Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-22T05:30:41.630Z] GC before operation: completed in 81.577 ms, heap usage 469.227 MB -> 69.073 MB. [2026-04-22T05:30:57.740Z] ====== als (apache-spark) [default], iteration 15 completed (14852.165 ms) ====== [2026-04-22T05:30:57.740Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-22T05:30:57.740Z] GC before operation: completed in 82.031 ms, heap usage 219.429 MB -> 69.129 MB. [2026-04-22T05:31:11.115Z] ====== als (apache-spark) [default], iteration 16 completed (14565.581 ms) ====== [2026-04-22T05:31:11.115Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-22T05:31:11.115Z] GC before operation: completed in 83.851 ms, heap usage 358.914 MB -> 69.502 MB. [2026-04-22T05:31:27.214Z] ====== als (apache-spark) [default], iteration 17 completed (14573.192 ms) ====== [2026-04-22T05:31:27.214Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-22T05:31:27.214Z] GC before operation: completed in 82.787 ms, heap usage 399.441 MB -> 69.809 MB. [2026-04-22T05:31:40.660Z] ====== als (apache-spark) [default], iteration 18 completed (14720.696 ms) ====== [2026-04-22T05:31:40.660Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-22T05:31:40.660Z] GC before operation: completed in 86.660 ms, heap usage 367.705 MB -> 69.887 MB. [2026-04-22T05:31:56.847Z] ====== als (apache-spark) [default], iteration 19 completed (14884.898 ms) ====== [2026-04-22T05:31:56.848Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-22T05:31:56.848Z] GC before operation: completed in 84.927 ms, heap usage 504.488 MB -> 70.446 MB. [2026-04-22T05:32:13.298Z] ====== als (apache-spark) [default], iteration 20 completed (15176.078 ms) ====== [2026-04-22T05:32:13.298Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-22T05:32:13.298Z] GC before operation: completed in 85.428 ms, heap usage 466.552 MB -> 70.618 MB. [2026-04-22T05:32:26.796Z] ====== als (apache-spark) [default], iteration 21 completed (14639.703 ms) ====== [2026-04-22T05:32:26.796Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-22T05:32:26.796Z] GC before operation: completed in 82.228 ms, heap usage 344.013 MB -> 70.754 MB. [2026-04-22T05:32:44.123Z] ====== als (apache-spark) [default], iteration 22 completed (16038.932 ms) ====== [2026-04-22T05:32:44.123Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-22T05:32:44.123Z] GC before operation: completed in 88.697 ms, heap usage 336.342 MB -> 71.037 MB. [2026-04-22T05:33:00.314Z] ====== als (apache-spark) [default], iteration 23 completed (16040.467 ms) ====== [2026-04-22T05:33:00.314Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-22T05:33:00.314Z] GC before operation: completed in 89.838 ms, heap usage 447.462 MB -> 71.440 MB. [2026-04-22T05:33:13.647Z] ====== als (apache-spark) [default], iteration 24 completed (14680.384 ms) ====== [2026-04-22T05:33:13.647Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-22T05:33:13.647Z] GC before operation: completed in 85.833 ms, heap usage 197.581 MB -> 71.350 MB. [2026-04-22T05:33:29.929Z] ====== als (apache-spark) [default], iteration 25 completed (14920.211 ms) ====== [2026-04-22T05:33:29.929Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-22T05:33:29.929Z] GC before operation: completed in 111.667 ms, heap usage 464.847 MB -> 72.015 MB. [2026-04-22T05:33:43.509Z] ====== als (apache-spark) [default], iteration 26 completed (14779.119 ms) ====== [2026-04-22T05:33:43.509Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-22T05:33:43.509Z] GC before operation: completed in 88.702 ms, heap usage 318.976 MB -> 72.097 MB. [2026-04-22T05:33:59.797Z] ====== als (apache-spark) [default], iteration 27 completed (14846.374 ms) ====== [2026-04-22T05:33:59.797Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-22T05:33:59.797Z] GC before operation: completed in 91.491 ms, heap usage 326.739 MB -> 72.324 MB. [2026-04-22T05:34:13.179Z] ====== als (apache-spark) [default], iteration 28 completed (13909.107 ms) ====== [2026-04-22T05:34:13.179Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-22T05:34:13.179Z] GC before operation: completed in 92.700 ms, heap usage 417.688 MB -> 72.253 MB. [2026-04-22T05:34:29.217Z] ====== als (apache-spark) [default], iteration 29 completed (14105.021 ms) ====== [2026-04-22T05:34:29.217Z] ----------------------------------- [2026-04-22T05:34:29.217Z] renaissance-als_0_PASSED [2026-04-22T05:34:29.217Z] ----------------------------------- [2026-04-22T05:34:29.217Z] [2026-04-22T05:34:29.217Z] TEST TEARDOWN: [2026-04-22T05:34:29.217Z] Nothing to be done for teardown. [2026-04-22T05:34:29.217Z] renaissance-als_0 Finish Time: Wed Apr 22 05:34:27 2026 Epoch Time (ms): 1776836067652