[2025-06-19T03:55:26.841Z] Running test renaissance-log-regression_0 ... [2025-06-19T03:55:26.841Z] =============================================== [2025-06-19T03:55:26.841Z] renaissance-log-regression_0 Start Time: Thu Jun 19 04:55:26 2025 Epoch Time (ms): 1750305326516 [2025-06-19T03:55:26.841Z] variation: NoOptions [2025-06-19T03:55:26.841Z] JVM_OPTIONS: [2025-06-19T03:55:26.841Z] { \ [2025-06-19T03:55:26.841Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T03:55:26.841Z] echo "Nothing to be done for setup."; \ [2025-06-19T03:55:26.841Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502945042457/renaissance-log-regression_0"; \ [2025-06-19T03:55:26.841Z] cd "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502945042457/renaissance-log-regression_0"; \ [2025-06-19T03:55:26.841Z] echo ""; echo "TESTING:"; \ [2025-06-19T03:55:26.841Z] "/Users/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502945042457/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-19T03:55:26.841Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502945042457/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T03:55:26.841Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T03:55:26.841Z] echo "Nothing to be done for teardown."; \ [2025-06-19T03:55:26.841Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502945042457/TestTargetResult"; [2025-06-19T03:55:26.841Z] [2025-06-19T03:55:26.841Z] TEST SETUP: [2025-06-19T03:55:26.841Z] Nothing to be done for setup. [2025-06-19T03:55:26.841Z] [2025-06-19T03:55:26.841Z] TESTING: [2025-06-19T03:55:32.954Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-19T03:55:36.049Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-19T03:55:36.049Z] GC before operation: completed in 39.973 ms, heap usage 42.987 MB -> 35.335 MB. [2025-06-19T03:55:52.388Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14588.432 ms) ====== [2025-06-19T03:55:52.388Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-19T03:55:52.388Z] GC before operation: completed in 85.808 ms, heap usage 506.780 MB -> 126.271 MB. [2025-06-19T03:55:54.762Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4393.250 ms) ====== [2025-06-19T03:55:54.762Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-19T03:55:54.762Z] GC before operation: completed in 76.531 ms, heap usage 628.079 MB -> 127.204 MB. [2025-06-19T03:55:58.732Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3742.830 ms) ====== [2025-06-19T03:55:58.732Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-19T03:55:58.732Z] GC before operation: completed in 79.859 ms, heap usage 425.957 MB -> 127.322 MB. [2025-06-19T03:56:02.694Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3575.524 ms) ====== [2025-06-19T03:56:02.694Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-19T03:56:02.694Z] GC before operation: completed in 88.946 ms, heap usage 702.921 MB -> 127.897 MB. [2025-06-19T03:56:05.805Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3508.797 ms) ====== [2025-06-19T03:56:05.805Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-19T03:56:05.805Z] GC before operation: completed in 82.580 ms, heap usage 320.507 MB -> 128.161 MB. [2025-06-19T03:56:09.850Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3411.279 ms) ====== [2025-06-19T03:56:09.850Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-19T03:56:09.850Z] GC before operation: completed in 88.047 ms, heap usage 622.892 MB -> 128.734 MB. [2025-06-19T03:56:12.953Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3403.534 ms) ====== [2025-06-19T03:56:12.953Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-19T03:56:12.953Z] GC before operation: completed in 85.960 ms, heap usage 763.274 MB -> 129.085 MB. [2025-06-19T03:56:16.897Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3364.074 ms) ====== [2025-06-19T03:56:16.897Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-19T03:56:16.897Z] GC before operation: completed in 94.395 ms, heap usage 769.282 MB -> 129.431 MB. [2025-06-19T03:56:20.080Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3448.211 ms) ====== [2025-06-19T03:56:20.080Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-19T03:56:20.080Z] GC before operation: completed in 91.363 ms, heap usage 318.552 MB -> 129.644 MB. [2025-06-19T03:56:23.184Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3472.938 ms) ====== [2025-06-19T03:56:23.184Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-19T03:56:23.544Z] GC before operation: completed in 93.939 ms, heap usage 656.315 MB -> 130.153 MB. [2025-06-19T03:56:26.652Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3303.082 ms) ====== [2025-06-19T03:56:26.652Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-19T03:56:27.006Z] GC before operation: completed in 93.673 ms, heap usage 301.475 MB -> 130.350 MB. [2025-06-19T03:56:30.113Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3378.194 ms) ====== [2025-06-19T03:56:30.113Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-19T03:56:30.468Z] GC before operation: completed in 98.741 ms, heap usage 661.935 MB -> 130.785 MB. [2025-06-19T03:56:33.565Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3348.648 ms) ====== [2025-06-19T03:56:33.565Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-19T03:56:33.919Z] GC before operation: completed in 95.433 ms, heap usage 715.793 MB -> 131.168 MB. [2025-06-19T03:56:37.032Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3373.485 ms) ====== [2025-06-19T03:56:37.032Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-19T03:56:37.387Z] GC before operation: completed in 96.305 ms, heap usage 667.423 MB -> 131.549 MB. [2025-06-19T03:56:40.491Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3389.223 ms) ====== [2025-06-19T03:56:40.491Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-19T03:56:40.845Z] GC before operation: completed in 87.509 ms, heap usage 779.230 MB -> 131.926 MB. [2025-06-19T03:56:43.982Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3315.443 ms) ====== [2025-06-19T03:56:43.982Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-19T03:56:43.982Z] GC before operation: completed in 91.450 ms, heap usage 486.220 MB -> 132.181 MB. [2025-06-19T03:56:47.952Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3247.834 ms) ====== [2025-06-19T03:56:47.952Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-19T03:56:47.952Z] GC before operation: completed in 94.854 ms, heap usage 288.707 MB -> 132.580 MB. [2025-06-19T03:56:51.057Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3324.069 ms) ====== [2025-06-19T03:56:51.057Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-19T03:56:51.057Z] GC before operation: completed in 96.296 ms, heap usage 462.236 MB -> 132.920 MB. [2025-06-19T03:56:54.210Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3131.957 ms) ====== [2025-06-19T03:56:54.210Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-19T03:56:54.210Z] GC before operation: completed in 88.750 ms, heap usage 376.536 MB -> 133.222 MB. [2025-06-19T03:56:57.403Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3357.728 ms) ====== [2025-06-19T03:56:57.755Z] ----------------------------------- [2025-06-19T03:56:57.755Z] renaissance-log-regression_0_PASSED [2025-06-19T03:56:57.755Z] ----------------------------------- [2025-06-19T03:56:57.755Z] [2025-06-19T03:56:57.755Z] TEST TEARDOWN: [2025-06-19T03:56:57.755Z] Nothing to be done for teardown. [2025-06-19T03:56:57.755Z] renaissance-log-regression_0 Finish Time: Thu Jun 19 04:56:57 2025 Epoch Time (ms): 1750305417660