[2025-03-06T00:49:12.714Z] Running test renaissance-log-regression_0 ... [2025-03-06T00:49:12.714Z] =============================================== [2025-03-06T00:49:12.714Z] renaissance-log-regression_0 Start Time: Thu Mar 6 00:49:12 2025 Epoch Time (ms): 1741222152069 [2025-03-06T00:49:12.714Z] variation: NoOptions [2025-03-06T00:49:12.714Z] JVM_OPTIONS: [2025-03-06T00:49:12.714Z] { \ [2025-03-06T00:49:12.714Z] echo ""; echo "TEST SETUP:"; \ [2025-03-06T00:49:12.714Z] echo "Nothing to be done for setup."; \ [2025-03-06T00:49:12.714Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17412198715947/renaissance-log-regression_0"; \ [2025-03-06T00:49:12.714Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17412198715947/renaissance-log-regression_0"; \ [2025-03-06T00:49:12.714Z] echo ""; echo "TESTING:"; \ [2025-03-06T00:49:12.714Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17412198715947/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-03-06T00:49:12.714Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17412198715947/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-06T00:49:12.714Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-06T00:49:12.714Z] echo "Nothing to be done for teardown."; \ [2025-03-06T00:49:12.714Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17412198715947/TestTargetResult"; [2025-03-06T00:49:12.714Z] [2025-03-06T00:49:12.714Z] TEST SETUP: [2025-03-06T00:49:12.714Z] Nothing to be done for setup. [2025-03-06T00:49:12.714Z] [2025-03-06T00:49:12.714Z] TESTING: [2025-03-06T00:49:20.566Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-03-06T00:49:25.793Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-03-06T00:49:27.141Z] WARNING: An illegal reflective access operation has occurred [2025-03-06T00:49:27.141Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17412198715947/renaissance-log-regression_0/harness-004912-1877080987265310961/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-03-06T00:49:27.141Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-06T00:49:27.141Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-06T00:49:27.141Z] WARNING: All illegal access operations will be denied in a future release [2025-03-06T00:49:30.232Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-03-06T00:49:30.233Z] GC before operation: completed in 99.494 ms, heap usage 57.639 MB -> 33.729 MB. [2025-03-06T00:49:56.060Z] 25/03/06 00:49:53 WARN BlockManager: Asked to remove block broadcast_28, which does not exist [2025-03-06T00:49:57.591Z] ====== log-regression (apache-spark) [default], iteration 0 completed (26764.912 ms) ====== [2025-03-06T00:49:57.591Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-03-06T00:49:57.591Z] GC before operation: completed in 276.385 ms, heap usage 290.942 MB -> 109.323 MB. [2025-03-06T00:50:10.531Z] ====== log-regression (apache-spark) [default], iteration 1 completed (12465.431 ms) ====== [2025-03-06T00:50:10.531Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-03-06T00:50:10.531Z] GC before operation: completed in 296.403 ms, heap usage 251.520 MB -> 109.645 MB. [2025-03-06T00:50:19.929Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8642.684 ms) ====== [2025-03-06T00:50:19.929Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-03-06T00:50:19.929Z] GC before operation: completed in 650.590 ms, heap usage 252.918 MB -> 110.027 MB. [2025-03-06T00:50:29.342Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8811.557 ms) ====== [2025-03-06T00:50:29.342Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-03-06T00:50:29.342Z] GC before operation: completed in 228.033 ms, heap usage 297.181 MB -> 110.451 MB. [2025-03-06T00:50:37.279Z] ====== log-regression (apache-spark) [default], iteration 4 completed (8867.274 ms) ====== [2025-03-06T00:50:37.279Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-03-06T00:50:37.961Z] GC before operation: completed in 140.887 ms, heap usage 235.722 MB -> 110.449 MB. [2025-03-06T00:50:48.106Z] ====== log-regression (apache-spark) [default], iteration 5 completed (9802.778 ms) ====== [2025-03-06T00:50:48.106Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-03-06T00:50:48.106Z] GC before operation: completed in 284.417 ms, heap usage 270.361 MB -> 110.728 MB. [2025-03-06T00:50:56.164Z] ====== log-regression (apache-spark) [default], iteration 6 completed (8303.670 ms) ====== [2025-03-06T00:50:56.164Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-03-06T00:50:56.164Z] GC before operation: completed in 390.657 ms, heap usage 342.502 MB -> 111.087 MB. [2025-03-06T00:51:04.588Z] ====== log-regression (apache-spark) [default], iteration 7 completed (7093.685 ms) ====== [2025-03-06T00:51:04.588Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-03-06T00:51:04.588Z] GC before operation: completed in 170.320 ms, heap usage 241.972 MB -> 111.039 MB. [2025-03-06T00:51:15.350Z] ====== log-regression (apache-spark) [default], iteration 8 completed (11859.262 ms) ====== [2025-03-06T00:51:15.350Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-03-06T00:51:16.309Z] GC before operation: completed in 968.488 ms, heap usage 271.135 MB -> 111.262 MB. [2025-03-06T00:51:29.120Z] ====== log-regression (apache-spark) [default], iteration 9 completed (11577.387 ms) ====== [2025-03-06T00:51:29.120Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-03-06T00:51:29.120Z] GC before operation: completed in 752.011 ms, heap usage 243.333 MB -> 111.381 MB. [2025-03-06T00:51:38.899Z] ====== log-regression (apache-spark) [default], iteration 10 completed (9674.707 ms) ====== [2025-03-06T00:51:38.899Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-03-06T00:51:40.014Z] GC before operation: completed in 688.147 ms, heap usage 270.633 MB -> 111.594 MB. [2025-03-06T00:51:46.259Z] 25/03/06 00:51:44 WARN BlockManager: Asked to remove block broadcast_525, which does not exist [2025-03-06T00:51:52.273Z] ====== log-regression (apache-spark) [default], iteration 11 completed (13294.297 ms) ====== [2025-03-06T00:51:52.273Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-03-06T00:51:52.973Z] GC before operation: completed in 156.410 ms, heap usage 259.731 MB -> 111.764 MB. [2025-03-06T00:51:57.175Z] 25/03/06 00:51:56 WARN BlockManager: Asked to remove block broadcast_586, which does not exist [2025-03-06T00:51:58.766Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5961.270 ms) ====== [2025-03-06T00:51:58.766Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-03-06T00:51:58.766Z] GC before operation: completed in 192.727 ms, heap usage 306.570 MB -> 112.040 MB. [2025-03-06T00:52:05.184Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6509.340 ms) ====== [2025-03-06T00:52:05.184Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-03-06T00:52:05.830Z] GC before operation: completed in 352.645 ms, heap usage 235.460 MB -> 112.018 MB. [2025-03-06T00:52:15.647Z] ====== log-regression (apache-spark) [default], iteration 14 completed (8333.650 ms) ====== [2025-03-06T00:52:15.647Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-03-06T00:52:15.647Z] GC before operation: completed in 583.331 ms, heap usage 235.551 MB -> 112.194 MB. [2025-03-06T00:52:19.386Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4491.244 ms) ====== [2025-03-06T00:52:19.386Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-03-06T00:52:20.055Z] GC before operation: completed in 636.106 ms, heap usage 289.708 MB -> 112.580 MB. [2025-03-06T00:52:28.427Z] ====== log-regression (apache-spark) [default], iteration 16 completed (7784.912 ms) ====== [2025-03-06T00:52:28.427Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-03-06T00:52:28.427Z] GC before operation: completed in 247.450 ms, heap usage 242.919 MB -> 112.629 MB. [2025-03-06T00:52:36.992Z] ====== log-regression (apache-spark) [default], iteration 17 completed (8960.853 ms) ====== [2025-03-06T00:52:36.992Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-03-06T00:52:36.992Z] GC before operation: completed in 403.614 ms, heap usage 255.402 MB -> 112.794 MB. [2025-03-06T00:52:47.818Z] ====== log-regression (apache-spark) [default], iteration 18 completed (9705.723 ms) ====== [2025-03-06T00:52:47.818Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-03-06T00:52:47.818Z] GC before operation: completed in 258.085 ms, heap usage 261.474 MB -> 112.998 MB. [2025-03-06T00:52:51.938Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4911.806 ms) ====== [2025-03-06T00:52:52.581Z] ----------------------------------- [2025-03-06T00:52:52.581Z] renaissance-log-regression_0_PASSED [2025-03-06T00:52:52.581Z] ----------------------------------- [2025-03-06T00:52:52.581Z] [2025-03-06T00:52:52.581Z] TEST TEARDOWN: [2025-03-06T00:52:52.581Z] Nothing to be done for teardown. [2025-03-06T00:52:52.581Z] renaissance-log-regression_0 Finish Time: Thu Mar 6 00:52:52 2025 Epoch Time (ms): 1741222372131