[2026-02-08T09:37:57.343Z] Running test renaissance-als_0 ... [2026-02-08T09:37:57.734Z] =============================================== [2026-02-08T09:37:57.734Z] renaissance-als_0 Start Time: Sun Feb 8 09:37:57 2026 Epoch Time (ms): 1770543477650 [2026-02-08T09:37:58.122Z] variation: NoOptions [2026-02-08T09:37:58.122Z] JVM_OPTIONS: [2026-02-08T09:37:58.122Z] { \ [2026-02-08T09:37:58.122Z] echo ""; echo "TEST SETUP:"; \ [2026-02-08T09:37:58.122Z] echo "Nothing to be done for setup."; \ [2026-02-08T09:37:58.122Z] mkdir -p "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705430733901\\renaissance-als_0"; \ [2026-02-08T09:37:58.122Z] cd "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705430733901\\renaissance-als_0"; \ [2026-02-08T09:37:58.122Z] echo ""; echo "TESTING:"; \ [2026-02-08T09:37:58.122Z] "c:/workspace/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:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705430733901\\renaissance-als_0"\\als.json" als; \ [2026-02-08T09:37:58.122Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705430733901\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-08T09:37:58.122Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-08T09:37:58.122Z] echo "Nothing to be done for teardown."; \ [2026-02-08T09:37:58.122Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705430733901\\TestTargetResult"; [2026-02-08T09:37:58.510Z] [2026-02-08T09:37:58.510Z] TEST SETUP: [2026-02-08T09:37:58.510Z] Nothing to be done for setup. [2026-02-08T09:37:58.510Z] [2026-02-08T09:37:58.510Z] TESTING: [2026-02-08T09:38:32.130Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-08T09:38:38.340Z] WARNING: An illegal reflective access operation has occurred [2026-02-08T09:38:38.340Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17705430733901/renaissance-als_0/harness-093800-13885941723089870500/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-08T09:38:38.340Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-08T09:38:38.340Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-08T09:38:38.340Z] WARNING: All illegal access operations will be denied in a future release [2026-02-08T09:38:40.090Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-08T09:38:40.491Z] GC before operation: completed in 208.670 ms, heap usage 72.566 MB -> 34.944 MB. [2026-02-08T09:40:04.651Z] ====== als (apache-spark) [default], iteration 0 completed (69707.179 ms) ====== [2026-02-08T09:40:04.651Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-08T09:40:04.651Z] GC before operation: completed in 239.836 ms, heap usage 304.679 MB -> 60.953 MB. [2026-02-08T09:40:33.097Z] ====== als (apache-spark) [default], iteration 1 completed (40138.837 ms) ====== [2026-02-08T09:40:33.097Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-08T09:40:33.097Z] GC before operation: completed in 248.222 ms, heap usage 235.854 MB -> 61.625 MB. [2026-02-08T09:41:16.400Z] ====== als (apache-spark) [default], iteration 2 completed (37656.778 ms) ====== [2026-02-08T09:41:16.400Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-08T09:41:16.400Z] GC before operation: completed in 328.155 ms, heap usage 485.489 MB -> 62.599 MB. [2026-02-08T09:41:50.326Z] ====== als (apache-spark) [default], iteration 3 completed (38573.604 ms) ====== [2026-02-08T09:41:50.326Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-08T09:41:50.326Z] GC before operation: completed in 283.602 ms, heap usage 211.280 MB -> 62.668 MB. [2026-02-08T09:42:31.166Z] ====== als (apache-spark) [default], iteration 4 completed (37687.128 ms) ====== [2026-02-08T09:42:31.166Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-08T09:42:31.166Z] GC before operation: completed in 286.122 ms, heap usage 279.180 MB -> 63.325 MB. [2026-02-08T09:43:12.213Z] ====== als (apache-spark) [default], iteration 5 completed (39114.571 ms) ====== [2026-02-08T09:43:12.213Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-08T09:43:12.213Z] GC before operation: completed in 249.009 ms, heap usage 93.278 MB -> 63.741 MB. [2026-02-08T09:43:47.925Z] ====== als (apache-spark) [default], iteration 6 completed (37601.690 ms) ====== [2026-02-08T09:43:47.925Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-08T09:43:47.925Z] GC before operation: completed in 285.410 ms, heap usage 115.104 MB -> 64.269 MB. [2026-02-08T09:44:25.885Z] ====== als (apache-spark) [default], iteration 7 completed (36504.659 ms) ====== [2026-02-08T09:44:25.885Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-08T09:44:25.885Z] GC before operation: completed in 246.911 ms, heap usage 230.532 MB -> 64.916 MB. [2026-02-08T09:45:11.926Z] ====== als (apache-spark) [default], iteration 8 completed (40837.881 ms) ====== [2026-02-08T09:45:11.926Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-08T09:45:11.926Z] GC before operation: completed in 352.924 ms, heap usage 402.847 MB -> 65.645 MB. [2026-02-08T09:45:45.992Z] ====== als (apache-spark) [default], iteration 9 completed (38073.632 ms) ====== [2026-02-08T09:45:45.992Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-08T09:45:45.992Z] GC before operation: completed in 333.261 ms, heap usage 274.893 MB -> 65.731 MB. [2026-02-08T09:46:28.985Z] ====== als (apache-spark) [default], iteration 10 completed (37125.726 ms) ====== [2026-02-08T09:46:28.985Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-08T09:46:28.985Z] GC before operation: completed in 309.488 ms, heap usage 232.161 MB -> 65.944 MB. [2026-02-08T09:47:02.521Z] ====== als (apache-spark) [default], iteration 11 completed (37724.937 ms) ====== [2026-02-08T09:47:02.521Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-08T09:47:02.521Z] GC before operation: completed in 258.286 ms, heap usage 254.055 MB -> 66.209 MB. [2026-02-08T09:47:44.558Z] ====== als (apache-spark) [default], iteration 12 completed (37060.796 ms) ====== [2026-02-08T09:47:44.558Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-08T09:47:44.558Z] GC before operation: completed in 276.157 ms, heap usage 231.924 MB -> 66.466 MB. [2026-02-08T09:48:21.138Z] ====== als (apache-spark) [default], iteration 13 completed (36932.698 ms) ====== [2026-02-08T09:48:21.138Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-08T09:48:21.138Z] GC before operation: completed in 291.626 ms, heap usage 275.259 MB -> 66.604 MB. [2026-02-08T09:48:56.401Z] ====== als (apache-spark) [default], iteration 14 completed (36347.412 ms) ====== [2026-02-08T09:48:56.401Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-08T09:48:56.401Z] GC before operation: completed in 278.582 ms, heap usage 233.458 MB -> 67.006 MB. [2026-02-08T09:49:32.262Z] ====== als (apache-spark) [default], iteration 15 completed (37268.384 ms) ====== [2026-02-08T09:49:32.262Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-08T09:49:32.262Z] GC before operation: completed in 279.655 ms, heap usage 171.428 MB -> 67.116 MB. [2026-02-08T09:50:08.743Z] ====== als (apache-spark) [default], iteration 16 completed (35871.271 ms) ====== [2026-02-08T09:50:08.743Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-08T09:50:08.743Z] GC before operation: completed in 259.273 ms, heap usage 162.620 MB -> 67.363 MB. [2026-02-08T09:50:48.046Z] ====== als (apache-spark) [default], iteration 17 completed (37411.294 ms) ====== [2026-02-08T09:50:48.046Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-08T09:50:48.046Z] GC before operation: completed in 256.599 ms, heap usage 253.330 MB -> 67.754 MB. [2026-02-08T09:51:33.817Z] ====== als (apache-spark) [default], iteration 18 completed (37273.128 ms) ====== [2026-02-08T09:51:33.817Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-08T09:51:33.817Z] GC before operation: completed in 267.967 ms, heap usage 100.178 MB -> 67.891 MB. [2026-02-08T09:52:07.850Z] ====== als (apache-spark) [default], iteration 19 completed (38546.440 ms) ====== [2026-02-08T09:52:07.850Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-08T09:52:07.850Z] GC before operation: completed in 257.385 ms, heap usage 248.464 MB -> 68.256 MB. [2026-02-08T09:52:42.007Z] ====== als (apache-spark) [default], iteration 20 completed (37576.191 ms) ====== [2026-02-08T09:52:42.007Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-08T09:52:42.007Z] GC before operation: completed in 270.138 ms, heap usage 145.595 MB -> 68.384 MB. [2026-02-08T09:53:30.845Z] ====== als (apache-spark) [default], iteration 21 completed (41806.789 ms) ====== [2026-02-08T09:53:30.845Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-08T09:53:30.845Z] GC before operation: completed in 273.075 ms, heap usage 139.879 MB -> 68.624 MB. [2026-02-08T09:54:04.766Z] ====== als (apache-spark) [default], iteration 22 completed (37142.899 ms) ====== [2026-02-08T09:54:04.766Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-08T09:54:04.766Z] GC before operation: completed in 275.550 ms, heap usage 256.224 MB -> 68.901 MB. [2026-02-08T09:54:45.468Z] ====== als (apache-spark) [default], iteration 23 completed (38363.580 ms) ====== [2026-02-08T09:54:45.468Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-08T09:54:45.468Z] GC before operation: completed in 345.142 ms, heap usage 258.386 MB -> 69.266 MB. [2026-02-08T09:55:19.812Z] ====== als (apache-spark) [default], iteration 24 completed (38257.755 ms) ====== [2026-02-08T09:55:19.812Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-08T09:55:19.812Z] GC before operation: completed in 298.650 ms, heap usage 103.236 MB -> 69.399 MB. [2026-02-08T09:56:01.020Z] ====== als (apache-spark) [default], iteration 25 completed (37618.941 ms) ====== [2026-02-08T09:56:01.020Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-08T09:56:01.020Z] GC before operation: completed in 286.562 ms, heap usage 162.289 MB -> 69.647 MB. [2026-02-08T09:56:42.191Z] ====== als (apache-spark) [default], iteration 26 completed (37606.013 ms) ====== [2026-02-08T09:56:42.191Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-08T09:56:42.191Z] GC before operation: completed in 282.046 ms, heap usage 278.488 MB -> 70.009 MB. [2026-02-08T09:57:16.036Z] ====== als (apache-spark) [default], iteration 27 completed (37293.128 ms) ====== [2026-02-08T09:57:16.036Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-08T09:57:16.037Z] GC before operation: completed in 272.177 ms, heap usage 343.919 MB -> 70.360 MB. [2026-02-08T09:57:56.692Z] ====== als (apache-spark) [default], iteration 28 completed (38020.364 ms) ====== [2026-02-08T09:57:56.692Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-08T09:57:56.692Z] GC before operation: completed in 266.810 ms, heap usage 238.869 MB -> 70.034 MB. [2026-02-08T09:58:30.571Z] ====== als (apache-spark) [default], iteration 29 completed (35970.784 ms) ====== [2026-02-08T09:58:30.933Z] ----------------------------------- [2026-02-08T09:58:30.933Z] renaissance-als_0_PASSED [2026-02-08T09:58:30.933Z] ----------------------------------- [2026-02-08T09:58:30.933Z] [2026-02-08T09:58:30.933Z] TEST TEARDOWN: [2026-02-08T09:58:30.933Z] Nothing to be done for teardown. [2026-02-08T09:58:31.289Z] renaissance-als_0 Finish Time: Sun Feb 8 09:58:31 2026 Epoch Time (ms): 1770544711014