[2025-06-30T21:22:05.589Z] Running test renaissance-log-regression_0 ... [2025-06-30T21:22:05.589Z] =============================================== [2025-06-30T21:22:05.589Z] renaissance-log-regression_0 Start Time: Mon Jun 30 22:22:05 2025 Epoch Time (ms): 1751318525443 [2025-06-30T21:22:05.589Z] variation: NoOptions [2025-06-30T21:22:05.589Z] JVM_OPTIONS: [2025-06-30T21:22:05.589Z] { \ [2025-06-30T21:22:05.589Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T21:22:05.589Z] echo "Nothing to be done for setup."; \ [2025-06-30T21:22:05.589Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513124431679/renaissance-log-regression_0"; \ [2025-06-30T21:22:05.589Z] cd "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513124431679/renaissance-log-regression_0"; \ [2025-06-30T21:22:05.589Z] echo ""; echo "TESTING:"; \ [2025-06-30T21:22:05.589Z] "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/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/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513124431679/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-30T21:22:05.589Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513124431679/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T21:22:05.589Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T21:22:05.589Z] echo "Nothing to be done for teardown."; \ [2025-06-30T21:22:05.589Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513124431679/TestTargetResult"; [2025-06-30T21:22:05.589Z] [2025-06-30T21:22:05.589Z] TEST SETUP: [2025-06-30T21:22:05.589Z] Nothing to be done for setup. [2025-06-30T21:22:05.589Z] [2025-06-30T21:22:05.589Z] TESTING: [2025-06-30T21:22:11.742Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-30T21:22:14.842Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-30T21:22:14.842Z] GC before operation: completed in 39.203 ms, heap usage 79.880 MB -> 35.729 MB. [2025-06-30T21:22:28.378Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12701.356 ms) ====== [2025-06-30T21:22:28.378Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-30T21:22:28.378Z] GC before operation: completed in 83.784 ms, heap usage 500.259 MB -> 126.537 MB. [2025-06-30T21:22:30.763Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3255.607 ms) ====== [2025-06-30T21:22:30.763Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-30T21:22:30.763Z] GC before operation: completed in 79.350 ms, heap usage 587.584 MB -> 127.194 MB. [2025-06-30T21:22:33.868Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2827.339 ms) ====== [2025-06-30T21:22:33.868Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-30T21:22:33.868Z] GC before operation: completed in 79.107 ms, heap usage 747.223 MB -> 127.747 MB. [2025-06-30T21:22:36.258Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2609.663 ms) ====== [2025-06-30T21:22:36.258Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-30T21:22:36.258Z] GC before operation: completed in 87.853 ms, heap usage 814.328 MB -> 128.325 MB. [2025-06-30T21:22:38.659Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2439.248 ms) ====== [2025-06-30T21:22:38.659Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-30T21:22:38.659Z] GC before operation: completed in 79.842 ms, heap usage 630.647 MB -> 128.507 MB. [2025-06-30T21:22:41.035Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2360.602 ms) ====== [2025-06-30T21:22:41.035Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-30T21:22:41.035Z] GC before operation: completed in 85.359 ms, heap usage 436.156 MB -> 128.719 MB. [2025-06-30T21:22:43.435Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2455.692 ms) ====== [2025-06-30T21:22:43.435Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-30T21:22:43.800Z] GC before operation: completed in 91.519 ms, heap usage 517.743 MB -> 129.100 MB. [2025-06-30T21:22:46.188Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2367.497 ms) ====== [2025-06-30T21:22:46.188Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-30T21:22:46.188Z] GC before operation: completed in 80.686 ms, heap usage 457.422 MB -> 129.464 MB. [2025-06-30T21:22:48.567Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2307.385 ms) ====== [2025-06-30T21:22:48.567Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-30T21:22:48.567Z] GC before operation: completed in 89.876 ms, heap usage 391.050 MB -> 129.616 MB. [2025-06-30T21:22:50.968Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2252.449 ms) ====== [2025-06-30T21:22:50.968Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-30T21:22:50.968Z] GC before operation: completed in 82.874 ms, heap usage 603.502 MB -> 130.151 MB. [2025-06-30T21:22:53.355Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2351.230 ms) ====== [2025-06-30T21:22:53.355Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-30T21:22:53.355Z] GC before operation: completed in 89.683 ms, heap usage 435.466 MB -> 130.514 MB. [2025-06-30T21:22:55.741Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2273.173 ms) ====== [2025-06-30T21:22:55.741Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-30T21:22:55.741Z] GC before operation: completed in 85.136 ms, heap usage 431.500 MB -> 130.882 MB. [2025-06-30T21:22:58.142Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2205.024 ms) ====== [2025-06-30T21:22:58.142Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-30T21:22:58.142Z] GC before operation: completed in 89.821 ms, heap usage 318.459 MB -> 131.011 MB. [2025-06-30T21:23:00.522Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2272.883 ms) ====== [2025-06-30T21:23:00.522Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-30T21:23:00.522Z] GC before operation: completed in 92.395 ms, heap usage 396.227 MB -> 131.397 MB. [2025-06-30T21:23:02.917Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2263.044 ms) ====== [2025-06-30T21:23:02.917Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-30T21:23:02.917Z] GC before operation: completed in 88.118 ms, heap usage 585.777 MB -> 132.032 MB. [2025-06-30T21:23:04.672Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2165.857 ms) ====== [2025-06-30T21:23:04.672Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-30T21:23:05.028Z] GC before operation: completed in 90.635 ms, heap usage 330.474 MB -> 132.119 MB. [2025-06-30T21:23:07.409Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2095.124 ms) ====== [2025-06-30T21:23:07.409Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-30T21:23:07.409Z] GC before operation: completed in 92.684 ms, heap usage 549.633 MB -> 132.705 MB. [2025-06-30T21:23:09.785Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2277.433 ms) ====== [2025-06-30T21:23:09.785Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-30T21:23:09.785Z] GC before operation: completed in 90.627 ms, heap usage 676.154 MB -> 133.283 MB. [2025-06-30T21:23:11.543Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2086.524 ms) ====== [2025-06-30T21:23:11.543Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-30T21:23:11.543Z] GC before operation: completed in 94.385 ms, heap usage 779.602 MB -> 133.744 MB. [2025-06-30T21:23:13.930Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2234.333 ms) ====== [2025-06-30T21:23:14.283Z] ----------------------------------- [2025-06-30T21:23:14.283Z] renaissance-log-regression_0_PASSED [2025-06-30T21:23:14.283Z] ----------------------------------- [2025-06-30T21:23:14.283Z] [2025-06-30T21:23:14.283Z] TEST TEARDOWN: [2025-06-30T21:23:14.283Z] Nothing to be done for teardown. [2025-06-30T21:23:14.283Z] renaissance-log-regression_0 Finish Time: Mon Jun 30 22:23:14 2025 Epoch Time (ms): 1751318594072