[2025-11-20T17:00:32.473Z] Running test renaissance-dec-tree_0 ... [2025-11-20T17:00:32.473Z] =============================================== [2025-11-20T17:00:32.473Z] renaissance-dec-tree_0 Start Time: Thu Nov 20 17:00:32 2025 Epoch Time (ms): 1763658032441 [2025-11-20T17:00:32.817Z] variation: NoOptions [2025-11-20T17:00:32.817Z] JVM_OPTIONS: [2025-11-20T17:00:32.817Z] { \ [2025-11-20T17:00:32.817Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T17:00:32.817Z] echo "Nothing to be done for setup."; \ [2025-11-20T17:00:32.817Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\renaissance-dec-tree_0"; \ [2025-11-20T17:00:32.817Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\renaissance-dec-tree_0"; \ [2025-11-20T17:00:32.817Z] echo ""; echo "TESTING:"; \ [2025-11-20T17:00:32.817Z] "c:/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-11-20T17:00:32.817Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T17:00:32.817Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T17:00:32.817Z] echo "Nothing to be done for teardown."; \ [2025-11-20T17:00:32.817Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17636570004825\\TestTargetResult"; [2025-11-20T17:00:32.817Z] [2025-11-20T17:00:32.817Z] TEST SETUP: [2025-11-20T17:00:32.817Z] Nothing to be done for setup. [2025-11-20T17:00:32.817Z] [2025-11-20T17:00:32.817Z] TESTING: [2025-11-20T17:00:48.677Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-20T17:00:49.024Z] WARNING: An illegal reflective access operation has occurred [2025-11-20T17:00:49.024Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17636570004825/renaissance-dec-tree_0/harness-170033-9606691071235002696/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-20T17:00:49.024Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-20T17:00:49.024Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-20T17:00:49.024Z] WARNING: All illegal access operations will be denied in a future release [2025-11-20T17:00:50.739Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-11-20T17:00:50.739Z] GC before operation: completed in 55.259 ms, heap usage 77.166 MB -> 34.892 MB. [2025-11-20T17:01:04.124Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11566.480 ms) ====== [2025-11-20T17:01:04.124Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-11-20T17:01:04.124Z] GC before operation: completed in 119.518 ms, heap usage 394.259 MB -> 68.944 MB. [2025-11-20T17:01:05.398Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3009.942 ms) ====== [2025-11-20T17:01:05.398Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-11-20T17:01:05.398Z] GC before operation: completed in 106.661 ms, heap usage 149.073 MB -> 69.089 MB. [2025-11-20T17:01:07.716Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2247.377 ms) ====== [2025-11-20T17:01:07.716Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-11-20T17:01:07.716Z] GC before operation: completed in 106.867 ms, heap usage 254.008 MB -> 69.517 MB. [2025-11-20T17:01:09.709Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1937.824 ms) ====== [2025-11-20T17:01:09.709Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-11-20T17:01:09.710Z] GC before operation: completed in 107.975 ms, heap usage 147.621 MB -> 69.572 MB. [2025-11-20T17:01:12.038Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2105.927 ms) ====== [2025-11-20T17:01:12.038Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-11-20T17:01:12.038Z] GC before operation: completed in 141.932 ms, heap usage 111.474 MB -> 80.763 MB. [2025-11-20T17:01:14.008Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1932.071 ms) ====== [2025-11-20T17:01:14.008Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-11-20T17:01:14.008Z] GC before operation: completed in 120.943 ms, heap usage 296.240 MB -> 81.304 MB. [2025-11-20T17:01:15.882Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1857.501 ms) ====== [2025-11-20T17:01:15.882Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-11-20T17:01:16.228Z] GC before operation: completed in 133.616 ms, heap usage 351.302 MB -> 81.322 MB. [2025-11-20T17:01:17.954Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1675.959 ms) ====== [2025-11-20T17:01:17.954Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-11-20T17:01:17.954Z] GC before operation: completed in 117.391 ms, heap usage 143.653 MB -> 70.349 MB. [2025-11-20T17:01:19.364Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1590.877 ms) ====== [2025-11-20T17:01:19.364Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-11-20T17:01:19.716Z] GC before operation: completed in 124.893 ms, heap usage 209.543 MB -> 70.465 MB. [2025-11-20T17:01:21.124Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1637.647 ms) ====== [2025-11-20T17:01:21.124Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-11-20T17:01:21.467Z] GC before operation: completed in 125.206 ms, heap usage 250.109 MB -> 70.581 MB. [2025-11-20T17:01:23.211Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1509.085 ms) ====== [2025-11-20T17:01:23.211Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-11-20T17:01:23.211Z] GC before operation: completed in 133.921 ms, heap usage 262.165 MB -> 87.231 MB. [2025-11-20T17:01:23.211Z] 17:01:22.786 WARN [block-manager-storage-async-thread-pool-22] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_149, which does not exist [2025-11-20T17:01:24.380Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1462.314 ms) ====== [2025-11-20T17:01:24.380Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-11-20T17:01:24.732Z] GC before operation: completed in 133.418 ms, heap usage 275.133 MB -> 81.720 MB. [2025-11-20T17:01:26.420Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1559.104 ms) ====== [2025-11-20T17:01:26.420Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-11-20T17:01:26.420Z] GC before operation: completed in 160.973 ms, heap usage 271.870 MB -> 81.839 MB. [2025-11-20T17:01:28.202Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1499.536 ms) ====== [2025-11-20T17:01:28.202Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-11-20T17:01:28.202Z] GC before operation: completed in 129.383 ms, heap usage 256.343 MB -> 81.953 MB. [2025-11-20T17:01:29.374Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1448.322 ms) ====== [2025-11-20T17:01:29.374Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-11-20T17:01:29.375Z] GC before operation: completed in 135.371 ms, heap usage 318.644 MB -> 82.232 MB. [2025-11-20T17:01:31.099Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1488.125 ms) ====== [2025-11-20T17:01:31.099Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-11-20T17:01:31.099Z] GC before operation: completed in 150.729 ms, heap usage 267.078 MB -> 71.497 MB. [2025-11-20T17:01:32.804Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1482.181 ms) ====== [2025-11-20T17:01:32.804Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-11-20T17:01:32.804Z] GC before operation: completed in 165.101 ms, heap usage 355.011 MB -> 82.564 MB. [2025-11-20T17:01:34.556Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1493.359 ms) ====== [2025-11-20T17:01:34.556Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-11-20T17:01:34.556Z] GC before operation: completed in 149.269 ms, heap usage 129.076 MB -> 71.256 MB. [2025-11-20T17:01:36.227Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1623.029 ms) ====== [2025-11-20T17:01:36.227Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-11-20T17:01:36.227Z] GC before operation: completed in 153.086 ms, heap usage 174.794 MB -> 82.428 MB. [2025-11-20T17:01:37.894Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1495.450 ms) ====== [2025-11-20T17:01:37.894Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-11-20T17:01:37.894Z] GC before operation: completed in 146.922 ms, heap usage 306.949 MB -> 93.933 MB. [2025-11-20T17:01:39.564Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1396.111 ms) ====== [2025-11-20T17:01:39.564Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-11-20T17:01:39.564Z] GC before operation: completed in 144.031 ms, heap usage 307.911 MB -> 88.400 MB. [2025-11-20T17:01:40.775Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1436.590 ms) ====== [2025-11-20T17:01:40.775Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-11-20T17:01:41.120Z] GC before operation: completed in 152.638 ms, heap usage 201.355 MB -> 71.713 MB. [2025-11-20T17:01:42.860Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1602.246 ms) ====== [2025-11-20T17:01:42.860Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-11-20T17:01:42.860Z] GC before operation: completed in 148.010 ms, heap usage 261.322 MB -> 83.191 MB. [2025-11-20T17:01:44.524Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1488.688 ms) ====== [2025-11-20T17:01:44.524Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-11-20T17:01:44.524Z] GC before operation: completed in 147.821 ms, heap usage 233.267 MB -> 83.340 MB. [2025-11-20T17:01:45.775Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1445.770 ms) ====== [2025-11-20T17:01:45.775Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-11-20T17:01:46.131Z] GC before operation: completed in 150.017 ms, heap usage 238.823 MB -> 88.842 MB. [2025-11-20T17:01:47.291Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1344.125 ms) ====== [2025-11-20T17:01:47.291Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-11-20T17:01:47.642Z] GC before operation: completed in 145.356 ms, heap usage 171.812 MB -> 83.002 MB. [2025-11-20T17:01:48.812Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1379.341 ms) ====== [2025-11-20T17:01:48.812Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-11-20T17:01:49.158Z] GC before operation: completed in 144.323 ms, heap usage 219.218 MB -> 83.783 MB. [2025-11-20T17:01:49.159Z] 17:01:48.796 WARN [block-manager-storage-async-thread-pool-39] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_373, which does not exist [2025-11-20T17:01:50.358Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1433.272 ms) ====== [2025-11-20T17:01:50.358Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-11-20T17:01:50.709Z] GC before operation: completed in 148.616 ms, heap usage 196.274 MB -> 83.387 MB. [2025-11-20T17:01:51.941Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1444.502 ms) ====== [2025-11-20T17:01:51.941Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-11-20T17:01:52.282Z] GC before operation: completed in 147.276 ms, heap usage 295.261 MB -> 94.935 MB. [2025-11-20T17:01:53.472Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1341.090 ms) ====== [2025-11-20T17:01:53.472Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-11-20T17:01:53.824Z] GC before operation: completed in 146.651 ms, heap usage 283.273 MB -> 100.471 MB. [2025-11-20T17:01:55.018Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1349.633 ms) ====== [2025-11-20T17:01:55.018Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-11-20T17:01:55.018Z] GC before operation: completed in 147.308 ms, heap usage 288.697 MB -> 89.450 MB. [2025-11-20T17:01:56.693Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1471.234 ms) ====== [2025-11-20T17:01:56.693Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-11-20T17:01:56.694Z] GC before operation: completed in 168.024 ms, heap usage 289.561 MB -> 89.559 MB. [2025-11-20T17:01:58.360Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1411.499 ms) ====== [2025-11-20T17:01:58.360Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-11-20T17:01:58.360Z] GC before operation: completed in 142.608 ms, heap usage 259.731 MB -> 89.878 MB. [2025-11-20T17:01:58.360Z] 17:01:58.151 WARN [block-manager-storage-async-thread-pool-47] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_457, which does not exist [2025-11-20T17:02:00.022Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1434.032 ms) ====== [2025-11-20T17:02:00.022Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-11-20T17:02:00.022Z] GC before operation: completed in 174.194 ms, heap usage 328.317 MB -> 89.796 MB. [2025-11-20T17:02:00.022Z] 17:01:59.760 WARN [block-manager-storage-async-thread-pool-29] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1629_0, which does not exist [2025-11-20T17:02:01.398Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1515.680 ms) ====== [2025-11-20T17:02:01.398Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-11-20T17:02:01.776Z] GC before operation: completed in 166.893 ms, heap usage 244.592 MB -> 73.381 MB. [2025-11-20T17:02:03.021Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1446.731 ms) ====== [2025-11-20T17:02:03.021Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-11-20T17:02:03.370Z] GC before operation: completed in 158.540 ms, heap usage 212.371 MB -> 84.591 MB. [2025-11-20T17:02:04.602Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1408.699 ms) ====== [2025-11-20T17:02:04.602Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-11-20T17:02:04.946Z] GC before operation: completed in 154.798 ms, heap usage 297.703 MB -> 89.913 MB. [2025-11-20T17:02:06.230Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1438.835 ms) ====== [2025-11-20T17:02:06.230Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-11-20T17:02:06.587Z] GC before operation: completed in 168.898 ms, heap usage 236.957 MB -> 95.651 MB. [2025-11-20T17:02:07.750Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1291.486 ms) ====== [2025-11-20T17:02:07.750Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-11-20T17:02:07.750Z] GC before operation: completed in 147.607 ms, heap usage 169.264 MB -> 84.892 MB. [2025-11-20T17:02:09.421Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1341.641 ms) ====== [2025-11-20T17:02:09.421Z] ----------------------------------- [2025-11-20T17:02:09.421Z] renaissance-dec-tree_0_PASSED [2025-11-20T17:02:09.421Z] ----------------------------------- [2025-11-20T17:02:09.751Z] [2025-11-20T17:02:09.751Z] TEST TEARDOWN: [2025-11-20T17:02:09.751Z] Nothing to be done for teardown. [2025-11-20T17:02:09.751Z] renaissance-dec-tree_0 Finish Time: Thu Nov 20 17:02:09 2025 Epoch Time (ms): 1763658129705