[2025-04-16T06:10:40.090Z] Running test renaissance-log-regression_0 ... [2025-04-16T06:10:40.090Z] =============================================== [2025-04-16T06:10:40.090Z] renaissance-log-regression_0 Start Time: Wed Apr 16 06:10:39 2025 Epoch Time (ms): 1744783839407 [2025-04-16T06:10:40.090Z] variation: NoOptions [2025-04-16T06:10:40.090Z] JVM_OPTIONS: [2025-04-16T06:10:40.090Z] { \ [2025-04-16T06:10:40.090Z] echo ""; echo "TEST SETUP:"; \ [2025-04-16T06:10:40.090Z] echo "Nothing to be done for setup."; \ [2025-04-16T06:10:40.090Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17447814251939/renaissance-log-regression_0"; \ [2025-04-16T06:10:40.090Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17447814251939/renaissance-log-regression_0"; \ [2025-04-16T06:10:40.090Z] echo ""; echo "TESTING:"; \ [2025-04-16T06:10:40.090Z] "/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_17447814251939/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-04-16T06:10:40.090Z] 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_17447814251939/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-16T06:10:40.090Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-16T06:10:40.090Z] echo "Nothing to be done for teardown."; \ [2025-04-16T06:10:40.090Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17447814251939/TestTargetResult"; [2025-04-16T06:10:40.090Z] [2025-04-16T06:10:40.090Z] TEST SETUP: [2025-04-16T06:10:40.090Z] Nothing to be done for setup. [2025-04-16T06:10:40.090Z] [2025-04-16T06:10:40.090Z] TESTING: [2025-04-16T06:10:47.798Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-04-16T06:10:50.091Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-04-16T06:10:50.727Z] GC before operation: completed in 105.461 ms, heap usage 58.513 MB -> 34.947 MB. [2025-04-16T06:11:12.518Z] ====== log-regression (apache-spark) [default], iteration 0 completed (19847.894 ms) ====== [2025-04-16T06:11:12.518Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-04-16T06:11:12.518Z] GC before operation: completed in 164.721 ms, heap usage 262.966 MB -> 116.663 MB. [2025-04-16T06:11:16.796Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6271.065 ms) ====== [2025-04-16T06:11:16.796Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-04-16T06:11:16.796Z] GC before operation: completed in 150.582 ms, heap usage 309.668 MB -> 117.289 MB. [2025-04-16T06:11:24.600Z] ====== log-regression (apache-spark) [default], iteration 2 completed (6794.282 ms) ====== [2025-04-16T06:11:24.600Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-04-16T06:11:24.600Z] GC before operation: completed in 157.670 ms, heap usage 264.178 MB -> 117.527 MB. [2025-04-16T06:11:28.735Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4521.343 ms) ====== [2025-04-16T06:11:28.735Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-04-16T06:11:28.735Z] GC before operation: completed in 201.421 ms, heap usage 261.514 MB -> 117.881 MB. [2025-04-16T06:11:33.836Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5036.986 ms) ====== [2025-04-16T06:11:33.836Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-04-16T06:11:33.836Z] GC before operation: completed in 237.430 ms, heap usage 253.881 MB -> 117.908 MB. [2025-04-16T06:11:39.002Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4773.502 ms) ====== [2025-04-16T06:11:39.002Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-04-16T06:11:39.002Z] GC before operation: completed in 210.269 ms, heap usage 274.966 MB -> 118.394 MB. [2025-04-16T06:11:43.163Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4222.672 ms) ====== [2025-04-16T06:11:43.163Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-04-16T06:11:43.163Z] GC before operation: completed in 189.799 ms, heap usage 253.382 MB -> 118.520 MB. [2025-04-16T06:11:47.178Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3524.623 ms) ====== [2025-04-16T06:11:47.178Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-04-16T06:11:47.178Z] GC before operation: completed in 109.004 ms, heap usage 261.240 MB -> 118.677 MB. [2025-04-16T06:11:51.247Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3897.717 ms) ====== [2025-04-16T06:11:51.247Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-04-16T06:11:51.247Z] GC before operation: completed in 246.347 ms, heap usage 291.421 MB -> 118.906 MB. [2025-04-16T06:11:56.407Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5082.268 ms) ====== [2025-04-16T06:11:56.407Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-04-16T06:11:56.407Z] GC before operation: completed in 170.338 ms, heap usage 352.785 MB -> 119.398 MB. [2025-04-16T06:12:01.533Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5097.693 ms) ====== [2025-04-16T06:12:01.533Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-04-16T06:12:01.533Z] GC before operation: completed in 271.246 ms, heap usage 281.193 MB -> 119.297 MB. [2025-04-16T06:12:06.741Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4405.220 ms) ====== [2025-04-16T06:12:06.741Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-04-16T06:12:06.741Z] GC before operation: completed in 191.893 ms, heap usage 231.805 MB -> 119.385 MB. [2025-04-16T06:12:10.829Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4693.928 ms) ====== [2025-04-16T06:12:10.829Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-04-16T06:12:11.504Z] GC before operation: completed in 287.558 ms, heap usage 259.296 MB -> 119.595 MB. [2025-04-16T06:12:15.634Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4088.389 ms) ====== [2025-04-16T06:12:15.634Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-04-16T06:12:15.634Z] GC before operation: completed in 126.869 ms, heap usage 249.784 MB -> 119.689 MB. [2025-04-16T06:12:19.663Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3783.527 ms) ====== [2025-04-16T06:12:19.663Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-04-16T06:12:19.663Z] GC before operation: completed in 509.341 ms, heap usage 326.645 MB -> 120.251 MB. [2025-04-16T06:12:24.000Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3950.606 ms) ====== [2025-04-16T06:12:24.000Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-04-16T06:12:24.000Z] GC before operation: completed in 197.227 ms, heap usage 292.958 MB -> 120.278 MB. [2025-04-16T06:12:29.017Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4449.224 ms) ====== [2025-04-16T06:12:29.018Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-04-16T06:12:29.018Z] GC before operation: completed in 250.986 ms, heap usage 250.721 MB -> 120.393 MB. [2025-04-16T06:12:34.275Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5092.811 ms) ====== [2025-04-16T06:12:34.275Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-04-16T06:12:34.275Z] GC before operation: completed in 336.002 ms, heap usage 282.395 MB -> 120.660 MB. [2025-04-16T06:12:37.444Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3634.362 ms) ====== [2025-04-16T06:12:37.444Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-04-16T06:12:38.096Z] GC before operation: completed in 206.975 ms, heap usage 262.476 MB -> 120.814 MB. [2025-04-16T06:12:41.357Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3131.605 ms) ====== [2025-04-16T06:12:41.357Z] ----------------------------------- [2025-04-16T06:12:41.357Z] renaissance-log-regression_0_PASSED [2025-04-16T06:12:41.357Z] ----------------------------------- [2025-04-16T06:12:41.357Z] [2025-04-16T06:12:41.357Z] TEST TEARDOWN: [2025-04-16T06:12:41.357Z] Nothing to be done for teardown. [2025-04-16T06:12:41.357Z] renaissance-log-regression_0 Finish Time: Wed Apr 16 06:12:41 2025 Epoch Time (ms): 1744783961014