[2026-01-24T14:03:53.937Z] Running test renaissance-als_0 ... [2026-01-24T14:03:53.937Z] =============================================== [2026-01-24T14:03:53.937Z] renaissance-als_0 Start Time: Sat Jan 24 14:03:53 2026 Epoch Time (ms): 1769263433842 [2026-01-24T14:03:53.937Z] variation: NoOptions [2026-01-24T14:03:53.937Z] JVM_OPTIONS: [2026-01-24T14:03:53.937Z] { \ [2026-01-24T14:03:53.937Z] echo ""; echo "TEST SETUP:"; \ [2026-01-24T14:03:53.937Z] echo "Nothing to be done for setup."; \ [2026-01-24T14:03:53.937Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692632386405/renaissance-als_0"; \ [2026-01-24T14:03:53.937Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692632386405/renaissance-als_0"; \ [2026-01-24T14:03:53.937Z] echo ""; echo "TESTING:"; \ [2026-01-24T14:03:53.937Z] "/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_17692632386405/renaissance-als_0"/als.json" als; \ [2026-01-24T14:03:53.937Z] 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_17692632386405/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-24T14:03:53.937Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-24T14:03:53.937Z] echo "Nothing to be done for teardown."; \ [2026-01-24T14:03:53.937Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692632386405/TestTargetResult"; [2026-01-24T14:03:53.937Z] [2026-01-24T14:03:53.937Z] TEST SETUP: [2026-01-24T14:03:53.937Z] Nothing to be done for setup. [2026-01-24T14:03:53.937Z] [2026-01-24T14:03:53.937Z] TESTING: [2026-01-24T14:03:54.671Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-24T14:03:54.671Z] 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_17692632386405/renaissance-als_0/launcher-140353-15325186012234116284/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-24T14:03:54.671Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-24T14:03:54.671Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-24T14:04:00.705Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-24T14:04:04.562Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-24T14:04:04.562Z] GC before operation: completed in 63.422 ms, heap usage 163.500 MB -> 34.882 MB. [2026-01-24T14:04:38.248Z] ====== als (apache-spark) [default], iteration 0 completed (28051.222 ms) ====== [2026-01-24T14:04:38.248Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-24T14:04:38.248Z] GC before operation: completed in 115.767 ms, heap usage 270.444 MB -> 61.417 MB. [2026-01-24T14:04:49.274Z] ====== als (apache-spark) [default], iteration 1 completed (13719.983 ms) ====== [2026-01-24T14:04:49.274Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-24T14:04:49.274Z] GC before operation: completed in 110.303 ms, heap usage 311.225 MB -> 62.413 MB. [2026-01-24T14:05:02.745Z] ====== als (apache-spark) [default], iteration 2 completed (13030.691 ms) ====== [2026-01-24T14:05:02.745Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-24T14:05:02.745Z] GC before operation: completed in 112.284 ms, heap usage 417.724 MB -> 63.100 MB. [2026-01-24T14:05:16.061Z] ====== als (apache-spark) [default], iteration 3 completed (12645.027 ms) ====== [2026-01-24T14:05:16.061Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-24T14:05:16.061Z] GC before operation: completed in 115.238 ms, heap usage 480.344 MB -> 63.694 MB. [2026-01-24T14:05:27.034Z] ====== als (apache-spark) [default], iteration 4 completed (12594.841 ms) ====== [2026-01-24T14:05:27.034Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-24T14:05:27.034Z] GC before operation: completed in 117.084 ms, heap usage 482.034 MB -> 64.286 MB. [2026-01-24T14:05:40.332Z] ====== als (apache-spark) [default], iteration 5 completed (11818.105 ms) ====== [2026-01-24T14:05:40.332Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-24T14:05:40.332Z] GC before operation: completed in 116.315 ms, heap usage 430.995 MB -> 64.784 MB. [2026-01-24T14:05:51.378Z] ====== als (apache-spark) [default], iteration 6 completed (11961.791 ms) ====== [2026-01-24T14:05:51.378Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-24T14:05:51.378Z] GC before operation: completed in 118.244 ms, heap usage 355.379 MB -> 65.207 MB. [2026-01-24T14:06:04.706Z] ====== als (apache-spark) [default], iteration 7 completed (12094.381 ms) ====== [2026-01-24T14:06:04.706Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-24T14:06:04.706Z] GC before operation: completed in 114.939 ms, heap usage 323.908 MB -> 65.736 MB. [2026-01-24T14:06:15.734Z] ====== als (apache-spark) [default], iteration 8 completed (11606.702 ms) ====== [2026-01-24T14:06:15.734Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-24T14:06:16.088Z] GC before operation: completed in 126.022 ms, heap usage 493.281 MB -> 66.465 MB. [2026-01-24T14:06:29.432Z] ====== als (apache-spark) [default], iteration 9 completed (11679.630 ms) ====== [2026-01-24T14:06:29.432Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-24T14:06:29.432Z] GC before operation: completed in 122.497 ms, heap usage 297.696 MB -> 66.443 MB. [2026-01-24T14:06:40.674Z] ====== als (apache-spark) [default], iteration 10 completed (11595.418 ms) ====== [2026-01-24T14:06:40.674Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-24T14:06:40.674Z] GC before operation: completed in 125.684 ms, heap usage 292.561 MB -> 66.699 MB. [2026-01-24T14:06:53.925Z] ====== als (apache-spark) [default], iteration 11 completed (11711.936 ms) ====== [2026-01-24T14:06:53.925Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-24T14:06:53.925Z] GC before operation: completed in 122.504 ms, heap usage 428.961 MB -> 67.227 MB. [2026-01-24T14:07:04.929Z] ====== als (apache-spark) [default], iteration 12 completed (11439.210 ms) ====== [2026-01-24T14:07:04.929Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-24T14:07:04.929Z] GC before operation: completed in 122.659 ms, heap usage 175.901 MB -> 66.939 MB. [2026-01-24T14:07:15.877Z] ====== als (apache-spark) [default], iteration 13 completed (11578.631 ms) ====== [2026-01-24T14:07:15.877Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-24T14:07:15.877Z] GC before operation: completed in 122.046 ms, heap usage 209.685 MB -> 67.243 MB. [2026-01-24T14:07:29.215Z] ====== als (apache-spark) [default], iteration 14 completed (11598.627 ms) ====== [2026-01-24T14:07:29.215Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-24T14:07:29.215Z] GC before operation: completed in 123.717 ms, heap usage 505.156 MB -> 67.995 MB. [2026-01-24T14:07:40.318Z] ====== als (apache-spark) [default], iteration 15 completed (11455.384 ms) ====== [2026-01-24T14:07:40.318Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-24T14:07:40.318Z] GC before operation: completed in 173.134 ms, heap usage 106.426 MB -> 67.788 MB. [2026-01-24T14:07:51.276Z] ====== als (apache-spark) [default], iteration 16 completed (11378.950 ms) ====== [2026-01-24T14:07:51.276Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-24T14:07:51.276Z] GC before operation: completed in 127.235 ms, heap usage 317.435 MB -> 68.276 MB. [2026-01-24T14:08:04.544Z] ====== als (apache-spark) [default], iteration 17 completed (11416.457 ms) ====== [2026-01-24T14:08:04.544Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-24T14:08:04.544Z] GC before operation: completed in 127.672 ms, heap usage 478.064 MB -> 68.844 MB. [2026-01-24T14:08:15.568Z] ====== als (apache-spark) [default], iteration 18 completed (11435.791 ms) ====== [2026-01-24T14:08:15.568Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-24T14:08:15.568Z] GC before operation: completed in 120.937 ms, heap usage 182.780 MB -> 68.599 MB. [2026-01-24T14:08:26.692Z] ====== als (apache-spark) [default], iteration 19 completed (11371.317 ms) ====== [2026-01-24T14:08:26.692Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-24T14:08:26.692Z] GC before operation: completed in 121.090 ms, heap usage 287.468 MB -> 68.945 MB. [2026-01-24T14:08:40.026Z] ====== als (apache-spark) [default], iteration 20 completed (12029.136 ms) ====== [2026-01-24T14:08:40.026Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-24T14:08:40.026Z] GC before operation: completed in 123.111 ms, heap usage 210.651 MB -> 69.072 MB. [2026-01-24T14:08:51.044Z] ====== als (apache-spark) [default], iteration 21 completed (11828.542 ms) ====== [2026-01-24T14:08:51.044Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-24T14:08:51.381Z] GC before operation: completed in 123.300 ms, heap usage 190.032 MB -> 69.308 MB. [2026-01-24T14:09:04.705Z] ====== als (apache-spark) [default], iteration 22 completed (11349.700 ms) ====== [2026-01-24T14:09:04.705Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-24T14:09:04.705Z] GC before operation: completed in 143.969 ms, heap usage 524.115 MB -> 70.153 MB. [2026-01-24T14:09:15.731Z] ====== als (apache-spark) [default], iteration 23 completed (11267.967 ms) ====== [2026-01-24T14:09:15.731Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-24T14:09:15.731Z] GC before operation: completed in 130.826 ms, heap usage 512.324 MB -> 70.331 MB. [2026-01-24T14:09:26.744Z] ====== als (apache-spark) [default], iteration 24 completed (11619.946 ms) ====== [2026-01-24T14:09:26.744Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-24T14:09:26.744Z] GC before operation: completed in 125.822 ms, heap usage 236.181 MB -> 70.246 MB. [2026-01-24T14:09:40.038Z] ====== als (apache-spark) [default], iteration 25 completed (11315.449 ms) ====== [2026-01-24T14:09:40.038Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-24T14:09:40.038Z] GC before operation: completed in 120.926 ms, heap usage 297.279 MB -> 70.676 MB. [2026-01-24T14:09:51.038Z] ====== als (apache-spark) [default], iteration 26 completed (11376.459 ms) ====== [2026-01-24T14:09:51.038Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-24T14:09:51.038Z] GC before operation: completed in 123.938 ms, heap usage 392.107 MB -> 70.866 MB. [2026-01-24T14:10:02.080Z] ====== als (apache-spark) [default], iteration 27 completed (11179.682 ms) ====== [2026-01-24T14:10:02.080Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-24T14:10:02.080Z] GC before operation: completed in 123.325 ms, heap usage 214.904 MB -> 70.839 MB. [2026-01-24T14:10:13.078Z] ====== als (apache-spark) [default], iteration 28 completed (11218.336 ms) ====== [2026-01-24T14:10:13.078Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-24T14:10:13.078Z] GC before operation: completed in 122.160 ms, heap usage 119.709 MB -> 70.622 MB. [2026-01-24T14:10:26.451Z] ====== als (apache-spark) [default], iteration 29 completed (11065.479 ms) ====== [2026-01-24T14:10:26.451Z] ----------------------------------- [2026-01-24T14:10:26.451Z] renaissance-als_0_PASSED [2026-01-24T14:10:26.451Z] ----------------------------------- [2026-01-24T14:10:26.451Z] [2026-01-24T14:10:26.451Z] TEST TEARDOWN: [2026-01-24T14:10:26.451Z] Nothing to be done for teardown. [2026-01-24T14:10:26.451Z] renaissance-als_0 Finish Time: Sat Jan 24 14:10:24 2026 Epoch Time (ms): 1769263824505