[2026-02-11T20:26:45.957Z] Running test renaissance-als_0 ... [2026-02-11T20:26:45.957Z] =============================================== [2026-02-11T20:26:46.291Z] renaissance-als_0 Start Time: Wed Feb 11 20:26:46 2026 Epoch Time (ms): 1770841606005 [2026-02-11T20:26:46.291Z] variation: NoOptions [2026-02-11T20:26:46.664Z] JVM_OPTIONS: [2026-02-11T20:26:46.664Z] { \ [2026-02-11T20:26:46.664Z] echo ""; echo "TEST SETUP:"; \ [2026-02-11T20:26:46.664Z] echo "Nothing to be done for setup."; \ [2026-02-11T20:26:46.664Z] mkdir -p "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1770841312372\\renaissance-als_0"; \ [2026-02-11T20:26:46.664Z] cd "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1770841312372\\renaissance-als_0"; \ [2026-02-11T20:26:46.664Z] echo ""; echo "TESTING:"; \ [2026-02-11T20:26:46.664Z] "c:/workspace/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1770841312372\\renaissance-als_0"\\als.json" als; \ [2026-02-11T20:26:46.664Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1770841312372\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-11T20:26:46.664Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-11T20:26:46.664Z] echo "Nothing to be done for teardown."; \ [2026-02-11T20:26:46.664Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1770841312372\\TestTargetResult"; [2026-02-11T20:26:46.664Z] [2026-02-11T20:26:46.664Z] TEST SETUP: [2026-02-11T20:26:46.664Z] Nothing to be done for setup. [2026-02-11T20:26:46.664Z] [2026-02-11T20:26:46.664Z] TESTING: [2026-02-11T20:27:10.626Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-11T20:27:16.676Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-11T20:27:16.676Z] GC before operation: completed in 53.197 ms, heap usage 52.944 MB -> 35.545 MB. [2026-02-11T20:28:04.996Z] ====== als (apache-spark) [default], iteration 0 completed (45730.588 ms) ====== [2026-02-11T20:28:04.996Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-11T20:28:04.996Z] GC before operation: completed in 141.960 ms, heap usage 394.239 MB -> 62.628 MB. [2026-02-11T20:28:32.910Z] ====== als (apache-spark) [default], iteration 1 completed (27158.508 ms) ====== [2026-02-11T20:28:32.910Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-11T20:28:32.910Z] GC before operation: completed in 138.091 ms, heap usage 1.271 GB -> 64.575 MB. [2026-02-11T20:29:00.819Z] ====== als (apache-spark) [default], iteration 2 completed (26252.842 ms) ====== [2026-02-11T20:29:00.819Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-11T20:29:00.819Z] GC before operation: completed in 130.703 ms, heap usage 1.449 GB -> 65.489 MB. [2026-02-11T20:29:24.029Z] ====== als (apache-spark) [default], iteration 3 completed (25701.857 ms) ====== [2026-02-11T20:29:24.029Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-11T20:29:24.029Z] GC before operation: completed in 127.062 ms, heap usage 872.238 MB -> 66.509 MB. [2026-02-11T20:29:51.971Z] ====== als (apache-spark) [default], iteration 4 completed (25871.785 ms) ====== [2026-02-11T20:29:51.971Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-11T20:29:51.971Z] GC before operation: completed in 133.119 ms, heap usage 713.738 MB -> 65.950 MB. [2026-02-11T20:30:19.862Z] ====== als (apache-spark) [default], iteration 5 completed (26280.293 ms) ====== [2026-02-11T20:30:19.862Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-11T20:30:19.862Z] GC before operation: completed in 144.418 ms, heap usage 1.165 GB -> 66.908 MB. [2026-02-11T20:30:43.168Z] ====== als (apache-spark) [default], iteration 6 completed (25980.902 ms) ====== [2026-02-11T20:30:43.168Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-11T20:30:43.168Z] GC before operation: completed in 126.287 ms, heap usage 1.070 GB -> 67.464 MB. [2026-02-11T20:31:11.182Z] ====== als (apache-spark) [default], iteration 7 completed (26285.997 ms) ====== [2026-02-11T20:31:11.182Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-11T20:31:11.182Z] GC before operation: completed in 160.825 ms, heap usage 562.052 MB -> 67.380 MB. [2026-02-11T20:31:39.141Z] ====== als (apache-spark) [default], iteration 8 completed (26632.049 ms) ====== [2026-02-11T20:31:39.141Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-11T20:31:39.141Z] GC before operation: completed in 127.849 ms, heap usage 458.298 MB -> 67.910 MB. [2026-02-11T20:32:02.600Z] ====== als (apache-spark) [default], iteration 9 completed (25508.153 ms) ====== [2026-02-11T20:32:02.600Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-11T20:32:03.048Z] GC before operation: completed in 112.544 ms, heap usage 869.109 MB -> 68.503 MB. [2026-02-11T20:32:30.965Z] ====== als (apache-spark) [default], iteration 10 completed (25006.422 ms) ====== [2026-02-11T20:32:30.965Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-11T20:32:30.965Z] GC before operation: completed in 108.798 ms, heap usage 384.357 MB -> 68.209 MB. [2026-02-11T20:32:54.215Z] ====== als (apache-spark) [default], iteration 11 completed (25446.126 ms) ====== [2026-02-11T20:32:54.216Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-11T20:32:54.216Z] GC before operation: completed in 123.070 ms, heap usage 888.761 MB -> 69.103 MB. [2026-02-11T20:33:22.116Z] ====== als (apache-spark) [default], iteration 12 completed (24943.137 ms) ====== [2026-02-11T20:33:22.116Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-11T20:33:22.116Z] GC before operation: completed in 109.146 ms, heap usage 793.135 MB -> 69.179 MB. [2026-02-11T20:33:45.337Z] ====== als (apache-spark) [default], iteration 13 completed (25011.118 ms) ====== [2026-02-11T20:33:45.337Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-11T20:33:45.337Z] GC before operation: completed in 126.393 ms, heap usage 1.113 GB -> 69.844 MB. [2026-02-11T20:34:13.217Z] ====== als (apache-spark) [default], iteration 14 completed (24985.511 ms) ====== [2026-02-11T20:34:13.217Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-11T20:34:13.217Z] GC before operation: completed in 113.719 ms, heap usage 875.597 MB -> 69.794 MB. [2026-02-11T20:34:36.439Z] ====== als (apache-spark) [default], iteration 15 completed (25337.210 ms) ====== [2026-02-11T20:34:36.439Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-11T20:34:36.439Z] GC before operation: completed in 114.579 ms, heap usage 266.837 MB -> 69.243 MB. [2026-02-11T20:35:04.317Z] ====== als (apache-spark) [default], iteration 16 completed (25109.998 ms) ====== [2026-02-11T20:35:04.317Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-11T20:35:04.317Z] GC before operation: completed in 117.406 ms, heap usage 158.078 MB -> 69.052 MB. [2026-02-11T20:35:27.594Z] ====== als (apache-spark) [default], iteration 17 completed (26011.938 ms) ====== [2026-02-11T20:35:27.594Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-11T20:35:27.594Z] GC before operation: completed in 119.354 ms, heap usage 430.941 MB -> 69.925 MB. [2026-02-11T20:35:55.492Z] ====== als (apache-spark) [default], iteration 18 completed (25078.530 ms) ====== [2026-02-11T20:35:55.492Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-11T20:35:55.492Z] GC before operation: completed in 118.143 ms, heap usage 647.561 MB -> 70.648 MB. [2026-02-11T20:36:23.337Z] ====== als (apache-spark) [default], iteration 19 completed (26457.126 ms) ====== [2026-02-11T20:36:23.337Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-11T20:36:23.337Z] GC before operation: completed in 121.330 ms, heap usage 221.173 MB -> 70.262 MB. [2026-02-11T20:36:46.591Z] ====== als (apache-spark) [default], iteration 20 completed (26091.671 ms) ====== [2026-02-11T20:36:46.591Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-11T20:36:46.591Z] GC before operation: completed in 132.449 ms, heap usage 733.695 MB -> 71.386 MB. [2026-02-11T20:37:14.532Z] ====== als (apache-spark) [default], iteration 21 completed (25557.476 ms) ====== [2026-02-11T20:37:14.532Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-11T20:37:14.532Z] GC before operation: completed in 119.029 ms, heap usage 947.957 MB -> 71.844 MB. [2026-02-11T20:37:37.781Z] ====== als (apache-spark) [default], iteration 22 completed (25079.557 ms) ====== [2026-02-11T20:37:37.781Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-11T20:37:37.781Z] GC before operation: completed in 122.613 ms, heap usage 232.627 MB -> 71.109 MB. [2026-02-11T20:38:05.698Z] ====== als (apache-spark) [default], iteration 23 completed (24789.078 ms) ====== [2026-02-11T20:38:05.698Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-11T20:38:05.698Z] GC before operation: completed in 134.085 ms, heap usage 886.963 MB -> 72.207 MB. [2026-02-11T20:38:28.893Z] ====== als (apache-spark) [default], iteration 24 completed (25076.196 ms) ====== [2026-02-11T20:38:28.893Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-11T20:38:28.893Z] GC before operation: completed in 120.884 ms, heap usage 1.022 GB -> 72.697 MB. [2026-02-11T20:38:56.732Z] ====== als (apache-spark) [default], iteration 25 completed (24660.322 ms) ====== [2026-02-11T20:38:56.732Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-11T20:38:56.732Z] GC before operation: completed in 117.984 ms, heap usage 825.847 MB -> 72.755 MB. [2026-02-11T20:39:19.922Z] ====== als (apache-spark) [default], iteration 26 completed (24902.358 ms) ====== [2026-02-11T20:39:19.922Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-11T20:39:19.922Z] GC before operation: completed in 125.968 ms, heap usage 1.013 GB -> 73.325 MB. [2026-02-11T20:39:43.172Z] ====== als (apache-spark) [default], iteration 27 completed (24911.041 ms) ====== [2026-02-11T20:39:43.172Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-11T20:39:43.172Z] GC before operation: completed in 126.215 ms, heap usage 805.942 MB -> 73.229 MB. [2026-02-11T20:40:11.077Z] ====== als (apache-spark) [default], iteration 28 completed (25178.405 ms) ====== [2026-02-11T20:40:11.077Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-11T20:40:11.077Z] GC before operation: completed in 121.005 ms, heap usage 121.990 MB -> 72.220 MB. [2026-02-11T20:40:34.318Z] ====== als (apache-spark) [default], iteration 29 completed (25319.915 ms) ====== [2026-02-11T20:40:35.535Z] ----------------------------------- [2026-02-11T20:40:35.535Z] renaissance-als_0_PASSED [2026-02-11T20:40:35.535Z] ----------------------------------- [2026-02-11T20:40:35.870Z] [2026-02-11T20:40:35.870Z] TEST TEARDOWN: [2026-02-11T20:40:35.870Z] Nothing to be done for teardown. [2026-02-11T20:40:36.249Z] renaissance-als_0 Finish Time: Wed Feb 11 20:40:35 2026 Epoch Time (ms): 1770842435895