[2026-03-02T00:15:21.381Z] Running test renaissance-als_0 ... [2026-03-02T00:15:21.707Z] =============================================== [2026-03-02T00:15:21.707Z] renaissance-als_0 Start Time: Mon Mar 2 00:15:21 2026 Epoch Time (ms): 1772410521506 [2026-03-02T00:15:21.707Z] variation: NoOptions [2026-03-02T00:15:21.707Z] JVM_OPTIONS: [2026-03-02T00:15:21.707Z] { \ [2026-03-02T00:15:21.707Z] echo ""; echo "TEST SETUP:"; \ [2026-03-02T00:15:21.707Z] echo "Nothing to be done for setup."; \ [2026-03-02T00:15:21.707Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17724102465297\\renaissance-als_0"; \ [2026-03-02T00:15:21.707Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17724102465297\\renaissance-als_0"; \ [2026-03-02T00:15:21.707Z] echo ""; echo "TESTING:"; \ [2026-03-02T00:15:21.707Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17724102465297\\renaissance-als_0"\\als.json" als; \ [2026-03-02T00:15:21.707Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17724102465297\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-02T00:15:21.707Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-02T00:15:21.707Z] echo "Nothing to be done for teardown."; \ [2026-03-02T00:15:21.707Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17724102465297\\TestTargetResult"; [2026-03-02T00:15:22.043Z] [2026-03-02T00:15:22.043Z] TEST SETUP: [2026-03-02T00:15:22.043Z] Nothing to be done for setup. [2026-03-02T00:15:22.043Z] [2026-03-02T00:15:22.043Z] TESTING: [2026-03-02T00:15:38.199Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-03-02T00:15:42.768Z] WARNING: An illegal reflective access operation has occurred [2026-03-02T00:15:42.768Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17724102465297/renaissance-als_0/harness-001522-13251774141877642366/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-03-02T00:15:42.768Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-03-02T00:15:42.768Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-03-02T00:15:42.768Z] WARNING: All illegal access operations will be denied in a future release [2026-03-02T00:15:44.097Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-02T00:15:44.098Z] GC before operation: completed in 110.181 ms, heap usage 331.510 MB -> 34.639 MB. [2026-03-02T00:16:45.929Z] ====== als (apache-spark) [default], iteration 0 completed (53516.915 ms) ====== [2026-03-02T00:16:45.929Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-02T00:16:45.929Z] GC before operation: completed in 323.347 ms, heap usage 225.798 MB -> 60.714 MB. [2026-03-02T00:17:14.589Z] ====== als (apache-spark) [default], iteration 1 completed (31371.498 ms) ====== [2026-03-02T00:17:14.589Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-02T00:17:14.589Z] GC before operation: completed in 170.616 ms, heap usage 176.132 MB -> 61.178 MB. [2026-03-02T00:17:42.940Z] ====== als (apache-spark) [default], iteration 2 completed (29689.313 ms) ====== [2026-03-02T00:17:42.940Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-02T00:17:42.940Z] GC before operation: completed in 170.884 ms, heap usage 134.667 MB -> 61.733 MB. [2026-03-02T00:18:12.448Z] ====== als (apache-spark) [default], iteration 3 completed (29654.054 ms) ====== [2026-03-02T00:18:12.448Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-02T00:18:12.448Z] GC before operation: completed in 240.518 ms, heap usage 172.760 MB -> 62.104 MB. [2026-03-02T00:18:46.273Z] ====== als (apache-spark) [default], iteration 4 completed (29682.025 ms) ====== [2026-03-02T00:18:46.273Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-02T00:18:46.273Z] GC before operation: completed in 246.408 ms, heap usage 203.383 MB -> 62.897 MB. [2026-03-02T00:19:14.535Z] ====== als (apache-spark) [default], iteration 5 completed (28518.373 ms) ====== [2026-03-02T00:19:14.535Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-02T00:19:14.535Z] GC before operation: completed in 204.274 ms, heap usage 234.504 MB -> 62.972 MB. [2026-03-02T00:19:42.941Z] ====== als (apache-spark) [default], iteration 6 completed (29934.871 ms) ====== [2026-03-02T00:19:42.941Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-02T00:19:42.941Z] GC before operation: completed in 216.678 ms, heap usage 233.237 MB -> 64.287 MB. [2026-03-02T00:20:11.556Z] ====== als (apache-spark) [default], iteration 7 completed (27988.402 ms) ====== [2026-03-02T00:20:11.556Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-02T00:20:11.556Z] GC before operation: completed in 191.601 ms, heap usage 209.654 MB -> 64.491 MB. [2026-03-02T00:20:39.402Z] ====== als (apache-spark) [default], iteration 8 completed (27922.201 ms) ====== [2026-03-02T00:20:39.402Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-02T00:20:39.402Z] GC before operation: completed in 177.401 ms, heap usage 239.349 MB -> 65.002 MB. [2026-03-02T00:21:08.021Z] ====== als (apache-spark) [default], iteration 9 completed (27563.058 ms) ====== [2026-03-02T00:21:08.021Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-02T00:21:08.021Z] GC before operation: completed in 181.929 ms, heap usage 327.380 MB -> 65.387 MB. [2026-03-02T00:21:36.504Z] ====== als (apache-spark) [default], iteration 10 completed (28518.198 ms) ====== [2026-03-02T00:21:36.504Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-02T00:21:36.961Z] GC before operation: completed in 202.192 ms, heap usage 129.503 MB -> 65.546 MB. [2026-03-02T00:22:11.046Z] ====== als (apache-spark) [default], iteration 11 completed (28534.567 ms) ====== [2026-03-02T00:22:11.046Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-02T00:22:11.046Z] GC before operation: completed in 179.830 ms, heap usage 114.794 MB -> 65.219 MB. [2026-03-02T00:22:35.759Z] ====== als (apache-spark) [default], iteration 12 completed (27979.095 ms) ====== [2026-03-02T00:22:35.759Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-02T00:22:35.759Z] GC before operation: completed in 179.700 ms, heap usage 149.219 MB -> 65.462 MB. [2026-03-02T00:23:05.424Z] ====== als (apache-spark) [default], iteration 13 completed (28694.644 ms) ====== [2026-03-02T00:23:05.424Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-02T00:23:05.424Z] GC before operation: completed in 178.814 ms, heap usage 231.359 MB -> 66.285 MB. [2026-03-02T00:23:33.651Z] ====== als (apache-spark) [default], iteration 14 completed (28183.251 ms) ====== [2026-03-02T00:23:33.651Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-02T00:23:33.651Z] GC before operation: completed in 189.960 ms, heap usage 107.984 MB -> 65.954 MB. [2026-03-02T00:24:02.339Z] ====== als (apache-spark) [default], iteration 15 completed (28110.244 ms) ====== [2026-03-02T00:24:02.339Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-02T00:24:02.339Z] GC before operation: completed in 181.685 ms, heap usage 117.299 MB -> 66.246 MB. [2026-03-02T00:24:31.070Z] ====== als (apache-spark) [default], iteration 16 completed (28088.637 ms) ====== [2026-03-02T00:24:31.071Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-02T00:24:31.071Z] GC before operation: completed in 184.884 ms, heap usage 133.066 MB -> 66.487 MB. [2026-03-02T00:24:59.940Z] ====== als (apache-spark) [default], iteration 17 completed (28326.113 ms) ====== [2026-03-02T00:24:59.940Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-02T00:24:59.940Z] GC before operation: completed in 219.017 ms, heap usage 80.435 MB -> 66.721 MB. [2026-03-02T00:25:28.193Z] ====== als (apache-spark) [default], iteration 18 completed (28174.550 ms) ====== [2026-03-02T00:25:28.193Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-02T00:25:28.193Z] GC before operation: completed in 186.271 ms, heap usage 127.573 MB -> 66.965 MB. [2026-03-02T00:25:57.505Z] ====== als (apache-spark) [default], iteration 19 completed (28374.912 ms) ====== [2026-03-02T00:25:57.505Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-02T00:25:57.505Z] GC before operation: completed in 206.991 ms, heap usage 101.580 MB -> 67.214 MB. [2026-03-02T00:26:27.222Z] ====== als (apache-spark) [default], iteration 20 completed (31200.661 ms) ====== [2026-03-02T00:26:27.222Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-02T00:26:27.611Z] GC before operation: completed in 196.508 ms, heap usage 279.158 MB -> 68.024 MB. [2026-03-02T00:26:56.646Z] ====== als (apache-spark) [default], iteration 21 completed (27962.613 ms) ====== [2026-03-02T00:26:56.646Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-02T00:26:56.646Z] GC before operation: completed in 210.473 ms, heap usage 222.653 MB -> 67.880 MB. [2026-03-02T00:27:25.398Z] ====== als (apache-spark) [default], iteration 22 completed (27360.973 ms) ====== [2026-03-02T00:27:25.398Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-02T00:27:25.398Z] GC before operation: completed in 185.719 ms, heap usage 138.573 MB -> 67.950 MB. [2026-03-02T00:27:55.446Z] ====== als (apache-spark) [default], iteration 23 completed (28788.363 ms) ====== [2026-03-02T00:27:55.446Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-02T00:27:55.446Z] GC before operation: completed in 227.792 ms, heap usage 209.505 MB -> 68.803 MB. [2026-03-02T00:28:24.247Z] ====== als (apache-spark) [default], iteration 24 completed (27563.988 ms) ====== [2026-03-02T00:28:24.248Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-02T00:28:24.248Z] GC before operation: completed in 214.164 ms, heap usage 241.906 MB -> 69.051 MB. [2026-03-02T00:28:52.648Z] ====== als (apache-spark) [default], iteration 25 completed (29070.299 ms) ====== [2026-03-02T00:28:52.648Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-02T00:28:52.648Z] GC before operation: completed in 192.870 ms, heap usage 110.176 MB -> 68.726 MB. [2026-03-02T00:29:22.508Z] ====== als (apache-spark) [default], iteration 26 completed (27492.326 ms) ====== [2026-03-02T00:29:22.508Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-02T00:29:22.508Z] GC before operation: completed in 223.086 ms, heap usage 399.226 MB -> 69.651 MB. [2026-03-02T00:29:51.212Z] ====== als (apache-spark) [default], iteration 27 completed (30064.357 ms) ====== [2026-03-02T00:29:51.212Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-02T00:29:51.212Z] GC before operation: completed in 213.611 ms, heap usage 105.798 MB -> 69.206 MB. [2026-03-02T00:30:19.982Z] ====== als (apache-spark) [default], iteration 28 completed (27954.159 ms) ====== [2026-03-02T00:30:19.982Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-02T00:30:19.982Z] GC before operation: completed in 234.665 ms, heap usage 141.513 MB -> 69.571 MB. [2026-03-02T00:30:48.310Z] ====== als (apache-spark) [default], iteration 29 completed (28314.531 ms) ====== [2026-03-02T00:30:48.310Z] ----------------------------------- [2026-03-02T00:30:48.310Z] renaissance-als_0_PASSED [2026-03-02T00:30:48.310Z] ----------------------------------- [2026-03-02T00:30:48.310Z] [2026-03-02T00:30:48.310Z] TEST TEARDOWN: [2026-03-02T00:30:48.311Z] Nothing to be done for teardown. [2026-03-02T00:30:48.311Z] renaissance-als_0 Finish Time: Mon Mar 2 00:30:47 2026 Epoch Time (ms): 1772411447478