[2025-08-02T03:44:41.404Z] Running test renaissance-dec-tree_0 ... [2025-08-02T03:44:41.728Z] =============================================== [2025-08-02T03:44:41.728Z] renaissance-dec-tree_0 Start Time: Sat Aug 2 03:44:41 2025 Epoch Time (ms): 1754106281509 [2025-08-02T03:44:41.728Z] variation: NoOptions [2025-08-02T03:44:41.728Z] JVM_OPTIONS: [2025-08-02T03:44:41.728Z] { \ [2025-08-02T03:44:41.728Z] echo ""; echo "TEST SETUP:"; \ [2025-08-02T03:44:41.728Z] echo "Nothing to be done for setup."; \ [2025-08-02T03:44:41.728Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-dec-tree_0"; \ [2025-08-02T03:44:41.728Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-dec-tree_0"; \ [2025-08-02T03:44:41.728Z] echo ""; echo "TESTING:"; \ [2025-08-02T03:44:41.728Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/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_rerun/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-08-02T03:44:41.728Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-02T03:44:41.728Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-02T03:44:41.728Z] echo "Nothing to be done for teardown."; \ [2025-08-02T03:44:41.728Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\TestTargetResult"; [2025-08-02T03:44:42.051Z] [2025-08-02T03:44:42.051Z] TEST SETUP: [2025-08-02T03:44:42.051Z] Nothing to be done for setup. [2025-08-02T03:44:42.051Z] [2025-08-02T03:44:42.051Z] TESTING: [2025-08-02T03:45:00.898Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-02T03:45:00.898Z] WARNING: An illegal reflective access operation has occurred [2025-08-02T03:45:00.898Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/TKG/output_17541054958678/renaissance-dec-tree_0/harness-034442-7879017064110801990/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-02T03:45:00.898Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-02T03:45:00.898Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-02T03:45:00.898Z] WARNING: All illegal access operations will be denied in a future release [2025-08-02T03:45:02.000Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-02T03:45:02.328Z] GC before operation: completed in 61.094 ms, heap usage 62.297 MB -> 34.867 MB. [2025-08-02T03:45:15.508Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (12161.619 ms) ====== [2025-08-02T03:45:15.508Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-02T03:45:15.508Z] GC before operation: completed in 163.731 ms, heap usage 342.003 MB -> 96.294 MB. [2025-08-02T03:45:17.664Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2965.556 ms) ====== [2025-08-02T03:45:17.664Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-02T03:45:17.664Z] GC before operation: completed in 181.650 ms, heap usage 263.997 MB -> 96.723 MB. [2025-08-02T03:45:20.673Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2573.979 ms) ====== [2025-08-02T03:45:20.673Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-02T03:45:20.673Z] GC before operation: completed in 152.148 ms, heap usage 162.540 MB -> 69.368 MB. [2025-08-02T03:45:22.547Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2229.244 ms) ====== [2025-08-02T03:45:22.547Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-02T03:45:22.875Z] GC before operation: completed in 119.756 ms, heap usage 104.943 MB -> 69.436 MB. [2025-08-02T03:45:24.677Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1994.777 ms) ====== [2025-08-02T03:45:24.677Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-02T03:45:24.985Z] GC before operation: completed in 133.280 ms, heap usage 157.616 MB -> 69.819 MB. [2025-08-02T03:45:26.792Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1994.866 ms) ====== [2025-08-02T03:45:26.792Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-02T03:45:27.135Z] GC before operation: completed in 117.548 ms, heap usage 144.068 MB -> 70.178 MB. [2025-08-02T03:45:28.844Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1863.478 ms) ====== [2025-08-02T03:45:28.844Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-02T03:45:28.844Z] GC before operation: completed in 118.504 ms, heap usage 277.600 MB -> 81.355 MB. [2025-08-02T03:45:31.022Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1732.124 ms) ====== [2025-08-02T03:45:31.022Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-02T03:45:31.022Z] GC before operation: completed in 119.438 ms, heap usage 284.868 MB -> 81.245 MB. [2025-08-02T03:45:32.615Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1813.471 ms) ====== [2025-08-02T03:45:32.615Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-02T03:45:32.615Z] GC before operation: completed in 121.082 ms, heap usage 260.476 MB -> 86.986 MB. [2025-08-02T03:45:34.784Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1748.215 ms) ====== [2025-08-02T03:45:34.784Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-02T03:45:34.784Z] GC before operation: completed in 159.240 ms, heap usage 194.994 MB -> 70.291 MB. [2025-08-02T03:45:36.389Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1740.191 ms) ====== [2025-08-02T03:45:36.389Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-02T03:45:36.389Z] GC before operation: completed in 130.309 ms, heap usage 108.988 MB -> 75.980 MB. [2025-08-02T03:45:38.558Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1671.813 ms) ====== [2025-08-02T03:45:38.558Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-02T03:45:38.558Z] GC before operation: completed in 127.392 ms, heap usage 176.402 MB -> 81.419 MB. [2025-08-02T03:45:40.154Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1701.353 ms) ====== [2025-08-02T03:45:40.154Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-02T03:45:40.154Z] GC before operation: completed in 134.072 ms, heap usage 310.931 MB -> 81.823 MB. [2025-08-02T03:45:42.416Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1770.630 ms) ====== [2025-08-02T03:45:42.416Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-02T03:45:42.416Z] GC before operation: completed in 151.424 ms, heap usage 396.823 MB -> 81.943 MB. [2025-08-02T03:45:44.038Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1716.330 ms) ====== [2025-08-02T03:45:44.038Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-02T03:45:44.038Z] GC before operation: completed in 140.272 ms, heap usage 214.624 MB -> 71.177 MB. [2025-08-02T03:45:45.681Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1674.886 ms) ====== [2025-08-02T03:45:45.681Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-02T03:45:45.681Z] GC before operation: completed in 138.742 ms, heap usage 153.748 MB -> 71.490 MB. [2025-08-02T03:45:47.380Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1679.689 ms) ====== [2025-08-02T03:45:47.380Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-02T03:45:47.703Z] GC before operation: completed in 135.128 ms, heap usage 272.377 MB -> 82.496 MB. [2025-08-02T03:45:47.703Z] 03:45:47.399 WARN [block-manager-storage-async-thread-pool-40] org.apache.spark.storage.BlockManager - Asked to remove block rdd_813_1, which does not exist [2025-08-02T03:45:49.301Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1689.082 ms) ====== [2025-08-02T03:45:49.301Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-02T03:45:49.301Z] GC before operation: completed in 137.608 ms, heap usage 285.592 MB -> 88.102 MB. [2025-08-02T03:45:51.504Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1751.655 ms) ====== [2025-08-02T03:45:51.504Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-02T03:45:51.504Z] GC before operation: completed in 162.880 ms, heap usage 442.915 MB -> 93.639 MB. [2025-08-02T03:45:53.099Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1653.245 ms) ====== [2025-08-02T03:45:53.099Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-02T03:45:53.099Z] GC before operation: completed in 144.084 ms, heap usage 163.991 MB -> 71.794 MB. [2025-08-02T03:45:54.847Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1787.434 ms) ====== [2025-08-02T03:45:54.847Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-02T03:45:55.173Z] GC before operation: completed in 150.511 ms, heap usage 227.879 MB -> 88.417 MB. [2025-08-02T03:45:56.776Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1653.987 ms) ====== [2025-08-02T03:45:56.776Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-02T03:45:56.776Z] GC before operation: completed in 164.971 ms, heap usage 125.065 MB -> 71.408 MB. [2025-08-02T03:45:59.056Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1722.878 ms) ====== [2025-08-02T03:45:59.056Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-02T03:45:59.056Z] GC before operation: completed in 142.467 ms, heap usage 161.160 MB -> 72.131 MB. [2025-08-02T03:46:00.646Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1634.767 ms) ====== [2025-08-02T03:46:00.646Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-02T03:46:00.646Z] GC before operation: completed in 144.905 ms, heap usage 243.493 MB -> 83.129 MB. [2025-08-02T03:46:02.254Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1562.794 ms) ====== [2025-08-02T03:46:02.254Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-02T03:46:02.254Z] GC before operation: completed in 143.633 ms, heap usage 266.039 MB -> 88.853 MB. [2025-08-02T03:46:03.839Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1635.682 ms) ====== [2025-08-02T03:46:03.839Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-02T03:46:04.164Z] GC before operation: completed in 167.544 ms, heap usage 446.087 MB -> 88.959 MB. [2025-08-02T03:46:05.754Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1602.807 ms) ====== [2025-08-02T03:46:05.754Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-02T03:46:05.754Z] GC before operation: completed in 158.264 ms, heap usage 145.869 MB -> 72.255 MB. [2025-08-02T03:46:07.507Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1777.185 ms) ====== [2025-08-02T03:46:07.507Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-02T03:46:07.834Z] GC before operation: completed in 161.864 ms, heap usage 268.794 MB -> 83.526 MB. [2025-08-02T03:46:09.430Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1662.745 ms) ====== [2025-08-02T03:46:09.430Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-02T03:46:09.430Z] GC before operation: completed in 166.700 ms, heap usage 213.097 MB -> 83.636 MB. [2025-08-02T03:46:11.054Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1541.935 ms) ====== [2025-08-02T03:46:11.054Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-02T03:46:11.378Z] GC before operation: completed in 147.691 ms, heap usage 248.070 MB -> 83.939 MB. [2025-08-02T03:46:12.967Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1616.201 ms) ====== [2025-08-02T03:46:12.967Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-02T03:46:12.967Z] GC before operation: completed in 169.530 ms, heap usage 330.905 MB -> 89.449 MB. [2025-08-02T03:46:14.580Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1579.709 ms) ====== [2025-08-02T03:46:14.580Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-02T03:46:14.905Z] GC before operation: completed in 145.498 ms, heap usage 272.259 MB -> 83.941 MB. [2025-08-02T03:46:16.496Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1607.089 ms) ====== [2025-08-02T03:46:16.496Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-02T03:46:16.496Z] GC before operation: completed in 178.298 ms, heap usage 121.858 MB -> 89.067 MB. [2025-08-02T03:46:18.379Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1739.213 ms) ====== [2025-08-02T03:46:18.379Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-02T03:46:18.379Z] GC before operation: completed in 167.305 ms, heap usage 266.856 MB -> 84.152 MB. [2025-08-02T03:46:20.073Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1659.291 ms) ====== [2025-08-02T03:46:20.073Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-02T03:46:20.399Z] GC before operation: completed in 168.584 ms, heap usage 231.321 MB -> 73.367 MB. [2025-08-02T03:46:22.091Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1742.119 ms) ====== [2025-08-02T03:46:22.091Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-02T03:46:22.091Z] GC before operation: completed in 155.594 ms, heap usage 112.185 MB -> 73.156 MB. [2025-08-02T03:46:23.812Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1676.243 ms) ====== [2025-08-02T03:46:23.812Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-02T03:46:24.137Z] GC before operation: completed in 162.595 ms, heap usage 173.970 MB -> 84.156 MB. [2025-08-02T03:46:25.744Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1551.654 ms) ====== [2025-08-02T03:46:25.744Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-02T03:46:25.744Z] GC before operation: completed in 159.383 ms, heap usage 159.118 MB -> 84.239 MB. [2025-08-02T03:46:27.338Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1612.521 ms) ====== [2025-08-02T03:46:27.338Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-02T03:46:27.662Z] GC before operation: completed in 159.578 ms, heap usage 217.569 MB -> 84.673 MB. [2025-08-02T03:46:29.258Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1625.763 ms) ====== [2025-08-02T03:46:29.581Z] ----------------------------------- [2025-08-02T03:46:29.581Z] renaissance-dec-tree_0_PASSED [2025-08-02T03:46:29.581Z] ----------------------------------- [2025-08-02T03:46:29.889Z] [2025-08-02T03:46:29.889Z] TEST TEARDOWN: [2025-08-02T03:46:29.889Z] Nothing to be done for teardown. [2025-08-02T03:46:29.889Z] renaissance-dec-tree_0 Finish Time: Sat Aug 2 03:46:29 2025 Epoch Time (ms): 1754106389697