[2025-08-13T20:30:59.532Z] Running test renaissance-log-regression_0 ... [2025-08-13T20:30:59.532Z] =============================================== [2025-08-13T20:30:59.532Z] renaissance-log-regression_0 Start Time: Wed Aug 13 20:30:59 2025 Epoch Time (ms): 1755117059133 [2025-08-13T20:30:59.532Z] variation: NoOptions [2025-08-13T20:30:59.532Z] JVM_OPTIONS: [2025-08-13T20:30:59.532Z] { \ [2025-08-13T20:30:59.532Z] echo ""; echo "TEST SETUP:"; \ [2025-08-13T20:30:59.532Z] echo "Nothing to be done for setup."; \ [2025-08-13T20:30:59.532Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17551159421546/renaissance-log-regression_0"; \ [2025-08-13T20:30:59.532Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17551159421546/renaissance-log-regression_0"; \ [2025-08-13T20:30:59.532Z] echo ""; echo "TESTING:"; \ [2025-08-13T20:30:59.532Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17551159421546/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-13T20:30:59.532Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17551159421546/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-13T20:30:59.532Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-13T20:30:59.532Z] echo "Nothing to be done for teardown."; \ [2025-08-13T20:30:59.532Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17551159421546/TestTargetResult"; [2025-08-13T20:30:59.532Z] [2025-08-13T20:30:59.532Z] TEST SETUP: [2025-08-13T20:30:59.532Z] Nothing to be done for setup. [2025-08-13T20:30:59.532Z] [2025-08-13T20:30:59.532Z] TESTING: [2025-08-13T20:31:06.437Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-08-13T20:31:09.948Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-13T20:31:09.948Z] GC before operation: completed in 69.950 ms, heap usage 54.863 MB -> 34.966 MB. [2025-08-13T20:31:22.092Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11492.390 ms) ====== [2025-08-13T20:31:22.092Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-13T20:31:22.092Z] GC before operation: completed in 98.946 ms, heap usage 386.192 MB -> 132.604 MB. [2025-08-13T20:31:23.707Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2213.717 ms) ====== [2025-08-13T20:31:23.707Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-13T20:31:23.707Z] GC before operation: completed in 114.896 ms, heap usage 567.682 MB -> 133.710 MB. [2025-08-13T20:31:26.203Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1876.078 ms) ====== [2025-08-13T20:31:26.203Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-13T20:31:26.203Z] GC before operation: completed in 112.512 ms, heap usage 455.930 MB -> 133.748 MB. [2025-08-13T20:31:27.806Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1858.836 ms) ====== [2025-08-13T20:31:27.806Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-13T20:31:27.806Z] GC before operation: completed in 101.402 ms, heap usage 407.285 MB -> 134.065 MB. [2025-08-13T20:31:29.408Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1785.108 ms) ====== [2025-08-13T20:31:29.408Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-13T20:31:30.222Z] GC before operation: completed in 107.065 ms, heap usage 536.732 MB -> 134.909 MB. [2025-08-13T20:31:31.842Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1739.014 ms) ====== [2025-08-13T20:31:31.842Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-13T20:31:31.842Z] GC before operation: completed in 115.778 ms, heap usage 347.604 MB -> 134.695 MB. [2025-08-13T20:31:33.445Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1700.814 ms) ====== [2025-08-13T20:31:33.445Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-13T20:31:33.445Z] GC before operation: completed in 128.273 ms, heap usage 765.112 MB -> 136.222 MB. [2025-08-13T20:31:35.064Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1690.636 ms) ====== [2025-08-13T20:31:35.064Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-13T20:31:35.064Z] GC before operation: completed in 117.834 ms, heap usage 567.251 MB -> 136.107 MB. [2025-08-13T20:31:36.664Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1655.389 ms) ====== [2025-08-13T20:31:36.664Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-13T20:31:37.450Z] GC before operation: completed in 111.906 ms, heap usage 524.050 MB -> 136.322 MB. [2025-08-13T20:31:39.078Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1663.158 ms) ====== [2025-08-13T20:31:39.078Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-13T20:31:39.078Z] GC before operation: completed in 114.520 ms, heap usage 597.673 MB -> 137.061 MB. [2025-08-13T20:31:40.683Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1621.011 ms) ====== [2025-08-13T20:31:40.683Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-13T20:31:40.683Z] GC before operation: completed in 114.088 ms, heap usage 775.467 MB -> 137.884 MB. [2025-08-13T20:31:42.302Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1646.604 ms) ====== [2025-08-13T20:31:42.303Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-13T20:31:42.303Z] GC before operation: completed in 119.906 ms, heap usage 505.965 MB -> 137.495 MB. [2025-08-13T20:31:43.902Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1616.501 ms) ====== [2025-08-13T20:31:43.902Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-13T20:31:43.902Z] GC before operation: completed in 130.937 ms, heap usage 360.975 MB -> 137.451 MB. [2025-08-13T20:31:45.519Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1590.107 ms) ====== [2025-08-13T20:31:45.520Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-13T20:31:45.520Z] GC before operation: completed in 121.996 ms, heap usage 348.491 MB -> 137.658 MB. [2025-08-13T20:31:47.128Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1590.527 ms) ====== [2025-08-13T20:31:47.128Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-13T20:31:47.927Z] GC before operation: completed in 123.129 ms, heap usage 743.227 MB -> 139.171 MB. [2025-08-13T20:31:49.532Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1608.970 ms) ====== [2025-08-13T20:31:49.532Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-13T20:31:49.532Z] GC before operation: completed in 125.149 ms, heap usage 350.361 MB -> 138.425 MB. [2025-08-13T20:31:51.135Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1549.722 ms) ====== [2025-08-13T20:31:51.135Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-13T20:31:51.135Z] GC before operation: completed in 130.411 ms, heap usage 239.953 MB -> 138.680 MB. [2025-08-13T20:31:52.738Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1581.438 ms) ====== [2025-08-13T20:31:52.738Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-13T20:31:52.738Z] GC before operation: completed in 122.478 ms, heap usage 333.204 MB -> 139.148 MB. [2025-08-13T20:31:54.406Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1625.132 ms) ====== [2025-08-13T20:31:54.406Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-13T20:31:54.406Z] GC before operation: completed in 140.582 ms, heap usage 872.301 MB -> 141.121 MB. [2025-08-13T20:31:56.028Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1590.216 ms) ====== [2025-08-13T20:31:56.802Z] ----------------------------------- [2025-08-13T20:31:56.802Z] renaissance-log-regression_0_PASSED [2025-08-13T20:31:56.802Z] ----------------------------------- [2025-08-13T20:31:56.802Z] [2025-08-13T20:31:56.802Z] TEST TEARDOWN: [2025-08-13T20:31:56.802Z] Nothing to be done for teardown. [2025-08-13T20:31:56.802Z] renaissance-log-regression_0 Finish Time: Wed Aug 13 20:31:55 2025 Epoch Time (ms): 1755117115964