[2026-01-30T19:19:33.645Z] Running test renaissance-log-regression_0 ... [2026-01-30T19:19:33.995Z] =============================================== [2026-01-30T19:19:33.995Z] renaissance-log-regression_0 Start Time: Fri Jan 30 19:19:33 2026 Epoch Time (ms): 1769800773860 [2026-01-30T19:19:34.351Z] variation: NoOptions [2026-01-30T19:19:34.351Z] JVM_OPTIONS: [2026-01-30T19:19:34.351Z] { \ [2026-01-30T19:19:34.351Z] echo ""; echo "TEST SETUP:"; \ [2026-01-30T19:19:34.351Z] echo "Nothing to be done for setup."; \ [2026-01-30T19:19:34.351Z] mkdir -p "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\renaissance-log-regression_0"; \ [2026-01-30T19:19:34.351Z] cd "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\renaissance-log-regression_0"; \ [2026-01-30T19:19:34.351Z] echo ""; echo "TESTING:"; \ [2026-01-30T19:19:34.351Z] "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_17697998804430\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2026-01-30T19:19:34.351Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_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_17697998804430\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-30T19:19:34.351Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-30T19:19:34.351Z] echo "Nothing to be done for teardown."; \ [2026-01-30T19:19:34.351Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\TestTargetResult"; [2026-01-30T19:19:34.351Z] [2026-01-30T19:19:34.351Z] TEST SETUP: [2026-01-30T19:19:34.351Z] Nothing to be done for setup. [2026-01-30T19:19:34.351Z] [2026-01-30T19:19:34.351Z] TESTING: [2026-01-30T19:19:45.145Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-30T19:19:47.410Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2026-01-30T19:19:47.410Z] GC before operation: completed in 32.868 ms, heap usage 58.494 MB -> 35.570 MB. [2026-01-30T19:19:56.563Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8030.853 ms) ====== [2026-01-30T19:19:56.563Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2026-01-30T19:19:56.563Z] GC before operation: completed in 76.933 ms, heap usage 309.912 MB -> 123.096 MB. [2026-01-30T19:19:56.955Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1672.048 ms) ====== [2026-01-30T19:19:56.955Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2026-01-30T19:19:57.593Z] GC before operation: completed in 50.882 ms, heap usage 524.534 MB -> 124.243 MB. [2026-01-30T19:19:58.786Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1517.904 ms) ====== [2026-01-30T19:19:58.786Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2026-01-30T19:19:58.786Z] GC before operation: completed in 55.023 ms, heap usage 286.770 MB -> 123.945 MB. [2026-01-30T19:19:59.978Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1189.956 ms) ====== [2026-01-30T19:19:59.978Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2026-01-30T19:19:59.978Z] GC before operation: completed in 67.864 ms, heap usage 297.264 MB -> 124.355 MB. [2026-01-30T19:20:01.122Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1088.648 ms) ====== [2026-01-30T19:20:01.122Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2026-01-30T19:20:01.122Z] GC before operation: completed in 55.406 ms, heap usage 331.210 MB -> 124.746 MB. [2026-01-30T19:20:02.302Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1053.193 ms) ====== [2026-01-30T19:20:02.302Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2026-01-30T19:20:02.302Z] GC before operation: completed in 58.509 ms, heap usage 387.539 MB -> 125.151 MB. [2026-01-30T19:20:03.130Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1009.493 ms) ====== [2026-01-30T19:20:03.130Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2026-01-30T19:20:03.130Z] GC before operation: completed in 68.980 ms, heap usage 403.414 MB -> 125.384 MB. [2026-01-30T19:20:04.397Z] ====== log-regression (apache-spark) [default], iteration 7 completed (989.154 ms) ====== [2026-01-30T19:20:04.397Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2026-01-30T19:20:04.397Z] GC before operation: completed in 57.582 ms, heap usage 303.831 MB -> 125.366 MB. [2026-01-30T19:20:05.278Z] ====== log-regression (apache-spark) [default], iteration 8 completed (844.993 ms) ====== [2026-01-30T19:20:05.278Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2026-01-30T19:20:05.278Z] GC before operation: completed in 61.879 ms, heap usage 529.635 MB -> 126.159 MB. [2026-01-30T19:20:06.496Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1100.338 ms) ====== [2026-01-30T19:20:06.496Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2026-01-30T19:20:06.496Z] GC before operation: completed in 62.159 ms, heap usage 235.627 MB -> 125.637 MB. [2026-01-30T19:20:07.328Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1033.380 ms) ====== [2026-01-30T19:20:07.328Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2026-01-30T19:20:07.780Z] GC before operation: completed in 101.724 ms, heap usage 296.678 MB -> 126.007 MB. [2026-01-30T19:20:08.597Z] ====== log-regression (apache-spark) [default], iteration 11 completed (967.781 ms) ====== [2026-01-30T19:20:08.597Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2026-01-30T19:20:08.597Z] GC before operation: completed in 62.025 ms, heap usage 699.900 MB -> 127.364 MB. [2026-01-30T19:20:09.389Z] ====== log-regression (apache-spark) [default], iteration 12 completed (953.644 ms) ====== [2026-01-30T19:20:09.389Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2026-01-30T19:20:09.806Z] GC before operation: completed in 68.620 ms, heap usage 392.582 MB -> 126.668 MB. [2026-01-30T19:20:10.568Z] ====== log-regression (apache-spark) [default], iteration 13 completed (988.380 ms) ====== [2026-01-30T19:20:10.568Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2026-01-30T19:20:10.568Z] GC before operation: completed in 64.182 ms, heap usage 324.466 MB -> 126.601 MB. [2026-01-30T19:20:11.310Z] ====== log-regression (apache-spark) [default], iteration 14 completed (760.582 ms) ====== [2026-01-30T19:20:11.310Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2026-01-30T19:20:11.310Z] GC before operation: completed in 68.074 ms, heap usage 300.589 MB -> 126.730 MB. [2026-01-30T19:20:12.508Z] ====== log-regression (apache-spark) [default], iteration 15 completed (808.726 ms) ====== [2026-01-30T19:20:12.508Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2026-01-30T19:20:12.508Z] GC before operation: completed in 68.482 ms, heap usage 460.716 MB -> 127.512 MB. [2026-01-30T19:20:13.306Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1072.177 ms) ====== [2026-01-30T19:20:13.306Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2026-01-30T19:20:13.716Z] GC before operation: completed in 69.123 ms, heap usage 257.062 MB -> 127.068 MB. [2026-01-30T19:20:14.462Z] ====== log-regression (apache-spark) [default], iteration 17 completed (871.631 ms) ====== [2026-01-30T19:20:14.462Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2026-01-30T19:20:14.462Z] GC before operation: completed in 62.734 ms, heap usage 517.638 MB -> 127.926 MB. [2026-01-30T19:20:15.719Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1034.605 ms) ====== [2026-01-30T19:20:15.719Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2026-01-30T19:20:15.719Z] GC before operation: completed in 72.403 ms, heap usage 319.835 MB -> 127.748 MB. [2026-01-30T19:20:16.429Z] ====== log-regression (apache-spark) [default], iteration 19 completed (812.890 ms) ====== [2026-01-30T19:20:16.767Z] ----------------------------------- [2026-01-30T19:20:16.767Z] renaissance-log-regression_0_PASSED [2026-01-30T19:20:16.767Z] ----------------------------------- [2026-01-30T19:20:16.767Z] [2026-01-30T19:20:16.767Z] TEST TEARDOWN: [2026-01-30T19:20:16.767Z] Nothing to be done for teardown. [2026-01-30T19:20:16.767Z] renaissance-log-regression_0 Finish Time: Fri Jan 30 19:20:16 2026 Epoch Time (ms): 1769800816712