[2026-02-12T21:35:56.901Z] Running test renaissance-als_0 ... [2026-02-12T21:35:56.901Z] =============================================== [2026-02-12T21:35:56.901Z] renaissance-als_0 Start Time: Thu Feb 12 21:35:56 2026 Epoch Time (ms): 1770932156837 [2026-02-12T21:35:57.262Z] variation: NoOptions [2026-02-12T21:35:57.262Z] JVM_OPTIONS: [2026-02-12T21:35:57.262Z] { \ [2026-02-12T21:35:57.262Z] echo ""; echo "TEST SETUP:"; \ [2026-02-12T21:35:57.262Z] echo "Nothing to be done for setup."; \ [2026-02-12T21:35:57.262Z] mkdir -p "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17709319024677\\renaissance-als_0"; \ [2026-02-12T21:35:57.262Z] cd "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17709319024677\\renaissance-als_0"; \ [2026-02-12T21:35:57.262Z] echo ""; echo "TESTING:"; \ [2026-02-12T21:35:57.262Z] "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_17709319024677\\renaissance-als_0"\\als.json" als; \ [2026-02-12T21:35:57.263Z] 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_17709319024677\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-12T21:35:57.263Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-12T21:35:57.263Z] echo "Nothing to be done for teardown."; \ [2026-02-12T21:35:57.263Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17709319024677\\TestTargetResult"; [2026-02-12T21:35:57.263Z] [2026-02-12T21:35:57.263Z] TEST SETUP: [2026-02-12T21:35:57.263Z] Nothing to be done for setup. [2026-02-12T21:35:57.263Z] [2026-02-12T21:35:57.263Z] TESTING: [2026-02-12T21:36:20.341Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-12T21:36:22.037Z] WARNING: An illegal reflective access operation has occurred [2026-02-12T21:36:22.038Z] 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_17709319024677/renaissance-als_0/harness-213558-13552698109143453229/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-12T21:36:22.038Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-12T21:36:22.038Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-12T21:36:22.038Z] WARNING: All illegal access operations will be denied in a future release [2026-02-12T21:36:23.753Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-12T21:36:23.753Z] GC before operation: completed in 113.202 ms, heap usage 74.009 MB -> 34.948 MB. [2026-02-12T21:37:12.066Z] ====== als (apache-spark) [default], iteration 0 completed (42153.257 ms) ====== [2026-02-12T21:37:12.066Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-12T21:37:12.066Z] GC before operation: completed in 176.877 ms, heap usage 241.366 MB -> 60.877 MB. [2026-02-12T21:37:31.339Z] ====== als (apache-spark) [default], iteration 1 completed (23748.597 ms) ====== [2026-02-12T21:37:31.339Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-12T21:37:31.339Z] GC before operation: completed in 190.981 ms, heap usage 243.085 MB -> 61.637 MB. [2026-02-12T21:37:59.166Z] ====== als (apache-spark) [default], iteration 2 completed (23496.619 ms) ====== [2026-02-12T21:37:59.166Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-12T21:37:59.166Z] GC before operation: completed in 228.785 ms, heap usage 263.953 MB -> 62.306 MB. [2026-02-12T21:38:22.284Z] ====== als (apache-spark) [default], iteration 3 completed (23021.981 ms) ====== [2026-02-12T21:38:22.284Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-12T21:38:22.284Z] GC before operation: completed in 193.373 ms, heap usage 192.826 MB -> 62.714 MB. [2026-02-12T21:38:45.450Z] ====== als (apache-spark) [default], iteration 4 completed (23411.068 ms) ====== [2026-02-12T21:38:45.450Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-12T21:38:45.450Z] GC before operation: completed in 207.736 ms, heap usage 111.399 MB -> 63.202 MB. [2026-02-12T21:39:08.578Z] ====== als (apache-spark) [default], iteration 5 completed (23518.089 ms) ====== [2026-02-12T21:39:08.578Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-12T21:39:08.578Z] GC before operation: completed in 214.823 ms, heap usage 228.546 MB -> 63.418 MB. [2026-02-12T21:39:31.714Z] ====== als (apache-spark) [default], iteration 6 completed (22933.386 ms) ====== [2026-02-12T21:39:31.714Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-12T21:39:31.714Z] GC before operation: completed in 216.810 ms, heap usage 238.818 MB -> 64.411 MB. [2026-02-12T21:39:54.860Z] ====== als (apache-spark) [default], iteration 7 completed (22777.948 ms) ====== [2026-02-12T21:39:54.860Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-12T21:39:54.860Z] GC before operation: completed in 235.658 ms, heap usage 108.582 MB -> 64.359 MB. [2026-02-12T21:40:19.568Z] ====== als (apache-spark) [default], iteration 8 completed (22641.810 ms) ====== [2026-02-12T21:40:19.568Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-12T21:40:19.568Z] GC before operation: completed in 207.602 ms, heap usage 244.359 MB -> 65.446 MB. [2026-02-12T21:40:42.702Z] ====== als (apache-spark) [default], iteration 9 completed (23647.171 ms) ====== [2026-02-12T21:40:42.702Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-12T21:40:42.702Z] GC before operation: completed in 245.423 ms, heap usage 407.142 MB -> 65.872 MB. [2026-02-12T21:41:05.887Z] ====== als (apache-spark) [default], iteration 10 completed (23296.551 ms) ====== [2026-02-12T21:41:05.887Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-12T21:41:05.887Z] GC before operation: completed in 183.287 ms, heap usage 113.108 MB -> 65.856 MB. [2026-02-12T21:41:29.048Z] ====== als (apache-spark) [default], iteration 11 completed (22071.101 ms) ====== [2026-02-12T21:41:29.048Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-12T21:41:29.048Z] GC before operation: completed in 204.174 ms, heap usage 240.950 MB -> 66.227 MB. [2026-02-12T21:41:52.218Z] ====== als (apache-spark) [default], iteration 12 completed (22257.310 ms) ====== [2026-02-12T21:41:52.218Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-12T21:41:52.218Z] GC before operation: completed in 192.705 ms, heap usage 248.297 MB -> 66.477 MB. [2026-02-12T21:42:15.335Z] ====== als (apache-spark) [default], iteration 13 completed (23221.731 ms) ====== [2026-02-12T21:42:15.335Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-12T21:42:15.335Z] GC before operation: completed in 238.628 ms, heap usage 232.495 MB -> 66.604 MB. [2026-02-12T21:42:38.482Z] ====== als (apache-spark) [default], iteration 14 completed (22219.592 ms) ====== [2026-02-12T21:42:38.482Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-12T21:42:38.482Z] GC before operation: completed in 264.330 ms, heap usage 227.083 MB -> 66.975 MB. [2026-02-12T21:43:01.612Z] ====== als (apache-spark) [default], iteration 15 completed (22316.440 ms) ====== [2026-02-12T21:43:01.612Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-12T21:43:01.612Z] GC before operation: completed in 210.744 ms, heap usage 248.090 MB -> 67.235 MB. [2026-02-12T21:43:24.710Z] ====== als (apache-spark) [default], iteration 16 completed (22418.670 ms) ====== [2026-02-12T21:43:24.710Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-12T21:43:24.710Z] GC before operation: completed in 212.494 ms, heap usage 298.316 MB -> 67.487 MB. [2026-02-12T21:43:47.857Z] ====== als (apache-spark) [default], iteration 17 completed (22684.036 ms) ====== [2026-02-12T21:43:47.857Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-12T21:43:47.857Z] GC before operation: completed in 189.012 ms, heap usage 252.892 MB -> 67.761 MB. [2026-02-12T21:44:11.014Z] ====== als (apache-spark) [default], iteration 18 completed (22181.975 ms) ====== [2026-02-12T21:44:11.014Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-12T21:44:11.014Z] GC before operation: completed in 197.097 ms, heap usage 167.824 MB -> 67.892 MB. [2026-02-12T21:44:30.264Z] ====== als (apache-spark) [default], iteration 19 completed (22139.550 ms) ====== [2026-02-12T21:44:30.264Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-12T21:44:30.264Z] GC before operation: completed in 233.708 ms, heap usage 611.395 MB -> 68.685 MB. [2026-02-12T21:44:53.387Z] ====== als (apache-spark) [default], iteration 20 completed (22156.388 ms) ====== [2026-02-12T21:44:53.387Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-12T21:44:53.387Z] GC before operation: completed in 206.949 ms, heap usage 196.861 MB -> 68.388 MB. [2026-02-12T21:45:16.561Z] ====== als (apache-spark) [default], iteration 21 completed (22480.335 ms) ====== [2026-02-12T21:45:16.561Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-12T21:45:16.561Z] GC before operation: completed in 218.563 ms, heap usage 216.491 MB -> 68.629 MB. [2026-02-12T21:45:39.872Z] ====== als (apache-spark) [default], iteration 22 completed (23294.653 ms) ====== [2026-02-12T21:45:39.872Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-12T21:45:39.872Z] GC before operation: completed in 225.103 ms, heap usage 317.530 MB -> 69.131 MB. [2026-02-12T21:46:03.038Z] ====== als (apache-spark) [default], iteration 23 completed (22687.251 ms) ====== [2026-02-12T21:46:03.038Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-12T21:46:03.038Z] GC before operation: completed in 191.855 ms, heap usage 275.110 MB -> 69.273 MB. [2026-02-12T21:46:26.210Z] ====== als (apache-spark) [default], iteration 24 completed (22350.592 ms) ====== [2026-02-12T21:46:26.210Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-12T21:46:26.210Z] GC before operation: completed in 203.797 ms, heap usage 136.093 MB -> 69.403 MB. [2026-02-12T21:46:49.470Z] ====== als (apache-spark) [default], iteration 25 completed (23641.985 ms) ====== [2026-02-12T21:46:49.470Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-12T21:46:49.470Z] GC before operation: completed in 200.824 ms, heap usage 268.223 MB -> 69.767 MB. [2026-02-12T21:47:12.618Z] ====== als (apache-spark) [default], iteration 26 completed (21536.052 ms) ====== [2026-02-12T21:47:12.618Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-12T21:47:12.618Z] GC before operation: completed in 211.552 ms, heap usage 495.220 MB -> 70.433 MB. [2026-02-12T21:47:35.732Z] ====== als (apache-spark) [default], iteration 27 completed (22320.280 ms) ====== [2026-02-12T21:47:35.732Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-12T21:47:35.732Z] GC before operation: completed in 191.707 ms, heap usage 276.770 MB -> 69.800 MB. [2026-02-12T21:47:58.887Z] ====== als (apache-spark) [default], iteration 28 completed (22752.398 ms) ====== [2026-02-12T21:47:58.887Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-12T21:47:58.887Z] GC before operation: completed in 211.626 ms, heap usage 97.103 MB -> 69.918 MB. [2026-02-12T21:48:22.060Z] ====== als (apache-spark) [default], iteration 29 completed (23099.340 ms) ====== [2026-02-12T21:48:22.060Z] ----------------------------------- [2026-02-12T21:48:22.060Z] renaissance-als_0_PASSED [2026-02-12T21:48:22.060Z] ----------------------------------- [2026-02-12T21:48:22.060Z] [2026-02-12T21:48:22.060Z] TEST TEARDOWN: [2026-02-12T21:48:22.060Z] Nothing to be done for teardown. [2026-02-12T21:48:22.060Z] renaissance-als_0 Finish Time: Thu Feb 12 21:48:21 2026 Epoch Time (ms): 1770932901685