[2026-01-14T12:28:33.263Z] Running test renaissance-als_0 ... [2026-01-14T12:28:33.263Z] =============================================== [2026-01-14T12:28:33.263Z] renaissance-als_0 Start Time: Wed Jan 14 12:28:33 2026 Epoch Time (ms): 1768393713206 [2026-01-14T12:28:33.263Z] variation: NoOptions [2026-01-14T12:28:33.263Z] JVM_OPTIONS: [2026-01-14T12:28:33.263Z] { \ [2026-01-14T12:28:33.263Z] echo ""; echo "TEST SETUP:"; \ [2026-01-14T12:28:33.263Z] echo "Nothing to be done for setup."; \ [2026-01-14T12:28:33.263Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17683935318914/renaissance-als_0"; \ [2026-01-14T12:28:33.263Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17683935318914/renaissance-als_0"; \ [2026-01-14T12:28:33.263Z] echo ""; echo "TESTING:"; \ [2026-01-14T12:28:33.263Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17683935318914/renaissance-als_0"/als.json" als; \ [2026-01-14T12:28:33.263Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17683935318914/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-14T12:28:33.263Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-14T12:28:33.263Z] echo "Nothing to be done for teardown."; \ [2026-01-14T12:28:33.263Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17683935318914/TestTargetResult"; [2026-01-14T12:28:33.263Z] [2026-01-14T12:28:33.263Z] TEST SETUP: [2026-01-14T12:28:33.263Z] Nothing to be done for setup. [2026-01-14T12:28:33.263Z] [2026-01-14T12:28:33.263Z] TESTING: [2026-01-14T12:28:34.004Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-14T12:28:34.004Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17683935318914/renaissance-als_0/launcher-122833-12654940239424726146/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-14T12:28:34.004Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-14T12:28:34.004Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-14T12:28:42.744Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-14T12:28:46.431Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-14T12:28:46.431Z] GC before operation: completed in 43.592 ms, heap usage 47.516 MB -> 34.904 MB. [2026-01-14T12:29:13.960Z] ====== als (apache-spark) [default], iteration 0 completed (25968.688 ms) ====== [2026-01-14T12:29:13.960Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-14T12:29:13.960Z] GC before operation: completed in 100.460 ms, heap usage 193.943 MB -> 61.238 MB. [2026-01-14T12:29:26.996Z] ====== als (apache-spark) [default], iteration 1 completed (12164.555 ms) ====== [2026-01-14T12:29:26.996Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-14T12:29:26.996Z] GC before operation: completed in 88.864 ms, heap usage 208.758 MB -> 62.235 MB. [2026-01-14T12:29:37.693Z] ====== als (apache-spark) [default], iteration 2 completed (11060.987 ms) ====== [2026-01-14T12:29:37.693Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-14T12:29:37.693Z] GC before operation: completed in 90.867 ms, heap usage 445.505 MB -> 63.053 MB. [2026-01-14T12:29:48.364Z] ====== als (apache-spark) [default], iteration 3 completed (11380.818 ms) ====== [2026-01-14T12:29:48.364Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-14T12:29:48.364Z] GC before operation: completed in 92.293 ms, heap usage 320.734 MB -> 63.471 MB. [2026-01-14T12:29:59.028Z] ====== als (apache-spark) [default], iteration 4 completed (10318.483 ms) ====== [2026-01-14T12:29:59.028Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-14T12:29:59.028Z] GC before operation: completed in 86.621 ms, heap usage 239.534 MB -> 63.853 MB. [2026-01-14T12:30:09.723Z] ====== als (apache-spark) [default], iteration 5 completed (10148.652 ms) ====== [2026-01-14T12:30:09.723Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-14T12:30:09.723Z] GC before operation: completed in 108.696 ms, heap usage 189.223 MB -> 64.340 MB. [2026-01-14T12:30:20.390Z] ====== als (apache-spark) [default], iteration 6 completed (9880.933 ms) ====== [2026-01-14T12:30:20.390Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-14T12:30:20.390Z] GC before operation: completed in 97.109 ms, heap usage 190.894 MB -> 64.861 MB. [2026-01-14T12:30:31.094Z] ====== als (apache-spark) [default], iteration 7 completed (10838.003 ms) ====== [2026-01-14T12:30:31.094Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-14T12:30:31.094Z] GC before operation: completed in 95.351 ms, heap usage 327.431 MB -> 65.607 MB. [2026-01-14T12:30:41.784Z] ====== als (apache-spark) [default], iteration 8 completed (10034.399 ms) ====== [2026-01-14T12:30:41.784Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-14T12:30:41.784Z] GC before operation: completed in 106.729 ms, heap usage 220.334 MB -> 66.098 MB. [2026-01-14T12:30:52.422Z] ====== als (apache-spark) [default], iteration 9 completed (9506.430 ms) ====== [2026-01-14T12:30:52.422Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-14T12:30:52.422Z] GC before operation: completed in 107.557 ms, heap usage 223.841 MB -> 66.412 MB. [2026-01-14T12:31:01.193Z] ====== als (apache-spark) [default], iteration 10 completed (9824.409 ms) ====== [2026-01-14T12:31:01.193Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-14T12:31:01.505Z] GC before operation: completed in 106.152 ms, heap usage 190.117 MB -> 66.486 MB. [2026-01-14T12:31:12.143Z] ====== als (apache-spark) [default], iteration 11 completed (9958.207 ms) ====== [2026-01-14T12:31:12.143Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-14T12:31:12.143Z] GC before operation: completed in 100.183 ms, heap usage 461.180 MB -> 67.222 MB. [2026-01-14T12:31:22.838Z] ====== als (apache-spark) [default], iteration 12 completed (9777.834 ms) ====== [2026-01-14T12:31:22.838Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-14T12:31:22.838Z] GC before operation: completed in 104.321 ms, heap usage 232.963 MB -> 67.108 MB. [2026-01-14T12:31:31.556Z] ====== als (apache-spark) [default], iteration 13 completed (9749.026 ms) ====== [2026-01-14T12:31:31.556Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-14T12:31:31.872Z] GC before operation: completed in 118.621 ms, heap usage 311.147 MB -> 67.587 MB. [2026-01-14T12:31:42.511Z] ====== als (apache-spark) [default], iteration 14 completed (9707.442 ms) ====== [2026-01-14T12:31:42.511Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-14T12:31:42.511Z] GC before operation: completed in 101.357 ms, heap usage 440.636 MB -> 68.085 MB. [2026-01-14T12:31:53.173Z] ====== als (apache-spark) [default], iteration 15 completed (9614.602 ms) ====== [2026-01-14T12:31:53.173Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-14T12:31:53.173Z] GC before operation: completed in 162.309 ms, heap usage 199.819 MB -> 67.748 MB. [2026-01-14T12:32:01.902Z] ====== als (apache-spark) [default], iteration 16 completed (9697.293 ms) ====== [2026-01-14T12:32:01.902Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-14T12:32:01.902Z] GC before operation: completed in 108.555 ms, heap usage 498.377 MB -> 68.536 MB. [2026-01-14T12:32:12.591Z] ====== als (apache-spark) [default], iteration 17 completed (9642.766 ms) ====== [2026-01-14T12:32:12.591Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-14T12:32:12.591Z] GC before operation: completed in 106.598 ms, heap usage 198.765 MB -> 68.280 MB. [2026-01-14T12:32:23.256Z] ====== als (apache-spark) [default], iteration 18 completed (9625.095 ms) ====== [2026-01-14T12:32:23.256Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-14T12:32:23.256Z] GC before operation: completed in 103.186 ms, heap usage 196.568 MB -> 68.617 MB. [2026-01-14T12:32:31.988Z] ====== als (apache-spark) [default], iteration 19 completed (9751.207 ms) ====== [2026-01-14T12:32:31.989Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-14T12:32:31.989Z] GC before operation: completed in 107.901 ms, heap usage 385.668 MB -> 69.043 MB. [2026-01-14T12:32:42.633Z] ====== als (apache-spark) [default], iteration 20 completed (9960.611 ms) ====== [2026-01-14T12:32:42.633Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-14T12:32:42.633Z] GC before operation: completed in 107.916 ms, heap usage 431.412 MB -> 69.382 MB. [2026-01-14T12:32:53.316Z] ====== als (apache-spark) [default], iteration 21 completed (10108.376 ms) ====== [2026-01-14T12:32:53.316Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-14T12:32:53.316Z] GC before operation: completed in 111.789 ms, heap usage 490.621 MB -> 69.674 MB. [2026-01-14T12:33:02.060Z] ====== als (apache-spark) [default], iteration 22 completed (9539.495 ms) ====== [2026-01-14T12:33:02.060Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-14T12:33:02.379Z] GC before operation: completed in 120.501 ms, heap usage 193.000 MB -> 69.573 MB. [2026-01-14T12:33:13.135Z] ====== als (apache-spark) [default], iteration 23 completed (9783.381 ms) ====== [2026-01-14T12:33:13.135Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-14T12:33:13.135Z] GC before operation: completed in 105.150 ms, heap usage 244.035 MB -> 69.925 MB. [2026-01-14T12:33:23.787Z] ====== als (apache-spark) [default], iteration 24 completed (9816.052 ms) ====== [2026-01-14T12:33:23.787Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-14T12:33:23.787Z] GC before operation: completed in 111.147 ms, heap usage 430.051 MB -> 70.503 MB. [2026-01-14T12:33:32.517Z] ====== als (apache-spark) [default], iteration 25 completed (9677.348 ms) ====== [2026-01-14T12:33:32.517Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-14T12:33:32.517Z] GC before operation: completed in 112.509 ms, heap usage 289.071 MB -> 70.507 MB. [2026-01-14T12:33:43.162Z] ====== als (apache-spark) [default], iteration 26 completed (9775.917 ms) ====== [2026-01-14T12:33:43.162Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-14T12:33:43.162Z] GC before operation: completed in 182.095 ms, heap usage 447.776 MB -> 71.081 MB. [2026-01-14T12:33:53.800Z] ====== als (apache-spark) [default], iteration 27 completed (9566.054 ms) ====== [2026-01-14T12:33:53.800Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-14T12:33:53.800Z] GC before operation: completed in 107.348 ms, heap usage 504.222 MB -> 71.336 MB. [2026-01-14T12:34:02.604Z] ====== als (apache-spark) [default], iteration 28 completed (9648.919 ms) ====== [2026-01-14T12:34:02.604Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-14T12:34:02.604Z] GC before operation: completed in 125.180 ms, heap usage 239.176 MB -> 71.285 MB. [2026-01-14T12:34:13.240Z] ====== als (apache-spark) [default], iteration 29 completed (9641.236 ms) ====== [2026-01-14T12:34:13.240Z] ----------------------------------- [2026-01-14T12:34:13.240Z] renaissance-als_0_PASSED [2026-01-14T12:34:13.240Z] ----------------------------------- [2026-01-14T12:34:13.240Z] [2026-01-14T12:34:13.240Z] TEST TEARDOWN: [2026-01-14T12:34:13.240Z] Nothing to be done for teardown. [2026-01-14T12:34:13.240Z] renaissance-als_0 Finish Time: Wed Jan 14 12:34:12 2026 Epoch Time (ms): 1768394052607