[2026-01-24T19:47:53.788Z] Running test renaissance-als_0 ... [2026-01-24T19:47:53.788Z] =============================================== [2026-01-24T19:47:53.788Z] renaissance-als_0 Start Time: Sat Jan 24 19:47:53 2026 Epoch Time (ms): 1769284073642 [2026-01-24T19:47:53.789Z] variation: NoOptions [2026-01-24T19:47:53.789Z] JVM_OPTIONS: [2026-01-24T19:47:53.789Z] { \ [2026-01-24T19:47:53.789Z] echo ""; echo "TEST SETUP:"; \ [2026-01-24T19:47:53.789Z] echo "Nothing to be done for setup."; \ [2026-01-24T19:47:53.789Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17692840725759/renaissance-als_0"; \ [2026-01-24T19:47:53.789Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17692840725759/renaissance-als_0"; \ [2026-01-24T19:47:53.789Z] echo ""; echo "TESTING:"; \ [2026-01-24T19:47:53.789Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/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_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17692840725759/renaissance-als_0"/als.json" als; \ [2026-01-24T19:47:53.789Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17692840725759/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-24T19:47:53.789Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-24T19:47:53.789Z] echo "Nothing to be done for teardown."; \ [2026-01-24T19:47:53.789Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17692840725759/TestTargetResult"; [2026-01-24T19:47:53.789Z] [2026-01-24T19:47:53.789Z] TEST SETUP: [2026-01-24T19:47:53.789Z] Nothing to be done for setup. [2026-01-24T19:47:53.789Z] [2026-01-24T19:47:53.789Z] TESTING: [2026-01-24T19:47:57.711Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-24T19:47:57.711Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/output_17692840725759/renaissance-als_0/launcher-194755-10130154312703380590/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-24T19:47:57.711Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-24T19:47:57.711Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-24T19:48:21.053Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-24T19:48:37.162Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-24T19:48:37.162Z] GC before operation: completed in 211.134 ms, heap usage 54.919 MB -> 35.414 MB. [2026-01-24T19:50:00.701Z] ====== als (apache-spark) [default], iteration 0 completed (74785.722 ms) ====== [2026-01-24T19:50:00.701Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-24T19:50:00.701Z] GC before operation: completed in 733.712 ms, heap usage 1.416 GB -> 76.334 MB. [2026-01-24T19:50:24.060Z] ====== als (apache-spark) [default], iteration 1 completed (26632.004 ms) ====== [2026-01-24T19:50:24.060Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-24T19:50:24.060Z] GC before operation: completed in 642.959 ms, heap usage 585.095 MB -> 76.529 MB. [2026-01-24T19:50:47.798Z] ====== als (apache-spark) [default], iteration 2 completed (21685.057 ms) ====== [2026-01-24T19:50:47.798Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-24T19:50:47.798Z] GC before operation: completed in 663.268 ms, heap usage 344.313 MB -> 76.997 MB. [2026-01-24T19:51:11.161Z] ====== als (apache-spark) [default], iteration 3 completed (21143.984 ms) ====== [2026-01-24T19:51:11.161Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-24T19:51:11.161Z] GC before operation: completed in 694.122 ms, heap usage 499.083 MB -> 77.706 MB. [2026-01-24T19:51:34.496Z] ====== als (apache-spark) [default], iteration 4 completed (20121.029 ms) ====== [2026-01-24T19:51:34.496Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-24T19:51:34.496Z] GC before operation: completed in 769.729 ms, heap usage 323.287 MB -> 78.022 MB. [2026-01-24T19:51:53.924Z] ====== als (apache-spark) [default], iteration 5 completed (19926.310 ms) ====== [2026-01-24T19:51:53.924Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-24T19:51:53.924Z] GC before operation: completed in 743.640 ms, heap usage 428.905 MB -> 78.644 MB. [2026-01-24T19:52:17.367Z] ====== als (apache-spark) [default], iteration 6 completed (19852.164 ms) ====== [2026-01-24T19:52:17.367Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-24T19:52:17.367Z] GC before operation: completed in 748.438 ms, heap usage 269.987 MB -> 79.110 MB. [2026-01-24T19:52:36.781Z] ====== als (apache-spark) [default], iteration 7 completed (19663.823 ms) ====== [2026-01-24T19:52:36.781Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-24T19:52:36.781Z] GC before operation: completed in 765.211 ms, heap usage 500.301 MB -> 79.910 MB. [2026-01-24T19:53:00.182Z] ====== als (apache-spark) [default], iteration 8 completed (20160.607 ms) ====== [2026-01-24T19:53:00.182Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-24T19:53:00.182Z] GC before operation: completed in 779.105 ms, heap usage 569.554 MB -> 80.441 MB. [2026-01-24T19:53:19.597Z] ====== als (apache-spark) [default], iteration 9 completed (19348.345 ms) ====== [2026-01-24T19:53:19.597Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-24T19:53:19.597Z] GC before operation: completed in 826.533 ms, heap usage 549.050 MB -> 80.860 MB. [2026-01-24T19:53:42.982Z] ====== als (apache-spark) [default], iteration 10 completed (19236.567 ms) ====== [2026-01-24T19:53:42.982Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-24T19:53:42.982Z] GC before operation: completed in 833.565 ms, heap usage 328.252 MB -> 80.794 MB. [2026-01-24T19:54:02.418Z] ====== als (apache-spark) [default], iteration 11 completed (19636.968 ms) ====== [2026-01-24T19:54:02.418Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-24T19:54:02.418Z] GC before operation: completed in 823.922 ms, heap usage 294.158 MB -> 80.968 MB. [2026-01-24T19:54:21.836Z] ====== als (apache-spark) [default], iteration 12 completed (19592.961 ms) ====== [2026-01-24T19:54:21.837Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-24T19:54:22.596Z] GC before operation: completed in 844.048 ms, heap usage 321.009 MB -> 81.285 MB. [2026-01-24T19:54:45.942Z] ====== als (apache-spark) [default], iteration 13 completed (19471.845 ms) ====== [2026-01-24T19:54:45.942Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-24T19:54:45.942Z] GC before operation: completed in 860.702 ms, heap usage 618.494 MB -> 81.768 MB. [2026-01-24T19:55:05.742Z] ====== als (apache-spark) [default], iteration 14 completed (19152.767 ms) ====== [2026-01-24T19:55:05.742Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-24T19:55:05.742Z] GC before operation: completed in 861.897 ms, heap usage 321.517 MB -> 81.835 MB. [2026-01-24T19:55:25.135Z] ====== als (apache-spark) [default], iteration 15 completed (19010.525 ms) ====== [2026-01-24T19:55:25.135Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-24T19:55:25.483Z] GC before operation: completed in 961.130 ms, heap usage 401.462 MB -> 82.109 MB. [2026-01-24T19:55:48.861Z] ====== als (apache-spark) [default], iteration 16 completed (18749.132 ms) ====== [2026-01-24T19:55:48.861Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-24T19:55:48.861Z] GC before operation: completed in 880.623 ms, heap usage 415.744 MB -> 82.406 MB. [2026-01-24T19:56:08.265Z] ====== als (apache-spark) [default], iteration 17 completed (18724.486 ms) ====== [2026-01-24T19:56:08.265Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-24T19:56:08.265Z] GC before operation: completed in 893.280 ms, heap usage 460.053 MB -> 82.930 MB. [2026-01-24T19:56:27.707Z] ====== als (apache-spark) [default], iteration 18 completed (18501.489 ms) ====== [2026-01-24T19:56:27.707Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-24T19:56:27.707Z] GC before operation: completed in 912.033 ms, heap usage 402.105 MB -> 82.886 MB. [2026-01-24T19:56:47.147Z] ====== als (apache-spark) [default], iteration 19 completed (19183.491 ms) ====== [2026-01-24T19:56:47.147Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-24T19:56:47.147Z] GC before operation: completed in 918.469 ms, heap usage 467.064 MB -> 83.277 MB. [2026-01-24T19:57:10.488Z] ====== als (apache-spark) [default], iteration 20 completed (18915.459 ms) ====== [2026-01-24T19:57:10.488Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-24T19:57:10.488Z] GC before operation: completed in 942.754 ms, heap usage 476.622 MB -> 83.648 MB. [2026-01-24T19:57:29.908Z] ====== als (apache-spark) [default], iteration 21 completed (19173.311 ms) ====== [2026-01-24T19:57:29.908Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-24T19:57:29.909Z] GC before operation: completed in 926.115 ms, heap usage 574.734 MB -> 84.027 MB. [2026-01-24T19:57:49.394Z] ====== als (apache-spark) [default], iteration 22 completed (19324.551 ms) ====== [2026-01-24T19:57:49.394Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-24T19:57:49.746Z] GC before operation: completed in 918.668 ms, heap usage 255.164 MB -> 83.886 MB. [2026-01-24T19:58:09.185Z] ====== als (apache-spark) [default], iteration 23 completed (18354.871 ms) ====== [2026-01-24T19:58:09.185Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-24T19:58:09.929Z] GC before operation: completed in 932.453 ms, heap usage 554.611 MB -> 84.475 MB. [2026-01-24T19:58:29.356Z] ====== als (apache-spark) [default], iteration 24 completed (18919.026 ms) ====== [2026-01-24T19:58:29.356Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-24T19:58:30.122Z] GC before operation: completed in 933.255 ms, heap usage 493.998 MB -> 84.711 MB. [2026-01-24T19:58:50.100Z] ====== als (apache-spark) [default], iteration 25 completed (18715.689 ms) ====== [2026-01-24T19:58:50.100Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-24T19:58:50.852Z] GC before operation: completed in 941.286 ms, heap usage 405.543 MB -> 84.757 MB. [2026-01-24T19:59:10.265Z] ====== als (apache-spark) [default], iteration 26 completed (18174.451 ms) ====== [2026-01-24T19:59:10.265Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-24T19:59:10.265Z] GC before operation: completed in 948.292 ms, heap usage 508.667 MB -> 85.130 MB. [2026-01-24T19:59:29.688Z] ====== als (apache-spark) [default], iteration 27 completed (18087.610 ms) ====== [2026-01-24T19:59:29.688Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-24T19:59:30.444Z] GC before operation: completed in 954.826 ms, heap usage 275.284 MB -> 85.070 MB. [2026-01-24T19:59:50.127Z] ====== als (apache-spark) [default], iteration 28 completed (18128.967 ms) ====== [2026-01-24T19:59:50.127Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-24T19:59:50.127Z] GC before operation: completed in 946.686 ms, heap usage 469.934 MB -> 85.200 MB. [2026-01-24T20:00:09.528Z] ====== als (apache-spark) [default], iteration 29 completed (18123.420 ms) ====== [2026-01-24T20:00:11.257Z] ----------------------------------- [2026-01-24T20:00:11.257Z] renaissance-als_0_PASSED [2026-01-24T20:00:11.257Z] ----------------------------------- [2026-01-24T20:00:11.257Z] [2026-01-24T20:00:11.257Z] TEST TEARDOWN: [2026-01-24T20:00:11.257Z] Nothing to be done for teardown. [2026-01-24T20:00:11.257Z] renaissance-als_0 Finish Time: Sat Jan 24 20:00:11 2026 Epoch Time (ms): 1769284811182