[2025-02-27T13:28:43.014Z] Running test renaissance-log-regression_0 ... [2025-02-27T13:28:43.014Z] =============================================== [2025-02-27T13:28:43.014Z] renaissance-log-regression_0 Start Time: Thu Feb 27 13:28:42 2025 Epoch Time (ms): 1740662922006 [2025-02-27T13:28:43.014Z] variation: NoOptions [2025-02-27T13:28:43.014Z] JVM_OPTIONS: [2025-02-27T13:28:43.014Z] { \ [2025-02-27T13:28:43.014Z] echo ""; echo "TEST SETUP:"; \ [2025-02-27T13:28:43.014Z] echo "Nothing to be done for setup."; \ [2025-02-27T13:28:43.014Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406613191788/renaissance-log-regression_0"; \ [2025-02-27T13:28:43.014Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406613191788/renaissance-log-regression_0"; \ [2025-02-27T13:28:43.014Z] echo ""; echo "TESTING:"; \ [2025-02-27T13:28:43.014Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406613191788/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-27T13:28:43.014Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406613191788/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-27T13:28:43.014Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-27T13:28:43.014Z] echo "Nothing to be done for teardown."; \ [2025-02-27T13:28:43.014Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406613191788/TestTargetResult"; [2025-02-27T13:28:43.014Z] [2025-02-27T13:28:43.014Z] TEST SETUP: [2025-02-27T13:28:43.014Z] Nothing to be done for setup. [2025-02-27T13:28:43.014Z] [2025-02-27T13:28:43.014Z] TESTING: [2025-02-27T13:28:46.523Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-27T13:28:50.047Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-27T13:28:52.590Z] WARNING: An illegal reflective access operation has occurred [2025-02-27T13:28:52.590Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17406613191788/renaissance-log-regression_0/harness-132843-15748482511717729483/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-27T13:28:52.590Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-27T13:28:52.590Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-27T13:28:52.590Z] WARNING: All illegal access operations will be denied in a future release [2025-02-27T13:28:57.168Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-27T13:28:57.960Z] GC before operation: completed in 128.385 ms, heap usage 71.530 MB -> 33.768 MB. [2025-02-27T13:29:17.194Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17397.618 ms) ====== [2025-02-27T13:29:17.194Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-27T13:29:17.194Z] GC before operation: completed in 208.362 ms, heap usage 382.827 MB -> 112.245 MB. [2025-02-27T13:29:18.105Z] 25/02/27 13:29:17 WARN BlockManager: Asked to remove block broadcast_61, which does not exist [2025-02-27T13:29:20.654Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5722.050 ms) ====== [2025-02-27T13:29:20.654Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-27T13:29:21.446Z] GC before operation: completed in 352.458 ms, heap usage 227.579 MB -> 112.740 MB. [2025-02-27T13:29:26.061Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4961.379 ms) ====== [2025-02-27T13:29:26.061Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-27T13:29:26.854Z] GC before operation: completed in 212.632 ms, heap usage 238.484 MB -> 112.815 MB. [2025-02-27T13:29:31.490Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4412.781 ms) ====== [2025-02-27T13:29:31.490Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-27T13:29:31.490Z] GC before operation: completed in 164.947 ms, heap usage 373.438 MB -> 113.361 MB. [2025-02-27T13:29:35.021Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3924.360 ms) ====== [2025-02-27T13:29:35.021Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-27T13:29:35.021Z] GC before operation: completed in 192.509 ms, heap usage 227.126 MB -> 113.251 MB. [2025-02-27T13:29:39.556Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3934.777 ms) ====== [2025-02-27T13:29:39.556Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-27T13:29:39.556Z] GC before operation: completed in 195.389 ms, heap usage 266.076 MB -> 113.537 MB. [2025-02-27T13:29:43.100Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3670.744 ms) ====== [2025-02-27T13:29:43.100Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-27T13:29:43.100Z] GC before operation: completed in 170.018 ms, heap usage 238.401 MB -> 113.639 MB. [2025-02-27T13:29:47.160Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3821.364 ms) ====== [2025-02-27T13:29:47.160Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-27T13:29:47.160Z] GC before operation: completed in 164.056 ms, heap usage 228.387 MB -> 113.824 MB. [2025-02-27T13:29:49.708Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2776.432 ms) ====== [2025-02-27T13:29:49.708Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-27T13:29:49.709Z] GC before operation: completed in 177.633 ms, heap usage 354.584 MB -> 114.297 MB. [2025-02-27T13:29:53.242Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3304.424 ms) ====== [2025-02-27T13:29:53.242Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-27T13:29:53.242Z] GC before operation: completed in 189.445 ms, heap usage 248.518 MB -> 114.263 MB. [2025-02-27T13:29:56.773Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3346.568 ms) ====== [2025-02-27T13:29:56.773Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-27T13:29:56.773Z] GC before operation: completed in 208.449 ms, heap usage 257.946 MB -> 114.458 MB. [2025-02-27T13:30:00.309Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3044.614 ms) ====== [2025-02-27T13:30:00.309Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-27T13:30:00.309Z] GC before operation: completed in 307.025 ms, heap usage 265.851 MB -> 114.698 MB. [2025-02-27T13:30:03.845Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3232.588 ms) ====== [2025-02-27T13:30:03.845Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-27T13:30:03.845Z] GC before operation: completed in 193.944 ms, heap usage 257.913 MB -> 114.854 MB. [2025-02-27T13:30:07.988Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3122.354 ms) ====== [2025-02-27T13:30:07.988Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-27T13:30:07.988Z] GC before operation: completed in 184.846 ms, heap usage 258.211 MB -> 115.015 MB. [2025-02-27T13:30:09.643Z] 25/02/27 13:30:08 WARN BlockManager: Asked to remove block broadcast_688, which does not exist [2025-02-27T13:30:10.451Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2860.310 ms) ====== [2025-02-27T13:30:10.451Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-27T13:30:10.451Z] GC before operation: completed in 173.638 ms, heap usage 297.649 MB -> 115.293 MB. [2025-02-27T13:30:12.987Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2823.201 ms) ====== [2025-02-27T13:30:12.987Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-27T13:30:12.987Z] GC before operation: completed in 183.841 ms, heap usage 343.945 MB -> 115.662 MB. [2025-02-27T13:30:16.515Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2902.520 ms) ====== [2025-02-27T13:30:16.515Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-27T13:30:16.515Z] GC before operation: completed in 160.080 ms, heap usage 334.596 MB -> 115.830 MB. [2025-02-27T13:30:19.070Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2912.188 ms) ====== [2025-02-27T13:30:19.070Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-27T13:30:19.070Z] GC before operation: completed in 179.627 ms, heap usage 343.626 MB -> 116.069 MB. [2025-02-27T13:30:22.588Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3223.877 ms) ====== [2025-02-27T13:30:22.588Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-27T13:30:22.588Z] GC before operation: completed in 213.962 ms, heap usage 230.051 MB -> 115.982 MB. [2025-02-27T13:30:26.132Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3264.006 ms) ====== [2025-02-27T13:30:26.919Z] ----------------------------------- [2025-02-27T13:30:26.919Z] renaissance-log-regression_0_PASSED [2025-02-27T13:30:26.919Z] ----------------------------------- [2025-02-27T13:30:26.919Z] [2025-02-27T13:30:26.919Z] TEST TEARDOWN: [2025-02-27T13:30:26.919Z] Nothing to be done for teardown. [2025-02-27T13:30:26.919Z] renaissance-log-regression_0 Finish Time: Thu Feb 27 13:30:26 2025 Epoch Time (ms): 1740663026094