[2024-06-27T04:20:00.235Z] Running test renaissance-log-regression_0 ... [2024-06-27T04:20:00.235Z] =============================================== [2024-06-27T04:20:00.235Z] renaissance-log-regression_0 Start Time: Thu Jun 27 00:19:59 2024 Epoch Time (ms): 1719461999896 [2024-06-27T04:20:00.235Z] variation: NoOptions [2024-06-27T04:20:00.235Z] JVM_OPTIONS: [2024-06-27T04:20:00.235Z] { \ [2024-06-27T04:20:00.235Z] echo ""; echo "TEST SETUP:"; \ [2024-06-27T04:20:00.236Z] echo "Nothing to be done for setup."; \ [2024-06-27T04:20:00.236Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/renaissance-log-regression_0"; \ [2024-06-27T04:20:00.236Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/renaissance-log-regression_0"; \ [2024-06-27T04:20:00.236Z] echo ""; echo "TESTING:"; \ [2024-06-27T04:20:00.236Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/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_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-06-27T04:20:00.236Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-27T04:20:00.236Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-27T04:20:00.236Z] echo "Nothing to be done for teardown."; \ [2024-06-27T04:20:00.236Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/TestTargetResult"; [2024-06-27T04:20:00.236Z] [2024-06-27T04:20:00.236Z] TEST SETUP: [2024-06-27T04:20:00.236Z] Nothing to be done for setup. [2024-06-27T04:20:00.236Z] [2024-06-27T04:20:00.236Z] TESTING: [2024-06-27T04:20:04.112Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-27T04:20:06.265Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-06-27T04:20:10.155Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-06-27T04:20:10.155Z] GC before operation: completed in 124.487 ms, heap usage 55.159 MB -> 34.829 MB. [2024-06-27T04:20:22.890Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12011.175 ms) ====== [2024-06-27T04:20:22.890Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-06-27T04:20:22.890Z] GC before operation: completed in 168.542 ms, heap usage 326.513 MB -> 110.421 MB. [2024-06-27T04:20:26.852Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4417.593 ms) ====== [2024-06-27T04:20:26.852Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-06-27T04:20:26.852Z] GC before operation: completed in 122.494 ms, heap usage 248.851 MB -> 110.725 MB. [2024-06-27T04:20:29.790Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3443.471 ms) ====== [2024-06-27T04:20:29.790Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-06-27T04:20:29.790Z] GC before operation: completed in 209.072 ms, heap usage 287.151 MB -> 111.225 MB. [2024-06-27T04:20:33.772Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3967.152 ms) ====== [2024-06-27T04:20:33.772Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-06-27T04:20:34.413Z] GC before operation: completed in 146.768 ms, heap usage 267.282 MB -> 111.451 MB. [2024-06-27T04:20:38.421Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4196.266 ms) ====== [2024-06-27T04:20:38.421Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-06-27T04:20:38.421Z] GC before operation: completed in 131.291 ms, heap usage 231.819 MB -> 111.603 MB. [2024-06-27T04:20:42.513Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3391.037 ms) ====== [2024-06-27T04:20:42.513Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-06-27T04:20:42.513Z] GC before operation: completed in 158.175 ms, heap usage 227.756 MB -> 111.812 MB. [2024-06-27T04:20:45.557Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3364.322 ms) ====== [2024-06-27T04:20:45.557Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-06-27T04:20:45.557Z] GC before operation: completed in 145.439 ms, heap usage 273.418 MB -> 112.007 MB. [2024-06-27T04:20:49.888Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3693.574 ms) ====== [2024-06-27T04:20:49.888Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-06-27T04:20:49.888Z] GC before operation: completed in 169.832 ms, heap usage 237.536 MB -> 112.103 MB. [2024-06-27T04:20:52.851Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3127.832 ms) ====== [2024-06-27T04:20:52.851Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-06-27T04:20:52.851Z] GC before operation: completed in 149.668 ms, heap usage 300.476 MB -> 112.537 MB. [2024-06-27T04:20:56.249Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3709.700 ms) ====== [2024-06-27T04:20:56.249Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-06-27T04:20:56.972Z] GC before operation: completed in 183.573 ms, heap usage 228.773 MB -> 112.501 MB. [2024-06-27T04:20:59.151Z] 24/06/27 00:20:58 WARN BlockManager: Asked to remove block broadcast_496, which does not exist [2024-06-27T04:20:59.872Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3306.837 ms) ====== [2024-06-27T04:20:59.872Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-06-27T04:20:59.872Z] GC before operation: completed in 191.787 ms, heap usage 328.610 MB -> 112.980 MB. [2024-06-27T04:21:02.857Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2553.383 ms) ====== [2024-06-27T04:21:02.857Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-06-27T04:21:02.857Z] GC before operation: completed in 84.252 ms, heap usage 301.411 MB -> 113.002 MB. [2024-06-27T04:21:05.995Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3096.205 ms) ====== [2024-06-27T04:21:05.995Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-06-27T04:21:05.995Z] GC before operation: completed in 105.549 ms, heap usage 327.665 MB -> 113.249 MB. [2024-06-27T04:21:09.084Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3431.311 ms) ====== [2024-06-27T04:21:09.084Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-06-27T04:21:09.760Z] GC before operation: completed in 196.787 ms, heap usage 335.915 MB -> 113.515 MB. [2024-06-27T04:21:12.756Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3397.382 ms) ====== [2024-06-27T04:21:12.756Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-06-27T04:21:13.440Z] GC before operation: completed in 236.706 ms, heap usage 280.824 MB -> 113.515 MB. [2024-06-27T04:21:16.871Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3430.992 ms) ====== [2024-06-27T04:21:16.871Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-06-27T04:21:16.871Z] GC before operation: completed in 245.076 ms, heap usage 273.410 MB -> 113.733 MB. [2024-06-27T04:21:20.097Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3293.527 ms) ====== [2024-06-27T04:21:20.097Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-06-27T04:21:20.097Z] GC before operation: completed in 173.563 ms, heap usage 335.188 MB -> 114.068 MB. [2024-06-27T04:21:24.167Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3433.648 ms) ====== [2024-06-27T04:21:24.167Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-06-27T04:21:24.167Z] GC before operation: completed in 149.718 ms, heap usage 273.128 MB -> 114.064 MB. [2024-06-27T04:21:27.223Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2881.258 ms) ====== [2024-06-27T04:21:27.224Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-06-27T04:21:27.224Z] GC before operation: completed in 268.796 ms, heap usage 317.177 MB -> 114.402 MB. [2024-06-27T04:21:31.203Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3865.025 ms) ====== [2024-06-27T04:21:31.203Z] ----------------------------------- [2024-06-27T04:21:31.203Z] renaissance-log-regression_0_PASSED [2024-06-27T04:21:31.203Z] ----------------------------------- [2024-06-27T04:21:31.860Z] [2024-06-27T04:21:31.860Z] TEST TEARDOWN: [2024-06-27T04:21:31.860Z] Nothing to be done for teardown. [2024-06-27T04:21:31.860Z] renaissance-log-regression_0 Finish Time: Thu Jun 27 00:21:31 2024 Epoch Time (ms): 1719462091170