[2026-02-06T23:50:09.151Z] Running test renaissance-als_0 ... [2026-02-06T23:50:09.151Z] =============================================== [2026-02-06T23:50:09.489Z] renaissance-als_0 Start Time: Fri Feb 6 23:50:09 2026 Epoch Time (ms): 1770421809197 [2026-02-06T23:50:09.489Z] variation: NoOptions [2026-02-06T23:50:09.489Z] JVM_OPTIONS: [2026-02-06T23:50:09.489Z] { \ [2026-02-06T23:50:09.489Z] echo ""; echo "TEST SETUP:"; \ [2026-02-06T23:50:09.489Z] echo "Nothing to be done for setup."; \ [2026-02-06T23:50:09.489Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17704216103171\\renaissance-als_0"; \ [2026-02-06T23:50:09.489Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17704216103171\\renaissance-als_0"; \ [2026-02-06T23:50:09.489Z] echo ""; echo "TESTING:"; \ [2026-02-06T23:50:09.489Z] "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_17704216103171\\renaissance-als_0"\\als.json" als; \ [2026-02-06T23:50:09.489Z] 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_17704216103171\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-06T23:50:09.489Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-06T23:50:09.489Z] echo "Nothing to be done for teardown."; \ [2026-02-06T23:50:09.489Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17704216103171\\TestTargetResult"; [2026-02-06T23:50:09.489Z] [2026-02-06T23:50:09.489Z] TEST SETUP: [2026-02-06T23:50:09.489Z] Nothing to be done for setup. [2026-02-06T23:50:09.489Z] [2026-02-06T23:50:09.489Z] TESTING: [2026-02-06T23:50:25.281Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-06T23:50:27.529Z] WARNING: An illegal reflective access operation has occurred [2026-02-06T23:50:27.529Z] 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_17704216103171/renaissance-als_0/harness-235010-15859736473447371835/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-06T23:50:27.529Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-06T23:50:27.529Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-06T23:50:27.529Z] WARNING: All illegal access operations will be denied in a future release [2026-02-06T23:50:28.680Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-06T23:50:28.680Z] GC before operation: completed in 102.556 ms, heap usage 463.500 MB -> 35.025 MB. [2026-02-06T23:51:02.384Z] ====== als (apache-spark) [default], iteration 0 completed (30976.104 ms) ====== [2026-02-06T23:51:02.384Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-06T23:51:02.384Z] GC before operation: completed in 128.447 ms, heap usage 298.456 MB -> 60.972 MB. [2026-02-06T23:51:21.693Z] ====== als (apache-spark) [default], iteration 1 completed (17792.039 ms) ====== [2026-02-06T23:51:21.693Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-06T23:51:21.693Z] GC before operation: completed in 134.563 ms, heap usage 241.186 MB -> 61.768 MB. [2026-02-06T23:51:37.702Z] ====== als (apache-spark) [default], iteration 2 completed (16903.942 ms) ====== [2026-02-06T23:51:37.702Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-06T23:51:37.702Z] GC before operation: completed in 135.013 ms, heap usage 369.293 MB -> 62.397 MB. [2026-02-06T23:51:53.896Z] ====== als (apache-spark) [default], iteration 3 completed (16823.238 ms) ====== [2026-02-06T23:51:53.896Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-06T23:51:53.896Z] GC before operation: completed in 152.333 ms, heap usage 394.110 MB -> 62.927 MB. [2026-02-06T23:52:13.211Z] ====== als (apache-spark) [default], iteration 4 completed (17048.905 ms) ====== [2026-02-06T23:52:13.211Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-06T23:52:13.211Z] GC before operation: completed in 130.103 ms, heap usage 555.593 MB -> 63.774 MB. [2026-02-06T23:52:29.220Z] ====== als (apache-spark) [default], iteration 5 completed (16506.140 ms) ====== [2026-02-06T23:52:29.220Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-06T23:52:29.220Z] GC before operation: completed in 135.230 ms, heap usage 262.319 MB -> 63.935 MB. [2026-02-06T23:52:45.151Z] ====== als (apache-spark) [default], iteration 6 completed (17100.710 ms) ====== [2026-02-06T23:52:45.151Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-06T23:52:45.151Z] GC before operation: completed in 136.858 ms, heap usage 262.848 MB -> 64.371 MB. [2026-02-06T23:53:04.647Z] ====== als (apache-spark) [default], iteration 7 completed (16503.604 ms) ====== [2026-02-06T23:53:04.647Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-06T23:53:04.647Z] GC before operation: completed in 211.506 ms, heap usage 402.852 MB -> 65.034 MB. [2026-02-06T23:53:20.701Z] ====== als (apache-spark) [default], iteration 8 completed (17282.437 ms) ====== [2026-02-06T23:53:20.701Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-06T23:53:20.701Z] GC before operation: completed in 156.680 ms, heap usage 369.139 MB -> 65.543 MB. [2026-02-06T23:53:36.701Z] ====== als (apache-spark) [default], iteration 9 completed (17056.755 ms) ====== [2026-02-06T23:53:36.701Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-06T23:53:37.086Z] GC before operation: completed in 138.245 ms, heap usage 102.250 MB -> 65.595 MB. [2026-02-06T23:53:56.667Z] ====== als (apache-spark) [default], iteration 10 completed (17345.050 ms) ====== [2026-02-06T23:53:56.667Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-06T23:53:56.667Z] GC before operation: completed in 140.530 ms, heap usage 103.983 MB -> 65.838 MB. [2026-02-06T23:54:12.686Z] ====== als (apache-spark) [default], iteration 11 completed (17139.441 ms) ====== [2026-02-06T23:54:12.686Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-06T23:54:12.686Z] GC before operation: completed in 146.210 ms, heap usage 280.236 MB -> 66.222 MB. [2026-02-06T23:54:32.121Z] ====== als (apache-spark) [default], iteration 12 completed (18928.454 ms) ====== [2026-02-06T23:54:32.122Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-06T23:54:32.122Z] GC before operation: completed in 136.623 ms, heap usage 269.335 MB -> 66.526 MB. [2026-02-06T23:54:51.507Z] ====== als (apache-spark) [default], iteration 13 completed (17258.997 ms) ====== [2026-02-06T23:54:51.507Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-06T23:54:51.507Z] GC before operation: completed in 148.500 ms, heap usage 144.533 MB -> 66.598 MB. [2026-02-06T23:55:07.362Z] ====== als (apache-spark) [default], iteration 14 completed (16871.764 ms) ====== [2026-02-06T23:55:07.362Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-06T23:55:07.362Z] GC before operation: completed in 196.406 ms, heap usage 249.989 MB -> 66.964 MB. [2026-02-06T23:55:23.837Z] ====== als (apache-spark) [default], iteration 15 completed (16579.751 ms) ====== [2026-02-06T23:55:23.837Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-06T23:55:23.837Z] GC before operation: completed in 167.274 ms, heap usage 270.560 MB -> 67.225 MB. [2026-02-06T23:55:43.299Z] ====== als (apache-spark) [default], iteration 16 completed (18429.202 ms) ====== [2026-02-06T23:55:43.299Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-06T23:55:43.299Z] GC before operation: completed in 137.957 ms, heap usage 280.407 MB -> 67.475 MB. [2026-02-06T23:56:02.878Z] ====== als (apache-spark) [default], iteration 17 completed (19179.221 ms) ====== [2026-02-06T23:56:02.878Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-06T23:56:02.878Z] GC before operation: completed in 135.525 ms, heap usage 237.617 MB -> 67.727 MB. [2026-02-06T23:56:18.878Z] ====== als (apache-spark) [default], iteration 18 completed (17298.359 ms) ====== [2026-02-06T23:56:18.878Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-06T23:56:19.423Z] GC before operation: completed in 152.396 ms, heap usage 114.124 MB -> 67.861 MB. [2026-02-06T23:56:38.641Z] ====== als (apache-spark) [default], iteration 19 completed (16637.375 ms) ====== [2026-02-06T23:56:38.641Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-06T23:56:38.641Z] GC before operation: completed in 148.904 ms, heap usage 244.587 MB -> 68.231 MB. [2026-02-06T23:56:54.734Z] ====== als (apache-spark) [default], iteration 20 completed (17132.812 ms) ====== [2026-02-06T23:56:54.734Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-06T23:56:54.734Z] GC before operation: completed in 157.605 ms, heap usage 261.510 MB -> 68.348 MB. [2026-02-06T23:57:10.602Z] ====== als (apache-spark) [default], iteration 21 completed (16563.070 ms) ====== [2026-02-06T23:57:10.602Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-06T23:57:10.602Z] GC before operation: completed in 145.020 ms, heap usage 404.256 MB -> 68.817 MB. [2026-02-06T23:57:29.888Z] ====== als (apache-spark) [default], iteration 22 completed (16543.099 ms) ====== [2026-02-06T23:57:29.888Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-06T23:57:29.888Z] GC before operation: completed in 150.850 ms, heap usage 412.139 MB -> 69.095 MB. [2026-02-06T23:57:45.785Z] ====== als (apache-spark) [default], iteration 23 completed (16941.950 ms) ====== [2026-02-06T23:57:45.785Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-06T23:57:45.785Z] GC before operation: completed in 146.275 ms, heap usage 100.809 MB -> 69.120 MB. [2026-02-06T23:58:01.878Z] ====== als (apache-spark) [default], iteration 24 completed (16843.745 ms) ====== [2026-02-06T23:58:01.878Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-06T23:58:01.878Z] GC before operation: completed in 158.566 ms, heap usage 469.293 MB -> 69.821 MB. [2026-02-06T23:58:21.088Z] ====== als (apache-spark) [default], iteration 25 completed (16690.407 ms) ====== [2026-02-06T23:58:21.088Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-06T23:58:21.088Z] GC before operation: completed in 145.288 ms, heap usage 243.453 MB -> 69.736 MB. [2026-02-06T23:58:37.114Z] ====== als (apache-spark) [default], iteration 26 completed (17074.336 ms) ====== [2026-02-06T23:58:37.114Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-06T23:58:37.114Z] GC before operation: completed in 142.767 ms, heap usage 298.856 MB -> 69.980 MB. [2026-02-06T23:58:56.459Z] ====== als (apache-spark) [default], iteration 27 completed (17239.396 ms) ====== [2026-02-06T23:58:56.459Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-06T23:58:56.459Z] GC before operation: completed in 165.639 ms, heap usage 271.804 MB -> 70.225 MB. [2026-02-06T23:59:12.542Z] ====== als (apache-spark) [default], iteration 28 completed (16658.497 ms) ====== [2026-02-06T23:59:12.542Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-06T23:59:12.542Z] GC before operation: completed in 150.106 ms, heap usage 272.149 MB -> 70.472 MB. [2026-02-06T23:59:28.537Z] ====== als (apache-spark) [default], iteration 29 completed (16897.181 ms) ====== [2026-02-06T23:59:28.537Z] ----------------------------------- [2026-02-06T23:59:28.537Z] renaissance-als_0_PASSED [2026-02-06T23:59:28.537Z] ----------------------------------- [2026-02-06T23:59:28.537Z] [2026-02-06T23:59:28.537Z] TEST TEARDOWN: [2026-02-06T23:59:28.537Z] Nothing to be done for teardown. [2026-02-06T23:59:28.537Z] renaissance-als_0 Finish Time: Fri Feb 6 23:59:28 2026 Epoch Time (ms): 1770422368489