[2025-05-29T04:23:38.654Z] Running test renaissance-log-regression_0 ... [2025-05-29T04:23:38.654Z] =============================================== [2025-05-29T04:23:38.654Z] renaissance-log-regression_0 Start Time: Thu May 29 04:23:38 2025 Epoch Time (ms): 1748492618130 [2025-05-29T04:23:38.654Z] variation: NoOptions [2025-05-29T04:23:38.654Z] JVM_OPTIONS: [2025-05-29T04:23:38.654Z] { \ [2025-05-29T04:23:38.654Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T04:23:38.654Z] echo "Nothing to be done for setup."; \ [2025-05-29T04:23:38.654Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17484905661127/renaissance-log-regression_0"; \ [2025-05-29T04:23:38.654Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17484905661127/renaissance-log-regression_0"; \ [2025-05-29T04:23:38.654Z] echo ""; echo "TESTING:"; \ [2025-05-29T04:23:38.654Z] "/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_17484905661127/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-29T04:23:38.654Z] 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_17484905661127/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T04:23:38.654Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T04:23:38.654Z] echo "Nothing to be done for teardown."; \ [2025-05-29T04:23:38.654Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17484905661127/TestTargetResult"; [2025-05-29T04:23:38.654Z] [2025-05-29T04:23:38.654Z] TEST SETUP: [2025-05-29T04:23:38.654Z] Nothing to be done for setup. [2025-05-29T04:23:38.654Z] [2025-05-29T04:23:38.654Z] TESTING: [2025-05-29T04:23:46.255Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-29T04:23:49.291Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-29T04:23:49.291Z] GC before operation: completed in 56.432 ms, heap usage 70.939 MB -> 35.033 MB. [2025-05-29T04:24:06.782Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17626.511 ms) ====== [2025-05-29T04:24:06.782Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-29T04:24:06.782Z] GC before operation: completed in 180.253 ms, heap usage 287.726 MB -> 118.270 MB. [2025-05-29T04:24:11.738Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4650.344 ms) ====== [2025-05-29T04:24:11.738Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-29T04:24:11.738Z] GC before operation: completed in 131.000 ms, heap usage 284.104 MB -> 117.381 MB. [2025-05-29T04:24:14.686Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3461.166 ms) ====== [2025-05-29T04:24:14.686Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-29T04:24:15.331Z] GC before operation: completed in 132.072 ms, heap usage 296.703 MB -> 117.765 MB. [2025-05-29T04:24:19.242Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4193.192 ms) ====== [2025-05-29T04:24:19.242Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-29T04:24:19.242Z] GC before operation: completed in 138.294 ms, heap usage 239.670 MB -> 117.960 MB. [2025-05-29T04:24:23.189Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3801.242 ms) ====== [2025-05-29T04:24:23.189Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-29T04:24:23.189Z] GC before operation: completed in 163.104 ms, heap usage 293.574 MB -> 118.316 MB. [2025-05-29T04:24:27.089Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3348.760 ms) ====== [2025-05-29T04:24:27.089Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-29T04:24:27.089Z] GC before operation: completed in 136.813 ms, heap usage 281.852 MB -> 118.537 MB. [2025-05-29T04:24:30.349Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3011.786 ms) ====== [2025-05-29T04:24:30.349Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-29T04:24:30.349Z] GC before operation: completed in 164.460 ms, heap usage 238.129 MB -> 118.796 MB. [2025-05-29T04:24:33.423Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3362.107 ms) ====== [2025-05-29T04:24:33.423Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-29T04:24:33.423Z] GC before operation: completed in 162.373 ms, heap usage 340.466 MB -> 119.139 MB. [2025-05-29T04:24:37.356Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3407.220 ms) ====== [2025-05-29T04:24:37.356Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-29T04:24:37.356Z] GC before operation: completed in 109.181 ms, heap usage 314.421 MB -> 119.177 MB. [2025-05-29T04:24:41.379Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3814.864 ms) ====== [2025-05-29T04:24:41.379Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-29T04:24:41.379Z] GC before operation: completed in 230.962 ms, heap usage 259.182 MB -> 119.219 MB. [2025-05-29T04:24:44.426Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3395.499 ms) ====== [2025-05-29T04:24:44.426Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-29T04:24:44.426Z] GC before operation: completed in 145.981 ms, heap usage 232.068 MB -> 119.369 MB. [2025-05-29T04:24:48.372Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3224.367 ms) ====== [2025-05-29T04:24:48.372Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-29T04:24:48.372Z] GC before operation: completed in 129.280 ms, heap usage 253.035 MB -> 119.557 MB. [2025-05-29T04:24:51.409Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3415.703 ms) ====== [2025-05-29T04:24:51.409Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-29T04:24:51.409Z] GC before operation: completed in 175.436 ms, heap usage 335.945 MB -> 120.014 MB. [2025-05-29T04:24:55.445Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4084.191 ms) ====== [2025-05-29T04:24:55.445Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-29T04:24:56.231Z] GC before operation: completed in 108.298 ms, heap usage 252.677 MB -> 119.983 MB. [2025-05-29T04:24:59.292Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2982.538 ms) ====== [2025-05-29T04:24:59.292Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-29T04:24:59.292Z] GC before operation: completed in 166.406 ms, heap usage 347.050 MB -> 120.343 MB. [2025-05-29T04:25:03.253Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3991.392 ms) ====== [2025-05-29T04:25:03.253Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-29T04:25:03.253Z] GC before operation: completed in 144.782 ms, heap usage 315.222 MB -> 120.492 MB. [2025-05-29T04:25:07.295Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3778.238 ms) ====== [2025-05-29T04:25:07.295Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-29T04:25:07.295Z] GC before operation: completed in 229.521 ms, heap usage 292.046 MB -> 120.590 MB. [2025-05-29T04:25:10.334Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3362.610 ms) ====== [2025-05-29T04:25:10.334Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-29T04:25:10.334Z] GC before operation: completed in 152.821 ms, heap usage 294.230 MB -> 120.907 MB. [2025-05-29T04:25:14.277Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3337.770 ms) ====== [2025-05-29T04:25:14.277Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-29T04:25:14.277Z] GC before operation: completed in 184.023 ms, heap usage 260.037 MB -> 120.909 MB. [2025-05-29T04:25:17.494Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3637.109 ms) ====== [2025-05-29T04:25:18.253Z] ----------------------------------- [2025-05-29T04:25:18.253Z] renaissance-log-regression_0_PASSED [2025-05-29T04:25:18.253Z] ----------------------------------- [2025-05-29T04:25:18.253Z] [2025-05-29T04:25:18.253Z] TEST TEARDOWN: [2025-05-29T04:25:18.253Z] Nothing to be done for teardown. [2025-05-29T04:25:18.253Z] renaissance-log-regression_0 Finish Time: Thu May 29 04:25:18 2025 Epoch Time (ms): 1748492718123