[2025-06-11T20:40:48.021Z] Running test renaissance-log-regression_0 ... [2025-06-11T20:40:48.021Z] =============================================== [2025-06-11T20:40:48.021Z] renaissance-log-regression_0 Start Time: Wed Jun 11 13:40:47 2025 Epoch Time (ms): 1749674447803 [2025-06-11T20:40:48.021Z] variation: NoOptions [2025-06-11T20:40:48.021Z] JVM_OPTIONS: [2025-06-11T20:40:48.021Z] { \ [2025-06-11T20:40:48.021Z] echo ""; echo "TEST SETUP:"; \ [2025-06-11T20:40:48.021Z] echo "Nothing to be done for setup."; \ [2025-06-11T20:40:48.021Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17496727588273/renaissance-log-regression_0"; \ [2025-06-11T20:40:48.021Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17496727588273/renaissance-log-regression_0"; \ [2025-06-11T20:40:48.021Z] echo ""; echo "TESTING:"; \ [2025-06-11T20:40:48.021Z] "/Users/admin/workspace/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/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17496727588273/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-11T20:40:48.021Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17496727588273/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-11T20:40:48.021Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-11T20:40:48.021Z] echo "Nothing to be done for teardown."; \ [2025-06-11T20:40:48.021Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17496727588273/TestTargetResult"; [2025-06-11T20:40:48.021Z] [2025-06-11T20:40:48.021Z] TEST SETUP: [2025-06-11T20:40:48.021Z] Nothing to be done for setup. [2025-06-11T20:40:48.021Z] [2025-06-11T20:40:48.021Z] TESTING: [2025-06-11T20:41:02.276Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-11T20:41:12.025Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-11T20:41:12.025Z] GC before operation: completed in 55.684 ms, heap usage 80.576 MB -> 35.230 MB. [2025-06-11T20:41:37.455Z] ====== log-regression (apache-spark) [default], iteration 0 completed (22585.271 ms) ====== [2025-06-11T20:41:37.455Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-11T20:41:37.455Z] GC before operation: completed in 135.144 ms, heap usage 364.536 MB -> 117.395 MB. [2025-06-11T20:41:38.920Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4719.209 ms) ====== [2025-06-11T20:41:38.920Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-11T20:41:38.920Z] GC before operation: completed in 143.258 ms, heap usage 263.940 MB -> 117.733 MB. [2025-06-11T20:41:43.551Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4607.072 ms) ====== [2025-06-11T20:41:43.551Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-11T20:41:43.551Z] GC before operation: completed in 131.179 ms, heap usage 451.342 MB -> 118.740 MB. [2025-06-11T20:41:48.390Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4296.058 ms) ====== [2025-06-11T20:41:48.390Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-11T20:41:48.390Z] GC before operation: completed in 131.922 ms, heap usage 445.727 MB -> 118.912 MB. [2025-06-11T20:41:52.018Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3487.125 ms) ====== [2025-06-11T20:41:52.018Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-11T20:41:52.018Z] GC before operation: completed in 176.372 ms, heap usage 450.419 MB -> 119.175 MB. [2025-06-11T20:41:55.980Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3853.336 ms) ====== [2025-06-11T20:41:55.980Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-11T20:41:55.980Z] GC before operation: completed in 234.691 ms, heap usage 444.690 MB -> 119.401 MB. [2025-06-11T20:42:00.708Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4750.642 ms) ====== [2025-06-11T20:42:00.708Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-11T20:42:00.708Z] GC before operation: completed in 193.279 ms, heap usage 985.869 MB -> 120.653 MB. [2025-06-11T20:42:04.563Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3191.747 ms) ====== [2025-06-11T20:42:04.563Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-11T20:42:04.563Z] GC before operation: completed in 197.150 ms, heap usage 536.977 MB -> 120.216 MB. [2025-06-11T20:42:06.648Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2549.465 ms) ====== [2025-06-11T20:42:06.648Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-11T20:42:07.089Z] GC before operation: completed in 182.460 ms, heap usage 321.877 MB -> 119.755 MB. [2025-06-11T20:42:10.751Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3246.067 ms) ====== [2025-06-11T20:42:10.751Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-11T20:42:10.751Z] GC before operation: completed in 193.862 ms, heap usage 393.661 MB -> 120.100 MB. [2025-06-11T20:42:13.678Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3296.337 ms) ====== [2025-06-11T20:42:13.678Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-11T20:42:14.151Z] GC before operation: completed in 259.428 ms, heap usage 326.471 MB -> 120.056 MB. [2025-06-11T20:42:17.797Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3610.085 ms) ====== [2025-06-11T20:42:17.797Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-11T20:42:17.797Z] GC before operation: completed in 150.468 ms, heap usage 340.171 MB -> 120.318 MB. [2025-06-11T20:42:20.724Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2988.478 ms) ====== [2025-06-11T20:42:20.724Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-11T20:42:20.724Z] GC before operation: completed in 170.934 ms, heap usage 387.533 MB -> 120.599 MB. [2025-06-11T20:42:23.596Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2611.821 ms) ====== [2025-06-11T20:42:23.596Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-11T20:42:23.596Z] GC before operation: completed in 138.659 ms, heap usage 511.918 MB -> 121.201 MB. [2025-06-11T20:42:26.378Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2658.469 ms) ====== [2025-06-11T20:42:26.378Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-11T20:42:26.378Z] GC before operation: completed in 146.282 ms, heap usage 529.968 MB -> 121.449 MB. [2025-06-11T20:42:29.148Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2899.676 ms) ====== [2025-06-11T20:42:29.148Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-11T20:42:29.552Z] GC before operation: completed in 168.627 ms, heap usage 964.020 MB -> 122.293 MB. [2025-06-11T20:42:32.435Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2864.870 ms) ====== [2025-06-11T20:42:32.435Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-11T20:42:32.435Z] GC before operation: completed in 208.779 ms, heap usage 341.408 MB -> 121.263 MB. [2025-06-11T20:42:35.241Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2168.349 ms) ====== [2025-06-11T20:42:35.241Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-11T20:42:35.241Z] GC before operation: completed in 170.332 ms, heap usage 353.937 MB -> 121.504 MB. [2025-06-11T20:42:37.445Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2720.689 ms) ====== [2025-06-11T20:42:37.445Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-11T20:42:37.896Z] GC before operation: completed in 191.810 ms, heap usage 316.019 MB -> 121.612 MB. [2025-06-11T20:42:41.614Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3783.545 ms) ====== [2025-06-11T20:42:42.517Z] ----------------------------------- [2025-06-11T20:42:42.517Z] renaissance-log-regression_0_PASSED [2025-06-11T20:42:42.517Z] ----------------------------------- [2025-06-11T20:42:42.517Z] [2025-06-11T20:42:42.517Z] TEST TEARDOWN: [2025-06-11T20:42:42.517Z] Nothing to be done for teardown. [2025-06-11T20:42:42.517Z] renaissance-log-regression_0 Finish Time: Wed Jun 11 13:42:42 2025 Epoch Time (ms): 1749674562001