[2025-11-19T22:56:24.197Z] Running test renaissance-dec-tree_0 ... [2025-11-19T22:56:24.197Z] =============================================== [2025-11-19T22:56:24.197Z] renaissance-dec-tree_0 Start Time: Wed Nov 19 22:56:23 2025 Epoch Time (ms): 1763592983750 [2025-11-19T22:56:24.197Z] variation: NoOptions [2025-11-19T22:56:24.197Z] JVM_OPTIONS: [2025-11-19T22:56:24.197Z] { \ [2025-11-19T22:56:24.197Z] echo ""; echo "TEST SETUP:"; \ [2025-11-19T22:56:24.197Z] echo "Nothing to be done for setup."; \ [2025-11-19T22:56:24.197Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17635915871138/renaissance-dec-tree_0"; \ [2025-11-19T22:56:24.197Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17635915871138/renaissance-dec-tree_0"; \ [2025-11-19T22:56:24.197Z] echo ""; echo "TESTING:"; \ [2025-11-19T22:56:24.197Z] "/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_17635915871138/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-11-19T22:56:24.197Z] 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_17635915871138/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-19T22:56:24.197Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-19T22:56:24.197Z] echo "Nothing to be done for teardown."; \ [2025-11-19T22:56:24.197Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17635915871138/TestTargetResult"; [2025-11-19T22:56:24.197Z] [2025-11-19T22:56:24.197Z] TEST SETUP: [2025-11-19T22:56:24.197Z] Nothing to be done for setup. [2025-11-19T22:56:24.197Z] [2025-11-19T22:56:24.197Z] TESTING: [2025-11-19T22:56:30.551Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-19T22:56:33.651Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-11-19T22:56:33.651Z] GC before operation: completed in 64.632 ms, heap usage 70.239 MB -> 34.906 MB. [2025-11-19T22:56:49.088Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (15024.730 ms) ====== [2025-11-19T22:56:49.088Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-11-19T22:56:49.088Z] GC before operation: completed in 432.581 ms, heap usage 334.725 MB -> 67.887 MB. [2025-11-19T22:56:54.349Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (4897.626 ms) ====== [2025-11-19T22:56:54.349Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-11-19T22:56:54.349Z] GC before operation: completed in 294.126 ms, heap usage 247.315 MB -> 68.212 MB. [2025-11-19T22:56:57.524Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3610.486 ms) ====== [2025-11-19T22:56:57.524Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-11-19T22:56:57.524Z] GC before operation: completed in 255.460 ms, heap usage 103.982 MB -> 68.457 MB. [2025-11-19T22:57:01.607Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3391.985 ms) ====== [2025-11-19T22:57:01.607Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-11-19T22:57:01.607Z] GC before operation: completed in 140.350 ms, heap usage 246.117 MB -> 96.283 MB. [2025-11-19T22:57:05.795Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (4053.733 ms) ====== [2025-11-19T22:57:05.795Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-11-19T22:57:05.795Z] GC before operation: completed in 245.676 ms, heap usage 222.790 MB -> 77.219 MB. [2025-11-19T22:57:09.848Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3660.382 ms) ====== [2025-11-19T22:57:09.848Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-11-19T22:57:09.848Z] GC before operation: completed in 175.128 ms, heap usage 388.458 MB -> 69.169 MB. [2025-11-19T22:57:13.942Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4093.426 ms) ====== [2025-11-19T22:57:13.942Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-11-19T22:57:13.942Z] GC before operation: completed in 214.520 ms, heap usage 154.131 MB -> 69.343 MB. [2025-11-19T22:57:17.785Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3665.576 ms) ====== [2025-11-19T22:57:17.785Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-11-19T22:57:17.785Z] GC before operation: completed in 233.349 ms, heap usage 255.329 MB -> 69.417 MB. [2025-11-19T22:57:21.085Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3368.300 ms) ====== [2025-11-19T22:57:21.085Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-11-19T22:57:21.085Z] GC before operation: completed in 201.957 ms, heap usage 316.565 MB -> 69.603 MB. [2025-11-19T22:57:24.344Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2883.538 ms) ====== [2025-11-19T22:57:24.344Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-11-19T22:57:24.344Z] GC before operation: completed in 244.364 ms, heap usage 331.104 MB -> 97.325 MB. [2025-11-19T22:57:28.377Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3775.182 ms) ====== [2025-11-19T22:57:28.377Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-11-19T22:57:28.377Z] GC before operation: completed in 267.412 ms, heap usage 248.361 MB -> 69.835 MB. [2025-11-19T22:57:31.525Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3277.311 ms) ====== [2025-11-19T22:57:31.525Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-11-19T22:57:32.186Z] GC before operation: completed in 235.352 ms, heap usage 306.634 MB -> 70.092 MB. [2025-11-19T22:57:35.253Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3068.879 ms) ====== [2025-11-19T22:57:35.253Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-11-19T22:57:35.253Z] GC before operation: completed in 306.891 ms, heap usage 167.754 MB -> 69.797 MB. [2025-11-19T22:57:39.413Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3487.154 ms) ====== [2025-11-19T22:57:39.413Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-11-19T22:57:39.413Z] GC before operation: completed in 391.250 ms, heap usage 110.944 MB -> 69.681 MB. [2025-11-19T22:57:43.687Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4448.443 ms) ====== [2025-11-19T22:57:43.687Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-11-19T22:57:43.687Z] GC before operation: completed in 161.817 ms, heap usage 182.364 MB -> 70.138 MB. [2025-11-19T22:57:47.847Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3468.453 ms) ====== [2025-11-19T22:57:47.847Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-11-19T22:57:47.847Z] GC before operation: completed in 217.405 ms, heap usage 308.205 MB -> 70.530 MB. [2025-11-19T22:57:51.075Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3348.408 ms) ====== [2025-11-19T22:57:51.075Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-11-19T22:57:51.075Z] GC before operation: completed in 243.694 ms, heap usage 256.905 MB -> 70.660 MB. [2025-11-19T22:57:55.088Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3384.289 ms) ====== [2025-11-19T22:57:55.088Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-11-19T22:57:55.088Z] GC before operation: completed in 172.050 ms, heap usage 149.108 MB -> 70.484 MB. [2025-11-19T22:57:58.261Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3781.943 ms) ====== [2025-11-19T22:57:58.261Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-11-19T22:57:58.985Z] GC before operation: completed in 319.056 ms, heap usage 240.487 MB -> 70.986 MB. [2025-11-19T22:58:02.066Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3414.827 ms) ====== [2025-11-19T22:58:02.066Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-11-19T22:58:02.066Z] GC before operation: completed in 218.515 ms, heap usage 147.669 MB -> 70.635 MB. [2025-11-19T22:58:05.226Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3064.848 ms) ====== [2025-11-19T22:58:05.226Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-11-19T22:58:05.889Z] GC before operation: completed in 175.047 ms, heap usage 371.646 MB -> 70.985 MB. [2025-11-19T22:58:09.281Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3139.273 ms) ====== [2025-11-19T22:58:09.281Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-11-19T22:58:09.281Z] GC before operation: completed in 269.682 ms, heap usage 300.463 MB -> 71.473 MB. [2025-11-19T22:58:12.498Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3049.508 ms) ====== [2025-11-19T22:58:12.498Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-11-19T22:58:12.498Z] GC before operation: completed in 502.118 ms, heap usage 342.865 MB -> 71.348 MB. [2025-11-19T22:58:16.880Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4237.807 ms) ====== [2025-11-19T22:58:16.880Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-11-19T22:58:16.880Z] GC before operation: completed in 212.136 ms, heap usage 334.858 MB -> 71.428 MB. [2025-11-19T22:58:20.555Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3665.442 ms) ====== [2025-11-19T22:58:20.555Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-11-19T22:58:21.266Z] GC before operation: completed in 358.688 ms, heap usage 115.187 MB -> 70.803 MB. [2025-11-19T22:58:24.465Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3588.104 ms) ====== [2025-11-19T22:58:24.465Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-11-19T22:58:25.128Z] GC before operation: completed in 212.350 ms, heap usage 217.982 MB -> 71.709 MB. [2025-11-19T22:58:28.292Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3201.769 ms) ====== [2025-11-19T22:58:28.292Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-11-19T22:58:28.292Z] GC before operation: completed in 235.785 ms, heap usage 150.422 MB -> 71.328 MB. [2025-11-19T22:58:31.405Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3247.728 ms) ====== [2025-11-19T22:58:31.405Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-11-19T22:58:32.097Z] GC before operation: completed in 182.935 ms, heap usage 265.483 MB -> 71.908 MB. [2025-11-19T22:58:35.236Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3198.585 ms) ====== [2025-11-19T22:58:35.236Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-11-19T22:58:35.236Z] GC before operation: completed in 336.044 ms, heap usage 333.402 MB -> 71.981 MB. [2025-11-19T22:58:38.469Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3110.272 ms) ====== [2025-11-19T22:58:38.469Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-11-19T22:58:39.209Z] GC before operation: completed in 416.224 ms, heap usage 294.897 MB -> 99.542 MB. [2025-11-19T22:58:39.209Z] 22:58:38.437 WARN [block-manager-storage-async-thread-pool-40] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415_piece0, which does not exist [2025-11-19T22:58:39.209Z] 22:58:38.442 WARN [block-manager-storage-async-thread-pool-39] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-11-19T22:58:42.269Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2936.200 ms) ====== [2025-11-19T22:58:42.269Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-11-19T22:58:42.269Z] GC before operation: completed in 282.187 ms, heap usage 361.138 MB -> 72.463 MB. [2025-11-19T22:58:45.345Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3538.740 ms) ====== [2025-11-19T22:58:45.345Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-11-19T22:58:46.125Z] GC before operation: completed in 390.603 ms, heap usage 306.354 MB -> 72.316 MB. [2025-11-19T22:58:49.303Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3439.144 ms) ====== [2025-11-19T22:58:49.303Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-11-19T22:58:50.013Z] GC before operation: completed in 225.576 ms, heap usage 154.308 MB -> 72.380 MB. [2025-11-19T22:58:53.295Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3023.075 ms) ====== [2025-11-19T22:58:53.295Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-11-19T22:58:53.295Z] GC before operation: completed in 278.484 ms, heap usage 369.810 MB -> 91.873 MB. [2025-11-19T22:58:56.468Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3456.432 ms) ====== [2025-11-19T22:58:56.468Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-11-19T22:58:56.468Z] GC before operation: completed in 218.959 ms, heap usage 228.591 MB -> 72.463 MB. [2025-11-19T22:58:59.768Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3218.407 ms) ====== [2025-11-19T22:58:59.768Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-11-19T22:59:00.445Z] GC before operation: completed in 366.808 ms, heap usage 321.976 MB -> 72.944 MB. [2025-11-19T22:59:03.532Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3497.585 ms) ====== [2025-11-19T22:59:03.532Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-11-19T22:59:04.259Z] GC before operation: completed in 271.592 ms, heap usage 235.034 MB -> 100.281 MB. [2025-11-19T22:59:08.485Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4352.312 ms) ====== [2025-11-19T22:59:08.485Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-11-19T22:59:08.485Z] GC before operation: completed in 414.150 ms, heap usage 289.427 MB -> 73.236 MB. [2025-11-19T22:59:11.758Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3042.709 ms) ====== [2025-11-19T22:59:11.758Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-11-19T22:59:11.758Z] GC before operation: completed in 219.221 ms, heap usage 273.864 MB -> 73.310 MB. [2025-11-19T22:59:16.170Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3663.807 ms) ====== [2025-11-19T22:59:16.170Z] ----------------------------------- [2025-11-19T22:59:16.170Z] renaissance-dec-tree_0_PASSED [2025-11-19T22:59:16.170Z] ----------------------------------- [2025-11-19T22:59:16.170Z] [2025-11-19T22:59:16.170Z] TEST TEARDOWN: [2025-11-19T22:59:16.170Z] Nothing to be done for teardown. [2025-11-19T22:59:16.170Z] renaissance-dec-tree_0 Finish Time: Wed Nov 19 22:59:15 2025 Epoch Time (ms): 1763593155635