[2025-11-12T23:27:34.311Z] Running test renaissance-dec-tree_0 ... [2025-11-12T23:27:34.311Z] =============================================== [2025-11-12T23:27:34.311Z] renaissance-dec-tree_0 Start Time: Wed Nov 12 23:27:33 2025 Epoch Time (ms): 1762990053819 [2025-11-12T23:27:34.311Z] variation: NoOptions [2025-11-12T23:27:34.311Z] JVM_OPTIONS: [2025-11-12T23:27:34.311Z] { \ [2025-11-12T23:27:34.311Z] echo ""; echo "TEST SETUP:"; \ [2025-11-12T23:27:34.311Z] echo "Nothing to be done for setup."; \ [2025-11-12T23:27:34.311Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629891323228/renaissance-dec-tree_0"; \ [2025-11-12T23:27:34.311Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629891323228/renaissance-dec-tree_0"; \ [2025-11-12T23:27:34.311Z] echo ""; echo "TESTING:"; \ [2025-11-12T23:27:34.311Z] "/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_17629891323228/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-11-12T23:27:34.311Z] 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_17629891323228/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-12T23:27:34.311Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-12T23:27:34.311Z] echo "Nothing to be done for teardown."; \ [2025-11-12T23:27:34.311Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629891323228/TestTargetResult"; [2025-11-12T23:27:34.311Z] [2025-11-12T23:27:34.311Z] TEST SETUP: [2025-11-12T23:27:34.311Z] Nothing to be done for setup. [2025-11-12T23:27:34.311Z] [2025-11-12T23:27:34.311Z] TESTING: [2025-11-12T23:27:41.235Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-12T23:27:42.488Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-11-12T23:27:42.488Z] GC before operation: completed in 149.651 ms, heap usage 52.370 MB -> 34.971 MB. [2025-11-12T23:27:52.587Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9632.965 ms) ====== [2025-11-12T23:27:52.587Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-11-12T23:27:52.587Z] GC before operation: completed in 232.035 ms, heap usage 130.711 MB -> 67.917 MB. [2025-11-12T23:27:55.372Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2809.579 ms) ====== [2025-11-12T23:27:55.372Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-11-12T23:27:55.372Z] GC before operation: completed in 150.497 ms, heap usage 351.417 MB -> 68.353 MB. [2025-11-12T23:27:58.331Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3006.323 ms) ====== [2025-11-12T23:27:58.331Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-11-12T23:27:58.331Z] GC before operation: completed in 111.428 ms, heap usage 192.065 MB -> 68.454 MB. [2025-11-12T23:28:01.080Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2144.828 ms) ====== [2025-11-12T23:28:01.080Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-11-12T23:28:01.080Z] GC before operation: completed in 145.218 ms, heap usage 197.339 MB -> 68.668 MB. [2025-11-12T23:28:03.407Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2301.769 ms) ====== [2025-11-12T23:28:03.407Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-11-12T23:28:03.407Z] GC before operation: completed in 138.413 ms, heap usage 251.479 MB -> 68.872 MB. [2025-11-12T23:28:06.127Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2626.546 ms) ====== [2025-11-12T23:28:06.127Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-11-12T23:28:06.128Z] GC before operation: completed in 146.582 ms, heap usage 196.010 MB -> 69.069 MB. [2025-11-12T23:28:08.861Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2329.771 ms) ====== [2025-11-12T23:28:08.861Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-11-12T23:28:08.861Z] GC before operation: completed in 128.835 ms, heap usage 319.426 MB -> 96.860 MB. [2025-11-12T23:28:10.848Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2148.024 ms) ====== [2025-11-12T23:28:10.848Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-11-12T23:28:10.848Z] GC before operation: completed in 246.662 ms, heap usage 114.309 MB -> 69.282 MB. [2025-11-12T23:28:12.806Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2102.241 ms) ====== [2025-11-12T23:28:12.806Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-11-12T23:28:13.398Z] GC before operation: completed in 188.901 ms, heap usage 334.828 MB -> 69.646 MB. [2025-11-12T23:28:15.349Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2285.153 ms) ====== [2025-11-12T23:28:15.349Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-11-12T23:28:15.945Z] GC before operation: completed in 147.554 ms, heap usage 230.602 MB -> 89.627 MB. [2025-11-12T23:28:17.924Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2199.805 ms) ====== [2025-11-12T23:28:17.924Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-11-12T23:28:17.924Z] GC before operation: completed in 123.896 ms, heap usage 232.037 MB -> 97.520 MB. [2025-11-12T23:28:20.642Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2184.342 ms) ====== [2025-11-12T23:28:20.642Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-11-12T23:28:20.642Z] GC before operation: completed in 135.233 ms, heap usage 115.361 MB -> 69.634 MB. [2025-11-12T23:28:22.715Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2116.254 ms) ====== [2025-11-12T23:28:22.715Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-11-12T23:28:22.715Z] GC before operation: completed in 123.373 ms, heap usage 179.405 MB -> 97.395 MB. [2025-11-12T23:28:22.715Z] 23:28:22.267 WARN [block-manager-storage-async-thread-pool-67] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_181, which does not exist [2025-11-12T23:28:24.665Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2175.957 ms) ====== [2025-11-12T23:28:24.665Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-11-12T23:28:24.665Z] GC before operation: completed in 163.419 ms, heap usage 252.008 MB -> 97.863 MB. [2025-11-12T23:28:27.390Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2242.840 ms) ====== [2025-11-12T23:28:27.390Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-11-12T23:28:27.390Z] GC before operation: completed in 133.593 ms, heap usage 155.960 MB -> 70.052 MB. [2025-11-12T23:28:29.364Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2186.907 ms) ====== [2025-11-12T23:28:29.364Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-11-12T23:28:29.364Z] GC before operation: completed in 130.532 ms, heap usage 165.548 MB -> 70.535 MB. [2025-11-12T23:28:32.132Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2208.114 ms) ====== [2025-11-12T23:28:32.132Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-11-12T23:28:32.132Z] GC before operation: completed in 130.771 ms, heap usage 233.541 MB -> 70.581 MB. [2025-11-12T23:28:34.131Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2046.337 ms) ====== [2025-11-12T23:28:34.131Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-11-12T23:28:34.131Z] GC before operation: completed in 165.650 ms, heap usage 222.978 MB -> 70.789 MB. [2025-11-12T23:28:36.162Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2173.560 ms) ====== [2025-11-12T23:28:36.162Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-11-12T23:28:36.796Z] GC before operation: completed in 149.880 ms, heap usage 220.317 MB -> 70.923 MB. [2025-11-12T23:28:38.727Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1980.821 ms) ====== [2025-11-12T23:28:38.727Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-11-12T23:28:38.727Z] GC before operation: completed in 157.720 ms, heap usage 330.514 MB -> 98.581 MB. [2025-11-12T23:28:41.047Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2116.146 ms) ====== [2025-11-12T23:28:41.047Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-11-12T23:28:41.047Z] GC before operation: completed in 141.721 ms, heap usage 112.756 MB -> 70.690 MB. [2025-11-12T23:28:43.022Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2103.368 ms) ====== [2025-11-12T23:28:43.022Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-11-12T23:28:43.022Z] GC before operation: completed in 147.388 ms, heap usage 103.780 MB -> 70.635 MB. [2025-11-12T23:28:44.959Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2068.330 ms) ====== [2025-11-12T23:28:44.959Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-11-12T23:28:45.655Z] GC before operation: completed in 220.651 ms, heap usage 315.680 MB -> 98.825 MB. [2025-11-12T23:28:47.612Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2286.019 ms) ====== [2025-11-12T23:28:47.612Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-11-12T23:28:47.612Z] GC before operation: completed in 163.424 ms, heap usage 112.290 MB -> 79.560 MB. [2025-11-12T23:28:50.338Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2055.079 ms) ====== [2025-11-12T23:28:50.338Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-11-12T23:28:50.338Z] GC before operation: completed in 157.698 ms, heap usage 227.217 MB -> 99.183 MB. [2025-11-12T23:28:52.517Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2195.894 ms) ====== [2025-11-12T23:28:52.517Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-11-12T23:28:52.517Z] GC before operation: completed in 181.317 ms, heap usage 120.537 MB -> 71.085 MB. [2025-11-12T23:28:54.466Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1889.581 ms) ====== [2025-11-12T23:28:54.466Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-11-12T23:28:54.466Z] GC before operation: completed in 148.034 ms, heap usage 346.763 MB -> 71.845 MB. [2025-11-12T23:28:57.220Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2289.231 ms) ====== [2025-11-12T23:28:57.220Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-11-12T23:28:57.220Z] GC before operation: completed in 149.519 ms, heap usage 170.718 MB -> 71.480 MB. [2025-11-12T23:28:58.466Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1759.523 ms) ====== [2025-11-12T23:28:58.466Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-11-12T23:28:59.075Z] GC before operation: completed in 155.103 ms, heap usage 167.743 MB -> 71.532 MB. [2025-11-12T23:29:01.020Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1929.632 ms) ====== [2025-11-12T23:29:01.020Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-11-12T23:29:01.020Z] GC before operation: completed in 194.553 ms, heap usage 284.516 MB -> 99.621 MB. [2025-11-12T23:29:02.953Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1844.420 ms) ====== [2025-11-12T23:29:02.953Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-11-12T23:29:02.953Z] GC before operation: completed in 125.028 ms, heap usage 307.337 MB -> 72.261 MB. [2025-11-12T23:29:04.932Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2085.581 ms) ====== [2025-11-12T23:29:04.932Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-11-12T23:29:04.932Z] GC before operation: completed in 147.342 ms, heap usage 211.978 MB -> 72.180 MB. [2025-11-12T23:29:06.906Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1851.117 ms) ====== [2025-11-12T23:29:06.906Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-11-12T23:29:06.907Z] GC before operation: completed in 145.063 ms, heap usage 169.774 MB -> 99.617 MB. [2025-11-12T23:29:06.907Z] 23:29:06.855 WARN [block-manager-storage-async-thread-pool-45] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_457_piece0, which does not exist [2025-11-12T23:29:09.631Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1974.803 ms) ====== [2025-11-12T23:29:09.631Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-11-12T23:29:09.631Z] GC before operation: completed in 134.833 ms, heap usage 106.696 MB -> 72.257 MB. [2025-11-12T23:29:10.893Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1786.639 ms) ====== [2025-11-12T23:29:10.893Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-11-12T23:29:11.499Z] GC before operation: completed in 158.408 ms, heap usage 185.195 MB -> 91.720 MB. [2025-11-12T23:29:13.437Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2030.418 ms) ====== [2025-11-12T23:29:13.437Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-11-12T23:29:13.437Z] GC before operation: completed in 120.939 ms, heap usage 109.102 MB -> 72.392 MB. [2025-11-12T23:29:15.759Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2126.335 ms) ====== [2025-11-12T23:29:15.759Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-11-12T23:29:15.759Z] GC before operation: completed in 163.965 ms, heap usage 302.759 MB -> 72.832 MB. [2025-11-12T23:29:17.708Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1892.052 ms) ====== [2025-11-12T23:29:17.708Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-11-12T23:29:17.708Z] GC before operation: completed in 158.988 ms, heap usage 320.413 MB -> 100.433 MB. [2025-11-12T23:29:19.667Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1881.658 ms) ====== [2025-11-12T23:29:19.668Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-11-12T23:29:19.668Z] GC before operation: completed in 158.673 ms, heap usage 133.118 MB -> 72.793 MB. [2025-11-12T23:29:21.974Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2006.675 ms) ====== [2025-11-12T23:29:21.974Z] ----------------------------------- [2025-11-12T23:29:21.974Z] renaissance-dec-tree_0_PASSED [2025-11-12T23:29:21.974Z] ----------------------------------- [2025-11-12T23:29:21.974Z] [2025-11-12T23:29:21.974Z] TEST TEARDOWN: [2025-11-12T23:29:21.974Z] Nothing to be done for teardown. [2025-11-12T23:29:21.974Z] renaissance-dec-tree_0 Finish Time: Wed Nov 12 23:29:21 2025 Epoch Time (ms): 1762990161709