[2026-01-03T13:08:56.475Z] Running test renaissance-als_0 ... [2026-01-03T13:08:56.475Z] =============================================== [2026-01-03T13:08:56.475Z] renaissance-als_0 Start Time: Sat Jan 3 13:08:56 2026 Epoch Time (ms): 1767445736202 [2026-01-03T13:08:56.475Z] variation: NoOptions [2026-01-03T13:08:56.475Z] JVM_OPTIONS: [2026-01-03T13:08:56.475Z] { \ [2026-01-03T13:08:56.475Z] echo ""; echo "TEST SETUP:"; \ [2026-01-03T13:08:56.475Z] echo "Nothing to be done for setup."; \ [2026-01-03T13:08:56.475Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17674455583897/renaissance-als_0"; \ [2026-01-03T13:08:56.475Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17674455583897/renaissance-als_0"; \ [2026-01-03T13:08:56.475Z] echo ""; echo "TESTING:"; \ [2026-01-03T13:08:56.475Z] "/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_17674455583897/renaissance-als_0"/als.json" als; \ [2026-01-03T13:08:56.475Z] 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_17674455583897/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-03T13:08:56.475Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-03T13:08:56.475Z] echo "Nothing to be done for teardown."; \ [2026-01-03T13:08:56.475Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17674455583897/TestTargetResult"; [2026-01-03T13:08:56.475Z] [2026-01-03T13:08:56.475Z] TEST SETUP: [2026-01-03T13:08:56.475Z] Nothing to be done for setup. [2026-01-03T13:08:56.475Z] [2026-01-03T13:08:56.475Z] TESTING: [2026-01-03T13:08:56.835Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-03T13:08:56.835Z] 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_17674455583897/renaissance-als_0/launcher-130856-12179626668761216020/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-03T13:08:56.835Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-03T13:08:56.835Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-03T13:09:04.331Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-03T13:09:08.255Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-03T13:09:08.616Z] GC before operation: completed in 50.579 ms, heap usage 154.025 MB -> 34.892 MB. [2026-01-03T13:09:36.729Z] ====== als (apache-spark) [default], iteration 0 completed (24365.558 ms) ====== [2026-01-03T13:09:36.729Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-03T13:09:36.729Z] GC before operation: completed in 102.018 ms, heap usage 152.999 MB -> 61.333 MB. [2026-01-03T13:09:47.731Z] ====== als (apache-spark) [default], iteration 1 completed (11878.851 ms) ====== [2026-01-03T13:09:47.732Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-03T13:09:47.732Z] GC before operation: completed in 91.832 ms, heap usage 453.186 MB -> 62.532 MB. [2026-01-03T13:09:58.657Z] ====== als (apache-spark) [default], iteration 2 completed (10498.505 ms) ====== [2026-01-03T13:09:58.657Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-03T13:09:58.657Z] GC before operation: completed in 91.206 ms, heap usage 224.131 MB -> 62.834 MB. [2026-01-03T13:10:09.577Z] ====== als (apache-spark) [default], iteration 3 completed (10548.893 ms) ====== [2026-01-03T13:10:09.577Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-03T13:10:09.577Z] GC before operation: completed in 88.525 ms, heap usage 315.447 MB -> 63.496 MB. [2026-01-03T13:10:18.568Z] ====== als (apache-spark) [default], iteration 4 completed (10073.386 ms) ====== [2026-01-03T13:10:18.568Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-03T13:10:18.568Z] GC before operation: completed in 86.782 ms, heap usage 355.248 MB -> 64.089 MB. [2026-01-03T13:10:29.554Z] ====== als (apache-spark) [default], iteration 5 completed (9938.691 ms) ====== [2026-01-03T13:10:29.554Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-03T13:10:29.554Z] GC before operation: completed in 99.063 ms, heap usage 203.380 MB -> 64.409 MB. [2026-01-03T13:10:38.680Z] ====== als (apache-spark) [default], iteration 6 completed (9785.738 ms) ====== [2026-01-03T13:10:38.680Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-03T13:10:38.680Z] GC before operation: completed in 127.736 ms, heap usage 140.780 MB -> 65.046 MB. [2026-01-03T13:10:49.606Z] ====== als (apache-spark) [default], iteration 7 completed (10545.337 ms) ====== [2026-01-03T13:10:49.606Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-03T13:10:49.606Z] GC before operation: completed in 97.705 ms, heap usage 424.207 MB -> 66.012 MB. [2026-01-03T13:11:00.621Z] ====== als (apache-spark) [default], iteration 8 completed (9962.512 ms) ====== [2026-01-03T13:11:00.621Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-03T13:11:00.621Z] GC before operation: completed in 99.141 ms, heap usage 265.029 MB -> 66.230 MB. [2026-01-03T13:11:09.830Z] ====== als (apache-spark) [default], iteration 9 completed (9797.709 ms) ====== [2026-01-03T13:11:09.830Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-03T13:11:09.830Z] GC before operation: completed in 101.767 ms, heap usage 102.225 MB -> 66.596 MB. [2026-01-03T13:11:21.344Z] ====== als (apache-spark) [default], iteration 10 completed (9907.117 ms) ====== [2026-01-03T13:11:21.344Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-03T13:11:21.344Z] GC before operation: completed in 97.488 ms, heap usage 201.304 MB -> 66.435 MB. [2026-01-03T13:11:30.405Z] ====== als (apache-spark) [default], iteration 11 completed (9548.798 ms) ====== [2026-01-03T13:11:30.405Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-03T13:11:30.405Z] GC before operation: completed in 101.626 ms, heap usage 500.335 MB -> 67.251 MB. [2026-01-03T13:11:41.383Z] ====== als (apache-spark) [default], iteration 12 completed (9529.781 ms) ====== [2026-01-03T13:11:41.383Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-03T13:11:41.383Z] GC before operation: completed in 99.565 ms, heap usage 144.437 MB -> 66.978 MB. [2026-01-03T13:11:50.463Z] ====== als (apache-spark) [default], iteration 13 completed (9618.058 ms) ====== [2026-01-03T13:11:50.463Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-03T13:11:50.463Z] GC before operation: completed in 101.505 ms, heap usage 110.556 MB -> 67.257 MB. [2026-01-03T13:11:59.461Z] ====== als (apache-spark) [default], iteration 14 completed (9611.372 ms) ====== [2026-01-03T13:11:59.461Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-03T13:11:59.792Z] GC before operation: completed in 99.247 ms, heap usage 385.085 MB -> 67.670 MB. [2026-01-03T13:12:10.776Z] ====== als (apache-spark) [default], iteration 15 completed (9612.851 ms) ====== [2026-01-03T13:12:10.776Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-03T13:12:10.776Z] GC before operation: completed in 101.817 ms, heap usage 79.168 MB -> 67.708 MB. [2026-01-03T13:12:19.859Z] ====== als (apache-spark) [default], iteration 16 completed (9526.816 ms) ====== [2026-01-03T13:12:19.859Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-03T13:12:19.859Z] GC before operation: completed in 170.723 ms, heap usage 171.495 MB -> 67.979 MB. [2026-01-03T13:12:30.782Z] ====== als (apache-spark) [default], iteration 17 completed (9640.675 ms) ====== [2026-01-03T13:12:30.782Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-03T13:12:30.782Z] GC before operation: completed in 96.325 ms, heap usage 110.446 MB -> 68.272 MB. [2026-01-03T13:12:39.725Z] ====== als (apache-spark) [default], iteration 18 completed (9592.424 ms) ====== [2026-01-03T13:12:39.725Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-03T13:12:39.725Z] GC before operation: completed in 105.819 ms, heap usage 202.178 MB -> 68.519 MB. [2026-01-03T13:12:50.588Z] ====== als (apache-spark) [default], iteration 19 completed (9545.913 ms) ====== [2026-01-03T13:12:50.588Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-03T13:12:50.588Z] GC before operation: completed in 126.253 ms, heap usage 430.316 MB -> 69.240 MB. [2026-01-03T13:12:59.600Z] ====== als (apache-spark) [default], iteration 20 completed (9954.731 ms) ====== [2026-01-03T13:12:59.600Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-03T13:12:59.600Z] GC before operation: completed in 106.786 ms, heap usage 133.161 MB -> 69.127 MB. [2026-01-03T13:13:10.623Z] ====== als (apache-spark) [default], iteration 21 completed (9792.170 ms) ====== [2026-01-03T13:13:10.623Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-03T13:13:10.623Z] GC before operation: completed in 108.077 ms, heap usage 105.087 MB -> 69.340 MB. [2026-01-03T13:13:19.598Z] ====== als (apache-spark) [default], iteration 22 completed (9512.188 ms) ====== [2026-01-03T13:13:19.598Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-03T13:13:19.598Z] GC before operation: completed in 109.057 ms, heap usage 464.759 MB -> 70.035 MB. [2026-01-03T13:13:30.503Z] ====== als (apache-spark) [default], iteration 23 completed (9556.758 ms) ====== [2026-01-03T13:13:30.503Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-03T13:13:30.503Z] GC before operation: completed in 104.162 ms, heap usage 280.564 MB -> 69.937 MB. [2026-01-03T13:13:39.531Z] ====== als (apache-spark) [default], iteration 24 completed (9685.675 ms) ====== [2026-01-03T13:13:39.531Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-03T13:13:39.531Z] GC before operation: completed in 100.022 ms, heap usage 285.238 MB -> 70.214 MB. [2026-01-03T13:13:50.446Z] ====== als (apache-spark) [default], iteration 25 completed (9480.729 ms) ====== [2026-01-03T13:13:50.446Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-03T13:13:50.446Z] GC before operation: completed in 103.492 ms, heap usage 209.440 MB -> 70.366 MB. [2026-01-03T13:13:59.483Z] ====== als (apache-spark) [default], iteration 26 completed (9599.281 ms) ====== [2026-01-03T13:13:59.483Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-03T13:13:59.483Z] GC before operation: completed in 106.887 ms, heap usage 201.539 MB -> 70.622 MB. [2026-01-03T13:14:08.662Z] ====== als (apache-spark) [default], iteration 27 completed (9852.746 ms) ====== [2026-01-03T13:14:08.662Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-03T13:14:09.006Z] GC before operation: completed in 108.665 ms, heap usage 196.514 MB -> 70.949 MB. [2026-01-03T13:14:19.941Z] ====== als (apache-spark) [default], iteration 28 completed (9951.274 ms) ====== [2026-01-03T13:14:19.941Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-03T13:14:19.941Z] GC before operation: completed in 106.180 ms, heap usage 148.257 MB -> 70.698 MB. [2026-01-03T13:14:28.831Z] ====== als (apache-spark) [default], iteration 29 completed (9516.797 ms) ====== [2026-01-03T13:14:29.162Z] ----------------------------------- [2026-01-03T13:14:29.162Z] renaissance-als_0_PASSED [2026-01-03T13:14:29.162Z] ----------------------------------- [2026-01-03T13:14:29.162Z] [2026-01-03T13:14:29.162Z] TEST TEARDOWN: [2026-01-03T13:14:29.162Z] Nothing to be done for teardown. [2026-01-03T13:14:29.162Z] renaissance-als_0 Finish Time: Sat Jan 3 13:14:29 2026 Epoch Time (ms): 1767446069090