[2025-02-26T22:05:34.838Z] Running test renaissance-log-regression_0 ... [2025-02-26T22:05:34.838Z] =============================================== [2025-02-26T22:05:34.838Z] renaissance-log-regression_0 Start Time: Wed Feb 26 22:05:34 2025 Epoch Time (ms): 1740607534333 [2025-02-26T22:05:34.838Z] variation: NoOptions [2025-02-26T22:05:34.838Z] JVM_OPTIONS: [2025-02-26T22:05:34.838Z] { \ [2025-02-26T22:05:34.838Z] echo ""; echo "TEST SETUP:"; \ [2025-02-26T22:05:34.838Z] echo "Nothing to be done for setup."; \ [2025-02-26T22:05:34.838Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17406068737315/renaissance-log-regression_0"; \ [2025-02-26T22:05:34.838Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17406068737315/renaissance-log-regression_0"; \ [2025-02-26T22:05:34.838Z] echo ""; echo "TESTING:"; \ [2025-02-26T22:05:34.838Z] "/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_17406068737315/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-26T22:05:34.838Z] 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_17406068737315/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-26T22:05:34.838Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-26T22:05:34.838Z] echo "Nothing to be done for teardown."; \ [2025-02-26T22:05:34.838Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17406068737315/TestTargetResult"; [2025-02-26T22:05:34.838Z] [2025-02-26T22:05:34.838Z] TEST SETUP: [2025-02-26T22:05:34.838Z] Nothing to be done for setup. [2025-02-26T22:05:34.838Z] [2025-02-26T22:05:34.838Z] TESTING: [2025-02-26T22:05:39.360Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-26T22:05:40.976Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-02-26T22:05:45.499Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-26T22:05:45.500Z] GC before operation: completed in 52.351 ms, heap usage 82.807 MB -> 35.326 MB. [2025-02-26T22:05:52.448Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7047.990 ms) ====== [2025-02-26T22:05:52.448Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-26T22:05:52.448Z] GC before operation: completed in 95.599 ms, heap usage 404.644 MB -> 125.985 MB. [2025-02-26T22:05:54.055Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1704.424 ms) ====== [2025-02-26T22:05:54.055Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-26T22:05:54.055Z] GC before operation: completed in 101.977 ms, heap usage 373.430 MB -> 126.573 MB. [2025-02-26T22:05:55.665Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1682.098 ms) ====== [2025-02-26T22:05:55.665Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-26T22:05:55.665Z] GC before operation: completed in 98.372 ms, heap usage 626.900 MB -> 127.550 MB. [2025-02-26T22:05:57.279Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1593.693 ms) ====== [2025-02-26T22:05:57.279Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-26T22:05:57.279Z] GC before operation: completed in 96.766 ms, heap usage 492.409 MB -> 127.615 MB. [2025-02-26T22:05:58.885Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1541.624 ms) ====== [2025-02-26T22:05:58.885Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-26T22:05:58.885Z] GC before operation: completed in 93.517 ms, heap usage 277.368 MB -> 127.573 MB. [2025-02-26T22:06:00.493Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1534.213 ms) ====== [2025-02-26T22:06:00.493Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-26T22:06:00.493Z] GC before operation: completed in 99.287 ms, heap usage 628.334 MB -> 128.660 MB. [2025-02-26T22:06:02.101Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1474.848 ms) ====== [2025-02-26T22:06:02.101Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-26T22:06:02.101Z] GC before operation: completed in 107.446 ms, heap usage 575.923 MB -> 128.915 MB. [2025-02-26T22:06:03.725Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1434.156 ms) ====== [2025-02-26T22:06:03.725Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-26T22:06:03.725Z] GC before operation: completed in 107.816 ms, heap usage 319.233 MB -> 128.748 MB. [2025-02-26T22:06:05.344Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1456.515 ms) ====== [2025-02-26T22:06:05.344Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-26T22:06:05.344Z] GC before operation: completed in 109.861 ms, heap usage 576.674 MB -> 129.659 MB. [2025-02-26T22:06:06.951Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1437.448 ms) ====== [2025-02-26T22:06:06.951Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-26T22:06:06.951Z] GC before operation: completed in 100.587 ms, heap usage 289.221 MB -> 129.415 MB. [2025-02-26T22:06:08.570Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1400.879 ms) ====== [2025-02-26T22:06:08.570Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-26T22:06:08.570Z] GC before operation: completed in 103.102 ms, heap usage 625.741 MB -> 130.481 MB. [2025-02-26T22:06:10.184Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1398.197 ms) ====== [2025-02-26T22:06:10.184Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-26T22:06:10.184Z] GC before operation: completed in 99.900 ms, heap usage 365.795 MB -> 130.342 MB. [2025-02-26T22:06:10.961Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1378.346 ms) ====== [2025-02-26T22:06:10.961Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-26T22:06:11.740Z] GC before operation: completed in 101.095 ms, heap usage 588.661 MB -> 131.212 MB. [2025-02-26T22:06:12.517Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1376.193 ms) ====== [2025-02-26T22:06:12.517Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-26T22:06:12.517Z] GC before operation: completed in 104.674 ms, heap usage 290.506 MB -> 130.855 MB. [2025-02-26T22:06:14.137Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1376.383 ms) ====== [2025-02-26T22:06:14.137Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-26T22:06:14.137Z] GC before operation: completed in 109.251 ms, heap usage 561.810 MB -> 131.738 MB. [2025-02-26T22:06:15.767Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1387.350 ms) ====== [2025-02-26T22:06:15.767Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-26T22:06:15.767Z] GC before operation: completed in 108.608 ms, heap usage 704.409 MB -> 132.450 MB. [2025-02-26T22:06:17.396Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1369.468 ms) ====== [2025-02-26T22:06:17.396Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-26T22:06:17.396Z] GC before operation: completed in 115.425 ms, heap usage 444.556 MB -> 132.210 MB. [2025-02-26T22:06:19.008Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1343.231 ms) ====== [2025-02-26T22:06:19.008Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-26T22:06:19.008Z] GC before operation: completed in 110.190 ms, heap usage 626.932 MB -> 132.973 MB. [2025-02-26T22:06:19.809Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1343.211 ms) ====== [2025-02-26T22:06:19.809Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-26T22:06:20.601Z] GC before operation: completed in 108.582 ms, heap usage 292.610 MB -> 132.614 MB. [2025-02-26T22:06:21.385Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1407.414 ms) ====== [2025-02-26T22:06:22.167Z] ----------------------------------- [2025-02-26T22:06:22.167Z] renaissance-log-regression_0_PASSED [2025-02-26T22:06:22.167Z] ----------------------------------- [2025-02-26T22:06:22.167Z] [2025-02-26T22:06:22.167Z] TEST TEARDOWN: [2025-02-26T22:06:22.167Z] Nothing to be done for teardown. [2025-02-26T22:06:22.167Z] renaissance-log-regression_0 Finish Time: Wed Feb 26 22:06:21 2025 Epoch Time (ms): 1740607581635