[2025-10-02T02:06:29.250Z] Running test renaissance-log-regression_0 ... [2025-10-02T02:06:29.250Z] =============================================== [2025-10-02T02:06:29.250Z] renaissance-log-regression_0 Start Time: Wed Oct 1 19:06:22 2025 Epoch Time (ms): 1759370782448 [2025-10-02T02:06:29.250Z] variation: NoOptions [2025-10-02T02:06:29.250Z] JVM_OPTIONS: [2025-10-02T02:06:29.675Z] { \ [2025-10-02T02:06:29.675Z] echo ""; echo "TEST SETUP:"; \ [2025-10-02T02:06:29.675Z] echo "Nothing to be done for setup."; \ [2025-10-02T02:06:29.675Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17593677319732/renaissance-log-regression_0"; \ [2025-10-02T02:06:29.675Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17593677319732/renaissance-log-regression_0"; \ [2025-10-02T02:06:29.675Z] echo ""; echo "TESTING:"; \ [2025-10-02T02:06:29.675Z] "/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_17593677319732/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-02T02:06:29.675Z] 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_17593677319732/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-02T02:06:29.675Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-02T02:06:29.675Z] echo "Nothing to be done for teardown."; \ [2025-10-02T02:06:29.675Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17593677319732/TestTargetResult"; [2025-10-02T02:06:29.675Z] [2025-10-02T02:06:29.675Z] TEST SETUP: [2025-10-02T02:06:29.675Z] Nothing to be done for setup. [2025-10-02T02:06:29.675Z] [2025-10-02T02:06:29.675Z] TESTING: [2025-10-02T02:06:47.924Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-02T02:06:59.700Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-02T02:06:59.700Z] GC before operation: completed in 47.693 ms, heap usage 67.338 MB -> 34.802 MB. [2025-10-02T02:07:30.119Z] ====== log-regression (apache-spark) [default], iteration 0 completed (27384.465 ms) ====== [2025-10-02T02:07:30.119Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-02T02:07:30.119Z] GC before operation: completed in 278.534 ms, heap usage 440.511 MB -> 117.057 MB. [2025-10-02T02:07:35.382Z] ====== log-regression (apache-spark) [default], iteration 1 completed (8527.245 ms) ====== [2025-10-02T02:07:35.382Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-02T02:07:35.382Z] GC before operation: completed in 127.164 ms, heap usage 379.087 MB -> 117.419 MB. [2025-10-02T02:07:43.156Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7376.236 ms) ====== [2025-10-02T02:07:43.156Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-02T02:07:43.156Z] GC before operation: completed in 218.023 ms, heap usage 509.269 MB -> 118.174 MB. [2025-10-02T02:07:49.197Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6401.026 ms) ====== [2025-10-02T02:07:49.197Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-02T02:07:49.197Z] GC before operation: completed in 173.635 ms, heap usage 487.939 MB -> 118.257 MB. [2025-10-02T02:07:56.678Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6590.283 ms) ====== [2025-10-02T02:07:56.678Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-02T02:07:56.678Z] GC before operation: completed in 195.479 ms, heap usage 320.798 MB -> 118.143 MB. [2025-10-02T02:08:01.436Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5389.484 ms) ====== [2025-10-02T02:08:01.436Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-02T02:08:01.437Z] GC before operation: completed in 186.690 ms, heap usage 405.816 MB -> 118.516 MB. [2025-10-02T02:08:07.608Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5098.190 ms) ====== [2025-10-02T02:08:07.608Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-02T02:08:07.608Z] GC before operation: completed in 293.194 ms, heap usage 345.254 MB -> 118.625 MB. [2025-10-02T02:08:12.422Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5608.149 ms) ====== [2025-10-02T02:08:12.422Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-02T02:08:12.861Z] GC before operation: completed in 214.311 ms, heap usage 435.439 MB -> 118.996 MB. [2025-10-02T02:08:17.768Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5119.114 ms) ====== [2025-10-02T02:08:17.768Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-02T02:08:18.331Z] GC before operation: completed in 213.590 ms, heap usage 410.214 MB -> 119.200 MB. [2025-10-02T02:08:23.111Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4849.698 ms) ====== [2025-10-02T02:08:23.111Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-02T02:08:23.111Z] GC before operation: completed in 266.983 ms, heap usage 377.934 MB -> 119.394 MB. [2025-10-02T02:08:27.199Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4074.944 ms) ====== [2025-10-02T02:08:27.199Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-02T02:08:27.733Z] GC before operation: completed in 250.042 ms, heap usage 271.864 MB -> 119.168 MB. [2025-10-02T02:08:33.670Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5371.835 ms) ====== [2025-10-02T02:08:33.670Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-02T02:08:33.670Z] GC before operation: completed in 179.913 ms, heap usage 421.037 MB -> 119.911 MB. [2025-10-02T02:08:37.591Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4328.586 ms) ====== [2025-10-02T02:08:37.591Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-02T02:08:37.591Z] GC before operation: completed in 181.085 ms, heap usage 253.979 MB -> 119.465 MB. [2025-10-02T02:08:42.608Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4475.830 ms) ====== [2025-10-02T02:08:42.608Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-02T02:08:42.608Z] GC before operation: completed in 211.112 ms, heap usage 407.439 MB -> 120.277 MB. [2025-10-02T02:08:47.313Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4421.467 ms) ====== [2025-10-02T02:08:47.313Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-02T02:08:47.313Z] GC before operation: completed in 212.480 ms, heap usage 227.269 MB -> 119.913 MB. [2025-10-02T02:08:52.142Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4423.822 ms) ====== [2025-10-02T02:08:52.142Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-02T02:08:52.142Z] GC before operation: completed in 216.212 ms, heap usage 287.475 MB -> 120.227 MB. [2025-10-02T02:08:56.803Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4688.462 ms) ====== [2025-10-02T02:08:56.803Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-02T02:08:56.803Z] GC before operation: completed in 151.592 ms, heap usage 526.694 MB -> 121.194 MB. [2025-10-02T02:09:00.635Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3617.039 ms) ====== [2025-10-02T02:09:00.635Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-02T02:09:00.635Z] GC before operation: completed in 151.813 ms, heap usage 369.835 MB -> 120.834 MB. [2025-10-02T02:09:04.449Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3836.245 ms) ====== [2025-10-02T02:09:04.449Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-02T02:09:04.449Z] GC before operation: completed in 167.774 ms, heap usage 389.883 MB -> 121.172 MB. [2025-10-02T02:09:09.631Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5145.083 ms) ====== [2025-10-02T02:09:10.455Z] ----------------------------------- [2025-10-02T02:09:10.455Z] renaissance-log-regression_0_PASSED [2025-10-02T02:09:10.455Z] ----------------------------------- [2025-10-02T02:09:10.455Z] [2025-10-02T02:09:10.455Z] TEST TEARDOWN: [2025-10-02T02:09:10.455Z] Nothing to be done for teardown. [2025-10-02T02:09:10.455Z] renaissance-log-regression_0 Finish Time: Wed Oct 1 19:09:03 2025 Epoch Time (ms): 1759370943494