[2025-06-29T20:31:10.714Z] Running test renaissance-dec-tree_0 ... [2025-06-29T20:31:10.714Z] =============================================== [2025-06-29T20:31:10.714Z] renaissance-dec-tree_0 Start Time: Sun Jun 29 15:31:10 2025 Epoch Time (ms): 1751229070168 [2025-06-29T20:31:10.714Z] variation: NoOptions [2025-06-29T20:31:10.714Z] JVM_OPTIONS: [2025-06-29T20:31:10.714Z] { \ [2025-06-29T20:31:10.714Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T20:31:10.714Z] echo "Nothing to be done for setup."; \ [2025-06-29T20:31:10.714Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-dec-tree_0"; \ [2025-06-29T20:31:10.714Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-dec-tree_0"; \ [2025-06-29T20:31:10.714Z] echo ""; echo "TESTING:"; \ [2025-06-29T20:31:10.714Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-29T20:31:10.714Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T20:31:10.714Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T20:31:10.714Z] echo "Nothing to be done for teardown."; \ [2025-06-29T20:31:10.714Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/TestTargetResult"; [2025-06-29T20:31:10.714Z] [2025-06-29T20:31:10.714Z] TEST SETUP: [2025-06-29T20:31:10.714Z] Nothing to be done for setup. [2025-06-29T20:31:10.714Z] [2025-06-29T20:31:10.714Z] TESTING: [2025-06-29T20:31:18.324Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-29T20:31:19.626Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-29T20:31:19.626Z] GC before operation: completed in 52.877 ms, heap usage 55.889 MB -> 35.048 MB. [2025-06-29T20:31:30.043Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10755.326 ms) ====== [2025-06-29T20:31:30.043Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-29T20:31:30.763Z] GC before operation: completed in 193.601 ms, heap usage 150.427 MB -> 67.930 MB. [2025-06-29T20:31:33.708Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3177.128 ms) ====== [2025-06-29T20:31:33.708Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-29T20:31:33.708Z] GC before operation: completed in 131.543 ms, heap usage 317.144 MB -> 68.712 MB. [2025-06-29T20:31:36.599Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2741.164 ms) ====== [2025-06-29T20:31:36.599Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-29T20:31:36.599Z] GC before operation: completed in 135.023 ms, heap usage 212.990 MB -> 68.679 MB. [2025-06-29T20:31:39.470Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2792.986 ms) ====== [2025-06-29T20:31:39.470Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-29T20:31:39.470Z] GC before operation: completed in 175.885 ms, heap usage 121.918 MB -> 76.459 MB. [2025-06-29T20:31:39.470Z] 15:31:39.239 WARN [block-manager-storage-async-thread-pool-61] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_51_piece0, which does not exist [2025-06-29T20:31:42.290Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2535.903 ms) ====== [2025-06-29T20:31:42.290Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-29T20:31:42.290Z] GC before operation: completed in 130.324 ms, heap usage 294.023 MB -> 68.992 MB. [2025-06-29T20:31:44.386Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2298.620 ms) ====== [2025-06-29T20:31:44.386Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-29T20:31:44.386Z] GC before operation: completed in 126.014 ms, heap usage 237.293 MB -> 96.794 MB. [2025-06-29T20:31:47.255Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2303.174 ms) ====== [2025-06-29T20:31:47.255Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-29T20:31:47.255Z] GC before operation: completed in 124.891 ms, heap usage 274.800 MB -> 69.906 MB. [2025-06-29T20:31:49.322Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2337.920 ms) ====== [2025-06-29T20:31:49.322Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-29T20:31:49.322Z] GC before operation: completed in 206.623 ms, heap usage 243.499 MB -> 97.277 MB. [2025-06-29T20:31:52.261Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2244.118 ms) ====== [2025-06-29T20:31:52.261Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-29T20:31:52.261Z] GC before operation: completed in 179.261 ms, heap usage 189.225 MB -> 69.860 MB. [2025-06-29T20:31:54.310Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2297.412 ms) ====== [2025-06-29T20:31:54.310Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-29T20:31:54.310Z] GC before operation: completed in 174.303 ms, heap usage 139.316 MB -> 69.501 MB. [2025-06-29T20:31:57.201Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2288.318 ms) ====== [2025-06-29T20:31:57.201Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-29T20:31:57.201Z] GC before operation: completed in 141.986 ms, heap usage 270.652 MB -> 97.594 MB. [2025-06-29T20:31:59.291Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2182.479 ms) ====== [2025-06-29T20:31:59.291Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-29T20:31:59.291Z] GC before operation: completed in 213.144 ms, heap usage 116.007 MB -> 89.285 MB. [2025-06-29T20:32:01.374Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2252.887 ms) ====== [2025-06-29T20:32:01.374Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-29T20:32:02.035Z] GC before operation: completed in 113.748 ms, heap usage 164.992 MB -> 97.841 MB. [2025-06-29T20:32:04.071Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2338.872 ms) ====== [2025-06-29T20:32:04.071Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-29T20:32:04.071Z] GC before operation: completed in 176.649 ms, heap usage 322.042 MB -> 97.913 MB. [2025-06-29T20:32:06.161Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2204.745 ms) ====== [2025-06-29T20:32:06.161Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-29T20:32:06.795Z] GC before operation: completed in 179.936 ms, heap usage 121.345 MB -> 70.278 MB. [2025-06-29T20:32:08.854Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2171.637 ms) ====== [2025-06-29T20:32:08.854Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-29T20:32:08.854Z] GC before operation: completed in 143.353 ms, heap usage 115.892 MB -> 69.990 MB. [2025-06-29T20:32:11.588Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2506.488 ms) ====== [2025-06-29T20:32:11.588Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-29T20:32:11.588Z] GC before operation: completed in 155.538 ms, heap usage 304.760 MB -> 70.812 MB. [2025-06-29T20:32:13.648Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2213.354 ms) ====== [2025-06-29T20:32:13.648Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-29T20:32:14.286Z] GC before operation: completed in 176.637 ms, heap usage 121.288 MB -> 70.290 MB. [2025-06-29T20:32:16.313Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2497.521 ms) ====== [2025-06-29T20:32:16.313Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-29T20:32:16.929Z] GC before operation: completed in 127.632 ms, heap usage 366.278 MB -> 90.323 MB. [2025-06-29T20:32:18.248Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1868.209 ms) ====== [2025-06-29T20:32:18.248Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-29T20:32:18.899Z] GC before operation: completed in 133.458 ms, heap usage 136.713 MB -> 71.135 MB. [2025-06-29T20:32:20.282Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1954.762 ms) ====== [2025-06-29T20:32:20.282Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-29T20:32:20.910Z] GC before operation: completed in 118.954 ms, heap usage 103.884 MB -> 70.527 MB. [2025-06-29T20:32:22.942Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2205.504 ms) ====== [2025-06-29T20:32:22.942Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-29T20:32:22.942Z] GC before operation: completed in 172.726 ms, heap usage 272.530 MB -> 90.792 MB. [2025-06-29T20:32:25.746Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2210.066 ms) ====== [2025-06-29T20:32:25.746Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-29T20:32:25.746Z] GC before operation: completed in 170.647 ms, heap usage 322.314 MB -> 90.769 MB. [2025-06-29T20:32:27.795Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2280.829 ms) ====== [2025-06-29T20:32:27.795Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-29T20:32:27.795Z] GC before operation: completed in 144.925 ms, heap usage 135.683 MB -> 71.839 MB. [2025-06-29T20:32:29.834Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2237.114 ms) ====== [2025-06-29T20:32:29.834Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-29T20:32:30.541Z] GC before operation: completed in 194.142 ms, heap usage 333.370 MB -> 99.169 MB. [2025-06-29T20:32:32.580Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2056.588 ms) ====== [2025-06-29T20:32:32.580Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-29T20:32:32.580Z] GC before operation: completed in 168.742 ms, heap usage 141.712 MB -> 71.797 MB. [2025-06-29T20:32:34.625Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2121.909 ms) ====== [2025-06-29T20:32:34.625Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-29T20:32:34.625Z] GC before operation: completed in 189.206 ms, heap usage 284.944 MB -> 71.950 MB. [2025-06-29T20:32:36.646Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2048.688 ms) ====== [2025-06-29T20:32:36.646Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-29T20:32:37.305Z] GC before operation: completed in 220.610 ms, heap usage 233.922 MB -> 72.072 MB. [2025-06-29T20:32:39.320Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2011.707 ms) ====== [2025-06-29T20:32:39.320Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-29T20:32:39.320Z] GC before operation: completed in 217.576 ms, heap usage 230.962 MB -> 99.562 MB. [2025-06-29T20:32:41.352Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1867.440 ms) ====== [2025-06-29T20:32:41.352Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-29T20:32:41.352Z] GC before operation: completed in 171.877 ms, heap usage 184.618 MB -> 91.234 MB. [2025-06-29T20:32:41.352Z] 15:32:41.044 WARN [block-manager-storage-async-thread-pool-48] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-06-29T20:32:43.348Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1831.846 ms) ====== [2025-06-29T20:32:43.348Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-29T20:32:43.348Z] GC before operation: completed in 126.852 ms, heap usage 358.691 MB -> 91.586 MB. [2025-06-29T20:32:45.345Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1783.543 ms) ====== [2025-06-29T20:32:45.345Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-29T20:32:45.345Z] GC before operation: completed in 146.424 ms, heap usage 142.005 MB -> 72.292 MB. [2025-06-29T20:32:46.649Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1545.333 ms) ====== [2025-06-29T20:32:46.649Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-29T20:32:46.649Z] GC before operation: completed in 127.896 ms, heap usage 119.336 MB -> 91.307 MB. [2025-06-29T20:32:48.643Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1532.566 ms) ====== [2025-06-29T20:32:48.643Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-29T20:32:48.643Z] GC before operation: completed in 137.419 ms, heap usage 302.786 MB -> 72.572 MB. [2025-06-29T20:32:50.652Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1854.499 ms) ====== [2025-06-29T20:32:50.652Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-29T20:32:50.652Z] GC before operation: completed in 140.683 ms, heap usage 170.206 MB -> 91.789 MB. [2025-06-29T20:32:52.674Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1834.808 ms) ====== [2025-06-29T20:32:52.674Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-29T20:32:52.674Z] GC before operation: completed in 170.986 ms, heap usage 129.871 MB -> 72.577 MB. [2025-06-29T20:32:54.674Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1872.373 ms) ====== [2025-06-29T20:32:54.674Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-29T20:32:54.674Z] GC before operation: completed in 198.408 ms, heap usage 325.641 MB -> 72.957 MB. [2025-06-29T20:32:56.676Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2087.831 ms) ====== [2025-06-29T20:32:56.676Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-29T20:32:56.676Z] GC before operation: completed in 145.097 ms, heap usage 301.236 MB -> 73.075 MB. [2025-06-29T20:32:59.125Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1838.155 ms) ====== [2025-06-29T20:32:59.125Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-29T20:32:59.125Z] GC before operation: completed in 130.148 ms, heap usage 276.443 MB -> 100.694 MB. [2025-06-29T20:33:00.402Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1601.120 ms) ====== [2025-06-29T20:33:01.014Z] ----------------------------------- [2025-06-29T20:33:01.014Z] renaissance-dec-tree_0_PASSED [2025-06-29T20:33:01.014Z] ----------------------------------- [2025-06-29T20:33:01.014Z] [2025-06-29T20:33:01.014Z] TEST TEARDOWN: [2025-06-29T20:33:01.014Z] Nothing to be done for teardown. [2025-06-29T20:33:01.014Z] renaissance-dec-tree_0 Finish Time: Sun Jun 29 15:33:00 2025 Epoch Time (ms): 1751229180520