[2026-01-18T20:21:20.553Z] Running test renaissance-als_0 ... [2026-01-18T20:21:20.553Z] =============================================== [2026-01-18T20:21:20.553Z] renaissance-als_0 Start Time: Sun Jan 18 20:21:20 2026 Epoch Time (ms): 1768767680539 [2026-01-18T20:21:20.553Z] variation: NoOptions [2026-01-18T20:21:20.553Z] JVM_OPTIONS: [2026-01-18T20:21:20.869Z] { \ [2026-01-18T20:21:20.869Z] echo ""; echo "TEST SETUP:"; \ [2026-01-18T20:21:20.869Z] echo "Nothing to be done for setup."; \ [2026-01-18T20:21:20.869Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17687674797321/renaissance-als_0"; \ [2026-01-18T20:21:20.869Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17687674797321/renaissance-als_0"; \ [2026-01-18T20:21:20.869Z] echo ""; echo "TESTING:"; \ [2026-01-18T20:21:20.869Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17687674797321/renaissance-als_0"/als.json" als; \ [2026-01-18T20:21:20.869Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17687674797321/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-18T20:21:20.869Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-18T20:21:20.869Z] echo "Nothing to be done for teardown."; \ [2026-01-18T20:21:20.869Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17687674797321/TestTargetResult"; [2026-01-18T20:21:20.869Z] [2026-01-18T20:21:20.869Z] TEST SETUP: [2026-01-18T20:21:20.869Z] Nothing to be done for setup. [2026-01-18T20:21:20.869Z] [2026-01-18T20:21:20.869Z] TESTING: [2026-01-18T20:21:25.497Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-18T20:21:30.141Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-18T20:21:30.141Z] GC before operation: completed in 46.757 ms, heap usage 60.967 MB -> 34.707 MB. [2026-01-18T20:21:57.661Z] ====== als (apache-spark) [default], iteration 0 completed (26167.582 ms) ====== [2026-01-18T20:21:57.661Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-18T20:21:57.661Z] GC before operation: completed in 114.206 ms, heap usage 204.867 MB -> 61.464 MB. [2026-01-18T20:22:10.621Z] ====== als (apache-spark) [default], iteration 1 completed (12835.889 ms) ====== [2026-01-18T20:22:10.621Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-18T20:22:10.621Z] GC before operation: completed in 96.410 ms, heap usage 896.971 MB -> 63.089 MB. [2026-01-18T20:22:23.560Z] ====== als (apache-spark) [default], iteration 2 completed (11382.795 ms) ====== [2026-01-18T20:22:23.560Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-18T20:22:23.560Z] GC before operation: completed in 91.577 ms, heap usage 407.650 MB -> 63.223 MB. [2026-01-18T20:22:34.235Z] ====== als (apache-spark) [default], iteration 3 completed (10926.996 ms) ====== [2026-01-18T20:22:34.235Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-18T20:22:34.235Z] GC before operation: completed in 99.787 ms, heap usage 514.607 MB -> 63.889 MB. [2026-01-18T20:22:44.893Z] ====== als (apache-spark) [default], iteration 4 completed (10603.317 ms) ====== [2026-01-18T20:22:44.894Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-18T20:22:44.894Z] GC before operation: completed in 91.994 ms, heap usage 359.718 MB -> 64.158 MB. [2026-01-18T20:22:55.545Z] ====== als (apache-spark) [default], iteration 5 completed (10111.629 ms) ====== [2026-01-18T20:22:55.545Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-18T20:22:55.545Z] GC before operation: completed in 92.675 ms, heap usage 395.961 MB -> 64.708 MB. [2026-01-18T20:23:06.218Z] ====== als (apache-spark) [default], iteration 6 completed (10204.213 ms) ====== [2026-01-18T20:23:06.218Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-18T20:23:06.218Z] GC before operation: completed in 99.828 ms, heap usage 109.787 MB -> 65.070 MB. [2026-01-18T20:23:16.984Z] ====== als (apache-spark) [default], iteration 7 completed (10086.229 ms) ====== [2026-01-18T20:23:16.984Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-18T20:23:16.984Z] GC before operation: completed in 95.426 ms, heap usage 416.604 MB -> 65.762 MB. [2026-01-18T20:23:27.652Z] ====== als (apache-spark) [default], iteration 8 completed (9813.503 ms) ====== [2026-01-18T20:23:27.652Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-18T20:23:27.652Z] GC before operation: completed in 97.670 ms, heap usage 352.037 MB -> 66.345 MB. [2026-01-18T20:23:36.451Z] ====== als (apache-spark) [default], iteration 9 completed (10223.091 ms) ====== [2026-01-18T20:23:36.451Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-18T20:23:36.451Z] GC before operation: completed in 96.792 ms, heap usage 507.531 MB -> 66.878 MB. [2026-01-18T20:23:47.092Z] ====== als (apache-spark) [default], iteration 10 completed (9892.907 ms) ====== [2026-01-18T20:23:47.092Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-18T20:23:47.092Z] GC before operation: completed in 95.654 ms, heap usage 249.168 MB -> 66.789 MB. [2026-01-18T20:23:57.737Z] ====== als (apache-spark) [default], iteration 11 completed (9761.579 ms) ====== [2026-01-18T20:23:57.737Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-18T20:23:57.737Z] GC before operation: completed in 97.671 ms, heap usage 379.409 MB -> 67.161 MB. [2026-01-18T20:24:08.366Z] ====== als (apache-spark) [default], iteration 12 completed (9986.335 ms) ====== [2026-01-18T20:24:08.366Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-18T20:24:08.366Z] GC before operation: completed in 99.076 ms, heap usage 218.422 MB -> 67.069 MB. [2026-01-18T20:24:17.110Z] ====== als (apache-spark) [default], iteration 13 completed (9837.660 ms) ====== [2026-01-18T20:24:17.110Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-18T20:24:17.110Z] GC before operation: completed in 99.856 ms, heap usage 398.852 MB -> 67.650 MB. [2026-01-18T20:24:27.758Z] ====== als (apache-spark) [default], iteration 14 completed (9809.466 ms) ====== [2026-01-18T20:24:27.758Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-18T20:24:27.758Z] GC before operation: completed in 98.392 ms, heap usage 226.033 MB -> 67.743 MB. [2026-01-18T20:24:38.416Z] ====== als (apache-spark) [default], iteration 15 completed (9643.252 ms) ====== [2026-01-18T20:24:38.416Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-18T20:24:38.416Z] GC before operation: completed in 99.826 ms, heap usage 409.323 MB -> 68.150 MB. [2026-01-18T20:24:49.070Z] ====== als (apache-spark) [default], iteration 16 completed (9873.964 ms) ====== [2026-01-18T20:24:49.070Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-18T20:24:49.070Z] GC before operation: completed in 122.044 ms, heap usage 503.858 MB -> 68.587 MB. [2026-01-18T20:24:57.793Z] ====== als (apache-spark) [default], iteration 17 completed (9476.149 ms) ====== [2026-01-18T20:24:57.793Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-18T20:24:57.793Z] GC before operation: completed in 129.030 ms, heap usage 402.522 MB -> 68.646 MB. [2026-01-18T20:25:08.498Z] ====== als (apache-spark) [default], iteration 18 completed (9789.189 ms) ====== [2026-01-18T20:25:08.498Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-18T20:25:08.498Z] GC before operation: completed in 103.093 ms, heap usage 361.019 MB -> 68.871 MB. [2026-01-18T20:25:17.216Z] ====== als (apache-spark) [default], iteration 19 completed (9659.088 ms) ====== [2026-01-18T20:25:17.216Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-18T20:25:17.216Z] GC before operation: completed in 105.515 ms, heap usage 731.620 MB -> 69.664 MB. [2026-01-18T20:25:28.003Z] ====== als (apache-spark) [default], iteration 20 completed (10153.487 ms) ====== [2026-01-18T20:25:28.003Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-18T20:25:28.003Z] GC before operation: completed in 98.576 ms, heap usage 193.403 MB -> 69.269 MB. [2026-01-18T20:25:38.688Z] ====== als (apache-spark) [default], iteration 21 completed (10155.234 ms) ====== [2026-01-18T20:25:38.688Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-18T20:25:38.688Z] GC before operation: completed in 103.700 ms, heap usage 543.942 MB -> 70.034 MB. [2026-01-18T20:25:49.355Z] ====== als (apache-spark) [default], iteration 22 completed (9711.878 ms) ====== [2026-01-18T20:25:49.355Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-18T20:25:49.355Z] GC before operation: completed in 105.687 ms, heap usage 727.060 MB -> 70.546 MB. [2026-01-18T20:25:58.080Z] ====== als (apache-spark) [default], iteration 23 completed (9714.389 ms) ====== [2026-01-18T20:25:58.080Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-18T20:25:58.391Z] GC before operation: completed in 157.826 ms, heap usage 282.997 MB -> 70.082 MB. [2026-01-18T20:26:09.035Z] ====== als (apache-spark) [default], iteration 24 completed (9761.577 ms) ====== [2026-01-18T20:26:09.036Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-18T20:26:09.036Z] GC before operation: completed in 105.163 ms, heap usage 711.389 MB -> 70.966 MB. [2026-01-18T20:26:19.741Z] ====== als (apache-spark) [default], iteration 25 completed (9688.605 ms) ====== [2026-01-18T20:26:19.741Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-18T20:26:19.741Z] GC before operation: completed in 104.351 ms, heap usage 344.528 MB -> 70.799 MB. [2026-01-18T20:26:28.513Z] ====== als (apache-spark) [default], iteration 26 completed (9619.412 ms) ====== [2026-01-18T20:26:28.513Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-18T20:26:28.513Z] GC before operation: completed in 102.906 ms, heap usage 125.048 MB -> 70.768 MB. [2026-01-18T20:26:39.162Z] ====== als (apache-spark) [default], iteration 27 completed (9644.852 ms) ====== [2026-01-18T20:26:39.162Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-18T20:26:39.162Z] GC before operation: completed in 122.950 ms, heap usage 520.016 MB -> 71.414 MB. [2026-01-18T20:26:49.823Z] ====== als (apache-spark) [default], iteration 28 completed (9787.446 ms) ====== [2026-01-18T20:26:49.823Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-18T20:26:49.823Z] GC before operation: completed in 103.021 ms, heap usage 289.438 MB -> 71.432 MB. [2026-01-18T20:26:58.563Z] ====== als (apache-spark) [default], iteration 29 completed (9305.851 ms) ====== [2026-01-18T20:26:58.563Z] ----------------------------------- [2026-01-18T20:26:58.563Z] renaissance-als_0_PASSED [2026-01-18T20:26:58.563Z] ----------------------------------- [2026-01-18T20:26:58.563Z] [2026-01-18T20:26:58.563Z] TEST TEARDOWN: [2026-01-18T20:26:58.564Z] Nothing to be done for teardown. [2026-01-18T20:26:58.564Z] renaissance-als_0 Finish Time: Sun Jan 18 20:26:58 2026 Epoch Time (ms): 1768768018099