[2025-05-17T05:44:02.493Z] Running test renaissance-dec-tree_0 ... [2025-05-17T05:44:02.493Z] =============================================== [2025-05-17T05:44:02.811Z] renaissance-dec-tree_0 Start Time: Sat May 17 05:44:02 2025 Epoch Time (ms): 1747460642543 [2025-05-17T05:44:02.811Z] variation: NoOptions [2025-05-17T05:44:02.811Z] JVM_OPTIONS: [2025-05-17T05:44:03.127Z] { \ [2025-05-17T05:44:03.127Z] echo ""; echo "TEST SETUP:"; \ [2025-05-17T05:44:03.127Z] echo "Nothing to be done for setup."; \ [2025-05-17T05:44:03.127Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747459850736\\renaissance-dec-tree_0"; \ [2025-05-17T05:44:03.127Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747459850736\\renaissance-dec-tree_0"; \ [2025-05-17T05:44:03.127Z] echo ""; echo "TESTING:"; \ [2025-05-17T05:44:03.127Z] "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_1747459850736\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-05-17T05:44:03.127Z] 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_1747459850736\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-17T05:44:03.127Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-17T05:44:03.127Z] echo "Nothing to be done for teardown."; \ [2025-05-17T05:44:03.128Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747459850736\\TestTargetResult"; [2025-05-17T05:44:03.128Z] [2025-05-17T05:44:03.128Z] TEST SETUP: [2025-05-17T05:44:03.128Z] Nothing to be done for setup. [2025-05-17T05:44:03.128Z] [2025-05-17T05:44:03.128Z] TESTING: [2025-05-17T05:44:15.898Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-17T05:44:16.220Z] WARNING: An illegal reflective access operation has occurred [2025-05-17T05:44:16.220Z] 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_1747459850736/renaissance-dec-tree_0/harness-054403-16644333422073806347/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-17T05:44:16.220Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-17T05:44:16.220Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-17T05:44:16.220Z] WARNING: All illegal access operations will be denied in a future release [2025-05-17T05:44:17.794Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-17T05:44:17.794Z] GC before operation: completed in 51.686 ms, heap usage 58.975 MB -> 34.861 MB. [2025-05-17T05:44:28.591Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10392.923 ms) ====== [2025-05-17T05:44:28.591Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-17T05:44:28.591Z] GC before operation: completed in 129.633 ms, heap usage 288.983 MB -> 69.037 MB. [2025-05-17T05:44:31.573Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2772.331 ms) ====== [2025-05-17T05:44:31.573Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-17T05:44:31.573Z] GC before operation: completed in 112.161 ms, heap usage 243.695 MB -> 69.320 MB. [2025-05-17T05:44:33.440Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2161.568 ms) ====== [2025-05-17T05:44:33.440Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-17T05:44:33.862Z] GC before operation: completed in 104.376 ms, heap usage 157.727 MB -> 69.475 MB. [2025-05-17T05:44:35.504Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1895.536 ms) ====== [2025-05-17T05:44:35.504Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-17T05:44:35.504Z] GC before operation: completed in 111.665 ms, heap usage 215.143 MB -> 69.732 MB. [2025-05-17T05:44:37.698Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1902.455 ms) ====== [2025-05-17T05:44:37.698Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-17T05:44:37.698Z] GC before operation: completed in 127.838 ms, heap usage 160.668 MB -> 69.784 MB. [2025-05-17T05:44:39.378Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1630.708 ms) ====== [2025-05-17T05:44:39.378Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-17T05:44:39.378Z] GC before operation: completed in 111.862 ms, heap usage 118.945 MB -> 69.702 MB. [2025-05-17T05:44:41.054Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1575.744 ms) ====== [2025-05-17T05:44:41.054Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-17T05:44:41.054Z] GC before operation: completed in 126.589 ms, heap usage 217.661 MB -> 70.222 MB. [2025-05-17T05:44:42.730Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1540.016 ms) ====== [2025-05-17T05:44:42.730Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-17T05:44:42.730Z] GC before operation: completed in 109.828 ms, heap usage 275.328 MB -> 81.228 MB. [2025-05-17T05:44:44.431Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1599.755 ms) ====== [2025-05-17T05:44:44.431Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-17T05:44:44.431Z] GC before operation: completed in 115.664 ms, heap usage 297.793 MB -> 86.965 MB. [2025-05-17T05:44:44.431Z] 05:44:44.283 WARN [block-manager-storage-async-thread-pool-93] org.apache.spark.storage.BlockManager - Asked to remove block rdd_429_1, which does not exist [2025-05-17T05:44:46.004Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1399.616 ms) ====== [2025-05-17T05:44:46.004Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-17T05:44:46.004Z] GC before operation: completed in 118.899 ms, heap usage 332.464 MB -> 87.085 MB. [2025-05-17T05:44:47.371Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1430.455 ms) ====== [2025-05-17T05:44:47.371Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-17T05:44:47.692Z] GC before operation: completed in 133.393 ms, heap usage 283.952 MB -> 87.234 MB. [2025-05-17T05:44:47.692Z] 05:44:47.363 WARN [block-manager-storage-async-thread-pool-32] org.apache.spark.storage.BlockManager - Asked to remove block rdd_525_2, which does not exist [2025-05-17T05:44:48.799Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1313.165 ms) ====== [2025-05-17T05:44:48.799Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-17T05:44:49.118Z] GC before operation: completed in 121.895 ms, heap usage 128.195 MB -> 70.512 MB. [2025-05-17T05:44:50.688Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1447.626 ms) ====== [2025-05-17T05:44:50.688Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-17T05:44:50.688Z] GC before operation: completed in 122.055 ms, heap usage 143.186 MB -> 82.047 MB. [2025-05-17T05:44:51.827Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1346.147 ms) ====== [2025-05-17T05:44:51.827Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-17T05:44:52.146Z] GC before operation: completed in 128.585 ms, heap usage 183.162 MB -> 81.896 MB. [2025-05-17T05:44:53.712Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1400.350 ms) ====== [2025-05-17T05:44:53.712Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-17T05:44:53.712Z] GC before operation: completed in 126.063 ms, heap usage 319.319 MB -> 87.716 MB. [2025-05-17T05:44:54.799Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1289.233 ms) ====== [2025-05-17T05:44:54.799Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-17T05:44:55.118Z] GC before operation: completed in 131.352 ms, heap usage 122.360 MB -> 71.212 MB. [2025-05-17T05:44:56.696Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1426.080 ms) ====== [2025-05-17T05:44:56.696Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-17T05:44:56.696Z] GC before operation: completed in 129.495 ms, heap usage 273.442 MB -> 93.600 MB. [2025-05-17T05:44:57.823Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1263.688 ms) ====== [2025-05-17T05:44:57.823Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-17T05:44:57.823Z] GC before operation: completed in 126.162 ms, heap usage 206.899 MB -> 82.468 MB. [2025-05-17T05:44:59.421Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1309.313 ms) ====== [2025-05-17T05:44:59.421Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-17T05:44:59.421Z] GC before operation: completed in 128.455 ms, heap usage 322.157 MB -> 93.640 MB. [2025-05-17T05:44:59.421Z] 05:44:59.178 WARN [block-manager-storage-async-thread-pool-85] org.apache.spark.storage.BlockManager - Asked to remove block rdd_909_0, which does not exist [2025-05-17T05:45:00.712Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1427.844 ms) ====== [2025-05-17T05:45:00.712Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-17T05:45:01.032Z] GC before operation: completed in 153.815 ms, heap usage 177.461 MB -> 93.610 MB. [2025-05-17T05:45:01.032Z] 05:45:00.761 WARN [block-manager-storage-async-thread-pool-65] org.apache.spark.storage.BlockManager - Asked to remove block rdd_957_1, which does not exist [2025-05-17T05:45:02.166Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1288.699 ms) ====== [2025-05-17T05:45:02.166Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-17T05:45:02.485Z] GC before operation: completed in 131.154 ms, heap usage 196.562 MB -> 88.252 MB. [2025-05-17T05:45:03.569Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1241.447 ms) ====== [2025-05-17T05:45:03.569Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-17T05:45:03.891Z] GC before operation: completed in 134.050 ms, heap usage 127.574 MB -> 87.949 MB. [2025-05-17T05:45:04.963Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1228.870 ms) ====== [2025-05-17T05:45:04.964Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-17T05:45:05.318Z] GC before operation: completed in 145.873 ms, heap usage 126.206 MB -> 82.878 MB. [2025-05-17T05:45:06.442Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1284.652 ms) ====== [2025-05-17T05:45:06.442Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-17T05:45:06.442Z] GC before operation: completed in 141.728 ms, heap usage 258.385 MB -> 88.780 MB. [2025-05-17T05:45:06.442Z] 05:45:06.359 WARN [block-manager-storage-async-thread-pool-31] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1149_1, which does not exist [2025-05-17T05:45:08.065Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1182.281 ms) ====== [2025-05-17T05:45:08.065Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-17T05:45:08.065Z] GC before operation: completed in 137.511 ms, heap usage 313.448 MB -> 88.867 MB. [2025-05-17T05:45:08.065Z] 05:45:07.680 WARN [block-manager-storage-async-thread-pool-91] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1197_1, which does not exist [2025-05-17T05:45:08.065Z] 05:45:07.680 WARN [block-manager-storage-async-thread-pool-91] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1197_2, which does not exist [2025-05-17T05:45:09.147Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1284.213 ms) ====== [2025-05-17T05:45:09.147Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-17T05:45:09.147Z] GC before operation: completed in 134.451 ms, heap usage 174.583 MB -> 83.201 MB. [2025-05-17T05:45:10.716Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1211.404 ms) ====== [2025-05-17T05:45:10.716Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-17T05:45:10.716Z] GC before operation: completed in 133.332 ms, heap usage 222.498 MB -> 83.490 MB. [2025-05-17T05:45:11.820Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1248.018 ms) ====== [2025-05-17T05:45:11.820Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-17T05:45:12.136Z] GC before operation: completed in 135.051 ms, heap usage 120.756 MB -> 83.459 MB. [2025-05-17T05:45:13.223Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1218.318 ms) ====== [2025-05-17T05:45:13.223Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-17T05:45:13.223Z] GC before operation: completed in 139.332 ms, heap usage 241.371 MB -> 94.775 MB. [2025-05-17T05:45:14.790Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1215.454 ms) ====== [2025-05-17T05:45:14.790Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-17T05:45:14.790Z] GC before operation: completed in 142.845 ms, heap usage 255.809 MB -> 89.247 MB. [2025-05-17T05:45:15.911Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1236.273 ms) ====== [2025-05-17T05:45:15.911Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-17T05:45:16.230Z] GC before operation: completed in 144.896 ms, heap usage 189.350 MB -> 89.214 MB. [2025-05-17T05:45:17.314Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1242.124 ms) ====== [2025-05-17T05:45:17.314Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-17T05:45:17.634Z] GC before operation: completed in 152.142 ms, heap usage 327.828 MB -> 84.160 MB. [2025-05-17T05:45:18.772Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1206.974 ms) ====== [2025-05-17T05:45:18.772Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-17T05:45:18.772Z] GC before operation: completed in 139.344 ms, heap usage 257.836 MB -> 89.764 MB. [2025-05-17T05:45:20.344Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1244.200 ms) ====== [2025-05-17T05:45:20.344Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-17T05:45:20.344Z] GC before operation: completed in 147.041 ms, heap usage 232.234 MB -> 84.223 MB. [2025-05-17T05:45:21.503Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1318.454 ms) ====== [2025-05-17T05:45:21.503Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-17T05:45:21.826Z] GC before operation: completed in 161.003 ms, heap usage 365.455 MB -> 89.948 MB. [2025-05-17T05:45:22.937Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1262.928 ms) ====== [2025-05-17T05:45:22.937Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-17T05:45:23.272Z] GC before operation: completed in 143.367 ms, heap usage 264.112 MB -> 95.652 MB. [2025-05-17T05:45:23.272Z] 05:45:22.948 WARN [block-manager-storage-async-thread-pool-56] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1725_0, which does not exist [2025-05-17T05:45:24.370Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1262.958 ms) ====== [2025-05-17T05:45:24.370Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-17T05:45:24.699Z] GC before operation: completed in 143.899 ms, heap usage 198.066 MB -> 84.745 MB. [2025-05-17T05:45:25.814Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1247.777 ms) ====== [2025-05-17T05:45:25.814Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-17T05:45:25.814Z] GC before operation: completed in 143.781 ms, heap usage 223.300 MB -> 84.793 MB. [2025-05-17T05:45:27.394Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1229.352 ms) ====== [2025-05-17T05:45:27.394Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-17T05:45:27.394Z] GC before operation: completed in 138.354 ms, heap usage 154.623 MB -> 84.694 MB. [2025-05-17T05:45:28.483Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1233.906 ms) ====== [2025-05-17T05:45:28.799Z] ----------------------------------- [2025-05-17T05:45:28.799Z] renaissance-dec-tree_0_PASSED [2025-05-17T05:45:28.799Z] ----------------------------------- [2025-05-17T05:45:29.115Z] [2025-05-17T05:45:29.116Z] TEST TEARDOWN: [2025-05-17T05:45:29.116Z] Nothing to be done for teardown. [2025-05-17T05:45:29.116Z] renaissance-dec-tree_0 Finish Time: Sat May 17 05:45:28 2025 Epoch Time (ms): 1747460728916