[2025-05-07T22:53:56.587Z] Running test renaissance-log-regression_0 ... [2025-05-07T22:53:56.587Z] =============================================== [2025-05-07T22:53:56.587Z] renaissance-log-regression_0 Start Time: Wed May 7 15:53:56 2025 Epoch Time (ms): 1746658436264 [2025-05-07T22:53:56.983Z] variation: NoOptions [2025-05-07T22:53:56.983Z] JVM_OPTIONS: [2025-05-07T22:53:56.983Z] { \ [2025-05-07T22:53:56.983Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T22:53:56.983Z] echo "Nothing to be done for setup."; \ [2025-05-07T22:53:56.983Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17466578783808/renaissance-log-regression_0"; \ [2025-05-07T22:53:56.983Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17466578783808/renaissance-log-regression_0"; \ [2025-05-07T22:53:56.983Z] echo ""; echo "TESTING:"; \ [2025-05-07T22:53:56.983Z] "/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_17466578783808/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-07T22:53:56.983Z] 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_17466578783808/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T22:53:56.983Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T22:53:56.983Z] echo "Nothing to be done for teardown."; \ [2025-05-07T22:53:56.983Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17466578783808/TestTargetResult"; [2025-05-07T22:53:56.983Z] [2025-05-07T22:53:56.983Z] TEST SETUP: [2025-05-07T22:53:56.983Z] Nothing to be done for setup. [2025-05-07T22:53:56.983Z] [2025-05-07T22:53:56.983Z] TESTING: [2025-05-07T22:54:02.340Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-07T22:54:04.360Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-07T22:54:04.360Z] GC before operation: completed in 25.383 ms, heap usage 52.700 MB -> 34.969 MB. [2025-05-07T22:54:12.403Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8011.698 ms) ====== [2025-05-07T22:54:12.403Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-07T22:54:12.403Z] GC before operation: completed in 62.581 ms, heap usage 549.911 MB -> 117.334 MB. [2025-05-07T22:54:14.413Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1595.800 ms) ====== [2025-05-07T22:54:14.413Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-07T22:54:14.413Z] GC before operation: completed in 43.106 ms, heap usage 321.127 MB -> 117.443 MB. [2025-05-07T22:54:15.819Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1583.242 ms) ====== [2025-05-07T22:54:15.819Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-07T22:54:15.819Z] GC before operation: completed in 53.119 ms, heap usage 256.773 MB -> 117.657 MB. [2025-05-07T22:54:17.240Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1560.242 ms) ====== [2025-05-07T22:54:17.240Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-07T22:54:17.240Z] GC before operation: completed in 52.055 ms, heap usage 503.151 MB -> 118.549 MB. [2025-05-07T22:54:18.647Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1303.152 ms) ====== [2025-05-07T22:54:18.647Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-07T22:54:18.647Z] GC before operation: completed in 54.267 ms, heap usage 378.844 MB -> 118.458 MB. [2025-05-07T22:54:20.160Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1471.644 ms) ====== [2025-05-07T22:54:20.160Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-07T22:54:20.160Z] GC before operation: completed in 61.765 ms, heap usage 491.144 MB -> 119.073 MB. [2025-05-07T22:54:21.580Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1268.108 ms) ====== [2025-05-07T22:54:21.580Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-07T22:54:21.580Z] GC before operation: completed in 49.788 ms, heap usage 268.823 MB -> 118.678 MB. [2025-05-07T22:54:22.999Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1348.083 ms) ====== [2025-05-07T22:54:22.999Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-07T22:54:22.999Z] GC before operation: completed in 54.632 ms, heap usage 468.331 MB -> 119.350 MB. [2025-05-07T22:54:24.421Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1181.987 ms) ====== [2025-05-07T22:54:24.421Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-07T22:54:24.421Z] GC before operation: completed in 52.365 ms, heap usage 396.687 MB -> 119.451 MB. [2025-05-07T22:54:25.279Z] ====== log-regression (apache-spark) [default], iteration 9 completed (959.047 ms) ====== [2025-05-07T22:54:25.279Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-07T22:54:25.279Z] GC before operation: completed in 51.892 ms, heap usage 353.431 MB -> 119.433 MB. [2025-05-07T22:54:26.640Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1216.972 ms) ====== [2025-05-07T22:54:26.640Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-07T22:54:26.640Z] GC before operation: completed in 53.470 ms, heap usage 464.139 MB -> 120.029 MB. [2025-05-07T22:54:28.020Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1224.712 ms) ====== [2025-05-07T22:54:28.020Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-07T22:54:28.020Z] GC before operation: completed in 55.415 ms, heap usage 474.215 MB -> 120.248 MB. [2025-05-07T22:54:28.892Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1207.079 ms) ====== [2025-05-07T22:54:28.892Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-07T22:54:28.892Z] GC before operation: completed in 52.919 ms, heap usage 225.812 MB -> 119.768 MB. [2025-05-07T22:54:30.350Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1013.811 ms) ====== [2025-05-07T22:54:30.350Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-07T22:54:30.350Z] GC before operation: completed in 52.931 ms, heap usage 517.933 MB -> 120.721 MB. [2025-05-07T22:54:31.222Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1078.886 ms) ====== [2025-05-07T22:54:31.222Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-07T22:54:31.222Z] GC before operation: completed in 53.457 ms, heap usage 356.361 MB -> 120.346 MB. [2025-05-07T22:54:32.603Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1094.716 ms) ====== [2025-05-07T22:54:32.603Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-07T22:54:32.603Z] GC before operation: completed in 53.651 ms, heap usage 448.003 MB -> 120.939 MB. [2025-05-07T22:54:33.474Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1156.160 ms) ====== [2025-05-07T22:54:33.474Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-07T22:54:33.474Z] GC before operation: completed in 55.208 ms, heap usage 451.940 MB -> 121.169 MB. [2025-05-07T22:54:34.856Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1099.926 ms) ====== [2025-05-07T22:54:34.856Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-07T22:54:34.856Z] GC before operation: completed in 68.377 ms, heap usage 476.828 MB -> 121.456 MB. [2025-05-07T22:54:36.227Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1123.758 ms) ====== [2025-05-07T22:54:36.227Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-07T22:54:36.227Z] GC before operation: completed in 60.027 ms, heap usage 486.537 MB -> 121.611 MB. [2025-05-07T22:54:37.086Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1142.061 ms) ====== [2025-05-07T22:54:37.489Z] ----------------------------------- [2025-05-07T22:54:37.489Z] renaissance-log-regression_0_PASSED [2025-05-07T22:54:37.489Z] ----------------------------------- [2025-05-07T22:54:37.489Z] [2025-05-07T22:54:37.489Z] TEST TEARDOWN: [2025-05-07T22:54:37.489Z] Nothing to be done for teardown. [2025-05-07T22:54:37.489Z] renaissance-log-regression_0 Finish Time: Wed May 7 15:54:36 2025 Epoch Time (ms): 1746658476923