[2025-02-26T22:14:29.755Z] Running test renaissance-dec-tree_0 ... [2025-02-26T22:14:29.755Z] =============================================== [2025-02-26T22:14:29.755Z] renaissance-dec-tree_0 Start Time: Wed Feb 26 17:14:29 2025 Epoch Time (ms): 1740608069354 [2025-02-26T22:14:29.755Z] variation: NoOptions [2025-02-26T22:14:29.755Z] JVM_OPTIONS: [2025-02-26T22:14:29.755Z] { \ [2025-02-26T22:14:29.755Z] echo ""; echo "TEST SETUP:"; \ [2025-02-26T22:14:29.755Z] echo "Nothing to be done for setup."; \ [2025-02-26T22:14:29.755Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406079039241/renaissance-dec-tree_0"; \ [2025-02-26T22:14:29.755Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406079039241/renaissance-dec-tree_0"; \ [2025-02-26T22:14:29.755Z] echo ""; echo "TESTING:"; \ [2025-02-26T22:14:29.755Z] "/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_17406079039241/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-26T22:14:29.755Z] 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_17406079039241/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-26T22:14:29.755Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-26T22:14:29.755Z] echo "Nothing to be done for teardown."; \ [2025-02-26T22:14:29.755Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17406079039241/TestTargetResult"; [2025-02-26T22:14:29.755Z] [2025-02-26T22:14:29.755Z] TEST SETUP: [2025-02-26T22:14:29.755Z] Nothing to be done for setup. [2025-02-26T22:14:29.755Z] [2025-02-26T22:14:29.755Z] TESTING: [2025-02-26T22:14:31.667Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-26T22:14:32.062Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-02-26T22:14:32.900Z] WARNING: An illegal reflective access operation has occurred [2025-02-26T22:14:32.900Z] 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_17406079039241/renaissance-dec-tree_0/harness-171429-5826486409912638522/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-26T22:14:32.900Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-26T22:14:32.900Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-26T22:14:32.900Z] WARNING: All illegal access operations will be denied in a future release [2025-02-26T22:14:34.239Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-26T22:14:34.239Z] GC before operation: completed in 27.681 ms, heap usage 50.603 MB -> 33.843 MB. [2025-02-26T22:14:38.587Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (4213.077 ms) ====== [2025-02-26T22:14:38.587Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-26T22:14:38.587Z] GC before operation: completed in 52.787 ms, heap usage 208.116 MB -> 63.849 MB. [2025-02-26T22:14:39.982Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1343.445 ms) ====== [2025-02-26T22:14:39.982Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-26T22:14:39.982Z] GC before operation: completed in 60.224 ms, heap usage 134.317 MB -> 64.136 MB. [2025-02-26T22:14:40.830Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1186.738 ms) ====== [2025-02-26T22:14:40.830Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-26T22:14:40.830Z] GC before operation: completed in 44.580 ms, heap usage 129.736 MB -> 64.131 MB. [2025-02-26T22:14:42.197Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (926.813 ms) ====== [2025-02-26T22:14:42.197Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-26T22:14:42.197Z] GC before operation: completed in 48.226 ms, heap usage 216.927 MB -> 64.644 MB. [2025-02-26T22:14:43.065Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (974.734 ms) ====== [2025-02-26T22:14:43.065Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-26T22:14:43.065Z] GC before operation: completed in 60.688 ms, heap usage 187.659 MB -> 64.733 MB. [2025-02-26T22:14:43.923Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (921.878 ms) ====== [2025-02-26T22:14:43.923Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-26T22:14:43.923Z] GC before operation: completed in 55.614 ms, heap usage 133.911 MB -> 65.062 MB. [2025-02-26T22:14:44.778Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (880.247 ms) ====== [2025-02-26T22:14:44.778Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-26T22:14:44.778Z] GC before operation: completed in 53.290 ms, heap usage 114.836 MB -> 64.855 MB. [2025-02-26T22:14:45.620Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (799.630 ms) ====== [2025-02-26T22:14:45.620Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-26T22:14:45.620Z] GC before operation: completed in 59.717 ms, heap usage 111.492 MB -> 65.119 MB. [2025-02-26T22:14:46.469Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (760.856 ms) ====== [2025-02-26T22:14:46.469Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-26T22:14:46.469Z] GC before operation: completed in 54.744 ms, heap usage 186.267 MB -> 65.372 MB. [2025-02-26T22:14:47.332Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (763.562 ms) ====== [2025-02-26T22:14:47.332Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-26T22:14:47.332Z] GC before operation: completed in 60.995 ms, heap usage 108.218 MB -> 65.201 MB. [2025-02-26T22:14:48.215Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (738.950 ms) ====== [2025-02-26T22:14:48.215Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-26T22:14:48.215Z] GC before operation: completed in 57.016 ms, heap usage 154.959 MB -> 65.591 MB. [2025-02-26T22:14:49.073Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (808.278 ms) ====== [2025-02-26T22:14:49.073Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-26T22:14:49.073Z] GC before operation: completed in 62.424 ms, heap usage 208.889 MB -> 65.699 MB. [2025-02-26T22:14:49.927Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (898.021 ms) ====== [2025-02-26T22:14:49.928Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-26T22:14:49.928Z] GC before operation: completed in 71.796 ms, heap usage 183.692 MB -> 65.812 MB. [2025-02-26T22:14:50.768Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (786.736 ms) ====== [2025-02-26T22:14:50.768Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-26T22:14:50.768Z] GC before operation: completed in 57.007 ms, heap usage 189.876 MB -> 65.889 MB. [2025-02-26T22:14:51.616Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (785.851 ms) ====== [2025-02-26T22:14:51.616Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-26T22:14:51.616Z] GC before operation: completed in 59.952 ms, heap usage 98.127 MB -> 65.712 MB. [2025-02-26T22:14:52.470Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (765.884 ms) ====== [2025-02-26T22:14:52.471Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-26T22:14:52.471Z] GC before operation: completed in 62.506 ms, heap usage 184.100 MB -> 66.314 MB. [2025-02-26T22:14:53.318Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (763.051 ms) ====== [2025-02-26T22:14:53.318Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-26T22:14:53.318Z] GC before operation: completed in 78.008 ms, heap usage 143.499 MB -> 66.253 MB. [2025-02-26T22:14:54.154Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (794.453 ms) ====== [2025-02-26T22:14:54.154Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-26T22:14:54.154Z] GC before operation: completed in 71.281 ms, heap usage 275.307 MB -> 66.520 MB. [2025-02-26T22:14:55.026Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (810.307 ms) ====== [2025-02-26T22:14:55.026Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-26T22:14:55.026Z] GC before operation: completed in 74.295 ms, heap usage 100.865 MB -> 66.334 MB. [2025-02-26T22:14:55.867Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (752.346 ms) ====== [2025-02-26T22:14:55.867Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-26T22:14:55.867Z] GC before operation: completed in 71.057 ms, heap usage 218.195 MB -> 66.729 MB. [2025-02-26T22:14:56.709Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (746.769 ms) ====== [2025-02-26T22:14:56.709Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-26T22:14:56.709Z] GC before operation: completed in 75.750 ms, heap usage 182.224 MB -> 66.839 MB. [2025-02-26T22:14:57.607Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (723.362 ms) ====== [2025-02-26T22:14:57.607Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-26T22:14:57.607Z] GC before operation: completed in 72.507 ms, heap usage 141.722 MB -> 66.775 MB. [2025-02-26T22:14:58.497Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (709.479 ms) ====== [2025-02-26T22:14:58.497Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-26T22:14:58.497Z] GC before operation: completed in 82.936 ms, heap usage 179.301 MB -> 66.927 MB. [2025-02-26T22:14:59.345Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (682.956 ms) ====== [2025-02-26T22:14:59.345Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-26T22:14:59.345Z] GC before operation: completed in 85.467 ms, heap usage 206.880 MB -> 81.511 MB. [2025-02-26T22:15:00.228Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (758.820 ms) ====== [2025-02-26T22:15:00.228Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-26T22:15:00.228Z] GC before operation: completed in 93.630 ms, heap usage 222.431 MB -> 67.258 MB. [2025-02-26T22:15:01.126Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (803.556 ms) ====== [2025-02-26T22:15:01.126Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-26T22:15:01.126Z] GC before operation: completed in 85.429 ms, heap usage 219.420 MB -> 67.172 MB. [2025-02-26T22:15:01.524Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (737.090 ms) ====== [2025-02-26T22:15:01.524Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-26T22:15:01.951Z] GC before operation: completed in 83.039 ms, heap usage 243.298 MB -> 67.279 MB. [2025-02-26T22:15:02.404Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (783.008 ms) ====== [2025-02-26T22:15:02.404Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-26T22:15:02.795Z] GC before operation: completed in 83.782 ms, heap usage 232.118 MB -> 67.381 MB. [2025-02-26T22:15:03.201Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (719.592 ms) ====== [2025-02-26T22:15:03.201Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-26T22:15:03.624Z] GC before operation: completed in 80.238 ms, heap usage 98.816 MB -> 67.343 MB. [2025-02-26T22:15:04.503Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (854.764 ms) ====== [2025-02-26T22:15:04.503Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-26T22:15:04.503Z] GC before operation: completed in 112.512 ms, heap usage 252.457 MB -> 80.425 MB. [2025-02-26T22:15:05.366Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (783.047 ms) ====== [2025-02-26T22:15:05.366Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-26T22:15:05.366Z] GC before operation: completed in 102.489 ms, heap usage 112.065 MB -> 67.122 MB. [2025-02-26T22:15:06.214Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (807.183 ms) ====== [2025-02-26T22:15:06.214Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-26T22:15:06.214Z] GC before operation: completed in 96.420 ms, heap usage 156.970 MB -> 67.770 MB. [2025-02-26T22:15:07.098Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (816.001 ms) ====== [2025-02-26T22:15:07.098Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-26T22:15:07.098Z] GC before operation: completed in 96.561 ms, heap usage 256.085 MB -> 67.882 MB. [2025-02-26T22:15:07.946Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (826.093 ms) ====== [2025-02-26T22:15:07.946Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-26T22:15:07.946Z] GC before operation: completed in 94.837 ms, heap usage 175.015 MB -> 82.662 MB. [2025-02-26T22:15:08.787Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (825.345 ms) ====== [2025-02-26T22:15:08.787Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-26T22:15:08.787Z] GC before operation: completed in 98.412 ms, heap usage 245.487 MB -> 68.086 MB. [2025-02-26T22:15:09.667Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (857.604 ms) ====== [2025-02-26T22:15:09.667Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-26T22:15:10.063Z] GC before operation: completed in 98.429 ms, heap usage 210.140 MB -> 68.212 MB. [2025-02-26T22:15:10.473Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (701.616 ms) ====== [2025-02-26T22:15:10.473Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-26T22:15:10.868Z] GC before operation: completed in 81.258 ms, heap usage 101.965 MB -> 68.002 MB. [2025-02-26T22:15:11.272Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (768.356 ms) ====== [2025-02-26T22:15:11.272Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-26T22:15:11.679Z] GC before operation: completed in 88.960 ms, heap usage 250.623 MB -> 83.073 MB. [2025-02-26T22:15:12.108Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (685.936 ms) ====== [2025-02-26T22:15:12.108Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-26T22:15:12.108Z] GC before operation: completed in 81.212 ms, heap usage 134.517 MB -> 67.925 MB. [2025-02-26T22:15:12.954Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (740.103 ms) ====== [2025-02-26T22:15:13.346Z] ----------------------------------- [2025-02-26T22:15:13.346Z] renaissance-dec-tree_0_PASSED [2025-02-26T22:15:13.346Z] ----------------------------------- [2025-02-26T22:15:13.346Z] [2025-02-26T22:15:13.346Z] TEST TEARDOWN: [2025-02-26T22:15:13.346Z] Nothing to be done for teardown. [2025-02-26T22:15:13.346Z] renaissance-dec-tree_0 Finish Time: Wed Feb 26 17:15:12 2025 Epoch Time (ms): 1740608112937