[2026-04-23T05:49:58.327Z] Running test renaissance-als_0 ... [2026-04-23T05:49:58.327Z] =============================================== [2026-04-23T05:49:58.327Z] renaissance-als_0 Start Time: Thu Apr 23 05:49:58 2026 Epoch Time (ms): 1776923398187 [2026-04-23T05:49:58.327Z] variation: NoOptions [2026-04-23T05:49:58.327Z] JVM_OPTIONS: [2026-04-23T05:49:58.327Z] { \ [2026-04-23T05:49:58.327Z] echo ""; echo "TEST SETUP:"; \ [2026-04-23T05:49:58.327Z] echo "Nothing to be done for setup."; \ [2026-04-23T05:49:58.327Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17769233979432/renaissance-als_0"; \ [2026-04-23T05:49:58.327Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17769233979432/renaissance-als_0"; \ [2026-04-23T05:49:58.327Z] echo ""; echo "TESTING:"; \ [2026-04-23T05:49:58.327Z] "/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_17769233979432/renaissance-als_0"/als.json" als; \ [2026-04-23T05:49:58.327Z] 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_17769233979432/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-23T05:49:58.327Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-23T05:49:58.327Z] echo "Nothing to be done for teardown."; \ [2026-04-23T05:49:58.327Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17769233979432/TestTargetResult"; [2026-04-23T05:49:58.327Z] [2026-04-23T05:49:58.327Z] TEST SETUP: [2026-04-23T05:49:58.327Z] Nothing to be done for setup. [2026-04-23T05:49:58.327Z] [2026-04-23T05:49:58.327Z] TESTING: [2026-04-23T05:49:59.098Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-04-23T05:49:59.098Z] 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_17769233979432/renaissance-als_0/launcher-054958-14774971841775005152/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-04-23T05:49:59.098Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-04-23T05:49:59.098Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-04-23T05:50:03.581Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-23T05:50:07.013Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-23T05:50:07.013Z] GC before operation: completed in 42.476 ms, heap usage 275.893 MB -> 35.179 MB. [2026-04-23T05:50:29.137Z] ====== als (apache-spark) [default], iteration 0 completed (19859.115 ms) ====== [2026-04-23T05:50:29.137Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-23T05:50:29.137Z] GC before operation: completed in 81.156 ms, heap usage 183.174 MB -> 61.591 MB. [2026-04-23T05:50:36.564Z] ====== als (apache-spark) [default], iteration 1 completed (7775.608 ms) ====== [2026-04-23T05:50:36.564Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-23T05:50:36.564Z] GC before operation: completed in 82.571 ms, heap usage 523.134 MB -> 62.823 MB. [2026-04-23T05:50:43.469Z] ====== als (apache-spark) [default], iteration 2 completed (7089.713 ms) ====== [2026-04-23T05:50:43.469Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-23T05:50:43.469Z] GC before operation: completed in 84.910 ms, heap usage 287.046 MB -> 63.066 MB. [2026-04-23T05:50:50.360Z] ====== als (apache-spark) [default], iteration 3 completed (7147.576 ms) ====== [2026-04-23T05:50:50.360Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-23T05:50:50.360Z] GC before operation: completed in 86.354 ms, heap usage 519.033 MB -> 64.133 MB. [2026-04-23T05:50:57.269Z] ====== als (apache-spark) [default], iteration 4 completed (6809.443 ms) ====== [2026-04-23T05:50:57.269Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-23T05:50:57.269Z] GC before operation: completed in 84.401 ms, heap usage 345.683 MB -> 64.347 MB. [2026-04-23T05:51:04.174Z] ====== als (apache-spark) [default], iteration 5 completed (6610.845 ms) ====== [2026-04-23T05:51:04.174Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-23T05:51:04.174Z] GC before operation: completed in 87.288 ms, heap usage 402.869 MB -> 64.919 MB. [2026-04-23T05:51:11.078Z] ====== als (apache-spark) [default], iteration 6 completed (6682.770 ms) ====== [2026-04-23T05:51:11.078Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-23T05:51:11.078Z] GC before operation: completed in 84.914 ms, heap usage 458.996 MB -> 65.608 MB. [2026-04-23T05:51:17.974Z] ====== als (apache-spark) [default], iteration 7 completed (6702.275 ms) ====== [2026-04-23T05:51:17.974Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-23T05:51:18.755Z] GC before operation: completed in 85.068 ms, heap usage 128.953 MB -> 65.802 MB. [2026-04-23T05:51:25.661Z] ====== als (apache-spark) [default], iteration 8 completed (6749.508 ms) ====== [2026-04-23T05:51:25.661Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-23T05:51:25.661Z] GC before operation: completed in 88.796 ms, heap usage 286.005 MB -> 66.484 MB. [2026-04-23T05:51:33.100Z] ====== als (apache-spark) [default], iteration 9 completed (6713.400 ms) ====== [2026-04-23T05:51:33.100Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-23T05:51:33.100Z] GC before operation: completed in 91.553 ms, heap usage 282.443 MB -> 66.616 MB. [2026-04-23T05:51:38.728Z] ====== als (apache-spark) [default], iteration 10 completed (6565.095 ms) ====== [2026-04-23T05:51:38.728Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-23T05:51:39.501Z] GC before operation: completed in 92.614 ms, heap usage 352.000 MB -> 67.072 MB. [2026-04-23T05:51:46.400Z] ====== als (apache-spark) [default], iteration 11 completed (6576.903 ms) ====== [2026-04-23T05:51:46.400Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-23T05:51:46.400Z] GC before operation: completed in 87.066 ms, heap usage 190.485 MB -> 67.074 MB. [2026-04-23T05:51:53.296Z] ====== als (apache-spark) [default], iteration 12 completed (6627.450 ms) ====== [2026-04-23T05:51:53.296Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-23T05:51:53.296Z] GC before operation: completed in 85.215 ms, heap usage 189.379 MB -> 67.369 MB. [2026-04-23T05:52:00.240Z] ====== als (apache-spark) [default], iteration 13 completed (6566.112 ms) ====== [2026-04-23T05:52:00.240Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-23T05:52:00.240Z] GC before operation: completed in 92.224 ms, heap usage 304.223 MB -> 67.847 MB. [2026-04-23T05:52:07.144Z] ====== als (apache-spark) [default], iteration 14 completed (6426.174 ms) ====== [2026-04-23T05:52:07.144Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-23T05:52:07.144Z] GC before operation: completed in 93.243 ms, heap usage 468.208 MB -> 68.353 MB. [2026-04-23T05:52:14.045Z] ====== als (apache-spark) [default], iteration 15 completed (6641.386 ms) ====== [2026-04-23T05:52:14.045Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-23T05:52:14.045Z] GC before operation: completed in 92.607 ms, heap usage 168.153 MB -> 68.141 MB. [2026-04-23T05:52:19.681Z] ====== als (apache-spark) [default], iteration 16 completed (6578.271 ms) ====== [2026-04-23T05:52:19.681Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-23T05:52:20.461Z] GC before operation: completed in 90.465 ms, heap usage 286.492 MB -> 68.504 MB. [2026-04-23T05:52:27.643Z] ====== als (apache-spark) [default], iteration 17 completed (6620.059 ms) ====== [2026-04-23T05:52:27.643Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-23T05:52:27.643Z] GC before operation: completed in 95.287 ms, heap usage 471.566 MB -> 69.110 MB. [2026-04-23T05:52:34.557Z] ====== als (apache-spark) [default], iteration 18 completed (6637.231 ms) ====== [2026-04-23T05:52:34.557Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-23T05:52:34.557Z] GC before operation: completed in 94.593 ms, heap usage 488.887 MB -> 69.363 MB. [2026-04-23T05:52:41.453Z] ====== als (apache-spark) [default], iteration 19 completed (6680.999 ms) ====== [2026-04-23T05:52:41.453Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-23T05:52:41.453Z] GC before operation: completed in 93.769 ms, heap usage 118.729 MB -> 69.166 MB. [2026-04-23T05:52:48.355Z] ====== als (apache-spark) [default], iteration 20 completed (6880.068 ms) ====== [2026-04-23T05:52:48.355Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-23T05:52:48.355Z] GC before operation: completed in 102.064 ms, heap usage 464.166 MB -> 69.884 MB. [2026-04-23T05:52:55.261Z] ====== als (apache-spark) [default], iteration 21 completed (7099.278 ms) ====== [2026-04-23T05:52:55.261Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-23T05:52:55.261Z] GC before operation: completed in 95.516 ms, heap usage 212.349 MB -> 69.719 MB. [2026-04-23T05:53:02.173Z] ====== als (apache-spark) [default], iteration 22 completed (6437.658 ms) ====== [2026-04-23T05:53:02.173Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-23T05:53:02.173Z] GC before operation: completed in 97.542 ms, heap usage 504.456 MB -> 70.480 MB. [2026-04-23T05:53:09.070Z] ====== als (apache-spark) [default], iteration 23 completed (6299.292 ms) ====== [2026-04-23T05:53:09.070Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-23T05:53:09.070Z] GC before operation: completed in 95.822 ms, heap usage 153.221 MB -> 70.283 MB. [2026-04-23T05:53:16.501Z] ====== als (apache-spark) [default], iteration 24 completed (6612.568 ms) ====== [2026-04-23T05:53:16.501Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-23T05:53:16.501Z] GC before operation: completed in 104.212 ms, heap usage 279.520 MB -> 70.695 MB. [2026-04-23T05:53:22.139Z] ====== als (apache-spark) [default], iteration 25 completed (6402.278 ms) ====== [2026-04-23T05:53:22.139Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-23T05:53:22.139Z] GC before operation: completed in 98.378 ms, heap usage 360.402 MB -> 71.001 MB. [2026-04-23T05:53:29.045Z] ====== als (apache-spark) [default], iteration 26 completed (6472.925 ms) ====== [2026-04-23T05:53:29.045Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-23T05:53:29.045Z] GC before operation: completed in 101.977 ms, heap usage 462.763 MB -> 71.485 MB. [2026-04-23T05:53:35.957Z] ====== als (apache-spark) [default], iteration 27 completed (6424.801 ms) ====== [2026-04-23T05:53:35.957Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-23T05:53:35.957Z] GC before operation: completed in 96.264 ms, heap usage 477.138 MB -> 71.783 MB. [2026-04-23T05:53:41.584Z] ====== als (apache-spark) [default], iteration 28 completed (6383.920 ms) ====== [2026-04-23T05:53:41.584Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-23T05:53:41.584Z] GC before operation: completed in 108.727 ms, heap usage 508.272 MB -> 71.607 MB. [2026-04-23T05:53:48.477Z] ====== als (apache-spark) [default], iteration 29 completed (6405.710 ms) ====== [2026-04-23T05:53:48.477Z] ----------------------------------- [2026-04-23T05:53:48.477Z] renaissance-als_0_PASSED [2026-04-23T05:53:48.477Z] ----------------------------------- [2026-04-23T05:53:48.477Z] [2026-04-23T05:53:48.477Z] TEST TEARDOWN: [2026-04-23T05:53:48.477Z] Nothing to be done for teardown. [2026-04-23T05:53:48.477Z] renaissance-als_0 Finish Time: Thu Apr 23 05:53:48 2026 Epoch Time (ms): 1776923628192