[2026-02-08T09:33:33.258Z] Running test renaissance-als_0 ... [2026-02-08T09:33:33.615Z] =============================================== [2026-02-08T09:33:33.615Z] renaissance-als_0 Start Time: Sun Feb 8 09:33:33 2026 Epoch Time (ms): 1770543213465 [2026-02-08T09:33:34.015Z] variation: NoOptions [2026-02-08T09:33:34.015Z] JVM_OPTIONS: [2026-02-08T09:33:34.015Z] { \ [2026-02-08T09:33:34.015Z] echo ""; echo "TEST SETUP:"; \ [2026-02-08T09:33:34.015Z] echo "Nothing to be done for setup."; \ [2026-02-08T09:33:34.015Z] mkdir -p "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705428298050\\renaissance-als_0"; \ [2026-02-08T09:33:34.015Z] cd "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705428298050\\renaissance-als_0"; \ [2026-02-08T09:33:34.015Z] echo ""; echo "TESTING:"; \ [2026-02-08T09:33:34.015Z] "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_17705428298050\\renaissance-als_0"\\als.json" als; \ [2026-02-08T09:33:34.015Z] 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_17705428298050\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-08T09:33:34.015Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-08T09:33:34.015Z] echo "Nothing to be done for teardown."; \ [2026-02-08T09:33:34.015Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17705428298050\\TestTargetResult"; [2026-02-08T09:33:34.404Z] [2026-02-08T09:33:34.404Z] TEST SETUP: [2026-02-08T09:33:34.404Z] Nothing to be done for setup. [2026-02-08T09:33:34.404Z] [2026-02-08T09:33:34.404Z] TESTING: [2026-02-08T09:34:02.402Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-08T09:34:08.595Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-08T09:34:08.595Z] GC before operation: completed in 65.744 ms, heap usage 56.461 MB -> 35.515 MB. [2026-02-08T09:35:19.038Z] ====== als (apache-spark) [default], iteration 0 completed (63330.519 ms) ====== [2026-02-08T09:35:19.038Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-08T09:35:19.038Z] GC before operation: completed in 144.187 ms, heap usage 167.704 MB -> 62.363 MB. [2026-02-08T09:35:52.878Z] ====== als (apache-spark) [default], iteration 1 completed (37164.191 ms) ====== [2026-02-08T09:35:52.878Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-08T09:35:52.878Z] GC before operation: completed in 148.453 ms, heap usage 233.650 MB -> 63.171 MB. [2026-02-08T09:36:26.967Z] ====== als (apache-spark) [default], iteration 2 completed (36286.033 ms) ====== [2026-02-08T09:36:26.967Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-08T09:36:26.967Z] GC before operation: completed in 133.781 ms, heap usage 371.787 MB -> 64.122 MB. [2026-02-08T09:37:07.813Z] ====== als (apache-spark) [default], iteration 3 completed (35144.797 ms) ====== [2026-02-08T09:37:07.813Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-08T09:37:07.813Z] GC before operation: completed in 160.388 ms, heap usage 242.083 MB -> 64.384 MB. [2026-02-08T09:37:41.747Z] ====== als (apache-spark) [default], iteration 4 completed (35207.868 ms) ====== [2026-02-08T09:37:41.747Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-08T09:37:41.747Z] GC before operation: completed in 142.664 ms, heap usage 336.923 MB -> 65.197 MB. [2026-02-08T09:38:15.716Z] ====== als (apache-spark) [default], iteration 5 completed (34772.581 ms) ====== [2026-02-08T09:38:15.716Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-08T09:38:15.716Z] GC before operation: completed in 148.313 ms, heap usage 506.860 MB -> 65.972 MB. [2026-02-08T09:38:49.859Z] ====== als (apache-spark) [default], iteration 6 completed (35758.091 ms) ====== [2026-02-08T09:38:49.859Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-08T09:38:50.478Z] GC before operation: completed in 179.573 ms, heap usage 140.830 MB -> 66.092 MB. [2026-02-08T09:39:24.365Z] ====== als (apache-spark) [default], iteration 7 completed (32393.233 ms) ====== [2026-02-08T09:39:24.365Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-08T09:39:24.365Z] GC before operation: completed in 122.020 ms, heap usage 470.843 MB -> 67.076 MB. [2026-02-08T09:39:52.702Z] ====== als (apache-spark) [default], iteration 8 completed (26055.088 ms) ====== [2026-02-08T09:39:52.702Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-08T09:39:52.702Z] GC before operation: completed in 125.002 ms, heap usage 300.853 MB -> 67.266 MB. [2026-02-08T09:40:16.140Z] ====== als (apache-spark) [default], iteration 9 completed (25500.502 ms) ====== [2026-02-08T09:40:16.140Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-08T09:40:16.140Z] GC before operation: completed in 116.788 ms, heap usage 347.339 MB -> 67.648 MB. [2026-02-08T09:40:50.275Z] ====== als (apache-spark) [default], iteration 10 completed (31310.392 ms) ====== [2026-02-08T09:40:50.275Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-08T09:40:50.275Z] GC before operation: completed in 143.453 ms, heap usage 473.255 MB -> 68.055 MB. [2026-02-08T09:41:24.071Z] ====== als (apache-spark) [default], iteration 11 completed (34043.411 ms) ====== [2026-02-08T09:41:24.071Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-08T09:41:24.071Z] GC before operation: completed in 140.699 ms, heap usage 195.645 MB -> 67.878 MB. [2026-02-08T09:41:58.165Z] ====== als (apache-spark) [default], iteration 12 completed (34586.951 ms) ====== [2026-02-08T09:41:58.165Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-08T09:41:58.165Z] GC before operation: completed in 141.178 ms, heap usage 294.316 MB -> 68.241 MB. [2026-02-08T09:42:32.330Z] ====== als (apache-spark) [default], iteration 13 completed (34644.242 ms) ====== [2026-02-08T09:42:32.330Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-08T09:42:32.330Z] GC before operation: completed in 155.627 ms, heap usage 497.282 MB -> 68.836 MB. [2026-02-08T09:43:12.956Z] ====== als (apache-spark) [default], iteration 14 completed (34650.577 ms) ====== [2026-02-08T09:43:12.956Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-08T09:43:12.956Z] GC before operation: completed in 142.978 ms, heap usage 307.455 MB -> 68.750 MB. [2026-02-08T09:43:42.283Z] ====== als (apache-spark) [default], iteration 15 completed (34325.863 ms) ====== [2026-02-08T09:43:42.284Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-08T09:43:42.284Z] GC before operation: completed in 141.917 ms, heap usage 241.034 MB -> 68.992 MB. [2026-02-08T09:44:17.222Z] ====== als (apache-spark) [default], iteration 16 completed (34117.005 ms) ====== [2026-02-08T09:44:17.222Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-08T09:44:17.222Z] GC before operation: completed in 152.093 ms, heap usage 415.925 MB -> 69.346 MB. [2026-02-08T09:44:53.066Z] ====== als (apache-spark) [default], iteration 17 completed (34596.863 ms) ====== [2026-02-08T09:44:53.066Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-08T09:44:53.066Z] GC before operation: completed in 145.671 ms, heap usage 393.403 MB -> 69.588 MB. [2026-02-08T09:45:21.249Z] ====== als (apache-spark) [default], iteration 18 completed (26337.686 ms) ====== [2026-02-08T09:45:21.249Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-08T09:45:21.249Z] GC before operation: completed in 122.643 ms, heap usage 365.168 MB -> 69.911 MB. [2026-02-08T09:45:49.272Z] ====== als (apache-spark) [default], iteration 19 completed (29746.556 ms) ====== [2026-02-08T09:45:49.272Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-08T09:45:49.272Z] GC before operation: completed in 143.885 ms, heap usage 416.372 MB -> 70.223 MB. [2026-02-08T09:46:27.146Z] ====== als (apache-spark) [default], iteration 20 completed (35973.706 ms) ====== [2026-02-08T09:46:27.146Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-08T09:46:27.146Z] GC before operation: completed in 156.685 ms, heap usage 376.519 MB -> 70.435 MB. [2026-02-08T09:47:02.470Z] ====== als (apache-spark) [default], iteration 21 completed (34390.332 ms) ====== [2026-02-08T09:47:02.470Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-08T09:47:02.470Z] GC before operation: completed in 145.149 ms, heap usage 510.130 MB -> 70.915 MB. [2026-02-08T09:47:37.860Z] ====== als (apache-spark) [default], iteration 22 completed (35263.679 ms) ====== [2026-02-08T09:47:37.860Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-08T09:47:37.860Z] GC before operation: completed in 144.769 ms, heap usage 380.583 MB -> 70.982 MB. [2026-02-08T09:48:14.353Z] ====== als (apache-spark) [default], iteration 23 completed (34169.530 ms) ====== [2026-02-08T09:48:14.353Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-08T09:48:14.353Z] GC before operation: completed in 150.069 ms, heap usage 371.218 MB -> 71.132 MB. [2026-02-08T09:48:52.059Z] ====== als (apache-spark) [default], iteration 24 completed (34908.653 ms) ====== [2026-02-08T09:48:52.059Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-08T09:48:52.059Z] GC before operation: completed in 143.576 ms, heap usage 343.590 MB -> 71.596 MB. [2026-02-08T09:49:21.099Z] ====== als (apache-spark) [default], iteration 25 completed (34271.583 ms) ====== [2026-02-08T09:49:21.100Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-08T09:49:21.100Z] GC before operation: completed in 149.568 ms, heap usage 201.026 MB -> 71.502 MB. [2026-02-08T09:49:55.954Z] ====== als (apache-spark) [default], iteration 26 completed (34289.561 ms) ====== [2026-02-08T09:49:55.954Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-08T09:49:55.954Z] GC before operation: completed in 144.114 ms, heap usage 149.013 MB -> 71.993 MB. [2026-02-08T09:50:33.094Z] ====== als (apache-spark) [default], iteration 27 completed (34527.316 ms) ====== [2026-02-08T09:50:33.094Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-08T09:50:33.094Z] GC before operation: completed in 143.201 ms, heap usage 225.685 MB -> 71.456 MB. [2026-02-08T09:51:05.476Z] ====== als (apache-spark) [default], iteration 28 completed (34002.221 ms) ====== [2026-02-08T09:51:05.476Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-08T09:51:05.476Z] GC before operation: completed in 145.259 ms, heap usage 280.709 MB -> 71.941 MB. [2026-02-08T09:51:42.592Z] ====== als (apache-spark) [default], iteration 29 completed (34136.199 ms) ====== [2026-02-08T09:51:42.592Z] ----------------------------------- [2026-02-08T09:51:42.592Z] renaissance-als_0_PASSED [2026-02-08T09:51:42.592Z] ----------------------------------- [2026-02-08T09:51:42.592Z] [2026-02-08T09:51:42.592Z] TEST TEARDOWN: [2026-02-08T09:51:42.592Z] Nothing to be done for teardown. [2026-02-08T09:51:42.592Z] renaissance-als_0 Finish Time: Sun Feb 8 09:51:39 2026 Epoch Time (ms): 1770544299932