[2025-09-03T22:01:44.751Z] Running test renaissance-log-regression_0 ... [2025-09-03T22:01:44.751Z] =============================================== [2025-09-03T22:01:44.751Z] renaissance-log-regression_0 Start Time: Wed Sep 3 15:01:44 2025 Epoch Time (ms): 1756936904462 [2025-09-03T22:01:44.751Z] variation: NoOptions [2025-09-03T22:01:45.168Z] JVM_OPTIONS: [2025-09-03T22:01:45.168Z] { \ [2025-09-03T22:01:45.168Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T22:01:45.168Z] echo "Nothing to be done for setup."; \ [2025-09-03T22:01:45.168Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-log-regression_0"; \ [2025-09-03T22:01:45.168Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-log-regression_0"; \ [2025-09-03T22:01:45.168Z] echo ""; echo "TESTING:"; \ [2025-09-03T22:01:45.168Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-09-03T22:01:45.168Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T22:01:45.168Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T22:01:45.168Z] echo "Nothing to be done for teardown."; \ [2025-09-03T22:01:45.168Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17569344478542/TestTargetResult"; [2025-09-03T22:01:45.168Z] [2025-09-03T22:01:45.168Z] TEST SETUP: [2025-09-03T22:01:45.168Z] Nothing to be done for setup. [2025-09-03T22:01:45.168Z] [2025-09-03T22:01:45.168Z] TESTING: [2025-09-03T22:02:03.130Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-09-03T22:02:13.187Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-09-03T22:02:13.187Z] GC before operation: completed in 103.010 ms, heap usage 81.307 MB -> 34.746 MB. [2025-09-03T22:02:43.260Z] ====== log-regression (apache-spark) [default], iteration 0 completed (29126.931 ms) ====== [2025-09-03T22:02:43.260Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-09-03T22:02:43.260Z] GC before operation: completed in 322.466 ms, heap usage 1.133 GB -> 118.272 MB. [2025-09-03T22:02:49.073Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6497.321 ms) ====== [2025-09-03T22:02:49.073Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-09-03T22:02:49.073Z] GC before operation: completed in 246.511 ms, heap usage 343.734 MB -> 117.513 MB. [2025-09-03T22:02:56.596Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7411.737 ms) ====== [2025-09-03T22:02:56.596Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-09-03T22:02:56.596Z] GC before operation: completed in 203.023 ms, heap usage 226.700 MB -> 117.678 MB. [2025-09-03T22:03:02.745Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6798.993 ms) ====== [2025-09-03T22:03:02.745Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-09-03T22:03:03.283Z] GC before operation: completed in 285.595 ms, heap usage 469.816 MB -> 118.668 MB. [2025-09-03T22:03:09.287Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5511.973 ms) ====== [2025-09-03T22:03:09.287Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-09-03T22:03:09.287Z] GC before operation: completed in 153.147 ms, heap usage 393.759 MB -> 118.633 MB. [2025-09-03T22:03:15.371Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6466.509 ms) ====== [2025-09-03T22:03:15.371Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-09-03T22:03:15.371Z] GC before operation: completed in 203.736 ms, heap usage 451.169 MB -> 118.903 MB. [2025-09-03T22:03:21.575Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5165.110 ms) ====== [2025-09-03T22:03:21.575Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-09-03T22:03:21.575Z] GC before operation: completed in 187.338 ms, heap usage 453.015 MB -> 119.184 MB. [2025-09-03T22:03:26.677Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5296.614 ms) ====== [2025-09-03T22:03:26.677Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-09-03T22:03:26.677Z] GC before operation: completed in 211.251 ms, heap usage 436.654 MB -> 119.297 MB. [2025-09-03T22:03:31.436Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4803.304 ms) ====== [2025-09-03T22:03:31.436Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-09-03T22:03:31.436Z] GC before operation: completed in 180.140 ms, heap usage 443.627 MB -> 119.604 MB. [2025-09-03T22:03:36.507Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5216.294 ms) ====== [2025-09-03T22:03:36.507Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-09-03T22:03:37.066Z] GC before operation: completed in 275.776 ms, heap usage 374.267 MB -> 119.526 MB. [2025-09-03T22:03:42.095Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4520.735 ms) ====== [2025-09-03T22:03:42.096Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-09-03T22:03:42.096Z] GC before operation: completed in 385.626 ms, heap usage 536.169 MB -> 120.204 MB. [2025-09-03T22:03:48.135Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5695.263 ms) ====== [2025-09-03T22:03:48.136Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-09-03T22:03:48.136Z] GC before operation: completed in 204.892 ms, heap usage 346.193 MB -> 119.897 MB. [2025-09-03T22:03:52.955Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5307.393 ms) ====== [2025-09-03T22:03:52.955Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-09-03T22:03:53.478Z] GC before operation: completed in 308.683 ms, heap usage 304.227 MB -> 119.952 MB. [2025-09-03T22:03:57.285Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4143.746 ms) ====== [2025-09-03T22:03:57.285Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-09-03T22:03:57.686Z] GC before operation: completed in 173.547 ms, heap usage 425.608 MB -> 120.453 MB. [2025-09-03T22:04:02.659Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4958.655 ms) ====== [2025-09-03T22:04:02.659Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-09-03T22:04:03.221Z] GC before operation: completed in 302.797 ms, heap usage 381.771 MB -> 120.494 MB. [2025-09-03T22:04:08.250Z] ====== log-regression (apache-spark) [default], iteration 15 completed (5422.840 ms) ====== [2025-09-03T22:04:08.250Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-09-03T22:04:08.894Z] GC before operation: completed in 157.128 ms, heap usage 435.775 MB -> 120.923 MB. [2025-09-03T22:04:12.776Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3584.572 ms) ====== [2025-09-03T22:04:12.776Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-09-03T22:04:12.776Z] GC before operation: completed in 235.554 ms, heap usage 450.249 MB -> 121.220 MB. [2025-09-03T22:04:17.613Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5233.117 ms) ====== [2025-09-03T22:04:17.613Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-09-03T22:04:17.613Z] GC before operation: completed in 258.606 ms, heap usage 444.399 MB -> 121.287 MB. [2025-09-03T22:04:22.432Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4807.103 ms) ====== [2025-09-03T22:04:22.432Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-09-03T22:04:23.055Z] GC before operation: completed in 299.899 ms, heap usage 499.944 MB -> 121.676 MB. [2025-09-03T22:04:27.742Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4883.563 ms) ====== [2025-09-03T22:04:28.676Z] ----------------------------------- [2025-09-03T22:04:28.676Z] renaissance-log-regression_0_PASSED [2025-09-03T22:04:28.676Z] ----------------------------------- [2025-09-03T22:04:28.676Z] [2025-09-03T22:04:28.676Z] TEST TEARDOWN: [2025-09-03T22:04:28.676Z] Nothing to be done for teardown. [2025-09-03T22:04:28.676Z] renaissance-log-regression_0 Finish Time: Wed Sep 3 15:04:28 2025 Epoch Time (ms): 1756937068077