[2026-03-18T04:00:27.992Z] Running test renaissance-als_0 ... [2026-03-18T04:00:27.992Z] =============================================== [2026-03-18T04:00:27.993Z] renaissance-als_0 Start Time: Wed Mar 18 04:00:27 2026 Epoch Time (ms): 1773806427656 [2026-03-18T04:00:27.993Z] variation: NoOptions [2026-03-18T04:00:27.993Z] JVM_OPTIONS: [2026-03-18T04:00:27.993Z] { \ [2026-03-18T04:00:27.993Z] echo ""; echo "TEST SETUP:"; \ [2026-03-18T04:00:27.993Z] echo "Nothing to be done for setup."; \ [2026-03-18T04:00:27.993Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17738064275686/renaissance-als_0"; \ [2026-03-18T04:00:27.993Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17738064275686/renaissance-als_0"; \ [2026-03-18T04:00:27.993Z] echo ""; echo "TESTING:"; \ [2026-03-18T04:00:27.993Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/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_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17738064275686/renaissance-als_0"/als.json" als; \ [2026-03-18T04:00:27.993Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17738064275686/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-18T04:00:27.993Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-18T04:00:27.993Z] echo "Nothing to be done for teardown."; \ [2026-03-18T04:00:27.993Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17738064275686/TestTargetResult"; [2026-03-18T04:00:27.993Z] [2026-03-18T04:00:27.993Z] TEST SETUP: [2026-03-18T04:00:27.993Z] Nothing to be done for setup. [2026-03-18T04:00:27.993Z] [2026-03-18T04:00:27.993Z] TESTING: [2026-03-18T04:00:30.113Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-03-18T04:00:30.114Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (resource:/shared/scala3-library_3-3.3.4.jar) [2026-03-18T04:00:30.114Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-03-18T04:00:30.114Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-03-18T04:00:42.554Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-03-18T04:00:49.078Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-18T04:00:49.078Z] GC before operation: completed in 44.489 ms, heap usage 53.500 MB -> 35.885 MB. [2026-03-18T04:01:15.230Z] ====== als (apache-spark) [default], iteration 0 completed (24497.048 ms) ====== [2026-03-18T04:01:15.230Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-18T04:01:15.230Z] GC before operation: completed in 138.335 ms, heap usage 371.715 MB -> 63.325 MB. [2026-03-18T04:01:22.482Z] ====== als (apache-spark) [default], iteration 1 completed (7707.878 ms) ====== [2026-03-18T04:01:22.482Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-18T04:01:23.518Z] GC before operation: completed in 110.382 ms, heap usage 570.445 MB -> 64.639 MB. [2026-03-18T04:01:31.329Z] ====== als (apache-spark) [default], iteration 2 completed (7395.280 ms) ====== [2026-03-18T04:01:31.329Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-18T04:01:31.329Z] GC before operation: completed in 112.974 ms, heap usage 965.562 MB -> 65.721 MB. [2026-03-18T04:01:38.690Z] ====== als (apache-spark) [default], iteration 3 completed (7177.382 ms) ====== [2026-03-18T04:01:38.690Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-18T04:01:38.690Z] GC before operation: completed in 124.324 ms, heap usage 549.830 MB -> 65.813 MB. [2026-03-18T04:01:46.773Z] ====== als (apache-spark) [default], iteration 4 completed (7198.948 ms) ====== [2026-03-18T04:01:46.773Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-18T04:01:46.773Z] GC before operation: completed in 129.741 ms, heap usage 394.784 MB -> 66.093 MB. [2026-03-18T04:01:52.764Z] ====== als (apache-spark) [default], iteration 5 completed (7210.464 ms) ====== [2026-03-18T04:01:52.764Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-18T04:01:53.799Z] GC before operation: completed in 148.377 ms, heap usage 621.063 MB -> 66.985 MB. [2026-03-18T04:01:59.809Z] ====== als (apache-spark) [default], iteration 6 completed (6319.440 ms) ====== [2026-03-18T04:01:59.809Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-18T04:01:59.809Z] GC before operation: completed in 123.546 ms, heap usage 722.571 MB -> 67.670 MB. [2026-03-18T04:02:07.613Z] ====== als (apache-spark) [default], iteration 7 completed (6776.188 ms) ====== [2026-03-18T04:02:07.613Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-18T04:02:07.613Z] GC before operation: completed in 137.786 ms, heap usage 657.063 MB -> 68.156 MB. [2026-03-18T04:02:13.087Z] ====== als (apache-spark) [default], iteration 8 completed (6093.047 ms) ====== [2026-03-18T04:02:13.087Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-18T04:02:13.087Z] GC before operation: completed in 140.121 ms, heap usage 554.505 MB -> 68.524 MB. [2026-03-18T04:02:20.945Z] ====== als (apache-spark) [default], iteration 9 completed (6511.771 ms) ====== [2026-03-18T04:02:20.945Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-18T04:02:20.945Z] GC before operation: completed in 134.075 ms, heap usage 318.373 MB -> 68.635 MB. [2026-03-18T04:02:26.761Z] ====== als (apache-spark) [default], iteration 10 completed (6245.286 ms) ====== [2026-03-18T04:02:26.761Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-18T04:02:26.761Z] GC before operation: completed in 145.281 ms, heap usage 579.367 MB -> 69.192 MB. [2026-03-18T04:02:32.584Z] ====== als (apache-spark) [default], iteration 11 completed (5735.391 ms) ====== [2026-03-18T04:02:32.584Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-18T04:02:32.584Z] GC before operation: completed in 201.296 ms, heap usage 453.986 MB -> 69.312 MB. [2026-03-18T04:02:38.450Z] ====== als (apache-spark) [default], iteration 12 completed (5757.558 ms) ====== [2026-03-18T04:02:38.450Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-18T04:02:39.496Z] GC before operation: completed in 130.166 ms, heap usage 316.744 MB -> 69.456 MB. [2026-03-18T04:02:45.329Z] ====== als (apache-spark) [default], iteration 13 completed (5916.084 ms) ====== [2026-03-18T04:02:45.329Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-18T04:02:45.329Z] GC before operation: completed in 158.854 ms, heap usage 1.050 GB -> 70.597 MB. [2026-03-18T04:02:51.275Z] ====== als (apache-spark) [default], iteration 14 completed (5917.501 ms) ====== [2026-03-18T04:02:51.275Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-18T04:02:51.275Z] GC before operation: completed in 144.735 ms, heap usage 462.321 MB -> 70.030 MB. [2026-03-18T04:02:57.192Z] ====== als (apache-spark) [default], iteration 15 completed (5627.892 ms) ====== [2026-03-18T04:02:57.192Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-18T04:02:57.192Z] GC before operation: completed in 151.632 ms, heap usage 206.450 MB -> 70.026 MB. [2026-03-18T04:03:03.022Z] ====== als (apache-spark) [default], iteration 16 completed (5457.493 ms) ====== [2026-03-18T04:03:03.022Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-18T04:03:03.022Z] GC before operation: completed in 152.724 ms, heap usage 1.035 GB -> 71.248 MB. [2026-03-18T04:03:08.838Z] ====== als (apache-spark) [default], iteration 17 completed (5412.748 ms) ====== [2026-03-18T04:03:08.838Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-18T04:03:08.838Z] GC before operation: completed in 135.399 ms, heap usage 1023.719 MB -> 71.530 MB. [2026-03-18T04:03:14.645Z] ====== als (apache-spark) [default], iteration 18 completed (5472.140 ms) ====== [2026-03-18T04:03:14.645Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-18T04:03:14.645Z] GC before operation: completed in 154.778 ms, heap usage 913.520 MB -> 71.613 MB. [2026-03-18T04:03:20.470Z] ====== als (apache-spark) [default], iteration 19 completed (5627.615 ms) ====== [2026-03-18T04:03:20.470Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-18T04:03:20.470Z] GC before operation: completed in 137.110 ms, heap usage 962.353 MB -> 72.055 MB. [2026-03-18T04:03:27.094Z] ====== als (apache-spark) [default], iteration 20 completed (5932.460 ms) ====== [2026-03-18T04:03:27.094Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-18T04:03:27.094Z] GC before operation: completed in 148.815 ms, heap usage 921.246 MB -> 72.174 MB. [2026-03-18T04:03:32.910Z] ====== als (apache-spark) [default], iteration 21 completed (5656.896 ms) ====== [2026-03-18T04:03:32.910Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-18T04:03:32.910Z] GC before operation: completed in 170.318 ms, heap usage 1.008 GB -> 72.560 MB. [2026-03-18T04:03:38.718Z] ====== als (apache-spark) [default], iteration 22 completed (5617.179 ms) ====== [2026-03-18T04:03:38.718Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-18T04:03:38.718Z] GC before operation: completed in 166.959 ms, heap usage 915.672 MB -> 72.633 MB. [2026-03-18T04:03:44.526Z] ====== als (apache-spark) [default], iteration 23 completed (5523.356 ms) ====== [2026-03-18T04:03:44.526Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-18T04:03:44.526Z] GC before operation: completed in 141.167 ms, heap usage 1.082 GB -> 73.154 MB. [2026-03-18T04:03:50.350Z] ====== als (apache-spark) [default], iteration 24 completed (5549.041 ms) ====== [2026-03-18T04:03:50.350Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-18T04:03:50.350Z] GC before operation: completed in 170.509 ms, heap usage 305.378 MB -> 72.453 MB. [2026-03-18T04:03:56.233Z] ====== als (apache-spark) [default], iteration 25 completed (5228.676 ms) ====== [2026-03-18T04:03:56.233Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-18T04:03:56.233Z] GC before operation: completed in 170.981 ms, heap usage 565.952 MB -> 73.095 MB. [2026-03-18T04:04:02.035Z] ====== als (apache-spark) [default], iteration 26 completed (5251.034 ms) ====== [2026-03-18T04:04:02.035Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-18T04:04:02.035Z] GC before operation: completed in 166.158 ms, heap usage 320.813 MB -> 72.963 MB. [2026-03-18T04:04:07.846Z] ====== als (apache-spark) [default], iteration 27 completed (5392.261 ms) ====== [2026-03-18T04:04:07.846Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-18T04:04:07.846Z] GC before operation: completed in 149.322 ms, heap usage 431.517 MB -> 73.019 MB. [2026-03-18T04:04:12.340Z] ====== als (apache-spark) [default], iteration 28 completed (5234.612 ms) ====== [2026-03-18T04:04:12.340Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-18T04:04:12.340Z] GC before operation: completed in 154.069 ms, heap usage 1.107 GB -> 73.951 MB. [2026-03-18T04:04:18.145Z] ====== als (apache-spark) [default], iteration 29 completed (5107.504 ms) ====== [2026-03-18T04:04:19.180Z] ----------------------------------- [2026-03-18T04:04:19.181Z] renaissance-als_0_PASSED [2026-03-18T04:04:19.181Z] ----------------------------------- [2026-03-18T04:04:19.181Z] [2026-03-18T04:04:19.181Z] TEST TEARDOWN: [2026-03-18T04:04:19.181Z] Nothing to be done for teardown. [2026-03-18T04:04:19.181Z] renaissance-als_0 Finish Time: Wed Mar 18 04:04:18 2026 Epoch Time (ms): 1773806658459