[2026-04-14T01:04:06.031Z] Running test renaissance-als_0 ... [2026-04-14T01:04:06.031Z] =============================================== [2026-04-14T01:04:06.031Z] renaissance-als_0 Start Time: Tue Apr 14 01:04:05 2026 Epoch Time (ms): 1776128645193 [2026-04-14T01:04:06.031Z] variation: NoOptions [2026-04-14T01:04:06.031Z] JVM_OPTIONS: [2026-04-14T01:04:06.031Z] { \ [2026-04-14T01:04:06.031Z] echo ""; echo "TEST SETUP:"; \ [2026-04-14T01:04:06.031Z] echo "Nothing to be done for setup."; \ [2026-04-14T01:04:06.031Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761284042559/renaissance-als_0"; \ [2026-04-14T01:04:06.031Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761284042559/renaissance-als_0"; \ [2026-04-14T01:04:06.031Z] echo ""; echo "TESTING:"; \ [2026-04-14T01:04:06.031Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_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_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761284042559/renaissance-als_0"/als.json" als; \ [2026-04-14T01:04:06.031Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761284042559/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-14T01:04:06.031Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-14T01:04:06.031Z] echo "Nothing to be done for teardown."; \ [2026-04-14T01:04:06.031Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17761284042559/TestTargetResult"; [2026-04-14T01:04:06.031Z] [2026-04-14T01:04:06.031Z] TEST SETUP: [2026-04-14T01:04:06.031Z] Nothing to be done for setup. [2026-04-14T01:04:06.031Z] [2026-04-14T01:04:06.031Z] TESTING: [2026-04-14T01:04:15.890Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-14T01:04:20.157Z] WARNING: An illegal reflective access operation has occurred [2026-04-14T01:04:20.157Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17761284042559/renaissance-als_0/harness-010406-7253912367490432021/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-14T01:04:20.157Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-14T01:04:20.157Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-14T01:04:20.157Z] WARNING: All illegal access operations will be denied in a future release [2026-04-14T01:04:22.133Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-14T01:04:22.133Z] GC before operation: completed in 109.974 ms, heap usage 343.500 MB -> 34.674 MB. [2026-04-14T01:04:55.894Z] ====== als (apache-spark) [default], iteration 0 completed (30463.994 ms) ====== [2026-04-14T01:04:55.894Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-14T01:04:55.894Z] GC before operation: completed in 183.942 ms, heap usage 483.681 MB -> 60.776 MB. [2026-04-14T01:05:07.792Z] ====== als (apache-spark) [default], iteration 1 completed (12520.917 ms) ====== [2026-04-14T01:05:07.792Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-14T01:05:07.792Z] GC before operation: completed in 226.266 ms, heap usage 178.238 MB -> 61.333 MB. [2026-04-14T01:05:18.361Z] ====== als (apache-spark) [default], iteration 2 completed (10832.942 ms) ====== [2026-04-14T01:05:18.361Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-14T01:05:19.336Z] GC before operation: completed in 210.764 ms, heap usage 187.380 MB -> 61.962 MB. [2026-04-14T01:05:30.910Z] ====== als (apache-spark) [default], iteration 3 completed (11559.280 ms) ====== [2026-04-14T01:05:30.910Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-14T01:05:30.910Z] GC before operation: completed in 182.491 ms, heap usage 202.752 MB -> 62.508 MB. [2026-04-14T01:05:42.743Z] ====== als (apache-spark) [default], iteration 4 completed (11032.459 ms) ====== [2026-04-14T01:05:42.743Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-14T01:05:42.743Z] GC before operation: completed in 211.931 ms, heap usage 169.607 MB -> 62.960 MB. [2026-04-14T01:05:54.570Z] ====== als (apache-spark) [default], iteration 5 completed (11016.259 ms) ====== [2026-04-14T01:05:54.570Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-14T01:05:54.570Z] GC before operation: completed in 217.736 ms, heap usage 266.253 MB -> 63.582 MB. [2026-04-14T01:06:04.443Z] ====== als (apache-spark) [default], iteration 6 completed (10686.393 ms) ====== [2026-04-14T01:06:04.443Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-14T01:06:05.408Z] GC before operation: completed in 202.403 ms, heap usage 235.129 MB -> 64.105 MB. [2026-04-14T01:06:17.111Z] ====== als (apache-spark) [default], iteration 7 completed (10626.776 ms) ====== [2026-04-14T01:06:17.111Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-14T01:06:17.111Z] GC before operation: completed in 205.403 ms, heap usage 238.548 MB -> 64.638 MB. [2026-04-14T01:06:28.694Z] ====== als (apache-spark) [default], iteration 8 completed (11017.380 ms) ====== [2026-04-14T01:06:28.694Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-14T01:06:28.694Z] GC before operation: completed in 200.378 ms, heap usage 210.438 MB -> 65.164 MB. [2026-04-14T01:06:38.638Z] ====== als (apache-spark) [default], iteration 9 completed (10633.934 ms) ====== [2026-04-14T01:06:38.638Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-14T01:06:39.597Z] GC before operation: completed in 222.063 ms, heap usage 267.342 MB -> 65.417 MB. [2026-04-14T01:06:50.186Z] ====== als (apache-spark) [default], iteration 10 completed (10866.781 ms) ====== [2026-04-14T01:06:50.186Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-14T01:06:51.153Z] GC before operation: completed in 220.352 ms, heap usage 220.278 MB -> 65.654 MB. [2026-04-14T01:07:02.797Z] ====== als (apache-spark) [default], iteration 11 completed (10474.565 ms) ====== [2026-04-14T01:07:02.797Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-14T01:07:02.797Z] GC before operation: completed in 214.292 ms, heap usage 270.615 MB -> 65.919 MB. [2026-04-14T01:07:12.740Z] ====== als (apache-spark) [default], iteration 12 completed (10659.052 ms) ====== [2026-04-14T01:07:12.740Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-14T01:07:12.740Z] GC before operation: completed in 198.674 ms, heap usage 253.528 MB -> 66.161 MB. [2026-04-14T01:07:24.447Z] ====== als (apache-spark) [default], iteration 13 completed (10681.726 ms) ====== [2026-04-14T01:07:24.447Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-14T01:07:24.447Z] GC before operation: completed in 209.217 ms, heap usage 266.653 MB -> 66.423 MB. [2026-04-14T01:07:35.883Z] ====== als (apache-spark) [default], iteration 14 completed (10383.868 ms) ====== [2026-04-14T01:07:35.883Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-14T01:07:35.883Z] GC before operation: completed in 204.368 ms, heap usage 272.286 MB -> 66.686 MB. [2026-04-14T01:07:45.644Z] ====== als (apache-spark) [default], iteration 15 completed (10004.016 ms) ====== [2026-04-14T01:07:45.644Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-14T01:07:45.644Z] GC before operation: completed in 211.747 ms, heap usage 282.396 MB -> 67.015 MB. [2026-04-14T01:07:57.174Z] ====== als (apache-spark) [default], iteration 16 completed (10023.819 ms) ====== [2026-04-14T01:07:57.174Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-14T01:07:57.174Z] GC before operation: completed in 228.807 ms, heap usage 715.494 MB -> 67.832 MB. [2026-04-14T01:08:06.947Z] ====== als (apache-spark) [default], iteration 17 completed (10562.493 ms) ====== [2026-04-14T01:08:06.947Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-14T01:08:07.902Z] GC before operation: completed in 217.695 ms, heap usage 256.684 MB -> 67.447 MB. [2026-04-14T01:08:19.504Z] ====== als (apache-spark) [default], iteration 18 completed (10352.925 ms) ====== [2026-04-14T01:08:19.504Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-14T01:08:19.504Z] GC before operation: completed in 223.185 ms, heap usage 319.612 MB -> 67.805 MB. [2026-04-14T01:08:30.278Z] ====== als (apache-spark) [default], iteration 19 completed (10622.207 ms) ====== [2026-04-14T01:08:30.278Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-14T01:08:30.278Z] GC before operation: completed in 219.264 ms, heap usage 324.671 MB -> 68.043 MB. [2026-04-14T01:08:41.273Z] ====== als (apache-spark) [default], iteration 20 completed (10774.300 ms) ====== [2026-04-14T01:08:41.273Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-14T01:08:41.273Z] GC before operation: completed in 229.120 ms, heap usage 376.859 MB -> 68.302 MB. [2026-04-14T01:08:51.822Z] ====== als (apache-spark) [default], iteration 21 completed (10367.431 ms) ====== [2026-04-14T01:08:51.822Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-14T01:08:51.822Z] GC before operation: completed in 248.306 ms, heap usage 515.470 MB -> 68.759 MB. [2026-04-14T01:09:03.631Z] ====== als (apache-spark) [default], iteration 22 completed (10610.581 ms) ====== [2026-04-14T01:09:03.631Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-14T01:09:03.631Z] GC before operation: completed in 228.963 ms, heap usage 266.906 MB -> 68.702 MB. [2026-04-14T01:09:13.736Z] ====== als (apache-spark) [default], iteration 23 completed (10448.829 ms) ====== [2026-04-14T01:09:13.736Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-14T01:09:13.736Z] GC before operation: completed in 229.056 ms, heap usage 373.738 MB -> 69.124 MB. [2026-04-14T01:09:25.539Z] ====== als (apache-spark) [default], iteration 24 completed (10923.264 ms) ====== [2026-04-14T01:09:25.539Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-14T01:09:25.539Z] GC before operation: completed in 217.742 ms, heap usage 195.584 MB -> 69.079 MB. [2026-04-14T01:09:37.226Z] ====== als (apache-spark) [default], iteration 25 completed (10661.263 ms) ====== [2026-04-14T01:09:37.226Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-14T01:09:37.226Z] GC before operation: completed in 230.280 ms, heap usage 409.935 MB -> 69.562 MB. [2026-04-14T01:09:48.988Z] ====== als (apache-spark) [default], iteration 26 completed (10498.301 ms) ====== [2026-04-14T01:09:48.988Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-14T01:09:48.988Z] GC before operation: completed in 231.575 ms, heap usage 100.135 MB -> 69.584 MB. [2026-04-14T01:09:58.794Z] ====== als (apache-spark) [default], iteration 27 completed (10378.302 ms) ====== [2026-04-14T01:09:58.794Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-14T01:09:58.794Z] GC before operation: completed in 239.657 ms, heap usage 167.066 MB -> 69.830 MB. [2026-04-14T01:10:11.341Z] ====== als (apache-spark) [default], iteration 28 completed (10796.883 ms) ====== [2026-04-14T01:10:11.341Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-14T01:10:11.341Z] GC before operation: completed in 213.193 ms, heap usage 154.930 MB -> 70.072 MB. [2026-04-14T01:10:21.229Z] ====== als (apache-spark) [default], iteration 29 completed (10007.418 ms) ====== [2026-04-14T01:10:21.229Z] ----------------------------------- [2026-04-14T01:10:21.229Z] renaissance-als_0_PASSED [2026-04-14T01:10:21.229Z] ----------------------------------- [2026-04-14T01:10:21.229Z] [2026-04-14T01:10:21.229Z] TEST TEARDOWN: [2026-04-14T01:10:21.229Z] Nothing to be done for teardown. [2026-04-14T01:10:21.229Z] renaissance-als_0 Finish Time: Tue Apr 14 01:10:20 2026 Epoch Time (ms): 1776129020939