[2024-08-15T07:09:13.354Z] Running test renaissance-log-regression_0 ... [2024-08-15T07:09:13.354Z] =============================================== [2024-08-15T07:09:13.354Z] renaissance-log-regression_0 Start Time: Thu Aug 15 07:09:12 2024 Epoch Time (ms): 1723705752894 [2024-08-15T07:09:13.354Z] variation: NoOptions [2024-08-15T07:09:13.354Z] JVM_OPTIONS: [2024-08-15T07:09:13.354Z] { \ [2024-08-15T07:09:13.354Z] echo ""; echo "TEST SETUP:"; \ [2024-08-15T07:09:13.354Z] echo "Nothing to be done for setup."; \ [2024-08-15T07:09:13.354Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17237038963107/renaissance-log-regression_0"; \ [2024-08-15T07:09:13.354Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17237038963107/renaissance-log-regression_0"; \ [2024-08-15T07:09:13.354Z] echo ""; echo "TESTING:"; \ [2024-08-15T07:09:13.355Z] "/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_17237038963107/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-15T07:09:13.355Z] 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_17237038963107/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-15T07:09:13.355Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-15T07:09:13.355Z] echo "Nothing to be done for teardown."; \ [2024-08-15T07:09:13.355Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17237038963107/TestTargetResult"; [2024-08-15T07:09:13.355Z] [2024-08-15T07:09:13.355Z] TEST SETUP: [2024-08-15T07:09:13.355Z] Nothing to be done for setup. [2024-08-15T07:09:13.355Z] [2024-08-15T07:09:13.355Z] TESTING: [2024-08-15T07:09:21.064Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-15T07:09:25.303Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-15T07:09:27.490Z] WARNING: An illegal reflective access operation has occurred [2024-08-15T07:09:27.491Z] 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_17237038963107/renaissance-log-regression_0/harness-070914-16647757163736021733/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-15T07:09:27.491Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-15T07:09:27.491Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-15T07:09:27.491Z] WARNING: All illegal access operations will be denied in a future release [2024-08-15T07:09:31.448Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-15T07:09:31.448Z] GC before operation: completed in 324.672 ms, heap usage 52.224 MB -> 33.555 MB. [2024-08-15T07:09:48.612Z] 24/08/15 07:09:46 WARN BlockManager: Asked to remove block broadcast_12_piece0, which does not exist [2024-08-15T07:09:48.612Z] 24/08/15 07:09:47 WARN BlockManager: Asked to remove block broadcast_12, which does not exist [2024-08-15T07:09:58.327Z] ====== log-regression (apache-spark) [default], iteration 0 completed (26799.222 ms) ====== [2024-08-15T07:09:58.327Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-15T07:09:58.971Z] GC before operation: completed in 252.745 ms, heap usage 265.472 MB -> 109.048 MB. [2024-08-15T07:10:08.088Z] ====== log-regression (apache-spark) [default], iteration 1 completed (8802.982 ms) ====== [2024-08-15T07:10:08.088Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-15T07:10:08.088Z] GC before operation: completed in 259.876 ms, heap usage 234.756 MB -> 109.410 MB. [2024-08-15T07:10:14.044Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5422.907 ms) ====== [2024-08-15T07:10:14.044Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-15T07:10:14.044Z] GC before operation: completed in 506.189 ms, heap usage 274.226 MB -> 109.887 MB. [2024-08-15T07:10:22.021Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8387.699 ms) ====== [2024-08-15T07:10:22.021Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-15T07:10:22.931Z] GC before operation: completed in 532.118 ms, heap usage 259.225 MB -> 110.188 MB. [2024-08-15T07:10:35.186Z] ====== log-regression (apache-spark) [default], iteration 4 completed (11920.437 ms) ====== [2024-08-15T07:10:35.186Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-15T07:10:35.186Z] GC before operation: completed in 462.429 ms, heap usage 227.637 MB -> 110.271 MB. [2024-08-15T07:10:42.845Z] ====== log-regression (apache-spark) [default], iteration 5 completed (7572.989 ms) ====== [2024-08-15T07:10:42.846Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-15T07:10:42.846Z] GC before operation: completed in 323.901 ms, heap usage 234.982 MB -> 110.494 MB. [2024-08-15T07:10:57.260Z] ====== log-regression (apache-spark) [default], iteration 6 completed (13311.923 ms) ====== [2024-08-15T07:10:57.260Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-15T07:10:57.260Z] GC before operation: completed in 531.447 ms, heap usage 308.520 MB -> 110.817 MB. [2024-08-15T07:11:03.516Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6021.285 ms) ====== [2024-08-15T07:11:03.516Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-15T07:11:03.516Z] GC before operation: completed in 328.226 ms, heap usage 254.386 MB -> 110.867 MB. [2024-08-15T07:11:07.250Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4262.808 ms) ====== [2024-08-15T07:11:07.250Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-15T07:11:07.855Z] GC before operation: completed in 300.877 ms, heap usage 289.270 MB -> 111.142 MB. [2024-08-15T07:11:18.913Z] ====== log-regression (apache-spark) [default], iteration 9 completed (10109.886 ms) ====== [2024-08-15T07:11:18.913Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-15T07:11:18.913Z] GC before operation: completed in 304.946 ms, heap usage 235.528 MB -> 111.171 MB. [2024-08-15T07:11:26.552Z] ====== log-regression (apache-spark) [default], iteration 10 completed (8679.770 ms) ====== [2024-08-15T07:11:26.552Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-15T07:11:27.296Z] GC before operation: completed in 611.790 ms, heap usage 224.838 MB -> 111.338 MB. [2024-08-15T07:11:38.815Z] ====== log-regression (apache-spark) [default], iteration 11 completed (10416.122 ms) ====== [2024-08-15T07:11:38.815Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-15T07:11:38.815Z] GC before operation: completed in 358.458 ms, heap usage 315.019 MB -> 111.738 MB. [2024-08-15T07:11:43.790Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5668.210 ms) ====== [2024-08-15T07:11:43.790Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-15T07:11:44.434Z] GC before operation: completed in 450.269 ms, heap usage 242.645 MB -> 111.718 MB. [2024-08-15T07:11:49.651Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5431.319 ms) ====== [2024-08-15T07:11:49.651Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-15T07:11:50.282Z] GC before operation: completed in 532.191 ms, heap usage 288.796 MB -> 111.984 MB. [2024-08-15T07:11:59.981Z] ====== log-regression (apache-spark) [default], iteration 14 completed (8461.752 ms) ====== [2024-08-15T07:11:59.981Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-15T07:11:59.981Z] GC before operation: completed in 747.372 ms, heap usage 206.866 MB -> 112.057 MB. [2024-08-15T07:12:09.441Z] ====== log-regression (apache-spark) [default], iteration 15 completed (9324.833 ms) ====== [2024-08-15T07:12:09.441Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-15T07:12:09.441Z] GC before operation: completed in 599.235 ms, heap usage 243.177 MB -> 112.259 MB. [2024-08-15T07:12:18.561Z] ====== log-regression (apache-spark) [default], iteration 16 completed (8063.023 ms) ====== [2024-08-15T07:12:18.562Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-15T07:12:18.562Z] GC before operation: completed in 413.934 ms, heap usage 235.524 MB -> 112.391 MB. [2024-08-15T07:12:24.549Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6853.666 ms) ====== [2024-08-15T07:12:24.549Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-15T07:12:25.250Z] GC before operation: completed in 424.286 ms, heap usage 225.993 MB -> 112.580 MB. [2024-08-15T07:12:31.353Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6194.341 ms) ====== [2024-08-15T07:12:31.353Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-15T07:12:32.030Z] GC before operation: completed in 557.158 ms, heap usage 308.183 MB -> 112.925 MB. [2024-08-15T07:12:39.917Z] ====== log-regression (apache-spark) [default], iteration 19 completed (7989.597 ms) ====== [2024-08-15T07:12:40.647Z] ----------------------------------- [2024-08-15T07:12:40.647Z] renaissance-log-regression_0_PASSED [2024-08-15T07:12:40.647Z] ----------------------------------- [2024-08-15T07:12:40.647Z] [2024-08-15T07:12:40.647Z] TEST TEARDOWN: [2024-08-15T07:12:40.647Z] Nothing to be done for teardown. [2024-08-15T07:12:40.647Z] renaissance-log-regression_0 Finish Time: Thu Aug 15 07:12:40 2024 Epoch Time (ms): 1723705960562