[2025-07-15T12:22:45.257Z] Running test renaissance-dec-tree_0 ... [2025-07-15T12:22:45.257Z] =============================================== [2025-07-15T12:22:45.257Z] renaissance-dec-tree_0 Start Time: Tue Jul 15 12:22:44 2025 Epoch Time (ms): 1752582164659 [2025-07-15T12:22:45.257Z] variation: NoOptions [2025-07-15T12:22:45.257Z] JVM_OPTIONS: [2025-07-15T12:22:45.257Z] { \ [2025-07-15T12:22:45.257Z] echo ""; echo "TEST SETUP:"; \ [2025-07-15T12:22:45.257Z] echo "Nothing to be done for setup."; \ [2025-07-15T12:22:45.257Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17525810668452/renaissance-dec-tree_0"; \ [2025-07-15T12:22:45.257Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17525810668452/renaissance-dec-tree_0"; \ [2025-07-15T12:22:45.257Z] echo ""; echo "TESTING:"; \ [2025-07-15T12:22:45.257Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17525810668452/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-07-15T12:22:45.257Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17525810668452/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-15T12:22:45.257Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-15T12:22:45.257Z] echo "Nothing to be done for teardown."; \ [2025-07-15T12:22:45.257Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17525810668452/TestTargetResult"; [2025-07-15T12:22:45.257Z] [2025-07-15T12:22:45.257Z] TEST SETUP: [2025-07-15T12:22:45.257Z] Nothing to be done for setup. [2025-07-15T12:22:45.257Z] [2025-07-15T12:22:45.257Z] TESTING: [2025-07-15T12:23:01.332Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-07-15T12:23:02.863Z] WARNING: An illegal reflective access operation has occurred [2025-07-15T12:23:02.863Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17525810668452/renaissance-dec-tree_0/harness-122249-2129973805052771579/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-07-15T12:23:02.863Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-07-15T12:23:02.863Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-07-15T12:23:02.863Z] WARNING: All illegal access operations will be denied in a future release [2025-07-15T12:23:05.364Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-07-15T12:23:06.108Z] GC before operation: completed in 296.706 ms, heap usage 47.870 MB -> 34.358 MB. [2025-07-15T12:23:36.600Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (30515.688 ms) ====== [2025-07-15T12:23:36.600Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-07-15T12:23:38.874Z] GC before operation: completed in 1673.838 ms, heap usage 124.872 MB -> 67.022 MB. [2025-07-15T12:23:45.616Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7552.660 ms) ====== [2025-07-15T12:23:45.616Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-07-15T12:23:45.616Z] GC before operation: completed in 259.752 ms, heap usage 183.421 MB -> 67.623 MB. [2025-07-15T12:23:50.145Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4206.387 ms) ====== [2025-07-15T12:23:50.145Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-07-15T12:23:50.145Z] GC before operation: completed in 246.200 ms, heap usage 138.967 MB -> 67.703 MB. [2025-07-15T12:23:54.107Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3804.919 ms) ====== [2025-07-15T12:23:54.107Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-07-15T12:23:54.107Z] GC before operation: completed in 224.281 ms, heap usage 288.919 MB -> 68.115 MB. [2025-07-15T12:24:00.681Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5862.038 ms) ====== [2025-07-15T12:24:00.681Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-07-15T12:24:00.681Z] GC before operation: completed in 224.390 ms, heap usage 136.946 MB -> 68.266 MB. [2025-07-15T12:24:04.670Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3713.356 ms) ====== [2025-07-15T12:24:04.670Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-07-15T12:24:04.670Z] GC before operation: completed in 283.686 ms, heap usage 222.277 MB -> 68.334 MB. [2025-07-15T12:24:07.804Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3410.409 ms) ====== [2025-07-15T12:24:07.804Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-07-15T12:24:08.537Z] GC before operation: completed in 307.436 ms, heap usage 107.693 MB -> 68.226 MB. [2025-07-15T12:24:13.667Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4699.014 ms) ====== [2025-07-15T12:24:13.667Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-07-15T12:24:13.667Z] GC before operation: completed in 302.162 ms, heap usage 105.515 MB -> 68.534 MB. [2025-07-15T12:24:16.726Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3587.311 ms) ====== [2025-07-15T12:24:16.726Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-07-15T12:24:17.412Z] GC before operation: completed in 383.056 ms, heap usage 170.098 MB -> 68.657 MB. [2025-07-15T12:24:21.472Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4223.433 ms) ====== [2025-07-15T12:24:21.472Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-07-15T12:24:21.472Z] GC before operation: completed in 392.128 ms, heap usage 198.216 MB -> 68.917 MB. [2025-07-15T12:24:25.519Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3691.905 ms) ====== [2025-07-15T12:24:25.519Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-07-15T12:24:25.519Z] GC before operation: completed in 349.439 ms, heap usage 105.871 MB -> 68.864 MB. [2025-07-15T12:24:30.763Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4346.279 ms) ====== [2025-07-15T12:24:30.763Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-07-15T12:24:30.763Z] GC before operation: completed in 341.950 ms, heap usage 269.968 MB -> 69.504 MB. [2025-07-15T12:24:37.345Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (6260.467 ms) ====== [2025-07-15T12:24:37.345Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-07-15T12:24:37.345Z] GC before operation: completed in 889.413 ms, heap usage 133.033 MB -> 69.620 MB. [2025-07-15T12:24:41.908Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3867.122 ms) ====== [2025-07-15T12:24:41.908Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-07-15T12:24:41.908Z] GC before operation: completed in 352.279 ms, heap usage 150.954 MB -> 69.033 MB. [2025-07-15T12:24:47.213Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (5622.872 ms) ====== [2025-07-15T12:24:47.213Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-07-15T12:24:47.866Z] GC before operation: completed in 419.154 ms, heap usage 167.491 MB -> 96.926 MB. [2025-07-15T12:24:53.081Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (4461.981 ms) ====== [2025-07-15T12:24:53.081Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-07-15T12:24:53.081Z] GC before operation: completed in 405.899 ms, heap usage 199.638 MB -> 69.789 MB. [2025-07-15T12:25:01.588Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (7980.083 ms) ====== [2025-07-15T12:25:01.588Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-07-15T12:25:01.588Z] GC before operation: completed in 287.532 ms, heap usage 171.642 MB -> 97.055 MB. [2025-07-15T12:25:05.778Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4074.404 ms) ====== [2025-07-15T12:25:05.778Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-07-15T12:25:06.432Z] GC before operation: completed in 1075.621 ms, heap usage 237.073 MB -> 70.281 MB. [2025-07-15T12:25:10.668Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4391.465 ms) ====== [2025-07-15T12:25:10.668Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-07-15T12:25:10.668Z] GC before operation: completed in 234.145 ms, heap usage 242.796 MB -> 69.985 MB. [2025-07-15T12:25:14.753Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3676.472 ms) ====== [2025-07-15T12:25:14.753Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-07-15T12:25:14.753Z] GC before operation: completed in 427.722 ms, heap usage 148.636 MB -> 97.369 MB. [2025-07-15T12:25:18.844Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3403.238 ms) ====== [2025-07-15T12:25:18.844Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-07-15T12:25:18.844Z] GC before operation: completed in 554.770 ms, heap usage 293.302 MB -> 70.349 MB. [2025-07-15T12:25:23.136Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4269.406 ms) ====== [2025-07-15T12:25:23.136Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-07-15T12:25:23.136Z] GC before operation: completed in 236.568 ms, heap usage 218.426 MB -> 70.296 MB. [2025-07-15T12:25:27.124Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3322.848 ms) ====== [2025-07-15T12:25:27.124Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-07-15T12:25:27.124Z] GC before operation: completed in 523.528 ms, heap usage 138.263 MB -> 70.529 MB. [2025-07-15T12:25:30.335Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3208.098 ms) ====== [2025-07-15T12:25:30.336Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-07-15T12:25:31.488Z] GC before operation: completed in 371.958 ms, heap usage 242.986 MB -> 98.105 MB. [2025-07-15T12:25:33.761Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2966.156 ms) ====== [2025-07-15T12:25:33.761Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-07-15T12:25:34.516Z] GC before operation: completed in 506.193 ms, heap usage 236.049 MB -> 70.751 MB. [2025-07-15T12:25:37.693Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3357.614 ms) ====== [2025-07-15T12:25:37.693Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-07-15T12:25:38.399Z] GC before operation: completed in 460.271 ms, heap usage 288.785 MB -> 70.703 MB. [2025-07-15T12:25:41.475Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3012.583 ms) ====== [2025-07-15T12:25:41.475Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-07-15T12:25:41.475Z] GC before operation: completed in 303.889 ms, heap usage 239.939 MB -> 98.403 MB. [2025-07-15T12:25:45.510Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3800.679 ms) ====== [2025-07-15T12:25:45.510Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-07-15T12:25:45.510Z] GC before operation: completed in 376.340 ms, heap usage 126.485 MB -> 70.729 MB. [2025-07-15T12:25:48.762Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3154.053 ms) ====== [2025-07-15T12:25:48.762Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-07-15T12:25:49.434Z] GC before operation: completed in 430.020 ms, heap usage 336.122 MB -> 71.152 MB. [2025-07-15T12:25:52.430Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3014.583 ms) ====== [2025-07-15T12:25:52.430Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-07-15T12:25:52.430Z] GC before operation: completed in 286.789 ms, heap usage 198.476 MB -> 71.243 MB. [2025-07-15T12:25:55.536Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2937.860 ms) ====== [2025-07-15T12:25:55.536Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-07-15T12:25:56.185Z] GC before operation: completed in 604.978 ms, heap usage 255.069 MB -> 98.807 MB. [2025-07-15T12:25:56.185Z] 12:25:55.687 WARN [block-manager-storage-async-thread-pool-11] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429, which does not exist [2025-07-15T12:25:56.185Z] 12:25:55.700 WARN [block-manager-storage-async-thread-pool-11] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429_piece0, which does not exist [2025-07-15T12:25:59.370Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3300.015 ms) ====== [2025-07-15T12:25:59.370Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-07-15T12:26:00.085Z] GC before operation: completed in 357.742 ms, heap usage 296.412 MB -> 71.495 MB. [2025-07-15T12:26:07.012Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (6562.529 ms) ====== [2025-07-15T12:26:07.012Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-07-15T12:26:08.692Z] GC before operation: completed in 2077.035 ms, heap usage 226.544 MB -> 71.437 MB. [2025-07-15T12:26:13.963Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (5595.832 ms) ====== [2025-07-15T12:26:13.963Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-07-15T12:26:13.963Z] GC before operation: completed in 240.900 ms, heap usage 133.410 MB -> 71.217 MB. [2025-07-15T12:26:16.995Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3091.281 ms) ====== [2025-07-15T12:26:16.995Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-07-15T12:26:17.663Z] GC before operation: completed in 354.049 ms, heap usage 108.009 MB -> 98.941 MB. [2025-07-15T12:26:21.055Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3115.186 ms) ====== [2025-07-15T12:26:21.055Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-07-15T12:26:21.055Z] GC before operation: completed in 274.582 ms, heap usage 250.825 MB -> 71.889 MB. [2025-07-15T12:26:25.181Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3983.715 ms) ====== [2025-07-15T12:26:25.181Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-07-15T12:26:25.181Z] GC before operation: completed in 357.100 ms, heap usage 311.205 MB -> 99.441 MB. [2025-07-15T12:26:29.129Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3188.996 ms) ====== [2025-07-15T12:26:29.129Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-07-15T12:26:29.129Z] GC before operation: completed in 415.890 ms, heap usage 133.754 MB -> 99.536 MB. [2025-07-15T12:26:32.193Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2818.433 ms) ====== [2025-07-15T12:26:32.193Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-07-15T12:26:32.193Z] GC before operation: completed in 444.806 ms, heap usage 194.243 MB -> 72.407 MB. [2025-07-15T12:26:36.826Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4851.289 ms) ====== [2025-07-15T12:26:37.492Z] ----------------------------------- [2025-07-15T12:26:37.492Z] renaissance-dec-tree_0_PASSED [2025-07-15T12:26:37.492Z] ----------------------------------- [2025-07-15T12:26:37.492Z] [2025-07-15T12:26:37.492Z] TEST TEARDOWN: [2025-07-15T12:26:37.492Z] Nothing to be done for teardown. [2025-07-15T12:26:37.492Z] renaissance-dec-tree_0 Finish Time: Tue Jul 15 12:26:37 2025 Epoch Time (ms): 1752582397334