[2025-07-16T06:48:34.223Z] Running test renaissance-log-regression_0 ... [2025-07-16T06:48:34.223Z] =============================================== [2025-07-16T06:48:34.223Z] renaissance-log-regression_0 Start Time: Wed Jul 16 06:48:33 2025 Epoch Time (ms): 1752648513375 [2025-07-16T06:48:34.223Z] variation: NoOptions [2025-07-16T06:48:34.223Z] JVM_OPTIONS: [2025-07-16T06:48:34.223Z] { \ [2025-07-16T06:48:34.223Z] echo ""; echo "TEST SETUP:"; \ [2025-07-16T06:48:34.223Z] echo "Nothing to be done for setup."; \ [2025-07-16T06:48:34.223Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-log-regression_0"; \ [2025-07-16T06:48:34.223Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-log-regression_0"; \ [2025-07-16T06:48:34.223Z] echo ""; echo "TESTING:"; \ [2025-07-16T06:48:34.223Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-16T06:48:34.223Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-16T06:48:34.223Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-16T06:48:34.223Z] echo "Nothing to be done for teardown."; \ [2025-07-16T06:48:34.223Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/TestTargetResult"; [2025-07-16T06:48:34.223Z] [2025-07-16T06:48:34.223Z] TEST SETUP: [2025-07-16T06:48:34.223Z] Nothing to be done for setup. [2025-07-16T06:48:34.223Z] [2025-07-16T06:48:34.223Z] TESTING: [2025-07-16T06:49:05.414Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-16T06:49:22.254Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-16T06:49:22.254Z] GC before operation: completed in 285.559 ms, heap usage 66.783 MB -> 35.062 MB. [2025-07-16T06:50:31.390Z] ====== log-regression (apache-spark) [default], iteration 0 completed (65217.509 ms) ====== [2025-07-16T06:50:31.390Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-16T06:50:31.390Z] GC before operation: completed in 372.781 ms, heap usage 298.764 MB -> 120.538 MB. [2025-07-16T06:50:42.023Z] ====== log-regression (apache-spark) [default], iteration 1 completed (15515.733 ms) ====== [2025-07-16T06:50:42.023Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-16T06:50:42.023Z] GC before operation: completed in 530.734 ms, heap usage 289.924 MB -> 120.038 MB. [2025-07-16T06:50:56.792Z] ====== log-regression (apache-spark) [default], iteration 2 completed (13801.303 ms) ====== [2025-07-16T06:50:56.792Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-16T06:50:56.792Z] GC before operation: completed in 495.431 ms, heap usage 440.663 MB -> 120.840 MB. [2025-07-16T06:51:11.427Z] ====== log-regression (apache-spark) [default], iteration 3 completed (13377.481 ms) ====== [2025-07-16T06:51:11.427Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-16T06:51:11.427Z] GC before operation: completed in 656.025 ms, heap usage 269.165 MB -> 120.598 MB. [2025-07-16T06:51:22.213Z] ====== log-regression (apache-spark) [default], iteration 4 completed (10594.921 ms) ====== [2025-07-16T06:51:22.213Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-16T06:51:22.213Z] GC before operation: completed in 485.663 ms, heap usage 332.599 MB -> 121.001 MB. [2025-07-16T06:51:32.849Z] ====== log-regression (apache-spark) [default], iteration 5 completed (10332.948 ms) ====== [2025-07-16T06:51:32.849Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-16T06:51:32.849Z] GC before operation: completed in 453.877 ms, heap usage 334.011 MB -> 121.232 MB. [2025-07-16T06:51:41.711Z] ====== log-regression (apache-spark) [default], iteration 6 completed (9067.852 ms) ====== [2025-07-16T06:51:41.711Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-16T06:51:41.711Z] GC before operation: completed in 413.038 ms, heap usage 368.894 MB -> 121.535 MB. [2025-07-16T06:51:50.563Z] ====== log-regression (apache-spark) [default], iteration 7 completed (8712.689 ms) ====== [2025-07-16T06:51:50.563Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-16T06:51:51.421Z] GC before operation: completed in 471.133 ms, heap usage 287.607 MB -> 121.674 MB. [2025-07-16T06:52:00.307Z] ====== log-regression (apache-spark) [default], iteration 8 completed (9368.288 ms) ====== [2025-07-16T06:52:00.307Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-16T06:52:01.132Z] GC before operation: completed in 537.582 ms, heap usage 258.408 MB -> 121.590 MB. [2025-07-16T06:52:10.404Z] ====== log-regression (apache-spark) [default], iteration 9 completed (8989.637 ms) ====== [2025-07-16T06:52:10.404Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-16T06:52:10.404Z] GC before operation: completed in 523.446 ms, heap usage 330.790 MB -> 122.082 MB. [2025-07-16T06:52:19.615Z] ====== log-regression (apache-spark) [default], iteration 10 completed (8626.859 ms) ====== [2025-07-16T06:52:19.615Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-16T06:52:19.615Z] GC before operation: completed in 488.818 ms, heap usage 303.783 MB -> 122.228 MB. [2025-07-16T06:52:30.250Z] ====== log-regression (apache-spark) [default], iteration 11 completed (9577.952 ms) ====== [2025-07-16T06:52:30.250Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-16T06:52:30.250Z] GC before operation: completed in 455.586 ms, heap usage 231.635 MB -> 122.871 MB. [2025-07-16T06:52:39.159Z] ====== log-regression (apache-spark) [default], iteration 12 completed (8899.850 ms) ====== [2025-07-16T06:52:39.159Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-16T06:52:39.159Z] GC before operation: completed in 430.510 ms, heap usage 248.017 MB -> 122.611 MB. [2025-07-16T06:52:48.058Z] ====== log-regression (apache-spark) [default], iteration 13 completed (9165.751 ms) ====== [2025-07-16T06:52:48.058Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-16T06:52:48.875Z] GC before operation: completed in 456.165 ms, heap usage 318.785 MB -> 122.883 MB. [2025-07-16T06:52:56.303Z] ====== log-regression (apache-spark) [default], iteration 14 completed (7449.653 ms) ====== [2025-07-16T06:52:56.303Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-16T06:52:56.303Z] GC before operation: completed in 420.826 ms, heap usage 294.144 MB -> 123.075 MB. [2025-07-16T06:53:05.279Z] ====== log-regression (apache-spark) [default], iteration 15 completed (8367.271 ms) ====== [2025-07-16T06:53:05.279Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-16T06:53:05.279Z] GC before operation: completed in 483.174 ms, heap usage 294.289 MB -> 123.384 MB. [2025-07-16T06:53:14.160Z] ====== log-regression (apache-spark) [default], iteration 16 completed (7259.444 ms) ====== [2025-07-16T06:53:14.160Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-16T06:53:14.160Z] GC before operation: completed in 358.037 ms, heap usage 371.459 MB -> 123.681 MB. [2025-07-16T06:53:23.400Z] ====== log-regression (apache-spark) [default], iteration 17 completed (8067.661 ms) ====== [2025-07-16T06:53:23.400Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-16T06:53:23.400Z] GC before operation: completed in 397.609 ms, heap usage 303.188 MB -> 123.592 MB. [2025-07-16T06:53:30.708Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6413.132 ms) ====== [2025-07-16T06:53:30.708Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-16T06:53:30.708Z] GC before operation: completed in 273.959 ms, heap usage 376.333 MB -> 124.113 MB. [2025-07-16T06:53:33.414Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5278.791 ms) ====== [2025-07-16T06:53:35.128Z] ----------------------------------- [2025-07-16T06:53:35.128Z] renaissance-log-regression_0_PASSED [2025-07-16T06:53:35.128Z] ----------------------------------- [2025-07-16T06:53:35.128Z] [2025-07-16T06:53:35.128Z] TEST TEARDOWN: [2025-07-16T06:53:35.128Z] Nothing to be done for teardown. [2025-07-16T06:53:35.128Z] renaissance-log-regression_0 Finish Time: Wed Jul 16 06:53:34 2025 Epoch Time (ms): 1752648814897