[2026-01-10T12:04:35.810Z] Running test renaissance-als_0 ... [2026-01-10T12:04:35.810Z] =============================================== [2026-01-10T12:04:35.810Z] renaissance-als_0 Start Time: Sat Jan 10 12:04:35 2026 Epoch Time (ms): 1768046675043 [2026-01-10T12:04:35.810Z] variation: NoOptions [2026-01-10T12:04:35.810Z] JVM_OPTIONS: [2026-01-10T12:04:35.810Z] { \ [2026-01-10T12:04:35.810Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T12:04:35.810Z] echo "Nothing to be done for setup."; \ [2026-01-10T12:04:35.810Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17680466747500/renaissance-als_0"; \ [2026-01-10T12:04:35.810Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17680466747500/renaissance-als_0"; \ [2026-01-10T12:04:35.810Z] echo ""; echo "TESTING:"; \ [2026-01-10T12:04:35.811Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_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_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17680466747500/renaissance-als_0"/als.json" als; \ [2026-01-10T12:04:35.811Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17680466747500/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T12:04:35.811Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T12:04:35.811Z] echo "Nothing to be done for teardown."; \ [2026-01-10T12:04:35.811Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17680466747500/TestTargetResult"; [2026-01-10T12:04:35.811Z] [2026-01-10T12:04:35.811Z] TEST SETUP: [2026-01-10T12:04:35.811Z] Nothing to be done for setup. [2026-01-10T12:04:35.811Z] [2026-01-10T12:04:35.811Z] TESTING: [2026-01-10T12:04:37.863Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-10T12:04:37.863Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_17680466747500/renaissance-als_0/launcher-120435-9111209941330765055/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-10T12:04:37.863Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-10T12:04:37.863Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-10T12:04:47.606Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-10T12:04:50.936Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T12:04:50.936Z] GC before operation: completed in 45.465 ms, heap usage 165.500 MB -> 35.080 MB. [2026-01-10T12:05:12.215Z] ====== als (apache-spark) [default], iteration 0 completed (18303.664 ms) ====== [2026-01-10T12:05:12.215Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T12:05:12.215Z] GC before operation: completed in 106.539 ms, heap usage 134.103 MB -> 61.649 MB. [2026-01-10T12:05:17.600Z] ====== als (apache-spark) [default], iteration 1 completed (6678.915 ms) ====== [2026-01-10T12:05:17.600Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T12:05:17.600Z] GC before operation: completed in 102.783 ms, heap usage 336.763 MB -> 62.630 MB. [2026-01-10T12:05:24.332Z] ====== als (apache-spark) [default], iteration 2 completed (6074.719 ms) ====== [2026-01-10T12:05:24.332Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T12:05:24.332Z] GC before operation: completed in 108.507 ms, heap usage 176.537 MB -> 63.059 MB. [2026-01-10T12:05:29.714Z] ====== als (apache-spark) [default], iteration 3 completed (6089.280 ms) ====== [2026-01-10T12:05:29.714Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T12:05:30.682Z] GC before operation: completed in 111.455 ms, heap usage 178.907 MB -> 63.729 MB. [2026-01-10T12:05:36.075Z] ====== als (apache-spark) [default], iteration 4 completed (5633.021 ms) ====== [2026-01-10T12:05:36.075Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T12:05:36.075Z] GC before operation: completed in 109.708 ms, heap usage 257.800 MB -> 64.372 MB. [2026-01-10T12:05:41.541Z] ====== als (apache-spark) [default], iteration 5 completed (5463.309 ms) ====== [2026-01-10T12:05:41.541Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T12:05:41.541Z] GC before operation: completed in 111.794 ms, heap usage 465.479 MB -> 65.221 MB. [2026-01-10T12:05:48.257Z] ====== als (apache-spark) [default], iteration 6 completed (5356.489 ms) ====== [2026-01-10T12:05:48.257Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T12:05:48.257Z] GC before operation: completed in 116.212 ms, heap usage 468.428 MB -> 65.785 MB. [2026-01-10T12:05:53.652Z] ====== als (apache-spark) [default], iteration 7 completed (5323.602 ms) ====== [2026-01-10T12:05:53.652Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T12:05:53.652Z] GC before operation: completed in 121.915 ms, heap usage 376.363 MB -> 66.198 MB. [2026-01-10T12:05:59.388Z] ====== als (apache-spark) [default], iteration 8 completed (5416.298 ms) ====== [2026-01-10T12:05:59.388Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T12:05:59.388Z] GC before operation: completed in 122.549 ms, heap usage 121.500 MB -> 66.494 MB. [2026-01-10T12:06:04.792Z] ====== als (apache-spark) [default], iteration 9 completed (5184.678 ms) ====== [2026-01-10T12:06:04.792Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T12:06:04.792Z] GC before operation: completed in 129.739 ms, heap usage 114.958 MB -> 66.582 MB. [2026-01-10T12:06:10.192Z] ====== als (apache-spark) [default], iteration 10 completed (5329.748 ms) ====== [2026-01-10T12:06:10.192Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T12:06:10.192Z] GC before operation: completed in 128.846 ms, heap usage 521.231 MB -> 67.356 MB. [2026-01-10T12:06:15.729Z] ====== als (apache-spark) [default], iteration 11 completed (5217.943 ms) ====== [2026-01-10T12:06:15.729Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T12:06:15.729Z] GC before operation: completed in 128.220 ms, heap usage 247.097 MB -> 67.222 MB. [2026-01-10T12:06:21.385Z] ====== als (apache-spark) [default], iteration 12 completed (5430.091 ms) ====== [2026-01-10T12:06:21.385Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T12:06:21.385Z] GC before operation: completed in 129.753 ms, heap usage 428.812 MB -> 67.965 MB. [2026-01-10T12:06:26.971Z] ====== als (apache-spark) [default], iteration 13 completed (5123.382 ms) ====== [2026-01-10T12:06:26.971Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T12:06:26.971Z] GC before operation: completed in 125.133 ms, heap usage 274.509 MB -> 67.814 MB. [2026-01-10T12:06:32.355Z] ====== als (apache-spark) [default], iteration 14 completed (5036.341 ms) ====== [2026-01-10T12:06:32.355Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T12:06:32.355Z] GC before operation: completed in 133.952 ms, heap usage 447.102 MB -> 68.438 MB. [2026-01-10T12:06:37.751Z] ====== als (apache-spark) [default], iteration 15 completed (5018.149 ms) ====== [2026-01-10T12:06:37.751Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T12:06:37.751Z] GC before operation: completed in 151.563 ms, heap usage 636.513 MB -> 68.807 MB. [2026-01-10T12:06:42.055Z] ====== als (apache-spark) [default], iteration 16 completed (5122.295 ms) ====== [2026-01-10T12:06:42.055Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T12:06:43.030Z] GC before operation: completed in 148.623 ms, heap usage 535.105 MB -> 68.999 MB. [2026-01-10T12:06:48.477Z] ====== als (apache-spark) [default], iteration 17 completed (5249.111 ms) ====== [2026-01-10T12:06:48.477Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T12:06:48.477Z] GC before operation: completed in 136.867 ms, heap usage 124.736 MB -> 68.683 MB. [2026-01-10T12:06:53.895Z] ====== als (apache-spark) [default], iteration 18 completed (5440.803 ms) ====== [2026-01-10T12:06:53.895Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T12:06:53.895Z] GC before operation: completed in 139.457 ms, heap usage 263.965 MB -> 69.074 MB. [2026-01-10T12:06:59.386Z] ====== als (apache-spark) [default], iteration 19 completed (5336.010 ms) ====== [2026-01-10T12:06:59.386Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T12:06:59.386Z] GC before operation: completed in 140.802 ms, heap usage 215.442 MB -> 69.264 MB. [2026-01-10T12:07:04.780Z] ====== als (apache-spark) [default], iteration 20 completed (5318.989 ms) ====== [2026-01-10T12:07:04.780Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T12:07:04.780Z] GC before operation: completed in 144.716 ms, heap usage 268.494 MB -> 69.638 MB. [2026-01-10T12:07:11.508Z] ====== als (apache-spark) [default], iteration 21 completed (5330.689 ms) ====== [2026-01-10T12:07:11.508Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T12:07:11.508Z] GC before operation: completed in 142.924 ms, heap usage 358.294 MB -> 69.969 MB. [2026-01-10T12:07:16.899Z] ====== als (apache-spark) [default], iteration 22 completed (5464.558 ms) ====== [2026-01-10T12:07:16.899Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T12:07:16.899Z] GC before operation: completed in 145.906 ms, heap usage 488.160 MB -> 70.548 MB. [2026-01-10T12:07:22.299Z] ====== als (apache-spark) [default], iteration 23 completed (5355.468 ms) ====== [2026-01-10T12:07:22.299Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T12:07:22.299Z] GC before operation: completed in 157.469 ms, heap usage 492.992 MB -> 70.778 MB. [2026-01-10T12:07:27.693Z] ====== als (apache-spark) [default], iteration 24 completed (5242.241 ms) ====== [2026-01-10T12:07:27.693Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T12:07:27.693Z] GC before operation: completed in 156.391 ms, heap usage 467.306 MB -> 71.003 MB. [2026-01-10T12:07:33.627Z] ====== als (apache-spark) [default], iteration 25 completed (5210.788 ms) ====== [2026-01-10T12:07:33.627Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T12:07:33.627Z] GC before operation: completed in 145.254 ms, heap usage 350.527 MB -> 71.043 MB. [2026-01-10T12:07:39.017Z] ====== als (apache-spark) [default], iteration 26 completed (5120.128 ms) ====== [2026-01-10T12:07:39.017Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T12:07:39.017Z] GC before operation: completed in 151.058 ms, heap usage 210.881 MB -> 71.062 MB. [2026-01-10T12:07:44.408Z] ====== als (apache-spark) [default], iteration 27 completed (5091.580 ms) ====== [2026-01-10T12:07:44.408Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T12:07:44.408Z] GC before operation: completed in 121.472 ms, heap usage 281.789 MB -> 71.506 MB. [2026-01-10T12:07:49.804Z] ====== als (apache-spark) [default], iteration 28 completed (4899.468 ms) ====== [2026-01-10T12:07:49.804Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T12:07:49.804Z] GC before operation: completed in 123.559 ms, heap usage 261.296 MB -> 71.325 MB. [2026-01-10T12:07:53.969Z] ====== als (apache-spark) [default], iteration 29 completed (4809.559 ms) ====== [2026-01-10T12:07:54.921Z] ----------------------------------- [2026-01-10T12:07:54.921Z] renaissance-als_0_PASSED [2026-01-10T12:07:54.921Z] ----------------------------------- [2026-01-10T12:07:54.921Z] [2026-01-10T12:07:54.921Z] TEST TEARDOWN: [2026-01-10T12:07:54.921Z] Nothing to be done for teardown. [2026-01-10T12:07:54.921Z] renaissance-als_0 Finish Time: Sat Jan 10 12:07:54 2026 Epoch Time (ms): 1768046874204