[2024-09-26T05:00:22.159Z] Running test renaissance-log-regression_0 ... [2024-09-26T05:00:22.159Z] =============================================== [2024-09-26T05:00:22.159Z] renaissance-log-regression_0 Start Time: Thu Sep 26 05:00:21 2024 Epoch Time (ms): 1727326821705 [2024-09-26T05:00:22.159Z] variation: NoOptions [2024-09-26T05:00:22.159Z] JVM_OPTIONS: [2024-09-26T05:00:22.159Z] { \ [2024-09-26T05:00:22.159Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T05:00:22.159Z] echo "Nothing to be done for setup."; \ [2024-09-26T05:00:22.159Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273249167590/renaissance-log-regression_0"; \ [2024-09-26T05:00:22.159Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273249167590/renaissance-log-regression_0"; \ [2024-09-26T05:00:22.159Z] echo ""; echo "TESTING:"; \ [2024-09-26T05:00:22.159Z] "/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_17273249167590/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-26T05:00:22.159Z] 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_17273249167590/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T05:00:22.159Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T05:00:22.159Z] echo "Nothing to be done for teardown."; \ [2024-09-26T05:00:22.159Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273249167590/TestTargetResult"; [2024-09-26T05:00:22.159Z] [2024-09-26T05:00:22.159Z] TEST SETUP: [2024-09-26T05:00:22.159Z] Nothing to be done for setup. [2024-09-26T05:00:22.159Z] [2024-09-26T05:00:22.159Z] TESTING: [2024-09-26T05:00:28.520Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T05:00:34.264Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-09-26T05:00:36.797Z] WARNING: An illegal reflective access operation has occurred [2024-09-26T05:00:36.797Z] 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_17273249167590/renaissance-log-regression_0/harness-050023-15980171934407517762/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-26T05:00:36.797Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-26T05:00:36.797Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-26T05:00:36.797Z] WARNING: All illegal access operations will be denied in a future release [2024-09-26T05:00:44.298Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-26T05:00:44.298Z] GC before operation: completed in 196.020 ms, heap usage 59.382 MB -> 33.726 MB. [2024-09-26T05:01:04.839Z] 24/09/26 05:01:02 WARN BlockManager: Asked to remove block broadcast_38_piece0, which does not exist [2024-09-26T05:01:04.839Z] 24/09/26 05:01:02 WARN BlockManager: Asked to remove block broadcast_38, which does not exist [2024-09-26T05:01:08.121Z] ====== log-regression (apache-spark) [default], iteration 0 completed (23483.142 ms) ====== [2024-09-26T05:01:08.121Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-26T05:01:08.900Z] GC before operation: completed in 834.752 ms, heap usage 263.039 MB -> 109.232 MB. [2024-09-26T05:01:28.910Z] ====== log-regression (apache-spark) [default], iteration 1 completed (19029.871 ms) ====== [2024-09-26T05:01:28.910Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-26T05:01:28.910Z] GC before operation: completed in 1333.200 ms, heap usage 278.178 MB -> 109.741 MB. [2024-09-26T05:01:39.743Z] ====== log-regression (apache-spark) [default], iteration 2 completed (11198.538 ms) ====== [2024-09-26T05:01:39.743Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-26T05:01:41.142Z] GC before operation: completed in 762.606 ms, heap usage 292.756 MB -> 110.168 MB. [2024-09-26T05:01:54.393Z] ====== log-regression (apache-spark) [default], iteration 3 completed (13691.802 ms) ====== [2024-09-26T05:01:54.393Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-26T05:01:55.077Z] GC before operation: completed in 632.772 ms, heap usage 258.784 MB -> 110.367 MB. [2024-09-26T05:02:07.980Z] ====== log-regression (apache-spark) [default], iteration 4 completed (11001.499 ms) ====== [2024-09-26T05:02:07.980Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-26T05:02:07.980Z] GC before operation: completed in 1545.601 ms, heap usage 243.312 MB -> 110.509 MB. [2024-09-26T05:02:27.952Z] ====== log-regression (apache-spark) [default], iteration 5 completed (18135.375 ms) ====== [2024-09-26T05:02:27.952Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-26T05:02:27.952Z] GC before operation: completed in 601.689 ms, heap usage 234.188 MB -> 110.631 MB. [2024-09-26T05:02:38.143Z] ====== log-regression (apache-spark) [default], iteration 6 completed (11727.114 ms) ====== [2024-09-26T05:02:38.143Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-26T05:02:38.783Z] GC before operation: completed in 423.093 ms, heap usage 234.842 MB -> 110.825 MB. [2024-09-26T05:02:50.100Z] ====== log-regression (apache-spark) [default], iteration 7 completed (10475.108 ms) ====== [2024-09-26T05:02:50.100Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-26T05:02:50.100Z] GC before operation: completed in 758.677 ms, heap usage 235.485 MB -> 110.999 MB. [2024-09-26T05:02:58.354Z] ====== log-regression (apache-spark) [default], iteration 8 completed (8733.571 ms) ====== [2024-09-26T05:02:58.354Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-26T05:02:58.986Z] GC before operation: completed in 284.801 ms, heap usage 224.528 MB -> 111.182 MB. [2024-09-26T05:03:06.344Z] ====== log-regression (apache-spark) [default], iteration 9 completed (7028.425 ms) ====== [2024-09-26T05:03:06.345Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-26T05:03:06.345Z] GC before operation: completed in 382.350 ms, heap usage 253.367 MB -> 111.389 MB. [2024-09-26T05:03:12.323Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5431.920 ms) ====== [2024-09-26T05:03:12.323Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-26T05:03:12.323Z] GC before operation: completed in 358.105 ms, heap usage 235.320 MB -> 111.549 MB. [2024-09-26T05:03:17.103Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4605.083 ms) ====== [2024-09-26T05:03:17.103Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-26T05:03:17.103Z] GC before operation: completed in 363.561 ms, heap usage 243.041 MB -> 111.770 MB. [2024-09-26T05:03:23.058Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5545.292 ms) ====== [2024-09-26T05:03:23.058Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-26T05:03:23.058Z] GC before operation: completed in 276.300 ms, heap usage 270.449 MB -> 111.987 MB. [2024-09-26T05:03:30.960Z] ====== log-regression (apache-spark) [default], iteration 13 completed (7483.204 ms) ====== [2024-09-26T05:03:30.960Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-26T05:03:30.960Z] GC before operation: completed in 507.186 ms, heap usage 227.131 MB -> 112.054 MB. [2024-09-26T05:03:41.165Z] ====== log-regression (apache-spark) [default], iteration 14 completed (10189.019 ms) ====== [2024-09-26T05:03:41.165Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-26T05:03:41.165Z] GC before operation: completed in 472.991 ms, heap usage 252.518 MB -> 112.263 MB. [2024-09-26T05:03:48.628Z] ====== log-regression (apache-spark) [default], iteration 15 completed (7386.606 ms) ====== [2024-09-26T05:03:48.628Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-26T05:03:49.237Z] GC before operation: completed in 200.753 ms, heap usage 325.039 MB -> 112.658 MB. [2024-09-26T05:03:56.750Z] ====== log-regression (apache-spark) [default], iteration 16 completed (7007.714 ms) ====== [2024-09-26T05:03:56.750Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-26T05:03:56.750Z] GC before operation: completed in 348.591 ms, heap usage 297.672 MB -> 112.780 MB. [2024-09-26T05:04:04.517Z] ====== log-regression (apache-spark) [default], iteration 17 completed (7636.134 ms) ====== [2024-09-26T05:04:04.517Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-26T05:04:04.517Z] GC before operation: completed in 294.280 ms, heap usage 289.138 MB -> 112.907 MB. [2024-09-26T05:04:12.683Z] ====== log-regression (apache-spark) [default], iteration 18 completed (7938.704 ms) ====== [2024-09-26T05:04:12.683Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-26T05:04:12.683Z] GC before operation: completed in 457.321 ms, heap usage 253.666 MB -> 112.978 MB. [2024-09-26T05:04:18.830Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6300.264 ms) ====== [2024-09-26T05:04:20.136Z] ----------------------------------- [2024-09-26T05:04:20.137Z] renaissance-log-regression_0_PASSED [2024-09-26T05:04:20.137Z] ----------------------------------- [2024-09-26T05:04:20.137Z] [2024-09-26T05:04:20.137Z] TEST TEARDOWN: [2024-09-26T05:04:20.137Z] Nothing to be done for teardown. [2024-09-26T05:04:20.137Z] renaissance-log-regression_0 Finish Time: Thu Sep 26 05:04:19 2024 Epoch Time (ms): 1727327059499