[2025-04-16T17:55:17.585Z] Running test renaissance-dec-tree_0 ... [2025-04-16T17:55:17.585Z] =============================================== [2025-04-16T17:55:17.585Z] renaissance-dec-tree_0 Start Time: Wed Apr 16 17:55:16 2025 Epoch Time (ms): 1744826116993 [2025-04-16T17:55:17.585Z] variation: NoOptions [2025-04-16T17:55:17.585Z] JVM_OPTIONS: [2025-04-16T17:55:17.585Z] { \ [2025-04-16T17:55:17.585Z] echo ""; echo "TEST SETUP:"; \ [2025-04-16T17:55:17.585Z] echo "Nothing to be done for setup."; \ [2025-04-16T17:55:17.585Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17448247441979/renaissance-dec-tree_0"; \ [2025-04-16T17:55:17.585Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17448247441979/renaissance-dec-tree_0"; \ [2025-04-16T17:55:17.585Z] echo ""; echo "TESTING:"; \ [2025-04-16T17:55:17.585Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17448247441979/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-04-16T17:55:17.585Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17448247441979/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-16T17:55:17.585Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-16T17:55:17.585Z] echo "Nothing to be done for teardown."; \ [2025-04-16T17:55:17.585Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17448247441979/TestTargetResult"; [2025-04-16T17:55:17.585Z] [2025-04-16T17:55:17.585Z] TEST SETUP: [2025-04-16T17:55:17.585Z] Nothing to be done for setup. [2025-04-16T17:55:17.585Z] [2025-04-16T17:55:17.585Z] TESTING: [2025-04-16T17:55:31.640Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-04-16T17:55:40.166Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-04-16T17:55:40.166Z] GC before operation: completed in 229.292 ms, heap usage 44.211 MB -> 34.859 MB. [2025-04-16T17:56:22.539Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (38483.988 ms) ====== [2025-04-16T17:56:22.539Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-04-16T17:56:22.539Z] GC before operation: completed in 377.657 ms, heap usage 289.642 MB -> 69.061 MB. [2025-04-16T17:56:29.641Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (9606.972 ms) ====== [2025-04-16T17:56:29.641Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-04-16T17:56:29.641Z] GC before operation: completed in 261.806 ms, heap usage 127.906 MB -> 85.416 MB. [2025-04-16T17:56:36.764Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (7275.896 ms) ====== [2025-04-16T17:56:36.764Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-04-16T17:56:36.764Z] GC before operation: completed in 326.958 ms, heap usage 430.872 MB -> 69.621 MB. [2025-04-16T17:56:45.271Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (8121.219 ms) ====== [2025-04-16T17:56:45.271Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-04-16T17:56:45.271Z] GC before operation: completed in 374.507 ms, heap usage 379.403 MB -> 69.722 MB. [2025-04-16T17:56:55.597Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (9491.220 ms) ====== [2025-04-16T17:56:55.597Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-04-16T17:56:55.597Z] GC before operation: completed in 352.629 ms, heap usage 265.663 MB -> 70.071 MB. [2025-04-16T17:57:02.683Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (6778.156 ms) ====== [2025-04-16T17:57:02.683Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-04-16T17:57:02.683Z] GC before operation: completed in 337.126 ms, heap usage 220.460 MB -> 70.143 MB. [2025-04-16T17:57:09.719Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (7106.051 ms) ====== [2025-04-16T17:57:09.720Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-04-16T17:57:09.720Z] GC before operation: completed in 255.350 ms, heap usage 116.341 MB -> 69.736 MB. [2025-04-16T17:57:16.841Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (6777.344 ms) ====== [2025-04-16T17:57:16.841Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-04-16T17:57:16.841Z] GC before operation: completed in 350.478 ms, heap usage 178.781 MB -> 70.377 MB. [2025-04-16T17:57:23.885Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (6901.494 ms) ====== [2025-04-16T17:57:23.885Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-04-16T17:57:23.885Z] GC before operation: completed in 327.203 ms, heap usage 146.486 MB -> 70.534 MB. [2025-04-16T17:57:30.921Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (6373.994 ms) ====== [2025-04-16T17:57:30.921Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-04-16T17:57:30.921Z] GC before operation: completed in 337.019 ms, heap usage 243.436 MB -> 70.941 MB. [2025-04-16T17:57:37.359Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (6145.985 ms) ====== [2025-04-16T17:57:37.359Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-04-16T17:57:37.359Z] GC before operation: completed in 265.330 ms, heap usage 414.857 MB -> 71.046 MB. [2025-04-16T17:57:43.122Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (5322.913 ms) ====== [2025-04-16T17:57:43.122Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-04-16T17:57:43.122Z] GC before operation: completed in 404.377 ms, heap usage 233.576 MB -> 71.411 MB. [2025-04-16T17:57:48.928Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (5744.110 ms) ====== [2025-04-16T17:57:48.928Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-04-16T17:57:48.928Z] GC before operation: completed in 328.664 ms, heap usage 275.115 MB -> 71.194 MB. [2025-04-16T17:57:53.623Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (5031.763 ms) ====== [2025-04-16T17:57:53.623Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-04-16T17:57:54.429Z] GC before operation: completed in 516.795 ms, heap usage 183.329 MB -> 71.101 MB. [2025-04-16T17:58:00.168Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (5896.669 ms) ====== [2025-04-16T17:58:00.168Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-04-16T17:58:00.956Z] GC before operation: completed in 372.539 ms, heap usage 203.054 MB -> 71.569 MB. [2025-04-16T17:58:05.540Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (4251.953 ms) ====== [2025-04-16T17:58:05.540Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-04-16T17:58:05.540Z] GC before operation: completed in 293.417 ms, heap usage 228.073 MB -> 71.727 MB. [2025-04-16T17:58:10.128Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4695.494 ms) ====== [2025-04-16T17:58:10.128Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-04-16T17:58:10.128Z] GC before operation: completed in 288.891 ms, heap usage 109.367 MB -> 71.249 MB. [2025-04-16T17:58:14.703Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4145.459 ms) ====== [2025-04-16T17:58:14.703Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-04-16T17:58:14.703Z] GC before operation: completed in 316.267 ms, heap usage 182.462 MB -> 71.669 MB. [2025-04-16T17:58:19.307Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4286.828 ms) ====== [2025-04-16T17:58:19.307Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-04-16T17:58:19.307Z] GC before operation: completed in 298.457 ms, heap usage 252.177 MB -> 94.605 MB. [2025-04-16T17:58:23.900Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4167.463 ms) ====== [2025-04-16T17:58:23.901Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-04-16T17:58:23.901Z] GC before operation: completed in 319.926 ms, heap usage 369.574 MB -> 72.543 MB. [2025-04-16T17:58:28.482Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (4397.498 ms) ====== [2025-04-16T17:58:28.482Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-04-16T17:58:28.482Z] GC before operation: completed in 319.769 ms, heap usage 322.446 MB -> 72.255 MB. [2025-04-16T17:58:33.044Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4946.159 ms) ====== [2025-04-16T17:58:33.044Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-04-16T17:58:33.844Z] GC before operation: completed in 287.794 ms, heap usage 382.395 MB -> 88.979 MB. [2025-04-16T17:58:33.844Z] 17:58:33.220 WARN [block-manager-storage-async-thread-pool-49] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1053_0, which does not exist [2025-04-16T17:58:37.373Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3869.690 ms) ====== [2025-04-16T17:58:37.373Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-04-16T17:58:38.174Z] GC before operation: completed in 413.854 ms, heap usage 402.162 MB -> 83.498 MB. [2025-04-16T17:58:42.249Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4575.310 ms) ====== [2025-04-16T17:58:42.249Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-04-16T17:58:43.035Z] GC before operation: completed in 402.866 ms, heap usage 308.093 MB -> 72.753 MB. [2025-04-16T17:58:47.657Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (5019.717 ms) ====== [2025-04-16T17:58:47.657Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-04-16T17:58:48.472Z] GC before operation: completed in 452.808 ms, heap usage 375.066 MB -> 100.272 MB. [2025-04-16T17:58:53.099Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4546.310 ms) ====== [2025-04-16T17:58:53.099Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-04-16T17:58:53.099Z] GC before operation: completed in 306.428 ms, heap usage 480.614 MB -> 72.887 MB. [2025-04-16T17:58:57.785Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4624.955 ms) ====== [2025-04-16T17:58:57.785Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-04-16T17:58:58.580Z] GC before operation: completed in 383.155 ms, heap usage 413.511 MB -> 73.006 MB. [2025-04-16T17:59:02.101Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3690.001 ms) ====== [2025-04-16T17:59:02.101Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-04-16T17:59:02.101Z] GC before operation: completed in 368.590 ms, heap usage 404.174 MB -> 72.950 MB. [2025-04-16T17:59:06.687Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4040.865 ms) ====== [2025-04-16T17:59:06.687Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-04-16T17:59:06.687Z] GC before operation: completed in 326.193 ms, heap usage 414.051 MB -> 73.077 MB. [2025-04-16T17:59:11.296Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3956.513 ms) ====== [2025-04-16T17:59:11.296Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-04-16T17:59:11.296Z] GC before operation: completed in 341.452 ms, heap usage 256.436 MB -> 73.221 MB. [2025-04-16T17:59:15.914Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4825.221 ms) ====== [2025-04-16T17:59:15.914Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-04-16T17:59:15.914Z] GC before operation: completed in 422.159 ms, heap usage 198.724 MB -> 73.157 MB. [2025-04-16T17:59:20.527Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4339.886 ms) ====== [2025-04-16T17:59:20.527Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-04-16T17:59:20.527Z] GC before operation: completed in 276.804 ms, heap usage 359.556 MB -> 73.316 MB. [2025-04-16T17:59:25.127Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3799.768 ms) ====== [2025-04-16T17:59:25.127Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-04-16T17:59:25.127Z] GC before operation: completed in 366.623 ms, heap usage 118.781 MB -> 73.185 MB. [2025-04-16T17:59:29.743Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4179.473 ms) ====== [2025-04-16T17:59:29.743Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-04-16T17:59:29.743Z] GC before operation: completed in 403.020 ms, heap usage 177.100 MB -> 73.585 MB. [2025-04-16T17:59:34.347Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (4245.938 ms) ====== [2025-04-16T17:59:34.348Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-04-16T17:59:34.348Z] GC before operation: completed in 346.863 ms, heap usage 511.675 MB -> 73.881 MB. [2025-04-16T17:59:38.929Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4476.230 ms) ====== [2025-04-16T17:59:38.929Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-04-16T17:59:38.929Z] GC before operation: completed in 443.720 ms, heap usage 390.507 MB -> 84.912 MB. [2025-04-16T17:59:44.771Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (5023.122 ms) ====== [2025-04-16T17:59:44.771Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-04-16T17:59:44.771Z] GC before operation: completed in 402.706 ms, heap usage 297.037 MB -> 74.075 MB. [2025-04-16T17:59:49.820Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4584.964 ms) ====== [2025-04-16T17:59:49.820Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-04-16T17:59:49.820Z] GC before operation: completed in 381.958 ms, heap usage 259.317 MB -> 74.165 MB. [2025-04-16T17:59:53.358Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4006.160 ms) ====== [2025-04-16T17:59:53.358Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-04-16T17:59:54.176Z] GC before operation: completed in 509.479 ms, heap usage 375.907 MB -> 74.115 MB. [2025-04-16T17:59:58.784Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4505.824 ms) ====== [2025-04-16T17:59:59.571Z] ----------------------------------- [2025-04-16T17:59:59.571Z] renaissance-dec-tree_0_PASSED [2025-04-16T17:59:59.571Z] ----------------------------------- [2025-04-16T17:59:59.571Z] [2025-04-16T17:59:59.571Z] TEST TEARDOWN: [2025-04-16T17:59:59.571Z] Nothing to be done for teardown. [2025-04-16T17:59:59.571Z] renaissance-dec-tree_0 Finish Time: Wed Apr 16 17:59:58 2025 Epoch Time (ms): 1744826398865