[2026-04-14T00:51:09.096Z] Running test renaissance-als_0 ... [2026-04-14T00:51:09.096Z] =============================================== [2026-04-14T00:51:09.096Z] renaissance-als_0 Start Time: Tue Apr 14 00:51:06 2026 Epoch Time (ms): 1776127866337 [2026-04-14T00:51:09.096Z] variation: NoOptions [2026-04-14T00:51:09.096Z] JVM_OPTIONS: [2026-04-14T00:51:09.096Z] { \ [2026-04-14T00:51:09.096Z] echo ""; echo "TEST SETUP:"; \ [2026-04-14T00:51:09.096Z] echo "Nothing to be done for setup."; \ [2026-04-14T00:51:09.096Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17761276673272/renaissance-als_0"; \ [2026-04-14T00:51:09.096Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17761276673272/renaissance-als_0"; \ [2026-04-14T00:51:09.096Z] echo ""; echo "TESTING:"; \ [2026-04-14T00:51:09.096Z] "/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_17761276673272/renaissance-als_0"/als.json" als; \ [2026-04-14T00:51:09.096Z] 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_17761276673272/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-14T00:51:09.096Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-14T00:51:09.096Z] echo "Nothing to be done for teardown."; \ [2026-04-14T00:51:09.096Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17761276673272/TestTargetResult"; [2026-04-14T00:51:09.096Z] [2026-04-14T00:51:09.096Z] TEST SETUP: [2026-04-14T00:51:09.096Z] Nothing to be done for setup. [2026-04-14T00:51:09.096Z] [2026-04-14T00:51:09.096Z] TESTING: [2026-04-14T00:51:09.096Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-04-14T00:51:09.096Z] 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_17761276673272/renaissance-als_0/launcher-005106-2523142273507386749/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-04-14T00:51:09.096Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-04-14T00:51:09.096Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-04-14T00:51:15.071Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-14T00:51:18.705Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-14T00:51:18.705Z] GC before operation: completed in 52.038 ms, heap usage 55.500 MB -> 34.912 MB. [2026-04-14T00:51:52.680Z] ====== als (apache-spark) [default], iteration 0 completed (28009.396 ms) ====== [2026-04-14T00:51:52.680Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-14T00:51:52.680Z] GC before operation: completed in 131.174 ms, heap usage 99.142 MB -> 61.174 MB. [2026-04-14T00:52:04.008Z] ====== als (apache-spark) [default], iteration 1 completed (14304.573 ms) ====== [2026-04-14T00:52:04.008Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-14T00:52:04.008Z] GC before operation: completed in 108.918 ms, heap usage 241.039 MB -> 62.240 MB. [2026-04-14T00:52:15.679Z] ====== als (apache-spark) [default], iteration 2 completed (13030.543 ms) ====== [2026-04-14T00:52:15.679Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-14T00:52:16.098Z] GC before operation: completed in 109.448 ms, heap usage 304.340 MB -> 62.918 MB. [2026-04-14T00:52:30.016Z] ====== als (apache-spark) [default], iteration 3 completed (12677.181 ms) ====== [2026-04-14T00:52:30.016Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-14T00:52:30.016Z] GC before operation: completed in 117.428 ms, heap usage 94.386 MB -> 63.816 MB. [2026-04-14T00:52:43.580Z] ====== als (apache-spark) [default], iteration 4 completed (12309.693 ms) ====== [2026-04-14T00:52:43.580Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-14T00:52:43.580Z] GC before operation: completed in 109.784 ms, heap usage 235.096 MB -> 63.984 MB. [2026-04-14T00:52:54.664Z] ====== als (apache-spark) [default], iteration 5 completed (12294.500 ms) ====== [2026-04-14T00:52:54.664Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-14T00:52:54.664Z] GC before operation: completed in 113.949 ms, heap usage 445.861 MB -> 64.871 MB. [2026-04-14T00:53:08.296Z] ====== als (apache-spark) [default], iteration 6 completed (11876.895 ms) ====== [2026-04-14T00:53:08.296Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-14T00:53:08.296Z] GC before operation: completed in 113.950 ms, heap usage 216.827 MB -> 65.044 MB. [2026-04-14T00:53:20.240Z] ====== als (apache-spark) [default], iteration 7 completed (12197.427 ms) ====== [2026-04-14T00:53:20.240Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-14T00:53:20.240Z] GC before operation: completed in 113.272 ms, heap usage 256.025 MB -> 65.670 MB. [2026-04-14T00:53:33.781Z] ====== als (apache-spark) [default], iteration 8 completed (11865.034 ms) ====== [2026-04-14T00:53:33.781Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-14T00:53:33.781Z] GC before operation: completed in 122.236 ms, heap usage 288.998 MB -> 66.169 MB. [2026-04-14T00:53:44.991Z] ====== als (apache-spark) [default], iteration 9 completed (12326.981 ms) ====== [2026-04-14T00:53:44.991Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-14T00:53:44.991Z] GC before operation: completed in 119.433 ms, heap usage 164.341 MB -> 66.246 MB. [2026-04-14T00:53:58.602Z] ====== als (apache-spark) [default], iteration 10 completed (12035.787 ms) ====== [2026-04-14T00:53:58.602Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-14T00:53:58.602Z] GC before operation: completed in 119.558 ms, heap usage 203.650 MB -> 66.469 MB. [2026-04-14T00:54:09.967Z] ====== als (apache-spark) [default], iteration 11 completed (11809.019 ms) ====== [2026-04-14T00:54:09.967Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-14T00:54:09.967Z] GC before operation: completed in 118.644 ms, heap usage 185.798 MB -> 66.761 MB. [2026-04-14T00:54:21.209Z] ====== als (apache-spark) [default], iteration 12 completed (11708.914 ms) ====== [2026-04-14T00:54:21.209Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-14T00:54:21.209Z] GC before operation: completed in 121.084 ms, heap usage 121.728 MB -> 67.031 MB. [2026-04-14T00:54:34.720Z] ====== als (apache-spark) [default], iteration 13 completed (11717.938 ms) ====== [2026-04-14T00:54:34.720Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-14T00:54:34.720Z] GC before operation: completed in 122.329 ms, heap usage 452.213 MB -> 67.862 MB. [2026-04-14T00:54:45.888Z] ====== als (apache-spark) [default], iteration 14 completed (11554.376 ms) ====== [2026-04-14T00:54:45.888Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-14T00:54:45.888Z] GC before operation: completed in 123.548 ms, heap usage 286.070 MB -> 67.596 MB. [2026-04-14T00:54:56.986Z] ====== als (apache-spark) [default], iteration 15 completed (11555.293 ms) ====== [2026-04-14T00:54:56.986Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-14T00:54:57.343Z] GC before operation: completed in 199.920 ms, heap usage 437.124 MB -> 68.232 MB. [2026-04-14T00:55:10.661Z] ====== als (apache-spark) [default], iteration 16 completed (11692.455 ms) ====== [2026-04-14T00:55:10.661Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-14T00:55:10.661Z] GC before operation: completed in 123.339 ms, heap usage 388.166 MB -> 68.330 MB. [2026-04-14T00:55:21.647Z] ====== als (apache-spark) [default], iteration 17 completed (11674.869 ms) ====== [2026-04-14T00:55:21.647Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-14T00:55:21.647Z] GC before operation: completed in 121.353 ms, heap usage 128.472 MB -> 68.329 MB. [2026-04-14T00:55:35.037Z] ====== als (apache-spark) [default], iteration 18 completed (11906.864 ms) ====== [2026-04-14T00:55:35.037Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-14T00:55:35.037Z] GC before operation: completed in 123.976 ms, heap usage 262.447 MB -> 68.760 MB. [2026-04-14T00:55:46.386Z] ====== als (apache-spark) [default], iteration 19 completed (11636.065 ms) ====== [2026-04-14T00:55:46.386Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-14T00:55:46.386Z] GC before operation: completed in 127.404 ms, heap usage 447.223 MB -> 69.396 MB. [2026-04-14T00:55:59.807Z] ====== als (apache-spark) [default], iteration 20 completed (12225.324 ms) ====== [2026-04-14T00:55:59.807Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-14T00:55:59.807Z] GC before operation: completed in 132.208 ms, heap usage 362.503 MB -> 69.293 MB. [2026-04-14T00:56:11.100Z] ====== als (apache-spark) [default], iteration 21 completed (11996.769 ms) ====== [2026-04-14T00:56:11.100Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-14T00:56:11.100Z] GC before operation: completed in 130.748 ms, heap usage 505.111 MB -> 69.831 MB. [2026-04-14T00:56:22.321Z] ====== als (apache-spark) [default], iteration 22 completed (11764.783 ms) ====== [2026-04-14T00:56:22.321Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-14T00:56:22.321Z] GC before operation: completed in 136.213 ms, heap usage 314.155 MB -> 69.746 MB. [2026-04-14T00:56:35.853Z] ====== als (apache-spark) [default], iteration 23 completed (11633.785 ms) ====== [2026-04-14T00:56:35.853Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-14T00:56:35.853Z] GC before operation: completed in 125.458 ms, heap usage 361.223 MB -> 70.098 MB. [2026-04-14T00:56:46.824Z] ====== als (apache-spark) [default], iteration 24 completed (11643.151 ms) ====== [2026-04-14T00:56:46.824Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-14T00:56:46.824Z] GC before operation: completed in 123.067 ms, heap usage 98.686 MB -> 70.162 MB. [2026-04-14T00:56:58.066Z] ====== als (apache-spark) [default], iteration 25 completed (11459.984 ms) ====== [2026-04-14T00:56:58.066Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-14T00:56:58.066Z] GC before operation: completed in 128.424 ms, heap usage 221.262 MB -> 70.597 MB. [2026-04-14T00:57:10.822Z] ====== als (apache-spark) [default], iteration 26 completed (11798.599 ms) ====== [2026-04-14T00:57:10.822Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-14T00:57:10.822Z] GC before operation: completed in 128.860 ms, heap usage 431.853 MB -> 71.147 MB. [2026-04-14T00:57:22.188Z] ====== als (apache-spark) [default], iteration 27 completed (11716.872 ms) ====== [2026-04-14T00:57:22.188Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-14T00:57:22.188Z] GC before operation: completed in 129.082 ms, heap usage 356.277 MB -> 71.110 MB. [2026-04-14T00:57:36.038Z] ====== als (apache-spark) [default], iteration 28 completed (12262.327 ms) ====== [2026-04-14T00:57:36.038Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-14T00:57:36.038Z] GC before operation: completed in 126.476 ms, heap usage 182.779 MB -> 71.128 MB. [2026-04-14T00:57:47.332Z] ====== als (apache-spark) [default], iteration 29 completed (11487.303 ms) ====== [2026-04-14T00:57:47.332Z] ----------------------------------- [2026-04-14T00:57:47.332Z] renaissance-als_0_PASSED [2026-04-14T00:57:47.332Z] ----------------------------------- [2026-04-14T00:57:47.332Z] [2026-04-14T00:57:47.332Z] TEST TEARDOWN: [2026-04-14T00:57:47.332Z] Nothing to be done for teardown. [2026-04-14T00:57:47.332Z] renaissance-als_0 Finish Time: Tue Apr 14 00:57:46 2026 Epoch Time (ms): 1776128266641