[2025-12-04T09:01:26.924Z] Running test renaissance-dec-tree_0 ... [2025-12-04T09:01:26.924Z] =============================================== [2025-12-04T09:01:26.924Z] renaissance-dec-tree_0 Start Time: Thu Dec 4 09:01:25 2025 Epoch Time (ms): 1764838885887 [2025-12-04T09:01:26.924Z] variation: NoOptions [2025-12-04T09:01:26.924Z] JVM_OPTIONS: [2025-12-04T09:01:26.924Z] { \ [2025-12-04T09:01:26.924Z] echo ""; echo "TEST SETUP:"; \ [2025-12-04T09:01:26.924Z] echo "Nothing to be done for setup."; \ [2025-12-04T09:01:26.924Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17648383431592/renaissance-dec-tree_0"; \ [2025-12-04T09:01:26.924Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17648383431592/renaissance-dec-tree_0"; \ [2025-12-04T09:01:26.924Z] echo ""; echo "TESTING:"; \ [2025-12-04T09:01:26.924Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17648383431592/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-12-04T09:01:26.924Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17648383431592/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-04T09:01:26.924Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-04T09:01:26.924Z] echo "Nothing to be done for teardown."; \ [2025-12-04T09:01:26.924Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17648383431592/TestTargetResult"; [2025-12-04T09:01:26.924Z] [2025-12-04T09:01:26.924Z] TEST SETUP: [2025-12-04T09:01:26.924Z] Nothing to be done for setup. [2025-12-04T09:01:26.924Z] [2025-12-04T09:01:26.924Z] TESTING: [2025-12-04T09:01:31.311Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-04T09:01:33.738Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-12-04T09:01:33.738Z] GC before operation: completed in 41.990 ms, heap usage 42.590 MB -> 34.982 MB. [2025-12-04T09:01:44.008Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9829.491 ms) ====== [2025-12-04T09:01:44.008Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-12-04T09:01:44.008Z] GC before operation: completed in 96.599 ms, heap usage 128.695 MB -> 68.665 MB. [2025-12-04T09:01:46.442Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2616.280 ms) ====== [2025-12-04T09:01:46.442Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-12-04T09:01:46.442Z] GC before operation: completed in 91.453 ms, heap usage 260.820 MB -> 69.373 MB. [2025-12-04T09:01:48.010Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1835.180 ms) ====== [2025-12-04T09:01:48.010Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-12-04T09:01:48.770Z] GC before operation: completed in 77.664 ms, heap usage 334.652 MB -> 80.964 MB. [2025-12-04T09:01:50.344Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1930.929 ms) ====== [2025-12-04T09:01:50.344Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-12-04T09:01:50.344Z] GC before operation: completed in 87.971 ms, heap usage 340.456 MB -> 70.359 MB. [2025-12-04T09:01:51.912Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1686.718 ms) ====== [2025-12-04T09:01:51.912Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-12-04T09:01:51.912Z] GC before operation: completed in 76.811 ms, heap usage 143.575 MB -> 70.229 MB. [2025-12-04T09:01:53.481Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1602.991 ms) ====== [2025-12-04T09:01:53.481Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-12-04T09:01:54.244Z] GC before operation: completed in 86.206 ms, heap usage 294.781 MB -> 81.544 MB. [2025-12-04T09:01:55.804Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1513.538 ms) ====== [2025-12-04T09:01:55.804Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-12-04T09:01:55.804Z] GC before operation: completed in 84.069 ms, heap usage 387.014 MB -> 70.876 MB. [2025-12-04T09:01:57.366Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1497.950 ms) ====== [2025-12-04T09:01:57.366Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-12-04T09:01:57.366Z] GC before operation: completed in 88.564 ms, heap usage 375.221 MB -> 81.945 MB. [2025-12-04T09:01:58.932Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1720.211 ms) ====== [2025-12-04T09:01:58.932Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-12-04T09:01:58.932Z] GC before operation: completed in 92.004 ms, heap usage 407.430 MB -> 87.316 MB. [2025-12-04T09:02:00.495Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1762.130 ms) ====== [2025-12-04T09:02:00.495Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-12-04T09:02:00.495Z] GC before operation: completed in 89.982 ms, heap usage 330.049 MB -> 87.622 MB. [2025-12-04T09:02:02.062Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1330.423 ms) ====== [2025-12-04T09:02:02.062Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-12-04T09:02:02.062Z] GC before operation: completed in 91.136 ms, heap usage 321.181 MB -> 87.587 MB. [2025-12-04T09:02:03.620Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1260.390 ms) ====== [2025-12-04T09:02:03.620Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-12-04T09:02:03.620Z] GC before operation: completed in 94.581 ms, heap usage 322.797 MB -> 82.569 MB. [2025-12-04T09:02:05.187Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1304.335 ms) ====== [2025-12-04T09:02:05.187Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-12-04T09:02:05.187Z] GC before operation: completed in 97.378 ms, heap usage 380.793 MB -> 82.600 MB. [2025-12-04T09:02:05.941Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1330.193 ms) ====== [2025-12-04T09:02:05.942Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-12-04T09:02:06.703Z] GC before operation: completed in 91.364 ms, heap usage 194.188 MB -> 71.292 MB. [2025-12-04T09:02:07.468Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1324.131 ms) ====== [2025-12-04T09:02:07.468Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-12-04T09:02:07.468Z] GC before operation: completed in 99.164 ms, heap usage 292.835 MB -> 88.069 MB. [2025-12-04T09:02:09.029Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1271.870 ms) ====== [2025-12-04T09:02:09.029Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-12-04T09:02:09.029Z] GC before operation: completed in 90.812 ms, heap usage 323.802 MB -> 71.771 MB. [2025-12-04T09:02:10.605Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1270.768 ms) ====== [2025-12-04T09:02:10.605Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-12-04T09:02:10.605Z] GC before operation: completed in 101.944 ms, heap usage 348.098 MB -> 94.093 MB. [2025-12-04T09:02:11.365Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1223.219 ms) ====== [2025-12-04T09:02:11.365Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-12-04T09:02:12.120Z] GC before operation: completed in 98.944 ms, heap usage 412.865 MB -> 88.496 MB. [2025-12-04T09:02:12.877Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1271.975 ms) ====== [2025-12-04T09:02:12.877Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-12-04T09:02:12.877Z] GC before operation: completed in 102.457 ms, heap usage 294.098 MB -> 94.133 MB. [2025-12-04T09:02:14.448Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1222.981 ms) ====== [2025-12-04T09:02:14.448Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-12-04T09:02:14.448Z] GC before operation: completed in 100.648 ms, heap usage 252.024 MB -> 83.438 MB. [2025-12-04T09:02:16.004Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1287.474 ms) ====== [2025-12-04T09:02:16.004Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-12-04T09:02:16.004Z] GC before operation: completed in 101.796 ms, heap usage 158.781 MB -> 88.524 MB. [2025-12-04T09:02:16.004Z] 09:02:15.472 WARN [block-manager-storage-async-thread-pool-94] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1005_1, which does not exist [2025-12-04T09:02:18.068Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1291.810 ms) ====== [2025-12-04T09:02:18.068Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-12-04T09:02:18.068Z] GC before operation: completed in 106.086 ms, heap usage 505.965 MB -> 89.384 MB. [2025-12-04T09:02:18.827Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1274.445 ms) ====== [2025-12-04T09:02:18.827Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-12-04T09:02:18.827Z] GC before operation: completed in 109.030 ms, heap usage 338.649 MB -> 89.031 MB. [2025-12-04T09:02:20.384Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1264.589 ms) ====== [2025-12-04T09:02:20.384Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-12-04T09:02:20.384Z] GC before operation: completed in 137.148 ms, heap usage 476.806 MB -> 89.212 MB. [2025-12-04T09:02:21.144Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1249.522 ms) ====== [2025-12-04T09:02:21.144Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-12-04T09:02:21.144Z] GC before operation: completed in 120.086 ms, heap usage 413.475 MB -> 89.304 MB. [2025-12-04T09:02:22.722Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1340.693 ms) ====== [2025-12-04T09:02:22.722Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-12-04T09:02:22.722Z] GC before operation: completed in 107.650 ms, heap usage 304.681 MB -> 89.418 MB. [2025-12-04T09:02:24.314Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1316.780 ms) ====== [2025-12-04T09:02:24.314Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-12-04T09:02:24.314Z] GC before operation: completed in 115.096 ms, heap usage 475.642 MB -> 89.736 MB. [2025-12-04T09:02:25.994Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1228.261 ms) ====== [2025-12-04T09:02:25.994Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-12-04T09:02:25.994Z] GC before operation: completed in 110.918 ms, heap usage 327.155 MB -> 84.049 MB. [2025-12-04T09:02:26.747Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1282.793 ms) ====== [2025-12-04T09:02:26.747Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-12-04T09:02:26.747Z] GC before operation: completed in 105.543 ms, heap usage 189.779 MB -> 94.961 MB. [2025-12-04T09:02:28.318Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1301.010 ms) ====== [2025-12-04T09:02:28.318Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-12-04T09:02:28.318Z] GC before operation: completed in 114.662 ms, heap usage 395.533 MB -> 89.945 MB. [2025-12-04T09:02:29.900Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1213.840 ms) ====== [2025-12-04T09:02:29.900Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-12-04T09:02:29.900Z] GC before operation: completed in 114.674 ms, heap usage 446.498 MB -> 101.072 MB. [2025-12-04T09:02:29.900Z] 09:02:29.382 WARN [block-manager-storage-async-thread-pool-64] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1485_2, which does not exist [2025-12-04T09:02:31.463Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1263.461 ms) ====== [2025-12-04T09:02:31.463Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-12-04T09:02:31.463Z] GC before operation: completed in 110.323 ms, heap usage 297.048 MB -> 90.130 MB. [2025-12-04T09:02:32.217Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1241.439 ms) ====== [2025-12-04T09:02:32.217Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-12-04T09:02:32.217Z] GC before operation: completed in 109.563 ms, heap usage 230.325 MB -> 90.142 MB. [2025-12-04T09:02:33.779Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1278.370 ms) ====== [2025-12-04T09:02:33.779Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-12-04T09:02:33.779Z] GC before operation: completed in 120.087 ms, heap usage 480.543 MB -> 101.386 MB. [2025-12-04T09:02:33.779Z] 09:02:33.507 WARN [block-manager-storage-async-thread-pool-61] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1629_1, which does not exist [2025-12-04T09:02:33.779Z] 09:02:33.507 WARN [block-manager-storage-async-thread-pool-61] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1629_2, which does not exist [2025-12-04T09:02:35.364Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1132.303 ms) ====== [2025-12-04T09:02:35.364Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-12-04T09:02:35.364Z] GC before operation: completed in 111.240 ms, heap usage 295.983 MB -> 90.414 MB. [2025-12-04T09:02:35.364Z] 09:02:34.751 WARN [block-manager-storage-async-thread-pool-20] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_485, which does not exist [2025-12-04T09:02:36.923Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1324.088 ms) ====== [2025-12-04T09:02:36.923Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-12-04T09:02:36.923Z] GC before operation: completed in 114.462 ms, heap usage 259.602 MB -> 96.088 MB. [2025-12-04T09:02:37.705Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1188.016 ms) ====== [2025-12-04T09:02:37.705Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-12-04T09:02:37.705Z] GC before operation: completed in 121.614 ms, heap usage 236.662 MB -> 85.042 MB. [2025-12-04T09:02:39.260Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1341.993 ms) ====== [2025-12-04T09:02:39.260Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-12-04T09:02:39.260Z] GC before operation: completed in 117.573 ms, heap usage 446.057 MB -> 101.873 MB. [2025-12-04T09:02:40.829Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1250.331 ms) ====== [2025-12-04T09:02:40.829Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-12-04T09:02:40.829Z] GC before operation: completed in 111.211 ms, heap usage 311.172 MB -> 90.968 MB. [2025-12-04T09:02:40.829Z] 09:02:40.322 WARN [block-manager-storage-async-thread-pool-93] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_541_piece0, which does not exist [2025-12-04T09:02:42.386Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1258.416 ms) ====== [2025-12-04T09:02:42.386Z] ----------------------------------- [2025-12-04T09:02:42.386Z] renaissance-dec-tree_0_PASSED [2025-12-04T09:02:42.386Z] ----------------------------------- [2025-12-04T09:02:42.386Z] [2025-12-04T09:02:42.386Z] TEST TEARDOWN: [2025-12-04T09:02:42.386Z] Nothing to be done for teardown. [2025-12-04T09:02:42.386Z] renaissance-dec-tree_0 Finish Time: Thu Dec 4 09:02:41 2025 Epoch Time (ms): 1764838961890