[2026-04-25T15:26:41.571Z] Running test renaissance-als_0 ... [2026-04-25T15:26:41.571Z] =============================================== [2026-04-25T15:26:41.941Z] renaissance-als_0 Start Time: Sat Apr 25 15:26:41 2026 Epoch Time (ms): 1777130801602 [2026-04-25T15:26:41.941Z] variation: NoOptions [2026-04-25T15:26:41.941Z] JVM_OPTIONS: [2026-04-25T15:26:41.941Z] { \ [2026-04-25T15:26:41.941Z] echo ""; echo "TEST SETUP:"; \ [2026-04-25T15:26:41.941Z] echo "Nothing to be done for setup."; \ [2026-04-25T15:26:41.941Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17771305327633\\renaissance-als_0"; \ [2026-04-25T15:26:41.941Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17771305327633\\renaissance-als_0"; \ [2026-04-25T15:26:41.941Z] echo ""; echo "TESTING:"; \ [2026-04-25T15:26:41.941Z] "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_17771305327633\\renaissance-als_0"\\als.json" als; \ [2026-04-25T15:26:41.941Z] 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_17771305327633\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-25T15:26:41.941Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-25T15:26:41.941Z] echo "Nothing to be done for teardown."; \ [2026-04-25T15:26:41.941Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17771305327633\\TestTargetResult"; [2026-04-25T15:26:41.941Z] [2026-04-25T15:26:41.941Z] TEST SETUP: [2026-04-25T15:26:41.941Z] Nothing to be done for setup. [2026-04-25T15:26:41.941Z] [2026-04-25T15:26:41.941Z] TESTING: [2026-04-25T15:26:58.116Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-25T15:27:01.428Z] WARNING: An illegal reflective access operation has occurred [2026-04-25T15:27:01.428Z] 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_17771305327633/renaissance-als_0/harness-152643-16431197322774202141/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-25T15:27:01.428Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-25T15:27:01.428Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-25T15:27:01.428Z] WARNING: All illegal access operations will be denied in a future release [2026-04-25T15:27:02.347Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-25T15:27:02.816Z] GC before operation: completed in 131.947 ms, heap usage 398.000 MB -> 35.205 MB. [2026-04-25T15:28:02.519Z] ====== als (apache-spark) [default], iteration 0 completed (52203.944 ms) ====== [2026-04-25T15:28:02.519Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-25T15:28:02.519Z] GC before operation: completed in 158.953 ms, heap usage 97.512 MB -> 60.386 MB. [2026-04-25T15:28:31.183Z] ====== als (apache-spark) [default], iteration 1 completed (30584.765 ms) ====== [2026-04-25T15:28:31.183Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-25T15:28:31.183Z] GC before operation: completed in 197.500 ms, heap usage 311.261 MB -> 61.236 MB. [2026-04-25T15:29:00.246Z] ====== als (apache-spark) [default], iteration 2 completed (29429.703 ms) ====== [2026-04-25T15:29:00.246Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-25T15:29:00.246Z] GC before operation: completed in 209.903 ms, heap usage 272.170 MB -> 61.859 MB. [2026-04-25T15:29:28.773Z] ====== als (apache-spark) [default], iteration 3 completed (29534.647 ms) ====== [2026-04-25T15:29:28.773Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-25T15:29:28.773Z] GC before operation: completed in 228.591 ms, heap usage 102.925 MB -> 62.391 MB. [2026-04-25T15:29:58.292Z] ====== als (apache-spark) [default], iteration 4 completed (27911.457 ms) ====== [2026-04-25T15:29:58.292Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-25T15:29:58.292Z] GC before operation: completed in 183.334 ms, heap usage 225.537 MB -> 62.909 MB. [2026-04-25T15:30:27.046Z] ====== als (apache-spark) [default], iteration 5 completed (27893.356 ms) ====== [2026-04-25T15:30:27.046Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-25T15:30:27.046Z] GC before operation: completed in 179.169 ms, heap usage 218.616 MB -> 63.426 MB. [2026-04-25T15:30:55.558Z] ====== als (apache-spark) [default], iteration 6 completed (28176.802 ms) ====== [2026-04-25T15:30:55.558Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-25T15:30:55.558Z] GC before operation: completed in 212.836 ms, heap usage 108.475 MB -> 63.377 MB. [2026-04-25T15:31:24.605Z] ====== als (apache-spark) [default], iteration 7 completed (28057.162 ms) ====== [2026-04-25T15:31:24.605Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-25T15:31:24.605Z] GC before operation: completed in 193.887 ms, heap usage 223.351 MB -> 64.501 MB. [2026-04-25T15:31:53.789Z] ====== als (apache-spark) [default], iteration 8 completed (28104.437 ms) ====== [2026-04-25T15:31:53.789Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-25T15:31:53.789Z] GC before operation: completed in 201.980 ms, heap usage 180.763 MB -> 64.897 MB. [2026-04-25T15:32:20.859Z] ====== als (apache-spark) [default], iteration 9 completed (28427.937 ms) ====== [2026-04-25T15:32:20.859Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-25T15:32:20.859Z] GC before operation: completed in 187.662 ms, heap usage 127.906 MB -> 64.717 MB. [2026-04-25T15:32:50.142Z] ====== als (apache-spark) [default], iteration 10 completed (28175.427 ms) ====== [2026-04-25T15:32:50.142Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-25T15:32:50.142Z] GC before operation: completed in 196.560 ms, heap usage 244.465 MB -> 65.572 MB. [2026-04-25T15:33:19.543Z] ====== als (apache-spark) [default], iteration 11 completed (28736.341 ms) ====== [2026-04-25T15:33:19.543Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-25T15:33:19.543Z] GC before operation: completed in 210.407 ms, heap usage 236.243 MB -> 65.805 MB. [2026-04-25T15:33:48.640Z] ====== als (apache-spark) [default], iteration 12 completed (28093.222 ms) ====== [2026-04-25T15:33:48.640Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-25T15:33:48.640Z] GC before operation: completed in 188.447 ms, heap usage 265.347 MB -> 66.050 MB. [2026-04-25T15:34:18.192Z] ====== als (apache-spark) [default], iteration 13 completed (29104.679 ms) ====== [2026-04-25T15:34:18.192Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-25T15:34:18.192Z] GC before operation: completed in 180.065 ms, heap usage 157.046 MB -> 65.709 MB. [2026-04-25T15:34:47.662Z] ====== als (apache-spark) [default], iteration 14 completed (28976.825 ms) ====== [2026-04-25T15:34:47.662Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-25T15:34:47.662Z] GC before operation: completed in 182.800 ms, heap usage 125.357 MB -> 65.958 MB. [2026-04-25T15:35:16.994Z] ====== als (apache-spark) [default], iteration 15 completed (28271.962 ms) ====== [2026-04-25T15:35:16.994Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-25T15:35:16.994Z] GC before operation: completed in 198.597 ms, heap usage 107.008 MB -> 66.223 MB. [2026-04-25T15:35:45.710Z] ====== als (apache-spark) [default], iteration 16 completed (28202.041 ms) ====== [2026-04-25T15:35:45.710Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-25T15:35:45.710Z] GC before operation: completed in 225.498 ms, heap usage 129.941 MB -> 66.470 MB. [2026-04-25T15:36:15.152Z] ====== als (apache-spark) [default], iteration 17 completed (27781.968 ms) ====== [2026-04-25T15:36:15.152Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-25T15:36:15.152Z] GC before operation: completed in 227.268 ms, heap usage 246.140 MB -> 67.286 MB. [2026-04-25T15:36:44.127Z] ====== als (apache-spark) [default], iteration 18 completed (29315.200 ms) ====== [2026-04-25T15:36:44.127Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-25T15:36:44.127Z] GC before operation: completed in 198.413 ms, heap usage 240.957 MB -> 67.527 MB. [2026-04-25T15:37:13.125Z] ====== als (apache-spark) [default], iteration 19 completed (28798.379 ms) ====== [2026-04-25T15:37:13.125Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-25T15:37:13.125Z] GC before operation: completed in 214.822 ms, heap usage 104.176 MB -> 67.298 MB. [2026-04-25T15:37:42.321Z] ====== als (apache-spark) [default], iteration 20 completed (29258.931 ms) ====== [2026-04-25T15:37:42.321Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-25T15:37:42.321Z] GC before operation: completed in 294.570 ms, heap usage 253.921 MB -> 67.551 MB. [2026-04-25T15:38:11.578Z] ====== als (apache-spark) [default], iteration 21 completed (29697.601 ms) ====== [2026-04-25T15:38:11.578Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-25T15:38:12.089Z] GC before operation: completed in 214.069 ms, heap usage 261.091 MB -> 68.284 MB. [2026-04-25T15:38:41.182Z] ====== als (apache-spark) [default], iteration 22 completed (28523.452 ms) ====== [2026-04-25T15:38:41.182Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-25T15:38:41.182Z] GC before operation: completed in 205.589 ms, heap usage 238.836 MB -> 68.531 MB. [2026-04-25T15:39:10.157Z] ====== als (apache-spark) [default], iteration 23 completed (27718.183 ms) ====== [2026-04-25T15:39:10.157Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-25T15:39:10.157Z] GC before operation: completed in 225.833 ms, heap usage 229.990 MB -> 68.811 MB. [2026-04-25T15:39:39.294Z] ====== als (apache-spark) [default], iteration 24 completed (28871.363 ms) ====== [2026-04-25T15:39:39.294Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-25T15:39:39.294Z] GC before operation: completed in 181.934 ms, heap usage 116.366 MB -> 68.480 MB. [2026-04-25T15:40:08.624Z] ====== als (apache-spark) [default], iteration 25 completed (28153.629 ms) ====== [2026-04-25T15:40:08.624Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-25T15:40:08.624Z] GC before operation: completed in 182.328 ms, heap usage 119.877 MB -> 68.732 MB. [2026-04-25T15:40:38.204Z] ====== als (apache-spark) [default], iteration 26 completed (28376.310 ms) ====== [2026-04-25T15:40:38.204Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-25T15:40:38.204Z] GC before operation: completed in 232.952 ms, heap usage 128.468 MB -> 69.085 MB. [2026-04-25T15:41:07.027Z] ====== als (apache-spark) [default], iteration 27 completed (28665.934 ms) ====== [2026-04-25T15:41:07.027Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-25T15:41:07.027Z] GC before operation: completed in 208.618 ms, heap usage 222.214 MB -> 69.793 MB. [2026-04-25T15:41:36.203Z] ====== als (apache-spark) [default], iteration 28 completed (27567.319 ms) ====== [2026-04-25T15:41:36.203Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-25T15:41:36.203Z] GC before operation: completed in 213.326 ms, heap usage 92.060 MB -> 69.458 MB. [2026-04-25T15:42:04.538Z] ====== als (apache-spark) [default], iteration 29 completed (28053.062 ms) ====== [2026-04-25T15:42:04.538Z] ----------------------------------- [2026-04-25T15:42:04.538Z] renaissance-als_0_PASSED [2026-04-25T15:42:04.538Z] ----------------------------------- [2026-04-25T15:42:04.538Z] [2026-04-25T15:42:04.538Z] TEST TEARDOWN: [2026-04-25T15:42:04.538Z] Nothing to be done for teardown. [2026-04-25T15:42:04.538Z] renaissance-als_0 Finish Time: Sat Apr 25 15:42:02 2026 Epoch Time (ms): 1777131722480