[2024-11-17T22:23:40.089Z] Running test renaissance-log-regression_0 ... [2024-11-17T22:23:40.089Z] =============================================== [2024-11-17T22:23:40.089Z] renaissance-log-regression_0 Start Time: Sun Nov 17 22:23:39 2024 Epoch Time (ms): 1731882219990 [2024-11-17T22:23:40.089Z] variation: NoOptions [2024-11-17T22:23:40.089Z] JVM_OPTIONS: [2024-11-17T22:23:40.089Z] { \ [2024-11-17T22:23:40.089Z] echo ""; echo "TEST SETUP:"; \ [2024-11-17T22:23:40.089Z] echo "Nothing to be done for setup."; \ [2024-11-17T22:23:40.089Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17318813106595/renaissance-log-regression_0"; \ [2024-11-17T22:23:40.089Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17318813106595/renaissance-log-regression_0"; \ [2024-11-17T22:23:40.089Z] echo ""; echo "TESTING:"; \ [2024-11-17T22:23:40.089Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-11.0.26+1/bin/..//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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17318813106595/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-17T22:23:40.089Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17318813106595/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-17T22:23:40.089Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-17T22:23:40.089Z] echo "Nothing to be done for teardown."; \ [2024-11-17T22:23:40.089Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17318813106595/TestTargetResult"; [2024-11-17T22:23:40.089Z] [2024-11-17T22:23:40.089Z] TEST SETUP: [2024-11-17T22:23:40.089Z] Nothing to be done for setup. [2024-11-17T22:23:40.089Z] [2024-11-17T22:23:40.089Z] TESTING: [2024-11-17T22:23:42.956Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-17T22:23:44.272Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-11-17T22:23:45.588Z] WARNING: An illegal reflective access operation has occurred [2024-11-17T22:23:45.588Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17318813106595/renaissance-log-regression_0/harness-222340-18133311506661441646/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-17T22:23:45.588Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-17T22:23:45.588Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-17T22:23:45.588Z] WARNING: All illegal access operations will be denied in a future release [2024-11-17T22:23:47.693Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-17T22:23:47.693Z] GC before operation: completed in 48.629 ms, heap usage 44.729 MB -> 33.701 MB. [2024-11-17T22:23:58.033Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9199.140 ms) ====== [2024-11-17T22:23:58.033Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-17T22:23:58.033Z] GC before operation: completed in 94.747 ms, heap usage 289.142 MB -> 109.153 MB. [2024-11-17T22:24:00.925Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3562.482 ms) ====== [2024-11-17T22:24:00.926Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-17T22:24:00.926Z] GC before operation: completed in 82.834 ms, heap usage 252.983 MB -> 109.541 MB. [2024-11-17T22:24:03.811Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2671.595 ms) ====== [2024-11-17T22:24:03.811Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-17T22:24:03.811Z] GC before operation: completed in 107.878 ms, heap usage 257.598 MB -> 109.933 MB. [2024-11-17T22:24:05.993Z] 24/11/17 22:24:05 WARN BlockManager: Asked to remove block broadcast_181, which does not exist [2024-11-17T22:24:05.993Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2689.429 ms) ====== [2024-11-17T22:24:05.993Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-17T22:24:05.993Z] GC before operation: completed in 115.964 ms, heap usage 270.978 MB -> 110.276 MB. [2024-11-17T22:24:08.865Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2341.051 ms) ====== [2024-11-17T22:24:08.865Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-17T22:24:08.865Z] GC before operation: completed in 122.067 ms, heap usage 272.170 MB -> 110.461 MB. [2024-11-17T22:24:10.929Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2043.768 ms) ====== [2024-11-17T22:24:10.929Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-17T22:24:10.929Z] GC before operation: completed in 111.020 ms, heap usage 409.240 MB -> 110.974 MB. [2024-11-17T22:24:12.993Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2454.458 ms) ====== [2024-11-17T22:24:12.993Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-17T22:24:13.628Z] GC before operation: completed in 93.981 ms, heap usage 261.077 MB -> 110.808 MB. [2024-11-17T22:24:14.951Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1862.276 ms) ====== [2024-11-17T22:24:14.951Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-17T22:24:15.588Z] GC before operation: completed in 104.545 ms, heap usage 262.469 MB -> 110.999 MB. [2024-11-17T22:24:17.660Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2169.925 ms) ====== [2024-11-17T22:24:17.660Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-17T22:24:17.660Z] GC before operation: completed in 98.634 ms, heap usage 288.621 MB -> 111.236 MB. [2024-11-17T22:24:19.725Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1721.499 ms) ====== [2024-11-17T22:24:19.725Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-17T22:24:19.725Z] GC before operation: completed in 108.930 ms, heap usage 419.684 MB -> 111.778 MB. [2024-11-17T22:24:21.046Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1817.574 ms) ====== [2024-11-17T22:24:21.046Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-17T22:24:21.678Z] GC before operation: completed in 106.235 ms, heap usage 253.037 MB -> 111.491 MB. [2024-11-17T22:24:22.992Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1679.483 ms) ====== [2024-11-17T22:24:22.992Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-17T22:24:22.992Z] GC before operation: completed in 105.379 ms, heap usage 245.702 MB -> 111.671 MB. [2024-11-17T22:24:25.060Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2007.192 ms) ====== [2024-11-17T22:24:25.060Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-17T22:24:25.060Z] GC before operation: completed in 96.051 ms, heap usage 235.601 MB -> 111.778 MB. [2024-11-17T22:24:27.118Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1818.907 ms) ====== [2024-11-17T22:24:27.118Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-17T22:24:27.118Z] GC before operation: completed in 96.756 ms, heap usage 278.194 MB -> 112.085 MB. [2024-11-17T22:24:29.188Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1600.035 ms) ====== [2024-11-17T22:24:29.188Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-17T22:24:29.188Z] GC before operation: completed in 98.878 ms, heap usage 278.349 MB -> 112.256 MB. [2024-11-17T22:24:31.140Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1919.870 ms) ====== [2024-11-17T22:24:31.140Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-17T22:24:31.140Z] GC before operation: completed in 105.793 ms, heap usage 305.963 MB -> 112.514 MB. [2024-11-17T22:24:33.207Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1865.900 ms) ====== [2024-11-17T22:24:33.207Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-17T22:24:33.207Z] GC before operation: completed in 103.174 ms, heap usage 271.302 MB -> 112.608 MB. [2024-11-17T22:24:34.524Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1826.929 ms) ====== [2024-11-17T22:24:34.524Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-17T22:24:34.524Z] GC before operation: completed in 103.451 ms, heap usage 288.839 MB -> 112.817 MB. [2024-11-17T22:24:36.588Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1716.995 ms) ====== [2024-11-17T22:24:36.588Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-17T22:24:36.588Z] GC before operation: completed in 99.875 ms, heap usage 252.987 MB -> 112.894 MB. [2024-11-17T22:24:38.645Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2005.102 ms) ====== [2024-11-17T22:24:39.282Z] ----------------------------------- [2024-11-17T22:24:39.282Z] renaissance-log-regression_0_PASSED [2024-11-17T22:24:39.282Z] ----------------------------------- [2024-11-17T22:24:39.282Z] [2024-11-17T22:24:39.282Z] TEST TEARDOWN: [2024-11-17T22:24:39.282Z] Nothing to be done for teardown. [2024-11-17T22:24:39.282Z] renaissance-log-regression_0 Finish Time: Sun Nov 17 22:24:38 2024 Epoch Time (ms): 1731882278606