[2025-12-20T18:28:38.506Z] Running test renaissance-als_0 ... [2025-12-20T18:28:38.506Z] =============================================== [2025-12-20T18:28:38.506Z] renaissance-als_0 Start Time: Sat Dec 20 18:28:38 2025 Epoch Time (ms): 1766255318475 [2025-12-20T18:28:38.506Z] variation: NoOptions [2025-12-20T18:28:38.506Z] JVM_OPTIONS: [2025-12-20T18:28:38.506Z] { \ [2025-12-20T18:28:38.506Z] echo ""; echo "TEST SETUP:"; \ [2025-12-20T18:28:38.506Z] echo "Nothing to be done for setup."; \ [2025-12-20T18:28:38.506Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17662553176067/renaissance-als_0"; \ [2025-12-20T18:28:38.506Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17662553176067/renaissance-als_0"; \ [2025-12-20T18:28:38.506Z] echo ""; echo "TESTING:"; \ [2025-12-20T18:28:38.506Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_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_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17662553176067/renaissance-als_0"/als.json" als; \ [2025-12-20T18:28:38.506Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17662553176067/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-20T18:28:38.506Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-20T18:28:38.506Z] echo "Nothing to be done for teardown."; \ [2025-12-20T18:28:38.506Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17662553176067/TestTargetResult"; [2025-12-20T18:28:38.834Z] [2025-12-20T18:28:38.834Z] TEST SETUP: [2025-12-20T18:28:38.834Z] Nothing to be done for setup. [2025-12-20T18:28:38.834Z] [2025-12-20T18:28:38.834Z] TESTING: [2025-12-20T18:28:41.107Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-20T18:28:41.107Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux/aqa-tests/TKG/output_17662553176067/renaissance-als_0/launcher-182839-7927133808617205140/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-20T18:28:41.107Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-20T18:28:41.107Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-20T18:29:04.069Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-20T18:29:19.877Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-20T18:29:19.877Z] GC before operation: completed in 244.142 ms, heap usage 201.852 MB -> 35.450 MB. [2025-12-20T18:30:42.891Z] ====== als (apache-spark) [default], iteration 0 completed (71295.595 ms) ====== [2025-12-20T18:30:42.891Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-20T18:30:42.891Z] GC before operation: completed in 715.174 ms, heap usage 731.427 MB -> 75.936 MB. [2025-12-20T18:31:02.016Z] ====== als (apache-spark) [default], iteration 1 completed (25352.607 ms) ====== [2025-12-20T18:31:02.016Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-20T18:31:02.016Z] GC before operation: completed in 679.311 ms, heap usage 689.970 MB -> 76.867 MB. [2025-12-20T18:31:25.034Z] ====== als (apache-spark) [default], iteration 2 completed (21847.627 ms) ====== [2025-12-20T18:31:25.034Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-20T18:31:25.415Z] GC before operation: completed in 697.119 ms, heap usage 403.281 MB -> 76.982 MB. [2025-12-20T18:31:48.411Z] ====== als (apache-spark) [default], iteration 3 completed (21812.652 ms) ====== [2025-12-20T18:31:48.411Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-20T18:31:49.120Z] GC before operation: completed in 733.821 ms, heap usage 222.658 MB -> 77.307 MB. [2025-12-20T18:32:12.144Z] ====== als (apache-spark) [default], iteration 4 completed (20209.689 ms) ====== [2025-12-20T18:32:12.144Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-20T18:32:12.144Z] GC before operation: completed in 725.121 ms, heap usage 548.240 MB -> 78.431 MB. [2025-12-20T18:32:35.454Z] ====== als (apache-spark) [default], iteration 5 completed (19881.210 ms) ====== [2025-12-20T18:32:35.454Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-20T18:32:35.454Z] GC before operation: completed in 738.723 ms, heap usage 307.175 MB -> 78.614 MB. [2025-12-20T18:32:54.534Z] ====== als (apache-spark) [default], iteration 6 completed (19384.581 ms) ====== [2025-12-20T18:32:54.534Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-20T18:32:54.534Z] GC before operation: completed in 786.686 ms, heap usage 517.113 MB -> 79.505 MB. [2025-12-20T18:33:13.620Z] ====== als (apache-spark) [default], iteration 7 completed (19358.028 ms) ====== [2025-12-20T18:33:13.620Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-20T18:33:14.770Z] GC before operation: completed in 780.363 ms, heap usage 493.774 MB -> 79.860 MB. [2025-12-20T18:33:37.756Z] ====== als (apache-spark) [default], iteration 8 completed (19294.223 ms) ====== [2025-12-20T18:33:37.756Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-20T18:33:37.756Z] GC before operation: completed in 788.359 ms, heap usage 243.830 MB -> 80.045 MB. [2025-12-20T18:33:56.883Z] ====== als (apache-spark) [default], iteration 9 completed (19264.943 ms) ====== [2025-12-20T18:33:56.883Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-20T18:33:56.883Z] GC before operation: completed in 848.811 ms, heap usage 596.794 MB -> 80.778 MB. [2025-12-20T18:34:19.884Z] ====== als (apache-spark) [default], iteration 10 completed (19547.314 ms) ====== [2025-12-20T18:34:19.884Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-20T18:34:19.884Z] GC before operation: completed in 808.291 ms, heap usage 563.602 MB -> 81.097 MB. [2025-12-20T18:34:38.973Z] ====== als (apache-spark) [default], iteration 11 completed (19173.276 ms) ====== [2025-12-20T18:34:38.973Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-20T18:34:38.973Z] GC before operation: completed in 802.499 ms, heap usage 285.553 MB -> 80.948 MB. [2025-12-20T18:35:01.977Z] ====== als (apache-spark) [default], iteration 12 completed (19112.556 ms) ====== [2025-12-20T18:35:01.977Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-20T18:35:01.977Z] GC before operation: completed in 849.383 ms, heap usage 288.966 MB -> 81.230 MB. [2025-12-20T18:35:21.070Z] ====== als (apache-spark) [default], iteration 13 completed (19166.813 ms) ====== [2025-12-20T18:35:21.070Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-20T18:35:21.070Z] GC before operation: completed in 863.643 ms, heap usage 544.836 MB -> 81.937 MB. [2025-12-20T18:35:40.171Z] ====== als (apache-spark) [default], iteration 14 completed (18718.868 ms) ====== [2025-12-20T18:35:40.171Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-20T18:35:40.506Z] GC before operation: completed in 877.949 ms, heap usage 263.218 MB -> 81.764 MB. [2025-12-20T18:36:03.497Z] ====== als (apache-spark) [default], iteration 15 completed (18790.045 ms) ====== [2025-12-20T18:36:03.497Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-20T18:36:03.497Z] GC before operation: completed in 861.489 ms, heap usage 196.666 MB -> 81.931 MB. [2025-12-20T18:36:22.589Z] ====== als (apache-spark) [default], iteration 16 completed (18467.139 ms) ====== [2025-12-20T18:36:22.589Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-20T18:36:22.589Z] GC before operation: completed in 889.166 ms, heap usage 467.395 MB -> 82.559 MB. [2025-12-20T18:36:42.232Z] ====== als (apache-spark) [default], iteration 17 completed (19044.061 ms) ====== [2025-12-20T18:36:42.232Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-20T18:36:42.232Z] GC before operation: completed in 901.776 ms, heap usage 502.141 MB -> 82.937 MB. [2025-12-20T18:37:01.300Z] ====== als (apache-spark) [default], iteration 18 completed (18112.523 ms) ====== [2025-12-20T18:37:01.300Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-20T18:37:02.022Z] GC before operation: completed in 893.194 ms, heap usage 286.897 MB -> 82.752 MB. [2025-12-20T18:37:21.087Z] ====== als (apache-spark) [default], iteration 19 completed (18313.462 ms) ====== [2025-12-20T18:37:21.087Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-20T18:37:21.812Z] GC before operation: completed in 904.518 ms, heap usage 560.782 MB -> 83.494 MB. [2025-12-20T18:37:44.818Z] ====== als (apache-spark) [default], iteration 20 completed (19075.419 ms) ====== [2025-12-20T18:37:44.818Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-20T18:37:44.818Z] GC before operation: completed in 904.530 ms, heap usage 150.450 MB -> 83.191 MB. [2025-12-20T18:38:03.904Z] ====== als (apache-spark) [default], iteration 21 completed (18147.462 ms) ====== [2025-12-20T18:38:03.904Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-20T18:38:03.904Z] GC before operation: completed in 918.554 ms, heap usage 481.257 MB -> 83.968 MB. [2025-12-20T18:38:23.016Z] ====== als (apache-spark) [default], iteration 22 completed (18040.695 ms) ====== [2025-12-20T18:38:23.016Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-20T18:38:23.016Z] GC before operation: completed in 934.546 ms, heap usage 238.372 MB -> 83.921 MB. [2025-12-20T18:38:42.190Z] ====== als (apache-spark) [default], iteration 23 completed (18178.827 ms) ====== [2025-12-20T18:38:42.190Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-20T18:38:42.190Z] GC before operation: completed in 934.885 ms, heap usage 479.497 MB -> 84.554 MB. [2025-12-20T18:39:01.704Z] ====== als (apache-spark) [default], iteration 24 completed (18862.532 ms) ====== [2025-12-20T18:39:01.704Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-20T18:39:02.424Z] GC before operation: completed in 926.078 ms, heap usage 569.824 MB -> 84.978 MB. [2025-12-20T18:39:21.539Z] ====== als (apache-spark) [default], iteration 25 completed (17958.595 ms) ====== [2025-12-20T18:39:21.539Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-20T18:39:22.261Z] GC before operation: completed in 973.995 ms, heap usage 284.528 MB -> 84.741 MB. [2025-12-20T18:39:41.356Z] ====== als (apache-spark) [default], iteration 26 completed (17773.496 ms) ====== [2025-12-20T18:39:41.356Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-20T18:39:41.685Z] GC before operation: completed in 963.284 ms, heap usage 604.035 MB -> 85.346 MB. [2025-12-20T18:40:00.884Z] ====== als (apache-spark) [default], iteration 27 completed (18130.276 ms) ====== [2025-12-20T18:40:00.884Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-20T18:40:01.589Z] GC before operation: completed in 956.610 ms, heap usage 538.643 MB -> 85.157 MB. [2025-12-20T18:40:24.589Z] ====== als (apache-spark) [default], iteration 28 completed (18883.742 ms) ====== [2025-12-20T18:40:24.589Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-20T18:40:24.589Z] GC before operation: completed in 956.556 ms, heap usage 292.275 MB -> 84.985 MB. [2025-12-20T18:40:43.667Z] ====== als (apache-spark) [default], iteration 29 completed (17927.015 ms) ====== [2025-12-20T18:40:43.667Z] ----------------------------------- [2025-12-20T18:40:43.667Z] renaissance-als_0_PASSED [2025-12-20T18:40:43.667Z] ----------------------------------- [2025-12-20T18:40:43.667Z] [2025-12-20T18:40:43.667Z] TEST TEARDOWN: [2025-12-20T18:40:43.667Z] Nothing to be done for teardown. [2025-12-20T18:40:43.667Z] renaissance-als_0 Finish Time: Sat Dec 20 18:40:42 2025 Epoch Time (ms): 1766256042912