[2025-03-27T04:05:10.537Z] Running test renaissance-dec-tree_0 ... [2025-03-27T04:05:10.537Z] =============================================== [2025-03-27T04:05:11.430Z] renaissance-dec-tree_0 Start Time: Wed Mar 26 21:05:10 2025 Epoch Time (ms): 1743048310701 [2025-03-27T04:05:11.430Z] variation: NoOptions [2025-03-27T04:05:11.430Z] JVM_OPTIONS: [2025-03-27T04:05:11.430Z] { \ [2025-03-27T04:05:11.430Z] echo ""; echo "TEST SETUP:"; \ [2025-03-27T04:05:11.430Z] echo "Nothing to be done for setup."; \ [2025-03-27T04:05:11.430Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17430478064651/renaissance-dec-tree_0"; \ [2025-03-27T04:05:11.430Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17430478064651/renaissance-dec-tree_0"; \ [2025-03-27T04:05:11.430Z] echo ""; echo "TESTING:"; \ [2025-03-27T04:05:11.431Z] "/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_17430478064651/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-03-27T04:05:11.431Z] 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_17430478064651/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-27T04:05:11.431Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-27T04:05:11.431Z] echo "Nothing to be done for teardown."; \ [2025-03-27T04:05:11.431Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17430478064651/TestTargetResult"; [2025-03-27T04:05:11.431Z] [2025-03-27T04:05:11.431Z] TEST SETUP: [2025-03-27T04:05:11.431Z] Nothing to be done for setup. [2025-03-27T04:05:11.431Z] [2025-03-27T04:05:11.431Z] TESTING: [2025-03-27T04:05:36.629Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-03-27T04:05:47.660Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-27T04:05:47.660Z] GC before operation: completed in 350.400 ms, heap usage 41.614 MB -> 34.764 MB. [2025-03-27T04:06:33.244Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (45128.210 ms) ====== [2025-03-27T04:06:33.244Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-27T04:06:33.244Z] GC before operation: completed in 304.506 ms, heap usage 227.647 MB -> 68.483 MB. [2025-03-27T04:06:45.284Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (12976.846 ms) ====== [2025-03-27T04:06:45.284Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-27T04:06:45.284Z] GC before operation: completed in 94.529 ms, heap usage 438.997 MB -> 68.901 MB. [2025-03-27T04:06:57.193Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (10962.657 ms) ====== [2025-03-27T04:06:57.193Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-27T04:06:57.193Z] GC before operation: completed in 273.164 ms, heap usage 318.508 MB -> 69.325 MB. [2025-03-27T04:07:09.135Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (11818.799 ms) ====== [2025-03-27T04:07:09.135Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-27T04:07:09.135Z] GC before operation: completed in 197.362 ms, heap usage 273.273 MB -> 97.151 MB. [2025-03-27T04:07:19.464Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (9757.076 ms) ====== [2025-03-27T04:07:19.464Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-27T04:07:19.464Z] GC before operation: completed in 200.265 ms, heap usage 262.599 MB -> 69.763 MB. [2025-03-27T04:07:36.182Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (15808.602 ms) ====== [2025-03-27T04:07:36.182Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-27T04:07:36.182Z] GC before operation: completed in 1351.246 ms, heap usage 399.375 MB -> 69.840 MB. [2025-03-27T04:07:42.911Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (6290.281 ms) ====== [2025-03-27T04:07:42.911Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-27T04:07:42.911Z] GC before operation: completed in 255.443 ms, heap usage 185.210 MB -> 69.765 MB. [2025-03-27T04:07:54.202Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (10791.032 ms) ====== [2025-03-27T04:07:54.202Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-27T04:07:54.202Z] GC before operation: completed in 281.693 ms, heap usage 182.451 MB -> 97.494 MB. [2025-03-27T04:08:03.610Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (9400.697 ms) ====== [2025-03-27T04:08:03.610Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-27T04:08:03.610Z] GC before operation: completed in 236.844 ms, heap usage 121.838 MB -> 70.138 MB. [2025-03-27T04:08:11.601Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (7373.537 ms) ====== [2025-03-27T04:08:11.601Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-27T04:08:12.336Z] GC before operation: completed in 1832.123 ms, heap usage 451.156 MB -> 84.831 MB. [2025-03-27T04:08:12.337Z] 21:08:11.981 WARN [block-manager-storage-async-thread-pool-38] org.apache.spark.storage.BlockManager - Asked to remove block rdd_477_0, which does not exist [2025-03-27T04:08:21.598Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (7906.343 ms) ====== [2025-03-27T04:08:21.598Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-27T04:08:21.598Z] GC before operation: completed in 272.060 ms, heap usage 215.228 MB -> 70.183 MB. [2025-03-27T04:08:29.780Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (8910.500 ms) ====== [2025-03-27T04:08:29.780Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-27T04:08:30.713Z] GC before operation: completed in 601.076 ms, heap usage 295.733 MB -> 70.610 MB. [2025-03-27T04:08:40.391Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (9142.274 ms) ====== [2025-03-27T04:08:40.391Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-27T04:08:40.391Z] GC before operation: completed in 966.341 ms, heap usage 192.664 MB -> 70.444 MB. [2025-03-27T04:08:48.374Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (8014.158 ms) ====== [2025-03-27T04:08:48.374Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-27T04:08:49.008Z] GC before operation: completed in 878.564 ms, heap usage 184.139 MB -> 70.771 MB. [2025-03-27T04:08:56.390Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (6930.677 ms) ====== [2025-03-27T04:08:56.390Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-27T04:08:56.390Z] GC before operation: completed in 276.016 ms, heap usage 414.402 MB -> 71.391 MB. [2025-03-27T04:09:03.839Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (7231.466 ms) ====== [2025-03-27T04:09:03.839Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-27T04:09:03.839Z] GC before operation: completed in 248.923 ms, heap usage 126.507 MB -> 70.641 MB. [2025-03-27T04:09:11.759Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (8189.026 ms) ====== [2025-03-27T04:09:11.759Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-27T04:09:12.202Z] GC before operation: completed in 306.663 ms, heap usage 373.897 MB -> 98.931 MB. [2025-03-27T04:09:18.565Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (6267.700 ms) ====== [2025-03-27T04:09:18.565Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-27T04:09:19.118Z] GC before operation: completed in 265.978 ms, heap usage 566.763 MB -> 71.777 MB. [2025-03-27T04:09:25.463Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (6265.938 ms) ====== [2025-03-27T04:09:25.463Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-27T04:09:25.463Z] GC before operation: completed in 286.427 ms, heap usage 158.063 MB -> 71.327 MB. [2025-03-27T04:09:33.624Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (7633.440 ms) ====== [2025-03-27T04:09:33.624Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-27T04:09:33.624Z] GC before operation: completed in 317.983 ms, heap usage 355.352 MB -> 71.916 MB. [2025-03-27T04:09:40.277Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (6900.182 ms) ====== [2025-03-27T04:09:40.277Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-27T04:09:40.277Z] GC before operation: completed in 174.049 ms, heap usage 167.383 MB -> 71.556 MB. [2025-03-27T04:09:48.143Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (7505.326 ms) ====== [2025-03-27T04:09:48.143Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-27T04:09:48.143Z] GC before operation: completed in 269.228 ms, heap usage 293.398 MB -> 72.012 MB. [2025-03-27T04:09:54.179Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (5860.064 ms) ====== [2025-03-27T04:09:54.179Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-27T04:09:54.179Z] GC before operation: completed in 240.983 ms, heap usage 335.415 MB -> 72.058 MB. [2025-03-27T04:10:02.772Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (8715.049 ms) ====== [2025-03-27T04:10:02.772Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-27T04:10:03.239Z] GC before operation: completed in 251.133 ms, heap usage 326.352 MB -> 72.072 MB. [2025-03-27T04:10:12.669Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (8132.328 ms) ====== [2025-03-27T04:10:12.669Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-27T04:10:12.669Z] GC before operation: completed in 229.009 ms, heap usage 193.295 MB -> 74.129 MB. [2025-03-27T04:10:19.403Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (7026.964 ms) ====== [2025-03-27T04:10:19.403Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-27T04:10:19.403Z] GC before operation: completed in 295.143 ms, heap usage 111.596 MB -> 72.092 MB. [2025-03-27T04:10:27.044Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (7766.684 ms) ====== [2025-03-27T04:10:27.045Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-27T04:10:27.045Z] GC before operation: completed in 370.427 ms, heap usage 480.820 MB -> 72.403 MB. [2025-03-27T04:10:34.907Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (7988.426 ms) ====== [2025-03-27T04:10:34.907Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-27T04:10:35.395Z] GC before operation: completed in 372.253 ms, heap usage 264.268 MB -> 100.288 MB. [2025-03-27T04:10:35.395Z] 21:10:34.954 WARN [block-manager-storage-async-thread-pool-218] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_387, which does not exist [2025-03-27T04:10:43.221Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (7083.308 ms) ====== [2025-03-27T04:10:43.221Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-27T04:10:43.892Z] GC before operation: completed in 1455.797 ms, heap usage 270.443 MB -> 100.256 MB. [2025-03-27T04:10:51.374Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (7078.080 ms) ====== [2025-03-27T04:10:51.374Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-27T04:10:51.374Z] GC before operation: completed in 454.025 ms, heap usage 266.748 MB -> 100.679 MB. [2025-03-27T04:11:02.901Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (10219.150 ms) ====== [2025-03-27T04:11:02.901Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-27T04:11:03.630Z] GC before operation: completed in 1467.985 ms, heap usage 467.567 MB -> 72.831 MB. [2025-03-27T04:11:13.857Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (9629.919 ms) ====== [2025-03-27T04:11:13.857Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-27T04:11:13.857Z] GC before operation: completed in 292.183 ms, heap usage 383.254 MB -> 100.471 MB. [2025-03-27T04:11:21.714Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (7459.306 ms) ====== [2025-03-27T04:11:21.714Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-27T04:11:21.714Z] GC before operation: completed in 258.186 ms, heap usage 230.635 MB -> 73.350 MB. [2025-03-27T04:11:26.806Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (5781.453 ms) ====== [2025-03-27T04:11:26.806Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-27T04:11:26.806Z] GC before operation: completed in 178.674 ms, heap usage 456.123 MB -> 73.184 MB. [2025-03-27T04:11:32.836Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (5215.600 ms) ====== [2025-03-27T04:11:32.836Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-27T04:11:32.836Z] GC before operation: completed in 322.871 ms, heap usage 140.222 MB -> 100.993 MB. [2025-03-27T04:11:37.897Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (5628.649 ms) ====== [2025-03-27T04:11:37.897Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-27T04:11:38.328Z] GC before operation: completed in 187.045 ms, heap usage 291.937 MB -> 73.426 MB. [2025-03-27T04:11:45.806Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (7108.743 ms) ====== [2025-03-27T04:11:45.806Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-27T04:11:45.806Z] GC before operation: completed in 224.051 ms, heap usage 564.794 MB -> 73.819 MB. [2025-03-27T04:11:52.207Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (6045.482 ms) ====== [2025-03-27T04:11:52.207Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-27T04:11:52.207Z] GC before operation: completed in 727.997 ms, heap usage 171.638 MB -> 73.262 MB. [2025-03-27T04:11:59.735Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (7276.998 ms) ====== [2025-03-27T04:11:59.735Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-27T04:11:59.735Z] GC before operation: completed in 316.848 ms, heap usage 468.067 MB -> 101.242 MB. [2025-03-27T04:12:11.021Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (10295.606 ms) ====== [2025-03-27T04:12:16.921Z] ----------------------------------- [2025-03-27T04:12:16.921Z] renaissance-dec-tree_0_PASSED [2025-03-27T04:12:16.921Z] ----------------------------------- [2025-03-27T04:12:16.921Z] [2025-03-27T04:12:16.921Z] TEST TEARDOWN: [2025-03-27T04:12:16.921Z] Nothing to be done for teardown. [2025-03-27T04:12:17.455Z] renaissance-dec-tree_0 Finish Time: Wed Mar 26 21:12:16 2025 Epoch Time (ms): 1743048736818