[2026-01-14T04:18:39.628Z] Running test renaissance-als_0 ... [2026-01-14T04:18:39.628Z] =============================================== [2026-01-14T04:18:39.628Z] renaissance-als_0 Start Time: Wed Jan 14 04:18:39 2026 Epoch Time (ms): 1768364319413 [2026-01-14T04:18:39.628Z] variation: NoOptions [2026-01-14T04:18:39.628Z] JVM_OPTIONS: [2026-01-14T04:18:39.628Z] { \ [2026-01-14T04:18:39.628Z] echo ""; echo "TEST SETUP:"; \ [2026-01-14T04:18:39.628Z] echo "Nothing to be done for setup."; \ [2026-01-14T04:18:39.628Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17683643197900/renaissance-als_0"; \ [2026-01-14T04:18:39.628Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17683643197900/renaissance-als_0"; \ [2026-01-14T04:18:39.628Z] echo ""; echo "TESTING:"; \ [2026-01-14T04:18:39.628Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17683643197900/renaissance-als_0"/als.json" als; \ [2026-01-14T04:18:39.628Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17683643197900/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-14T04:18:39.628Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-14T04:18:39.628Z] echo "Nothing to be done for teardown."; \ [2026-01-14T04:18:39.628Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17683643197900/TestTargetResult"; [2026-01-14T04:18:39.628Z] [2026-01-14T04:18:39.628Z] TEST SETUP: [2026-01-14T04:18:39.628Z] Nothing to be done for setup. [2026-01-14T04:18:39.628Z] [2026-01-14T04:18:39.628Z] TESTING: [2026-01-14T04:18:49.463Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-14T04:18:57.704Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-14T04:18:57.704Z] GC before operation: completed in 99.508 ms, heap usage 101.205 MB -> 34.918 MB. [2026-01-14T04:19:32.907Z] ====== als (apache-spark) [default], iteration 0 completed (33362.642 ms) ====== [2026-01-14T04:19:32.907Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-14T04:19:32.907Z] GC before operation: completed in 190.467 ms, heap usage 265.176 MB -> 61.945 MB. [2026-01-14T04:19:46.604Z] ====== als (apache-spark) [default], iteration 1 completed (13036.393 ms) ====== [2026-01-14T04:19:46.604Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-14T04:19:46.604Z] GC before operation: completed in 136.058 ms, heap usage 130.189 MB -> 62.704 MB. [2026-01-14T04:19:56.422Z] ====== als (apache-spark) [default], iteration 2 completed (10443.462 ms) ====== [2026-01-14T04:19:56.422Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-14T04:19:56.422Z] GC before operation: completed in 137.570 ms, heap usage 806.418 MB -> 64.075 MB. [2026-01-14T04:20:08.071Z] ====== als (apache-spark) [default], iteration 3 completed (10811.665 ms) ====== [2026-01-14T04:20:08.071Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-14T04:20:08.071Z] GC before operation: completed in 180.732 ms, heap usage 317.170 MB -> 64.053 MB. [2026-01-14T04:20:17.892Z] ====== als (apache-spark) [default], iteration 4 completed (9878.383 ms) ====== [2026-01-14T04:20:17.892Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-14T04:20:17.892Z] GC before operation: completed in 199.064 ms, heap usage 754.730 MB -> 65.232 MB. [2026-01-14T04:20:29.525Z] ====== als (apache-spark) [default], iteration 5 completed (9961.669 ms) ====== [2026-01-14T04:20:29.525Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-14T04:20:29.525Z] GC before operation: completed in 173.584 ms, heap usage 399.382 MB -> 65.213 MB. [2026-01-14T04:20:39.421Z] ====== als (apache-spark) [default], iteration 6 completed (9734.471 ms) ====== [2026-01-14T04:20:39.421Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-14T04:20:39.421Z] GC before operation: completed in 164.381 ms, heap usage 755.542 MB -> 66.242 MB. [2026-01-14T04:20:49.248Z] ====== als (apache-spark) [default], iteration 7 completed (10388.201 ms) ====== [2026-01-14T04:20:49.248Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-14T04:20:49.248Z] GC before operation: completed in 130.711 ms, heap usage 751.248 MB -> 66.876 MB. [2026-01-14T04:21:00.880Z] ====== als (apache-spark) [default], iteration 8 completed (10104.957 ms) ====== [2026-01-14T04:21:00.880Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-14T04:21:00.880Z] GC before operation: completed in 178.996 ms, heap usage 360.176 MB -> 66.914 MB. [2026-01-14T04:21:10.696Z] ====== als (apache-spark) [default], iteration 9 completed (10199.225 ms) ====== [2026-01-14T04:21:10.696Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-14T04:21:10.696Z] GC before operation: completed in 146.126 ms, heap usage 166.485 MB -> 66.929 MB. [2026-01-14T04:21:20.505Z] ====== als (apache-spark) [default], iteration 10 completed (9011.228 ms) ====== [2026-01-14T04:21:20.505Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-14T04:21:20.505Z] GC before operation: completed in 192.104 ms, heap usage 391.802 MB -> 67.211 MB. [2026-01-14T04:21:29.270Z] ====== als (apache-spark) [default], iteration 11 completed (9286.839 ms) ====== [2026-01-14T04:21:29.270Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-14T04:21:30.039Z] GC before operation: completed in 165.269 ms, heap usage 217.415 MB -> 67.402 MB. [2026-01-14T04:21:39.842Z] ====== als (apache-spark) [default], iteration 12 completed (9510.848 ms) ====== [2026-01-14T04:21:39.842Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-14T04:21:39.842Z] GC before operation: completed in 152.460 ms, heap usage 504.565 MB -> 68.056 MB. [2026-01-14T04:21:49.812Z] ====== als (apache-spark) [default], iteration 13 completed (9604.141 ms) ====== [2026-01-14T04:21:49.812Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-14T04:21:49.812Z] GC before operation: completed in 227.142 ms, heap usage 395.264 MB -> 68.023 MB. [2026-01-14T04:21:59.649Z] ====== als (apache-spark) [default], iteration 14 completed (9776.437 ms) ====== [2026-01-14T04:21:59.650Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-14T04:22:00.415Z] GC before operation: completed in 228.086 ms, heap usage 268.641 MB -> 68.243 MB. [2026-01-14T04:22:10.306Z] ====== als (apache-spark) [default], iteration 15 completed (9162.539 ms) ====== [2026-01-14T04:22:10.306Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-14T04:22:10.306Z] GC before operation: completed in 188.700 ms, heap usage 153.528 MB -> 68.340 MB. [2026-01-14T04:22:20.120Z] ====== als (apache-spark) [default], iteration 16 completed (9020.060 ms) ====== [2026-01-14T04:22:20.120Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-14T04:22:20.120Z] GC before operation: completed in 160.315 ms, heap usage 593.214 MB -> 69.202 MB. [2026-01-14T04:22:28.861Z] ====== als (apache-spark) [default], iteration 17 completed (8745.954 ms) ====== [2026-01-14T04:22:28.861Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-14T04:22:28.861Z] GC before operation: completed in 166.499 ms, heap usage 150.300 MB -> 69.016 MB. [2026-01-14T04:22:37.090Z] ====== als (apache-spark) [default], iteration 18 completed (8518.279 ms) ====== [2026-01-14T04:22:37.090Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-14T04:22:37.090Z] GC before operation: completed in 138.577 ms, heap usage 254.970 MB -> 69.325 MB. [2026-01-14T04:22:46.998Z] ====== als (apache-spark) [default], iteration 19 completed (9080.439 ms) ====== [2026-01-14T04:22:46.998Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-14T04:22:46.999Z] GC before operation: completed in 175.698 ms, heap usage 221.411 MB -> 69.413 MB. [2026-01-14T04:22:56.826Z] ====== als (apache-spark) [default], iteration 20 completed (8647.736 ms) ====== [2026-01-14T04:22:56.826Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-14T04:22:56.826Z] GC before operation: completed in 152.719 ms, heap usage 619.783 MB -> 70.240 MB. [2026-01-14T04:23:05.055Z] ====== als (apache-spark) [default], iteration 21 completed (8660.251 ms) ====== [2026-01-14T04:23:05.055Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-14T04:23:05.055Z] GC before operation: completed in 154.530 ms, heap usage 516.481 MB -> 70.508 MB. [2026-01-14T04:23:14.864Z] ====== als (apache-spark) [default], iteration 22 completed (8592.106 ms) ====== [2026-01-14T04:23:14.864Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-14T04:23:14.864Z] GC before operation: completed in 172.495 ms, heap usage 485.665 MB -> 70.772 MB. [2026-01-14T04:23:23.619Z] ====== als (apache-spark) [default], iteration 23 completed (8500.715 ms) ====== [2026-01-14T04:23:23.619Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-14T04:23:23.619Z] GC before operation: completed in 164.403 ms, heap usage 766.411 MB -> 71.264 MB. [2026-01-14T04:23:31.914Z] ====== als (apache-spark) [default], iteration 24 completed (9176.014 ms) ====== [2026-01-14T04:23:31.914Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-14T04:23:32.689Z] GC before operation: completed in 155.550 ms, heap usage 158.951 MB -> 70.951 MB. [2026-01-14T04:23:41.054Z] ====== als (apache-spark) [default], iteration 25 completed (8761.342 ms) ====== [2026-01-14T04:23:41.054Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-14T04:23:41.823Z] GC before operation: completed in 165.929 ms, heap usage 447.076 MB -> 71.598 MB. [2026-01-14T04:23:51.712Z] ====== als (apache-spark) [default], iteration 26 completed (9005.768 ms) ====== [2026-01-14T04:23:51.712Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-14T04:23:51.712Z] GC before operation: completed in 148.515 ms, heap usage 517.752 MB -> 71.921 MB. [2026-01-14T04:23:59.965Z] ====== als (apache-spark) [default], iteration 27 completed (8507.741 ms) ====== [2026-01-14T04:23:59.965Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-14T04:23:59.965Z] GC before operation: completed in 194.742 ms, heap usage 261.760 MB -> 71.323 MB. [2026-01-14T04:24:09.773Z] ====== als (apache-spark) [default], iteration 28 completed (8607.933 ms) ====== [2026-01-14T04:24:09.773Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-14T04:24:09.773Z] GC before operation: completed in 219.921 ms, heap usage 672.202 MB -> 72.639 MB. [2026-01-14T04:24:18.510Z] ====== als (apache-spark) [default], iteration 29 completed (8530.298 ms) ====== [2026-01-14T04:24:18.510Z] ----------------------------------- [2026-01-14T04:24:18.510Z] renaissance-als_0_PASSED [2026-01-14T04:24:18.510Z] ----------------------------------- [2026-01-14T04:24:18.510Z] [2026-01-14T04:24:18.510Z] TEST TEARDOWN: [2026-01-14T04:24:18.510Z] Nothing to be done for teardown. [2026-01-14T04:24:18.510Z] renaissance-als_0 Finish Time: Wed Jan 14 04:24:17 2026 Epoch Time (ms): 1768364657928