[2024-08-28T20:57:06.992Z] Running test renaissance-dec-tree_0 ... [2024-08-28T20:57:06.992Z] =============================================== [2024-08-28T20:57:06.992Z] renaissance-dec-tree_0 Start Time: Wed Aug 28 16:57:06 2024 Epoch Time (ms): 1724878626952 [2024-08-28T20:57:07.352Z] variation: NoOptions [2024-08-28T20:57:07.352Z] JVM_OPTIONS: [2024-08-28T20:57:07.352Z] { \ [2024-08-28T20:57:07.352Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T20:57:07.352Z] echo "Nothing to be done for setup."; \ [2024-08-28T20:57:07.352Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1724878409841/renaissance-dec-tree_0"; \ [2024-08-28T20:57:07.352Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1724878409841/renaissance-dec-tree_0"; \ [2024-08-28T20:57:07.352Z] echo ""; echo "TESTING:"; \ [2024-08-28T20:57:07.352Z] "/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_1724878409841/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-28T20:57:07.352Z] 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_1724878409841/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T20:57:07.352Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T20:57:07.352Z] echo "Nothing to be done for teardown."; \ [2024-08-28T20:57:07.352Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_1724878409841/TestTargetResult"; [2024-08-28T20:57:07.352Z] [2024-08-28T20:57:07.352Z] TEST SETUP: [2024-08-28T20:57:07.352Z] Nothing to be done for setup. [2024-08-28T20:57:07.352Z] [2024-08-28T20:57:07.352Z] TESTING: [2024-08-28T20:57:09.775Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T20:57:10.545Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-28T20:57:11.785Z] WARNING: An illegal reflective access operation has occurred [2024-08-28T20:57:11.785Z] 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_1724878409841/renaissance-dec-tree_0/harness-165707-9439298797113196228/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-28T20:57:11.785Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-28T20:57:11.785Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-28T20:57:11.785Z] WARNING: All illegal access operations will be denied in a future release [2024-08-28T20:57:13.045Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-28T20:57:13.045Z] GC before operation: completed in 44.110 ms, heap usage 77.982 MB -> 33.861 MB. [2024-08-28T20:57:19.248Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (5727.697 ms) ====== [2024-08-28T20:57:19.248Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-28T20:57:19.248Z] GC before operation: completed in 57.248 ms, heap usage 106.348 MB -> 63.528 MB. [2024-08-28T20:57:20.576Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1452.269 ms) ====== [2024-08-28T20:57:20.576Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-28T20:57:20.576Z] GC before operation: completed in 63.981 ms, heap usage 145.559 MB -> 63.875 MB. [2024-08-28T20:57:21.848Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1233.785 ms) ====== [2024-08-28T20:57:21.848Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-28T20:57:21.848Z] GC before operation: completed in 75.766 ms, heap usage 227.480 MB -> 64.428 MB. [2024-08-28T20:57:23.138Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1261.444 ms) ====== [2024-08-28T20:57:23.138Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-28T20:57:23.138Z] GC before operation: completed in 66.464 ms, heap usage 201.227 MB -> 64.506 MB. [2024-08-28T20:57:24.074Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1178.258 ms) ====== [2024-08-28T20:57:24.074Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-28T20:57:24.074Z] GC before operation: completed in 65.068 ms, heap usage 173.947 MB -> 64.896 MB. [2024-08-28T20:57:25.354Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1116.076 ms) ====== [2024-08-28T20:57:25.355Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-28T20:57:25.355Z] GC before operation: completed in 81.262 ms, heap usage 207.224 MB -> 65.316 MB. [2024-08-28T20:57:26.609Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1086.910 ms) ====== [2024-08-28T20:57:26.609Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-28T20:57:26.609Z] GC before operation: completed in 71.577 ms, heap usage 199.123 MB -> 65.168 MB. [2024-08-28T20:57:27.898Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1069.710 ms) ====== [2024-08-28T20:57:27.898Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-28T20:57:27.898Z] GC before operation: completed in 70.023 ms, heap usage 117.591 MB -> 65.048 MB. [2024-08-28T20:57:28.679Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1033.275 ms) ====== [2024-08-28T20:57:28.679Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-28T20:57:28.679Z] GC before operation: completed in 74.862 ms, heap usage 305.295 MB -> 65.388 MB. [2024-08-28T20:57:30.027Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1090.919 ms) ====== [2024-08-28T20:57:30.027Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-28T20:57:30.027Z] GC before operation: completed in 80.014 ms, heap usage 161.105 MB -> 65.502 MB. [2024-08-28T20:57:31.275Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1055.132 ms) ====== [2024-08-28T20:57:31.275Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-28T20:57:31.275Z] GC before operation: completed in 72.570 ms, heap usage 133.104 MB -> 65.744 MB. [2024-08-28T20:57:32.095Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (919.251 ms) ====== [2024-08-28T20:57:32.095Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-28T20:57:32.095Z] GC before operation: completed in 77.075 ms, heap usage 147.436 MB -> 65.424 MB. [2024-08-28T20:57:33.360Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (953.702 ms) ====== [2024-08-28T20:57:33.360Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-28T20:57:33.360Z] GC before operation: completed in 78.710 ms, heap usage 128.222 MB -> 65.808 MB. [2024-08-28T20:57:34.141Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (919.814 ms) ====== [2024-08-28T20:57:34.141Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-28T20:57:34.141Z] GC before operation: completed in 73.712 ms, heap usage 97.009 MB -> 65.769 MB. [2024-08-28T20:57:35.382Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1019.990 ms) ====== [2024-08-28T20:57:35.382Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-28T20:57:35.382Z] GC before operation: completed in 78.498 ms, heap usage 256.206 MB -> 80.926 MB. [2024-08-28T20:57:36.182Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (998.912 ms) ====== [2024-08-28T20:57:36.182Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-28T20:57:36.564Z] GC before operation: completed in 92.647 ms, heap usage 198.710 MB -> 66.342 MB. [2024-08-28T20:57:37.362Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (935.397 ms) ====== [2024-08-28T20:57:37.362Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-28T20:57:37.362Z] GC before operation: completed in 82.121 ms, heap usage 208.164 MB -> 66.295 MB. [2024-08-28T20:57:38.616Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (972.933 ms) ====== [2024-08-28T20:57:38.616Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-28T20:57:38.616Z] GC before operation: completed in 96.808 ms, heap usage 127.121 MB -> 66.751 MB. [2024-08-28T20:57:39.407Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1059.134 ms) ====== [2024-08-28T20:57:39.407Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-28T20:57:39.785Z] GC before operation: completed in 94.402 ms, heap usage 257.938 MB -> 66.502 MB. [2024-08-28T20:57:40.568Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (981.138 ms) ====== [2024-08-28T20:57:40.568Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-28T20:57:40.568Z] GC before operation: completed in 91.147 ms, heap usage 227.929 MB -> 66.602 MB. [2024-08-28T20:57:41.812Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (935.442 ms) ====== [2024-08-28T20:57:41.812Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-28T20:57:41.812Z] GC before operation: completed in 90.828 ms, heap usage 134.173 MB -> 66.834 MB. [2024-08-28T20:57:42.631Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (922.967 ms) ====== [2024-08-28T20:57:42.631Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-28T20:57:42.631Z] GC before operation: completed in 94.716 ms, heap usage 216.528 MB -> 66.805 MB. [2024-08-28T20:57:43.497Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (885.016 ms) ====== [2024-08-28T20:57:43.497Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-28T20:57:43.871Z] GC before operation: completed in 84.271 ms, heap usage 254.926 MB -> 66.876 MB. [2024-08-28T20:57:45.222Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1442.065 ms) ====== [2024-08-28T20:57:45.222Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-28T20:57:45.222Z] GC before operation: completed in 88.476 ms, heap usage 189.806 MB -> 67.124 MB. [2024-08-28T20:57:46.011Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (933.300 ms) ====== [2024-08-28T20:57:46.011Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-28T20:57:46.408Z] GC before operation: completed in 95.531 ms, heap usage 147.070 MB -> 79.997 MB. [2024-08-28T20:57:47.176Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (889.877 ms) ====== [2024-08-28T20:57:47.176Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-28T20:57:47.176Z] GC before operation: completed in 91.869 ms, heap usage 268.381 MB -> 67.223 MB. [2024-08-28T20:57:48.435Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (886.366 ms) ====== [2024-08-28T20:57:48.436Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-28T20:57:48.436Z] GC before operation: completed in 89.866 ms, heap usage 200.122 MB -> 67.469 MB. [2024-08-28T20:57:49.204Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (892.703 ms) ====== [2024-08-28T20:57:49.204Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-28T20:57:49.204Z] GC before operation: completed in 85.341 ms, heap usage 221.897 MB -> 67.433 MB. [2024-08-28T20:57:49.984Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (860.931 ms) ====== [2024-08-28T20:57:49.984Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-28T20:57:49.984Z] GC before operation: completed in 90.435 ms, heap usage 252.874 MB -> 67.539 MB. [2024-08-28T20:57:51.290Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (885.241 ms) ====== [2024-08-28T20:57:51.290Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-28T20:57:51.290Z] GC before operation: completed in 95.342 ms, heap usage 110.502 MB -> 67.352 MB. [2024-08-28T20:57:52.069Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (846.017 ms) ====== [2024-08-28T20:57:52.069Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-28T20:57:52.069Z] GC before operation: completed in 99.413 ms, heap usage 208.363 MB -> 67.737 MB. [2024-08-28T20:57:52.849Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (861.189 ms) ====== [2024-08-28T20:57:52.849Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-28T20:57:53.216Z] GC before operation: completed in 95.936 ms, heap usage 209.450 MB -> 82.526 MB. [2024-08-28T20:57:53.986Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (871.535 ms) ====== [2024-08-28T20:57:53.986Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-28T20:57:53.986Z] GC before operation: completed in 90.459 ms, heap usage 184.318 MB -> 68.053 MB. [2024-08-28T20:57:54.759Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (928.728 ms) ====== [2024-08-28T20:57:54.759Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-28T20:57:55.153Z] GC before operation: completed in 111.194 ms, heap usage 166.037 MB -> 95.608 MB. [2024-08-28T20:57:55.952Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (868.705 ms) ====== [2024-08-28T20:57:55.952Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-28T20:57:55.952Z] GC before operation: completed in 99.579 ms, heap usage 260.157 MB -> 68.114 MB. [2024-08-28T20:57:56.735Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (879.494 ms) ====== [2024-08-28T20:57:56.735Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-28T20:57:57.169Z] GC before operation: completed in 99.395 ms, heap usage 191.039 MB -> 95.812 MB. [2024-08-28T20:57:57.975Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (902.603 ms) ====== [2024-08-28T20:57:57.975Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-28T20:57:57.975Z] GC before operation: completed in 105.354 ms, heap usage 188.016 MB -> 68.309 MB. [2024-08-28T20:57:58.765Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (899.124 ms) ====== [2024-08-28T20:57:58.766Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-28T20:57:59.147Z] GC before operation: completed in 108.665 ms, heap usage 260.532 MB -> 68.401 MB. [2024-08-28T20:57:59.949Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (904.984 ms) ====== [2024-08-28T20:57:59.949Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-28T20:57:59.949Z] GC before operation: completed in 106.526 ms, heap usage 167.940 MB -> 68.649 MB. [2024-08-28T20:58:01.226Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1006.149 ms) ====== [2024-08-28T20:58:01.226Z] ----------------------------------- [2024-08-28T20:58:01.226Z] renaissance-dec-tree_0_PASSED [2024-08-28T20:58:01.226Z] ----------------------------------- [2024-08-28T20:58:01.226Z] [2024-08-28T20:58:01.226Z] TEST TEARDOWN: [2024-08-28T20:58:01.226Z] Nothing to be done for teardown. [2024-08-28T20:58:01.226Z] renaissance-dec-tree_0 Finish Time: Wed Aug 28 16:58:01 2024 Epoch Time (ms): 1724878681020