[2026-02-12T18:22:02.274Z] Running test renaissance-als_0 ... [2026-02-12T18:22:02.274Z] =============================================== [2026-02-12T18:22:02.274Z] renaissance-als_0 Start Time: Thu Feb 12 18:22:01 2026 Epoch Time (ms): 1770920521152 [2026-02-12T18:22:02.274Z] variation: NoOptions [2026-02-12T18:22:02.274Z] JVM_OPTIONS: [2026-02-12T18:22:02.274Z] { \ [2026-02-12T18:22:02.274Z] echo ""; echo "TEST SETUP:"; \ [2026-02-12T18:22:02.274Z] echo "Nothing to be done for setup."; \ [2026-02-12T18:22:02.274Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17709205203075/renaissance-als_0"; \ [2026-02-12T18:22:02.274Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17709205203075/renaissance-als_0"; \ [2026-02-12T18:22:02.274Z] echo ""; echo "TESTING:"; \ [2026-02-12T18:22:02.274Z] "/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_17709205203075/renaissance-als_0"/als.json" als; \ [2026-02-12T18:22:02.274Z] 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_17709205203075/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-12T18:22:02.274Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-12T18:22:02.274Z] echo "Nothing to be done for teardown."; \ [2026-02-12T18:22:02.274Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17709205203075/TestTargetResult"; [2026-02-12T18:22:02.274Z] [2026-02-12T18:22:02.274Z] TEST SETUP: [2026-02-12T18:22:02.274Z] Nothing to be done for setup. [2026-02-12T18:22:02.274Z] [2026-02-12T18:22:02.274Z] TESTING: [2026-02-12T18:22:20.923Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-12T18:22:36.939Z] WARNING: An illegal reflective access operation has occurred [2026-02-12T18:22:36.939Z] 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_17709205203075/renaissance-als_0/harness-182202-3756375128474109557/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-12T18:22:36.939Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-12T18:22:36.939Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-12T18:22:36.939Z] WARNING: All illegal access operations will be denied in a future release [2026-02-12T18:22:36.939Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-12T18:22:36.939Z] GC before operation: completed in 273.903 ms, heap usage 306.000 MB -> 34.666 MB. [2026-02-12T18:23:38.179Z] ====== als (apache-spark) [default], iteration 0 completed (59093.957 ms) ====== [2026-02-12T18:23:38.179Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-12T18:23:38.179Z] GC before operation: completed in 512.625 ms, heap usage 222.132 MB -> 60.527 MB. [2026-02-12T18:24:03.239Z] ====== als (apache-spark) [default], iteration 1 completed (21709.224 ms) ====== [2026-02-12T18:24:03.239Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-12T18:24:03.239Z] GC before operation: completed in 469.748 ms, heap usage 514.138 MB -> 61.745 MB. [2026-02-12T18:24:19.435Z] ====== als (apache-spark) [default], iteration 2 completed (17744.296 ms) ====== [2026-02-12T18:24:19.435Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-12T18:24:21.131Z] GC before operation: completed in 495.840 ms, heap usage 407.177 MB -> 62.134 MB. [2026-02-12T18:24:39.780Z] ====== als (apache-spark) [default], iteration 3 completed (18980.976 ms) ====== [2026-02-12T18:24:39.780Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-12T18:24:40.761Z] GC before operation: completed in 531.159 ms, heap usage 142.738 MB -> 62.397 MB. [2026-02-12T18:24:59.363Z] ====== als (apache-spark) [default], iteration 4 completed (17788.685 ms) ====== [2026-02-12T18:24:59.363Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-12T18:24:59.363Z] GC before operation: completed in 481.498 ms, heap usage 224.950 MB -> 63.036 MB. [2026-02-12T18:25:17.993Z] ====== als (apache-spark) [default], iteration 5 completed (16825.052 ms) ====== [2026-02-12T18:25:17.993Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-12T18:25:17.993Z] GC before operation: completed in 604.338 ms, heap usage 221.679 MB -> 63.589 MB. [2026-02-12T18:25:39.551Z] ====== als (apache-spark) [default], iteration 6 completed (19389.301 ms) ====== [2026-02-12T18:25:39.551Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-12T18:25:39.551Z] GC before operation: completed in 453.156 ms, heap usage 274.672 MB -> 64.085 MB. [2026-02-12T18:25:55.604Z] ====== als (apache-spark) [default], iteration 7 completed (16372.987 ms) ====== [2026-02-12T18:25:55.604Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-12T18:25:56.599Z] GC before operation: completed in 482.590 ms, heap usage 148.469 MB -> 64.468 MB. [2026-02-12T18:26:13.378Z] ====== als (apache-spark) [default], iteration 8 completed (15319.022 ms) ====== [2026-02-12T18:26:13.378Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-12T18:26:13.378Z] GC before operation: completed in 492.486 ms, heap usage 232.163 MB -> 65.104 MB. [2026-02-12T18:26:32.049Z] ====== als (apache-spark) [default], iteration 9 completed (16378.710 ms) ====== [2026-02-12T18:26:32.049Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-12T18:26:32.049Z] GC before operation: completed in 655.393 ms, heap usage 263.505 MB -> 65.389 MB. [2026-02-12T18:26:53.657Z] ====== als (apache-spark) [default], iteration 10 completed (20287.247 ms) ====== [2026-02-12T18:26:53.657Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-12T18:26:53.657Z] GC before operation: completed in 583.221 ms, heap usage 339.562 MB -> 65.768 MB. [2026-02-12T18:27:12.620Z] ====== als (apache-spark) [default], iteration 11 completed (17946.516 ms) ====== [2026-02-12T18:27:12.620Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-12T18:27:12.620Z] GC before operation: completed in 577.852 ms, heap usage 257.644 MB -> 65.866 MB. [2026-02-12T18:27:31.599Z] ====== als (apache-spark) [default], iteration 12 completed (19043.872 ms) ====== [2026-02-12T18:27:31.599Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-12T18:27:32.587Z] GC before operation: completed in 717.789 ms, heap usage 520.027 MB -> 66.519 MB. [2026-02-12T18:27:57.937Z] ====== als (apache-spark) [default], iteration 13 completed (22688.591 ms) ====== [2026-02-12T18:27:57.937Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-12T18:27:57.937Z] GC before operation: completed in 961.450 ms, heap usage 237.730 MB -> 66.437 MB. [2026-02-12T18:28:20.793Z] ====== als (apache-spark) [default], iteration 14 completed (20069.213 ms) ====== [2026-02-12T18:28:20.794Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-12T18:28:20.794Z] GC before operation: completed in 758.428 ms, heap usage 267.960 MB -> 66.696 MB. [2026-02-12T18:28:39.853Z] ====== als (apache-spark) [default], iteration 15 completed (17828.579 ms) ====== [2026-02-12T18:28:39.853Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-12T18:28:39.853Z] GC before operation: completed in 652.631 ms, heap usage 164.489 MB -> 66.844 MB. [2026-02-12T18:28:53.564Z] ====== als (apache-spark) [default], iteration 16 completed (13696.343 ms) ====== [2026-02-12T18:28:53.564Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-12T18:28:53.564Z] GC before operation: completed in 502.149 ms, heap usage 349.884 MB -> 67.337 MB. [2026-02-12T18:29:09.833Z] ====== als (apache-spark) [default], iteration 17 completed (15375.447 ms) ====== [2026-02-12T18:29:09.833Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-12T18:29:09.833Z] GC before operation: completed in 574.535 ms, heap usage 217.940 MB -> 67.437 MB. [2026-02-12T18:29:31.879Z] ====== als (apache-spark) [default], iteration 18 completed (19314.085 ms) ====== [2026-02-12T18:29:31.879Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-12T18:29:31.879Z] GC before operation: completed in 706.326 ms, heap usage 315.262 MB -> 67.793 MB. [2026-02-12T18:29:57.531Z] ====== als (apache-spark) [default], iteration 19 completed (23425.335 ms) ====== [2026-02-12T18:29:57.531Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-12T18:29:57.531Z] GC before operation: completed in 705.943 ms, heap usage 134.240 MB -> 67.812 MB. [2026-02-12T18:30:20.630Z] ====== als (apache-spark) [default], iteration 20 completed (22518.535 ms) ====== [2026-02-12T18:30:20.630Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-12T18:30:20.630Z] GC before operation: completed in 576.063 ms, heap usage 255.804 MB -> 68.186 MB. [2026-02-12T18:30:42.619Z] ====== als (apache-spark) [default], iteration 21 completed (19833.721 ms) ====== [2026-02-12T18:30:42.619Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-12T18:30:42.619Z] GC before operation: completed in 704.124 ms, heap usage 295.575 MB -> 68.417 MB. [2026-02-12T18:31:01.492Z] ====== als (apache-spark) [default], iteration 22 completed (17264.271 ms) ====== [2026-02-12T18:31:01.492Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-12T18:31:01.492Z] GC before operation: completed in 643.078 ms, heap usage 151.982 MB -> 68.581 MB. [2026-02-12T18:31:20.445Z] ====== als (apache-spark) [default], iteration 23 completed (19011.477 ms) ====== [2026-02-12T18:31:20.446Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-12T18:31:21.456Z] GC before operation: completed in 604.027 ms, heap usage 258.309 MB -> 68.951 MB. [2026-02-12T18:31:43.252Z] ====== als (apache-spark) [default], iteration 24 completed (19764.880 ms) ====== [2026-02-12T18:31:43.252Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-12T18:31:43.252Z] GC before operation: completed in 564.323 ms, heap usage 161.573 MB -> 69.104 MB. [2026-02-12T18:31:59.657Z] ====== als (apache-spark) [default], iteration 25 completed (16883.250 ms) ====== [2026-02-12T18:31:59.657Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-12T18:31:59.657Z] GC before operation: completed in 619.487 ms, heap usage 337.282 MB -> 69.553 MB. [2026-02-12T18:32:15.990Z] ====== als (apache-spark) [default], iteration 26 completed (15083.628 ms) ====== [2026-02-12T18:32:15.990Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-12T18:32:15.990Z] GC before operation: completed in 586.635 ms, heap usage 219.521 MB -> 69.700 MB. [2026-02-12T18:32:34.640Z] ====== als (apache-spark) [default], iteration 27 completed (15908.756 ms) ====== [2026-02-12T18:32:34.640Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-12T18:32:34.640Z] GC before operation: completed in 665.910 ms, heap usage 615.175 MB -> 70.478 MB. [2026-02-12T18:32:53.360Z] ====== als (apache-spark) [default], iteration 28 completed (17730.721 ms) ====== [2026-02-12T18:32:53.360Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-12T18:32:53.360Z] GC before operation: completed in 483.563 ms, heap usage 338.836 MB -> 69.834 MB. [2026-02-12T18:33:09.437Z] ====== als (apache-spark) [default], iteration 29 completed (16198.065 ms) ====== [2026-02-12T18:33:11.439Z] ----------------------------------- [2026-02-12T18:33:11.439Z] renaissance-als_0_PASSED [2026-02-12T18:33:11.439Z] ----------------------------------- [2026-02-12T18:33:11.439Z] [2026-02-12T18:33:11.439Z] TEST TEARDOWN: [2026-02-12T18:33:11.439Z] Nothing to be done for teardown. [2026-02-12T18:33:11.439Z] renaissance-als_0 Finish Time: Thu Feb 12 18:33:10 2026 Epoch Time (ms): 1770921190564