[2026-03-05T02:19:42.737Z] Running test renaissance-als_0 ... [2026-03-05T02:19:42.737Z] =============================================== [2026-03-05T02:19:42.737Z] renaissance-als_0 Start Time: Thu Mar 5 02:19:42 2026 Epoch Time (ms): 1772677182177 [2026-03-05T02:19:42.737Z] variation: NoOptions [2026-03-05T02:19:42.737Z] JVM_OPTIONS: [2026-03-05T02:19:42.737Z] { \ [2026-03-05T02:19:42.737Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T02:19:42.737Z] echo "Nothing to be done for setup."; \ [2026-03-05T02:19:42.737Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17726771819423/renaissance-als_0"; \ [2026-03-05T02:19:42.737Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17726771819423/renaissance-als_0"; \ [2026-03-05T02:19:42.737Z] echo ""; echo "TESTING:"; \ [2026-03-05T02:19:42.737Z] "/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_17726771819423/renaissance-als_0"/als.json" als; \ [2026-03-05T02:19:42.737Z] 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_17726771819423/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T02:19:42.737Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T02:19:42.737Z] echo "Nothing to be done for teardown."; \ [2026-03-05T02:19:42.737Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17726771819423/TestTargetResult"; [2026-03-05T02:19:42.737Z] [2026-03-05T02:19:42.737Z] TEST SETUP: [2026-03-05T02:19:42.737Z] Nothing to be done for setup. [2026-03-05T02:19:42.737Z] [2026-03-05T02:19:42.737Z] TESTING: [2026-03-05T02:19:52.516Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-03-05T02:19:54.479Z] WARNING: An illegal reflective access operation has occurred [2026-03-05T02:19:54.479Z] 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_17726771819423/renaissance-als_0/harness-021943-13308102831507937164/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-03-05T02:19:54.479Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-03-05T02:19:54.479Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-03-05T02:19:54.479Z] WARNING: All illegal access operations will be denied in a future release [2026-03-05T02:19:56.440Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-05T02:19:56.440Z] GC before operation: completed in 128.024 ms, heap usage 275.525 MB -> 34.643 MB. [2026-03-05T02:20:25.045Z] ====== als (apache-spark) [default], iteration 0 completed (25048.223 ms) ====== [2026-03-05T02:20:25.045Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-05T02:20:25.045Z] GC before operation: completed in 188.856 ms, heap usage 138.139 MB -> 60.513 MB. [2026-03-05T02:20:33.287Z] ====== als (apache-spark) [default], iteration 1 completed (10251.581 ms) ====== [2026-03-05T02:20:33.287Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-05T02:20:33.287Z] GC before operation: completed in 208.653 ms, heap usage 177.983 MB -> 61.313 MB. [2026-03-05T02:20:44.867Z] ====== als (apache-spark) [default], iteration 2 completed (9845.676 ms) ====== [2026-03-05T02:20:44.867Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-05T02:20:44.867Z] GC before operation: completed in 198.943 ms, heap usage 583.759 MB -> 62.453 MB. [2026-03-05T02:20:53.069Z] ====== als (apache-spark) [default], iteration 3 completed (8558.584 ms) ====== [2026-03-05T02:20:53.069Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-05T02:20:53.069Z] GC before operation: completed in 198.385 ms, heap usage 314.726 MB -> 62.541 MB. [2026-03-05T02:21:02.897Z] ====== als (apache-spark) [default], iteration 4 completed (8734.900 ms) ====== [2026-03-05T02:21:02.897Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-05T02:21:02.897Z] GC before operation: completed in 211.532 ms, heap usage 264.275 MB -> 63.028 MB. [2026-03-05T02:21:12.710Z] ====== als (apache-spark) [default], iteration 5 completed (10394.822 ms) ====== [2026-03-05T02:21:12.710Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-05T02:21:12.710Z] GC before operation: completed in 205.396 ms, heap usage 90.076 MB -> 63.445 MB. [2026-03-05T02:21:22.528Z] ====== als (apache-spark) [default], iteration 6 completed (8544.235 ms) ====== [2026-03-05T02:21:22.528Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-05T02:21:22.528Z] GC before operation: completed in 303.137 ms, heap usage 214.576 MB -> 64.120 MB. [2026-03-05T02:21:32.377Z] ====== als (apache-spark) [default], iteration 7 completed (9028.283 ms) ====== [2026-03-05T02:21:32.377Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-05T02:21:32.377Z] GC before operation: completed in 221.031 ms, heap usage 328.198 MB -> 64.750 MB. [2026-03-05T02:21:40.757Z] ====== als (apache-spark) [default], iteration 8 completed (7865.907 ms) ====== [2026-03-05T02:21:40.757Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-05T02:21:40.757Z] GC before operation: completed in 216.544 ms, heap usage 368.063 MB -> 65.413 MB. [2026-03-05T02:21:50.540Z] ====== als (apache-spark) [default], iteration 9 completed (9545.513 ms) ====== [2026-03-05T02:21:50.540Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-05T02:21:50.540Z] GC before operation: completed in 262.811 ms, heap usage 164.813 MB -> 65.333 MB. [2026-03-05T02:22:00.349Z] ====== als (apache-spark) [default], iteration 10 completed (9725.487 ms) ====== [2026-03-05T02:22:00.349Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-05T02:22:00.349Z] GC before operation: completed in 340.364 ms, heap usage 318.132 MB -> 65.693 MB. [2026-03-05T02:22:10.141Z] ====== als (apache-spark) [default], iteration 11 completed (8931.364 ms) ====== [2026-03-05T02:22:10.141Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-05T02:22:10.141Z] GC before operation: completed in 324.464 ms, heap usage 615.466 MB -> 66.354 MB. [2026-03-05T02:22:19.947Z] ====== als (apache-spark) [default], iteration 12 completed (9104.334 ms) ====== [2026-03-05T02:22:19.947Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-05T02:22:19.947Z] GC before operation: completed in 207.978 ms, heap usage 324.933 MB -> 66.277 MB. [2026-03-05T02:22:28.138Z] ====== als (apache-spark) [default], iteration 13 completed (7686.768 ms) ====== [2026-03-05T02:22:28.138Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-05T02:22:28.138Z] GC before operation: completed in 185.044 ms, heap usage 257.885 MB -> 66.415 MB. [2026-03-05T02:22:36.658Z] ====== als (apache-spark) [default], iteration 14 completed (8489.138 ms) ====== [2026-03-05T02:22:36.658Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-05T02:22:36.658Z] GC before operation: completed in 219.035 ms, heap usage 241.328 MB -> 66.675 MB. [2026-03-05T02:22:46.516Z] ====== als (apache-spark) [default], iteration 15 completed (9178.079 ms) ====== [2026-03-05T02:22:46.516Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-05T02:22:46.516Z] GC before operation: completed in 260.667 ms, heap usage 257.835 MB -> 66.973 MB. [2026-03-05T02:22:56.318Z] ====== als (apache-spark) [default], iteration 16 completed (9106.187 ms) ====== [2026-03-05T02:22:56.318Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-05T02:22:56.318Z] GC before operation: completed in 251.751 ms, heap usage 217.745 MB -> 67.216 MB. [2026-03-05T02:23:06.128Z] ====== als (apache-spark) [default], iteration 17 completed (8205.226 ms) ====== [2026-03-05T02:23:06.128Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-05T02:23:06.128Z] GC before operation: completed in 242.224 ms, heap usage 181.216 MB -> 67.342 MB. [2026-03-05T02:23:14.324Z] ====== als (apache-spark) [default], iteration 18 completed (8443.443 ms) ====== [2026-03-05T02:23:14.324Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-05T02:23:14.324Z] GC before operation: completed in 289.950 ms, heap usage 482.150 MB -> 68.071 MB. [2026-03-05T02:23:24.180Z] ====== als (apache-spark) [default], iteration 19 completed (9535.160 ms) ====== [2026-03-05T02:23:24.180Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-05T02:23:24.180Z] GC before operation: completed in 309.010 ms, heap usage 154.951 MB -> 67.834 MB. [2026-03-05T02:23:33.966Z] ====== als (apache-spark) [default], iteration 20 completed (8720.904 ms) ====== [2026-03-05T02:23:33.966Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-05T02:23:33.966Z] GC before operation: completed in 202.413 ms, heap usage 375.034 MB -> 68.304 MB. [2026-03-05T02:23:42.198Z] ====== als (apache-spark) [default], iteration 21 completed (8082.800 ms) ====== [2026-03-05T02:23:42.198Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-05T02:23:42.198Z] GC before operation: completed in 250.810 ms, heap usage 275.937 MB -> 68.491 MB. [2026-03-05T02:23:50.403Z] ====== als (apache-spark) [default], iteration 22 completed (8239.647 ms) ====== [2026-03-05T02:23:50.403Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-05T02:23:50.403Z] GC before operation: completed in 224.258 ms, heap usage 227.032 MB -> 68.719 MB. [2026-03-05T02:24:00.243Z] ====== als (apache-spark) [default], iteration 23 completed (8242.543 ms) ====== [2026-03-05T02:24:00.243Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-05T02:24:00.243Z] GC before operation: completed in 222.217 ms, heap usage 208.556 MB -> 68.969 MB. [2026-03-05T02:24:08.492Z] ====== als (apache-spark) [default], iteration 24 completed (8776.034 ms) ====== [2026-03-05T02:24:08.492Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-05T02:24:08.492Z] GC before operation: completed in 256.789 ms, heap usage 168.236 MB -> 69.099 MB. [2026-03-05T02:24:18.297Z] ====== als (apache-spark) [default], iteration 25 completed (8790.104 ms) ====== [2026-03-05T02:24:18.297Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-05T02:24:18.297Z] GC before operation: completed in 276.169 ms, heap usage 362.087 MB -> 69.576 MB. [2026-03-05T02:24:26.492Z] ====== als (apache-spark) [default], iteration 26 completed (8049.399 ms) ====== [2026-03-05T02:24:26.492Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-05T02:24:26.492Z] GC before operation: completed in 253.278 ms, heap usage 281.233 MB -> 69.766 MB. [2026-03-05T02:24:36.378Z] ====== als (apache-spark) [default], iteration 27 completed (8552.690 ms) ====== [2026-03-05T02:24:36.378Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-05T02:24:36.378Z] GC before operation: completed in 222.697 ms, heap usage 179.962 MB -> 69.391 MB. [2026-03-05T02:24:43.294Z] ====== als (apache-spark) [default], iteration 28 completed (7714.427 ms) ====== [2026-03-05T02:24:43.294Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-05T02:24:44.254Z] GC before operation: completed in 244.230 ms, heap usage 692.770 MB -> 70.281 MB. [2026-03-05T02:24:52.438Z] ====== als (apache-spark) [default], iteration 29 completed (8154.425 ms) ====== [2026-03-05T02:24:53.393Z] ----------------------------------- [2026-03-05T02:24:53.393Z] renaissance-als_0_PASSED [2026-03-05T02:24:53.393Z] ----------------------------------- [2026-03-05T02:24:53.393Z] [2026-03-05T02:24:53.393Z] TEST TEARDOWN: [2026-03-05T02:24:53.393Z] Nothing to be done for teardown. [2026-03-05T02:24:53.393Z] renaissance-als_0 Finish Time: Thu Mar 5 02:24:52 2026 Epoch Time (ms): 1772677492668