[2025-06-12T22:03:58.273Z] Running test renaissance-dec-tree_0 ... [2025-06-12T22:03:58.582Z] =============================================== [2025-06-12T22:03:58.582Z] renaissance-dec-tree_0 Start Time: Thu Jun 12 22:03:58 2025 Epoch Time (ms): 1749765838496 [2025-06-12T22:03:58.901Z] variation: NoOptions [2025-06-12T22:03:58.901Z] JVM_OPTIONS: [2025-06-12T22:03:58.901Z] { \ [2025-06-12T22:03:58.901Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T22:03:58.901Z] echo "Nothing to be done for setup."; \ [2025-06-12T22:03:58.901Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497652166408\\renaissance-dec-tree_0"; \ [2025-06-12T22:03:58.901Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497652166408\\renaissance-dec-tree_0"; \ [2025-06-12T22:03:58.901Z] echo ""; echo "TESTING:"; \ [2025-06-12T22:03:58.901Z] "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_17497652166408\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-06-12T22:03:58.901Z] 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_17497652166408\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T22:03:58.901Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T22:03:58.901Z] echo "Nothing to be done for teardown."; \ [2025-06-12T22:03:58.901Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497652166408\\TestTargetResult"; [2025-06-12T22:03:58.901Z] [2025-06-12T22:03:58.901Z] TEST SETUP: [2025-06-12T22:03:58.901Z] Nothing to be done for setup. [2025-06-12T22:03:58.901Z] [2025-06-12T22:03:58.901Z] TESTING: [2025-06-12T22:04:11.760Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-12T22:04:14.598Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-12T22:04:14.598Z] GC before operation: completed in 42.628 ms, heap usage 55.908 MB -> 35.086 MB. [2025-06-12T22:04:25.485Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9485.627 ms) ====== [2025-06-12T22:04:25.485Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-12T22:04:25.485Z] GC before operation: completed in 75.016 ms, heap usage 85.299 MB -> 69.185 MB. [2025-06-12T22:04:26.587Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2644.064 ms) ====== [2025-06-12T22:04:26.587Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-12T22:04:26.587Z] GC before operation: completed in 88.522 ms, heap usage 470.468 MB -> 97.284 MB. [2025-06-12T22:04:28.989Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2188.393 ms) ====== [2025-06-12T22:04:28.989Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-12T22:04:28.989Z] GC before operation: completed in 75.584 ms, heap usage 271.260 MB -> 69.957 MB. [2025-06-12T22:04:31.261Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1798.509 ms) ====== [2025-06-12T22:04:31.261Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-12T22:04:31.261Z] GC before operation: completed in 76.136 ms, heap usage 311.563 MB -> 81.307 MB. [2025-06-12T22:04:32.433Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1671.441 ms) ====== [2025-06-12T22:04:32.433Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-12T22:04:32.771Z] GC before operation: completed in 96.235 ms, heap usage 323.954 MB -> 81.688 MB. [2025-06-12T22:04:34.462Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1580.331 ms) ====== [2025-06-12T22:04:34.462Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-12T22:04:34.462Z] GC before operation: completed in 82.160 ms, heap usage 261.234 MB -> 81.740 MB. [2025-06-12T22:04:36.208Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1558.794 ms) ====== [2025-06-12T22:04:36.208Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-12T22:04:36.208Z] GC before operation: completed in 81.489 ms, heap usage 239.624 MB -> 81.984 MB. [2025-06-12T22:04:37.304Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1450.082 ms) ====== [2025-06-12T22:04:37.304Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-12T22:04:37.304Z] GC before operation: completed in 79.982 ms, heap usage 240.018 MB -> 82.103 MB. [2025-06-12T22:04:38.906Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1468.908 ms) ====== [2025-06-12T22:04:38.906Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-12T22:04:38.906Z] GC before operation: completed in 84.516 ms, heap usage 240.745 MB -> 87.603 MB. [2025-06-12T22:04:40.530Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1389.987 ms) ====== [2025-06-12T22:04:40.530Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-12T22:04:40.530Z] GC before operation: completed in 89.350 ms, heap usage 487.369 MB -> 87.749 MB. [2025-06-12T22:04:42.154Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1451.753 ms) ====== [2025-06-12T22:04:42.154Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-12T22:04:42.154Z] GC before operation: completed in 89.548 ms, heap usage 137.389 MB -> 87.330 MB. [2025-06-12T22:04:43.762Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1396.321 ms) ====== [2025-06-12T22:04:43.762Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-12T22:04:43.762Z] GC before operation: completed in 91.946 ms, heap usage 343.052 MB -> 88.155 MB. [2025-06-12T22:04:44.900Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1389.768 ms) ====== [2025-06-12T22:04:44.900Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-12T22:04:44.900Z] GC before operation: completed in 102.704 ms, heap usage 443.338 MB -> 88.146 MB. [2025-06-12T22:04:46.495Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1378.257 ms) ====== [2025-06-12T22:04:46.495Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-12T22:04:46.495Z] GC before operation: completed in 92.924 ms, heap usage 475.175 MB -> 71.802 MB. [2025-06-12T22:04:48.189Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1370.761 ms) ====== [2025-06-12T22:04:48.189Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-12T22:04:48.189Z] GC before operation: completed in 92.006 ms, heap usage 113.028 MB -> 71.343 MB. [2025-06-12T22:04:49.280Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1388.164 ms) ====== [2025-06-12T22:04:49.280Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-12T22:04:49.280Z] GC before operation: completed in 94.413 ms, heap usage 529.605 MB -> 72.467 MB. [2025-06-12T22:04:50.852Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1257.339 ms) ====== [2025-06-12T22:04:50.852Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-12T22:04:50.852Z] GC before operation: completed in 97.414 ms, heap usage 346.048 MB -> 94.112 MB. [2025-06-12T22:04:52.503Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1329.651 ms) ====== [2025-06-12T22:04:52.503Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-12T22:04:52.503Z] GC before operation: completed in 115.220 ms, heap usage 385.249 MB -> 88.733 MB. [2025-06-12T22:04:53.672Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1383.010 ms) ====== [2025-06-12T22:04:53.672Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-12T22:04:53.672Z] GC before operation: completed in 99.978 ms, heap usage 436.695 MB -> 94.306 MB. [2025-06-12T22:04:55.351Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1444.600 ms) ====== [2025-06-12T22:04:55.351Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-12T22:04:55.351Z] GC before operation: completed in 100.781 ms, heap usage 385.635 MB -> 94.470 MB. [2025-06-12T22:04:56.920Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1422.579 ms) ====== [2025-06-12T22:04:56.920Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-12T22:04:56.920Z] GC before operation: completed in 99.309 ms, heap usage 389.572 MB -> 89.151 MB. [2025-06-12T22:04:58.023Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1322.339 ms) ====== [2025-06-12T22:04:58.023Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-12T22:04:58.347Z] GC before operation: completed in 98.529 ms, heap usage 422.316 MB -> 89.256 MB. [2025-06-12T22:04:59.474Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1345.403 ms) ====== [2025-06-12T22:04:59.474Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-12T22:04:59.796Z] GC before operation: completed in 107.675 ms, heap usage 416.643 MB -> 89.346 MB. [2025-06-12T22:05:00.937Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1368.099 ms) ====== [2025-06-12T22:05:00.937Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-12T22:05:01.261Z] GC before operation: completed in 111.257 ms, heap usage 410.186 MB -> 89.646 MB. [2025-06-12T22:05:02.404Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1341.066 ms) ====== [2025-06-12T22:05:02.404Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-12T22:05:02.726Z] GC before operation: completed in 107.464 ms, heap usage 407.591 MB -> 89.567 MB. [2025-06-12T22:05:02.726Z] 22:05:02.400 WARN [block-manager-storage-async-thread-pool-98] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1197_2, which does not exist [2025-06-12T22:05:03.891Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1385.520 ms) ====== [2025-06-12T22:05:03.891Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-12T22:05:04.213Z] GC before operation: completed in 102.426 ms, heap usage 428.043 MB -> 89.655 MB. [2025-06-12T22:05:04.213Z] 22:05:03.885 WARN [block-manager-storage-async-thread-pool-78] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1245_1, which does not exist [2025-06-12T22:05:05.309Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1260.934 ms) ====== [2025-06-12T22:05:05.309Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-12T22:05:05.309Z] GC before operation: completed in 105.425 ms, heap usage 424.129 MB -> 89.658 MB. [2025-06-12T22:05:06.901Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1267.082 ms) ====== [2025-06-12T22:05:06.901Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-12T22:05:06.901Z] GC before operation: completed in 106.181 ms, heap usage 405.406 MB -> 89.970 MB. [2025-06-12T22:05:08.058Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1338.010 ms) ====== [2025-06-12T22:05:08.058Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-12T22:05:08.381Z] GC before operation: completed in 108.127 ms, heap usage 414.135 MB -> 95.278 MB. [2025-06-12T22:05:09.557Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1369.478 ms) ====== [2025-06-12T22:05:09.557Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-12T22:05:09.885Z] GC before operation: completed in 110.883 ms, heap usage 363.886 MB -> 90.167 MB. [2025-06-12T22:05:10.994Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1270.793 ms) ====== [2025-06-12T22:05:10.994Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-12T22:05:10.994Z] GC before operation: completed in 108.596 ms, heap usage 433.509 MB -> 90.230 MB. [2025-06-12T22:05:12.585Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1358.768 ms) ====== [2025-06-12T22:05:12.585Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-12T22:05:12.585Z] GC before operation: completed in 122.216 ms, heap usage 420.277 MB -> 90.561 MB. [2025-06-12T22:05:14.184Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1281.360 ms) ====== [2025-06-12T22:05:14.184Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-12T22:05:14.184Z] GC before operation: completed in 107.171 ms, heap usage 410.761 MB -> 90.418 MB. [2025-06-12T22:05:15.284Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1290.888 ms) ====== [2025-06-12T22:05:15.284Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-12T22:05:15.284Z] GC before operation: completed in 110.367 ms, heap usage 403.608 MB -> 90.562 MB. [2025-06-12T22:05:16.894Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1405.365 ms) ====== [2025-06-12T22:05:16.894Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-12T22:05:16.894Z] GC before operation: completed in 107.849 ms, heap usage 396.034 MB -> 90.697 MB. [2025-06-12T22:05:16.894Z] 22:05:16.719 WARN [block-manager-storage-async-thread-pool-91] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_485, which does not exist [2025-06-12T22:05:18.483Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1406.094 ms) ====== [2025-06-12T22:05:18.483Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-12T22:05:18.483Z] GC before operation: completed in 108.262 ms, heap usage 430.003 MB -> 96.193 MB. [2025-06-12T22:05:19.684Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1335.131 ms) ====== [2025-06-12T22:05:19.684Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-12T22:05:20.018Z] GC before operation: completed in 111.627 ms, heap usage 362.240 MB -> 90.884 MB. [2025-06-12T22:05:20.018Z] 22:05:19.680 WARN [block-manager-storage-async-thread-pool-33] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_513_piece0, which does not exist [2025-06-12T22:05:21.151Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1318.882 ms) ====== [2025-06-12T22:05:21.151Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-12T22:05:21.151Z] GC before operation: completed in 109.529 ms, heap usage 411.081 MB -> 91.008 MB. [2025-06-12T22:05:22.806Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1315.854 ms) ====== [2025-06-12T22:05:22.806Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-12T22:05:22.806Z] GC before operation: completed in 107.330 ms, heap usage 397.378 MB -> 91.132 MB. [2025-06-12T22:05:23.917Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1254.649 ms) ====== [2025-06-12T22:05:24.239Z] ----------------------------------- [2025-06-12T22:05:24.239Z] renaissance-dec-tree_0_PASSED [2025-06-12T22:05:24.239Z] ----------------------------------- [2025-06-12T22:05:24.560Z] [2025-06-12T22:05:24.560Z] TEST TEARDOWN: [2025-06-12T22:05:24.560Z] Nothing to be done for teardown. [2025-06-12T22:05:24.560Z] renaissance-dec-tree_0 Finish Time: Thu Jun 12 22:05:24 2025 Epoch Time (ms): 1749765924442