[2024-12-06T22:27:47.953Z] Running test renaissance-log-regression_0 ... [2024-12-06T22:27:47.953Z] =============================================== [2024-12-06T22:27:47.953Z] renaissance-log-regression_0 Start Time: Fri Dec 6 22:27:47 2024 Epoch Time (ms): 1733524067759 [2024-12-06T22:27:47.953Z] variation: NoOptions [2024-12-06T22:27:47.953Z] JVM_OPTIONS: [2024-12-06T22:27:47.953Z] { \ [2024-12-06T22:27:47.953Z] echo ""; echo "TEST SETUP:"; \ [2024-12-06T22:27:47.953Z] echo "Nothing to be done for setup."; \ [2024-12-06T22:27:47.953Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17335231462955/renaissance-log-regression_0"; \ [2024-12-06T22:27:47.953Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17335231462955/renaissance-log-regression_0"; \ [2024-12-06T22:27:47.953Z] echo ""; echo "TESTING:"; \ [2024-12-06T22:27:47.953Z] "/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_17335231462955/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-12-06T22:27:47.953Z] 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_17335231462955/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-06T22:27:47.953Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-06T22:27:47.953Z] echo "Nothing to be done for teardown."; \ [2024-12-06T22:27:47.953Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17335231462955/TestTargetResult"; [2024-12-06T22:27:47.953Z] [2024-12-06T22:27:47.953Z] TEST SETUP: [2024-12-06T22:27:47.953Z] Nothing to be done for setup. [2024-12-06T22:27:47.953Z] [2024-12-06T22:27:47.953Z] TESTING: [2024-12-06T22:27:52.212Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-06T22:27:54.750Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-12-06T22:27:56.626Z] WARNING: An illegal reflective access operation has occurred [2024-12-06T22:27:56.626Z] 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_17335231462955/renaissance-log-regression_0/harness-222748-1797428506252837182/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-12-06T22:27:56.626Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-12-06T22:27:56.626Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-12-06T22:27:56.626Z] WARNING: All illegal access operations will be denied in a future release [2024-12-06T22:27:59.921Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-12-06T22:28:00.305Z] GC before operation: completed in 65.442 ms, heap usage 77.822 MB -> 33.763 MB. [2024-12-06T22:28:12.025Z] 24/12/06 22:28:10 WARN BlockManager: Asked to remove block broadcast_28_piece0, which does not exist [2024-12-06T22:28:12.025Z] 24/12/06 22:28:10 WARN BlockManager: Asked to remove block broadcast_28, which does not exist [2024-12-06T22:28:12.406Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12182.628 ms) ====== [2024-12-06T22:28:12.406Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-12-06T22:28:12.406Z] GC before operation: completed in 90.697 ms, heap usage 289.862 MB -> 111.995 MB. [2024-12-06T22:28:14.363Z] 24/12/06 22:28:13 WARN BlockManager: Asked to remove block broadcast_55, which does not exist [2024-12-06T22:28:16.331Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4052.268 ms) ====== [2024-12-06T22:28:16.331Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-12-06T22:28:16.730Z] GC before operation: completed in 84.850 ms, heap usage 258.358 MB -> 112.454 MB. [2024-12-06T22:28:20.126Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3429.943 ms) ====== [2024-12-06T22:28:20.126Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-12-06T22:28:20.126Z] GC before operation: completed in 88.330 ms, heap usage 259.018 MB -> 112.819 MB. [2024-12-06T22:28:23.471Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2931.983 ms) ====== [2024-12-06T22:28:23.471Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-12-06T22:28:23.471Z] GC before operation: completed in 107.841 ms, heap usage 297.367 MB -> 113.130 MB. [2024-12-06T22:28:25.401Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2422.632 ms) ====== [2024-12-06T22:28:25.401Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-12-06T22:28:25.831Z] GC before operation: completed in 90.878 ms, heap usage 321.928 MB -> 113.408 MB. [2024-12-06T22:28:28.398Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2630.746 ms) ====== [2024-12-06T22:28:28.398Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-12-06T22:28:28.399Z] GC before operation: completed in 84.912 ms, heap usage 265.319 MB -> 113.515 MB. [2024-12-06T22:28:30.988Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2438.998 ms) ====== [2024-12-06T22:28:30.988Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-12-06T22:28:30.988Z] GC before operation: completed in 89.106 ms, heap usage 314.088 MB -> 113.793 MB. [2024-12-06T22:28:33.855Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2495.372 ms) ====== [2024-12-06T22:28:33.855Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-12-06T22:28:33.855Z] GC before operation: completed in 89.598 ms, heap usage 258.782 MB -> 113.877 MB. [2024-12-06T22:28:35.787Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2338.363 ms) ====== [2024-12-06T22:28:35.787Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-12-06T22:28:35.787Z] GC before operation: completed in 92.749 ms, heap usage 296.321 MB -> 114.169 MB. [2024-12-06T22:28:38.428Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2351.828 ms) ====== [2024-12-06T22:28:38.428Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-12-06T22:28:38.428Z] GC before operation: completed in 89.805 ms, heap usage 247.348 MB -> 114.274 MB. [2024-12-06T22:28:41.031Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2187.751 ms) ====== [2024-12-06T22:28:41.031Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-12-06T22:28:41.031Z] GC before operation: completed in 102.326 ms, heap usage 314.721 MB -> 114.581 MB. [2024-12-06T22:28:42.986Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2287.732 ms) ====== [2024-12-06T22:28:42.986Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-12-06T22:28:42.986Z] GC before operation: completed in 109.847 ms, heap usage 266.050 MB -> 114.680 MB. [2024-12-06T22:28:45.611Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2325.244 ms) ====== [2024-12-06T22:28:45.611Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-12-06T22:28:45.611Z] GC before operation: completed in 98.562 ms, heap usage 238.936 MB -> 114.779 MB. [2024-12-06T22:28:48.225Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2382.256 ms) ====== [2024-12-06T22:28:48.225Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-12-06T22:28:48.225Z] GC before operation: completed in 105.675 ms, heap usage 259.980 MB -> 115.010 MB. [2024-12-06T22:28:50.183Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2304.316 ms) ====== [2024-12-06T22:28:50.183Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-12-06T22:28:50.183Z] GC before operation: completed in 93.123 ms, heap usage 257.705 MB -> 115.170 MB. [2024-12-06T22:28:52.770Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2223.602 ms) ====== [2024-12-06T22:28:52.770Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-12-06T22:28:52.770Z] GC before operation: completed in 96.436 ms, heap usage 276.355 MB -> 115.448 MB. [2024-12-06T22:28:55.360Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2367.825 ms) ====== [2024-12-06T22:28:55.360Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-12-06T22:28:55.360Z] GC before operation: completed in 102.286 ms, heap usage 228.999 MB -> 115.558 MB. [2024-12-06T22:28:57.290Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2292.893 ms) ====== [2024-12-06T22:28:57.290Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-12-06T22:28:57.678Z] GC before operation: completed in 98.673 ms, heap usage 296.944 MB -> 115.891 MB. [2024-12-06T22:29:00.281Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2307.245 ms) ====== [2024-12-06T22:29:00.281Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-12-06T22:29:00.281Z] GC before operation: completed in 103.262 ms, heap usage 267.702 MB -> 116.003 MB. [2024-12-06T22:29:02.225Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2442.431 ms) ====== [2024-12-06T22:29:02.610Z] ----------------------------------- [2024-12-06T22:29:02.610Z] renaissance-log-regression_0_PASSED [2024-12-06T22:29:02.610Z] ----------------------------------- [2024-12-06T22:29:02.610Z] [2024-12-06T22:29:02.610Z] TEST TEARDOWN: [2024-12-06T22:29:02.610Z] Nothing to be done for teardown. [2024-12-06T22:29:02.610Z] renaissance-log-regression_0 Finish Time: Fri Dec 6 22:29:02 2024 Epoch Time (ms): 1733524142572