[2026-02-20T22:09:31.486Z] Running test renaissance-als_0 ... [2026-02-20T22:09:31.487Z] =============================================== [2026-02-20T22:09:31.487Z] renaissance-als_0 Start Time: Fri Feb 20 22:09:31 2026 Epoch Time (ms): 1771625371194 [2026-02-20T22:09:31.487Z] variation: NoOptions [2026-02-20T22:09:31.487Z] JVM_OPTIONS: [2026-02-20T22:09:31.487Z] { \ [2026-02-20T22:09:31.487Z] echo ""; echo "TEST SETUP:"; \ [2026-02-20T22:09:31.487Z] echo "Nothing to be done for setup."; \ [2026-02-20T22:09:31.487Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17716251624300/renaissance-als_0"; \ [2026-02-20T22:09:31.487Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17716251624300/renaissance-als_0"; \ [2026-02-20T22:09:31.487Z] echo ""; echo "TESTING:"; \ [2026-02-20T22:09:31.487Z] "/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_17716251624300/renaissance-als_0"/als.json" als; \ [2026-02-20T22:09:31.487Z] 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_17716251624300/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-20T22:09:31.487Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-20T22:09:31.487Z] echo "Nothing to be done for teardown."; \ [2026-02-20T22:09:31.487Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17716251624300/TestTargetResult"; [2026-02-20T22:09:31.487Z] [2026-02-20T22:09:31.487Z] TEST SETUP: [2026-02-20T22:09:31.487Z] Nothing to be done for setup. [2026-02-20T22:09:31.487Z] [2026-02-20T22:09:31.487Z] TESTING: [2026-02-20T22:09:40.775Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-20T22:09:44.107Z] WARNING: An illegal reflective access operation has occurred [2026-02-20T22:09:44.107Z] 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_17716251624300/renaissance-als_0/harness-220932-9300507442830434726/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-20T22:09:44.107Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-20T22:09:44.107Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-20T22:09:44.107Z] WARNING: All illegal access operations will be denied in a future release [2026-02-20T22:09:46.532Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-20T22:09:46.532Z] GC before operation: completed in 56.436 ms, heap usage 318.000 MB -> 34.490 MB. [2026-02-20T22:10:15.158Z] ====== als (apache-spark) [default], iteration 0 completed (27213.210 ms) ====== [2026-02-20T22:10:15.158Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-20T22:10:15.158Z] GC before operation: completed in 120.844 ms, heap usage 380.275 MB -> 60.490 MB. [2026-02-20T22:10:26.588Z] ====== als (apache-spark) [default], iteration 1 completed (11241.350 ms) ====== [2026-02-20T22:10:26.588Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-20T22:10:26.588Z] GC before operation: completed in 103.830 ms, heap usage 223.264 MB -> 61.140 MB. [2026-02-20T22:10:38.088Z] ====== als (apache-spark) [default], iteration 2 completed (10252.161 ms) ====== [2026-02-20T22:10:38.088Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-20T22:10:38.088Z] GC before operation: completed in 110.253 ms, heap usage 411.551 MB -> 61.927 MB. [2026-02-20T22:10:49.669Z] ====== als (apache-spark) [default], iteration 3 completed (10535.595 ms) ====== [2026-02-20T22:10:49.669Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-20T22:10:49.669Z] GC before operation: completed in 111.286 ms, heap usage 228.512 MB -> 62.362 MB. [2026-02-20T22:10:59.152Z] ====== als (apache-spark) [default], iteration 4 completed (10048.252 ms) ====== [2026-02-20T22:10:59.152Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-20T22:10:59.152Z] GC before operation: completed in 111.485 ms, heap usage 315.355 MB -> 62.949 MB. [2026-02-20T22:11:08.769Z] ====== als (apache-spark) [default], iteration 5 completed (9861.727 ms) ====== [2026-02-20T22:11:08.769Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-20T22:11:09.150Z] GC before operation: completed in 122.829 ms, heap usage 238.178 MB -> 63.397 MB. [2026-02-20T22:11:20.743Z] ====== als (apache-spark) [default], iteration 6 completed (10029.690 ms) ====== [2026-02-20T22:11:20.743Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-20T22:11:20.743Z] GC before operation: completed in 147.643 ms, heap usage 290.696 MB -> 63.928 MB. [2026-02-20T22:11:30.464Z] ====== als (apache-spark) [default], iteration 7 completed (10105.503 ms) ====== [2026-02-20T22:11:30.464Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-20T22:11:30.464Z] GC before operation: completed in 133.833 ms, heap usage 240.133 MB -> 64.461 MB. [2026-02-20T22:11:41.957Z] ====== als (apache-spark) [default], iteration 8 completed (9974.003 ms) ====== [2026-02-20T22:11:41.957Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-20T22:11:41.957Z] GC before operation: completed in 121.332 ms, heap usage 367.645 MB -> 65.112 MB. [2026-02-20T22:11:51.503Z] ====== als (apache-spark) [default], iteration 9 completed (9800.426 ms) ====== [2026-02-20T22:11:51.503Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-20T22:11:51.503Z] GC before operation: completed in 122.119 ms, heap usage 239.030 MB -> 65.261 MB. [2026-02-20T22:12:01.086Z] ====== als (apache-spark) [default], iteration 10 completed (9856.074 ms) ====== [2026-02-20T22:12:01.086Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-20T22:12:01.086Z] GC before operation: completed in 124.636 ms, heap usage 367.520 MB -> 65.582 MB. [2026-02-20T22:12:12.689Z] ====== als (apache-spark) [default], iteration 11 completed (9751.675 ms) ====== [2026-02-20T22:12:12.689Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-20T22:12:12.689Z] GC before operation: completed in 125.214 ms, heap usage 237.091 MB -> 65.626 MB. [2026-02-20T22:12:22.419Z] ====== als (apache-spark) [default], iteration 12 completed (9713.110 ms) ====== [2026-02-20T22:12:22.419Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-20T22:12:22.419Z] GC before operation: completed in 120.081 ms, heap usage 329.498 MB -> 66.112 MB. [2026-02-20T22:12:32.029Z] ====== als (apache-spark) [default], iteration 13 completed (9542.808 ms) ====== [2026-02-20T22:12:32.029Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-20T22:12:32.029Z] GC before operation: completed in 144.937 ms, heap usage 222.399 MB -> 66.358 MB. [2026-02-20T22:12:43.525Z] ====== als (apache-spark) [default], iteration 14 completed (9821.785 ms) ====== [2026-02-20T22:12:43.525Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-20T22:12:43.525Z] GC before operation: completed in 156.790 ms, heap usage 149.142 MB -> 66.383 MB. [2026-02-20T22:12:53.065Z] ====== als (apache-spark) [default], iteration 15 completed (9402.278 ms) ====== [2026-02-20T22:12:53.065Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-20T22:12:53.065Z] GC before operation: completed in 125.724 ms, heap usage 233.680 MB -> 66.816 MB. [2026-02-20T22:13:02.691Z] ====== als (apache-spark) [default], iteration 16 completed (9557.629 ms) ====== [2026-02-20T22:13:02.691Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-20T22:13:02.691Z] GC before operation: completed in 136.146 ms, heap usage 226.627 MB -> 67.004 MB. [2026-02-20T22:13:12.348Z] ====== als (apache-spark) [default], iteration 17 completed (9458.080 ms) ====== [2026-02-20T22:13:12.348Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-20T22:13:12.348Z] GC before operation: completed in 128.141 ms, heap usage 285.576 MB -> 67.443 MB. [2026-02-20T22:13:22.128Z] ====== als (apache-spark) [default], iteration 18 completed (9746.379 ms) ====== [2026-02-20T22:13:22.128Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-20T22:13:22.128Z] GC before operation: completed in 127.625 ms, heap usage 214.258 MB -> 67.417 MB. [2026-02-20T22:13:33.586Z] ====== als (apache-spark) [default], iteration 19 completed (9719.778 ms) ====== [2026-02-20T22:13:33.586Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-20T22:13:33.586Z] GC before operation: completed in 129.406 ms, heap usage 205.202 MB -> 67.661 MB. [2026-02-20T22:13:43.304Z] ====== als (apache-spark) [default], iteration 20 completed (9910.046 ms) ====== [2026-02-20T22:13:43.304Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-20T22:13:43.304Z] GC before operation: completed in 135.832 ms, heap usage 363.891 MB -> 68.130 MB. [2026-02-20T22:13:52.840Z] ====== als (apache-spark) [default], iteration 21 completed (9765.327 ms) ====== [2026-02-20T22:13:52.840Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-20T22:13:52.840Z] GC before operation: completed in 127.975 ms, heap usage 233.328 MB -> 68.269 MB. [2026-02-20T22:14:03.844Z] ====== als (apache-spark) [default], iteration 22 completed (9557.863 ms) ====== [2026-02-20T22:14:03.844Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-20T22:14:03.844Z] GC before operation: completed in 128.771 ms, heap usage 334.472 MB -> 68.654 MB. [2026-02-20T22:14:13.400Z] ====== als (apache-spark) [default], iteration 23 completed (9946.924 ms) ====== [2026-02-20T22:14:13.400Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-20T22:14:13.400Z] GC before operation: completed in 127.847 ms, heap usage 120.706 MB -> 68.709 MB. [2026-02-20T22:14:23.560Z] ====== als (apache-spark) [default], iteration 24 completed (9560.869 ms) ====== [2026-02-20T22:14:23.560Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-20T22:14:23.560Z] GC before operation: completed in 133.531 ms, heap usage 595.531 MB -> 69.469 MB. [2026-02-20T22:14:33.179Z] ====== als (apache-spark) [default], iteration 25 completed (9493.876 ms) ====== [2026-02-20T22:14:33.179Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-20T22:14:33.179Z] GC before operation: completed in 131.613 ms, heap usage 232.641 MB -> 69.286 MB. [2026-02-20T22:14:42.666Z] ====== als (apache-spark) [default], iteration 26 completed (9586.416 ms) ====== [2026-02-20T22:14:42.666Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-20T22:14:43.069Z] GC before operation: completed in 131.219 ms, heap usage 341.418 MB -> 69.636 MB. [2026-02-20T22:14:54.452Z] ====== als (apache-spark) [default], iteration 27 completed (9512.526 ms) ====== [2026-02-20T22:14:54.452Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-20T22:14:54.452Z] GC before operation: completed in 132.937 ms, heap usage 239.648 MB -> 69.665 MB. [2026-02-20T22:15:03.989Z] ====== als (apache-spark) [default], iteration 28 completed (9933.881 ms) ====== [2026-02-20T22:15:03.989Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-20T22:15:03.989Z] GC before operation: completed in 130.904 ms, heap usage 156.676 MB -> 69.450 MB. [2026-02-20T22:15:13.628Z] ====== als (apache-spark) [default], iteration 29 completed (9497.084 ms) ====== [2026-02-20T22:15:13.628Z] ----------------------------------- [2026-02-20T22:15:13.628Z] renaissance-als_0_PASSED [2026-02-20T22:15:13.628Z] ----------------------------------- [2026-02-20T22:15:13.628Z] [2026-02-20T22:15:13.628Z] TEST TEARDOWN: [2026-02-20T22:15:13.628Z] Nothing to be done for teardown. [2026-02-20T22:15:13.628Z] renaissance-als_0 Finish Time: Fri Feb 20 22:15:13 2026 Epoch Time (ms): 1771625713367