[2024-08-10T07:59:29.719Z] Running test renaissance-log-regression_0 ... [2024-08-10T07:59:29.719Z] =============================================== [2024-08-10T07:59:29.719Z] renaissance-log-regression_0 Start Time: Sat Aug 10 07:59:29 2024 Epoch Time (ms): 1723276769402 [2024-08-10T07:59:29.719Z] variation: NoOptions [2024-08-10T07:59:29.719Z] JVM_OPTIONS: [2024-08-10T07:59:29.719Z] { \ [2024-08-10T07:59:29.719Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T07:59:29.719Z] echo "Nothing to be done for setup."; \ [2024-08-10T07:59:29.719Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232755595588/renaissance-log-regression_0"; \ [2024-08-10T07:59:29.719Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232755595588/renaissance-log-regression_0"; \ [2024-08-10T07:59:29.719Z] echo ""; echo "TESTING:"; \ [2024-08-10T07:59:29.720Z] "/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_17232755595588/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-10T07:59:29.720Z] 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_17232755595588/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T07:59:29.720Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T07:59:29.720Z] echo "Nothing to be done for teardown."; \ [2024-08-10T07:59:29.720Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232755595588/TestTargetResult"; [2024-08-10T07:59:29.720Z] [2024-08-10T07:59:29.720Z] TEST SETUP: [2024-08-10T07:59:29.720Z] Nothing to be done for setup. [2024-08-10T07:59:29.720Z] [2024-08-10T07:59:29.720Z] TESTING: [2024-08-10T07:59:34.415Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T07:59:35.707Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-10T07:59:37.821Z] WARNING: An illegal reflective access operation has occurred [2024-08-10T07:59:37.821Z] 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_17232755595588/renaissance-log-regression_0/harness-075930-6797596420488828985/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-10T07:59:37.821Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-10T07:59:37.821Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-10T07:59:37.821Z] WARNING: All illegal access operations will be denied in a future release [2024-08-10T07:59:40.770Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-10T07:59:41.447Z] GC before operation: completed in 111.112 ms, heap usage 75.884 MB -> 33.560 MB. [2024-08-10T07:59:59.757Z] ====== log-regression (apache-spark) [default], iteration 0 completed (16366.951 ms) ====== [2024-08-10T07:59:59.757Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-10T07:59:59.757Z] GC before operation: completed in 148.701 ms, heap usage 264.556 MB -> 109.051 MB. [2024-08-10T08:00:03.681Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6037.503 ms) ====== [2024-08-10T08:00:03.681Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-10T08:00:03.681Z] GC before operation: completed in 278.606 ms, heap usage 291.953 MB -> 109.565 MB. [2024-08-10T08:00:08.523Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4464.543 ms) ====== [2024-08-10T08:00:08.523Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-10T08:00:08.523Z] GC before operation: completed in 200.367 ms, heap usage 236.667 MB -> 109.809 MB. [2024-08-10T08:00:14.626Z] ====== log-regression (apache-spark) [default], iteration 3 completed (5679.024 ms) ====== [2024-08-10T08:00:14.626Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-10T08:00:14.626Z] GC before operation: completed in 189.215 ms, heap usage 254.653 MB -> 110.140 MB. [2024-08-10T08:00:17.619Z] 24/08/10 08:00:17 WARN BlockManager: Asked to remove block broadcast_212, which does not exist [2024-08-10T08:00:19.619Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5063.944 ms) ====== [2024-08-10T08:00:19.619Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-10T08:00:19.619Z] GC before operation: completed in 315.465 ms, heap usage 236.464 MB -> 110.272 MB. [2024-08-10T08:00:25.521Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5008.114 ms) ====== [2024-08-10T08:00:25.521Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-10T08:00:25.521Z] GC before operation: completed in 273.881 ms, heap usage 271.935 MB -> 110.542 MB. [2024-08-10T08:00:31.489Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5901.655 ms) ====== [2024-08-10T08:00:31.489Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-10T08:00:31.489Z] GC before operation: completed in 181.390 ms, heap usage 288.641 MB -> 110.828 MB. [2024-08-10T08:00:35.447Z] 24/08/10 08:00:35 WARN BlockManager: Asked to remove block broadcast_357, which does not exist [2024-08-10T08:00:36.824Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5698.965 ms) ====== [2024-08-10T08:00:36.824Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-10T08:00:37.487Z] GC before operation: completed in 249.175 ms, heap usage 288.401 MB -> 110.950 MB. [2024-08-10T08:00:44.508Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7520.179 ms) ====== [2024-08-10T08:00:44.508Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-10T08:00:45.284Z] GC before operation: completed in 290.735 ms, heap usage 261.158 MB -> 111.079 MB. [2024-08-10T08:00:51.675Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6082.148 ms) ====== [2024-08-10T08:00:51.676Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-10T08:00:51.676Z] GC before operation: completed in 220.694 ms, heap usage 259.869 MB -> 111.254 MB. [2024-08-10T08:00:56.838Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5370.333 ms) ====== [2024-08-10T08:00:56.838Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-10T08:00:56.838Z] GC before operation: completed in 319.999 ms, heap usage 224.893 MB -> 111.340 MB. [2024-08-10T08:01:00.717Z] 24/08/10 08:01:00 WARN BlockManager: Asked to remove block broadcast_539, which does not exist [2024-08-10T08:01:02.043Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5012.987 ms) ====== [2024-08-10T08:01:02.043Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-10T08:01:02.043Z] GC before operation: completed in 244.309 ms, heap usage 308.305 MB -> 111.697 MB. [2024-08-10T08:01:07.175Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5003.262 ms) ====== [2024-08-10T08:01:07.175Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-10T08:01:07.806Z] GC before operation: completed in 248.355 ms, heap usage 259.880 MB -> 111.769 MB. [2024-08-10T08:01:11.536Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3582.059 ms) ====== [2024-08-10T08:01:11.536Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-10T08:01:11.536Z] GC before operation: completed in 136.161 ms, heap usage 254.693 MB -> 111.880 MB. [2024-08-10T08:01:14.369Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3074.977 ms) ====== [2024-08-10T08:01:14.369Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-10T08:01:14.369Z] GC before operation: completed in 183.095 ms, heap usage 307.783 MB -> 112.203 MB. [2024-08-10T08:01:20.317Z] ====== log-regression (apache-spark) [default], iteration 15 completed (5083.016 ms) ====== [2024-08-10T08:01:20.317Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-10T08:01:20.317Z] GC before operation: completed in 166.151 ms, heap usage 253.780 MB -> 112.260 MB. [2024-08-10T08:01:24.157Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4045.880 ms) ====== [2024-08-10T08:01:24.157Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-10T08:01:24.157Z] GC before operation: completed in 198.655 ms, heap usage 271.118 MB -> 112.490 MB. [2024-08-10T08:01:27.058Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3298.102 ms) ====== [2024-08-10T08:01:27.058Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-10T08:01:27.854Z] GC before operation: completed in 244.776 ms, heap usage 243.668 MB -> 112.613 MB. [2024-08-10T08:01:31.701Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4257.954 ms) ====== [2024-08-10T08:01:31.701Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-10T08:01:32.322Z] GC before operation: completed in 290.739 ms, heap usage 271.421 MB -> 112.815 MB. [2024-08-10T08:01:36.174Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3694.727 ms) ====== [2024-08-10T08:01:36.174Z] ----------------------------------- [2024-08-10T08:01:36.174Z] renaissance-log-regression_0_PASSED [2024-08-10T08:01:36.174Z] ----------------------------------- [2024-08-10T08:01:36.174Z] [2024-08-10T08:01:36.174Z] TEST TEARDOWN: [2024-08-10T08:01:36.174Z] Nothing to be done for teardown. [2024-08-10T08:01:36.174Z] renaissance-log-regression_0 Finish Time: Sat Aug 10 08:01:35 2024 Epoch Time (ms): 1723276895992