[2025-08-27T22:35:18.514Z] Running test renaissance-log-regression_0 ... [2025-08-27T22:35:18.819Z] =============================================== [2025-08-27T22:35:18.819Z] renaissance-log-regression_0 Start Time: Wed Aug 27 22:35:18 2025 Epoch Time (ms): 1756334118694 [2025-08-27T22:35:18.819Z] variation: NoOptions [2025-08-27T22:35:19.136Z] JVM_OPTIONS: [2025-08-27T22:35:19.136Z] { \ [2025-08-27T22:35:19.136Z] echo ""; echo "TEST SETUP:"; \ [2025-08-27T22:35:19.136Z] echo "Nothing to be done for setup."; \ [2025-08-27T22:35:19.136Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\renaissance-log-regression_0"; \ [2025-08-27T22:35:19.136Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\renaissance-log-regression_0"; \ [2025-08-27T22:35:19.136Z] echo ""; echo "TESTING:"; \ [2025-08-27T22:35:19.136Z] "c:/jenkins/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:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-08-27T22:35:19.136Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-27T22:35:19.136Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-27T22:35:19.136Z] echo "Nothing to be done for teardown."; \ [2025-08-27T22:35:19.136Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17563326569832\\TestTargetResult"; [2025-08-27T22:35:19.136Z] [2025-08-27T22:35:19.137Z] TEST SETUP: [2025-08-27T22:35:19.137Z] Nothing to be done for setup. [2025-08-27T22:35:19.137Z] [2025-08-27T22:35:19.137Z] TESTING: [2025-08-27T22:35:31.914Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-27T22:35:34.061Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-27T22:35:34.061Z] GC before operation: completed in 45.604 ms, heap usage 50.554 MB -> 35.436 MB. [2025-08-27T22:35:44.758Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9980.911 ms) ====== [2025-08-27T22:35:44.758Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-27T22:35:44.758Z] GC before operation: completed in 94.134 ms, heap usage 382.841 MB -> 120.432 MB. [2025-08-27T22:35:45.940Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2045.471 ms) ====== [2025-08-27T22:35:45.940Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-27T22:35:46.271Z] GC before operation: completed in 69.032 ms, heap usage 266.307 MB -> 120.626 MB. [2025-08-27T22:35:47.866Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1683.327 ms) ====== [2025-08-27T22:35:47.866Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-27T22:35:47.866Z] GC before operation: completed in 82.612 ms, heap usage 377.958 MB -> 121.308 MB. [2025-08-27T22:35:49.605Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1461.146 ms) ====== [2025-08-27T22:35:49.605Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-27T22:35:49.605Z] GC before operation: completed in 72.658 ms, heap usage 424.470 MB -> 121.746 MB. [2025-08-27T22:35:50.706Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1380.027 ms) ====== [2025-08-27T22:35:50.706Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-27T22:35:51.064Z] GC before operation: completed in 79.293 ms, heap usage 274.077 MB -> 121.614 MB. [2025-08-27T22:35:52.231Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1280.575 ms) ====== [2025-08-27T22:35:52.231Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-27T22:35:52.231Z] GC before operation: completed in 78.637 ms, heap usage 290.285 MB -> 121.831 MB. [2025-08-27T22:35:53.892Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1306.523 ms) ====== [2025-08-27T22:35:53.892Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-27T22:35:53.892Z] GC before operation: completed in 75.768 ms, heap usage 368.813 MB -> 122.320 MB. [2025-08-27T22:35:55.021Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1296.547 ms) ====== [2025-08-27T22:35:55.021Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-27T22:35:55.021Z] GC before operation: completed in 81.447 ms, heap usage 268.649 MB -> 122.294 MB. [2025-08-27T22:35:56.151Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1244.411 ms) ====== [2025-08-27T22:35:56.151Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-27T22:35:56.650Z] GC before operation: completed in 81.181 ms, heap usage 430.125 MB -> 122.962 MB. [2025-08-27T22:35:57.760Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1307.478 ms) ====== [2025-08-27T22:35:57.760Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-27T22:35:57.760Z] GC before operation: completed in 79.458 ms, heap usage 270.793 MB -> 122.655 MB. [2025-08-27T22:35:58.884Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1237.530 ms) ====== [2025-08-27T22:35:58.884Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-27T22:35:58.884Z] GC before operation: completed in 78.986 ms, heap usage 431.986 MB -> 123.386 MB. [2025-08-27T22:36:00.126Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1189.859 ms) ====== [2025-08-27T22:36:00.126Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-27T22:36:00.519Z] GC before operation: completed in 85.690 ms, heap usage 429.382 MB -> 123.631 MB. [2025-08-27T22:36:01.641Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1208.577 ms) ====== [2025-08-27T22:36:01.641Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-27T22:36:01.641Z] GC before operation: completed in 83.353 ms, heap usage 257.462 MB -> 123.145 MB. [2025-08-27T22:36:02.822Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1137.138 ms) ====== [2025-08-27T22:36:02.822Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-27T22:36:02.822Z] GC before operation: completed in 83.644 ms, heap usage 258.807 MB -> 123.469 MB. [2025-08-27T22:36:03.930Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1198.912 ms) ====== [2025-08-27T22:36:03.930Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-27T22:36:04.359Z] GC before operation: completed in 83.283 ms, heap usage 426.797 MB -> 124.203 MB. [2025-08-27T22:36:05.522Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1177.304 ms) ====== [2025-08-27T22:36:05.522Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-27T22:36:05.522Z] GC before operation: completed in 85.727 ms, heap usage 424.232 MB -> 124.433 MB. [2025-08-27T22:36:06.725Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1216.198 ms) ====== [2025-08-27T22:36:06.725Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-27T22:36:06.725Z] GC before operation: completed in 87.325 ms, heap usage 422.291 MB -> 124.630 MB. [2025-08-27T22:36:07.896Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1140.039 ms) ====== [2025-08-27T22:36:07.896Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-27T22:36:07.896Z] GC before operation: completed in 85.017 ms, heap usage 275.323 MB -> 124.308 MB. [2025-08-27T22:36:09.002Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1126.645 ms) ====== [2025-08-27T22:36:09.002Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-27T22:36:09.002Z] GC before operation: completed in 86.771 ms, heap usage 417.468 MB -> 125.020 MB. [2025-08-27T22:36:10.761Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1366.563 ms) ====== [2025-08-27T22:36:10.761Z] ----------------------------------- [2025-08-27T22:36:10.762Z] renaissance-log-regression_0_PASSED [2025-08-27T22:36:10.762Z] ----------------------------------- [2025-08-27T22:36:11.096Z] [2025-08-27T22:36:11.096Z] TEST TEARDOWN: [2025-08-27T22:36:11.096Z] Nothing to be done for teardown. [2025-08-27T22:36:11.096Z] renaissance-log-regression_0 Finish Time: Wed Aug 27 22:36:10 2025 Epoch Time (ms): 1756334170867