[2025-01-11T04:51:40.168Z] Running test renaissance-dec-tree_0 ... [2025-01-11T04:51:40.168Z] =============================================== [2025-01-11T04:51:40.168Z] renaissance-dec-tree_0 Start Time: Fri Jan 10 20:51:40 2025 Epoch Time (ms): 1736571100065 [2025-01-11T04:51:40.168Z] variation: NoOptions [2025-01-11T04:51:40.168Z] JVM_OPTIONS: [2025-01-11T04:51:40.168Z] { \ [2025-01-11T04:51:40.168Z] echo ""; echo "TEST SETUP:"; \ [2025-01-11T04:51:40.168Z] echo "Nothing to be done for setup."; \ [2025-01-11T04:51:40.168Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17365706868889/renaissance-dec-tree_0"; \ [2025-01-11T04:51:40.168Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17365706868889/renaissance-dec-tree_0"; \ [2025-01-11T04:51:40.168Z] echo ""; echo "TESTING:"; \ [2025-01-11T04:51:40.168Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/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_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17365706868889/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-01-11T04:51:40.168Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17365706868889/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-11T04:51:40.168Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-11T04:51:40.168Z] echo "Nothing to be done for teardown."; \ [2025-01-11T04:51:40.168Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17365706868889/TestTargetResult"; [2025-01-11T04:51:40.168Z] [2025-01-11T04:51:40.168Z] TEST SETUP: [2025-01-11T04:51:40.168Z] Nothing to be done for setup. [2025-01-11T04:51:40.168Z] [2025-01-11T04:51:40.168Z] TESTING: [2025-01-11T04:52:07.127Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-11T04:52:10.027Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-01-11T04:52:25.692Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-01-11T04:52:25.692Z] GC before operation: completed in 249.865 ms, heap usage 51.735 MB -> 35.506 MB. [2025-01-11T04:53:12.247Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (43892.247 ms) ====== [2025-01-11T04:53:12.247Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-01-11T04:53:12.247Z] GC before operation: completed in 361.573 ms, heap usage 365.186 MB -> 65.271 MB. [2025-01-11T04:53:24.297Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (14992.426 ms) ====== [2025-01-11T04:53:24.297Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-01-11T04:53:24.876Z] GC before operation: completed in 1413.698 ms, heap usage 197.806 MB -> 65.499 MB. [2025-01-11T04:53:33.138Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (8642.459 ms) ====== [2025-01-11T04:53:33.138Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-01-11T04:53:33.576Z] GC before operation: completed in 239.011 ms, heap usage 341.494 MB -> 93.844 MB. [2025-01-11T04:53:53.263Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (17133.157 ms) ====== [2025-01-11T04:53:53.263Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-01-11T04:53:53.263Z] GC before operation: completed in 285.897 ms, heap usage 294.770 MB -> 66.266 MB. [2025-01-11T04:54:00.094Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (8545.290 ms) ====== [2025-01-11T04:54:00.094Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-01-11T04:54:00.094Z] GC before operation: completed in 358.273 ms, heap usage 138.115 MB -> 65.827 MB. [2025-01-11T04:54:08.559Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (8843.224 ms) ====== [2025-01-11T04:54:08.559Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-01-11T04:54:09.093Z] GC before operation: completed in 296.984 ms, heap usage 235.409 MB -> 66.336 MB. [2025-01-11T04:54:20.943Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (10387.704 ms) ====== [2025-01-11T04:54:20.943Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-01-11T04:54:20.943Z] GC before operation: completed in 422.016 ms, heap usage 248.602 MB -> 94.201 MB. [2025-01-11T04:54:30.674Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (9372.171 ms) ====== [2025-01-11T04:54:30.674Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-01-11T04:54:30.674Z] GC before operation: completed in 522.809 ms, heap usage 302.780 MB -> 66.494 MB. [2025-01-11T04:54:38.602Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (8996.780 ms) ====== [2025-01-11T04:54:38.602Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-01-11T04:54:39.189Z] GC before operation: completed in 212.049 ms, heap usage 370.888 MB -> 66.712 MB. [2025-01-11T04:54:49.560Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (8951.705 ms) ====== [2025-01-11T04:54:49.560Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-01-11T04:54:49.560Z] GC before operation: completed in 298.735 ms, heap usage 302.444 MB -> 66.930 MB. [2025-01-11T04:54:57.463Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (8816.468 ms) ====== [2025-01-11T04:54:57.463Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-01-11T04:54:57.463Z] GC before operation: completed in 247.254 ms, heap usage 135.084 MB -> 66.788 MB. [2025-01-11T04:55:07.115Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (9409.137 ms) ====== [2025-01-11T04:55:07.115Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-01-11T04:55:07.115Z] GC before operation: completed in 156.677 ms, heap usage 318.303 MB -> 66.996 MB. [2025-01-11T04:55:14.584Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (6716.324 ms) ====== [2025-01-11T04:55:14.584Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-01-11T04:55:14.584Z] GC before operation: completed in 262.717 ms, heap usage 379.632 MB -> 80.327 MB. [2025-01-11T04:55:14.584Z] 25/01/10 20:55:13 WARN BlockManager: Asked to remove block rdd_634_0, which does not exist [2025-01-11T04:55:24.227Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (10065.714 ms) ====== [2025-01-11T04:55:24.227Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-01-11T04:55:24.227Z] GC before operation: completed in 269.481 ms, heap usage 530.257 MB -> 67.718 MB. [2025-01-11T04:55:34.316Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (8961.425 ms) ====== [2025-01-11T04:55:34.316Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-01-11T04:55:34.316Z] GC before operation: completed in 356.469 ms, heap usage 114.354 MB -> 66.867 MB. [2025-01-11T04:55:40.856Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (7133.839 ms) ====== [2025-01-11T04:55:40.856Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-01-11T04:55:40.856Z] GC before operation: completed in 214.123 ms, heap usage 229.898 MB -> 95.496 MB. [2025-01-11T04:55:48.844Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (6734.251 ms) ====== [2025-01-11T04:55:48.844Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-01-11T04:55:48.844Z] GC before operation: completed in 250.190 ms, heap usage 270.846 MB -> 67.582 MB. [2025-01-11T04:55:55.551Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (6932.939 ms) ====== [2025-01-11T04:55:55.551Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-01-11T04:55:56.274Z] GC before operation: completed in 952.301 ms, heap usage 121.491 MB -> 67.143 MB. [2025-01-11T04:56:04.298Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (7224.719 ms) ====== [2025-01-11T04:56:04.298Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-01-11T04:56:04.298Z] GC before operation: completed in 271.535 ms, heap usage 503.501 MB -> 68.101 MB. [2025-01-11T04:56:12.261Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (8591.397 ms) ====== [2025-01-11T04:56:12.261Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-01-11T04:56:12.730Z] GC before operation: completed in 242.308 ms, heap usage 300.924 MB -> 95.663 MB. [2025-01-11T04:56:19.535Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (6491.945 ms) ====== [2025-01-11T04:56:19.535Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-01-11T04:56:19.535Z] GC before operation: completed in 180.935 ms, heap usage 227.612 MB -> 68.063 MB. [2025-01-11T04:56:26.156Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (6421.188 ms) ====== [2025-01-11T04:56:26.156Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-01-11T04:56:26.729Z] GC before operation: completed in 947.329 ms, heap usage 332.450 MB -> 95.804 MB. [2025-01-11T04:56:35.005Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (7241.028 ms) ====== [2025-01-11T04:56:35.005Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-01-11T04:56:35.006Z] GC before operation: completed in 510.716 ms, heap usage 570.373 MB -> 81.539 MB. [2025-01-11T04:56:42.729Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (8304.690 ms) ====== [2025-01-11T04:56:42.729Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-01-11T04:56:42.729Z] GC before operation: completed in 134.213 ms, heap usage 206.106 MB -> 68.363 MB. [2025-01-11T04:56:50.212Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (7111.831 ms) ====== [2025-01-11T04:56:50.212Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-01-11T04:56:50.212Z] GC before operation: completed in 317.498 ms, heap usage 244.370 MB -> 68.685 MB. [2025-01-11T04:56:58.017Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (6805.086 ms) ====== [2025-01-11T04:56:58.017Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-01-11T04:56:58.017Z] GC before operation: completed in 628.957 ms, heap usage 122.176 MB -> 68.107 MB. [2025-01-11T04:57:05.521Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (7860.505 ms) ====== [2025-01-11T04:57:05.521Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-01-11T04:57:06.240Z] GC before operation: completed in 349.865 ms, heap usage 274.500 MB -> 68.928 MB. [2025-01-11T04:57:13.640Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (7168.766 ms) ====== [2025-01-11T04:57:13.640Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-01-11T04:57:13.640Z] GC before operation: completed in 243.146 ms, heap usage 216.180 MB -> 68.798 MB. [2025-01-11T04:57:23.055Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (8474.255 ms) ====== [2025-01-11T04:57:23.055Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-01-11T04:57:23.055Z] GC before operation: completed in 197.292 ms, heap usage 112.301 MB -> 96.501 MB. [2025-01-11T04:57:30.254Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (7321.343 ms) ====== [2025-01-11T04:57:30.254Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-01-11T04:57:30.254Z] GC before operation: completed in 567.454 ms, heap usage 270.947 MB -> 96.572 MB. [2025-01-11T04:57:37.582Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (7551.903 ms) ====== [2025-01-11T04:57:37.582Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-01-11T04:57:37.582Z] GC before operation: completed in 299.213 ms, heap usage 255.334 MB -> 69.376 MB. [2025-01-11T04:57:43.664Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (6165.139 ms) ====== [2025-01-11T04:57:43.664Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-01-11T04:57:44.150Z] GC before operation: completed in 329.354 ms, heap usage 138.221 MB -> 69.328 MB. [2025-01-11T04:57:49.421Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (5072.324 ms) ====== [2025-01-11T04:57:49.421Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-01-11T04:57:49.421Z] GC before operation: completed in 217.904 ms, heap usage 227.266 MB -> 97.129 MB. [2025-01-11T04:57:56.055Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (5707.423 ms) ====== [2025-01-11T04:57:56.055Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-01-11T04:57:56.055Z] GC before operation: completed in 216.505 ms, heap usage 474.671 MB -> 69.920 MB. [2025-01-11T04:58:02.457Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (6187.309 ms) ====== [2025-01-11T04:58:02.457Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-01-11T04:58:02.457Z] GC before operation: completed in 566.930 ms, heap usage 273.045 MB -> 69.905 MB. [2025-01-11T04:58:10.939Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (8602.230 ms) ====== [2025-01-11T04:58:10.939Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-01-11T04:58:10.939Z] GC before operation: completed in 282.371 ms, heap usage 222.847 MB -> 97.461 MB. [2025-01-11T04:58:20.355Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (7747.689 ms) ====== [2025-01-11T04:58:20.355Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-01-11T04:58:20.355Z] GC before operation: completed in 546.482 ms, heap usage 344.363 MB -> 97.197 MB. [2025-01-11T04:58:27.799Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (7587.684 ms) ====== [2025-01-11T04:58:27.799Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-01-11T04:58:27.799Z] GC before operation: completed in 318.770 ms, heap usage 156.344 MB -> 97.340 MB. [2025-01-11T04:58:32.844Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (5781.208 ms) ====== [2025-01-11T04:58:32.844Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-01-11T04:58:33.366Z] GC before operation: completed in 276.305 ms, heap usage 358.638 MB -> 97.510 MB. [2025-01-11T04:58:40.529Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (6470.181 ms) ====== [2025-01-11T04:58:42.470Z] ----------------------------------- [2025-01-11T04:58:42.470Z] renaissance-dec-tree_0_PASSED [2025-01-11T04:58:42.470Z] ----------------------------------- [2025-01-11T04:58:42.470Z] [2025-01-11T04:58:42.470Z] TEST TEARDOWN: [2025-01-11T04:58:42.470Z] Nothing to be done for teardown. [2025-01-11T04:58:42.470Z] renaissance-dec-tree_0 Finish Time: Fri Jan 10 20:58:42 2025 Epoch Time (ms): 1736571522334