[2025-05-07T14:17:34.186Z] Running test renaissance-log-regression_0 ... [2025-05-07T14:17:34.186Z] =============================================== [2025-05-07T14:17:34.186Z] renaissance-log-regression_0 Start Time: Wed May 7 10:17:34 2025 Epoch Time (ms): 1746627454084 [2025-05-07T14:17:34.186Z] variation: NoOptions [2025-05-07T14:17:34.186Z] JVM_OPTIONS: [2025-05-07T14:17:34.186Z] { \ [2025-05-07T14:17:34.186Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T14:17:34.186Z] echo "Nothing to be done for setup."; \ [2025-05-07T14:17:34.186Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466219193988/renaissance-log-regression_0"; \ [2025-05-07T14:17:34.186Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466219193988/renaissance-log-regression_0"; \ [2025-05-07T14:17:34.186Z] echo ""; echo "TESTING:"; \ [2025-05-07T14:17:34.186Z] "/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_17466219193988/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-07T14:17:34.186Z] 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_17466219193988/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T14:17:34.186Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T14:17:34.186Z] echo "Nothing to be done for teardown."; \ [2025-05-07T14:17:34.186Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466219193988/TestTargetResult"; [2025-05-07T14:17:34.186Z] [2025-05-07T14:17:34.186Z] TEST SETUP: [2025-05-07T14:17:34.186Z] Nothing to be done for setup. [2025-05-07T14:17:34.186Z] [2025-05-07T14:17:34.186Z] TESTING: [2025-05-07T14:17:48.865Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-07T14:17:50.948Z] WARNING: An illegal reflective access operation has occurred [2025-05-07T14:17:50.948Z] 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_17466219193988/renaissance-log-regression_0/harness-101737-2797626374676629601/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-07T14:17:50.948Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-07T14:17:50.948Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-07T14:17:50.948Z] WARNING: All illegal access operations will be denied in a future release [2025-05-07T14:17:52.627Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-07T14:17:52.627Z] GC before operation: completed in 80.248 ms, heap usage 51.314 MB -> 34.387 MB. [2025-05-07T14:18:34.692Z] 10:18:30.373 WARN [block-manager-storage-async-thread-pool-61] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_43_piece0, which does not exist [2025-05-07T14:18:34.692Z] ====== log-regression (apache-spark) [default], iteration 0 completed (39858.600 ms) ====== [2025-05-07T14:18:34.692Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-07T14:18:34.692Z] GC before operation: completed in 386.148 ms, heap usage 280.091 MB -> 115.670 MB. [2025-05-07T14:18:41.683Z] ====== log-regression (apache-spark) [default], iteration 1 completed (8774.685 ms) ====== [2025-05-07T14:18:41.683Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-07T14:18:41.683Z] GC before operation: completed in 228.656 ms, heap usage 268.090 MB -> 116.129 MB. [2025-05-07T14:18:49.724Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7707.013 ms) ====== [2025-05-07T14:18:49.724Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-07T14:18:49.724Z] GC before operation: completed in 350.909 ms, heap usage 270.232 MB -> 116.442 MB. [2025-05-07T14:18:56.544Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6985.371 ms) ====== [2025-05-07T14:18:56.544Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-07T14:18:57.238Z] GC before operation: completed in 299.939 ms, heap usage 269.372 MB -> 116.814 MB. [2025-05-07T14:19:03.754Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6264.734 ms) ====== [2025-05-07T14:19:03.754Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-07T14:19:03.754Z] GC before operation: completed in 209.860 ms, heap usage 258.725 MB -> 116.921 MB. [2025-05-07T14:19:10.969Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6333.613 ms) ====== [2025-05-07T14:19:10.969Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-07T14:19:10.969Z] GC before operation: completed in 254.435 ms, heap usage 289.076 MB -> 117.205 MB. [2025-05-07T14:19:15.273Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5001.702 ms) ====== [2025-05-07T14:19:15.273Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-07T14:19:15.273Z] GC before operation: completed in 178.773 ms, heap usage 280.028 MB -> 117.379 MB. [2025-05-07T14:19:20.694Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5476.730 ms) ====== [2025-05-07T14:19:20.694Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-07T14:19:20.694Z] GC before operation: completed in 264.859 ms, heap usage 313.728 MB -> 117.648 MB. [2025-05-07T14:19:31.285Z] ====== log-regression (apache-spark) [default], iteration 8 completed (9467.307 ms) ====== [2025-05-07T14:19:31.285Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-07T14:19:31.285Z] GC before operation: completed in 392.842 ms, heap usage 269.610 MB -> 117.750 MB. [2025-05-07T14:19:36.577Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5439.062 ms) ====== [2025-05-07T14:19:36.577Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-07T14:19:36.577Z] GC before operation: completed in 193.906 ms, heap usage 251.180 MB -> 117.834 MB. [2025-05-07T14:19:41.849Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4828.861 ms) ====== [2025-05-07T14:19:41.849Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-07T14:19:41.849Z] GC before operation: completed in 181.980 ms, heap usage 280.418 MB -> 118.107 MB. [2025-05-07T14:19:48.784Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6645.324 ms) ====== [2025-05-07T14:19:48.784Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-07T14:19:48.784Z] GC before operation: completed in 505.782 ms, heap usage 303.719 MB -> 118.357 MB. [2025-05-07T14:19:57.973Z] ====== log-regression (apache-spark) [default], iteration 12 completed (9203.508 ms) ====== [2025-05-07T14:19:57.973Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-07T14:19:57.973Z] GC before operation: completed in 316.023 ms, heap usage 252.047 MB -> 118.360 MB. [2025-05-07T14:20:10.634Z] ====== log-regression (apache-spark) [default], iteration 13 completed (12410.770 ms) ====== [2025-05-07T14:20:10.634Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-07T14:20:10.634Z] GC before operation: completed in 170.131 ms, heap usage 317.493 MB -> 118.776 MB. [2025-05-07T14:20:18.253Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6870.738 ms) ====== [2025-05-07T14:20:18.253Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-07T14:20:18.253Z] GC before operation: completed in 393.975 ms, heap usage 315.753 MB -> 118.967 MB. [2025-05-07T14:20:26.845Z] ====== log-regression (apache-spark) [default], iteration 15 completed (8268.928 ms) ====== [2025-05-07T14:20:26.845Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-07T14:20:26.845Z] GC before operation: completed in 218.439 ms, heap usage 281.084 MB -> 119.016 MB. [2025-05-07T14:20:42.474Z] ====== log-regression (apache-spark) [default], iteration 16 completed (16336.959 ms) ====== [2025-05-07T14:20:42.474Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-07T14:20:43.197Z] GC before operation: completed in 738.159 ms, heap usage 289.986 MB -> 119.205 MB. [2025-05-07T14:20:48.555Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4461.830 ms) ====== [2025-05-07T14:20:48.555Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-07T14:20:48.555Z] GC before operation: completed in 191.923 ms, heap usage 259.319 MB -> 119.298 MB. [2025-05-07T14:20:53.955Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5662.923 ms) ====== [2025-05-07T14:20:53.955Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-07T14:20:53.955Z] GC before operation: completed in 231.340 ms, heap usage 246.885 MB -> 119.389 MB. [2025-05-07T14:21:01.369Z] ====== log-regression (apache-spark) [default], iteration 19 completed (7247.279 ms) ====== [2025-05-07T14:21:02.776Z] ----------------------------------- [2025-05-07T14:21:02.776Z] renaissance-log-regression_0_PASSED [2025-05-07T14:21:02.776Z] ----------------------------------- [2025-05-07T14:21:02.776Z] [2025-05-07T14:21:02.776Z] TEST TEARDOWN: [2025-05-07T14:21:02.776Z] Nothing to be done for teardown. [2025-05-07T14:21:02.776Z] renaissance-log-regression_0 Finish Time: Wed May 7 10:21:02 2025 Epoch Time (ms): 1746627662641