[2024-06-27T08:33:05.996Z] Running test renaissance-log-regression_0 ... [2024-06-27T08:33:05.996Z] =============================================== [2024-06-27T08:33:05.996Z] renaissance-log-regression_0 Start Time: Thu Jun 27 08:33:05 2024 Epoch Time (ms): 1719477185595 [2024-06-27T08:33:05.996Z] variation: NoOptions [2024-06-27T08:33:05.996Z] JVM_OPTIONS: [2024-06-27T08:33:05.996Z] { \ [2024-06-27T08:33:05.996Z] echo ""; echo "TEST SETUP:"; \ [2024-06-27T08:33:05.996Z] echo "Nothing to be done for setup."; \ [2024-06-27T08:33:05.996Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194760022523/renaissance-log-regression_0"; \ [2024-06-27T08:33:05.996Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194760022523/renaissance-log-regression_0"; \ [2024-06-27T08:33:05.996Z] echo ""; echo "TESTING:"; \ [2024-06-27T08:33:05.996Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194760022523/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-06-27T08:33:05.996Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194760022523/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-27T08:33:05.996Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-27T08:33:05.996Z] echo "Nothing to be done for teardown."; \ [2024-06-27T08:33:05.996Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194760022523/TestTargetResult"; [2024-06-27T08:33:05.996Z] [2024-06-27T08:33:05.996Z] TEST SETUP: [2024-06-27T08:33:05.996Z] Nothing to be done for setup. [2024-06-27T08:33:05.996Z] [2024-06-27T08:33:05.996Z] TESTING: [2024-06-27T08:33:09.845Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-27T08:33:12.749Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-06-27T08:33:14.038Z] WARNING: An illegal reflective access operation has occurred [2024-06-27T08:33:14.038Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/output_17194760022523/renaissance-log-regression_0/harness-083306-17685658728516627130/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-06-27T08:33:14.038Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-06-27T08:33:14.038Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-06-27T08:33:14.038Z] WARNING: All illegal access operations will be denied in a future release [2024-06-27T08:33:16.184Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-06-27T08:33:16.184Z] GC before operation: completed in 156.747 ms, heap usage 66.972 MB -> 33.560 MB. [2024-06-27T08:33:31.234Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14276.752 ms) ====== [2024-06-27T08:33:31.234Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-06-27T08:33:31.234Z] GC before operation: completed in 244.925 ms, heap usage 247.143 MB -> 109.374 MB. [2024-06-27T08:33:34.984Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4269.179 ms) ====== [2024-06-27T08:33:34.984Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-06-27T08:33:35.619Z] GC before operation: completed in 129.931 ms, heap usage 261.492 MB -> 109.497 MB. [2024-06-27T08:33:39.340Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3504.324 ms) ====== [2024-06-27T08:33:39.340Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-06-27T08:33:39.340Z] GC before operation: completed in 121.858 ms, heap usage 271.448 MB -> 109.887 MB. [2024-06-27T08:33:43.150Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4250.066 ms) ====== [2024-06-27T08:33:43.150Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-06-27T08:33:43.150Z] GC before operation: completed in 149.365 ms, heap usage 308.261 MB -> 110.273 MB. [2024-06-27T08:33:46.990Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3629.073 ms) ====== [2024-06-27T08:33:46.990Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-06-27T08:33:46.990Z] GC before operation: completed in 111.092 ms, heap usage 253.422 MB -> 110.331 MB. [2024-06-27T08:33:51.916Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4156.227 ms) ====== [2024-06-27T08:33:51.916Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-06-27T08:33:51.916Z] GC before operation: completed in 163.054 ms, heap usage 296.623 MB -> 110.640 MB. [2024-06-27T08:33:53.345Z] 24/06/27 08:33:53 WARN BlockManager: Asked to remove block broadcast_288, which does not exist [2024-06-27T08:33:55.482Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4035.130 ms) ====== [2024-06-27T08:33:55.482Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-06-27T08:33:55.482Z] GC before operation: completed in 113.442 ms, heap usage 324.657 MB -> 110.864 MB. [2024-06-27T08:34:00.289Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4283.662 ms) ====== [2024-06-27T08:34:00.289Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-06-27T08:34:00.289Z] GC before operation: completed in 169.808 ms, heap usage 261.175 MB -> 110.895 MB. [2024-06-27T08:34:04.074Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3837.927 ms) ====== [2024-06-27T08:34:04.074Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-06-27T08:34:04.074Z] GC before operation: completed in 172.695 ms, heap usage 242.243 MB -> 111.038 MB. [2024-06-27T08:34:06.106Z] 24/06/27 08:34:05 WARN BlockManager: Asked to remove block broadcast_435_piece0, which does not exist [2024-06-27T08:34:06.106Z] 24/06/27 08:34:05 WARN BlockManager: Asked to remove block broadcast_435, which does not exist [2024-06-27T08:34:08.308Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3784.553 ms) ====== [2024-06-27T08:34:08.308Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-06-27T08:34:08.308Z] GC before operation: completed in 239.503 ms, heap usage 269.791 MB -> 111.260 MB. [2024-06-27T08:34:12.227Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4375.210 ms) ====== [2024-06-27T08:34:12.227Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-06-27T08:34:12.901Z] GC before operation: completed in 158.878 ms, heap usage 306.236 MB -> 111.511 MB. [2024-06-27T08:34:16.923Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4099.200 ms) ====== [2024-06-27T08:34:16.923Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-06-27T08:34:16.923Z] GC before operation: completed in 161.647 ms, heap usage 305.844 MB -> 111.687 MB. [2024-06-27T08:34:20.808Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3454.175 ms) ====== [2024-06-27T08:34:20.808Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-06-27T08:34:20.808Z] GC before operation: completed in 180.217 ms, heap usage 306.070 MB -> 111.854 MB. [2024-06-27T08:34:24.694Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3812.377 ms) ====== [2024-06-27T08:34:24.694Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-06-27T08:34:24.694Z] GC before operation: completed in 165.672 ms, heap usage 242.174 MB -> 111.886 MB. [2024-06-27T08:34:27.627Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3333.463 ms) ====== [2024-06-27T08:34:27.627Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-06-27T08:34:28.288Z] GC before operation: completed in 204.182 ms, heap usage 296.121 MB -> 112.208 MB. [2024-06-27T08:34:32.232Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4182.091 ms) ====== [2024-06-27T08:34:32.232Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-06-27T08:34:32.232Z] GC before operation: completed in 129.675 ms, heap usage 289.876 MB -> 112.356 MB. [2024-06-27T08:34:36.065Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3174.318 ms) ====== [2024-06-27T08:34:36.065Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-06-27T08:34:36.065Z] GC before operation: completed in 247.969 ms, heap usage 253.805 MB -> 112.451 MB. [2024-06-27T08:34:39.902Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3730.640 ms) ====== [2024-06-27T08:34:39.902Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-06-27T08:34:39.902Z] GC before operation: completed in 226.908 ms, heap usage 324.604 MB -> 112.790 MB. [2024-06-27T08:34:43.778Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3596.471 ms) ====== [2024-06-27T08:34:43.778Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-06-27T08:34:43.778Z] GC before operation: completed in 211.710 ms, heap usage 261.503 MB -> 112.821 MB. [2024-06-27T08:34:47.528Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3668.384 ms) ====== [2024-06-27T08:34:47.528Z] ----------------------------------- [2024-06-27T08:34:47.528Z] renaissance-log-regression_0_PASSED [2024-06-27T08:34:47.528Z] ----------------------------------- [2024-06-27T08:34:47.528Z] [2024-06-27T08:34:47.528Z] TEST TEARDOWN: [2024-06-27T08:34:47.528Z] Nothing to be done for teardown. [2024-06-27T08:34:47.528Z] renaissance-log-regression_0 Finish Time: Thu Jun 27 08:34:47 2024 Epoch Time (ms): 1719477287313