[2025-05-08T10:17:58.187Z] Running test renaissance-log-regression_0 ... [2025-05-08T10:17:58.187Z] =============================================== [2025-05-08T10:17:58.187Z] renaissance-log-regression_0 Start Time: Thu May 8 10:17:57 2025 Epoch Time (ms): 1746699477279 [2025-05-08T10:17:58.187Z] variation: NoOptions [2025-05-08T10:17:58.187Z] JVM_OPTIONS: [2025-05-08T10:17:58.187Z] { \ [2025-05-08T10:17:58.187Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T10:17:58.187Z] echo "Nothing to be done for setup."; \ [2025-05-08T10:17:58.187Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/renaissance-log-regression_0"; \ [2025-05-08T10:17:58.187Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/renaissance-log-regression_0"; \ [2025-05-08T10:17:58.187Z] echo ""; echo "TESTING:"; \ [2025-05-08T10:17:58.187Z] "/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_17466919033356/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-08T10:17:58.187Z] 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_17466919033356/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T10:17:58.187Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T10:17:58.187Z] echo "Nothing to be done for teardown."; \ [2025-05-08T10:17:58.187Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/TestTargetResult"; [2025-05-08T10:17:58.187Z] [2025-05-08T10:17:58.187Z] TEST SETUP: [2025-05-08T10:17:58.187Z] Nothing to be done for setup. [2025-05-08T10:17:58.187Z] [2025-05-08T10:17:58.187Z] TESTING: [2025-05-08T10:18:21.391Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-08T10:18:36.543Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-08T10:18:36.543Z] GC before operation: completed in 204.672 ms, heap usage 42.660 MB -> 35.067 MB. [2025-05-08T10:19:35.163Z] ====== log-regression (apache-spark) [default], iteration 0 completed (55206.818 ms) ====== [2025-05-08T10:19:35.163Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-08T10:19:35.163Z] GC before operation: completed in 319.573 ms, heap usage 372.797 MB -> 119.805 MB. [2025-05-08T10:19:44.137Z] ====== log-regression (apache-spark) [default], iteration 1 completed (12994.736 ms) ====== [2025-05-08T10:19:44.137Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-08T10:19:44.137Z] GC before operation: completed in 327.040 ms, heap usage 367.139 MB -> 120.233 MB. [2025-05-08T10:19:56.645Z] ====== log-regression (apache-spark) [default], iteration 2 completed (11882.704 ms) ====== [2025-05-08T10:19:56.645Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-08T10:19:56.645Z] GC before operation: completed in 430.533 ms, heap usage 402.501 MB -> 120.839 MB. [2025-05-08T10:20:07.315Z] ====== log-regression (apache-spark) [default], iteration 3 completed (9693.934 ms) ====== [2025-05-08T10:20:07.315Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-08T10:20:07.315Z] GC before operation: completed in 311.730 ms, heap usage 303.340 MB -> 120.803 MB. [2025-05-08T10:20:15.417Z] ====== log-regression (apache-spark) [default], iteration 4 completed (9181.953 ms) ====== [2025-05-08T10:20:15.417Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-08T10:20:16.275Z] GC before operation: completed in 343.571 ms, heap usage 263.822 MB -> 121.117 MB. [2025-05-08T10:20:23.765Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7547.754 ms) ====== [2025-05-08T10:20:23.765Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-08T10:20:23.765Z] GC before operation: completed in 451.678 ms, heap usage 360.795 MB -> 121.277 MB. [2025-05-08T10:20:31.239Z] ====== log-regression (apache-spark) [default], iteration 6 completed (7079.942 ms) ====== [2025-05-08T10:20:31.239Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-08T10:20:31.239Z] GC before operation: completed in 324.430 ms, heap usage 231.225 MB -> 121.251 MB. [2025-05-08T10:20:38.741Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6981.739 ms) ====== [2025-05-08T10:20:38.741Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-08T10:20:38.741Z] GC before operation: completed in 350.762 ms, heap usage 341.894 MB -> 121.689 MB. [2025-05-08T10:20:46.183Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7318.203 ms) ====== [2025-05-08T10:20:46.183Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-08T10:20:46.183Z] GC before operation: completed in 352.974 ms, heap usage 316.337 MB -> 121.834 MB. [2025-05-08T10:20:53.621Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6751.624 ms) ====== [2025-05-08T10:20:53.621Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-08T10:20:53.621Z] GC before operation: completed in 357.594 ms, heap usage 377.584 MB -> 122.648 MB. [2025-05-08T10:20:59.721Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6179.506 ms) ====== [2025-05-08T10:20:59.721Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-08T10:20:59.721Z] GC before operation: completed in 357.067 ms, heap usage 385.997 MB -> 122.616 MB. [2025-05-08T10:21:07.184Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6298.765 ms) ====== [2025-05-08T10:21:07.184Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-08T10:21:07.184Z] GC before operation: completed in 357.596 ms, heap usage 260.125 MB -> 122.371 MB. [2025-05-08T10:21:13.869Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6039.427 ms) ====== [2025-05-08T10:21:13.869Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-08T10:21:13.869Z] GC before operation: completed in 319.467 ms, heap usage 321.438 MB -> 122.644 MB. [2025-05-08T10:21:19.955Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6224.584 ms) ====== [2025-05-08T10:21:19.955Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-08T10:21:19.955Z] GC before operation: completed in 472.961 ms, heap usage 311.298 MB -> 122.786 MB. [2025-05-08T10:21:27.419Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6947.596 ms) ====== [2025-05-08T10:21:27.419Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-08T10:21:27.419Z] GC before operation: completed in 377.845 ms, heap usage 287.318 MB -> 122.972 MB. [2025-05-08T10:21:34.827Z] ====== log-regression (apache-spark) [default], iteration 15 completed (7122.885 ms) ====== [2025-05-08T10:21:34.827Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-08T10:21:34.827Z] GC before operation: completed in 384.163 ms, heap usage 457.472 MB -> 123.675 MB. [2025-05-08T10:21:41.116Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6308.986 ms) ====== [2025-05-08T10:21:41.116Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-08T10:21:41.116Z] GC before operation: completed in 330.967 ms, heap usage 311.068 MB -> 123.592 MB. [2025-05-08T10:21:47.223Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5664.038 ms) ====== [2025-05-08T10:21:47.223Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-08T10:21:47.223Z] GC before operation: completed in 349.092 ms, heap usage 425.810 MB -> 124.093 MB. [2025-05-08T10:21:53.414Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6072.019 ms) ====== [2025-05-08T10:21:53.414Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-08T10:21:54.290Z] GC before operation: completed in 458.666 ms, heap usage 239.456 MB -> 123.739 MB. [2025-05-08T10:22:01.354Z] ====== log-regression (apache-spark) [default], iteration 19 completed (7022.186 ms) ====== [2025-05-08T10:22:02.143Z] ----------------------------------- [2025-05-08T10:22:02.143Z] renaissance-log-regression_0_PASSED [2025-05-08T10:22:02.143Z] ----------------------------------- [2025-05-08T10:22:02.143Z] [2025-05-08T10:22:02.143Z] TEST TEARDOWN: [2025-05-08T10:22:02.143Z] Nothing to be done for teardown. [2025-05-08T10:22:02.143Z] renaissance-log-regression_0 Finish Time: Thu May 8 10:22:01 2025 Epoch Time (ms): 1746699721651