[2024-08-21T21:42:31.329Z] Running test renaissance-dec-tree_0 ... [2024-08-21T21:42:31.329Z] =============================================== [2024-08-21T21:42:31.329Z] renaissance-dec-tree_0 Start Time: Wed Aug 21 17:42:31 2024 Epoch Time (ms): 1724276551159 [2024-08-21T21:42:31.329Z] variation: NoOptions [2024-08-21T21:42:31.329Z] JVM_OPTIONS: [2024-08-21T21:42:31.329Z] { \ [2024-08-21T21:42:31.329Z] echo ""; echo "TEST SETUP:"; \ [2024-08-21T21:42:31.329Z] echo "Nothing to be done for setup."; \ [2024-08-21T21:42:31.329Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17242763514565/renaissance-dec-tree_0"; \ [2024-08-21T21:42:31.329Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17242763514565/renaissance-dec-tree_0"; \ [2024-08-21T21:42:31.329Z] echo ""; echo "TESTING:"; \ [2024-08-21T21:42:31.329Z] "/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_17242763514565/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-21T21:42:31.329Z] 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_17242763514565/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-21T21:42:31.329Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-21T21:42:31.329Z] echo "Nothing to be done for teardown."; \ [2024-08-21T21:42:31.329Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17242763514565/TestTargetResult"; [2024-08-21T21:42:31.329Z] [2024-08-21T21:42:31.329Z] TEST SETUP: [2024-08-21T21:42:31.329Z] Nothing to be done for setup. [2024-08-21T21:42:31.329Z] [2024-08-21T21:42:31.329Z] TESTING: [2024-08-21T21:42:33.749Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-21T21:42:34.533Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-21T21:42:35.306Z] WARNING: An illegal reflective access operation has occurred [2024-08-21T21:42:35.306Z] 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_17242763514565/renaissance-dec-tree_0/harness-174231-6798180808089444203/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-21T21:42:35.306Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-21T21:42:35.306Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-21T21:42:35.306Z] WARNING: All illegal access operations will be denied in a future release [2024-08-21T21:42:36.546Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-21T21:42:36.546Z] GC before operation: completed in 37.922 ms, heap usage 54.845 MB -> 33.850 MB. [2024-08-21T21:42:42.781Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (5536.573 ms) ====== [2024-08-21T21:42:42.781Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-21T21:42:42.781Z] GC before operation: completed in 77.463 ms, heap usage 99.535 MB -> 63.822 MB. [2024-08-21T21:42:43.599Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1474.719 ms) ====== [2024-08-21T21:42:43.599Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-21T21:42:43.964Z] GC before operation: completed in 64.841 ms, heap usage 278.450 MB -> 64.155 MB. [2024-08-21T21:42:45.249Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1275.823 ms) ====== [2024-08-21T21:42:45.249Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-21T21:42:45.249Z] GC before operation: completed in 68.440 ms, heap usage 125.862 MB -> 64.265 MB. [2024-08-21T21:42:46.497Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1155.960 ms) ====== [2024-08-21T21:42:46.497Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-21T21:42:46.497Z] GC before operation: completed in 66.811 ms, heap usage 230.461 MB -> 64.639 MB. [2024-08-21T21:42:47.786Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1114.557 ms) ====== [2024-08-21T21:42:47.786Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-21T21:42:47.786Z] GC before operation: completed in 67.981 ms, heap usage 271.203 MB -> 64.859 MB. [2024-08-21T21:42:48.561Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (975.061 ms) ====== [2024-08-21T21:42:48.561Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-21T21:42:48.561Z] GC before operation: completed in 67.288 ms, heap usage 200.660 MB -> 65.067 MB. [2024-08-21T21:42:49.831Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (950.949 ms) ====== [2024-08-21T21:42:49.831Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-21T21:42:49.831Z] GC before operation: completed in 64.748 ms, heap usage 128.292 MB -> 65.106 MB. [2024-08-21T21:42:50.615Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1004.984 ms) ====== [2024-08-21T21:42:50.615Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-21T21:42:50.615Z] GC before operation: completed in 73.289 ms, heap usage 143.981 MB -> 65.216 MB. [2024-08-21T21:42:51.868Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (957.061 ms) ====== [2024-08-21T21:42:51.869Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-21T21:42:51.869Z] GC before operation: completed in 72.415 ms, heap usage 159.599 MB -> 91.144 MB. [2024-08-21T21:42:52.670Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (914.489 ms) ====== [2024-08-21T21:42:52.670Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-21T21:42:52.670Z] GC before operation: completed in 71.797 ms, heap usage 299.639 MB -> 65.472 MB. [2024-08-21T21:42:53.957Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (944.386 ms) ====== [2024-08-21T21:42:53.957Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-21T21:42:53.957Z] GC before operation: completed in 65.239 ms, heap usage 163.704 MB -> 65.578 MB. [2024-08-21T21:42:54.728Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (894.371 ms) ====== [2024-08-21T21:42:54.728Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-21T21:42:54.729Z] GC before operation: completed in 74.317 ms, heap usage 195.544 MB -> 65.835 MB. [2024-08-21T21:42:55.516Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (924.609 ms) ====== [2024-08-21T21:42:55.516Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-21T21:42:55.516Z] GC before operation: completed in 70.380 ms, heap usage 194.280 MB -> 65.947 MB. [2024-08-21T21:42:56.756Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (923.304 ms) ====== [2024-08-21T21:42:56.756Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-21T21:42:56.756Z] GC before operation: completed in 89.622 ms, heap usage 106.955 MB -> 65.631 MB. [2024-08-21T21:42:57.543Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (850.439 ms) ====== [2024-08-21T21:42:57.543Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-21T21:42:57.543Z] GC before operation: completed in 75.130 ms, heap usage 224.624 MB -> 66.030 MB. [2024-08-21T21:42:58.319Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (883.284 ms) ====== [2024-08-21T21:42:58.319Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-21T21:42:58.709Z] GC before operation: completed in 74.390 ms, heap usage 213.248 MB -> 66.350 MB. [2024-08-21T21:42:59.501Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (817.014 ms) ====== [2024-08-21T21:42:59.501Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-21T21:42:59.501Z] GC before operation: completed in 82.463 ms, heap usage 208.920 MB -> 66.455 MB. [2024-08-21T21:43:00.284Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (827.222 ms) ====== [2024-08-21T21:43:00.284Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-21T21:43:00.284Z] GC before operation: completed in 78.356 ms, heap usage 239.999 MB -> 66.405 MB. [2024-08-21T21:43:01.538Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (879.260 ms) ====== [2024-08-21T21:43:01.538Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-21T21:43:01.539Z] GC before operation: completed in 86.139 ms, heap usage 214.430 MB -> 66.653 MB. [2024-08-21T21:43:02.463Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (859.468 ms) ====== [2024-08-21T21:43:02.463Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-21T21:43:02.463Z] GC before operation: completed in 86.269 ms, heap usage 211.911 MB -> 66.605 MB. [2024-08-21T21:43:03.241Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (839.509 ms) ====== [2024-08-21T21:43:03.241Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-21T21:43:03.241Z] GC before operation: completed in 82.435 ms, heap usage 210.386 MB -> 66.678 MB. [2024-08-21T21:43:04.043Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (807.763 ms) ====== [2024-08-21T21:43:04.043Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-21T21:43:04.043Z] GC before operation: completed in 85.368 ms, heap usage 104.555 MB -> 81.393 MB. [2024-08-21T21:43:04.842Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (815.092 ms) ====== [2024-08-21T21:43:04.842Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-21T21:43:04.842Z] GC before operation: completed in 87.894 ms, heap usage 234.500 MB -> 79.979 MB. [2024-08-21T21:43:06.110Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (812.808 ms) ====== [2024-08-21T21:43:06.110Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-21T21:43:06.110Z] GC before operation: completed in 84.667 ms, heap usage 250.533 MB -> 81.684 MB. [2024-08-21T21:43:06.908Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (754.474 ms) ====== [2024-08-21T21:43:06.908Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-21T21:43:06.908Z] GC before operation: completed in 86.503 ms, heap usage 188.371 MB -> 79.795 MB. [2024-08-21T21:43:07.675Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (783.307 ms) ====== [2024-08-21T21:43:07.675Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-21T21:43:07.675Z] GC before operation: completed in 85.643 ms, heap usage 121.004 MB -> 80.011 MB. [2024-08-21T21:43:08.461Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (763.086 ms) ====== [2024-08-21T21:43:08.461Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-21T21:43:08.461Z] GC before operation: completed in 89.210 ms, heap usage 289.805 MB -> 67.309 MB. [2024-08-21T21:43:09.249Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (784.815 ms) ====== [2024-08-21T21:43:09.249Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-21T21:43:09.249Z] GC before operation: completed in 84.520 ms, heap usage 99.964 MB -> 82.024 MB. [2024-08-21T21:43:09.249Z] 24/08/21 17:43:09 WARN BlockManager: Asked to remove block rdd_1369_2, which does not exist [2024-08-21T21:43:10.034Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (847.794 ms) ====== [2024-08-21T21:43:10.034Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-21T21:43:10.403Z] GC before operation: completed in 86.816 ms, heap usage 277.295 MB -> 67.660 MB. [2024-08-21T21:43:11.198Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (894.612 ms) ====== [2024-08-21T21:43:11.198Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-21T21:43:11.198Z] GC before operation: completed in 101.332 ms, heap usage 175.830 MB -> 67.609 MB. [2024-08-21T21:43:11.973Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (784.262 ms) ====== [2024-08-21T21:43:11.973Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-21T21:43:12.331Z] GC before operation: completed in 108.151 ms, heap usage 160.114 MB -> 67.712 MB. [2024-08-21T21:43:13.102Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (836.861 ms) ====== [2024-08-21T21:43:13.102Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-21T21:43:13.102Z] GC before operation: completed in 99.030 ms, heap usage 133.606 MB -> 67.754 MB. [2024-08-21T21:43:13.877Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (850.957 ms) ====== [2024-08-21T21:43:13.877Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-21T21:43:14.241Z] GC before operation: completed in 102.871 ms, heap usage 227.212 MB -> 68.014 MB. [2024-08-21T21:43:15.036Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (799.031 ms) ====== [2024-08-21T21:43:15.036Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-21T21:43:15.036Z] GC before operation: completed in 104.815 ms, heap usage 101.144 MB -> 67.683 MB. [2024-08-21T21:43:15.841Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (782.124 ms) ====== [2024-08-21T21:43:15.841Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-21T21:43:15.841Z] GC before operation: completed in 102.227 ms, heap usage 233.294 MB -> 82.797 MB. [2024-08-21T21:43:16.609Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (800.862 ms) ====== [2024-08-21T21:43:16.609Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-21T21:43:16.609Z] GC before operation: completed in 108.963 ms, heap usage 365.461 MB -> 95.772 MB. [2024-08-21T21:43:17.376Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (778.345 ms) ====== [2024-08-21T21:43:17.376Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-21T21:43:17.738Z] GC before operation: completed in 109.786 ms, heap usage 136.297 MB -> 67.988 MB. [2024-08-21T21:43:18.529Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (773.883 ms) ====== [2024-08-21T21:43:18.529Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-21T21:43:18.529Z] GC before operation: completed in 106.528 ms, heap usage 194.661 MB -> 68.393 MB. [2024-08-21T21:43:19.312Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (783.464 ms) ====== [2024-08-21T21:43:19.312Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-21T21:43:19.312Z] GC before operation: completed in 108.364 ms, heap usage 104.673 MB -> 68.352 MB. [2024-08-21T21:43:20.097Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (844.687 ms) ====== [2024-08-21T21:43:20.461Z] ----------------------------------- [2024-08-21T21:43:20.461Z] renaissance-dec-tree_0_PASSED [2024-08-21T21:43:20.461Z] ----------------------------------- [2024-08-21T21:43:20.461Z] [2024-08-21T21:43:20.461Z] TEST TEARDOWN: [2024-08-21T21:43:20.461Z] Nothing to be done for teardown. [2024-08-21T21:43:20.461Z] renaissance-dec-tree_0 Finish Time: Wed Aug 21 17:43:20 2024 Epoch Time (ms): 1724276600243