[2024-11-15T23:46:47.553Z] Running test renaissance-log-regression_0 ... [2024-11-15T23:46:47.553Z] =============================================== [2024-11-15T23:46:47.553Z] renaissance-log-regression_0 Start Time: Fri Nov 15 23:46:46 2024 Epoch Time (ms): 1731714406930 [2024-11-15T23:46:47.553Z] variation: NoOptions [2024-11-15T23:46:47.553Z] JVM_OPTIONS: [2024-11-15T23:46:47.553Z] { \ [2024-11-15T23:46:47.553Z] echo ""; echo "TEST SETUP:"; \ [2024-11-15T23:46:47.553Z] echo "Nothing to be done for setup."; \ [2024-11-15T23:46:47.553Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317136185048/renaissance-log-regression_0"; \ [2024-11-15T23:46:47.553Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317136185048/renaissance-log-regression_0"; \ [2024-11-15T23:46:47.553Z] echo ""; echo "TESTING:"; \ [2024-11-15T23:46:47.553Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317136185048/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-15T23:46:47.553Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317136185048/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-15T23:46:47.553Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-15T23:46:47.553Z] echo "Nothing to be done for teardown."; \ [2024-11-15T23:46:47.553Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17317136185048/TestTargetResult"; [2024-11-15T23:46:47.553Z] [2024-11-15T23:46:47.553Z] TEST SETUP: [2024-11-15T23:46:47.553Z] Nothing to be done for setup. [2024-11-15T23:46:47.553Z] [2024-11-15T23:46:47.553Z] TESTING: [2024-11-15T23:46:50.515Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-15T23:46:52.435Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-15T23:46:54.356Z] WARNING: An illegal reflective access operation has occurred [2024-11-15T23:46:54.356Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17317136185048/renaissance-log-regression_0/harness-234647-8237353636061801384/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-15T23:46:54.356Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-15T23:46:54.356Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-15T23:46:54.356Z] WARNING: All illegal access operations will be denied in a future release [2024-11-15T23:46:57.014Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-15T23:46:57.014Z] GC before operation: completed in 56.774 ms, heap usage 64.025 MB -> 33.767 MB. [2024-11-15T23:47:03.639Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7609.300 ms) ====== [2024-11-15T23:47:03.639Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-15T23:47:03.639Z] GC before operation: completed in 102.312 ms, heap usage 395.081 MB -> 112.208 MB. [2024-11-15T23:47:06.618Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2209.709 ms) ====== [2024-11-15T23:47:06.618Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-15T23:47:06.618Z] GC before operation: completed in 85.705 ms, heap usage 266.726 MB -> 112.490 MB. [2024-11-15T23:47:08.653Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1926.438 ms) ====== [2024-11-15T23:47:08.653Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-15T23:47:08.653Z] GC before operation: completed in 86.179 ms, heap usage 326.218 MB -> 113.019 MB. [2024-11-15T23:47:10.572Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1872.825 ms) ====== [2024-11-15T23:47:10.572Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-15T23:47:10.572Z] GC before operation: completed in 73.762 ms, heap usage 247.570 MB -> 113.055 MB. [2024-11-15T23:47:12.491Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1778.566 ms) ====== [2024-11-15T23:47:12.491Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-15T23:47:12.491Z] GC before operation: completed in 80.736 ms, heap usage 335.473 MB -> 113.454 MB. [2024-11-15T23:47:14.426Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1751.559 ms) ====== [2024-11-15T23:47:14.426Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-15T23:47:14.426Z] GC before operation: completed in 92.626 ms, heap usage 230.096 MB -> 113.416 MB. [2024-11-15T23:47:16.364Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1802.707 ms) ====== [2024-11-15T23:47:16.364Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-15T23:47:16.364Z] GC before operation: completed in 88.601 ms, heap usage 305.787 MB -> 113.798 MB. [2024-11-15T23:47:16.364Z] 24/11/15 23:47:16 WARN BlockManager: Asked to remove block broadcast_339, which does not exist [2024-11-15T23:47:17.304Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1676.568 ms) ====== [2024-11-15T23:47:17.304Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-15T23:47:17.304Z] GC before operation: completed in 93.266 ms, heap usage 333.686 MB -> 114.033 MB. [2024-11-15T23:47:19.239Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1705.623 ms) ====== [2024-11-15T23:47:19.239Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-15T23:47:19.239Z] GC before operation: completed in 88.852 ms, heap usage 284.899 MB -> 114.134 MB. [2024-11-15T23:47:21.162Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1692.473 ms) ====== [2024-11-15T23:47:21.163Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-15T23:47:21.163Z] GC before operation: completed in 125.048 ms, heap usage 296.182 MB -> 114.325 MB. [2024-11-15T23:47:23.087Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1788.346 ms) ====== [2024-11-15T23:47:23.087Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-15T23:47:23.087Z] GC before operation: completed in 104.256 ms, heap usage 296.324 MB -> 114.553 MB. [2024-11-15T23:47:25.029Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1717.298 ms) ====== [2024-11-15T23:47:25.029Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-15T23:47:25.029Z] GC before operation: completed in 91.032 ms, heap usage 315.376 MB -> 114.786 MB. [2024-11-15T23:47:26.949Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1670.087 ms) ====== [2024-11-15T23:47:26.949Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-15T23:47:26.949Z] GC before operation: completed in 88.128 ms, heap usage 277.418 MB -> 114.889 MB. [2024-11-15T23:47:27.904Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1568.233 ms) ====== [2024-11-15T23:47:27.904Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-15T23:47:28.841Z] GC before operation: completed in 84.635 ms, heap usage 363.148 MB -> 115.265 MB. [2024-11-15T23:47:29.805Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1485.548 ms) ====== [2024-11-15T23:47:29.805Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-15T23:47:29.805Z] GC before operation: completed in 85.715 ms, heap usage 297.157 MB -> 115.267 MB. [2024-11-15T23:47:31.827Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1609.540 ms) ====== [2024-11-15T23:47:31.827Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-15T23:47:31.827Z] GC before operation: completed in 93.106 ms, heap usage 278.754 MB -> 115.452 MB. [2024-11-15T23:47:33.746Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1558.453 ms) ====== [2024-11-15T23:47:33.746Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-15T23:47:33.746Z] GC before operation: completed in 101.231 ms, heap usage 297.201 MB -> 115.733 MB. [2024-11-15T23:47:34.701Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1741.606 ms) ====== [2024-11-15T23:47:34.701Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-15T23:47:35.636Z] GC before operation: completed in 104.430 ms, heap usage 259.308 MB -> 115.835 MB. [2024-11-15T23:47:36.570Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1563.612 ms) ====== [2024-11-15T23:47:36.570Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-15T23:47:36.570Z] GC before operation: completed in 103.984 ms, heap usage 229.363 MB -> 115.977 MB. [2024-11-15T23:47:38.496Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1700.630 ms) ====== [2024-11-15T23:47:38.496Z] ----------------------------------- [2024-11-15T23:47:38.496Z] renaissance-log-regression_0_PASSED [2024-11-15T23:47:38.496Z] ----------------------------------- [2024-11-15T23:47:38.496Z] [2024-11-15T23:47:38.496Z] TEST TEARDOWN: [2024-11-15T23:47:38.496Z] Nothing to be done for teardown. [2024-11-15T23:47:38.496Z] renaissance-log-regression_0 Finish Time: Fri Nov 15 23:47:38 2024 Epoch Time (ms): 1731714458329