[2024-11-22T21:50:22.713Z] Running test renaissance-dec-tree_0 ... [2024-11-22T21:50:22.713Z] =============================================== [2024-11-22T21:50:22.713Z] renaissance-dec-tree_0 Start Time: Fri Nov 22 16:50:22 2024 Epoch Time (ms): 1732312222439 [2024-11-22T21:50:22.713Z] variation: NoOptions [2024-11-22T21:50:22.713Z] JVM_OPTIONS: [2024-11-22T21:50:22.713Z] { \ [2024-11-22T21:50:22.713Z] echo ""; echo "TEST SETUP:"; \ [2024-11-22T21:50:22.713Z] echo "Nothing to be done for setup."; \ [2024-11-22T21:50:22.713Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17323120598852/renaissance-dec-tree_0"; \ [2024-11-22T21:50:22.713Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17323120598852/renaissance-dec-tree_0"; \ [2024-11-22T21:50:22.713Z] echo ""; echo "TESTING:"; \ [2024-11-22T21:50:22.713Z] "/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_17323120598852/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-11-22T21:50:22.713Z] 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_17323120598852/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-22T21:50:22.713Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-22T21:50:22.713Z] echo "Nothing to be done for teardown."; \ [2024-11-22T21:50:22.713Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17323120598852/TestTargetResult"; [2024-11-22T21:50:22.713Z] [2024-11-22T21:50:22.713Z] TEST SETUP: [2024-11-22T21:50:22.713Z] Nothing to be done for setup. [2024-11-22T21:50:22.713Z] [2024-11-22T21:50:22.713Z] TESTING: [2024-11-22T21:50:25.124Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-22T21:50:25.483Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-11-22T21:50:27.272Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-22T21:50:27.272Z] GC before operation: completed in 28.569 ms, heap usage 70.469 MB -> 35.200 MB. [2024-11-22T21:50:32.513Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (4368.066 ms) ====== [2024-11-22T21:50:32.513Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-22T21:50:32.513Z] GC before operation: completed in 37.190 ms, heap usage 220.683 MB -> 64.593 MB. [2024-11-22T21:50:32.882Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1268.948 ms) ====== [2024-11-22T21:50:32.882Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-22T21:50:32.882Z] GC before operation: completed in 41.728 ms, heap usage 221.972 MB -> 64.964 MB. [2024-11-22T21:50:34.197Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1097.506 ms) ====== [2024-11-22T21:50:34.198Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-22T21:50:34.198Z] GC before operation: completed in 46.871 ms, heap usage 156.889 MB -> 64.958 MB. [2024-11-22T21:50:35.005Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (998.175 ms) ====== [2024-11-22T21:50:35.005Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-22T21:50:35.424Z] GC before operation: completed in 47.675 ms, heap usage 266.927 MB -> 65.632 MB. [2024-11-22T21:50:36.192Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (873.620 ms) ====== [2024-11-22T21:50:36.192Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-22T21:50:36.192Z] GC before operation: completed in 42.441 ms, heap usage 270.524 MB -> 78.654 MB. [2024-11-22T21:50:37.000Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (850.314 ms) ====== [2024-11-22T21:50:37.000Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-22T21:50:37.000Z] GC before operation: completed in 51.346 ms, heap usage 299.846 MB -> 65.882 MB. [2024-11-22T21:50:37.808Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (876.494 ms) ====== [2024-11-22T21:50:37.808Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-22T21:50:37.808Z] GC before operation: completed in 47.706 ms, heap usage 273.110 MB -> 66.094 MB. [2024-11-22T21:50:39.048Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (831.664 ms) ====== [2024-11-22T21:50:39.048Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-22T21:50:39.048Z] GC before operation: completed in 49.534 ms, heap usage 278.110 MB -> 66.397 MB. [2024-11-22T21:50:39.874Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (820.743 ms) ====== [2024-11-22T21:50:39.874Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-22T21:50:39.874Z] GC before operation: completed in 38.547 ms, heap usage 258.293 MB -> 66.371 MB. [2024-11-22T21:50:40.649Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (828.490 ms) ====== [2024-11-22T21:50:40.649Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-22T21:50:40.649Z] GC before operation: completed in 46.920 ms, heap usage 101.984 MB -> 65.933 MB. [2024-11-22T21:50:41.442Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (801.114 ms) ====== [2024-11-22T21:50:41.442Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-22T21:50:41.442Z] GC before operation: completed in 43.793 ms, heap usage 160.755 MB -> 66.783 MB. [2024-11-22T21:50:42.271Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (765.631 ms) ====== [2024-11-22T21:50:42.271Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-22T21:50:42.271Z] GC before operation: completed in 39.834 ms, heap usage 219.001 MB -> 66.792 MB. [2024-11-22T21:50:43.103Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (749.975 ms) ====== [2024-11-22T21:50:43.103Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-22T21:50:43.103Z] GC before operation: completed in 50.945 ms, heap usage 97.826 MB -> 66.373 MB. [2024-11-22T21:50:43.889Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (754.148 ms) ====== [2024-11-22T21:50:43.889Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-22T21:50:43.889Z] GC before operation: completed in 50.044 ms, heap usage 101.148 MB -> 66.673 MB. [2024-11-22T21:50:44.746Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (806.474 ms) ====== [2024-11-22T21:50:44.746Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-22T21:50:44.746Z] 24/11/22 16:50:44 WARN BlockManager: Asked to remove block rdd_732_2, which does not exist [2024-11-22T21:50:44.746Z] GC before operation: completed in 56.200 ms, heap usage 358.905 MB -> 82.375 MB. [2024-11-22T21:50:45.525Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (755.810 ms) ====== [2024-11-22T21:50:45.525Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-22T21:50:45.525Z] GC before operation: completed in 43.371 ms, heap usage 331.518 MB -> 67.439 MB. [2024-11-22T21:50:46.331Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (740.624 ms) ====== [2024-11-22T21:50:46.331Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-22T21:50:46.331Z] GC before operation: completed in 53.486 ms, heap usage 206.239 MB -> 67.412 MB. [2024-11-22T21:50:47.099Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (696.548 ms) ====== [2024-11-22T21:50:47.099Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-22T21:50:47.099Z] GC before operation: completed in 47.556 ms, heap usage 242.968 MB -> 67.673 MB. [2024-11-22T21:50:47.887Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (770.966 ms) ====== [2024-11-22T21:50:47.887Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-22T21:50:47.887Z] GC before operation: completed in 50.792 ms, heap usage 337.960 MB -> 95.260 MB. [2024-11-22T21:50:48.687Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (665.380 ms) ====== [2024-11-22T21:50:48.687Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-22T21:50:48.687Z] GC before operation: completed in 55.442 ms, heap usage 285.762 MB -> 67.735 MB. [2024-11-22T21:50:49.454Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (698.639 ms) ====== [2024-11-22T21:50:49.454Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-22T21:50:49.454Z] GC before operation: completed in 54.634 ms, heap usage 133.430 MB -> 67.589 MB. [2024-11-22T21:50:50.224Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (701.657 ms) ====== [2024-11-22T21:50:50.224Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-22T21:50:50.224Z] GC before operation: completed in 50.112 ms, heap usage 418.419 MB -> 67.929 MB. [2024-11-22T21:50:50.584Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (672.428 ms) ====== [2024-11-22T21:50:50.584Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-22T21:50:50.940Z] GC before operation: completed in 55.722 ms, heap usage 244.739 MB -> 93.880 MB. [2024-11-22T21:50:51.704Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (738.601 ms) ====== [2024-11-22T21:50:51.704Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-22T21:50:51.704Z] GC before operation: completed in 54.608 ms, heap usage 336.282 MB -> 82.628 MB. [2024-11-22T21:50:52.474Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (721.228 ms) ====== [2024-11-22T21:50:52.474Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-22T21:50:52.475Z] GC before operation: completed in 59.947 ms, heap usage 321.588 MB -> 83.224 MB. [2024-11-22T21:50:53.237Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (718.890 ms) ====== [2024-11-22T21:50:53.237Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-22T21:50:53.237Z] GC before operation: completed in 62.422 ms, heap usage 297.847 MB -> 68.385 MB. [2024-11-22T21:50:54.056Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (704.614 ms) ====== [2024-11-22T21:50:54.056Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-22T21:50:54.056Z] GC before operation: completed in 51.480 ms, heap usage 326.031 MB -> 68.615 MB. [2024-11-22T21:50:54.825Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (719.858 ms) ====== [2024-11-22T21:50:54.825Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-22T21:50:54.825Z] GC before operation: completed in 52.513 ms, heap usage 311.753 MB -> 68.600 MB. [2024-11-22T21:50:55.592Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (682.837 ms) ====== [2024-11-22T21:50:55.592Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-22T21:50:55.592Z] GC before operation: completed in 56.586 ms, heap usage 202.213 MB -> 68.675 MB. [2024-11-22T21:50:56.364Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (727.023 ms) ====== [2024-11-22T21:50:56.365Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-22T21:50:56.365Z] GC before operation: completed in 46.945 ms, heap usage 200.278 MB -> 83.682 MB. [2024-11-22T21:50:57.142Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (710.088 ms) ====== [2024-11-22T21:50:57.142Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-22T21:50:57.142Z] GC before operation: completed in 56.006 ms, heap usage 319.440 MB -> 68.977 MB. [2024-11-22T21:50:57.501Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (700.454 ms) ====== [2024-11-22T21:50:57.501Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-22T21:50:57.860Z] GC before operation: completed in 56.007 ms, heap usage 206.980 MB -> 68.907 MB. [2024-11-22T21:50:58.627Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (703.770 ms) ====== [2024-11-22T21:50:58.627Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-22T21:50:58.627Z] GC before operation: completed in 56.712 ms, heap usage 289.543 MB -> 69.173 MB. [2024-11-22T21:50:58.994Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (673.304 ms) ====== [2024-11-22T21:50:58.994Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-22T21:50:59.357Z] GC before operation: completed in 64.306 ms, heap usage 131.938 MB -> 83.554 MB. [2024-11-22T21:51:00.134Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (704.297 ms) ====== [2024-11-22T21:51:00.134Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-22T21:51:00.134Z] GC before operation: completed in 54.386 ms, heap usage 219.285 MB -> 69.417 MB. [2024-11-22T21:51:00.499Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (678.093 ms) ====== [2024-11-22T21:51:00.499Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-22T21:51:00.861Z] GC before operation: completed in 68.335 ms, heap usage 212.190 MB -> 84.114 MB. [2024-11-22T21:51:01.230Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (667.029 ms) ====== [2024-11-22T21:51:01.230Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-22T21:51:01.599Z] GC before operation: completed in 55.557 ms, heap usage 320.659 MB -> 69.662 MB. [2024-11-22T21:51:01.961Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (671.958 ms) ====== [2024-11-22T21:51:01.961Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-22T21:51:02.324Z] GC before operation: completed in 59.449 ms, heap usage 197.013 MB -> 84.530 MB. [2024-11-22T21:51:02.684Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (672.247 ms) ====== [2024-11-22T21:51:02.684Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-22T21:51:03.068Z] GC before operation: completed in 61.889 ms, heap usage 163.867 MB -> 69.352 MB. [2024-11-22T21:51:03.439Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (686.068 ms) ====== [2024-11-22T21:51:03.809Z] ----------------------------------- [2024-11-22T21:51:03.809Z] renaissance-dec-tree_0_PASSED [2024-11-22T21:51:03.809Z] ----------------------------------- [2024-11-22T21:51:03.809Z] [2024-11-22T21:51:03.809Z] TEST TEARDOWN: [2024-11-22T21:51:03.809Z] Nothing to be done for teardown. [2024-11-22T21:51:03.809Z] renaissance-dec-tree_0 Finish Time: Fri Nov 22 16:51:03 2024 Epoch Time (ms): 1732312263512