[2025-10-22T23:17:53.296Z] Running test renaissance-als_0 ... [2025-10-22T23:17:53.296Z] =============================================== [2025-10-22T23:17:53.296Z] renaissance-als_0 Start Time: Wed Oct 22 23:17:53 2025 Epoch Time (ms): 1761175073023 [2025-10-22T23:17:53.296Z] variation: NoOptions [2025-10-22T23:17:53.296Z] JVM_OPTIONS: [2025-10-22T23:17:53.296Z] { \ [2025-10-22T23:17:53.296Z] echo ""; echo "TEST SETUP:"; \ [2025-10-22T23:17:53.296Z] echo "Nothing to be done for setup."; \ [2025-10-22T23:17:53.296Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17611745521285/renaissance-als_0"; \ [2025-10-22T23:17:53.296Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17611745521285/renaissance-als_0"; \ [2025-10-22T23:17:53.296Z] echo ""; echo "TESTING:"; \ [2025-10-22T23:17:53.296Z] "/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_17611745521285/renaissance-als_0"/als.json" als; \ [2025-10-22T23:17:53.296Z] 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_17611745521285/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-22T23:17:53.296Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-22T23:17:53.296Z] echo "Nothing to be done for teardown."; \ [2025-10-22T23:17:53.296Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17611745521285/TestTargetResult"; [2025-10-22T23:17:53.296Z] [2025-10-22T23:17:53.296Z] TEST SETUP: [2025-10-22T23:17:53.296Z] Nothing to be done for setup. [2025-10-22T23:17:53.296Z] [2025-10-22T23:17:53.296Z] TESTING: [2025-10-22T23:17:55.827Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-22T23:17:55.828Z] 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_17611745521285/renaissance-als_0/launcher-231753-14830280185721357972/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-22T23:17:55.828Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-22T23:17:55.828Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-22T23:18:15.874Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-22T23:18:35.932Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-22T23:18:35.932Z] GC before operation: completed in 207.168 ms, heap usage 64.000 MB -> 35.382 MB. [2025-10-22T23:19:46.566Z] ====== als (apache-spark) [default], iteration 0 completed (65838.440 ms) ====== [2025-10-22T23:19:46.566Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-22T23:19:46.566Z] GC before operation: completed in 765.711 ms, heap usage 1.158 GB -> 76.531 MB. [2025-10-22T23:20:15.384Z] ====== als (apache-spark) [default], iteration 1 completed (25525.927 ms) ====== [2025-10-22T23:20:15.384Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-22T23:20:15.384Z] GC before operation: completed in 704.087 ms, heap usage 271.532 MB -> 76.386 MB. [2025-10-22T23:20:35.681Z] ====== als (apache-spark) [default], iteration 2 completed (22901.411 ms) ====== [2025-10-22T23:20:35.681Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-22T23:20:36.083Z] GC before operation: completed in 726.697 ms, heap usage 303.702 MB -> 76.964 MB. [2025-10-22T23:21:04.898Z] ====== als (apache-spark) [default], iteration 3 completed (22299.217 ms) ====== [2025-10-22T23:21:04.898Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-22T23:21:04.898Z] GC before operation: completed in 736.344 ms, heap usage 122.474 MB -> 77.372 MB. [2025-10-22T23:21:25.050Z] ====== als (apache-spark) [default], iteration 4 completed (21651.887 ms) ====== [2025-10-22T23:21:25.050Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-22T23:21:25.050Z] GC before operation: completed in 729.321 ms, heap usage 367.317 MB -> 78.137 MB. [2025-10-22T23:21:45.147Z] ====== als (apache-spark) [default], iteration 5 completed (20268.010 ms) ====== [2025-10-22T23:21:45.147Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-22T23:21:45.578Z] GC before operation: completed in 752.917 ms, heap usage 641.110 MB -> 79.200 MB. [2025-10-22T23:22:09.613Z] ====== als (apache-spark) [default], iteration 6 completed (20874.569 ms) ====== [2025-10-22T23:22:09.613Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-22T23:22:09.613Z] GC before operation: completed in 731.973 ms, heap usage 503.699 MB -> 79.460 MB. [2025-10-22T23:22:30.957Z] ====== als (apache-spark) [default], iteration 7 completed (21424.223 ms) ====== [2025-10-22T23:22:30.957Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-22T23:22:31.358Z] GC before operation: completed in 745.706 ms, heap usage 275.893 MB -> 79.661 MB. [2025-10-22T23:22:55.408Z] ====== als (apache-spark) [default], iteration 8 completed (20890.272 ms) ====== [2025-10-22T23:22:55.408Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-22T23:22:55.408Z] GC before operation: completed in 749.589 ms, heap usage 385.229 MB -> 80.281 MB. [2025-10-22T23:23:15.484Z] ====== als (apache-spark) [default], iteration 9 completed (20368.548 ms) ====== [2025-10-22T23:23:15.484Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-22T23:23:15.880Z] GC before operation: completed in 791.637 ms, heap usage 157.851 MB -> 80.195 MB. [2025-10-22T23:23:39.936Z] ====== als (apache-spark) [default], iteration 10 completed (20187.384 ms) ====== [2025-10-22T23:23:39.936Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-22T23:23:39.936Z] GC before operation: completed in 820.848 ms, heap usage 163.904 MB -> 80.445 MB. [2025-10-22T23:24:00.795Z] ====== als (apache-spark) [default], iteration 11 completed (20448.431 ms) ====== [2025-10-22T23:24:00.795Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-22T23:24:00.795Z] GC before operation: completed in 797.955 ms, heap usage 308.668 MB -> 80.836 MB. [2025-10-22T23:24:20.919Z] ====== als (apache-spark) [default], iteration 12 completed (20024.466 ms) ====== [2025-10-22T23:24:20.919Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-22T23:24:21.319Z] GC before operation: completed in 831.802 ms, heap usage 440.537 MB -> 81.574 MB. [2025-10-22T23:24:45.395Z] ====== als (apache-spark) [default], iteration 13 completed (20317.633 ms) ====== [2025-10-22T23:24:45.395Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-22T23:24:45.395Z] GC before operation: completed in 813.868 ms, heap usage 147.194 MB -> 81.370 MB. [2025-10-22T23:25:05.478Z] ====== als (apache-spark) [default], iteration 14 completed (20227.731 ms) ====== [2025-10-22T23:25:05.478Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-22T23:25:05.478Z] GC before operation: completed in 889.695 ms, heap usage 247.902 MB -> 81.688 MB. [2025-10-22T23:25:29.959Z] ====== als (apache-spark) [default], iteration 15 completed (20787.638 ms) ====== [2025-10-22T23:25:29.959Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-22T23:25:29.959Z] GC before operation: completed in 862.590 ms, heap usage 503.975 MB -> 82.320 MB. [2025-10-22T23:25:50.018Z] ====== als (apache-spark) [default], iteration 16 completed (19786.517 ms) ====== [2025-10-22T23:25:50.018Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-22T23:25:50.018Z] GC before operation: completed in 874.458 ms, heap usage 403.756 MB -> 82.325 MB. [2025-10-22T23:26:10.091Z] ====== als (apache-spark) [default], iteration 17 completed (19517.101 ms) ====== [2025-10-22T23:26:10.091Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-22T23:26:10.484Z] GC before operation: completed in 879.002 ms, heap usage 309.168 MB -> 82.606 MB. [2025-10-22T23:26:34.544Z] ====== als (apache-spark) [default], iteration 18 completed (19883.566 ms) ====== [2025-10-22T23:26:34.544Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-22T23:26:34.544Z] GC before operation: completed in 884.432 ms, heap usage 311.583 MB -> 82.686 MB. [2025-10-22T23:26:53.744Z] ====== als (apache-spark) [default], iteration 19 completed (20287.623 ms) ====== [2025-10-22T23:26:53.744Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-22T23:26:54.141Z] GC before operation: completed in 887.118 ms, heap usage 188.070 MB -> 82.870 MB. [2025-10-22T23:27:18.191Z] ====== als (apache-spark) [default], iteration 20 completed (20427.938 ms) ====== [2025-10-22T23:27:18.191Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-22T23:27:18.191Z] GC before operation: completed in 891.324 ms, heap usage 218.462 MB -> 83.128 MB. [2025-10-22T23:27:38.256Z] ====== als (apache-spark) [default], iteration 21 completed (19760.341 ms) ====== [2025-10-22T23:27:38.256Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-22T23:27:38.256Z] GC before operation: completed in 888.325 ms, heap usage 503.783 MB -> 83.892 MB. [2025-10-22T23:27:58.342Z] ====== als (apache-spark) [default], iteration 22 completed (19146.331 ms) ====== [2025-10-22T23:27:58.342Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-22T23:27:58.734Z] GC before operation: completed in 904.539 ms, heap usage 148.678 MB -> 83.688 MB. [2025-10-22T23:28:20.858Z] ====== als (apache-spark) [default], iteration 23 completed (19696.133 ms) ====== [2025-10-22T23:28:20.858Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-22T23:28:20.858Z] GC before operation: completed in 894.860 ms, heap usage 361.198 MB -> 84.202 MB. [2025-10-22T23:28:40.925Z] ====== als (apache-spark) [default], iteration 24 completed (19276.643 ms) ====== [2025-10-22T23:28:40.925Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-22T23:28:40.925Z] GC before operation: completed in 912.825 ms, heap usage 174.730 MB -> 84.327 MB. [2025-10-22T23:29:04.996Z] ====== als (apache-spark) [default], iteration 25 completed (19818.981 ms) ====== [2025-10-22T23:29:04.996Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-22T23:29:04.996Z] GC before operation: completed in 920.697 ms, heap usage 383.763 MB -> 84.742 MB. [2025-10-22T23:29:25.104Z] ====== als (apache-spark) [default], iteration 26 completed (19470.197 ms) ====== [2025-10-22T23:29:25.104Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-22T23:29:25.104Z] GC before operation: completed in 914.664 ms, heap usage 570.058 MB -> 85.333 MB. [2025-10-22T23:29:48.706Z] ====== als (apache-spark) [default], iteration 27 completed (19531.680 ms) ====== [2025-10-22T23:29:48.707Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-22T23:29:48.707Z] GC before operation: completed in 924.523 ms, heap usage 564.926 MB -> 85.605 MB. [2025-10-22T23:30:10.292Z] ====== als (apache-spark) [default], iteration 28 completed (19422.985 ms) ====== [2025-10-22T23:30:10.292Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-22T23:30:10.292Z] GC before operation: completed in 989.446 ms, heap usage 258.250 MB -> 84.862 MB. [2025-10-22T23:30:41.571Z] ====== als (apache-spark) [default], iteration 29 completed (19229.501 ms) ====== [2025-10-22T23:30:41.571Z] ----------------------------------- [2025-10-22T23:30:41.571Z] renaissance-als_0_PASSED [2025-10-22T23:30:41.571Z] ----------------------------------- [2025-10-22T23:30:41.571Z] [2025-10-22T23:30:41.571Z] TEST TEARDOWN: [2025-10-22T23:30:41.571Z] Nothing to be done for teardown. [2025-10-22T23:30:41.571Z] renaissance-als_0 Finish Time: Wed Oct 22 23:30:28 2025 Epoch Time (ms): 1761175828527