[2024-08-28T21:22:25.073Z] Running test renaissance-log-regression_0 ... [2024-08-28T21:22:25.073Z] =============================================== [2024-08-28T21:22:25.073Z] renaissance-log-regression_0 Start Time: Wed Aug 28 21:22:24 2024 Epoch Time (ms): 1724880144643 [2024-08-28T21:22:25.073Z] variation: NoOptions [2024-08-28T21:22:25.073Z] JVM_OPTIONS: [2024-08-28T21:22:25.073Z] { \ [2024-08-28T21:22:25.073Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T21:22:25.073Z] echo "Nothing to be done for setup."; \ [2024-08-28T21:22:25.073Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17248791343223/renaissance-log-regression_0"; \ [2024-08-28T21:22:25.073Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17248791343223/renaissance-log-regression_0"; \ [2024-08-28T21:22:25.073Z] echo ""; echo "TESTING:"; \ [2024-08-28T21:22:25.073Z] "/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_17248791343223/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-28T21:22:25.073Z] 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_17248791343223/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T21:22:25.073Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T21:22:25.073Z] echo "Nothing to be done for teardown."; \ [2024-08-28T21:22:25.073Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17248791343223/TestTargetResult"; [2024-08-28T21:22:25.073Z] [2024-08-28T21:22:25.073Z] TEST SETUP: [2024-08-28T21:22:25.073Z] Nothing to be done for setup. [2024-08-28T21:22:25.073Z] [2024-08-28T21:22:25.073Z] TESTING: [2024-08-28T21:22:27.876Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T21:22:29.932Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-28T21:22:33.715Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-28T21:22:33.715Z] GC before operation: completed in 55.308 ms, heap usage 53.483 MB -> 34.904 MB. [2024-08-28T21:22:45.912Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10952.401 ms) ====== [2024-08-28T21:22:45.912Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-28T21:22:45.912Z] GC before operation: completed in 134.439 ms, heap usage 341.685 MB -> 111.050 MB. [2024-08-28T21:22:48.748Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3981.139 ms) ====== [2024-08-28T21:22:48.749Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-28T21:22:48.749Z] GC before operation: completed in 109.295 ms, heap usage 245.715 MB -> 110.751 MB. [2024-08-28T21:22:52.494Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3320.287 ms) ====== [2024-08-28T21:22:52.494Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-28T21:22:52.494Z] GC before operation: completed in 96.287 ms, heap usage 249.373 MB -> 111.175 MB. [2024-08-28T21:22:55.287Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2744.011 ms) ====== [2024-08-28T21:22:55.287Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-28T21:22:55.287Z] GC before operation: completed in 107.445 ms, heap usage 274.638 MB -> 111.546 MB. [2024-08-28T21:22:58.139Z] 24/08/28 21:22:57 WARN BlockManager: Asked to remove block broadcast_232, which does not exist [2024-08-28T21:22:58.139Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2848.392 ms) ====== [2024-08-28T21:22:58.139Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-28T21:22:58.140Z] GC before operation: completed in 82.216 ms, heap usage 238.459 MB -> 111.637 MB. [2024-08-28T21:23:00.998Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2760.421 ms) ====== [2024-08-28T21:23:00.998Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-28T21:23:00.998Z] GC before operation: completed in 121.605 ms, heap usage 237.013 MB -> 111.818 MB. [2024-08-28T21:23:03.015Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2419.793 ms) ====== [2024-08-28T21:23:03.015Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-28T21:23:04.029Z] GC before operation: completed in 86.579 ms, heap usage 280.209 MB -> 112.156 MB. [2024-08-28T21:23:05.432Z] 24/08/28 21:23:04 WARN BlockManager: Asked to remove block broadcast_355, which does not exist [2024-08-28T21:23:06.039Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2776.078 ms) ====== [2024-08-28T21:23:06.039Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-28T21:23:06.039Z] GC before operation: completed in 113.075 ms, heap usage 294.503 MB -> 112.295 MB. [2024-08-28T21:23:09.751Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3125.331 ms) ====== [2024-08-28T21:23:09.751Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-28T21:23:09.751Z] GC before operation: completed in 97.303 ms, heap usage 320.082 MB -> 112.560 MB. [2024-08-28T21:23:12.758Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2997.034 ms) ====== [2024-08-28T21:23:12.758Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-28T21:23:12.758Z] GC before operation: completed in 122.776 ms, heap usage 291.402 MB -> 112.683 MB. [2024-08-28T21:23:15.628Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2762.281 ms) ====== [2024-08-28T21:23:15.628Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-28T21:23:15.628Z] GC before operation: completed in 111.570 ms, heap usage 227.344 MB -> 112.696 MB. [2024-08-28T21:23:18.434Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2636.489 ms) ====== [2024-08-28T21:23:18.434Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-28T21:23:18.434Z] GC before operation: completed in 143.648 ms, heap usage 226.391 MB -> 112.930 MB. [2024-08-28T21:23:20.470Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2421.644 ms) ====== [2024-08-28T21:23:20.470Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-28T21:23:21.118Z] GC before operation: completed in 163.448 ms, heap usage 290.933 MB -> 113.235 MB. [2024-08-28T21:23:24.039Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2903.443 ms) ====== [2024-08-28T21:23:24.039Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-28T21:23:24.039Z] GC before operation: completed in 171.841 ms, heap usage 262.424 MB -> 113.410 MB. [2024-08-28T21:23:26.980Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2838.056 ms) ====== [2024-08-28T21:23:26.980Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-28T21:23:26.980Z] GC before operation: completed in 209.785 ms, heap usage 244.912 MB -> 113.471 MB. [2024-08-28T21:23:29.799Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3135.375 ms) ====== [2024-08-28T21:23:29.799Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-28T21:23:30.481Z] GC before operation: completed in 183.348 ms, heap usage 228.123 MB -> 113.658 MB. [2024-08-28T21:23:33.437Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2958.831 ms) ====== [2024-08-28T21:23:33.437Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-28T21:23:33.437Z] GC before operation: completed in 118.195 ms, heap usage 262.696 MB -> 113.888 MB. [2024-08-28T21:23:36.399Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2814.281 ms) ====== [2024-08-28T21:23:36.399Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-28T21:23:36.399Z] GC before operation: completed in 161.211 ms, heap usage 290.129 MB -> 114.203 MB. [2024-08-28T21:23:39.733Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3175.503 ms) ====== [2024-08-28T21:23:39.733Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-28T21:23:39.733Z] GC before operation: completed in 249.367 ms, heap usage 228.032 MB -> 114.231 MB. [2024-08-28T21:23:42.644Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2789.925 ms) ====== [2024-08-28T21:23:43.259Z] ----------------------------------- [2024-08-28T21:23:43.259Z] renaissance-log-regression_0_PASSED [2024-08-28T21:23:43.259Z] ----------------------------------- [2024-08-28T21:23:43.259Z] [2024-08-28T21:23:43.259Z] TEST TEARDOWN: [2024-08-28T21:23:43.259Z] Nothing to be done for teardown. [2024-08-28T21:23:43.259Z] renaissance-log-regression_0 Finish Time: Wed Aug 28 21:23:42 2024 Epoch Time (ms): 1724880222780