[2025-02-05T22:50:08.360Z] Running test renaissance-log-regression_0 ... [2025-02-05T22:50:08.360Z] =============================================== [2025-02-05T22:50:08.360Z] renaissance-log-regression_0 Start Time: Wed Feb 5 22:50:08 2025 Epoch Time (ms): 1738795808278 [2025-02-05T22:50:08.360Z] variation: NoOptions [2025-02-05T22:50:08.360Z] JVM_OPTIONS: [2025-02-05T22:50:08.360Z] { \ [2025-02-05T22:50:08.360Z] echo ""; echo "TEST SETUP:"; \ [2025-02-05T22:50:08.360Z] echo "Nothing to be done for setup."; \ [2025-02-05T22:50:08.360Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17387948919696/renaissance-log-regression_0"; \ [2025-02-05T22:50:08.360Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17387948919696/renaissance-log-regression_0"; \ [2025-02-05T22:50:08.360Z] echo ""; echo "TESTING:"; \ [2025-02-05T22:50:08.360Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17387948919696/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-05T22:50:08.360Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17387948919696/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-05T22:50:08.360Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-05T22:50:08.360Z] echo "Nothing to be done for teardown."; \ [2025-02-05T22:50:08.360Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17387948919696/TestTargetResult"; [2025-02-05T22:50:08.360Z] [2025-02-05T22:50:08.360Z] TEST SETUP: [2025-02-05T22:50:08.360Z] Nothing to be done for setup. [2025-02-05T22:50:08.360Z] [2025-02-05T22:50:08.360Z] TESTING: [2025-02-05T22:50:13.515Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-05T22:50:15.397Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-05T22:50:17.265Z] WARNING: An illegal reflective access operation has occurred [2025-02-05T22:50:17.265Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17387948919696/renaissance-log-regression_0/harness-225009-3362947909806750642/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-05T22:50:17.265Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-05T22:50:17.265Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-05T22:50:17.266Z] WARNING: All illegal access operations will be denied in a future release [2025-02-05T22:50:20.544Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-05T22:50:20.544Z] GC before operation: completed in 59.336 ms, heap usage 55.040 MB -> 33.762 MB. [2025-02-05T22:50:32.205Z] 25/02/05 22:50:30 WARN BlockManager: Asked to remove block broadcast_22_piece0, which does not exist [2025-02-05T22:50:32.205Z] 25/02/05 22:50:30 WARN BlockManager: Asked to remove block broadcast_22, which does not exist [2025-02-05T22:50:33.572Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12572.315 ms) ====== [2025-02-05T22:50:33.572Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-05T22:50:33.572Z] GC before operation: completed in 86.356 ms, heap usage 278.288 MB -> 111.990 MB. [2025-02-05T22:50:37.910Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4076.568 ms) ====== [2025-02-05T22:50:37.910Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-05T22:50:37.910Z] GC before operation: completed in 97.910 ms, heap usage 275.865 MB -> 112.499 MB. [2025-02-05T22:50:40.561Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3049.815 ms) ====== [2025-02-05T22:50:40.561Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-05T22:50:40.561Z] GC before operation: completed in 106.399 ms, heap usage 268.263 MB -> 112.885 MB. [2025-02-05T22:50:43.951Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2727.126 ms) ====== [2025-02-05T22:50:43.951Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-05T22:50:43.951Z] GC before operation: completed in 86.210 ms, heap usage 257.406 MB -> 113.058 MB. [2025-02-05T22:50:46.679Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2619.322 ms) ====== [2025-02-05T22:50:46.679Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-05T22:50:46.679Z] GC before operation: completed in 87.919 ms, heap usage 229.453 MB -> 113.231 MB. [2025-02-05T22:50:48.601Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2404.544 ms) ====== [2025-02-05T22:50:48.601Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-05T22:50:48.601Z] GC before operation: completed in 101.881 ms, heap usage 277.878 MB -> 113.512 MB. [2025-02-05T22:50:51.163Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2568.367 ms) ====== [2025-02-05T22:50:51.163Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-05T22:50:51.546Z] GC before operation: completed in 85.574 ms, heap usage 259.002 MB -> 113.628 MB. [2025-02-05T22:50:54.141Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2631.798 ms) ====== [2025-02-05T22:50:54.141Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-05T22:50:54.141Z] GC before operation: completed in 94.587 ms, heap usage 239.800 MB -> 113.816 MB. [2025-02-05T22:50:56.751Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2596.622 ms) ====== [2025-02-05T22:50:56.751Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-05T22:50:56.751Z] GC before operation: completed in 87.551 ms, heap usage 276.621 MB -> 114.113 MB. [2025-02-05T22:50:59.363Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2219.225 ms) ====== [2025-02-05T22:50:59.363Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-05T22:50:59.363Z] GC before operation: completed in 109.964 ms, heap usage 276.605 MB -> 114.308 MB. [2025-02-05T22:51:01.255Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2169.794 ms) ====== [2025-02-05T22:51:01.255Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-05T22:51:01.255Z] GC before operation: completed in 95.913 ms, heap usage 257.941 MB -> 114.429 MB. [2025-02-05T22:51:03.888Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2276.244 ms) ====== [2025-02-05T22:51:03.888Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-05T22:51:03.888Z] GC before operation: completed in 108.595 ms, heap usage 285.056 MB -> 114.707 MB. [2025-02-05T22:51:05.804Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2183.904 ms) ====== [2025-02-05T22:51:05.804Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-05T22:51:06.185Z] GC before operation: completed in 93.000 ms, heap usage 238.374 MB -> 114.757 MB. [2025-02-05T22:51:08.070Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2004.054 ms) ====== [2025-02-05T22:51:08.070Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-05T22:51:08.070Z] GC before operation: completed in 94.049 ms, heap usage 278.571 MB -> 115.035 MB. [2025-02-05T22:51:10.678Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2164.849 ms) ====== [2025-02-05T22:51:10.678Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-05T22:51:10.678Z] GC before operation: completed in 97.257 ms, heap usage 247.934 MB -> 115.180 MB. [2025-02-05T22:51:12.625Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2262.711 ms) ====== [2025-02-05T22:51:12.625Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-05T22:51:12.625Z] GC before operation: completed in 115.186 ms, heap usage 239.752 MB -> 115.354 MB. [2025-02-05T22:51:15.230Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2257.920 ms) ====== [2025-02-05T22:51:15.230Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-05T22:51:15.230Z] GC before operation: completed in 98.454 ms, heap usage 277.428 MB -> 115.657 MB. [2025-02-05T22:51:17.230Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2035.181 ms) ====== [2025-02-05T22:51:17.230Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-05T22:51:17.230Z] GC before operation: completed in 105.441 ms, heap usage 324.106 MB -> 115.988 MB. [2025-02-05T22:51:20.001Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2280.401 ms) ====== [2025-02-05T22:51:20.001Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-05T22:51:20.001Z] GC before operation: completed in 98.582 ms, heap usage 230.042 MB -> 115.954 MB. [2025-02-05T22:51:22.557Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2467.012 ms) ====== [2025-02-05T22:51:22.557Z] ----------------------------------- [2025-02-05T22:51:22.557Z] renaissance-log-regression_0_PASSED [2025-02-05T22:51:22.557Z] ----------------------------------- [2025-02-05T22:51:22.557Z] [2025-02-05T22:51:22.557Z] TEST TEARDOWN: [2025-02-05T22:51:22.557Z] Nothing to be done for teardown. [2025-02-05T22:51:22.557Z] renaissance-log-regression_0 Finish Time: Wed Feb 5 22:51:22 2025 Epoch Time (ms): 1738795882222