[2025-02-26T21:54:04.421Z] Running test renaissance-dec-tree_0 ... [2025-02-26T21:54:04.421Z] =============================================== [2025-02-26T21:54:04.421Z] renaissance-dec-tree_0 Start Time: Wed Feb 26 16:54:04 2025 Epoch Time (ms): 1740606844218 [2025-02-26T21:54:04.421Z] variation: NoOptions [2025-02-26T21:54:04.421Z] JVM_OPTIONS: [2025-02-26T21:54:04.421Z] { \ [2025-02-26T21:54:04.421Z] echo ""; echo "TEST SETUP:"; \ [2025-02-26T21:54:04.421Z] echo "Nothing to be done for setup."; \ [2025-02-26T21:54:04.421Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406066794944/renaissance-dec-tree_0"; \ [2025-02-26T21:54:04.421Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406066794944/renaissance-dec-tree_0"; \ [2025-02-26T21:54:04.421Z] echo ""; echo "TESTING:"; \ [2025-02-26T21:54:04.421Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406066794944/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-26T21:54:04.421Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406066794944/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-26T21:54:04.421Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-26T21:54:04.421Z] echo "Nothing to be done for teardown."; \ [2025-02-26T21:54:04.421Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406066794944/TestTargetResult"; [2025-02-26T21:54:04.421Z] [2025-02-26T21:54:04.421Z] TEST SETUP: [2025-02-26T21:54:04.421Z] Nothing to be done for setup. [2025-02-26T21:54:04.421Z] [2025-02-26T21:54:04.421Z] TESTING: [2025-02-26T21:54:06.332Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-26T21:54:06.719Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-02-26T21:54:08.058Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-26T21:54:08.058Z] GC before operation: completed in 20.779 ms, heap usage 41.315 MB -> 34.987 MB. [2025-02-26T21:54:11.405Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (3586.622 ms) ====== [2025-02-26T21:54:11.405Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-26T21:54:11.405Z] GC before operation: completed in 29.289 ms, heap usage 281.450 MB -> 64.494 MB. [2025-02-26T21:54:12.227Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (980.389 ms) ====== [2025-02-26T21:54:12.227Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-26T21:54:12.227Z] GC before operation: completed in 28.601 ms, heap usage 185.077 MB -> 64.615 MB. [2025-02-26T21:54:13.063Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (765.784 ms) ====== [2025-02-26T21:54:13.063Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-26T21:54:13.063Z] GC before operation: completed in 29.155 ms, heap usage 240.671 MB -> 65.203 MB. [2025-02-26T21:54:13.934Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (748.623 ms) ====== [2025-02-26T21:54:13.934Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-26T21:54:13.934Z] GC before operation: completed in 28.464 ms, heap usage 239.452 MB -> 65.389 MB. [2025-02-26T21:54:14.765Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (709.560 ms) ====== [2025-02-26T21:54:14.765Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-26T21:54:14.765Z] GC before operation: completed in 30.222 ms, heap usage 227.409 MB -> 65.595 MB. [2025-02-26T21:54:15.643Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (667.693 ms) ====== [2025-02-26T21:54:15.643Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-26T21:54:15.643Z] GC before operation: completed in 30.674 ms, heap usage 192.225 MB -> 65.806 MB. [2025-02-26T21:54:16.040Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (641.913 ms) ====== [2025-02-26T21:54:16.040Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-26T21:54:16.040Z] GC before operation: completed in 31.641 ms, heap usage 256.213 MB -> 66.023 MB. [2025-02-26T21:54:16.889Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (650.331 ms) ====== [2025-02-26T21:54:16.889Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-26T21:54:16.889Z] GC before operation: completed in 33.232 ms, heap usage 279.655 MB -> 66.145 MB. [2025-02-26T21:54:17.290Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (636.656 ms) ====== [2025-02-26T21:54:17.290Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-26T21:54:17.290Z] GC before operation: completed in 38.269 ms, heap usage 281.333 MB -> 66.330 MB. [2025-02-26T21:54:18.123Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (648.472 ms) ====== [2025-02-26T21:54:18.123Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-26T21:54:18.123Z] GC before operation: completed in 34.213 ms, heap usage 214.160 MB -> 66.387 MB. [2025-02-26T21:54:18.588Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (634.792 ms) ====== [2025-02-26T21:54:18.588Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-26T21:54:18.981Z] GC before operation: completed in 36.686 ms, heap usage 244.493 MB -> 66.512 MB. [2025-02-26T21:54:19.373Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (632.161 ms) ====== [2025-02-26T21:54:19.373Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-26T21:54:19.373Z] GC before operation: completed in 34.312 ms, heap usage 242.799 MB -> 66.625 MB. [2025-02-26T21:54:20.217Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (596.732 ms) ====== [2025-02-26T21:54:20.217Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-26T21:54:20.217Z] GC before operation: completed in 34.623 ms, heap usage 235.381 MB -> 66.785 MB. [2025-02-26T21:54:20.629Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (600.087 ms) ====== [2025-02-26T21:54:20.629Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-26T21:54:20.629Z] GC before operation: completed in 37.675 ms, heap usage 230.789 MB -> 67.006 MB. [2025-02-26T21:54:21.466Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (584.890 ms) ====== [2025-02-26T21:54:21.466Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-26T21:54:21.466Z] GC before operation: completed in 36.153 ms, heap usage 232.486 MB -> 67.026 MB. [2025-02-26T21:54:21.862Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (611.983 ms) ====== [2025-02-26T21:54:21.863Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-26T21:54:21.863Z] GC before operation: completed in 35.781 ms, heap usage 232.319 MB -> 67.339 MB. [2025-02-26T21:54:22.722Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (575.000 ms) ====== [2025-02-26T21:54:22.722Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-26T21:54:22.722Z] GC before operation: completed in 38.030 ms, heap usage 230.904 MB -> 67.277 MB. [2025-02-26T21:54:23.110Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (557.699 ms) ====== [2025-02-26T21:54:23.110Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-26T21:54:23.110Z] GC before operation: completed in 36.558 ms, heap usage 223.405 MB -> 67.436 MB. [2025-02-26T21:54:23.951Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (594.807 ms) ====== [2025-02-26T21:54:23.951Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-26T21:54:23.951Z] GC before operation: completed in 39.449 ms, heap usage 235.525 MB -> 67.516 MB. [2025-02-26T21:54:24.342Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (575.648 ms) ====== [2025-02-26T21:54:24.342Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-26T21:54:24.342Z] GC before operation: completed in 38.267 ms, heap usage 226.634 MB -> 67.614 MB. [2025-02-26T21:54:25.199Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (582.907 ms) ====== [2025-02-26T21:54:25.199Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-26T21:54:25.199Z] GC before operation: completed in 36.112 ms, heap usage 222.620 MB -> 67.680 MB. [2025-02-26T21:54:25.589Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (572.653 ms) ====== [2025-02-26T21:54:25.589Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-26T21:54:25.589Z] GC before operation: completed in 37.808 ms, heap usage 223.786 MB -> 67.824 MB. [2025-02-26T21:54:26.427Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (550.150 ms) ====== [2025-02-26T21:54:26.427Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-26T21:54:26.427Z] GC before operation: completed in 39.325 ms, heap usage 215.325 MB -> 67.961 MB. [2025-02-26T21:54:26.819Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (553.667 ms) ====== [2025-02-26T21:54:26.819Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-26T21:54:26.819Z] GC before operation: completed in 42.610 ms, heap usage 214.120 MB -> 68.067 MB. [2025-02-26T21:54:27.676Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (535.817 ms) ====== [2025-02-26T21:54:27.676Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-26T21:54:27.676Z] GC before operation: completed in 38.927 ms, heap usage 224.759 MB -> 68.158 MB. [2025-02-26T21:54:28.084Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (536.609 ms) ====== [2025-02-26T21:54:28.084Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-26T21:54:28.084Z] GC before operation: completed in 37.861 ms, heap usage 213.695 MB -> 68.283 MB. [2025-02-26T21:54:28.482Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (534.462 ms) ====== [2025-02-26T21:54:28.482Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-26T21:54:28.482Z] GC before operation: completed in 40.007 ms, heap usage 210.577 MB -> 68.359 MB. [2025-02-26T21:54:29.306Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (539.875 ms) ====== [2025-02-26T21:54:29.306Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-26T21:54:29.306Z] GC before operation: completed in 38.742 ms, heap usage 214.032 MB -> 68.492 MB. [2025-02-26T21:54:29.717Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (535.733 ms) ====== [2025-02-26T21:54:29.717Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-26T21:54:29.717Z] GC before operation: completed in 40.500 ms, heap usage 215.207 MB -> 68.761 MB. [2025-02-26T21:54:30.553Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (554.977 ms) ====== [2025-02-26T21:54:30.553Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-26T21:54:30.553Z] GC before operation: completed in 41.952 ms, heap usage 207.178 MB -> 68.707 MB. [2025-02-26T21:54:30.968Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (549.433 ms) ====== [2025-02-26T21:54:30.968Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-26T21:54:30.968Z] GC before operation: completed in 39.674 ms, heap usage 204.556 MB -> 68.806 MB. [2025-02-26T21:54:31.358Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (548.556 ms) ====== [2025-02-26T21:54:31.358Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-26T21:54:31.358Z] GC before operation: completed in 40.537 ms, heap usage 197.468 MB -> 68.926 MB. [2025-02-26T21:54:32.206Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (540.816 ms) ====== [2025-02-26T21:54:32.206Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-26T21:54:32.206Z] GC before operation: completed in 39.618 ms, heap usage 196.437 MB -> 68.992 MB. [2025-02-26T21:54:32.592Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (522.764 ms) ====== [2025-02-26T21:54:32.592Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-26T21:54:32.592Z] GC before operation: completed in 41.626 ms, heap usage 187.330 MB -> 69.128 MB. [2025-02-26T21:54:33.414Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (545.381 ms) ====== [2025-02-26T21:54:33.414Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-26T21:54:33.414Z] GC before operation: completed in 40.907 ms, heap usage 168.832 MB -> 68.987 MB. [2025-02-26T21:54:33.809Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (563.193 ms) ====== [2025-02-26T21:54:33.809Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-26T21:54:33.809Z] GC before operation: completed in 40.662 ms, heap usage 148.005 MB -> 69.095 MB. [2025-02-26T21:54:34.647Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (545.246 ms) ====== [2025-02-26T21:54:34.647Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-26T21:54:34.647Z] GC before operation: completed in 43.468 ms, heap usage 408.793 MB -> 69.572 MB. [2025-02-26T21:54:35.041Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (546.792 ms) ====== [2025-02-26T21:54:35.041Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-26T21:54:35.041Z] GC before operation: completed in 41.765 ms, heap usage 189.045 MB -> 69.600 MB. [2025-02-26T21:54:35.446Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (552.784 ms) ====== [2025-02-26T21:54:35.446Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-26T21:54:35.898Z] GC before operation: completed in 42.566 ms, heap usage 170.385 MB -> 69.298 MB. [2025-02-26T21:54:36.296Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (555.561 ms) ====== [2025-02-26T21:54:36.296Z] ----------------------------------- [2025-02-26T21:54:36.296Z] renaissance-dec-tree_0_PASSED [2025-02-26T21:54:36.296Z] ----------------------------------- [2025-02-26T21:54:36.296Z] [2025-02-26T21:54:36.296Z] TEST TEARDOWN: [2025-02-26T21:54:36.296Z] Nothing to be done for teardown. [2025-02-26T21:54:36.296Z] renaissance-dec-tree_0 Finish Time: Wed Feb 26 16:54:36 2025 Epoch Time (ms): 1740606876089