[2024-08-16T23:31:40.601Z] Running test renaissance-dec-tree_0 ... [2024-08-16T23:31:40.601Z] =============================================== [2024-08-16T23:31:40.601Z] renaissance-dec-tree_0 Start Time: Fri Aug 16 16:31:39 2024 Epoch Time (ms): 1723851099798 [2024-08-16T23:31:40.601Z] variation: NoOptions [2024-08-16T23:31:40.601Z] JVM_OPTIONS: [2024-08-16T23:31:40.601Z] { \ [2024-08-16T23:31:40.601Z] echo ""; echo "TEST SETUP:"; \ [2024-08-16T23:31:40.601Z] echo "Nothing to be done for setup."; \ [2024-08-16T23:31:40.601Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17238495041963/renaissance-dec-tree_0"; \ [2024-08-16T23:31:40.601Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17238495041963/renaissance-dec-tree_0"; \ [2024-08-16T23:31:40.601Z] echo ""; echo "TESTING:"; \ [2024-08-16T23:31:40.601Z] "/Users/admin/workspace/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17238495041963/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-16T23:31:40.601Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17238495041963/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-16T23:31:40.601Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-16T23:31:40.601Z] echo "Nothing to be done for teardown."; \ [2024-08-16T23:31:40.601Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17238495041963/TestTargetResult"; [2024-08-16T23:31:40.601Z] [2024-08-16T23:31:40.601Z] TEST SETUP: [2024-08-16T23:31:40.601Z] Nothing to be done for setup. [2024-08-16T23:31:40.601Z] [2024-08-16T23:31:40.601Z] TESTING: [2024-08-16T23:31:57.095Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-16T23:32:05.892Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-16T23:32:11.769Z] WARNING: An illegal reflective access operation has occurred [2024-08-16T23:32:11.769Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/output_17238495041963/renaissance-dec-tree_0/harness-163143-7829612441135104108/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-16T23:32:11.769Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-16T23:32:11.769Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-16T23:32:11.769Z] WARNING: All illegal access operations will be denied in a future release [2024-08-16T23:32:27.054Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-16T23:32:27.055Z] GC before operation: completed in 1213.382 ms, heap usage 127.253 MB -> 33.914 MB. [2024-08-16T23:33:29.161Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (53331.509 ms) ====== [2024-08-16T23:33:29.161Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-16T23:33:29.161Z] GC before operation: completed in 469.907 ms, heap usage 106.446 MB -> 63.472 MB. [2024-08-16T23:33:36.826Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (15524.086 ms) ====== [2024-08-16T23:33:36.826Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-16T23:33:36.826Z] GC before operation: completed in 399.729 ms, heap usage 109.862 MB -> 63.765 MB. [2024-08-16T23:33:48.315Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (11379.210 ms) ====== [2024-08-16T23:33:48.315Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-16T23:33:48.315Z] GC before operation: completed in 266.049 ms, heap usage 100.903 MB -> 91.620 MB. [2024-08-16T23:33:59.578Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (10006.789 ms) ====== [2024-08-16T23:33:59.578Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-16T23:33:59.578Z] GC before operation: completed in 300.903 ms, heap usage 145.869 MB -> 64.379 MB. [2024-08-16T23:34:09.141Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (10491.508 ms) ====== [2024-08-16T23:34:09.141Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-16T23:34:09.549Z] GC before operation: completed in 462.940 ms, heap usage 207.681 MB -> 64.880 MB. [2024-08-16T23:34:21.386Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (11049.920 ms) ====== [2024-08-16T23:34:21.386Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-16T23:34:21.386Z] GC before operation: completed in 308.155 ms, heap usage 379.661 MB -> 65.343 MB. [2024-08-16T23:34:30.436Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (8423.973 ms) ====== [2024-08-16T23:34:30.436Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-16T23:34:30.436Z] GC before operation: completed in 669.159 ms, heap usage 103.579 MB -> 64.993 MB. [2024-08-16T23:34:38.413Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (7428.345 ms) ====== [2024-08-16T23:34:38.414Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-16T23:34:38.414Z] GC before operation: completed in 986.915 ms, heap usage 190.844 MB -> 92.442 MB. [2024-08-16T23:34:45.073Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (6700.256 ms) ====== [2024-08-16T23:34:45.073Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-16T23:34:45.575Z] GC before operation: completed in 517.699 ms, heap usage 290.078 MB -> 92.955 MB. [2024-08-16T23:34:53.331Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (6874.480 ms) ====== [2024-08-16T23:34:53.331Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-16T23:34:53.331Z] GC before operation: completed in 317.790 ms, heap usage 362.649 MB -> 65.455 MB. [2024-08-16T23:34:58.601Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (5890.058 ms) ====== [2024-08-16T23:34:58.601Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-16T23:34:59.094Z] GC before operation: completed in 390.861 ms, heap usage 316.338 MB -> 93.159 MB. [2024-08-16T23:35:06.708Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (6696.854 ms) ====== [2024-08-16T23:35:06.708Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-16T23:35:07.124Z] GC before operation: completed in 1107.621 ms, heap usage 231.233 MB -> 65.812 MB. [2024-08-16T23:35:15.040Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (7004.162 ms) ====== [2024-08-16T23:35:15.040Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-16T23:35:15.040Z] GC before operation: completed in 791.904 ms, heap usage 288.615 MB -> 65.917 MB. [2024-08-16T23:35:24.306Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (8389.370 ms) ====== [2024-08-16T23:35:24.306Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-16T23:35:25.057Z] GC before operation: completed in 1739.650 ms, heap usage 327.715 MB -> 65.879 MB. [2024-08-16T23:35:32.301Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (6340.670 ms) ====== [2024-08-16T23:35:32.301Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-16T23:35:32.301Z] GC before operation: completed in 345.838 ms, heap usage 183.418 MB -> 93.583 MB. [2024-08-16T23:35:37.186Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (5231.292 ms) ====== [2024-08-16T23:35:37.186Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-16T23:35:37.186Z] GC before operation: completed in 419.799 ms, heap usage 265.003 MB -> 93.749 MB. [2024-08-16T23:35:43.191Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (5586.661 ms) ====== [2024-08-16T23:35:43.191Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-16T23:35:43.191Z] GC before operation: completed in 248.840 ms, heap usage 144.296 MB -> 66.050 MB. [2024-08-16T23:35:51.047Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (7563.462 ms) ====== [2024-08-16T23:35:51.047Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-16T23:35:51.047Z] GC before operation: completed in 428.758 ms, heap usage 187.540 MB -> 66.339 MB. [2024-08-16T23:35:58.574Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (6929.923 ms) ====== [2024-08-16T23:35:58.574Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-16T23:35:59.298Z] GC before operation: completed in 830.409 ms, heap usage 113.105 MB -> 66.563 MB. [2024-08-16T23:36:06.970Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (7334.665 ms) ====== [2024-08-16T23:36:06.971Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-16T23:36:06.971Z] GC before operation: completed in 309.983 ms, heap usage 262.880 MB -> 92.215 MB. [2024-08-16T23:36:15.018Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (7632.426 ms) ====== [2024-08-16T23:36:15.018Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-16T23:36:15.018Z] GC before operation: completed in 372.581 ms, heap usage 332.182 MB -> 94.269 MB. [2024-08-16T23:36:19.932Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (5319.462 ms) ====== [2024-08-16T23:36:19.932Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-16T23:36:20.466Z] GC before operation: completed in 669.582 ms, heap usage 403.141 MB -> 94.371 MB. [2024-08-16T23:36:34.421Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (13836.568 ms) ====== [2024-08-16T23:36:34.421Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-16T23:36:34.817Z] GC before operation: completed in 325.522 ms, heap usage 397.721 MB -> 92.659 MB. [2024-08-16T23:36:38.630Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3505.646 ms) ====== [2024-08-16T23:36:38.630Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-16T23:36:38.630Z] GC before operation: completed in 435.409 ms, heap usage 181.444 MB -> 66.691 MB. [2024-08-16T23:36:45.088Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (6323.290 ms) ====== [2024-08-16T23:36:45.088Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-16T23:36:45.639Z] GC before operation: completed in 534.052 ms, heap usage 336.940 MB -> 94.665 MB. [2024-08-16T23:36:51.785Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (6180.949 ms) ====== [2024-08-16T23:36:51.785Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-16T23:36:52.409Z] GC before operation: completed in 590.588 ms, heap usage 265.165 MB -> 94.759 MB. [2024-08-16T23:37:00.778Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (8214.786 ms) ====== [2024-08-16T23:37:00.778Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-16T23:37:00.778Z] GC before operation: completed in 313.119 ms, heap usage 162.164 MB -> 66.969 MB. [2024-08-16T23:37:08.721Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (7496.516 ms) ====== [2024-08-16T23:37:08.721Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-16T23:37:09.260Z] GC before operation: completed in 646.967 ms, heap usage 157.943 MB -> 94.710 MB. [2024-08-16T23:37:09.260Z] 24/08/16 16:37:08 WARN BlockManager: Asked to remove block broadcast_386, which does not exist [2024-08-16T23:37:09.260Z] 24/08/16 16:37:08 WARN BlockManager: Asked to remove block broadcast_386_piece0, which does not exist [2024-08-16T23:37:15.587Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (6177.288 ms) ====== [2024-08-16T23:37:15.587Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-16T23:37:15.587Z] GC before operation: completed in 432.152 ms, heap usage 265.050 MB -> 67.465 MB. [2024-08-16T23:37:23.280Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (7283.512 ms) ====== [2024-08-16T23:37:23.280Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-16T23:37:23.280Z] GC before operation: completed in 295.948 ms, heap usage 105.058 MB -> 66.992 MB. [2024-08-16T23:37:37.031Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (14045.223 ms) ====== [2024-08-16T23:37:37.031Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-16T23:37:37.457Z] GC before operation: completed in 357.163 ms, heap usage 197.060 MB -> 67.647 MB. [2024-08-16T23:37:42.543Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4648.037 ms) ====== [2024-08-16T23:37:42.543Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-16T23:37:44.379Z] GC before operation: completed in 1792.940 ms, heap usage 290.746 MB -> 67.743 MB. [2024-08-16T23:37:49.247Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (5094.845 ms) ====== [2024-08-16T23:37:49.247Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-16T23:37:49.247Z] GC before operation: completed in 272.111 ms, heap usage 274.802 MB -> 95.434 MB. [2024-08-16T23:37:55.798Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (5902.073 ms) ====== [2024-08-16T23:37:55.798Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-16T23:37:56.812Z] GC before operation: completed in 1585.259 ms, heap usage 144.510 MB -> 67.378 MB. [2024-08-16T23:38:04.408Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (6846.206 ms) ====== [2024-08-16T23:38:04.408Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-16T23:38:04.408Z] GC before operation: completed in 263.998 ms, heap usage 191.403 MB -> 68.185 MB. [2024-08-16T23:38:10.854Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (6706.855 ms) ====== [2024-08-16T23:38:10.854Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-16T23:38:10.854Z] GC before operation: completed in 278.340 ms, heap usage 130.022 MB -> 67.847 MB. [2024-08-16T23:38:18.297Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (7522.539 ms) ====== [2024-08-16T23:38:18.297Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-16T23:38:18.844Z] GC before operation: completed in 410.943 ms, heap usage 212.252 MB -> 68.225 MB. [2024-08-16T23:38:26.266Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (6213.402 ms) ====== [2024-08-16T23:38:26.266Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-16T23:38:26.266Z] GC before operation: completed in 400.047 ms, heap usage 167.643 MB -> 68.057 MB. [2024-08-16T23:38:42.548Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (15625.544 ms) ====== [2024-08-16T23:38:42.548Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-16T23:38:42.548Z] GC before operation: completed in 459.643 ms, heap usage 116.407 MB -> 68.264 MB. [2024-08-16T23:38:42.548Z] 24/08/16 16:38:40 WARN BlockManager: Asked to remove block broadcast_540, which does not exist [2024-08-16T23:38:46.342Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4503.865 ms) ====== [2024-08-16T23:38:48.847Z] ----------------------------------- [2024-08-16T23:38:48.847Z] renaissance-dec-tree_0_PASSED [2024-08-16T23:38:48.847Z] ----------------------------------- [2024-08-16T23:38:48.847Z] [2024-08-16T23:38:48.847Z] TEST TEARDOWN: [2024-08-16T23:38:48.847Z] Nothing to be done for teardown. [2024-08-16T23:38:48.847Z] renaissance-dec-tree_0 Finish Time: Fri Aug 16 16:38:47 2024 Epoch Time (ms): 1723851527664