[2025-01-10T11:50:34.089Z] Running test renaissance-dec-tree_0 ... [2025-01-10T11:50:34.089Z] =============================================== [2025-01-10T11:50:34.089Z] renaissance-dec-tree_0 Start Time: Fri Jan 10 06:50:33 2025 Epoch Time (ms): 1736509833824 [2025-01-10T11:50:34.089Z] variation: NoOptions [2025-01-10T11:50:34.089Z] JVM_OPTIONS: [2025-01-10T11:50:34.089Z] { \ [2025-01-10T11:50:34.089Z] echo ""; echo "TEST SETUP:"; \ [2025-01-10T11:50:34.089Z] echo "Nothing to be done for setup."; \ [2025-01-10T11:50:34.089Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17365096249001/renaissance-dec-tree_0"; \ [2025-01-10T11:50:34.089Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17365096249001/renaissance-dec-tree_0"; \ [2025-01-10T11:50:34.089Z] echo ""; echo "TESTING:"; \ [2025-01-10T11:50:34.089Z] "/Users/admin/workspace/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17365096249001/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-01-10T11:50:34.089Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17365096249001/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-10T11:50:34.089Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-10T11:50:34.090Z] echo "Nothing to be done for teardown."; \ [2025-01-10T11:50:34.090Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17365096249001/TestTargetResult"; [2025-01-10T11:50:34.090Z] [2025-01-10T11:50:34.090Z] TEST SETUP: [2025-01-10T11:50:34.090Z] Nothing to be done for setup. [2025-01-10T11:50:34.090Z] [2025-01-10T11:50:34.090Z] TESTING: [2025-01-10T11:50:36.499Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-10T11:50:37.273Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-01-10T11:50:38.060Z] WARNING: An illegal reflective access operation has occurred [2025-01-10T11:50:38.061Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/output_17365096249001/renaissance-dec-tree_0/harness-065034-18301009077036142863/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-01-10T11:50:38.061Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-01-10T11:50:38.061Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-01-10T11:50:38.061Z] WARNING: All illegal access operations will be denied in a future release [2025-01-10T11:50:39.309Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-01-10T11:50:39.309Z] GC before operation: completed in 35.260 ms, heap usage 61.491 MB -> 33.865 MB. [2025-01-10T11:50:44.451Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (5115.424 ms) ====== [2025-01-10T11:50:44.451Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-01-10T11:50:44.451Z] GC before operation: completed in 74.568 ms, heap usage 216.711 MB -> 63.849 MB. [2025-01-10T11:50:45.746Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1483.681 ms) ====== [2025-01-10T11:50:45.747Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-01-10T11:50:45.747Z] GC before operation: completed in 59.572 ms, heap usage 234.159 MB -> 64.177 MB. [2025-01-10T11:50:47.075Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1191.008 ms) ====== [2025-01-10T11:50:47.075Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-01-10T11:50:47.075Z] GC before operation: completed in 60.493 ms, heap usage 175.466 MB -> 77.371 MB. [2025-01-10T11:50:48.323Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1038.071 ms) ====== [2025-01-10T11:50:48.323Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-01-10T11:50:48.323Z] GC before operation: completed in 71.951 ms, heap usage 283.770 MB -> 64.645 MB. [2025-01-10T11:50:49.089Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (978.417 ms) ====== [2025-01-10T11:50:49.089Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-01-10T11:50:49.446Z] GC before operation: completed in 71.147 ms, heap usage 212.671 MB -> 64.861 MB. [2025-01-10T11:50:50.259Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1009.892 ms) ====== [2025-01-10T11:50:50.259Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-01-10T11:50:50.259Z] GC before operation: completed in 64.693 ms, heap usage 234.384 MB -> 65.098 MB. [2025-01-10T11:50:51.496Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (939.658 ms) ====== [2025-01-10T11:50:51.496Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-01-10T11:50:51.496Z] GC before operation: completed in 67.364 ms, heap usage 214.017 MB -> 78.249 MB. [2025-01-10T11:50:52.295Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (950.231 ms) ====== [2025-01-10T11:50:52.295Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-01-10T11:50:52.295Z] GC before operation: completed in 68.335 ms, heap usage 216.983 MB -> 91.051 MB. [2025-01-10T11:50:53.562Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (959.823 ms) ====== [2025-01-10T11:50:53.562Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-01-10T11:50:53.562Z] GC before operation: completed in 74.454 ms, heap usage 230.821 MB -> 78.472 MB. [2025-01-10T11:50:54.360Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (947.922 ms) ====== [2025-01-10T11:50:54.360Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-01-10T11:50:54.360Z] GC before operation: completed in 70.488 ms, heap usage 233.605 MB -> 80.391 MB. [2025-01-10T11:50:55.649Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (899.196 ms) ====== [2025-01-10T11:50:55.649Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-01-10T11:50:55.649Z] GC before operation: completed in 80.116 ms, heap usage 161.945 MB -> 65.726 MB. [2025-01-10T11:50:56.457Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (938.067 ms) ====== [2025-01-10T11:50:56.457Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-01-10T11:50:56.457Z] GC before operation: completed in 80.444 ms, heap usage 212.462 MB -> 93.303 MB. [2025-01-10T11:50:57.263Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (898.249 ms) ====== [2025-01-10T11:50:57.263Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-01-10T11:50:57.623Z] GC before operation: completed in 74.855 ms, heap usage 156.019 MB -> 65.810 MB. [2025-01-10T11:50:58.479Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (878.300 ms) ====== [2025-01-10T11:50:58.479Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-01-10T11:50:58.479Z] GC before operation: completed in 81.609 ms, heap usage 205.125 MB -> 78.643 MB. [2025-01-10T11:50:59.263Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (991.230 ms) ====== [2025-01-10T11:50:59.263Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-01-10T11:50:59.675Z] GC before operation: completed in 86.348 ms, heap usage 120.060 MB -> 65.912 MB. [2025-01-10T11:51:00.495Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (956.061 ms) ====== [2025-01-10T11:51:00.495Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-01-10T11:51:00.495Z] GC before operation: completed in 83.493 ms, heap usage 187.861 MB -> 66.371 MB. [2025-01-10T11:51:01.762Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (931.934 ms) ====== [2025-01-10T11:51:01.762Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-01-10T11:51:01.762Z] GC before operation: completed in 82.836 ms, heap usage 147.106 MB -> 66.039 MB. [2025-01-10T11:51:02.534Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (817.594 ms) ====== [2025-01-10T11:51:02.534Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-01-10T11:51:02.534Z] GC before operation: completed in 91.897 ms, heap usage 112.266 MB -> 66.116 MB. [2025-01-10T11:51:03.338Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (855.986 ms) ====== [2025-01-10T11:51:03.339Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-01-10T11:51:03.339Z] GC before operation: completed in 88.520 ms, heap usage 213.666 MB -> 66.502 MB. [2025-01-10T11:51:04.126Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (901.522 ms) ====== [2025-01-10T11:51:04.127Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-01-10T11:51:04.519Z] GC before operation: completed in 87.207 ms, heap usage 125.289 MB -> 66.598 MB. [2025-01-10T11:51:06.046Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (929.113 ms) ====== [2025-01-10T11:51:06.046Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-01-10T11:51:06.046Z] GC before operation: completed in 96.875 ms, heap usage 100.156 MB -> 66.429 MB. [2025-01-10T11:51:06.416Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (940.441 ms) ====== [2025-01-10T11:51:06.416Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-01-10T11:51:06.416Z] GC before operation: completed in 91.137 ms, heap usage 189.914 MB -> 66.819 MB. [2025-01-10T11:51:07.211Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (860.298 ms) ====== [2025-01-10T11:51:07.211Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-01-10T11:51:07.571Z] GC before operation: completed in 96.326 ms, heap usage 294.455 MB -> 66.914 MB. [2025-01-10T11:51:08.378Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (909.029 ms) ====== [2025-01-10T11:51:08.378Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-01-10T11:51:08.378Z] GC before operation: completed in 80.441 ms, heap usage 145.978 MB -> 66.998 MB. [2025-01-10T11:51:09.152Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (846.278 ms) ====== [2025-01-10T11:51:09.152Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-01-10T11:51:09.536Z] GC before operation: completed in 95.168 ms, heap usage 159.206 MB -> 67.115 MB. [2025-01-10T11:51:10.309Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (867.426 ms) ====== [2025-01-10T11:51:10.309Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-01-10T11:51:10.309Z] GC before operation: completed in 93.861 ms, heap usage 205.448 MB -> 67.353 MB. [2025-01-10T11:51:11.094Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (837.936 ms) ====== [2025-01-10T11:51:11.094Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-01-10T11:51:11.094Z] GC before operation: completed in 90.470 ms, heap usage 261.638 MB -> 67.313 MB. [2025-01-10T11:51:12.339Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (881.992 ms) ====== [2025-01-10T11:51:12.339Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-01-10T11:51:12.339Z] GC before operation: completed in 95.947 ms, heap usage 231.273 MB -> 94.987 MB. [2025-01-10T11:51:13.145Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (855.611 ms) ====== [2025-01-10T11:51:13.145Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-01-10T11:51:13.145Z] GC before operation: completed in 95.479 ms, heap usage 295.684 MB -> 67.856 MB. [2025-01-10T11:51:13.932Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (927.486 ms) ====== [2025-01-10T11:51:13.932Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-01-10T11:51:14.310Z] GC before operation: completed in 109.986 ms, heap usage 130.957 MB -> 67.577 MB. [2025-01-10T11:51:15.138Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (837.236 ms) ====== [2025-01-10T11:51:15.138Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-01-10T11:51:15.138Z] GC before operation: completed in 105.545 ms, heap usage 113.172 MB -> 67.551 MB. [2025-01-10T11:51:15.909Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (851.085 ms) ====== [2025-01-10T11:51:15.910Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-01-10T11:51:16.277Z] GC before operation: completed in 98.800 ms, heap usage 103.938 MB -> 67.499 MB. [2025-01-10T11:51:17.131Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (842.177 ms) ====== [2025-01-10T11:51:17.131Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-01-10T11:51:17.131Z] GC before operation: completed in 114.493 ms, heap usage 231.986 MB -> 67.884 MB. [2025-01-10T11:51:17.965Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (831.064 ms) ====== [2025-01-10T11:51:17.965Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-01-10T11:51:17.965Z] GC before operation: completed in 119.948 ms, heap usage 182.662 MB -> 67.985 MB. [2025-01-10T11:51:18.736Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (873.881 ms) ====== [2025-01-10T11:51:18.736Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-01-10T11:51:19.099Z] GC before operation: completed in 108.843 ms, heap usage 130.775 MB -> 68.084 MB. [2025-01-10T11:51:19.871Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (903.125 ms) ====== [2025-01-10T11:51:19.871Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-01-10T11:51:19.871Z] GC before operation: completed in 111.270 ms, heap usage 145.000 MB -> 68.326 MB. [2025-01-10T11:51:21.125Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (907.545 ms) ====== [2025-01-10T11:51:21.125Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-01-10T11:51:21.125Z] GC before operation: completed in 125.499 ms, heap usage 192.586 MB -> 80.993 MB. [2025-01-10T11:51:21.922Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (967.940 ms) ====== [2025-01-10T11:51:21.922Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-01-10T11:51:22.294Z] GC before operation: completed in 123.822 ms, heap usage 346.852 MB -> 68.539 MB. [2025-01-10T11:51:23.071Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (842.736 ms) ====== [2025-01-10T11:51:23.072Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-01-10T11:51:23.072Z] GC before operation: completed in 116.716 ms, heap usage 250.790 MB -> 68.478 MB. [2025-01-10T11:51:23.843Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (897.210 ms) ====== [2025-01-10T11:51:24.211Z] ----------------------------------- [2025-01-10T11:51:24.211Z] renaissance-dec-tree_0_PASSED [2025-01-10T11:51:24.211Z] ----------------------------------- [2025-01-10T11:51:24.211Z] [2025-01-10T11:51:24.211Z] TEST TEARDOWN: [2025-01-10T11:51:24.211Z] Nothing to be done for teardown. [2025-01-10T11:51:24.211Z] renaissance-dec-tree_0 Finish Time: Fri Jan 10 06:51:24 2025 Epoch Time (ms): 1736509884040