[2025-05-08T09:53:56.810Z] Running test renaissance-dec-tree_0 ... [2025-05-08T09:53:56.810Z] =============================================== [2025-05-08T09:53:56.810Z] renaissance-dec-tree_0 Start Time: Thu May 8 09:53:56 2025 Epoch Time (ms): 1746698036736 [2025-05-08T09:53:57.136Z] variation: NoOptions [2025-05-08T09:53:57.136Z] JVM_OPTIONS: [2025-05-08T09:53:57.136Z] { \ [2025-05-08T09:53:57.136Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T09:53:57.136Z] echo "Nothing to be done for setup."; \ [2025-05-08T09:53:57.136Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17466972283983\\renaissance-dec-tree_0"; \ [2025-05-08T09:53:57.136Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17466972283983\\renaissance-dec-tree_0"; \ [2025-05-08T09:53:57.136Z] echo ""; echo "TESTING:"; \ [2025-05-08T09:53:57.136Z] "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_17466972283983\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-05-08T09:53:57.137Z] 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_17466972283983\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T09:53:57.137Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T09:53:57.137Z] echo "Nothing to be done for teardown."; \ [2025-05-08T09:53:57.137Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17466972283983\\TestTargetResult"; [2025-05-08T09:53:57.137Z] [2025-05-08T09:53:57.137Z] TEST SETUP: [2025-05-08T09:53:57.137Z] Nothing to be done for setup. [2025-05-08T09:53:57.137Z] [2025-05-08T09:53:57.137Z] TESTING: [2025-05-08T09:54:10.080Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-08T09:54:10.772Z] WARNING: An illegal reflective access operation has occurred [2025-05-08T09:54:10.772Z] 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_17466972283983/renaissance-dec-tree_0/harness-095357-5143080079041093963/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-08T09:54:10.772Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-08T09:54:10.772Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-08T09:54:10.772Z] WARNING: All illegal access operations will be denied in a future release [2025-05-08T09:54:12.379Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-08T09:54:12.379Z] GC before operation: completed in 53.420 ms, heap usage 81.443 MB -> 34.873 MB. [2025-05-08T09:54:23.100Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10721.449 ms) ====== [2025-05-08T09:54:23.100Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-08T09:54:23.433Z] GC before operation: completed in 143.770 ms, heap usage 189.966 MB -> 68.423 MB. [2025-05-08T09:54:26.439Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2891.269 ms) ====== [2025-05-08T09:54:26.439Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-08T09:54:26.439Z] GC before operation: completed in 107.842 ms, heap usage 188.387 MB -> 69.046 MB. [2025-05-08T09:54:28.810Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2445.997 ms) ====== [2025-05-08T09:54:28.810Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-08T09:54:28.810Z] GC before operation: completed in 106.506 ms, heap usage 251.930 MB -> 69.336 MB. [2025-05-08T09:54:31.106Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2133.939 ms) ====== [2025-05-08T09:54:31.106Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-08T09:54:31.106Z] GC before operation: completed in 118.478 ms, heap usage 208.416 MB -> 69.558 MB. [2025-05-08T09:54:33.312Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1937.558 ms) ====== [2025-05-08T09:54:33.312Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-08T09:54:33.312Z] GC before operation: completed in 132.671 ms, heap usage 174.836 MB -> 69.781 MB. [2025-05-08T09:54:34.957Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1733.589 ms) ====== [2025-05-08T09:54:34.957Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-08T09:54:34.957Z] GC before operation: completed in 108.003 ms, heap usage 261.989 MB -> 81.295 MB. [2025-05-08T09:54:36.651Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1704.375 ms) ====== [2025-05-08T09:54:36.651Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-08T09:54:36.996Z] GC before operation: completed in 115.461 ms, heap usage 126.065 MB -> 81.190 MB. [2025-05-08T09:54:38.626Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1693.343 ms) ====== [2025-05-08T09:54:38.626Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-08T09:54:38.626Z] GC before operation: completed in 118.690 ms, heap usage 123.050 MB -> 70.027 MB. [2025-05-08T09:54:40.305Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1555.085 ms) ====== [2025-05-08T09:54:40.305Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-08T09:54:40.305Z] GC before operation: completed in 114.638 ms, heap usage 187.377 MB -> 76.082 MB. [2025-05-08T09:54:41.958Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1558.097 ms) ====== [2025-05-08T09:54:41.958Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-08T09:54:41.958Z] GC before operation: completed in 119.405 ms, heap usage 115.525 MB -> 86.451 MB. [2025-05-08T09:54:43.605Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1372.960 ms) ====== [2025-05-08T09:54:43.605Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-08T09:54:43.605Z] GC before operation: completed in 136.430 ms, heap usage 264.381 MB -> 87.416 MB. [2025-05-08T09:54:45.223Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1419.934 ms) ====== [2025-05-08T09:54:45.223Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-08T09:54:45.223Z] GC before operation: completed in 119.346 ms, heap usage 284.543 MB -> 81.701 MB. [2025-05-08T09:54:46.473Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1399.338 ms) ====== [2025-05-08T09:54:46.473Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-08T09:54:46.473Z] GC before operation: completed in 122.979 ms, heap usage 282.715 MB -> 87.466 MB. [2025-05-08T09:54:48.082Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1363.152 ms) ====== [2025-05-08T09:54:48.082Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-08T09:54:48.082Z] GC before operation: completed in 125.865 ms, heap usage 136.097 MB -> 81.589 MB. [2025-05-08T09:54:49.715Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1409.664 ms) ====== [2025-05-08T09:54:49.715Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-08T09:54:49.715Z] GC before operation: completed in 127.900 ms, heap usage 114.264 MB -> 81.708 MB. [2025-05-08T09:54:51.327Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1438.854 ms) ====== [2025-05-08T09:54:51.327Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-08T09:54:51.327Z] GC before operation: completed in 146.019 ms, heap usage 243.189 MB -> 71.471 MB. [2025-05-08T09:54:52.485Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1347.573 ms) ====== [2025-05-08T09:54:52.485Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-08T09:54:52.820Z] GC before operation: completed in 124.743 ms, heap usage 184.565 MB -> 82.719 MB. [2025-05-08T09:54:53.959Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1344.147 ms) ====== [2025-05-08T09:54:53.959Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-08T09:54:54.287Z] GC before operation: completed in 135.765 ms, heap usage 133.944 MB -> 71.423 MB. [2025-05-08T09:54:55.534Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1461.147 ms) ====== [2025-05-08T09:54:55.535Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-08T09:54:55.862Z] GC before operation: completed in 126.087 ms, heap usage 175.597 MB -> 82.735 MB. [2025-05-08T09:54:57.049Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1380.337 ms) ====== [2025-05-08T09:54:57.049Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-08T09:54:57.375Z] GC before operation: completed in 135.204 ms, heap usage 276.874 MB -> 93.908 MB. [2025-05-08T09:54:58.492Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1253.699 ms) ====== [2025-05-08T09:54:58.492Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-08T09:54:58.492Z] GC before operation: completed in 135.522 ms, heap usage 203.165 MB -> 82.750 MB. [2025-05-08T09:55:00.094Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1264.730 ms) ====== [2025-05-08T09:55:00.094Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-08T09:55:00.094Z] GC before operation: completed in 131.974 ms, heap usage 261.695 MB -> 88.517 MB. [2025-05-08T09:55:01.310Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1338.605 ms) ====== [2025-05-08T09:55:01.310Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-08T09:55:01.639Z] GC before operation: completed in 139.539 ms, heap usage 132.420 MB -> 82.833 MB. [2025-05-08T09:55:02.854Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1316.886 ms) ====== [2025-05-08T09:55:02.854Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-08T09:55:02.854Z] GC before operation: completed in 134.085 ms, heap usage 185.900 MB -> 82.776 MB. [2025-05-08T09:55:04.485Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1263.131 ms) ====== [2025-05-08T09:55:04.485Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-08T09:55:04.485Z] GC before operation: completed in 138.893 ms, heap usage 228.051 MB -> 88.616 MB. [2025-05-08T09:55:05.657Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1264.094 ms) ====== [2025-05-08T09:55:05.657Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-08T09:55:05.657Z] GC before operation: completed in 140.998 ms, heap usage 310.051 MB -> 83.301 MB. [2025-05-08T09:55:07.275Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1323.250 ms) ====== [2025-05-08T09:55:07.275Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-08T09:55:07.275Z] GC before operation: completed in 140.373 ms, heap usage 287.566 MB -> 89.063 MB. [2025-05-08T09:55:08.442Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1264.193 ms) ====== [2025-05-08T09:55:08.442Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-08T09:55:08.769Z] GC before operation: completed in 143.916 ms, heap usage 300.730 MB -> 88.936 MB. [2025-05-08T09:55:09.928Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1338.525 ms) ====== [2025-05-08T09:55:09.928Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-08T09:55:10.252Z] GC before operation: completed in 164.494 ms, heap usage 418.660 MB -> 94.890 MB. [2025-05-08T09:55:11.425Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1333.596 ms) ====== [2025-05-08T09:55:11.425Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-08T09:55:11.754Z] GC before operation: completed in 144.804 ms, heap usage 141.761 MB -> 83.612 MB. [2025-05-08T09:55:12.862Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1277.388 ms) ====== [2025-05-08T09:55:12.862Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-08T09:55:13.191Z] GC before operation: completed in 160.364 ms, heap usage 429.175 MB -> 89.443 MB. [2025-05-08T09:55:14.364Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1341.827 ms) ====== [2025-05-08T09:55:14.364Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-08T09:55:14.741Z] GC before operation: completed in 141.195 ms, heap usage 123.310 MB -> 72.731 MB. [2025-05-08T09:55:15.853Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1304.873 ms) ====== [2025-05-08T09:55:15.853Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-08T09:55:15.853Z] GC before operation: completed in 136.606 ms, heap usage 284.216 MB -> 89.696 MB. [2025-05-08T09:55:17.481Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1328.528 ms) ====== [2025-05-08T09:55:17.481Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-08T09:55:17.481Z] GC before operation: completed in 172.111 ms, heap usage 417.061 MB -> 89.772 MB. [2025-05-08T09:55:18.665Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1238.756 ms) ====== [2025-05-08T09:55:18.665Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-08T09:55:18.994Z] GC before operation: completed in 138.500 ms, heap usage 239.836 MB -> 89.831 MB. [2025-05-08T09:55:20.107Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1277.382 ms) ====== [2025-05-08T09:55:20.107Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-08T09:55:20.452Z] GC before operation: completed in 144.731 ms, heap usage 356.436 MB -> 95.562 MB. [2025-05-08T09:55:20.452Z] 09:55:20.118 WARN [block-manager-storage-async-thread-pool-60] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1725_0, which does not exist [2025-05-08T09:55:21.678Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1423.169 ms) ====== [2025-05-08T09:55:21.678Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-08T09:55:22.003Z] GC before operation: completed in 171.654 ms, heap usage 520.656 MB -> 90.287 MB. [2025-05-08T09:55:23.152Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1320.237 ms) ====== [2025-05-08T09:55:23.152Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-08T09:55:23.489Z] GC before operation: completed in 141.029 ms, heap usage 180.830 MB -> 84.719 MB. [2025-05-08T09:55:24.605Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1293.935 ms) ====== [2025-05-08T09:55:24.605Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-08T09:55:24.934Z] GC before operation: completed in 146.733 ms, heap usage 286.211 MB -> 90.313 MB. [2025-05-08T09:55:24.934Z] 09:55:24.613 WARN [block-manager-storage-async-thread-pool-77] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_541, which does not exist [2025-05-08T09:55:26.043Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1323.144 ms) ====== [2025-05-08T09:55:26.370Z] ----------------------------------- [2025-05-08T09:55:26.370Z] renaissance-dec-tree_0_PASSED [2025-05-08T09:55:26.370Z] ----------------------------------- [2025-05-08T09:55:26.681Z] [2025-05-08T09:55:26.681Z] TEST TEARDOWN: [2025-05-08T09:55:26.681Z] Nothing to be done for teardown. [2025-05-08T09:55:26.681Z] renaissance-dec-tree_0 Finish Time: Thu May 8 09:55:26 2025 Epoch Time (ms): 1746698126533