[2025-02-06T07:09:49.577Z] Running test renaissance-log-regression_0 ... [2025-02-06T07:09:49.577Z] =============================================== [2025-02-06T07:09:49.577Z] renaissance-log-regression_0 Start Time: Thu Feb 6 02:09:48 2025 Epoch Time (ms): 1738825788863 [2025-02-06T07:09:49.577Z] variation: NoOptions [2025-02-06T07:09:49.577Z] JVM_OPTIONS: [2025-02-06T07:09:49.577Z] { \ [2025-02-06T07:09:49.577Z] echo ""; echo "TEST SETUP:"; \ [2025-02-06T07:09:49.577Z] echo "Nothing to be done for setup."; \ [2025-02-06T07:09:49.577Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17388242733326/renaissance-log-regression_0"; \ [2025-02-06T07:09:49.577Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17388242733326/renaissance-log-regression_0"; \ [2025-02-06T07:09:49.577Z] echo ""; echo "TESTING:"; \ [2025-02-06T07:09:49.577Z] "/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_17388242733326/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-06T07:09:49.577Z] 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_17388242733326/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-06T07:09:49.577Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-06T07:09:49.577Z] echo "Nothing to be done for teardown."; \ [2025-02-06T07:09:49.577Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17388242733326/TestTargetResult"; [2025-02-06T07:09:49.577Z] [2025-02-06T07:09:49.577Z] TEST SETUP: [2025-02-06T07:09:49.577Z] Nothing to be done for setup. [2025-02-06T07:09:49.577Z] [2025-02-06T07:09:49.577Z] TESTING: [2025-02-06T07:09:53.147Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-06T07:09:56.173Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-02-06T07:09:56.836Z] WARNING: An illegal reflective access operation has occurred [2025-02-06T07:09:56.836Z] 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_17388242733326/renaissance-log-regression_0/harness-020949-16193583813731844795/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-06T07:09:56.836Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-06T07:09:56.836Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-06T07:09:56.836Z] WARNING: All illegal access operations will be denied in a future release [2025-02-06T07:10:00.009Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-06T07:10:00.009Z] GC before operation: completed in 176.692 ms, heap usage 115.259 MB -> 33.768 MB. [2025-02-06T07:10:18.024Z] 25/02/06 02:10:15 WARN BlockManager: Asked to remove block broadcast_34, which does not exist [2025-02-06T07:10:18.024Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17504.499 ms) ====== [2025-02-06T07:10:18.024Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-06T07:10:18.024Z] GC before operation: completed in 289.921 ms, heap usage 327.740 MB -> 109.399 MB. [2025-02-06T07:10:24.512Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6831.739 ms) ====== [2025-02-06T07:10:24.512Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-06T07:10:24.512Z] GC before operation: completed in 153.579 ms, heap usage 234.428 MB -> 109.613 MB. [2025-02-06T07:10:31.047Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5507.207 ms) ====== [2025-02-06T07:10:31.047Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-06T07:10:31.047Z] GC before operation: completed in 259.988 ms, heap usage 224.805 MB -> 109.976 MB. [2025-02-06T07:10:37.522Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6320.518 ms) ====== [2025-02-06T07:10:37.522Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-06T07:10:37.522Z] GC before operation: completed in 710.615 ms, heap usage 298.535 MB -> 110.458 MB. [2025-02-06T07:10:45.571Z] ====== log-regression (apache-spark) [default], iteration 4 completed (7291.964 ms) ====== [2025-02-06T07:10:45.571Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-06T07:10:45.571Z] GC before operation: completed in 327.664 ms, heap usage 243.069 MB -> 110.502 MB. [2025-02-06T07:10:52.391Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6169.683 ms) ====== [2025-02-06T07:10:52.391Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-06T07:10:52.391Z] GC before operation: completed in 424.877 ms, heap usage 260.794 MB -> 110.729 MB. [2025-02-06T07:10:58.298Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6273.331 ms) ====== [2025-02-06T07:10:58.298Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-06T07:10:58.298Z] GC before operation: completed in 134.277 ms, heap usage 270.192 MB -> 110.912 MB. [2025-02-06T07:11:03.597Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5076.333 ms) ====== [2025-02-06T07:11:03.597Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-06T07:11:03.597Z] GC before operation: completed in 197.960 ms, heap usage 252.889 MB -> 111.039 MB. [2025-02-06T07:11:08.920Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5623.589 ms) ====== [2025-02-06T07:11:08.920Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-06T07:11:09.649Z] GC before operation: completed in 318.690 ms, heap usage 233.947 MB -> 111.181 MB. [2025-02-06T07:11:14.928Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5390.259 ms) ====== [2025-02-06T07:11:14.928Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-06T07:11:14.928Z] GC before operation: completed in 377.263 ms, heap usage 253.144 MB -> 111.391 MB. [2025-02-06T07:11:20.451Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5150.971 ms) ====== [2025-02-06T07:11:20.451Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-06T07:11:20.451Z] GC before operation: completed in 208.395 ms, heap usage 270.516 MB -> 111.609 MB. [2025-02-06T07:11:25.585Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5231.137 ms) ====== [2025-02-06T07:11:25.585Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-06T07:11:26.331Z] GC before operation: completed in 180.894 ms, heap usage 261.292 MB -> 111.793 MB. [2025-02-06T07:11:31.701Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4980.971 ms) ====== [2025-02-06T07:11:31.701Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-06T07:11:31.701Z] GC before operation: completed in 213.092 ms, heap usage 288.968 MB -> 111.997 MB. [2025-02-06T07:11:36.042Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4497.705 ms) ====== [2025-02-06T07:11:36.042Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-06T07:11:36.042Z] GC before operation: completed in 456.209 ms, heap usage 271.613 MB -> 112.125 MB. [2025-02-06T07:11:41.167Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4806.312 ms) ====== [2025-02-06T07:11:41.167Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-06T07:11:41.167Z] GC before operation: completed in 271.064 ms, heap usage 243.281 MB -> 112.238 MB. [2025-02-06T07:11:48.459Z] ====== log-regression (apache-spark) [default], iteration 15 completed (5786.399 ms) ====== [2025-02-06T07:11:48.459Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-06T07:11:48.459Z] GC before operation: completed in 204.269 ms, heap usage 271.175 MB -> 112.502 MB. [2025-02-06T07:11:52.667Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5386.888 ms) ====== [2025-02-06T07:11:52.667Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-06T07:11:52.667Z] GC before operation: completed in 136.176 ms, heap usage 272.544 MB -> 112.678 MB. [2025-02-06T07:12:00.361Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6667.071 ms) ====== [2025-02-06T07:12:00.361Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-06T07:12:00.361Z] GC before operation: completed in 338.743 ms, heap usage 235.884 MB -> 112.787 MB. [2025-02-06T07:12:04.529Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4779.613 ms) ====== [2025-02-06T07:12:04.529Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-06T07:12:05.328Z] GC before operation: completed in 362.125 ms, heap usage 243.427 MB -> 112.995 MB. [2025-02-06T07:12:10.597Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5595.230 ms) ====== [2025-02-06T07:12:11.240Z] ----------------------------------- [2025-02-06T07:12:11.240Z] renaissance-log-regression_0_PASSED [2025-02-06T07:12:11.240Z] ----------------------------------- [2025-02-06T07:12:11.240Z] [2025-02-06T07:12:11.240Z] TEST TEARDOWN: [2025-02-06T07:12:11.240Z] Nothing to be done for teardown. [2025-02-06T07:12:11.240Z] renaissance-log-regression_0 Finish Time: Thu Feb 6 02:12:11 2025 Epoch Time (ms): 1738825931170