[2025-05-08T07:58:49.440Z] Running test renaissance-log-regression_0 ... [2025-05-08T07:58:49.440Z] =============================================== [2025-05-08T07:58:49.440Z] renaissance-log-regression_0 Start Time: Thu May 8 07:58:48 2025 Epoch Time (ms): 1746691128477 [2025-05-08T07:58:49.440Z] variation: NoOptions [2025-05-08T07:58:49.440Z] JVM_OPTIONS: [2025-05-08T07:58:49.440Z] { \ [2025-05-08T07:58:49.440Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T07:58:49.440Z] echo "Nothing to be done for setup."; \ [2025-05-08T07:58:49.440Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-log-regression_0"; \ [2025-05-08T07:58:49.440Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-log-regression_0"; \ [2025-05-08T07:58:49.440Z] echo ""; echo "TESTING:"; \ [2025-05-08T07:58:49.440Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-08T07:58:49.440Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T07:58:49.440Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T07:58:49.440Z] echo "Nothing to be done for teardown."; \ [2025-05-08T07:58:49.440Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/TestTargetResult"; [2025-05-08T07:58:49.440Z] [2025-05-08T07:58:49.440Z] TEST SETUP: [2025-05-08T07:58:49.440Z] Nothing to be done for setup. [2025-05-08T07:58:49.440Z] [2025-05-08T07:58:49.440Z] TESTING: [2025-05-08T07:58:54.807Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-08T07:58:56.761Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-08T07:58:56.761Z] GC before operation: completed in 39.323 ms, heap usage 55.447 MB -> 35.114 MB. [2025-05-08T07:59:06.499Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9587.861 ms) ====== [2025-05-08T07:59:06.499Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-08T07:59:06.499Z] GC before operation: completed in 76.959 ms, heap usage 276.792 MB -> 119.443 MB. [2025-05-08T07:59:08.462Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2067.496 ms) ====== [2025-05-08T07:59:08.462Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-08T07:59:08.462Z] GC before operation: completed in 61.443 ms, heap usage 323.353 MB -> 120.168 MB. [2025-05-08T07:59:10.418Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1677.987 ms) ====== [2025-05-08T07:59:10.418Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-08T07:59:10.418Z] GC before operation: completed in 68.487 ms, heap usage 280.005 MB -> 120.427 MB. [2025-05-08T07:59:12.549Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1875.810 ms) ====== [2025-05-08T07:59:12.549Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-08T07:59:12.549Z] GC before operation: completed in 85.767 ms, heap usage 270.814 MB -> 120.684 MB. [2025-05-08T07:59:13.505Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1726.983 ms) ====== [2025-05-08T07:59:13.505Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-08T07:59:13.505Z] GC before operation: completed in 86.318 ms, heap usage 265.536 MB -> 120.976 MB. [2025-05-08T07:59:15.459Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1631.822 ms) ====== [2025-05-08T07:59:15.459Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-08T07:59:15.459Z] GC before operation: completed in 76.171 ms, heap usage 268.272 MB -> 121.147 MB. [2025-05-08T07:59:17.412Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1592.351 ms) ====== [2025-05-08T07:59:17.412Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-08T07:59:17.412Z] GC before operation: completed in 73.780 ms, heap usage 348.662 MB -> 121.588 MB. [2025-05-08T07:59:18.371Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1602.138 ms) ====== [2025-05-08T07:59:18.371Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-08T07:59:20.328Z] GC before operation: completed in 74.986 ms, heap usage 398.357 MB -> 121.970 MB. [2025-05-08T07:59:21.281Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1746.077 ms) ====== [2025-05-08T07:59:21.281Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-08T07:59:21.281Z] GC before operation: completed in 78.358 ms, heap usage 369.299 MB -> 122.012 MB. [2025-05-08T07:59:22.234Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1633.976 ms) ====== [2025-05-08T07:59:22.234Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-08T07:59:22.234Z] GC before operation: completed in 77.768 ms, heap usage 326.499 MB -> 122.131 MB. [2025-05-08T07:59:24.191Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1562.071 ms) ====== [2025-05-08T07:59:24.191Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-08T07:59:24.191Z] GC before operation: completed in 88.523 ms, heap usage 451.702 MB -> 122.721 MB. [2025-05-08T07:59:26.145Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1724.549 ms) ====== [2025-05-08T07:59:26.145Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-08T07:59:26.145Z] GC before operation: completed in 81.957 ms, heap usage 305.552 MB -> 122.480 MB. [2025-05-08T07:59:27.098Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1481.676 ms) ====== [2025-05-08T07:59:27.098Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-08T07:59:27.098Z] GC before operation: completed in 83.080 ms, heap usage 433.478 MB -> 123.062 MB. [2025-05-08T07:59:29.055Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1609.366 ms) ====== [2025-05-08T07:59:29.055Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-08T07:59:29.055Z] GC before operation: completed in 84.686 ms, heap usage 269.393 MB -> 122.846 MB. [2025-05-08T07:59:31.011Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1595.060 ms) ====== [2025-05-08T07:59:31.011Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-08T07:59:31.011Z] GC before operation: completed in 95.699 ms, heap usage 288.683 MB -> 123.031 MB. [2025-05-08T07:59:31.967Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1551.822 ms) ====== [2025-05-08T07:59:31.967Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-08T07:59:33.122Z] GC before operation: completed in 82.715 ms, heap usage 355.253 MB -> 123.432 MB. [2025-05-08T07:59:34.074Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1788.411 ms) ====== [2025-05-08T07:59:34.074Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-08T07:59:34.074Z] GC before operation: completed in 88.638 ms, heap usage 328.103 MB -> 123.681 MB. [2025-05-08T07:59:36.027Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1686.637 ms) ====== [2025-05-08T07:59:36.027Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-08T07:59:36.027Z] GC before operation: completed in 82.129 ms, heap usage 300.565 MB -> 123.830 MB. [2025-05-08T07:59:37.982Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1665.686 ms) ====== [2025-05-08T07:59:37.982Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-08T07:59:37.982Z] GC before operation: completed in 80.069 ms, heap usage 297.667 MB -> 123.864 MB. [2025-05-08T07:59:39.937Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1676.145 ms) ====== [2025-05-08T07:59:39.937Z] ----------------------------------- [2025-05-08T07:59:39.937Z] renaissance-log-regression_0_PASSED [2025-05-08T07:59:39.937Z] ----------------------------------- [2025-05-08T07:59:39.937Z] [2025-05-08T07:59:39.937Z] TEST TEARDOWN: [2025-05-08T07:59:39.937Z] Nothing to be done for teardown. [2025-05-08T07:59:39.937Z] renaissance-log-regression_0 Finish Time: Thu May 8 07:59:39 2025 Epoch Time (ms): 1746691179274