[2025-06-18T22:33:15.836Z] Running test renaissance-log-regression_0 ... [2025-06-18T22:33:15.836Z] =============================================== [2025-06-18T22:33:15.836Z] renaissance-log-regression_0 Start Time: Wed Jun 18 22:33:15 2025 Epoch Time (ms): 1750285995249 [2025-06-18T22:33:15.836Z] variation: NoOptions [2025-06-18T22:33:15.836Z] JVM_OPTIONS: [2025-06-18T22:33:15.836Z] { \ [2025-06-18T22:33:15.836Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T22:33:15.836Z] echo "Nothing to be done for setup."; \ [2025-06-18T22:33:15.836Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-log-regression_0"; \ [2025-06-18T22:33:15.836Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-log-regression_0"; \ [2025-06-18T22:33:15.836Z] echo ""; echo "TESTING:"; \ [2025-06-18T22:33:15.836Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-18T22:33:15.836Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T22:33:15.836Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T22:33:15.836Z] echo "Nothing to be done for teardown."; \ [2025-06-18T22:33:15.836Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/TestTargetResult"; [2025-06-18T22:33:15.836Z] [2025-06-18T22:33:15.836Z] TEST SETUP: [2025-06-18T22:33:15.836Z] Nothing to be done for setup. [2025-06-18T22:33:15.836Z] [2025-06-18T22:33:15.836Z] TESTING: [2025-06-18T22:34:04.758Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-18T22:34:21.928Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-18T22:34:21.928Z] GC before operation: completed in 422.875 ms, heap usage 75.327 MB -> 35.194 MB. [2025-06-18T22:36:27.120Z] ====== log-regression (apache-spark) [default], iteration 0 completed (110389.771 ms) ====== [2025-06-18T22:36:27.120Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-18T22:36:27.120Z] GC before operation: completed in 780.526 ms, heap usage 342.407 MB -> 119.718 MB. [2025-06-18T22:36:41.441Z] ====== log-regression (apache-spark) [default], iteration 1 completed (27398.063 ms) ====== [2025-06-18T22:36:41.441Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-18T22:36:41.441Z] GC before operation: completed in 814.268 ms, heap usage 512.730 MB -> 120.695 MB. [2025-06-18T22:37:04.590Z] ====== log-regression (apache-spark) [default], iteration 2 completed (21193.898 ms) ====== [2025-06-18T22:37:04.590Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-18T22:37:04.590Z] GC before operation: completed in 619.241 ms, heap usage 295.769 MB -> 120.586 MB. [2025-06-18T22:37:20.599Z] ====== log-regression (apache-spark) [default], iteration 3 completed (17621.464 ms) ====== [2025-06-18T22:37:20.599Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-18T22:37:21.408Z] GC before operation: completed in 615.951 ms, heap usage 567.559 MB -> 121.658 MB. [2025-06-18T22:37:35.586Z] ====== log-regression (apache-spark) [default], iteration 4 completed (14048.319 ms) ====== [2025-06-18T22:37:35.586Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-18T22:37:35.586Z] GC before operation: completed in 722.798 ms, heap usage 757.056 MB -> 122.516 MB. [2025-06-18T22:37:52.114Z] ====== log-regression (apache-spark) [default], iteration 5 completed (13897.520 ms) ====== [2025-06-18T22:37:52.114Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-18T22:37:52.114Z] GC before operation: completed in 562.165 ms, heap usage 550.254 MB -> 122.125 MB. [2025-06-18T22:38:04.307Z] ====== log-regression (apache-spark) [default], iteration 6 completed (14171.881 ms) ====== [2025-06-18T22:38:04.307Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-18T22:38:05.115Z] GC before operation: completed in 605.819 ms, heap usage 528.138 MB -> 122.273 MB. [2025-06-18T22:38:19.405Z] ====== log-regression (apache-spark) [default], iteration 7 completed (12759.285 ms) ====== [2025-06-18T22:38:19.405Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-18T22:38:19.405Z] GC before operation: completed in 779.693 ms, heap usage 537.025 MB -> 122.617 MB. [2025-06-18T22:38:33.843Z] ====== log-regression (apache-spark) [default], iteration 8 completed (13492.681 ms) ====== [2025-06-18T22:38:33.843Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-18T22:38:33.843Z] GC before operation: completed in 677.910 ms, heap usage 249.388 MB -> 121.891 MB. [2025-06-18T22:38:46.154Z] ====== log-regression (apache-spark) [default], iteration 9 completed (12941.452 ms) ====== [2025-06-18T22:38:46.154Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-18T22:38:46.154Z] GC before operation: completed in 729.912 ms, heap usage 305.556 MB -> 122.293 MB. [2025-06-18T22:39:00.404Z] ====== log-regression (apache-spark) [default], iteration 10 completed (12372.488 ms) ====== [2025-06-18T22:39:00.404Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-18T22:39:00.404Z] GC before operation: completed in 662.830 ms, heap usage 343.393 MB -> 122.620 MB. [2025-06-18T22:39:13.134Z] ====== log-regression (apache-spark) [default], iteration 11 completed (12677.652 ms) ====== [2025-06-18T22:39:13.134Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-18T22:39:13.134Z] GC before operation: completed in 999.581 ms, heap usage 696.340 MB -> 123.899 MB. [2025-06-18T22:39:25.489Z] ====== log-regression (apache-spark) [default], iteration 12 completed (11940.670 ms) ====== [2025-06-18T22:39:25.489Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-18T22:39:26.297Z] GC before operation: completed in 772.142 ms, heap usage 402.278 MB -> 123.133 MB. [2025-06-18T22:39:38.475Z] ====== log-regression (apache-spark) [default], iteration 13 completed (11874.997 ms) ====== [2025-06-18T22:39:38.475Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-18T22:39:38.475Z] GC before operation: completed in 737.361 ms, heap usage 276.464 MB -> 122.938 MB. [2025-06-18T22:39:50.747Z] ====== log-regression (apache-spark) [default], iteration 14 completed (10947.815 ms) ====== [2025-06-18T22:39:50.747Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-18T22:39:50.747Z] GC before operation: completed in 640.088 ms, heap usage 616.098 MB -> 124.349 MB. [2025-06-18T22:40:00.966Z] ====== log-regression (apache-spark) [default], iteration 15 completed (10252.286 ms) ====== [2025-06-18T22:40:00.966Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-18T22:40:00.966Z] GC before operation: completed in 691.857 ms, heap usage 515.564 MB -> 124.142 MB. [2025-06-18T22:40:13.741Z] ====== log-regression (apache-spark) [default], iteration 16 completed (11192.018 ms) ====== [2025-06-18T22:40:13.741Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-18T22:40:13.741Z] GC before operation: completed in 673.504 ms, heap usage 356.907 MB -> 123.939 MB. [2025-06-18T22:40:24.023Z] ====== log-regression (apache-spark) [default], iteration 17 completed (10566.261 ms) ====== [2025-06-18T22:40:24.023Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-18T22:40:24.023Z] GC before operation: completed in 734.695 ms, heap usage 374.226 MB -> 124.155 MB. [2025-06-18T22:40:36.084Z] ====== log-regression (apache-spark) [default], iteration 18 completed (10527.533 ms) ====== [2025-06-18T22:40:36.084Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-18T22:40:36.084Z] GC before operation: completed in 847.736 ms, heap usage 841.594 MB -> 125.831 MB. [2025-06-18T22:40:48.189Z] ====== log-regression (apache-spark) [default], iteration 19 completed (11208.148 ms) ====== [2025-06-18T22:40:48.968Z] ----------------------------------- [2025-06-18T22:40:48.968Z] renaissance-log-regression_0_PASSED [2025-06-18T22:40:48.968Z] ----------------------------------- [2025-06-18T22:40:48.968Z] [2025-06-18T22:40:48.968Z] TEST TEARDOWN: [2025-06-18T22:40:48.968Z] Nothing to be done for teardown. [2025-06-18T22:40:48.968Z] renaissance-log-regression_0 Finish Time: Wed Jun 18 22:40:48 2025 Epoch Time (ms): 1750286448719