[2026-01-21T00:04:42.486Z] Running test renaissance-als_0 ... [2026-01-21T00:04:42.486Z] =============================================== [2026-01-21T00:04:42.486Z] renaissance-als_0 Start Time: Wed Jan 21 00:04:41 2026 Epoch Time (ms): 1768953881851 [2026-01-21T00:04:42.486Z] variation: NoOptions [2026-01-21T00:04:42.486Z] JVM_OPTIONS: [2026-01-21T00:04:42.486Z] { \ [2026-01-21T00:04:42.486Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T00:04:42.486Z] echo "Nothing to be done for setup."; \ [2026-01-21T00:04:42.486Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17689537205497/renaissance-als_0"; \ [2026-01-21T00:04:42.486Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17689537205497/renaissance-als_0"; \ [2026-01-21T00:04:42.486Z] echo ""; echo "TESTING:"; \ [2026-01-21T00:04:42.486Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17689537205497/renaissance-als_0"/als.json" als; \ [2026-01-21T00:04:42.486Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17689537205497/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T00:04:42.486Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T00:04:42.486Z] echo "Nothing to be done for teardown."; \ [2026-01-21T00:04:42.486Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17689537205497/TestTargetResult"; [2026-01-21T00:04:42.486Z] [2026-01-21T00:04:42.486Z] TEST SETUP: [2026-01-21T00:04:42.486Z] Nothing to be done for setup. [2026-01-21T00:04:42.486Z] [2026-01-21T00:04:42.486Z] TESTING: [2026-01-21T00:04:47.937Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-21T00:04:52.153Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T00:04:52.153Z] GC before operation: completed in 47.935 ms, heap usage 55.385 MB -> 34.958 MB. [2026-01-21T00:05:10.660Z] ====== als (apache-spark) [default], iteration 0 completed (17063.153 ms) ====== [2026-01-21T00:05:10.660Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T00:05:10.660Z] GC before operation: completed in 80.185 ms, heap usage 170.804 MB -> 61.897 MB. [2026-01-21T00:05:17.469Z] ====== als (apache-spark) [default], iteration 1 completed (7875.982 ms) ====== [2026-01-21T00:05:17.469Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T00:05:17.469Z] GC before operation: completed in 72.657 ms, heap usage 587.670 MB -> 63.159 MB. [2026-01-21T00:05:25.839Z] ====== als (apache-spark) [default], iteration 2 completed (8182.960 ms) ====== [2026-01-21T00:05:25.839Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T00:05:25.839Z] GC before operation: completed in 72.749 ms, heap usage 360.040 MB -> 63.537 MB. [2026-01-21T00:05:34.114Z] ====== als (apache-spark) [default], iteration 3 completed (7149.136 ms) ====== [2026-01-21T00:05:34.114Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T00:05:34.114Z] GC before operation: completed in 83.489 ms, heap usage 252.740 MB -> 64.015 MB. [2026-01-21T00:05:40.906Z] ====== als (apache-spark) [default], iteration 4 completed (6994.150 ms) ====== [2026-01-21T00:05:40.906Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T00:05:40.906Z] GC before operation: completed in 69.951 ms, heap usage 356.480 MB -> 64.597 MB. [2026-01-21T00:05:47.717Z] ====== als (apache-spark) [default], iteration 5 completed (6927.061 ms) ====== [2026-01-21T00:05:47.717Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T00:05:47.717Z] GC before operation: completed in 105.819 ms, heap usage 643.528 MB -> 65.435 MB. [2026-01-21T00:05:55.772Z] ====== als (apache-spark) [default], iteration 6 completed (6848.441 ms) ====== [2026-01-21T00:05:55.772Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T00:05:55.772Z] GC before operation: completed in 82.556 ms, heap usage 276.778 MB -> 65.681 MB. [2026-01-21T00:06:02.740Z] ====== als (apache-spark) [default], iteration 7 completed (6777.844 ms) ====== [2026-01-21T00:06:02.740Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T00:06:02.740Z] GC before operation: completed in 90.008 ms, heap usage 252.602 MB -> 66.148 MB. [2026-01-21T00:06:09.598Z] ====== als (apache-spark) [default], iteration 8 completed (6890.959 ms) ====== [2026-01-21T00:06:09.598Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T00:06:09.598Z] GC before operation: completed in 84.159 ms, heap usage 125.171 MB -> 66.585 MB. [2026-01-21T00:06:16.392Z] ====== als (apache-spark) [default], iteration 9 completed (6796.789 ms) ====== [2026-01-21T00:06:16.392Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T00:06:16.392Z] GC before operation: completed in 103.805 ms, heap usage 288.719 MB -> 67.069 MB. [2026-01-21T00:06:24.675Z] ====== als (apache-spark) [default], iteration 10 completed (6848.237 ms) ====== [2026-01-21T00:06:24.675Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T00:06:24.675Z] GC before operation: completed in 111.304 ms, heap usage 257.734 MB -> 67.346 MB. [2026-01-21T00:06:31.481Z] ====== als (apache-spark) [default], iteration 11 completed (6787.871 ms) ====== [2026-01-21T00:06:31.481Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T00:06:31.481Z] GC before operation: completed in 102.334 ms, heap usage 456.319 MB -> 67.706 MB. [2026-01-21T00:06:38.289Z] ====== als (apache-spark) [default], iteration 12 completed (6700.187 ms) ====== [2026-01-21T00:06:38.289Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T00:06:38.289Z] GC before operation: completed in 87.717 ms, heap usage 154.992 MB -> 67.724 MB. [2026-01-21T00:06:45.097Z] ====== als (apache-spark) [default], iteration 13 completed (6981.048 ms) ====== [2026-01-21T00:06:45.097Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T00:06:45.097Z] GC before operation: completed in 89.579 ms, heap usage 172.985 MB -> 67.956 MB. [2026-01-21T00:06:52.981Z] ====== als (apache-spark) [default], iteration 14 completed (6859.276 ms) ====== [2026-01-21T00:06:52.981Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T00:06:52.981Z] GC before operation: completed in 91.332 ms, heap usage 150.525 MB -> 68.312 MB. [2026-01-21T00:06:59.798Z] ====== als (apache-spark) [default], iteration 15 completed (6740.190 ms) ====== [2026-01-21T00:06:59.798Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T00:06:59.798Z] GC before operation: completed in 94.619 ms, heap usage 642.820 MB -> 68.956 MB. [2026-01-21T00:07:06.778Z] ====== als (apache-spark) [default], iteration 16 completed (6818.478 ms) ====== [2026-01-21T00:07:06.778Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T00:07:06.778Z] GC before operation: completed in 92.841 ms, heap usage 467.878 MB -> 69.045 MB. [2026-01-21T00:07:13.694Z] ====== als (apache-spark) [default], iteration 17 completed (6678.893 ms) ====== [2026-01-21T00:07:13.694Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T00:07:13.694Z] GC before operation: completed in 81.623 ms, heap usage 261.567 MB -> 69.015 MB. [2026-01-21T00:07:20.576Z] ====== als (apache-spark) [default], iteration 18 completed (6617.703 ms) ====== [2026-01-21T00:07:20.576Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T00:07:20.576Z] GC before operation: completed in 88.329 ms, heap usage 218.231 MB -> 69.247 MB. [2026-01-21T00:07:27.371Z] ====== als (apache-spark) [default], iteration 19 completed (6644.667 ms) ====== [2026-01-21T00:07:27.371Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T00:07:27.371Z] GC before operation: completed in 103.912 ms, heap usage 343.476 MB -> 69.814 MB. [2026-01-21T00:07:34.179Z] ====== als (apache-spark) [default], iteration 20 completed (6915.016 ms) ====== [2026-01-21T00:07:34.179Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T00:07:34.179Z] GC before operation: completed in 87.022 ms, heap usage 262.650 MB -> 69.882 MB. [2026-01-21T00:07:42.443Z] ====== als (apache-spark) [default], iteration 21 completed (6824.855 ms) ====== [2026-01-21T00:07:42.443Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T00:07:42.443Z] GC before operation: completed in 88.546 ms, heap usage 378.367 MB -> 70.190 MB. [2026-01-21T00:07:48.639Z] ====== als (apache-spark) [default], iteration 22 completed (6647.179 ms) ====== [2026-01-21T00:07:48.639Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T00:07:48.639Z] GC before operation: completed in 87.979 ms, heap usage 205.886 MB -> 70.370 MB. [2026-01-21T00:07:55.442Z] ====== als (apache-spark) [default], iteration 23 completed (6609.572 ms) ====== [2026-01-21T00:07:55.442Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T00:07:55.442Z] GC before operation: completed in 84.682 ms, heap usage 147.100 MB -> 70.449 MB. [2026-01-21T00:08:02.371Z] ====== als (apache-spark) [default], iteration 24 completed (6550.497 ms) ====== [2026-01-21T00:08:02.371Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T00:08:02.371Z] GC before operation: completed in 88.687 ms, heap usage 171.761 MB -> 70.878 MB. [2026-01-21T00:08:09.181Z] ====== als (apache-spark) [default], iteration 25 completed (6464.846 ms) ====== [2026-01-21T00:08:09.181Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T00:08:09.181Z] GC before operation: completed in 86.701 ms, heap usage 399.464 MB -> 71.382 MB. [2026-01-21T00:08:16.015Z] ====== als (apache-spark) [default], iteration 26 completed (6568.705 ms) ====== [2026-01-21T00:08:16.015Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T00:08:16.015Z] GC before operation: completed in 87.120 ms, heap usage 190.168 MB -> 71.454 MB. [2026-01-21T00:08:22.814Z] ====== als (apache-spark) [default], iteration 27 completed (6575.819 ms) ====== [2026-01-21T00:08:22.814Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T00:08:22.814Z] GC before operation: completed in 83.956 ms, heap usage 169.814 MB -> 71.548 MB. [2026-01-21T00:08:29.611Z] ====== als (apache-spark) [default], iteration 28 completed (6652.202 ms) ====== [2026-01-21T00:08:29.611Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T00:08:29.611Z] GC before operation: completed in 83.695 ms, heap usage 261.010 MB -> 71.575 MB. [2026-01-21T00:08:36.435Z] ====== als (apache-spark) [default], iteration 29 completed (6523.651 ms) ====== [2026-01-21T00:08:36.435Z] ----------------------------------- [2026-01-21T00:08:36.435Z] renaissance-als_0_PASSED [2026-01-21T00:08:36.435Z] ----------------------------------- [2026-01-21T00:08:36.435Z] [2026-01-21T00:08:36.435Z] TEST TEARDOWN: [2026-01-21T00:08:36.435Z] Nothing to be done for teardown. [2026-01-21T00:08:36.435Z] renaissance-als_0 Finish Time: Wed Jan 21 00:08:36 2026 Epoch Time (ms): 1768954116163