[2026-01-31T11:08:28.926Z] Running test renaissance-als_0 ... [2026-01-31T11:08:28.926Z] =============================================== [2026-01-31T11:08:28.926Z] renaissance-als_0 Start Time: Sat Jan 31 11:08:28 2026 Epoch Time (ms): 1769857708635 [2026-01-31T11:08:28.926Z] variation: NoOptions [2026-01-31T11:08:28.926Z] JVM_OPTIONS: [2026-01-31T11:08:28.926Z] { \ [2026-01-31T11:08:28.926Z] echo ""; echo "TEST SETUP:"; \ [2026-01-31T11:08:28.926Z] echo "Nothing to be done for setup."; \ [2026-01-31T11:08:28.926Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17698577087232/renaissance-als_0"; \ [2026-01-31T11:08:28.926Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17698577087232/renaissance-als_0"; \ [2026-01-31T11:08:28.926Z] echo ""; echo "TESTING:"; \ [2026-01-31T11:08:28.926Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17698577087232/renaissance-als_0"/als.json" als; \ [2026-01-31T11:08:28.926Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17698577087232/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-31T11:08:28.926Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-31T11:08:28.926Z] echo "Nothing to be done for teardown."; \ [2026-01-31T11:08:28.926Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17698577087232/TestTargetResult"; [2026-01-31T11:08:28.926Z] [2026-01-31T11:08:28.926Z] TEST SETUP: [2026-01-31T11:08:28.926Z] Nothing to be done for setup. [2026-01-31T11:08:28.926Z] [2026-01-31T11:08:28.926Z] TESTING: [2026-01-31T11:08:29.668Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-31T11:08:29.668Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17698577087232/renaissance-als_0/launcher-110828-13998034528410362759/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-31T11:08:29.668Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-31T11:08:29.668Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-31T11:08:37.672Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-31T11:08:44.676Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-31T11:08:44.677Z] GC before operation: completed in 106.157 ms, heap usage 59.000 MB -> 35.067 MB. [2026-01-31T11:09:14.876Z] ====== als (apache-spark) [default], iteration 0 completed (29642.406 ms) ====== [2026-01-31T11:09:14.876Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-31T11:09:15.628Z] GC before operation: completed in 173.760 ms, heap usage 241.188 MB -> 61.777 MB. [2026-01-31T11:09:29.092Z] ====== als (apache-spark) [default], iteration 1 completed (12409.485 ms) ====== [2026-01-31T11:09:29.092Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-31T11:09:29.092Z] GC before operation: completed in 202.594 ms, heap usage 467.386 MB -> 62.916 MB. [2026-01-31T11:09:40.617Z] ====== als (apache-spark) [default], iteration 2 completed (10641.508 ms) ====== [2026-01-31T11:09:40.617Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-31T11:09:40.617Z] GC before operation: completed in 223.683 ms, heap usage 365.258 MB -> 63.357 MB. [2026-01-31T11:09:50.226Z] ====== als (apache-spark) [default], iteration 3 completed (10534.270 ms) ====== [2026-01-31T11:09:50.226Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-31T11:09:50.989Z] GC before operation: completed in 170.040 ms, heap usage 128.386 MB -> 63.662 MB. [2026-01-31T11:10:02.819Z] ====== als (apache-spark) [default], iteration 4 completed (10722.242 ms) ====== [2026-01-31T11:10:02.819Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-31T11:10:02.819Z] GC before operation: completed in 180.671 ms, heap usage 318.702 MB -> 64.484 MB. [2026-01-31T11:10:12.447Z] ====== als (apache-spark) [default], iteration 5 completed (9945.124 ms) ====== [2026-01-31T11:10:12.447Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-31T11:10:12.447Z] GC before operation: completed in 181.020 ms, heap usage 194.755 MB -> 64.805 MB. [2026-01-31T11:10:23.815Z] ====== als (apache-spark) [default], iteration 6 completed (10481.172 ms) ====== [2026-01-31T11:10:23.815Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-31T11:10:23.815Z] GC before operation: completed in 189.204 ms, heap usage 277.321 MB -> 65.455 MB. [2026-01-31T11:10:37.305Z] ====== als (apache-spark) [default], iteration 7 completed (11987.247 ms) ====== [2026-01-31T11:10:37.305Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-31T11:10:37.305Z] GC before operation: completed in 178.152 ms, heap usage 336.484 MB -> 66.122 MB. [2026-01-31T11:10:46.939Z] ====== als (apache-spark) [default], iteration 8 completed (10442.609 ms) ====== [2026-01-31T11:10:46.939Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-31T11:10:46.939Z] GC before operation: completed in 164.492 ms, heap usage 348.763 MB -> 66.661 MB. [2026-01-31T11:10:58.283Z] ====== als (apache-spark) [default], iteration 9 completed (11005.046 ms) ====== [2026-01-31T11:10:58.283Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-31T11:10:58.283Z] GC before operation: completed in 230.987 ms, heap usage 246.304 MB -> 66.756 MB. [2026-01-31T11:11:09.658Z] ====== als (apache-spark) [default], iteration 10 completed (10554.567 ms) ====== [2026-01-31T11:11:09.658Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-31T11:11:09.658Z] GC before operation: completed in 189.304 ms, heap usage 304.668 MB -> 67.056 MB. [2026-01-31T11:11:21.537Z] ====== als (apache-spark) [default], iteration 11 completed (10636.596 ms) ====== [2026-01-31T11:11:21.537Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-31T11:11:21.537Z] GC before operation: completed in 216.967 ms, heap usage 351.613 MB -> 67.380 MB. [2026-01-31T11:11:31.120Z] ====== als (apache-spark) [default], iteration 12 completed (10537.712 ms) ====== [2026-01-31T11:11:31.120Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-31T11:11:31.850Z] GC before operation: completed in 195.817 ms, heap usage 436.258 MB -> 67.793 MB. [2026-01-31T11:11:43.203Z] ====== als (apache-spark) [default], iteration 13 completed (11474.366 ms) ====== [2026-01-31T11:11:43.203Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-31T11:11:43.945Z] GC before operation: completed in 218.206 ms, heap usage 510.141 MB -> 68.094 MB. [2026-01-31T11:11:55.273Z] ====== als (apache-spark) [default], iteration 14 completed (10424.747 ms) ====== [2026-01-31T11:11:55.273Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-31T11:11:55.273Z] GC before operation: completed in 198.527 ms, heap usage 501.573 MB -> 68.401 MB. [2026-01-31T11:12:06.610Z] ====== als (apache-spark) [default], iteration 15 completed (10740.857 ms) ====== [2026-01-31T11:12:06.610Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-31T11:12:06.610Z] GC before operation: completed in 237.764 ms, heap usage 192.347 MB -> 68.227 MB. [2026-01-31T11:12:17.953Z] ====== als (apache-spark) [default], iteration 16 completed (10978.791 ms) ====== [2026-01-31T11:12:17.953Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-31T11:12:17.953Z] GC before operation: completed in 221.973 ms, heap usage 506.240 MB -> 68.958 MB. [2026-01-31T11:12:30.577Z] ====== als (apache-spark) [default], iteration 17 completed (12184.993 ms) ====== [2026-01-31T11:12:30.577Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-31T11:12:30.577Z] GC before operation: completed in 170.048 ms, heap usage 189.010 MB -> 68.768 MB. [2026-01-31T11:12:43.942Z] ====== als (apache-spark) [default], iteration 18 completed (13091.650 ms) ====== [2026-01-31T11:12:43.942Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-31T11:12:43.942Z] GC before operation: completed in 232.671 ms, heap usage 350.780 MB -> 69.277 MB. [2026-01-31T11:12:58.990Z] ====== als (apache-spark) [default], iteration 19 completed (14005.989 ms) ====== [2026-01-31T11:12:58.990Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-31T11:12:58.990Z] GC before operation: completed in 173.726 ms, heap usage 426.889 MB -> 69.752 MB. [2026-01-31T11:13:10.348Z] ====== als (apache-spark) [default], iteration 20 completed (11627.250 ms) ====== [2026-01-31T11:13:10.348Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-31T11:13:10.348Z] GC before operation: completed in 215.061 ms, heap usage 190.572 MB -> 69.602 MB. [2026-01-31T11:13:26.066Z] ====== als (apache-spark) [default], iteration 21 completed (13814.138 ms) ====== [2026-01-31T11:13:26.066Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-31T11:13:26.066Z] GC before operation: completed in 252.306 ms, heap usage 484.420 MB -> 70.256 MB. [2026-01-31T11:13:34.733Z] ====== als (apache-spark) [default], iteration 22 completed (10022.099 ms) ====== [2026-01-31T11:13:34.733Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-31T11:13:34.733Z] GC before operation: completed in 158.304 ms, heap usage 432.275 MB -> 70.608 MB. [2026-01-31T11:13:44.322Z] ====== als (apache-spark) [default], iteration 23 completed (9481.671 ms) ====== [2026-01-31T11:13:44.322Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-31T11:13:45.059Z] GC before operation: completed in 211.123 ms, heap usage 367.576 MB -> 70.586 MB. [2026-01-31T11:13:54.644Z] ====== als (apache-spark) [default], iteration 24 completed (9462.151 ms) ====== [2026-01-31T11:13:54.644Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-31T11:13:54.644Z] GC before operation: completed in 221.736 ms, heap usage 512.561 MB -> 71.032 MB. [2026-01-31T11:14:05.993Z] ====== als (apache-spark) [default], iteration 25 completed (9801.989 ms) ====== [2026-01-31T11:14:05.993Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-31T11:14:05.993Z] GC before operation: completed in 171.769 ms, heap usage 154.469 MB -> 70.868 MB. [2026-01-31T11:14:15.581Z] ====== als (apache-spark) [default], iteration 26 completed (10023.096 ms) ====== [2026-01-31T11:14:15.581Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-31T11:14:15.581Z] GC before operation: completed in 216.690 ms, heap usage 120.856 MB -> 70.712 MB. [2026-01-31T11:14:26.971Z] ====== als (apache-spark) [default], iteration 27 completed (10457.271 ms) ====== [2026-01-31T11:14:26.971Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-31T11:14:26.971Z] GC before operation: completed in 185.181 ms, heap usage 126.242 MB -> 71.461 MB. [2026-01-31T11:14:38.311Z] ====== als (apache-spark) [default], iteration 28 completed (11106.145 ms) ====== [2026-01-31T11:14:38.311Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-31T11:14:38.311Z] GC before operation: completed in 193.869 ms, heap usage 272.272 MB -> 71.368 MB. [2026-01-31T11:14:50.189Z] ====== als (apache-spark) [default], iteration 29 completed (10956.611 ms) ====== [2026-01-31T11:14:50.189Z] ----------------------------------- [2026-01-31T11:14:50.189Z] renaissance-als_0_PASSED [2026-01-31T11:14:50.189Z] ----------------------------------- [2026-01-31T11:14:50.189Z] [2026-01-31T11:14:50.189Z] TEST TEARDOWN: [2026-01-31T11:14:50.189Z] Nothing to be done for teardown. [2026-01-31T11:14:50.189Z] renaissance-als_0 Finish Time: Sat Jan 31 11:14:49 2026 Epoch Time (ms): 1769858089542