[2025-05-08T01:58:54.549Z] Running test renaissance-log-regression_0 ... [2025-05-08T01:58:54.549Z] =============================================== [2025-05-08T01:58:54.549Z] renaissance-log-regression_0 Start Time: Thu May 8 01:58:54 2025 Epoch Time (ms): 1746669534413 [2025-05-08T01:58:54.549Z] variation: NoOptions [2025-05-08T01:58:54.549Z] JVM_OPTIONS: [2025-05-08T01:58:54.549Z] { \ [2025-05-08T01:58:54.549Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T01:58:54.549Z] echo "Nothing to be done for setup."; \ [2025-05-08T01:58:54.549Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17466684911316/renaissance-log-regression_0"; \ [2025-05-08T01:58:54.549Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17466684911316/renaissance-log-regression_0"; \ [2025-05-08T01:58:54.549Z] echo ""; echo "TESTING:"; \ [2025-05-08T01:58:54.549Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17466684911316/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-08T01:58:54.549Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17466684911316/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T01:58:54.549Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T01:58:54.549Z] echo "Nothing to be done for teardown."; \ [2025-05-08T01:58:54.549Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17466684911316/TestTargetResult"; [2025-05-08T01:58:54.549Z] [2025-05-08T01:58:54.549Z] TEST SETUP: [2025-05-08T01:58:54.549Z] Nothing to be done for setup. [2025-05-08T01:58:54.549Z] [2025-05-08T01:58:54.549Z] TESTING: [2025-05-08T01:59:02.769Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-05-08T01:59:05.246Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-08T01:59:05.246Z] GC before operation: completed in 58.866 ms, heap usage 39.310 MB -> 34.654 MB. [2025-05-08T01:59:16.862Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10714.021 ms) ====== [2025-05-08T01:59:16.862Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-08T01:59:16.862Z] GC before operation: completed in 107.737 ms, heap usage 269.740 MB -> 131.589 MB. [2025-05-08T01:59:17.627Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1960.926 ms) ====== [2025-05-08T01:59:17.627Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-08T01:59:17.627Z] GC before operation: completed in 108.073 ms, heap usage 243.230 MB -> 132.128 MB. [2025-05-08T01:59:20.076Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1727.523 ms) ====== [2025-05-08T01:59:20.076Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-08T01:59:20.076Z] GC before operation: completed in 102.896 ms, heap usage 526.131 MB -> 133.232 MB. [2025-05-08T01:59:21.649Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1637.966 ms) ====== [2025-05-08T01:59:21.649Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-08T01:59:21.649Z] GC before operation: completed in 108.326 ms, heap usage 837.800 MB -> 134.692 MB. [2025-05-08T01:59:23.225Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1600.553 ms) ====== [2025-05-08T01:59:23.225Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-08T01:59:23.225Z] GC before operation: completed in 108.831 ms, heap usage 464.618 MB -> 133.881 MB. [2025-05-08T01:59:24.779Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1602.770 ms) ====== [2025-05-08T01:59:24.779Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-08T01:59:24.779Z] GC before operation: completed in 108.956 ms, heap usage 564.660 MB -> 134.587 MB. [2025-05-08T01:59:26.729Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1552.267 ms) ====== [2025-05-08T01:59:26.729Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-08T01:59:26.729Z] GC before operation: completed in 104.966 ms, heap usage 568.768 MB -> 134.967 MB. [2025-05-08T01:59:28.843Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1527.206 ms) ====== [2025-05-08T01:59:28.843Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-08T01:59:28.843Z] GC before operation: completed in 110.555 ms, heap usage 326.135 MB -> 134.660 MB. [2025-05-08T01:59:29.674Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1517.456 ms) ====== [2025-05-08T01:59:29.674Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-08T01:59:29.674Z] GC before operation: completed in 114.180 ms, heap usage 403.880 MB -> 135.233 MB. [2025-05-08T01:59:31.613Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1498.044 ms) ====== [2025-05-08T01:59:31.614Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-08T01:59:31.614Z] GC before operation: completed in 121.365 ms, heap usage 272.637 MB -> 135.265 MB. [2025-05-08T01:59:33.185Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1482.178 ms) ====== [2025-05-08T01:59:33.185Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-08T01:59:33.185Z] GC before operation: completed in 118.151 ms, heap usage 809.930 MB -> 137.242 MB. [2025-05-08T01:59:34.840Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1576.618 ms) ====== [2025-05-08T01:59:34.840Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-08T01:59:34.840Z] GC before operation: completed in 125.194 ms, heap usage 564.898 MB -> 136.908 MB. [2025-05-08T01:59:36.425Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1514.687 ms) ====== [2025-05-08T01:59:36.425Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-08T01:59:36.425Z] GC before operation: completed in 121.472 ms, heap usage 566.900 MB -> 137.303 MB. [2025-05-08T01:59:38.005Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1472.280 ms) ====== [2025-05-08T01:59:38.005Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-08T01:59:38.005Z] GC before operation: completed in 114.122 ms, heap usage 566.969 MB -> 137.694 MB. [2025-05-08T01:59:39.584Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1496.880 ms) ====== [2025-05-08T01:59:39.584Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-08T01:59:39.584Z] GC before operation: completed in 129.067 ms, heap usage 941.426 MB -> 139.137 MB. [2025-05-08T01:59:41.158Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1456.477 ms) ====== [2025-05-08T01:59:41.158Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-08T01:59:41.158Z] GC before operation: completed in 115.610 ms, heap usage 269.363 MB -> 137.650 MB. [2025-05-08T01:59:42.732Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1455.912 ms) ====== [2025-05-08T01:59:42.732Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-08T01:59:42.732Z] GC before operation: completed in 122.515 ms, heap usage 838.297 MB -> 139.846 MB. [2025-05-08T01:59:44.327Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1460.194 ms) ====== [2025-05-08T01:59:44.327Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-08T01:59:44.327Z] GC before operation: completed in 118.152 ms, heap usage 448.548 MB -> 138.938 MB. [2025-05-08T01:59:45.909Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1451.904 ms) ====== [2025-05-08T01:59:45.909Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-08T01:59:45.909Z] GC before operation: completed in 132.719 ms, heap usage 568.812 MB -> 139.671 MB. [2025-05-08T01:59:47.488Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1478.797 ms) ====== [2025-05-08T01:59:47.488Z] ----------------------------------- [2025-05-08T01:59:47.488Z] renaissance-log-regression_0_PASSED [2025-05-08T01:59:47.488Z] ----------------------------------- [2025-05-08T01:59:47.488Z] [2025-05-08T01:59:47.488Z] TEST TEARDOWN: [2025-05-08T01:59:47.488Z] Nothing to be done for teardown. [2025-05-08T01:59:47.488Z] renaissance-log-regression_0 Finish Time: Thu May 8 01:59:47 2025 Epoch Time (ms): 1746669587387