[2024-06-26T23:07:28.189Z] Running test renaissance-dec-tree_0 ... [2024-06-26T23:07:28.189Z] =============================================== [2024-06-26T23:07:28.189Z] renaissance-dec-tree_0 Start Time: Wed Jun 26 16:07:27 2024 Epoch Time (ms): 1719443247810 [2024-06-26T23:07:28.189Z] variation: NoOptions [2024-06-26T23:07:28.189Z] JVM_OPTIONS: [2024-06-26T23:07:28.189Z] { \ [2024-06-26T23:07:28.189Z] echo ""; echo "TEST SETUP:"; \ [2024-06-26T23:07:28.189Z] echo "Nothing to be done for setup."; \ [2024-06-26T23:07:28.189Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17194430781820/renaissance-dec-tree_0"; \ [2024-06-26T23:07:28.189Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17194430781820/renaissance-dec-tree_0"; \ [2024-06-26T23:07:28.189Z] echo ""; echo "TESTING:"; \ [2024-06-26T23:07:28.189Z] "/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_17194430781820/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-06-26T23:07:28.189Z] 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_17194430781820/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-26T23:07:28.189Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-26T23:07:28.189Z] echo "Nothing to be done for teardown."; \ [2024-06-26T23:07:28.189Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17194430781820/TestTargetResult"; [2024-06-26T23:07:28.189Z] [2024-06-26T23:07:28.189Z] TEST SETUP: [2024-06-26T23:07:28.189Z] Nothing to be done for setup. [2024-06-26T23:07:28.189Z] [2024-06-26T23:07:28.189Z] TESTING: [2024-06-26T23:07:32.653Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-26T23:07:34.004Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-06-26T23:07:38.516Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-06-26T23:07:38.516Z] GC before operation: completed in 97.811 ms, heap usage 45.753 MB -> 35.438 MB. [2024-06-26T23:07:50.666Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11696.291 ms) ====== [2024-06-26T23:07:50.666Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-06-26T23:07:50.666Z] GC before operation: completed in 127.312 ms, heap usage 236.412 MB -> 64.983 MB. [2024-06-26T23:07:53.664Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3688.716 ms) ====== [2024-06-26T23:07:53.664Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-06-26T23:07:53.664Z] GC before operation: completed in 66.191 ms, heap usage 132.709 MB -> 65.156 MB. [2024-06-26T23:07:56.404Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2304.740 ms) ====== [2024-06-26T23:07:56.404Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-06-26T23:07:56.404Z] GC before operation: completed in 86.097 ms, heap usage 193.800 MB -> 65.873 MB. [2024-06-26T23:07:58.656Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2273.897 ms) ====== [2024-06-26T23:07:58.656Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-06-26T23:07:58.656Z] GC before operation: completed in 102.469 ms, heap usage 197.953 MB -> 93.309 MB. [2024-06-26T23:08:02.430Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3944.364 ms) ====== [2024-06-26T23:08:02.430Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-06-26T23:08:02.430Z] GC before operation: completed in 121.816 ms, heap usage 327.088 MB -> 66.405 MB. [2024-06-26T23:08:06.207Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3195.654 ms) ====== [2024-06-26T23:08:06.207Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-06-26T23:08:06.207Z] GC before operation: completed in 124.570 ms, heap usage 134.284 MB -> 66.075 MB. [2024-06-26T23:08:09.329Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2998.474 ms) ====== [2024-06-26T23:08:09.329Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-06-26T23:08:09.329Z] GC before operation: completed in 127.854 ms, heap usage 249.748 MB -> 66.428 MB. [2024-06-26T23:08:12.347Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3014.346 ms) ====== [2024-06-26T23:08:12.347Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-06-26T23:08:12.347Z] GC before operation: completed in 108.087 ms, heap usage 210.445 MB -> 66.710 MB. [2024-06-26T23:08:14.525Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2083.248 ms) ====== [2024-06-26T23:08:14.525Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-06-26T23:08:14.525Z] GC before operation: completed in 97.130 ms, heap usage 262.483 MB -> 79.823 MB. [2024-06-26T23:08:14.525Z] 24/06/26 16:08:13 WARN BlockManager: Asked to remove block broadcast_120, which does not exist [2024-06-26T23:08:16.709Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1844.806 ms) ====== [2024-06-26T23:08:16.709Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-06-26T23:08:16.709Z] GC before operation: completed in 85.075 ms, heap usage 272.436 MB -> 94.509 MB. [2024-06-26T23:08:18.694Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2225.216 ms) ====== [2024-06-26T23:08:18.694Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-06-26T23:08:18.694Z] GC before operation: completed in 85.871 ms, heap usage 302.577 MB -> 66.853 MB. [2024-06-26T23:08:20.756Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2161.216 ms) ====== [2024-06-26T23:08:20.756Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-06-26T23:08:20.756Z] GC before operation: completed in 114.163 ms, heap usage 384.146 MB -> 94.582 MB. [2024-06-26T23:08:22.762Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1950.402 ms) ====== [2024-06-26T23:08:22.762Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-06-26T23:08:23.164Z] GC before operation: completed in 168.537 ms, heap usage 284.838 MB -> 94.884 MB. [2024-06-26T23:08:25.273Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2165.902 ms) ====== [2024-06-26T23:08:25.273Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-06-26T23:08:25.273Z] GC before operation: completed in 93.270 ms, heap usage 221.958 MB -> 94.893 MB. [2024-06-26T23:08:27.452Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2109.392 ms) ====== [2024-06-26T23:08:27.452Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-06-26T23:08:27.452Z] GC before operation: completed in 119.618 ms, heap usage 518.563 MB -> 67.601 MB. [2024-06-26T23:08:29.564Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1812.932 ms) ====== [2024-06-26T23:08:29.564Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-06-26T23:08:29.564Z] GC before operation: completed in 154.173 ms, heap usage 193.676 MB -> 67.757 MB. [2024-06-26T23:08:32.416Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2826.323 ms) ====== [2024-06-26T23:08:32.416Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-06-26T23:08:32.416Z] GC before operation: completed in 240.042 ms, heap usage 214.221 MB -> 67.994 MB. [2024-06-26T23:08:35.246Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2643.229 ms) ====== [2024-06-26T23:08:35.246Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-06-26T23:08:35.246Z] GC before operation: completed in 188.050 ms, heap usage 320.350 MB -> 67.825 MB. [2024-06-26T23:08:37.945Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2486.850 ms) ====== [2024-06-26T23:08:37.945Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-06-26T23:08:37.945Z] GC before operation: completed in 123.006 ms, heap usage 116.228 MB -> 80.771 MB. [2024-06-26T23:08:40.031Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2145.591 ms) ====== [2024-06-26T23:08:40.031Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-06-26T23:08:40.467Z] GC before operation: completed in 153.812 ms, heap usage 342.923 MB -> 68.119 MB. [2024-06-26T23:08:41.954Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1863.800 ms) ====== [2024-06-26T23:08:41.954Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-06-26T23:08:42.405Z] GC before operation: completed in 97.230 ms, heap usage 312.574 MB -> 81.098 MB. [2024-06-26T23:08:44.459Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1854.605 ms) ====== [2024-06-26T23:08:44.460Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-06-26T23:08:44.460Z] GC before operation: completed in 103.712 ms, heap usage 255.860 MB -> 68.161 MB. [2024-06-26T23:08:45.865Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1759.880 ms) ====== [2024-06-26T23:08:45.865Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-06-26T23:08:46.278Z] GC before operation: completed in 169.339 ms, heap usage 243.425 MB -> 68.138 MB. [2024-06-26T23:08:48.307Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1802.523 ms) ====== [2024-06-26T23:08:48.307Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-06-26T23:08:48.307Z] GC before operation: completed in 122.280 ms, heap usage 318.369 MB -> 68.415 MB. [2024-06-26T23:08:49.574Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1705.949 ms) ====== [2024-06-26T23:08:49.574Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-06-26T23:08:49.957Z] GC before operation: completed in 99.269 ms, heap usage 380.941 MB -> 81.077 MB. [2024-06-26T23:08:51.409Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1755.871 ms) ====== [2024-06-26T23:08:51.876Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-06-26T23:08:51.876Z] GC before operation: completed in 104.665 ms, heap usage 127.389 MB -> 96.410 MB. [2024-06-26T23:08:53.843Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2145.782 ms) ====== [2024-06-26T23:08:53.843Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-06-26T23:08:53.843Z] GC before operation: completed in 123.206 ms, heap usage 626.405 MB -> 69.014 MB. [2024-06-26T23:08:55.812Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1611.679 ms) ====== [2024-06-26T23:08:55.812Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-06-26T23:08:55.812Z] GC before operation: completed in 81.072 ms, heap usage 119.390 MB -> 68.203 MB. [2024-06-26T23:08:57.759Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1745.417 ms) ====== [2024-06-26T23:08:57.759Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-06-26T23:08:57.759Z] GC 24/06/26 16:08:57 WARN BlockManager: Asked to remove block broadcast_404, which does not exist [2024-06-26T23:08:57.759Z] before operation: completed in 100.163 ms, heap usage 212.079 MB -> 94.912 MB. [2024-06-26T23:09:00.473Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2606.607 ms) ====== [2024-06-26T23:09:00.473Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-06-26T23:09:00.473Z] GC before operation: completed in 211.611 ms, heap usage 474.462 MB -> 69.271 MB. [2024-06-26T23:09:03.272Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2476.790 ms) ====== [2024-06-26T23:09:03.273Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-06-26T23:09:03.273Z] GC before operation: completed in 181.763 ms, heap usage 354.688 MB -> 96.652 MB. [2024-06-26T23:09:03.273Z] 24/06/26 16:09:02 WARN BlockManager: Asked to remove block broadcast_428_piece0, which does not exist [2024-06-26T23:09:06.382Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3249.271 ms) ====== [2024-06-26T23:09:06.382Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-06-26T23:09:06.382Z] GC before operation: completed in 169.080 ms, heap usage 248.277 MB -> 69.191 MB. [2024-06-26T23:09:09.095Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2507.828 ms) ====== [2024-06-26T23:09:09.095Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-06-26T23:09:09.095Z] GC before operation: completed in 201.029 ms, heap usage 260.278 MB -> 69.324 MB. [2024-06-26T23:09:12.043Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2550.825 ms) ====== [2024-06-26T23:09:12.043Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-06-26T23:09:12.043Z] GC before operation: completed in 210.855 ms, heap usage 485.779 MB -> 69.725 MB. [2024-06-26T23:09:14.764Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2755.981 ms) ====== [2024-06-26T23:09:14.764Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-06-26T23:09:14.764Z] GC before operation: completed in 169.291 ms, heap usage 269.770 MB -> 69.500 MB. [2024-06-26T23:09:18.060Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2860.865 ms) ====== [2024-06-26T23:09:18.060Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-06-26T23:09:18.060Z] GC before operation: completed in 306.644 ms, heap usage 330.835 MB -> 84.380 MB. [2024-06-26T23:09:18.060Z] 24/06/26 16:09:17 WARN BlockManager: Asked to remove block broadcast_498, which does not exist [2024-06-26T23:09:20.864Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2982.032 ms) ====== [2024-06-26T23:09:20.864Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-06-26T23:09:21.310Z] GC before operation: completed in 155.960 ms, heap usage 481.872 MB -> 70.105 MB. [2024-06-26T23:09:23.421Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2151.925 ms) ====== [2024-06-26T23:09:23.421Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-06-26T23:09:23.421Z] GC before operation: completed in 198.051 ms, heap usage 343.143 MB -> 70.034 MB. [2024-06-26T23:09:26.280Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2628.139 ms) ====== [2024-06-26T23:09:26.280Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-06-26T23:09:26.280Z] GC before operation: completed in 212.103 ms, heap usage 182.577 MB -> 69.870 MB. [2024-06-26T23:09:29.137Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2783.998 ms) ====== [2024-06-26T23:09:29.982Z] ----------------------------------- [2024-06-26T23:09:29.982Z] renaissance-dec-tree_0_PASSED [2024-06-26T23:09:29.982Z] ----------------------------------- [2024-06-26T23:09:29.982Z] [2024-06-26T23:09:29.982Z] TEST TEARDOWN: [2024-06-26T23:09:29.982Z] Nothing to be done for teardown. [2024-06-26T23:09:29.982Z] renaissance-dec-tree_0 Finish Time: Wed Jun 26 16:09:29 2024 Epoch Time (ms): 1719443369412