[2025-10-17T20:28:01.684Z] Running test renaissance-als_0 ... [2025-10-17T20:28:01.684Z] =============================================== [2025-10-17T20:28:01.684Z] renaissance-als_0 Start Time: Fri Oct 17 20:28:01 2025 Epoch Time (ms): 1760732881673 [2025-10-17T20:28:02.018Z] variation: NoOptions [2025-10-17T20:28:02.019Z] JVM_OPTIONS: [2025-10-17T20:28:02.019Z] { \ [2025-10-17T20:28:02.019Z] echo ""; echo "TEST SETUP:"; \ [2025-10-17T20:28:02.019Z] echo "Nothing to be done for setup."; \ [2025-10-17T20:28:02.019Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17607328808896/renaissance-als_0"; \ [2025-10-17T20:28:02.019Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17607328808896/renaissance-als_0"; \ [2025-10-17T20:28:02.019Z] echo ""; echo "TESTING:"; \ [2025-10-17T20:28:02.019Z] "/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_17607328808896/renaissance-als_0"/als.json" als; \ [2025-10-17T20:28:02.019Z] 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_17607328808896/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-17T20:28:02.019Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-17T20:28:02.019Z] echo "Nothing to be done for teardown."; \ [2025-10-17T20:28:02.019Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17607328808896/TestTargetResult"; [2025-10-17T20:28:02.019Z] [2025-10-17T20:28:02.019Z] TEST SETUP: [2025-10-17T20:28:02.019Z] Nothing to be done for setup. [2025-10-17T20:28:02.019Z] [2025-10-17T20:28:02.019Z] TESTING: [2025-10-17T20:28:04.265Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-17T20:28:04.265Z] 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_17607328808896/renaissance-als_0/launcher-202802-2415097833421891845/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-17T20:28:04.265Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-17T20:28:04.265Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-17T20:28:27.440Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-17T20:28:43.292Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-17T20:28:43.292Z] GC before operation: completed in 221.708 ms, heap usage 171.544 MB -> 35.616 MB. [2025-10-17T20:30:06.298Z] ====== als (apache-spark) [default], iteration 0 completed (66803.670 ms) ====== [2025-10-17T20:30:06.298Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-17T20:30:06.298Z] GC before operation: completed in 606.501 ms, heap usage 177.811 MB -> 75.474 MB. [2025-10-17T20:30:22.129Z] ====== als (apache-spark) [default], iteration 1 completed (25453.207 ms) ====== [2025-10-17T20:30:22.129Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-17T20:30:22.129Z] GC before operation: completed in 661.594 ms, heap usage 218.473 MB -> 76.385 MB. [2025-10-17T20:30:49.902Z] ====== als (apache-spark) [default], iteration 2 completed (22928.383 ms) ====== [2025-10-17T20:30:49.902Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-17T20:30:49.903Z] GC before operation: completed in 704.094 ms, heap usage 569.898 MB -> 77.536 MB. [2025-10-17T20:31:12.967Z] ====== als (apache-spark) [default], iteration 3 completed (22722.832 ms) ====== [2025-10-17T20:31:12.967Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-17T20:31:12.967Z] GC before operation: completed in 793.041 ms, heap usage 204.498 MB -> 77.599 MB. [2025-10-17T20:31:36.000Z] ====== als (apache-spark) [default], iteration 4 completed (21722.593 ms) ====== [2025-10-17T20:31:36.000Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-17T20:31:36.000Z] GC before operation: completed in 742.731 ms, heap usage 284.124 MB -> 78.313 MB. [2025-10-17T20:31:59.030Z] ====== als (apache-spark) [default], iteration 5 completed (21989.535 ms) ====== [2025-10-17T20:31:59.030Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-17T20:31:59.030Z] GC before operation: completed in 777.359 ms, heap usage 1.561 GB -> 80.379 MB. [2025-10-17T20:32:22.065Z] ====== als (apache-spark) [default], iteration 6 completed (22197.247 ms) ====== [2025-10-17T20:32:22.065Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-17T20:32:22.792Z] GC before operation: completed in 781.561 ms, heap usage 232.096 MB -> 79.338 MB. [2025-10-17T20:32:45.839Z] ====== als (apache-spark) [default], iteration 7 completed (21792.483 ms) ====== [2025-10-17T20:32:45.839Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-17T20:32:46.516Z] GC before operation: completed in 847.819 ms, heap usage 1.477 GB -> 81.423 MB. [2025-10-17T20:33:09.602Z] ====== als (apache-spark) [default], iteration 8 completed (21748.991 ms) ====== [2025-10-17T20:33:09.602Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-17T20:33:09.602Z] GC before operation: completed in 804.827 ms, heap usage 492.713 MB -> 80.773 MB. [2025-10-17T20:33:32.634Z] ====== als (apache-spark) [default], iteration 9 completed (21090.441 ms) ====== [2025-10-17T20:33:32.634Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-17T20:33:32.634Z] GC before operation: completed in 834.445 ms, heap usage 178.269 MB -> 80.542 MB. [2025-10-17T20:33:55.687Z] ====== als (apache-spark) [default], iteration 10 completed (21035.978 ms) ====== [2025-10-17T20:33:55.687Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-17T20:33:55.687Z] GC before operation: completed in 839.610 ms, heap usage 563.951 MB -> 81.168 MB. [2025-10-17T20:34:18.712Z] ====== als (apache-spark) [default], iteration 11 completed (20465.607 ms) ====== [2025-10-17T20:34:18.712Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-17T20:34:18.712Z] GC before operation: completed in 852.386 ms, heap usage 486.066 MB -> 81.405 MB. [2025-10-17T20:34:41.743Z] ====== als (apache-spark) [default], iteration 12 completed (20849.703 ms) ====== [2025-10-17T20:34:41.743Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-17T20:34:41.743Z] GC before operation: completed in 872.692 ms, heap usage 196.848 MB -> 81.331 MB. [2025-10-17T20:35:04.906Z] ====== als (apache-spark) [default], iteration 13 completed (20492.078 ms) ====== [2025-10-17T20:35:04.906Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-17T20:35:04.906Z] GC before operation: completed in 879.298 ms, heap usage 472.790 MB -> 82.051 MB. [2025-10-17T20:35:24.037Z] ====== als (apache-spark) [default], iteration 14 completed (20433.370 ms) ====== [2025-10-17T20:35:24.037Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-17T20:35:24.368Z] GC before operation: completed in 895.165 ms, heap usage 134.002 MB -> 82.417 MB. [2025-10-17T20:35:47.408Z] ====== als (apache-spark) [default], iteration 15 completed (20520.684 ms) ====== [2025-10-17T20:35:47.408Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-17T20:35:47.408Z] GC before operation: completed in 919.242 ms, heap usage 296.560 MB -> 82.283 MB. [2025-10-17T20:36:10.422Z] ====== als (apache-spark) [default], iteration 16 completed (20603.804 ms) ====== [2025-10-17T20:36:10.422Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-17T20:36:10.422Z] GC before operation: completed in 918.237 ms, heap usage 304.679 MB -> 82.483 MB. [2025-10-17T20:36:33.449Z] ====== als (apache-spark) [default], iteration 17 completed (20193.890 ms) ====== [2025-10-17T20:36:33.449Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-17T20:36:33.449Z] GC before operation: completed in 925.039 ms, heap usage 309.902 MB -> 82.775 MB. [2025-10-17T20:36:52.683Z] ====== als (apache-spark) [default], iteration 18 completed (20548.490 ms) ====== [2025-10-17T20:36:52.683Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-17T20:36:53.014Z] GC before operation: completed in 937.407 ms, heap usage 623.813 MB -> 83.412 MB. [2025-10-17T20:37:16.053Z] ====== als (apache-spark) [default], iteration 19 completed (20452.722 ms) ====== [2025-10-17T20:37:16.053Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-17T20:37:16.053Z] GC before operation: completed in 931.778 ms, heap usage 585.095 MB -> 83.713 MB. [2025-10-17T20:37:39.098Z] ====== als (apache-spark) [default], iteration 20 completed (21550.723 ms) ====== [2025-10-17T20:37:39.098Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-17T20:37:39.098Z] GC before operation: completed in 931.964 ms, heap usage 474.923 MB -> 83.882 MB. [2025-10-17T20:38:02.112Z] ====== als (apache-spark) [default], iteration 21 completed (20635.171 ms) ====== [2025-10-17T20:38:02.113Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-17T20:38:02.113Z] GC before operation: completed in 949.436 ms, heap usage 217.961 MB -> 83.729 MB. [2025-10-17T20:38:25.448Z] ====== als (apache-spark) [default], iteration 22 completed (20062.952 ms) ====== [2025-10-17T20:38:25.448Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-17T20:38:25.448Z] GC before operation: completed in 960.012 ms, heap usage 190.610 MB -> 83.968 MB. [2025-10-17T20:38:44.553Z] ====== als (apache-spark) [default], iteration 23 completed (19828.777 ms) ====== [2025-10-17T20:38:44.553Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-17T20:38:44.553Z] GC before operation: completed in 955.822 ms, heap usage 294.103 MB -> 84.416 MB. [2025-10-17T20:39:07.597Z] ====== als (apache-spark) [default], iteration 24 completed (20059.342 ms) ====== [2025-10-17T20:39:07.597Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-17T20:39:07.597Z] GC before operation: completed in 968.240 ms, heap usage 533.990 MB -> 85.023 MB. [2025-10-17T20:39:26.716Z] ====== als (apache-spark) [default], iteration 25 completed (19775.430 ms) ====== [2025-10-17T20:39:26.716Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-17T20:39:27.898Z] GC before operation: completed in 993.132 ms, heap usage 272.765 MB -> 84.849 MB. [2025-10-17T20:39:50.940Z] ====== als (apache-spark) [default], iteration 26 completed (20131.533 ms) ====== [2025-10-17T20:39:50.940Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-17T20:39:50.940Z] GC before operation: completed in 984.728 ms, heap usage 258.671 MB -> 85.209 MB. [2025-10-17T20:40:14.126Z] ====== als (apache-spark) [default], iteration 27 completed (20616.470 ms) ====== [2025-10-17T20:40:14.126Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-17T20:40:14.126Z] GC before operation: completed in 977.109 ms, heap usage 447.657 MB -> 85.258 MB. [2025-10-17T20:40:33.262Z] ====== als (apache-spark) [default], iteration 28 completed (19722.052 ms) ====== [2025-10-17T20:40:33.262Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-17T20:40:33.262Z] GC before operation: completed in 987.694 ms, heap usage 566.903 MB -> 85.638 MB. [2025-10-17T20:40:56.291Z] ====== als (apache-spark) [default], iteration 29 completed (19920.356 ms) ====== [2025-10-17T20:40:56.291Z] ----------------------------------- [2025-10-17T20:40:56.291Z] renaissance-als_0_PASSED [2025-10-17T20:40:56.291Z] ----------------------------------- [2025-10-17T20:40:56.291Z] [2025-10-17T20:40:56.291Z] TEST TEARDOWN: [2025-10-17T20:40:56.291Z] Nothing to be done for teardown. [2025-10-17T20:40:56.291Z] renaissance-als_0 Finish Time: Fri Oct 17 20:40:56 2025 Epoch Time (ms): 1760733656016