[2026-01-21T06:59:00.342Z] Running test renaissance-als_0 ... [2026-01-21T06:59:00.342Z] =============================================== [2026-01-21T06:59:00.342Z] renaissance-als_0 Start Time: Wed Jan 21 01:59:00 2026 Epoch Time (ms): 1768978740123 [2026-01-21T06:59:00.342Z] variation: NoOptions [2026-01-21T06:59:00.342Z] JVM_OPTIONS: [2026-01-21T06:59:00.342Z] { \ [2026-01-21T06:59:00.342Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T06:59:00.342Z] echo "Nothing to be done for setup."; \ [2026-01-21T06:59:00.342Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17689787394584/renaissance-als_0"; \ [2026-01-21T06:59:00.342Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17689787394584/renaissance-als_0"; \ [2026-01-21T06:59:00.342Z] echo ""; echo "TESTING:"; \ [2026-01-21T06:59:00.342Z] "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17689787394584/renaissance-als_0"/als.json" als; \ [2026-01-21T06:59:00.342Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17689787394584/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T06:59:00.342Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T06:59:00.342Z] echo "Nothing to be done for teardown."; \ [2026-01-21T06:59:00.342Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17689787394584/TestTargetResult"; [2026-01-21T06:59:00.342Z] [2026-01-21T06:59:00.342Z] TEST SETUP: [2026-01-21T06:59:00.342Z] Nothing to be done for setup. [2026-01-21T06:59:00.342Z] [2026-01-21T06:59:00.342Z] TESTING: [2026-01-21T06:59:06.694Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-21T06:59:07.955Z] WARNING: An illegal reflective access operation has occurred [2026-01-21T06:59:07.955Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/output_17689787394584/renaissance-als_0/harness-015900-8713723601233084002/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-21T06:59:07.955Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-21T06:59:07.955Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-21T06:59:07.955Z] WARNING: All illegal access operations will be denied in a future release [2026-01-21T06:59:09.467Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T06:59:09.467Z] GC before operation: completed in 44.047 ms, heap usage 465.502 MB -> 34.761 MB. [2026-01-21T06:59:23.169Z] ====== als (apache-spark) [default], iteration 0 completed (12210.375 ms) ====== [2026-01-21T06:59:23.169Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T06:59:23.169Z] GC before operation: completed in 68.242 ms, heap usage 259.797 MB -> 60.727 MB. [2026-01-21T06:59:27.202Z] ====== als (apache-spark) [default], iteration 1 completed (4786.261 ms) ====== [2026-01-21T06:59:27.202Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T06:59:27.202Z] GC before operation: completed in 74.521 ms, heap usage 328.795 MB -> 61.668 MB. [2026-01-21T06:59:32.381Z] ====== als (apache-spark) [default], iteration 2 completed (4615.473 ms) ====== [2026-01-21T06:59:32.381Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T06:59:32.381Z] GC before operation: completed in 75.505 ms, heap usage 237.999 MB -> 61.994 MB. [2026-01-21T06:59:36.548Z] ====== als (apache-spark) [default], iteration 3 completed (4401.354 ms) ====== [2026-01-21T06:59:36.548Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T06:59:36.548Z] GC before operation: completed in 71.350 ms, heap usage 284.658 MB -> 62.631 MB. [2026-01-21T06:59:41.762Z] ====== als (apache-spark) [default], iteration 4 completed (4533.539 ms) ====== [2026-01-21T06:59:41.762Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T06:59:41.762Z] GC before operation: completed in 60.825 ms, heap usage 155.595 MB -> 63.312 MB. [2026-01-21T06:59:45.949Z] ====== als (apache-spark) [default], iteration 5 completed (4260.596 ms) ====== [2026-01-21T06:59:45.949Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T06:59:45.949Z] GC before operation: completed in 76.676 ms, heap usage 245.047 MB -> 63.694 MB. [2026-01-21T06:59:51.100Z] ====== als (apache-spark) [default], iteration 6 completed (4439.964 ms) ====== [2026-01-21T06:59:51.100Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T06:59:51.100Z] GC before operation: completed in 97.275 ms, heap usage 281.615 MB -> 64.225 MB. [2026-01-21T06:59:55.233Z] ====== als (apache-spark) [default], iteration 7 completed (4484.376 ms) ====== [2026-01-21T06:59:55.233Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T06:59:55.233Z] GC before operation: completed in 74.850 ms, heap usage 89.113 MB -> 64.654 MB. [2026-01-21T06:59:59.541Z] ====== als (apache-spark) [default], iteration 8 completed (4394.328 ms) ====== [2026-01-21T06:59:59.541Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T06:59:59.919Z] GC before operation: completed in 89.198 ms, heap usage 319.233 MB -> 65.407 MB. [2026-01-21T07:00:04.268Z] ====== als (apache-spark) [default], iteration 9 completed (4002.807 ms) ====== [2026-01-21T07:00:04.268Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T07:00:04.268Z] GC before operation: completed in 74.733 ms, heap usage 394.977 MB -> 65.719 MB. [2026-01-21T07:00:08.440Z] ====== als (apache-spark) [default], iteration 10 completed (4132.047 ms) ====== [2026-01-21T07:00:08.440Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T07:00:08.440Z] GC before operation: completed in 89.052 ms, heap usage 205.808 MB -> 65.720 MB. [2026-01-21T07:00:13.782Z] ====== als (apache-spark) [default], iteration 11 completed (4831.143 ms) ====== [2026-01-21T07:00:13.782Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T07:00:13.782Z] GC before operation: completed in 79.249 ms, heap usage 328.318 MB -> 66.066 MB. [2026-01-21T07:00:18.010Z] ====== als (apache-spark) [default], iteration 12 completed (4197.051 ms) ====== [2026-01-21T07:00:18.010Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T07:00:18.010Z] GC before operation: completed in 67.927 ms, heap usage 243.928 MB -> 66.322 MB. [2026-01-21T07:00:22.150Z] ====== als (apache-spark) [default], iteration 13 completed (4170.511 ms) ====== [2026-01-21T07:00:22.151Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T07:00:22.151Z] GC before operation: completed in 78.394 ms, heap usage 230.695 MB -> 66.554 MB. [2026-01-21T07:00:26.348Z] ====== als (apache-spark) [default], iteration 14 completed (4149.358 ms) ====== [2026-01-21T07:00:26.348Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T07:00:26.348Z] GC before operation: completed in 87.129 ms, heap usage 400.737 MB -> 66.921 MB. [2026-01-21T07:00:32.699Z] ====== als (apache-spark) [default], iteration 15 completed (5271.324 ms) ====== [2026-01-21T07:00:32.699Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T07:00:32.699Z] GC before operation: completed in 73.138 ms, heap usage 253.198 MB -> 67.073 MB. [2026-01-21T07:00:36.917Z] ====== als (apache-spark) [default], iteration 16 completed (4482.635 ms) ====== [2026-01-21T07:00:36.917Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T07:00:36.917Z] GC before operation: completed in 89.594 ms, heap usage 179.032 MB -> 67.244 MB. [2026-01-21T07:00:42.107Z] ====== als (apache-spark) [default], iteration 17 completed (4826.127 ms) ====== [2026-01-21T07:00:42.107Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T07:00:42.107Z] GC before operation: completed in 101.565 ms, heap usage 233.406 MB -> 67.566 MB. [2026-01-21T07:00:46.321Z] ====== als (apache-spark) [default], iteration 18 completed (4565.037 ms) ====== [2026-01-21T07:00:46.321Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T07:00:46.321Z] GC before operation: completed in 95.892 ms, heap usage 406.660 MB -> 67.961 MB. [2026-01-21T07:00:51.506Z] ====== als (apache-spark) [default], iteration 19 completed (4117.293 ms) ====== [2026-01-21T07:00:51.506Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T07:00:51.506Z] GC before operation: completed in 110.826 ms, heap usage 268.300 MB -> 68.187 MB. [2026-01-21T07:00:55.596Z] ====== als (apache-spark) [default], iteration 20 completed (4607.254 ms) ====== [2026-01-21T07:00:55.596Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T07:00:55.596Z] GC before operation: completed in 76.756 ms, heap usage 89.667 MB -> 68.220 MB. [2026-01-21T07:00:59.686Z] ====== als (apache-spark) [default], iteration 21 completed (3762.276 ms) ====== [2026-01-21T07:00:59.686Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T07:00:59.686Z] GC before operation: completed in 89.660 ms, heap usage 620.546 MB -> 69.057 MB. [2026-01-21T07:01:04.893Z] ====== als (apache-spark) [default], iteration 22 completed (4588.145 ms) ====== [2026-01-21T07:01:04.893Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T07:01:04.893Z] GC before operation: completed in 74.815 ms, heap usage 888.880 MB -> 69.586 MB. [2026-01-21T07:01:10.070Z] ====== als (apache-spark) [default], iteration 23 completed (4866.539 ms) ====== [2026-01-21T07:01:10.070Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T07:01:10.070Z] GC before operation: completed in 103.662 ms, heap usage 221.830 MB -> 69.120 MB. [2026-01-21T07:01:15.248Z] ====== als (apache-spark) [default], iteration 24 completed (4786.859 ms) ====== [2026-01-21T07:01:15.248Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T07:01:15.248Z] GC before operation: completed in 83.532 ms, heap usage 314.439 MB -> 69.326 MB. [2026-01-21T07:01:19.498Z] ====== als (apache-spark) [default], iteration 25 completed (4184.785 ms) ====== [2026-01-21T07:01:19.498Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T07:01:19.498Z] GC before operation: completed in 87.110 ms, heap usage 273.369 MB -> 69.664 MB. [2026-01-21T07:01:23.754Z] ====== als (apache-spark) [default], iteration 26 completed (4542.957 ms) ====== [2026-01-21T07:01:23.754Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T07:01:23.754Z] GC before operation: completed in 91.706 ms, heap usage 335.689 MB -> 69.925 MB. [2026-01-21T07:01:27.917Z] ====== als (apache-spark) [default], iteration 27 completed (4128.599 ms) ====== [2026-01-21T07:01:27.917Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T07:01:27.917Z] GC before operation: completed in 85.510 ms, heap usage 266.378 MB -> 70.081 MB. [2026-01-21T07:01:32.307Z] ====== als (apache-spark) [default], iteration 28 completed (4206.164 ms) ====== [2026-01-21T07:01:32.307Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T07:01:32.307Z] GC before operation: completed in 87.772 ms, heap usage 127.953 MB -> 69.731 MB. [2026-01-21T07:01:37.394Z] ====== als (apache-spark) [default], iteration 29 completed (4174.312 ms) ====== [2026-01-21T07:01:37.394Z] ----------------------------------- [2026-01-21T07:01:37.394Z] renaissance-als_0_PASSED [2026-01-21T07:01:37.394Z] ----------------------------------- [2026-01-21T07:01:37.394Z] [2026-01-21T07:01:37.394Z] TEST TEARDOWN: [2026-01-21T07:01:37.394Z] Nothing to be done for teardown. [2026-01-21T07:01:37.394Z] renaissance-als_0 Finish Time: Wed Jan 21 02:01:36 2026 Epoch Time (ms): 1768978896890