[2025-05-08T02:25:23.175Z] Running test renaissance-log-regression_0 ... [2025-05-08T02:25:23.175Z] =============================================== [2025-05-08T02:25:23.175Z] renaissance-log-regression_0 Start Time: Thu May 8 02:25:22 2025 Epoch Time (ms): 1746671122898 [2025-05-08T02:25:23.175Z] variation: NoOptions [2025-05-08T02:25:23.175Z] JVM_OPTIONS: [2025-05-08T02:25:23.175Z] { \ [2025-05-08T02:25:23.175Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T02:25:23.175Z] echo "Nothing to be done for setup."; \ [2025-05-08T02:25:23.175Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466671573413/renaissance-log-regression_0"; \ [2025-05-08T02:25:23.175Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466671573413/renaissance-log-regression_0"; \ [2025-05-08T02:25:23.175Z] echo ""; echo "TESTING:"; \ [2025-05-08T02:25:23.176Z] "/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_17466671573413/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-08T02:25:23.176Z] 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_17466671573413/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T02:25:23.176Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T02:25:23.176Z] echo "Nothing to be done for teardown."; \ [2025-05-08T02:25:23.176Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466671573413/TestTargetResult"; [2025-05-08T02:25:23.176Z] [2025-05-08T02:25:23.176Z] TEST SETUP: [2025-05-08T02:25:23.176Z] Nothing to be done for setup. [2025-05-08T02:25:23.176Z] [2025-05-08T02:25:23.176Z] TESTING: [2025-05-08T02:25:32.762Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-08T02:25:35.071Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-08T02:25:35.071Z] GC before operation: completed in 80.375 ms, heap usage 75.654 MB -> 34.938 MB. [2025-05-08T02:26:05.154Z] ====== log-regression (apache-spark) [default], iteration 0 completed (30113.207 ms) ====== [2025-05-08T02:26:05.154Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-08T02:26:06.172Z] GC before operation: completed in 825.174 ms, heap usage 336.852 MB -> 116.993 MB. [2025-05-08T02:26:14.177Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7748.982 ms) ====== [2025-05-08T02:26:14.177Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-08T02:26:14.177Z] GC before operation: completed in 210.124 ms, heap usage 254.472 MB -> 117.241 MB. [2025-05-08T02:26:19.306Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4029.813 ms) ====== [2025-05-08T02:26:19.306Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-08T02:26:19.306Z] GC before operation: completed in 154.622 ms, heap usage 308.338 MB -> 117.796 MB. [2025-05-08T02:26:23.485Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4662.671 ms) ====== [2025-05-08T02:26:23.485Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-08T02:26:23.485Z] GC before operation: completed in 250.433 ms, heap usage 230.054 MB -> 117.915 MB. [2025-05-08T02:26:27.535Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4265.136 ms) ====== [2025-05-08T02:26:27.535Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-08T02:26:27.535Z] GC before operation: completed in 188.675 ms, heap usage 317.685 MB -> 118.364 MB. [2025-05-08T02:26:33.424Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4044.065 ms) ====== [2025-05-08T02:26:33.424Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-08T02:26:33.424Z] GC before operation: completed in 924.930 ms, heap usage 260.700 MB -> 118.368 MB. [2025-05-08T02:26:36.804Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4296.413 ms) ====== [2025-05-08T02:26:36.804Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-08T02:26:36.804Z] GC before operation: completed in 154.279 ms, heap usage 315.140 MB -> 118.663 MB. [2025-05-08T02:26:41.977Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4184.134 ms) ====== [2025-05-08T02:26:41.977Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-08T02:26:41.977Z] GC before operation: completed in 155.876 ms, heap usage 250.261 MB -> 118.715 MB. [2025-05-08T02:26:45.472Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3783.863 ms) ====== [2025-05-08T02:26:45.472Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-08T02:26:45.472Z] GC before operation: completed in 138.403 ms, heap usage 291.506 MB -> 119.094 MB. [2025-05-08T02:26:52.063Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6316.159 ms) ====== [2025-05-08T02:26:52.063Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-08T02:26:53.169Z] GC before operation: completed in 1236.525 ms, heap usage 261.982 MB -> 119.137 MB. [2025-05-08T02:26:59.942Z] ====== log-regression (apache-spark) [default], iteration 10 completed (7057.646 ms) ====== [2025-05-08T02:26:59.942Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-08T02:26:59.942Z] GC before operation: completed in 233.312 ms, heap usage 336.446 MB -> 119.622 MB. [2025-05-08T02:27:05.145Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4381.064 ms) ====== [2025-05-08T02:27:05.145Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-08T02:27:05.145Z] GC before operation: completed in 193.285 ms, heap usage 248.390 MB -> 119.435 MB. [2025-05-08T02:27:09.419Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3946.008 ms) ====== [2025-05-08T02:27:09.419Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-08T02:27:09.419Z] GC before operation: completed in 221.212 ms, heap usage 317.144 MB -> 119.951 MB. [2025-05-08T02:27:12.726Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4028.552 ms) ====== [2025-05-08T02:27:12.726Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-08T02:27:13.614Z] GC before operation: completed in 197.346 ms, heap usage 240.013 MB -> 119.803 MB. [2025-05-08T02:27:16.767Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3858.901 ms) ====== [2025-05-08T02:27:16.767Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-08T02:27:17.501Z] GC before operation: completed in 129.865 ms, heap usage 232.670 MB -> 120.024 MB. [2025-05-08T02:27:26.565Z] ====== log-regression (apache-spark) [default], iteration 15 completed (8380.348 ms) ====== [2025-05-08T02:27:26.565Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-08T02:27:26.565Z] GC before operation: completed in 348.446 ms, heap usage 282.368 MB -> 120.340 MB. [2025-05-08T02:27:30.612Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4898.114 ms) ====== [2025-05-08T02:27:30.612Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-08T02:27:31.285Z] GC before operation: completed in 203.186 ms, heap usage 298.311 MB -> 120.594 MB. [2025-05-08T02:27:35.432Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3872.309 ms) ====== [2025-05-08T02:27:35.432Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-08T02:27:35.432Z] GC before operation: completed in 304.640 ms, heap usage 303.845 MB -> 120.806 MB. [2025-05-08T02:27:38.432Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3455.519 ms) ====== [2025-05-08T02:27:38.432Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-08T02:27:39.089Z] GC before operation: completed in 168.058 ms, heap usage 292.120 MB -> 120.931 MB. [2025-05-08T02:27:42.091Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3227.637 ms) ====== [2025-05-08T02:27:42.992Z] ----------------------------------- [2025-05-08T02:27:42.992Z] renaissance-log-regression_0_PASSED [2025-05-08T02:27:42.992Z] ----------------------------------- [2025-05-08T02:27:42.992Z] [2025-05-08T02:27:42.992Z] TEST TEARDOWN: [2025-05-08T02:27:42.992Z] Nothing to be done for teardown. [2025-05-08T02:27:42.992Z] renaissance-log-regression_0 Finish Time: Thu May 8 02:27:42 2025 Epoch Time (ms): 1746671262159