[2025-06-12T04:32:45.828Z] Running test renaissance-log-regression_0 ... [2025-06-12T04:32:45.828Z] =============================================== [2025-06-12T04:32:45.828Z] renaissance-log-regression_0 Start Time: Thu Jun 12 04:32:45 2025 Epoch Time (ms): 1749702765711 [2025-06-12T04:32:45.828Z] variation: NoOptions [2025-06-12T04:32:45.828Z] JVM_OPTIONS: [2025-06-12T04:32:45.828Z] { \ [2025-06-12T04:32:45.828Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T04:32:45.828Z] echo "Nothing to be done for setup."; \ [2025-06-12T04:32:45.828Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17496993394779/renaissance-log-regression_0"; \ [2025-06-12T04:32:45.828Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17496993394779/renaissance-log-regression_0"; \ [2025-06-12T04:32:45.828Z] echo ""; echo "TESTING:"; \ [2025-06-12T04:32:45.828Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17496993394779/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-12T04:32:45.828Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17496993394779/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T04:32:45.829Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T04:32:45.829Z] echo "Nothing to be done for teardown."; \ [2025-06-12T04:32:45.829Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17496993394779/TestTargetResult"; [2025-06-12T04:32:45.829Z] [2025-06-12T04:32:45.829Z] TEST SETUP: [2025-06-12T04:32:45.829Z] Nothing to be done for setup. [2025-06-12T04:32:45.829Z] [2025-06-12T04:32:45.829Z] TESTING: [2025-06-12T04:32:56.673Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-12T04:33:01.747Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-12T04:33:01.747Z] GC before operation: completed in 100.913 ms, heap usage 65.286 MB -> 34.887 MB. [2025-06-12T04:33:41.192Z] ====== log-regression (apache-spark) [default], iteration 0 completed (39625.629 ms) ====== [2025-06-12T04:33:41.192Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-12T04:33:41.192Z] GC before operation: completed in 263.328 ms, heap usage 399.622 MB -> 117.198 MB. [2025-06-12T04:33:47.498Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6129.919 ms) ====== [2025-06-12T04:33:47.498Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-12T04:33:47.498Z] GC before operation: completed in 245.513 ms, heap usage 309.538 MB -> 117.381 MB. [2025-06-12T04:33:52.688Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5401.256 ms) ====== [2025-06-12T04:33:52.688Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-12T04:33:53.438Z] GC before operation: completed in 790.994 ms, heap usage 308.611 MB -> 117.764 MB. [2025-06-12T04:34:06.218Z] ====== log-regression (apache-spark) [default], iteration 3 completed (12327.638 ms) ====== [2025-06-12T04:34:06.218Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-12T04:34:06.218Z] GC before operation: completed in 214.356 ms, heap usage 290.751 MB -> 117.987 MB. [2025-06-12T04:34:15.284Z] ====== log-regression (apache-spark) [default], iteration 4 completed (8781.523 ms) ====== [2025-06-12T04:34:15.284Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-12T04:34:15.284Z] GC before operation: completed in 112.883 ms, heap usage 361.500 MB -> 118.342 MB. [2025-06-12T04:34:20.493Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5301.109 ms) ====== [2025-06-12T04:34:20.493Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-12T04:34:20.493Z] GC before operation: completed in 124.295 ms, heap usage 249.855 MB -> 118.264 MB. [2025-06-12T04:34:27.500Z] ====== log-regression (apache-spark) [default], iteration 6 completed (7270.528 ms) ====== [2025-06-12T04:34:27.500Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-12T04:34:28.318Z] GC before operation: completed in 139.106 ms, heap usage 316.275 MB -> 118.643 MB. [2025-06-12T04:34:38.314Z] ====== log-regression (apache-spark) [default], iteration 7 completed (9608.915 ms) ====== [2025-06-12T04:34:38.314Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-12T04:34:38.314Z] GC before operation: completed in 415.007 ms, heap usage 231.043 MB -> 118.604 MB. [2025-06-12T04:34:43.607Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5417.184 ms) ====== [2025-06-12T04:34:43.607Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-12T04:34:43.607Z] GC before operation: completed in 230.010 ms, heap usage 339.952 MB -> 119.240 MB. [2025-06-12T04:34:48.837Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5471.485 ms) ====== [2025-06-12T04:34:48.837Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-12T04:34:49.951Z] GC before operation: completed in 176.567 ms, heap usage 239.912 MB -> 118.943 MB. [2025-06-12T04:35:01.398Z] ====== log-regression (apache-spark) [default], iteration 10 completed (10684.005 ms) ====== [2025-06-12T04:35:01.398Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-12T04:35:01.398Z] GC before operation: completed in 1569.487 ms, heap usage 227.862 MB -> 119.203 MB. [2025-06-12T04:35:06.492Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4849.178 ms) ====== [2025-06-12T04:35:06.492Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-12T04:35:06.492Z] GC before operation: completed in 146.320 ms, heap usage 295.205 MB -> 119.461 MB. [2025-06-12T04:35:10.663Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3559.345 ms) ====== [2025-06-12T04:35:10.663Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-12T04:35:10.663Z] GC before operation: completed in 121.108 ms, heap usage 281.278 MB -> 119.674 MB. [2025-06-12T04:35:17.547Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6640.786 ms) ====== [2025-06-12T04:35:17.548Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-12T04:35:17.548Z] GC before operation: completed in 728.913 ms, heap usage 284.119 MB -> 119.835 MB. [2025-06-12T04:35:23.618Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4946.310 ms) ====== [2025-06-12T04:35:23.618Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-12T04:35:23.618Z] GC before operation: completed in 262.476 ms, heap usage 326.450 MB -> 120.153 MB. [2025-06-12T04:35:28.027Z] ====== log-regression (apache-spark) [default], iteration 15 completed (5453.670 ms) ====== [2025-06-12T04:35:28.027Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-12T04:35:28.722Z] GC before operation: completed in 108.929 ms, heap usage 269.995 MB -> 120.200 MB. [2025-06-12T04:35:31.729Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3203.553 ms) ====== [2025-06-12T04:35:31.729Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-12T04:35:31.729Z] GC before operation: completed in 305.200 ms, heap usage 292.326 MB -> 120.446 MB. [2025-06-12T04:35:37.104Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5532.320 ms) ====== [2025-06-12T04:35:37.104Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-12T04:35:37.808Z] GC before operation: completed in 272.932 ms, heap usage 238.218 MB -> 120.414 MB. [2025-06-12T04:35:44.853Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6581.445 ms) ====== [2025-06-12T04:35:44.853Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-12T04:35:46.652Z] GC before operation: completed in 1804.342 ms, heap usage 268.922 MB -> 120.734 MB. [2025-06-12T04:35:50.762Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4802.452 ms) ====== [2025-06-12T04:35:51.463Z] ----------------------------------- [2025-06-12T04:35:51.463Z] renaissance-log-regression_0_PASSED [2025-06-12T04:35:51.463Z] ----------------------------------- [2025-06-12T04:35:51.463Z] [2025-06-12T04:35:51.463Z] TEST TEARDOWN: [2025-06-12T04:35:51.463Z] Nothing to be done for teardown. [2025-06-12T04:35:51.463Z] renaissance-log-regression_0 Finish Time: Thu Jun 12 04:35:51 2025 Epoch Time (ms): 1749702951371