[2024-12-07T00:09:00.955Z] Running test renaissance-dec-tree_0 ... [2024-12-07T00:09:00.955Z] =============================================== [2024-12-07T00:09:00.955Z] renaissance-dec-tree_0 Start Time: Sat Dec 7 00:09:00 2024 Epoch Time (ms): 1733530140557 [2024-12-07T00:09:00.955Z] variation: NoOptions [2024-12-07T00:09:00.955Z] JVM_OPTIONS: [2024-12-07T00:09:00.955Z] { \ [2024-12-07T00:09:00.955Z] echo ""; echo "TEST SETUP:"; \ [2024-12-07T00:09:00.955Z] echo "Nothing to be done for setup."; \ [2024-12-07T00:09:00.955Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1733527164839/renaissance-dec-tree_0"; \ [2024-12-07T00:09:00.955Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1733527164839/renaissance-dec-tree_0"; \ [2024-12-07T00:09:00.955Z] echo ""; echo "TESTING:"; \ [2024-12-07T00:09:00.955Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1733527164839/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-12-07T00:09:00.955Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1733527164839/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-07T00:09:00.955Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-07T00:09:00.955Z] echo "Nothing to be done for teardown."; \ [2024-12-07T00:09:00.955Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1733527164839/TestTargetResult"; [2024-12-07T00:09:00.955Z] [2024-12-07T00:09:00.955Z] TEST SETUP: [2024-12-07T00:09:00.955Z] Nothing to be done for setup. [2024-12-07T00:09:00.955Z] [2024-12-07T00:09:00.955Z] TESTING: [2024-12-07T00:09:16.758Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-07T00:09:30.438Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-12-07T00:09:40.235Z] WARNING: An illegal reflective access operation has occurred [2024-12-07T00:09:40.235Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_1733527164839/renaissance-dec-tree_0/harness-000904-12169637134264472820/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-12-07T00:09:40.235Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-12-07T00:09:40.235Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-12-07T00:09:40.235Z] WARNING: All illegal access operations will be denied in a future release [2024-12-07T00:09:59.181Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-12-07T00:09:59.181Z] GC before operation: completed in 408.512 ms, heap usage 38.650 MB -> 33.787 MB. [2024-12-07T00:11:18.855Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (80213.241 ms) ====== [2024-12-07T00:11:18.855Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-12-07T00:11:18.855Z] GC before operation: completed in 741.164 ms, heap usage 202.179 MB -> 64.049 MB. [2024-12-07T00:11:45.157Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (24678.272 ms) ====== [2024-12-07T00:11:45.157Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-12-07T00:11:45.157Z] GC before operation: completed in 902.593 ms, heap usage 259.224 MB -> 63.968 MB. [2024-12-07T00:12:04.425Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (19733.709 ms) ====== [2024-12-07T00:12:04.425Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-12-07T00:12:05.239Z] GC before operation: completed in 672.410 ms, heap usage 107.544 MB -> 64.351 MB. [2024-12-07T00:12:05.239Z] 24/12/07 00:12:04 WARN BlockManager: Asked to remove block broadcast_36, which does not exist [2024-12-07T00:12:21.684Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (16769.077 ms) ====== [2024-12-07T00:12:21.684Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-12-07T00:12:22.468Z] GC before operation: completed in 865.270 ms, heap usage 230.652 MB -> 64.459 MB. [2024-12-07T00:12:42.514Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (17717.820 ms) ====== [2024-12-07T00:12:42.514Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-12-07T00:12:42.514Z] GC before operation: completed in 798.423 ms, heap usage 117.155 MB -> 64.753 MB. [2024-12-07T00:12:59.090Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (16301.318 ms) ====== [2024-12-07T00:12:59.091Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-12-07T00:12:59.091Z] GC before operation: completed in 949.327 ms, heap usage 127.102 MB -> 65.007 MB. [2024-12-07T00:13:12.953Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (14081.653 ms) ====== [2024-12-07T00:13:12.953Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-12-07T00:13:12.953Z] GC before operation: completed in 743.940 ms, heap usage 125.503 MB -> 65.125 MB. [2024-12-07T00:13:26.599Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (12777.040 ms) ====== [2024-12-07T00:13:26.599Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-12-07T00:13:26.599Z] GC before operation: completed in 817.599 ms, heap usage 118.594 MB -> 65.299 MB. [2024-12-07T00:13:40.319Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (13595.718 ms) ====== [2024-12-07T00:13:40.319Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-12-07T00:13:41.118Z] GC before operation: completed in 888.302 ms, heap usage 186.238 MB -> 65.684 MB. [2024-12-07T00:13:54.664Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (12662.663 ms) ====== [2024-12-07T00:13:54.664Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-12-07T00:13:54.664Z] GC before operation: completed in 736.775 ms, heap usage 249.431 MB -> 65.420 MB. [2024-12-07T00:14:11.195Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (15588.731 ms) ====== [2024-12-07T00:14:11.195Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-12-07T00:14:11.195Z] GC before operation: completed in 915.839 ms, heap usage 191.544 MB -> 65.506 MB. [2024-12-07T00:14:22.775Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (11750.443 ms) ====== [2024-12-07T00:14:22.775Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-12-07T00:14:23.544Z] GC before operation: completed in 780.914 ms, heap usage 118.562 MB -> 65.323 MB. [2024-12-07T00:14:35.117Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (11487.183 ms) ====== [2024-12-07T00:14:35.117Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-12-07T00:14:36.666Z] GC before operation: completed in 1190.253 ms, heap usage 252.911 MB -> 65.719 MB. [2024-12-07T00:14:48.390Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (12109.396 ms) ====== [2024-12-07T00:14:48.390Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-12-07T00:14:49.138Z] GC before operation: completed in 956.022 ms, heap usage 243.364 MB -> 66.009 MB. [2024-12-07T00:15:02.717Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (12628.234 ms) ====== [2024-12-07T00:15:02.718Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-12-07T00:15:03.487Z] GC before operation: completed in 980.837 ms, heap usage 109.186 MB -> 65.789 MB. [2024-12-07T00:15:17.098Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (12983.740 ms) ====== [2024-12-07T00:15:17.098Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-12-07T00:15:17.098Z] GC before operation: completed in 970.963 ms, heap usage 276.626 MB -> 66.243 MB. [2024-12-07T00:15:29.187Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (12399.466 ms) ====== [2024-12-07T00:15:29.187Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-12-07T00:15:30.746Z] GC before operation: completed in 850.313 ms, heap usage 273.583 MB -> 66.196 MB. [2024-12-07T00:15:42.257Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (12200.960 ms) ====== [2024-12-07T00:15:42.257Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-12-07T00:15:43.852Z] GC before operation: completed in 1052.218 ms, heap usage 271.795 MB -> 66.358 MB. [2024-12-07T00:15:53.544Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (10250.968 ms) ====== [2024-12-07T00:15:53.544Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-12-07T00:15:55.084Z] GC before operation: completed in 820.845 ms, heap usage 202.758 MB -> 66.421 MB. [2024-12-07T00:16:04.797Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (9406.835 ms) ====== [2024-12-07T00:16:04.798Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-12-07T00:16:04.798Z] GC before operation: completed in 760.413 ms, heap usage 230.059 MB -> 94.120 MB. [2024-12-07T00:16:16.315Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (10988.972 ms) ====== [2024-12-07T00:16:16.315Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-12-07T00:16:17.060Z] GC before operation: completed in 1072.859 ms, heap usage 220.581 MB -> 66.719 MB. [2024-12-07T00:16:31.177Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (12923.387 ms) ====== [2024-12-07T00:16:31.177Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-12-07T00:16:31.177Z] GC before operation: completed in 859.963 ms, heap usage 145.810 MB -> 66.571 MB. [2024-12-07T00:16:42.685Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (11278.000 ms) ====== [2024-12-07T00:16:42.685Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-12-07T00:16:43.444Z] GC before operation: completed in 1023.703 ms, heap usage 113.850 MB -> 66.671 MB. [2024-12-07T00:16:55.003Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (10683.262 ms) ====== [2024-12-07T00:16:55.003Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-12-07T00:16:55.003Z] GC before operation: completed in 842.543 ms, heap usage 268.625 MB -> 67.004 MB. [2024-12-07T00:17:04.684Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (10059.643 ms) ====== [2024-12-07T00:17:04.684Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-12-07T00:17:05.434Z] GC before operation: completed in 891.422 ms, heap usage 185.407 MB -> 67.175 MB. [2024-12-07T00:17:15.279Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (9131.173 ms) ====== [2024-12-07T00:17:15.279Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-12-07T00:17:16.049Z] GC before operation: completed in 961.624 ms, heap usage 130.589 MB -> 67.270 MB. [2024-12-07T00:17:25.860Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (9724.542 ms) ====== [2024-12-07T00:17:25.860Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-12-07T00:17:26.612Z] GC before operation: completed in 968.243 ms, heap usage 170.505 MB -> 66.970 MB. [2024-12-07T00:17:38.155Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (10500.653 ms) ====== [2024-12-07T00:17:38.155Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-12-07T00:17:38.155Z] GC before operation: completed in 884.792 ms, heap usage 135.111 MB -> 67.202 MB. [2024-12-07T00:17:48.365Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (10642.817 ms) ====== [2024-12-07T00:17:48.365Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-12-07T00:17:49.121Z] GC before operation: completed in 1028.163 ms, heap usage 234.359 MB -> 67.610 MB. [2024-12-07T00:18:00.634Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (9877.762 ms) ====== [2024-12-07T00:18:00.634Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-12-07T00:18:00.634Z] GC before operation: completed in 1012.882 ms, heap usage 175.123 MB -> 67.405 MB. [2024-12-07T00:18:10.351Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (9464.016 ms) ====== [2024-12-07T00:18:10.351Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-12-07T00:18:10.351Z] GC before operation: completed in 796.902 ms, heap usage 206.191 MB -> 67.775 MB. [2024-12-07T00:18:20.145Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (9684.139 ms) ====== [2024-12-07T00:18:20.145Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-12-07T00:18:20.911Z] GC before operation: completed in 948.137 ms, heap usage 115.218 MB -> 67.437 MB. [2024-12-07T00:18:30.777Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (9599.708 ms) ====== [2024-12-07T00:18:30.777Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-12-07T00:18:31.529Z] GC before operation: completed in 989.741 ms, heap usage 254.917 MB -> 67.966 MB. [2024-12-07T00:18:41.369Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (9224.760 ms) ====== [2024-12-07T00:18:41.369Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-12-07T00:18:42.119Z] GC before operation: completed in 745.238 ms, heap usage 220.864 MB -> 67.910 MB. [2024-12-07T00:18:51.932Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (9103.329 ms) ====== [2024-12-07T00:18:51.932Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-12-07T00:18:51.932Z] GC before operation: completed in 860.492 ms, heap usage 231.290 MB -> 84.729 MB. [2024-12-07T00:19:01.718Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (8813.605 ms) ====== [2024-12-07T00:19:01.718Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-12-07T00:19:01.718Z] GC before operation: completed in 831.094 ms, heap usage 219.318 MB -> 68.270 MB. [2024-12-07T00:19:09.817Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (8021.430 ms) ====== [2024-12-07T00:19:09.817Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-12-07T00:19:09.817Z] GC before operation: completed in 683.841 ms, heap usage 252.284 MB -> 68.368 MB. [2024-12-07T00:19:21.365Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (9856.736 ms) ====== [2024-12-07T00:19:21.365Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-12-07T00:19:21.365Z] GC before operation: completed in 1090.542 ms, heap usage 307.435 MB -> 68.316 MB. [2024-12-07T00:19:31.085Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (9754.482 ms) ====== [2024-12-07T00:19:31.086Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-12-07T00:19:31.839Z] GC before operation: completed in 1014.395 ms, heap usage 283.965 MB -> 69.107 MB. [2024-12-07T00:19:41.701Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (8824.592 ms) ====== [2024-12-07T00:19:42.486Z] ----------------------------------- [2024-12-07T00:19:42.486Z] renaissance-dec-tree_0_PASSED [2024-12-07T00:19:42.486Z] ----------------------------------- [2024-12-07T00:19:42.486Z] [2024-12-07T00:19:42.486Z] TEST TEARDOWN: [2024-12-07T00:19:42.486Z] Nothing to be done for teardown. [2024-12-07T00:19:42.486Z] renaissance-dec-tree_0 Finish Time: Sat Dec 7 00:19:42 2024 Epoch Time (ms): 1733530782021