[2024-11-16T03:30:01.569Z] Running test renaissance-log-regression_0 ... [2024-11-16T03:30:01.569Z] =============================================== [2024-11-16T03:30:01.569Z] renaissance-log-regression_0 Start Time: Fri Nov 15 22:30:00 2024 Epoch Time (ms): 1731727800931 [2024-11-16T03:30:01.569Z] variation: NoOptions [2024-11-16T03:30:01.569Z] JVM_OPTIONS: [2024-11-16T03:30:01.569Z] { \ [2024-11-16T03:30:01.569Z] echo ""; echo "TEST SETUP:"; \ [2024-11-16T03:30:01.569Z] echo "Nothing to be done for setup."; \ [2024-11-16T03:30:01.569Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17317268621572/renaissance-log-regression_0"; \ [2024-11-16T03:30:01.569Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17317268621572/renaissance-log-regression_0"; \ [2024-11-16T03:30:01.569Z] echo ""; echo "TESTING:"; \ [2024-11-16T03:30:01.569Z] "/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_17317268621572/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-16T03:30:01.569Z] 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_17317268621572/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-16T03:30:01.569Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-16T03:30:01.569Z] echo "Nothing to be done for teardown."; \ [2024-11-16T03:30:01.569Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17317268621572/TestTargetResult"; [2024-11-16T03:30:01.569Z] [2024-11-16T03:30:01.569Z] TEST SETUP: [2024-11-16T03:30:01.569Z] Nothing to be done for setup. [2024-11-16T03:30:01.569Z] [2024-11-16T03:30:01.569Z] TESTING: [2024-11-16T03:30:05.502Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-16T03:30:06.869Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-11-16T03:30:08.226Z] WARNING: An illegal reflective access operation has occurred [2024-11-16T03:30:08.226Z] 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_17317268621572/renaissance-log-regression_0/harness-223002-8848315790515779938/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-16T03:30:08.226Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-16T03:30:08.226Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-16T03:30:08.226Z] WARNING: All illegal access operations will be denied in a future release [2024-11-16T03:30:10.356Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-16T03:30:10.356Z] GC before operation: completed in 84.492 ms, heap usage 67.320 MB -> 33.757 MB. [2024-11-16T03:30:25.502Z] ====== log-regression (apache-spark) [default], iteration 0 completed (13158.059 ms) ====== [2024-11-16T03:30:25.503Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-16T03:30:25.503Z] GC before operation: completed in 207.875 ms, heap usage 205.667 MB -> 109.117 MB. [2024-11-16T03:30:27.647Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3560.001 ms) ====== [2024-11-16T03:30:27.647Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-16T03:30:27.648Z] GC before operation: completed in 212.356 ms, heap usage 258.943 MB -> 109.687 MB. [2024-11-16T03:30:31.662Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3598.344 ms) ====== [2024-11-16T03:30:31.662Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-16T03:30:31.662Z] GC before operation: completed in 119.623 ms, heap usage 270.374 MB -> 110.064 MB. [2024-11-16T03:30:34.681Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3411.679 ms) ====== [2024-11-16T03:30:34.681Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-16T03:30:34.681Z] GC before operation: completed in 129.165 ms, heap usage 251.916 MB -> 110.299 MB. [2024-11-16T03:30:36.881Z] 24/11/15 22:30:36 WARN BlockManager: Asked to remove block broadcast_194, which does not exist [2024-11-16T03:30:39.061Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3910.109 ms) ====== [2024-11-16T03:30:39.061Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-16T03:30:39.061Z] GC before operation: completed in 190.443 ms, heap usage 269.586 MB -> 110.531 MB. [2024-11-16T03:30:42.971Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3656.380 ms) ====== [2024-11-16T03:30:42.971Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-16T03:30:42.971Z] GC before operation: completed in 159.029 ms, heap usage 234.467 MB -> 110.611 MB. [2024-11-16T03:30:46.932Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4112.802 ms) ====== [2024-11-16T03:30:46.932Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-16T03:30:46.932Z] GC before operation: completed in 132.522 ms, heap usage 233.903 MB -> 110.795 MB. [2024-11-16T03:30:51.032Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3812.074 ms) ====== [2024-11-16T03:30:51.032Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-16T03:30:51.032Z] GC before operation: completed in 165.303 ms, heap usage 252.348 MB -> 111.028 MB. [2024-11-16T03:30:55.024Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3747.160 ms) ====== [2024-11-16T03:30:55.024Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-16T03:30:55.024Z] GC before operation: completed in 181.247 ms, heap usage 233.472 MB -> 111.170 MB. [2024-11-16T03:30:58.124Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3327.904 ms) ====== [2024-11-16T03:30:58.124Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-16T03:30:58.124Z] GC before operation: completed in 175.858 ms, heap usage 252.706 MB -> 111.394 MB. [2024-11-16T03:31:02.124Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3754.926 ms) ====== [2024-11-16T03:31:02.124Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-16T03:31:02.124Z] GC before operation: completed in 168.428 ms, heap usage 252.286 MB -> 111.572 MB. [2024-11-16T03:31:06.217Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3367.546 ms) ====== [2024-11-16T03:31:06.217Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-16T03:31:06.217Z] GC before operation: completed in 156.918 ms, heap usage 289.048 MB -> 111.835 MB. [2024-11-16T03:31:09.293Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3518.362 ms) ====== [2024-11-16T03:31:09.293Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-16T03:31:09.293Z] GC before operation: completed in 125.272 ms, heap usage 235.178 MB -> 111.866 MB. [2024-11-16T03:31:13.351Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3534.115 ms) ====== [2024-11-16T03:31:13.351Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-16T03:31:13.351Z] GC before operation: completed in 164.629 ms, heap usage 234.469 MB -> 112.050 MB. [2024-11-16T03:31:16.322Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3382.598 ms) ====== [2024-11-16T03:31:16.322Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-16T03:31:16.971Z] GC before operation: completed in 164.786 ms, heap usage 297.181 MB -> 112.401 MB. [2024-11-16T03:31:20.083Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3187.079 ms) ====== [2024-11-16T03:31:20.083Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-16T03:31:20.083Z] GC before operation: completed in 160.838 ms, heap usage 235.493 MB -> 112.429 MB. [2024-11-16T03:31:24.059Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3618.669 ms) ====== [2024-11-16T03:31:24.059Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-16T03:31:24.059Z] GC before operation: completed in 173.341 ms, heap usage 289.723 MB -> 112.731 MB. [2024-11-16T03:31:27.181Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3114.763 ms) ====== [2024-11-16T03:31:27.181Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-16T03:31:27.181Z] GC before operation: completed in 164.869 ms, heap usage 288.806 MB -> 112.900 MB. [2024-11-16T03:31:30.211Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3231.332 ms) ====== [2024-11-16T03:31:30.211Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-16T03:31:30.853Z] GC before operation: completed in 209.627 ms, heap usage 254.548 MB -> 112.986 MB. [2024-11-16T03:31:33.904Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3208.977 ms) ====== [2024-11-16T03:31:33.904Z] ----------------------------------- [2024-11-16T03:31:33.904Z] renaissance-log-regression_0_PASSED [2024-11-16T03:31:33.904Z] ----------------------------------- [2024-11-16T03:31:33.904Z] [2024-11-16T03:31:33.904Z] TEST TEARDOWN: [2024-11-16T03:31:33.904Z] Nothing to be done for teardown. [2024-11-16T03:31:34.557Z] renaissance-log-regression_0 Finish Time: Fri Nov 15 22:31:33 2024 Epoch Time (ms): 1731727893871