[2026-01-12T15:26:01.323Z] Running test renaissance-als_0 ... [2026-01-12T15:26:01.323Z] =============================================== [2026-01-12T15:26:01.323Z] renaissance-als_0 Start Time: Mon Jan 12 15:26:00 2026 Epoch Time (ms): 1768231560557 [2026-01-12T15:26:01.323Z] variation: NoOptions [2026-01-12T15:26:01.323Z] JVM_OPTIONS: [2026-01-12T15:26:01.323Z] { \ [2026-01-12T15:26:01.323Z] echo ""; echo "TEST SETUP:"; \ [2026-01-12T15:26:01.323Z] echo "Nothing to be done for setup."; \ [2026-01-12T15:26:01.323Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17682315601318/renaissance-als_0"; \ [2026-01-12T15:26:01.323Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17682315601318/renaissance-als_0"; \ [2026-01-12T15:26:01.323Z] echo ""; echo "TESTING:"; \ [2026-01-12T15:26:01.323Z] "/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_17682315601318/renaissance-als_0"/als.json" als; \ [2026-01-12T15:26:01.323Z] 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_17682315601318/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-12T15:26:01.323Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-12T15:26:01.323Z] echo "Nothing to be done for teardown."; \ [2026-01-12T15:26:01.323Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17682315601318/TestTargetResult"; [2026-01-12T15:26:01.323Z] [2026-01-12T15:26:01.323Z] TEST SETUP: [2026-01-12T15:26:01.323Z] Nothing to be done for setup. [2026-01-12T15:26:01.323Z] [2026-01-12T15:26:01.323Z] TESTING: [2026-01-12T15:26:01.323Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-12T15:26:01.323Z] 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_17682315601318/renaissance-als_0/launcher-152600-8016621867782673006/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-12T15:26:01.323Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-12T15:26:01.323Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-12T15:26:06.716Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-12T15:26:11.123Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-12T15:26:11.857Z] GC before operation: completed in 66.669 ms, heap usage 81.000 MB -> 35.048 MB. [2026-01-12T15:26:33.468Z] ====== als (apache-spark) [default], iteration 0 completed (19879.743 ms) ====== [2026-01-12T15:26:33.468Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-12T15:26:33.468Z] GC before operation: completed in 101.407 ms, heap usage 158.405 MB -> 61.709 MB. [2026-01-12T15:26:54.883Z] ====== als (apache-spark) [default], iteration 1 completed (20716.826 ms) ====== [2026-01-12T15:26:54.883Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-12T15:26:54.883Z] GC before operation: completed in 87.488 ms, heap usage 466.367 MB -> 62.960 MB. [2026-01-12T15:27:01.545Z] ====== als (apache-spark) [default], iteration 2 completed (7472.697 ms) ====== [2026-01-12T15:27:01.545Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-12T15:27:01.545Z] GC before operation: completed in 92.029 ms, heap usage 257.552 MB -> 63.176 MB. [2026-01-12T15:27:09.725Z] ====== als (apache-spark) [default], iteration 3 completed (7647.242 ms) ====== [2026-01-12T15:27:09.725Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-12T15:27:09.725Z] GC before operation: completed in 86.437 ms, heap usage 259.482 MB -> 63.811 MB. [2026-01-12T15:27:16.380Z] ====== als (apache-spark) [default], iteration 4 completed (7337.349 ms) ====== [2026-01-12T15:27:16.380Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-12T15:27:16.380Z] GC before operation: completed in 93.303 ms, heap usage 279.095 MB -> 64.493 MB. [2026-01-12T15:27:24.431Z] ====== als (apache-spark) [default], iteration 5 completed (6977.861 ms) ====== [2026-01-12T15:27:24.432Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-12T15:27:24.432Z] GC before operation: completed in 89.676 ms, heap usage 124.192 MB -> 64.735 MB. [2026-01-12T15:27:31.090Z] ====== als (apache-spark) [default], iteration 6 completed (7130.274 ms) ====== [2026-01-12T15:27:31.090Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-12T15:27:31.090Z] GC before operation: completed in 94.343 ms, heap usage 253.391 MB -> 65.476 MB. [2026-01-12T15:27:39.141Z] ====== als (apache-spark) [default], iteration 7 completed (6780.564 ms) ====== [2026-01-12T15:27:39.141Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-12T15:27:39.141Z] GC before operation: completed in 93.311 ms, heap usage 157.045 MB -> 65.939 MB. [2026-01-12T15:27:45.797Z] ====== als (apache-spark) [default], iteration 8 completed (7313.909 ms) ====== [2026-01-12T15:27:45.797Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-12T15:27:45.797Z] GC before operation: completed in 100.173 ms, heap usage 486.947 MB -> 66.880 MB. [2026-01-12T15:27:53.830Z] ====== als (apache-spark) [default], iteration 9 completed (6939.081 ms) ====== [2026-01-12T15:27:53.830Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-12T15:27:53.830Z] GC before operation: completed in 92.853 ms, heap usage 343.767 MB -> 66.901 MB. [2026-01-12T15:28:00.485Z] ====== als (apache-spark) [default], iteration 10 completed (7009.211 ms) ====== [2026-01-12T15:28:00.485Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-12T15:28:00.485Z] GC before operation: completed in 97.120 ms, heap usage 533.679 MB -> 67.398 MB. [2026-01-12T15:28:07.619Z] ====== als (apache-spark) [default], iteration 11 completed (7099.074 ms) ====== [2026-01-12T15:28:07.619Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-12T15:28:07.619Z] GC before operation: completed in 119.836 ms, heap usage 523.506 MB -> 67.740 MB. [2026-01-12T15:28:15.701Z] ====== als (apache-spark) [default], iteration 12 completed (6718.028 ms) ====== [2026-01-12T15:28:15.701Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-12T15:28:15.701Z] GC before operation: completed in 105.431 ms, heap usage 281.551 MB -> 67.572 MB. [2026-01-12T15:28:22.372Z] ====== als (apache-spark) [default], iteration 13 completed (6647.693 ms) ====== [2026-01-12T15:28:22.372Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-12T15:28:22.372Z] GC before operation: completed in 97.562 ms, heap usage 171.169 MB -> 67.630 MB. [2026-01-12T15:28:29.025Z] ====== als (apache-spark) [default], iteration 14 completed (6792.264 ms) ====== [2026-01-12T15:28:29.025Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-12T15:28:29.025Z] GC before operation: completed in 96.411 ms, heap usage 343.586 MB -> 68.205 MB. [2026-01-12T15:28:35.691Z] ====== als (apache-spark) [default], iteration 15 completed (6753.261 ms) ====== [2026-01-12T15:28:35.691Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-12T15:28:35.691Z] GC before operation: completed in 98.385 ms, heap usage 259.766 MB -> 68.268 MB. [2026-01-12T15:28:42.368Z] ====== als (apache-spark) [default], iteration 16 completed (6683.060 ms) ====== [2026-01-12T15:28:42.368Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-12T15:28:43.106Z] GC before operation: completed in 106.346 ms, heap usage 427.971 MB -> 68.902 MB. [2026-01-12T15:28:49.757Z] ====== als (apache-spark) [default], iteration 17 completed (6662.406 ms) ====== [2026-01-12T15:28:49.757Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-12T15:28:49.757Z] GC before operation: completed in 100.114 ms, heap usage 345.657 MB -> 68.879 MB. [2026-01-12T15:28:56.991Z] ====== als (apache-spark) [default], iteration 18 completed (6599.458 ms) ====== [2026-01-12T15:28:56.991Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-12T15:28:56.991Z] GC before operation: completed in 104.651 ms, heap usage 97.993 MB -> 68.902 MB. [2026-01-12T15:29:03.651Z] ====== als (apache-spark) [default], iteration 19 completed (6754.830 ms) ====== [2026-01-12T15:29:03.651Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-12T15:29:03.651Z] GC before operation: completed in 106.404 ms, heap usage 428.714 MB -> 69.711 MB. [2026-01-12T15:29:11.823Z] ====== als (apache-spark) [default], iteration 20 completed (7018.645 ms) ====== [2026-01-12T15:29:11.823Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-12T15:29:11.823Z] GC before operation: completed in 102.225 ms, heap usage 185.697 MB -> 69.512 MB. [2026-01-12T15:29:18.472Z] ====== als (apache-spark) [default], iteration 21 completed (6757.985 ms) ====== [2026-01-12T15:29:18.472Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-12T15:29:18.472Z] GC before operation: completed in 105.438 ms, heap usage 106.987 MB -> 69.751 MB. [2026-01-12T15:29:25.130Z] ====== als (apache-spark) [default], iteration 22 completed (6787.379 ms) ====== [2026-01-12T15:29:25.130Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-12T15:29:25.130Z] GC before operation: completed in 104.785 ms, heap usage 352.632 MB -> 70.339 MB. [2026-01-12T15:29:31.775Z] ====== als (apache-spark) [default], iteration 23 completed (6641.995 ms) ====== [2026-01-12T15:29:31.775Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-12T15:29:31.775Z] GC before operation: completed in 105.559 ms, heap usage 514.242 MB -> 70.843 MB. [2026-01-12T15:29:39.797Z] ====== als (apache-spark) [default], iteration 24 completed (6793.851 ms) ====== [2026-01-12T15:29:39.797Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-12T15:29:39.797Z] GC before operation: completed in 104.420 ms, heap usage 256.471 MB -> 70.752 MB. [2026-01-12T15:29:46.452Z] ====== als (apache-spark) [default], iteration 25 completed (6740.882 ms) ====== [2026-01-12T15:29:46.452Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-12T15:29:46.452Z] GC before operation: completed in 104.901 ms, heap usage 203.279 MB -> 70.794 MB. [2026-01-12T15:29:53.593Z] ====== als (apache-spark) [default], iteration 26 completed (6699.825 ms) ====== [2026-01-12T15:29:53.593Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-12T15:29:53.593Z] GC before operation: completed in 102.080 ms, heap usage 194.649 MB -> 71.046 MB. [2026-01-12T15:30:00.282Z] ====== als (apache-spark) [default], iteration 27 completed (6613.186 ms) ====== [2026-01-12T15:30:00.282Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-12T15:30:00.282Z] GC before operation: completed in 108.614 ms, heap usage 507.949 MB -> 71.747 MB. [2026-01-12T15:30:06.934Z] ====== als (apache-spark) [default], iteration 28 completed (6609.364 ms) ====== [2026-01-12T15:30:06.934Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-12T15:30:06.934Z] GC before operation: completed in 104.859 ms, heap usage 438.253 MB -> 71.591 MB. [2026-01-12T15:30:13.585Z] ====== als (apache-spark) [default], iteration 29 completed (6586.830 ms) ====== [2026-01-12T15:30:13.585Z] ----------------------------------- [2026-01-12T15:30:13.585Z] renaissance-als_0_PASSED [2026-01-12T15:30:13.585Z] ----------------------------------- [2026-01-12T15:30:13.585Z] [2026-01-12T15:30:13.585Z] TEST TEARDOWN: [2026-01-12T15:30:13.585Z] Nothing to be done for teardown. [2026-01-12T15:30:13.585Z] renaissance-als_0 Finish Time: Mon Jan 12 15:30:13 2026 Epoch Time (ms): 1768231813349