[2025-06-19T11:17:00.341Z] Running test renaissance-dec-tree_0 ... [2025-06-19T11:17:00.652Z] =============================================== [2025-06-19T11:17:00.652Z] renaissance-dec-tree_0 Start Time: Thu Jun 19 11:17:00 2025 Epoch Time (ms): 1750331820447 [2025-06-19T11:17:00.652Z] variation: NoOptions [2025-06-19T11:17:00.978Z] JVM_OPTIONS: [2025-06-19T11:17:00.978Z] { \ [2025-06-19T11:17:00.978Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T11:17:00.978Z] echo "Nothing to be done for setup."; \ [2025-06-19T11:17:00.978Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-dec-tree_0"; \ [2025-06-19T11:17:00.978Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-dec-tree_0"; \ [2025-06-19T11:17:00.978Z] echo ""; echo "TESTING:"; \ [2025-06-19T11:17:00.978Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-06-19T11:17:00.978Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T11:17:00.978Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T11:17:00.978Z] echo "Nothing to be done for teardown."; \ [2025-06-19T11:17:00.978Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\TestTargetResult"; [2025-06-19T11:17:00.978Z] [2025-06-19T11:17:00.978Z] TEST SETUP: [2025-06-19T11:17:00.978Z] Nothing to be done for setup. [2025-06-19T11:17:00.978Z] [2025-06-19T11:17:00.978Z] TESTING: [2025-06-19T11:17:13.892Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-19T11:17:15.498Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-19T11:17:15.498Z] GC before operation: completed in 39.865 ms, heap usage 54.259 MB -> 35.392 MB. [2025-06-19T11:17:26.342Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9366.038 ms) ====== [2025-06-19T11:17:26.342Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-19T11:17:26.342Z] GC before operation: completed in 73.446 ms, heap usage 152.845 MB -> 69.182 MB. [2025-06-19T11:17:27.622Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2581.052 ms) ====== [2025-06-19T11:17:27.622Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-19T11:17:27.622Z] GC before operation: completed in 77.344 ms, heap usage 245.684 MB -> 69.891 MB. [2025-06-19T11:17:29.339Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2006.708 ms) ====== [2025-06-19T11:17:29.339Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-19T11:17:29.339Z] GC before operation: completed in 66.805 ms, heap usage 148.321 MB -> 69.841 MB. [2025-06-19T11:17:31.676Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1858.966 ms) ====== [2025-06-19T11:17:31.676Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-19T11:17:31.676Z] GC before operation: completed in 86.050 ms, heap usage 108.097 MB -> 69.780 MB. [2025-06-19T11:17:33.392Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1733.336 ms) ====== [2025-06-19T11:17:33.392Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-19T11:17:33.392Z] GC before operation: completed in 90.602 ms, heap usage 226.113 MB -> 81.715 MB. [2025-06-19T11:17:35.052Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1531.972 ms) ====== [2025-06-19T11:17:35.052Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-19T11:17:35.052Z] GC before operation: completed in 81.129 ms, heap usage 250.115 MB -> 81.892 MB. [2025-06-19T11:17:36.694Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1512.014 ms) ====== [2025-06-19T11:17:36.694Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-19T11:17:36.694Z] GC before operation: completed in 79.981 ms, heap usage 260.208 MB -> 82.220 MB. [2025-06-19T11:17:37.818Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1444.827 ms) ====== [2025-06-19T11:17:37.818Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-19T11:17:38.150Z] GC before operation: completed in 80.603 ms, heap usage 250.964 MB -> 82.294 MB. [2025-06-19T11:17:39.780Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1481.381 ms) ====== [2025-06-19T11:17:39.780Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-19T11:17:39.780Z] GC before operation: completed in 82.442 ms, heap usage 247.999 MB -> 88.090 MB. [2025-06-19T11:17:40.969Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1381.771 ms) ====== [2025-06-19T11:17:40.969Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-19T11:17:40.969Z] GC before operation: completed in 84.860 ms, heap usage 487.510 MB -> 88.043 MB. [2025-06-19T11:17:42.657Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1408.373 ms) ====== [2025-06-19T11:17:42.657Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-19T11:17:42.657Z] GC before operation: completed in 88.355 ms, heap usage 223.864 MB -> 88.117 MB. [2025-06-19T11:17:43.816Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1341.629 ms) ====== [2025-06-19T11:17:43.816Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-19T11:17:44.155Z] GC before operation: completed in 116.495 ms, heap usage 482.379 MB -> 71.812 MB. [2025-06-19T11:17:45.313Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1421.425 ms) ====== [2025-06-19T11:17:45.313Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-19T11:17:45.646Z] GC before operation: completed in 93.195 ms, heap usage 365.920 MB -> 83.163 MB. [2025-06-19T11:17:46.769Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1349.122 ms) ====== [2025-06-19T11:17:46.769Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-19T11:17:47.096Z] GC before operation: completed in 90.682 ms, heap usage 188.617 MB -> 82.589 MB. [2025-06-19T11:17:48.216Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1391.168 ms) ====== [2025-06-19T11:17:48.216Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-19T11:17:48.540Z] GC before operation: completed in 89.073 ms, heap usage 117.399 MB -> 71.453 MB. [2025-06-19T11:17:49.694Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1382.211 ms) ====== [2025-06-19T11:17:49.694Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-19T11:17:50.020Z] GC before operation: completed in 92.218 ms, heap usage 514.051 MB -> 72.706 MB. [2025-06-19T11:17:51.140Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1268.843 ms) ====== [2025-06-19T11:17:51.140Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-19T11:17:51.140Z] GC before operation: completed in 89.408 ms, heap usage 350.773 MB -> 94.426 MB. [2025-06-19T11:17:52.785Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1326.210 ms) ====== [2025-06-19T11:17:52.785Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-19T11:17:52.785Z] GC before operation: completed in 97.633 ms, heap usage 399.550 MB -> 89.096 MB. [2025-06-19T11:17:53.925Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1338.193 ms) ====== [2025-06-19T11:17:53.925Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-19T11:17:54.281Z] GC before operation: completed in 99.890 ms, heap usage 447.900 MB -> 94.585 MB. [2025-06-19T11:17:55.389Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1318.299 ms) ====== [2025-06-19T11:17:55.389Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-19T11:17:55.389Z] GC before operation: completed in 95.237 ms, heap usage 390.833 MB -> 72.783 MB. [2025-06-19T11:17:56.986Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1275.930 ms) ====== [2025-06-19T11:17:56.986Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-19T11:17:56.986Z] GC before operation: completed in 93.554 ms, heap usage 329.458 MB -> 89.456 MB. [2025-06-19T11:17:58.091Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1254.418 ms) ====== [2025-06-19T11:17:58.091Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-19T11:17:58.091Z] GC before operation: completed in 98.067 ms, heap usage 437.057 MB -> 89.520 MB. [2025-06-19T11:17:59.718Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1278.355 ms) ====== [2025-06-19T11:17:59.718Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-19T11:17:59.718Z] GC before operation: completed in 98.844 ms, heap usage 435.643 MB -> 89.576 MB. [2025-06-19T11:18:00.823Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1285.557 ms) ====== [2025-06-19T11:18:00.823Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-19T11:18:01.150Z] GC before operation: completed in 98.543 ms, heap usage 460.301 MB -> 89.889 MB. [2025-06-19T11:18:01.150Z] 11:18:00.819 WARN [block-manager-storage-async-thread-pool-27] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1149_2, which does not exist [2025-06-19T11:18:02.311Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1285.196 ms) ====== [2025-06-19T11:18:02.311Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-19T11:18:02.311Z] GC before operation: completed in 98.966 ms, heap usage 448.791 MB -> 89.991 MB. [2025-06-19T11:18:03.622Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1329.639 ms) ====== [2025-06-19T11:18:03.622Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-19T11:18:03.950Z] GC before operation: completed in 101.696 ms, heap usage 441.068 MB -> 89.907 MB. [2025-06-19T11:18:05.101Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1348.799 ms) ====== [2025-06-19T11:18:05.101Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-19T11:18:05.430Z] GC before operation: completed in 102.204 ms, heap usage 430.313 MB -> 90.223 MB. [2025-06-19T11:18:06.535Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1231.287 ms) ====== [2025-06-19T11:18:06.535Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-19T11:18:06.535Z] GC before operation: completed in 100.747 ms, heap usage 418.152 MB -> 90.116 MB. [2025-06-19T11:18:08.176Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1279.463 ms) ====== [2025-06-19T11:18:08.176Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-19T11:18:08.176Z] GC before operation: completed in 119.222 ms, heap usage 409.124 MB -> 95.668 MB. [2025-06-19T11:18:09.297Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1315.624 ms) ====== [2025-06-19T11:18:09.297Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-19T11:18:09.297Z] GC before operation: completed in 106.740 ms, heap usage 378.799 MB -> 90.530 MB. [2025-06-19T11:18:10.928Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1234.804 ms) ====== [2025-06-19T11:18:10.928Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-19T11:18:10.928Z] GC before operation: completed in 104.752 ms, heap usage 416.732 MB -> 90.670 MB. [2025-06-19T11:18:12.160Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1291.701 ms) ====== [2025-06-19T11:18:12.160Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-19T11:18:12.160Z] GC before operation: completed in 101.539 ms, heap usage 408.273 MB -> 90.617 MB. [2025-06-19T11:18:13.326Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1266.951 ms) ====== [2025-06-19T11:18:13.326Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-19T11:18:13.710Z] GC before operation: completed in 101.444 ms, heap usage 411.839 MB -> 90.728 MB. [2025-06-19T11:18:13.710Z] 11:18:13.342 WARN [block-manager-storage-async-thread-pool-44] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1581_2, which does not exist [2025-06-19T11:18:14.813Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1233.779 ms) ====== [2025-06-19T11:18:14.813Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-19T11:18:14.813Z] GC before operation: completed in 102.227 ms, heap usage 414.129 MB -> 90.997 MB. [2025-06-19T11:18:14.813Z] 11:18:14.679 WARN [block-manager-storage-async-thread-pool-22] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1629_1, which does not exist [2025-06-19T11:18:14.813Z] 11:18:14.679 WARN [block-manager-storage-async-thread-pool-22] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1629_2, which does not exist [2025-06-19T11:18:16.539Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1329.596 ms) ====== [2025-06-19T11:18:16.539Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-19T11:18:16.539Z] GC before operation: completed in 107.537 ms, heap usage 395.627 MB -> 91.138 MB. [2025-06-19T11:18:17.708Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1273.381 ms) ====== [2025-06-19T11:18:17.708Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-19T11:18:17.708Z] GC before operation: completed in 103.865 ms, heap usage 416.259 MB -> 96.468 MB. [2025-06-19T11:18:19.801Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1322.019 ms) ====== [2025-06-19T11:18:19.801Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-19T11:18:19.801Z] GC before operation: completed in 102.136 ms, heap usage 363.385 MB -> 91.111 MB. [2025-06-19T11:18:20.486Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1363.564 ms) ====== [2025-06-19T11:18:20.486Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-19T11:18:20.486Z] GC before operation: completed in 104.015 ms, heap usage 422.786 MB -> 91.203 MB. [2025-06-19T11:18:20.486Z] 11:18:20.387 WARN [block-manager-storage-async-thread-pool-80] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_527_piece0, which does not exist [2025-06-19T11:18:21.746Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1258.869 ms) ====== [2025-06-19T11:18:21.746Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-19T11:18:22.076Z] GC before operation: completed in 105.605 ms, heap usage 426.506 MB -> 91.342 MB. [2025-06-19T11:18:23.181Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1217.716 ms) ====== [2025-06-19T11:18:23.491Z] ----------------------------------- [2025-06-19T11:18:23.491Z] renaissance-dec-tree_0_PASSED [2025-06-19T11:18:23.491Z] ----------------------------------- [2025-06-19T11:18:23.491Z] [2025-06-19T11:18:23.491Z] TEST TEARDOWN: [2025-06-19T11:18:23.491Z] Nothing to be done for teardown. [2025-06-19T11:18:23.801Z] renaissance-dec-tree_0 Finish Time: Thu Jun 19 11:18:23 2025 Epoch Time (ms): 1750331903496