[2025-10-01T22:07:05.021Z] Running test renaissance-dec-tree_0 ... [2025-10-01T22:07:05.021Z] =============================================== [2025-10-01T22:07:05.021Z] renaissance-dec-tree_0 Start Time: Wed Oct 1 17:07:04 2025 Epoch Time (ms): 1759356424417 [2025-10-01T22:07:05.021Z] variation: NoOptions [2025-10-01T22:07:05.021Z] JVM_OPTIONS: [2025-10-01T22:07:05.021Z] { \ [2025-10-01T22:07:05.021Z] echo ""; echo "TEST SETUP:"; \ [2025-10-01T22:07:05.021Z] echo "Nothing to be done for setup."; \ [2025-10-01T22:07:05.021Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593552308779/renaissance-dec-tree_0"; \ [2025-10-01T22:07:05.021Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593552308779/renaissance-dec-tree_0"; \ [2025-10-01T22:07:05.021Z] echo ""; echo "TESTING:"; \ [2025-10-01T22:07:05.021Z] "/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_17593552308779/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-10-01T22:07:05.021Z] 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_17593552308779/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-01T22:07:05.021Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-01T22:07:05.021Z] echo "Nothing to be done for teardown."; \ [2025-10-01T22:07:05.021Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593552308779/TestTargetResult"; [2025-10-01T22:07:05.021Z] [2025-10-01T22:07:05.021Z] TEST SETUP: [2025-10-01T22:07:05.021Z] Nothing to be done for setup. [2025-10-01T22:07:05.021Z] [2025-10-01T22:07:05.021Z] TESTING: [2025-10-01T22:07:20.021Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-10-01T22:07:23.125Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-10-01T22:07:23.125Z] GC before operation: completed in 49.124 ms, heap usage 52.620 MB -> 35.059 MB. [2025-10-01T22:07:40.745Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (16672.915 ms) ====== [2025-10-01T22:07:40.745Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-10-01T22:07:40.745Z] GC before operation: completed in 248.841 ms, heap usage 206.353 MB -> 68.439 MB. [2025-10-01T22:07:45.799Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (5329.280 ms) ====== [2025-10-01T22:07:45.799Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-10-01T22:07:45.799Z] GC before operation: completed in 329.031 ms, heap usage 129.541 MB -> 68.279 MB. [2025-10-01T22:07:49.917Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4151.276 ms) ====== [2025-10-01T22:07:49.917Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-10-01T22:07:49.917Z] GC before operation: completed in 202.820 ms, heap usage 176.341 MB -> 68.341 MB. [2025-10-01T22:07:54.005Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4180.855 ms) ====== [2025-10-01T22:07:54.005Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-10-01T22:07:54.653Z] GC before operation: completed in 214.471 ms, heap usage 238.176 MB -> 68.709 MB. [2025-10-01T22:07:57.677Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3440.816 ms) ====== [2025-10-01T22:07:57.677Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-10-01T22:07:58.410Z] GC before operation: completed in 384.511 ms, heap usage 279.099 MB -> 68.895 MB. [2025-10-01T22:08:02.465Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3693.784 ms) ====== [2025-10-01T22:08:02.465Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-10-01T22:08:02.465Z] GC before operation: completed in 247.778 ms, heap usage 134.145 MB -> 68.889 MB. [2025-10-01T22:08:05.565Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3478.879 ms) ====== [2025-10-01T22:08:05.565Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-10-01T22:08:06.201Z] GC before operation: completed in 168.914 ms, heap usage 323.154 MB -> 69.298 MB. [2025-10-01T22:08:09.386Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3181.301 ms) ====== [2025-10-01T22:08:09.386Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-10-01T22:08:09.386Z] GC before operation: completed in 208.086 ms, heap usage 327.907 MB -> 69.617 MB. [2025-10-01T22:08:12.818Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3316.061 ms) ====== [2025-10-01T22:08:12.818Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-10-01T22:08:13.591Z] GC before operation: completed in 509.898 ms, heap usage 320.525 MB -> 70.036 MB. [2025-10-01T22:08:16.678Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3268.642 ms) ====== [2025-10-01T22:08:16.678Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-10-01T22:08:16.678Z] GC before operation: completed in 211.429 ms, heap usage 105.231 MB -> 96.700 MB. [2025-10-01T22:08:19.589Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3056.536 ms) ====== [2025-10-01T22:08:19.589Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-10-01T22:08:19.590Z] GC before operation: completed in 222.807 ms, heap usage 322.658 MB -> 70.205 MB. [2025-10-01T22:08:23.538Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3026.198 ms) ====== [2025-10-01T22:08:23.538Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-10-01T22:08:23.538Z] GC before operation: completed in 153.039 ms, heap usage 107.995 MB -> 96.963 MB. [2025-10-01T22:08:23.538Z] 17:08:22.746 WARN [block-manager-storage-async-thread-pool-99] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_163_piece0, which does not exist [2025-10-01T22:08:23.538Z] 17:08:22.749 WARN [block-manager-storage-async-thread-pool-99] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_163, which does not exist [2025-10-01T22:08:25.775Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2977.161 ms) ====== [2025-10-01T22:08:25.775Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-10-01T22:08:26.548Z] GC before operation: completed in 226.514 ms, heap usage 140.903 MB -> 70.265 MB. [2025-10-01T22:08:29.477Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3295.483 ms) ====== [2025-10-01T22:08:29.477Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-10-01T22:08:30.190Z] GC before operation: completed in 305.266 ms, heap usage 314.943 MB -> 70.258 MB. [2025-10-01T22:08:32.402Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2694.613 ms) ====== [2025-10-01T22:08:32.402Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-10-01T22:08:33.121Z] GC before operation: completed in 547.253 ms, heap usage 253.401 MB -> 89.778 MB. [2025-10-01T22:08:35.976Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2906.139 ms) ====== [2025-10-01T22:08:35.976Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-10-01T22:08:35.976Z] GC before operation: completed in 201.641 ms, heap usage 237.501 MB -> 70.474 MB. [2025-10-01T22:08:39.822Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3269.233 ms) ====== [2025-10-01T22:08:39.822Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-10-01T22:08:39.822Z] GC before operation: completed in 156.405 ms, heap usage 106.110 MB -> 70.134 MB. [2025-10-01T22:08:42.723Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3017.918 ms) ====== [2025-10-01T22:08:42.723Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-10-01T22:08:42.723Z] GC before operation: completed in 206.586 ms, heap usage 185.492 MB -> 70.402 MB. [2025-10-01T22:08:45.763Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2629.726 ms) ====== [2025-10-01T22:08:45.763Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-10-01T22:08:45.763Z] GC before operation: completed in 313.399 ms, heap usage 269.639 MB -> 70.910 MB. [2025-10-01T22:08:48.863Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2898.882 ms) ====== [2025-10-01T22:08:48.864Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-10-01T22:08:48.864Z] GC before operation: completed in 237.186 ms, heap usage 208.811 MB -> 70.903 MB. [2025-10-01T22:08:52.814Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3449.092 ms) ====== [2025-10-01T22:08:52.814Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-10-01T22:08:52.814Z] GC before operation: completed in 286.181 ms, heap usage 340.464 MB -> 98.633 MB. [2025-10-01T22:08:55.694Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2626.008 ms) ====== [2025-10-01T22:08:55.694Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-10-01T22:08:55.694Z] GC before operation: completed in 250.966 ms, heap usage 302.178 MB -> 71.160 MB. [2025-10-01T22:08:58.683Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2986.749 ms) ====== [2025-10-01T22:08:58.683Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-10-01T22:08:58.683Z] GC before operation: completed in 282.365 ms, heap usage 111.357 MB -> 98.311 MB. [2025-10-01T22:09:01.555Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2845.137 ms) ====== [2025-10-01T22:09:01.555Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-10-01T22:09:02.195Z] GC before operation: completed in 269.915 ms, heap usage 236.660 MB -> 71.524 MB. [2025-10-01T22:09:04.259Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2593.362 ms) ====== [2025-10-01T22:09:04.259Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-10-01T22:09:04.951Z] GC before operation: completed in 210.741 ms, heap usage 327.713 MB -> 99.082 MB. [2025-10-01T22:09:04.951Z] 17:09:04.410 WARN [block-manager-storage-async-thread-pool-98] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1197_1, which does not exist [2025-10-01T22:09:07.034Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2328.003 ms) ====== [2025-10-01T22:09:07.034Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-10-01T22:09:07.034Z] GC before operation: completed in 196.536 ms, heap usage 308.427 MB -> 71.624 MB. [2025-10-01T22:09:09.413Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2353.732 ms) ====== [2025-10-01T22:09:09.413Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-10-01T22:09:10.049Z] GC before operation: completed in 278.371 ms, heap usage 253.266 MB -> 71.722 MB. [2025-10-01T22:09:12.942Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2545.770 ms) ====== [2025-10-01T22:09:12.942Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-10-01T22:09:12.942Z] GC before operation: completed in 226.352 ms, heap usage 177.765 MB -> 71.538 MB. [2025-10-01T22:09:15.084Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2435.051 ms) ====== [2025-10-01T22:09:15.084Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-10-01T22:09:15.715Z] GC before operation: completed in 290.321 ms, heap usage 363.964 MB -> 71.859 MB. [2025-10-01T22:09:18.690Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2803.482 ms) ====== [2025-10-01T22:09:18.690Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-10-01T22:09:18.690Z] GC before operation: completed in 234.574 ms, heap usage 176.669 MB -> 99.339 MB. [2025-10-01T22:09:18.690Z] 17:09:18.116 WARN [block-manager-storage-async-thread-pool-37] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-10-01T22:09:20.778Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2351.608 ms) ====== [2025-10-01T22:09:20.778Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-10-01T22:09:20.778Z] GC before operation: completed in 235.451 ms, heap usage 304.800 MB -> 72.130 MB. [2025-10-01T22:09:23.827Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2608.533 ms) ====== [2025-10-01T22:09:23.827Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-10-01T22:09:23.827Z] GC before operation: completed in 322.835 ms, heap usage 379.015 MB -> 99.906 MB. [2025-10-01T22:09:26.688Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2317.608 ms) ====== [2025-10-01T22:09:26.689Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-10-01T22:09:26.689Z] GC before operation: completed in 251.626 ms, heap usage 332.649 MB -> 100.027 MB. [2025-10-01T22:09:29.405Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2339.515 ms) ====== [2025-10-01T22:09:29.405Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-10-01T22:09:29.405Z] GC before operation: completed in 250.828 ms, heap usage 130.655 MB -> 71.820 MB. [2025-10-01T22:09:31.565Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2420.232 ms) ====== [2025-10-01T22:09:31.565Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-10-01T22:09:31.565Z] GC before operation: completed in 189.814 ms, heap usage 236.377 MB -> 72.590 MB. [2025-10-01T22:09:34.472Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2431.656 ms) ====== [2025-10-01T22:09:34.472Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-10-01T22:09:34.472Z] GC before operation: completed in 345.214 ms, heap usage 117.242 MB -> 72.212 MB. [2025-10-01T22:09:37.277Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3006.686 ms) ====== [2025-10-01T22:09:37.277Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-10-01T22:09:37.953Z] GC before operation: completed in 302.125 ms, heap usage 201.281 MB -> 73.207 MB. [2025-10-01T22:09:41.110Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2776.533 ms) ====== [2025-10-01T22:09:41.110Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-10-01T22:09:41.110Z] GC before operation: completed in 192.760 ms, heap usage 225.046 MB -> 72.919 MB. [2025-10-01T22:09:43.939Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2865.951 ms) ====== [2025-10-01T22:09:43.939Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-10-01T22:09:43.939Z] GC before operation: completed in 252.193 ms, heap usage 280.373 MB -> 72.986 MB. [2025-10-01T22:09:46.851Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2713.119 ms) ====== [2025-10-01T22:09:46.851Z] ----------------------------------- [2025-10-01T22:09:46.851Z] renaissance-dec-tree_0_PASSED [2025-10-01T22:09:46.851Z] ----------------------------------- [2025-10-01T22:09:46.851Z] [2025-10-01T22:09:46.851Z] TEST TEARDOWN: [2025-10-01T22:09:46.851Z] Nothing to be done for teardown. [2025-10-01T22:09:46.851Z] renaissance-dec-tree_0 Finish Time: Wed Oct 1 17:09:46 2025 Epoch Time (ms): 1759356586752