[2025-12-16T08:57:40.596Z] Running test renaissance-als_0 ... [2025-12-16T08:57:40.596Z] =============================================== [2025-12-16T08:57:40.596Z] renaissance-als_0 Start Time: Tue Dec 16 08:57:40 2025 Epoch Time (ms): 1765875460417 [2025-12-16T08:57:40.596Z] variation: NoOptions [2025-12-16T08:57:40.596Z] JVM_OPTIONS: [2025-12-16T08:57:40.596Z] { \ [2025-12-16T08:57:40.596Z] echo ""; echo "TEST SETUP:"; \ [2025-12-16T08:57:40.596Z] echo "Nothing to be done for setup."; \ [2025-12-16T08:57:40.596Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17658754598044/renaissance-als_0"; \ [2025-12-16T08:57:40.596Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17658754598044/renaissance-als_0"; \ [2025-12-16T08:57:40.596Z] echo ""; echo "TESTING:"; \ [2025-12-16T08:57:40.596Z] "/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_17658754598044/renaissance-als_0"/als.json" als; \ [2025-12-16T08:57:40.596Z] 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_17658754598044/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-16T08:57:40.596Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-16T08:57:40.596Z] echo "Nothing to be done for teardown."; \ [2025-12-16T08:57:40.596Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17658754598044/TestTargetResult"; [2025-12-16T08:57:40.596Z] [2025-12-16T08:57:40.596Z] TEST SETUP: [2025-12-16T08:57:40.596Z] Nothing to be done for setup. [2025-12-16T08:57:40.596Z] [2025-12-16T08:57:40.596Z] TESTING: [2025-12-16T08:57:42.901Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-16T08:57:42.901Z] 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_17658754598044/renaissance-als_0/launcher-085741-17314931768870196260/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-16T08:57:42.901Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-16T08:57:42.901Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-16T08:58:06.122Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-16T08:58:22.023Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-16T08:58:22.023Z] GC before operation: completed in 224.339 ms, heap usage 201.646 MB -> 35.436 MB. [2025-12-16T08:59:31.434Z] ====== als (apache-spark) [default], iteration 0 completed (63915.843 ms) ====== [2025-12-16T08:59:31.434Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-16T08:59:31.434Z] GC before operation: completed in 674.783 ms, heap usage 311.561 MB -> 75.546 MB. [2025-12-16T08:59:54.895Z] ====== als (apache-spark) [default], iteration 1 completed (23227.683 ms) ====== [2025-12-16T08:59:54.895Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-16T08:59:54.895Z] GC before operation: completed in 652.626 ms, heap usage 157.537 MB -> 76.190 MB. [2025-12-16T09:00:18.836Z] ====== als (apache-spark) [default], iteration 2 completed (20510.203 ms) ====== [2025-12-16T09:00:18.836Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-16T09:00:18.836Z] GC before operation: completed in 714.431 ms, heap usage 274.333 MB -> 76.864 MB. [2025-12-16T09:00:38.134Z] ====== als (apache-spark) [default], iteration 3 completed (20460.083 ms) ====== [2025-12-16T09:00:38.134Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-16T09:00:38.871Z] GC before operation: completed in 696.112 ms, heap usage 353.803 MB -> 77.519 MB. [2025-12-16T09:01:02.160Z] ====== als (apache-spark) [default], iteration 4 completed (19067.205 ms) ====== [2025-12-16T09:01:02.160Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-16T09:01:02.160Z] GC before operation: completed in 777.925 ms, heap usage 853.837 MB -> 78.708 MB. [2025-12-16T09:01:21.556Z] ====== als (apache-spark) [default], iteration 5 completed (19746.241 ms) ====== [2025-12-16T09:01:21.556Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-16T09:01:21.556Z] GC before operation: completed in 740.851 ms, heap usage 355.351 MB -> 78.659 MB. [2025-12-16T09:01:45.063Z] ====== als (apache-spark) [default], iteration 6 completed (19891.582 ms) ====== [2025-12-16T09:01:45.063Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-16T09:01:45.063Z] GC before operation: completed in 821.728 ms, heap usage 1.218 GB -> 80.274 MB. [2025-12-16T09:02:04.300Z] ====== als (apache-spark) [default], iteration 7 completed (19155.456 ms) ====== [2025-12-16T09:02:04.300Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-16T09:02:04.300Z] GC before operation: completed in 800.519 ms, heap usage 374.146 MB -> 79.673 MB. [2025-12-16T09:02:24.110Z] ====== als (apache-spark) [default], iteration 8 completed (19458.581 ms) ====== [2025-12-16T09:02:24.110Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-16T09:02:24.968Z] GC before operation: completed in 784.491 ms, heap usage 534.831 MB -> 80.432 MB. [2025-12-16T09:02:43.931Z] ====== als (apache-spark) [default], iteration 9 completed (18427.178 ms) ====== [2025-12-16T09:02:43.931Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-16T09:02:44.667Z] GC before operation: completed in 788.678 ms, heap usage 556.684 MB -> 80.735 MB. [2025-12-16T09:03:07.819Z] ====== als (apache-spark) [default], iteration 10 completed (18768.635 ms) ====== [2025-12-16T09:03:07.819Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-16T09:03:07.819Z] GC before operation: completed in 846.115 ms, heap usage 336.259 MB -> 80.707 MB. [2025-12-16T09:03:27.111Z] ====== als (apache-spark) [default], iteration 11 completed (18694.734 ms) ====== [2025-12-16T09:03:27.111Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-16T09:03:27.111Z] GC before operation: completed in 846.182 ms, heap usage 765.471 MB -> 81.644 MB. [2025-12-16T09:03:46.424Z] ====== als (apache-spark) [default], iteration 12 completed (18473.802 ms) ====== [2025-12-16T09:03:46.424Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-16T09:03:46.424Z] GC before operation: completed in 828.834 ms, heap usage 125.106 MB -> 81.405 MB. [2025-12-16T09:04:09.697Z] ====== als (apache-spark) [default], iteration 13 completed (18351.275 ms) ====== [2025-12-16T09:04:09.697Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-16T09:04:09.697Z] GC before operation: completed in 837.230 ms, heap usage 231.412 MB -> 81.358 MB. [2025-12-16T09:04:25.624Z] ====== als (apache-spark) [default], iteration 14 completed (18012.302 ms) ====== [2025-12-16T09:04:25.624Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-16T09:04:25.624Z] GC before operation: completed in 845.543 ms, heap usage 506.951 MB -> 81.967 MB. [2025-12-16T09:04:45.953Z] ====== als (apache-spark) [default], iteration 15 completed (17850.218 ms) ====== [2025-12-16T09:04:45.953Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-16T09:04:45.953Z] GC before operation: completed in 864.957 ms, heap usage 156.498 MB -> 81.868 MB. [2025-12-16T09:05:09.692Z] ====== als (apache-spark) [default], iteration 16 completed (18376.086 ms) ====== [2025-12-16T09:05:09.692Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-16T09:05:09.692Z] GC before operation: completed in 861.651 ms, heap usage 602.754 MB -> 82.695 MB. [2025-12-16T09:05:26.124Z] ====== als (apache-spark) [default], iteration 17 completed (17891.479 ms) ====== [2025-12-16T09:05:26.124Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-16T09:05:26.124Z] GC before operation: completed in 870.773 ms, heap usage 152.779 MB -> 82.630 MB. [2025-12-16T09:05:45.317Z] ====== als (apache-spark) [default], iteration 18 completed (17627.758 ms) ====== [2025-12-16T09:05:45.317Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-16T09:05:45.317Z] GC before operation: completed in 885.657 ms, heap usage 520.736 MB -> 83.040 MB. [2025-12-16T09:06:04.534Z] ====== als (apache-spark) [default], iteration 19 completed (17584.226 ms) ====== [2025-12-16T09:06:04.534Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-16T09:06:04.534Z] GC before operation: completed in 915.633 ms, heap usage 288.084 MB -> 83.014 MB. [2025-12-16T09:06:23.883Z] ====== als (apache-spark) [default], iteration 20 completed (19004.386 ms) ====== [2025-12-16T09:06:23.883Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-16T09:06:23.883Z] GC before operation: completed in 917.468 ms, heap usage 645.229 MB -> 83.622 MB. [2025-12-16T09:06:43.064Z] ====== als (apache-spark) [default], iteration 21 completed (18014.204 ms) ====== [2025-12-16T09:06:43.064Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-16T09:06:43.402Z] GC before operation: completed in 888.490 ms, heap usage 307.651 MB -> 83.381 MB. [2025-12-16T09:07:02.744Z] ====== als (apache-spark) [default], iteration 22 completed (17558.940 ms) ====== [2025-12-16T09:07:02.744Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-16T09:07:02.744Z] GC before operation: completed in 896.261 ms, heap usage 602.314 MB -> 84.174 MB. [2025-12-16T09:07:22.035Z] ====== als (apache-spark) [default], iteration 23 completed (17580.753 ms) ====== [2025-12-16T09:07:22.035Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-16T09:07:22.035Z] GC before operation: completed in 934.778 ms, heap usage 271.885 MB -> 83.949 MB. [2025-12-16T09:07:41.356Z] ====== als (apache-spark) [default], iteration 24 completed (18230.407 ms) ====== [2025-12-16T09:07:41.356Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-16T09:07:41.696Z] GC before operation: completed in 935.464 ms, heap usage 1.062 GB -> 85.218 MB. [2025-12-16T09:08:00.901Z] ====== als (apache-spark) [default], iteration 25 completed (18161.051 ms) ====== [2025-12-16T09:08:00.901Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-16T09:08:01.646Z] GC before operation: completed in 951.092 ms, heap usage 486.464 MB -> 84.942 MB. [2025-12-16T09:08:20.823Z] ====== als (apache-spark) [default], iteration 26 completed (17450.810 ms) ====== [2025-12-16T09:08:20.823Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-16T09:08:20.823Z] GC before operation: completed in 931.811 ms, heap usage 410.970 MB -> 85.022 MB. [2025-12-16T09:08:40.141Z] ====== als (apache-spark) [default], iteration 27 completed (17046.956 ms) ====== [2025-12-16T09:08:40.141Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-16T09:08:40.141Z] GC before operation: completed in 932.649 ms, heap usage 589.589 MB -> 85.528 MB. [2025-12-16T09:08:59.501Z] ====== als (apache-spark) [default], iteration 28 completed (17880.042 ms) ====== [2025-12-16T09:08:59.501Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-16T09:08:59.501Z] GC before operation: completed in 930.847 ms, heap usage 567.191 MB -> 85.357 MB. [2025-12-16T09:09:18.721Z] ====== als (apache-spark) [default], iteration 29 completed (17066.355 ms) ====== [2025-12-16T09:09:18.721Z] ----------------------------------- [2025-12-16T09:09:18.721Z] renaissance-als_0_PASSED [2025-12-16T09:09:18.721Z] ----------------------------------- [2025-12-16T09:09:18.721Z] [2025-12-16T09:09:18.721Z] TEST TEARDOWN: [2025-12-16T09:09:18.721Z] Nothing to be done for teardown. [2025-12-16T09:09:18.721Z] renaissance-als_0 Finish Time: Tue Dec 16 09:09:18 2025 Epoch Time (ms): 1765876158644