[2025-03-27T14:53:52.427Z] Running test renaissance-dec-tree_0 ... [2025-03-27T14:53:52.427Z] =============================================== [2025-03-27T14:53:52.427Z] renaissance-dec-tree_0 Start Time: Thu Mar 27 14:53:51 2025 Epoch Time (ms): 1743087231966 [2025-03-27T14:53:52.427Z] variation: NoOptions [2025-03-27T14:53:52.427Z] JVM_OPTIONS: [2025-03-27T14:53:52.427Z] { \ [2025-03-27T14:53:52.427Z] echo ""; echo "TEST SETUP:"; \ [2025-03-27T14:53:52.427Z] echo "Nothing to be done for setup."; \ [2025-03-27T14:53:52.427Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1743086208764/renaissance-dec-tree_0"; \ [2025-03-27T14:53:52.427Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1743086208764/renaissance-dec-tree_0"; \ [2025-03-27T14:53:52.427Z] echo ""; echo "TESTING:"; \ [2025-03-27T14:53:52.427Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1743086208764/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-03-27T14:53:52.427Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1743086208764/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-27T14:53:52.427Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-27T14:53:52.427Z] echo "Nothing to be done for teardown."; \ [2025-03-27T14:53:52.427Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1743086208764/TestTargetResult"; [2025-03-27T14:53:52.427Z] [2025-03-27T14:53:52.427Z] TEST SETUP: [2025-03-27T14:53:52.427Z] Nothing to be done for setup. [2025-03-27T14:53:52.427Z] [2025-03-27T14:53:52.427Z] TESTING: [2025-03-27T14:54:04.640Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-27T14:54:11.852Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-27T14:54:11.852Z] GC before operation: completed in 119.693 ms, heap usage 43.976 MB -> 34.912 MB. [2025-03-27T14:54:42.768Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (30984.779 ms) ====== [2025-03-27T14:54:42.768Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-27T14:54:42.768Z] GC before operation: completed in 210.680 ms, heap usage 234.132 MB -> 68.952 MB. [2025-03-27T14:54:51.456Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7673.218 ms) ====== [2025-03-27T14:54:51.456Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-27T14:54:51.456Z] GC before operation: completed in 291.047 ms, heap usage 183.025 MB -> 85.785 MB. [2025-03-27T14:54:51.456Z] 14:54:50.596 WARN [block-manager-storage-async-thread-pool-10] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_23, which does not exist [2025-03-27T14:54:57.406Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6652.311 ms) ====== [2025-03-27T14:54:57.406Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-27T14:54:58.240Z] GC before operation: completed in 230.740 ms, heap usage 230.583 MB -> 69.691 MB. [2025-03-27T14:55:04.184Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5999.502 ms) ====== [2025-03-27T14:55:04.184Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-27T14:55:04.184Z] GC before operation: completed in 0.658 ms, heap usage 113.845 MB -> 113.845 MB. [2025-03-27T14:55:10.145Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5673.360 ms) ====== [2025-03-27T14:55:10.145Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-27T14:55:10.145Z] GC before operation: completed in 326.805 ms, heap usage 348.311 MB -> 70.171 MB. [2025-03-27T14:55:14.861Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (5077.374 ms) ====== [2025-03-27T14:55:14.861Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-27T14:55:15.701Z] GC before operation: completed in 233.215 ms, heap usage 109.204 MB -> 70.084 MB. [2025-03-27T14:55:20.420Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5037.990 ms) ====== [2025-03-27T14:55:20.420Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-27T14:55:20.420Z] GC before operation: completed in 295.925 ms, heap usage 227.024 MB -> 70.610 MB. [2025-03-27T14:55:25.094Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4724.393 ms) ====== [2025-03-27T14:55:25.094Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-27T14:55:25.921Z] GC before operation: completed in 327.020 ms, heap usage 269.840 MB -> 70.606 MB. [2025-03-27T14:55:30.653Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4477.673 ms) ====== [2025-03-27T14:55:30.653Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-27T14:55:30.653Z] GC before operation: completed in 228.310 ms, heap usage 360.389 MB -> 70.858 MB. [2025-03-27T14:55:34.844Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4152.555 ms) ====== [2025-03-27T14:55:34.844Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-27T14:55:34.844Z] GC before operation: completed in 291.890 ms, heap usage 346.577 MB -> 71.008 MB. [2025-03-27T14:55:39.549Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (4183.126 ms) ====== [2025-03-27T14:55:39.549Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-27T14:55:39.549Z] GC before operation: completed in 304.066 ms, heap usage 361.595 MB -> 71.004 MB. [2025-03-27T14:55:43.162Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4172.855 ms) ====== [2025-03-27T14:55:43.162Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-27T14:55:43.973Z] GC before operation: completed in 260.383 ms, heap usage 343.347 MB -> 71.196 MB. [2025-03-27T14:55:48.677Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4375.379 ms) ====== [2025-03-27T14:55:48.677Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-27T14:55:48.677Z] GC before operation: completed in 307.883 ms, heap usage 344.856 MB -> 71.366 MB. [2025-03-27T14:55:52.291Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3751.560 ms) ====== [2025-03-27T14:55:52.292Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-27T14:55:52.292Z] GC before operation: completed in 318.163 ms, heap usage 363.530 MB -> 88.068 MB. [2025-03-27T14:55:57.027Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3889.919 ms) ====== [2025-03-27T14:55:57.027Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-27T14:55:57.027Z] GC before operation: completed in 253.532 ms, heap usage 144.897 MB -> 70.865 MB. [2025-03-27T14:56:00.668Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3874.289 ms) ====== [2025-03-27T14:56:00.668Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-27T14:56:00.668Z] GC before operation: completed in 285.533 ms, heap usage 222.015 MB -> 71.655 MB. [2025-03-27T14:56:04.278Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3268.313 ms) ====== [2025-03-27T14:56:04.278Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-27T14:56:04.278Z] GC before operation: completed in 258.474 ms, heap usage 313.444 MB -> 71.941 MB. [2025-03-27T14:56:07.900Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3084.512 ms) ====== [2025-03-27T14:56:07.900Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-27T14:56:07.900Z] GC before operation: completed in 287.326 ms, heap usage 352.395 MB -> 72.041 MB. [2025-03-27T14:56:11.525Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3964.600 ms) ====== [2025-03-27T14:56:11.525Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-27T14:56:12.351Z] GC before operation: completed in 325.752 ms, heap usage 334.094 MB -> 71.995 MB. [2025-03-27T14:56:14.969Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3022.696 ms) ====== [2025-03-27T14:56:14.969Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-27T14:56:15.779Z] GC before operation: completed in 351.112 ms, heap usage 350.106 MB -> 72.293 MB. [2025-03-27T14:56:18.390Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2958.885 ms) ====== [2025-03-27T14:56:18.390Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-27T14:56:18.390Z] GC before operation: completed in 271.016 ms, heap usage 291.335 MB -> 88.635 MB. [2025-03-27T14:56:21.999Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3486.021 ms) ====== [2025-03-27T14:56:21.999Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-27T14:56:22.822Z] GC before operation: completed in 323.356 ms, heap usage 139.429 MB -> 71.676 MB. [2025-03-27T14:56:26.438Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (4021.017 ms) ====== [2025-03-27T14:56:26.438Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-27T14:56:26.438Z] GC before operation: completed in 280.472 ms, heap usage 158.947 MB -> 72.274 MB. [2025-03-27T14:56:30.048Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3101.714 ms) ====== [2025-03-27T14:56:30.048Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-27T14:56:30.048Z] GC before operation: completed in 343.049 ms, heap usage 230.870 MB -> 72.570 MB. [2025-03-27T14:56:33.666Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3140.217 ms) ====== [2025-03-27T14:56:33.666Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-27T14:56:33.666Z] GC before operation: completed in 287.118 ms, heap usage 466.354 MB -> 78.553 MB. [2025-03-27T14:56:37.288Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3124.711 ms) ====== [2025-03-27T14:56:37.288Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-27T14:56:37.288Z] GC before operation: completed in 266.294 ms, heap usage 272.356 MB -> 72.976 MB. [2025-03-27T14:56:39.901Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2909.286 ms) ====== [2025-03-27T14:56:39.901Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-27T14:56:39.901Z] GC before operation: completed in 255.995 ms, heap usage 316.791 MB -> 72.872 MB. [2025-03-27T14:56:43.547Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2980.856 ms) ====== [2025-03-27T14:56:43.547Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-27T14:56:43.547Z] GC before operation: completed in 296.009 ms, heap usage 328.613 MB -> 73.423 MB. [2025-03-27T14:56:47.180Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3696.808 ms) ====== [2025-03-27T14:56:47.180Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-27T14:56:47.180Z] GC before operation: completed in 282.471 ms, heap usage 124.166 MB -> 72.642 MB. [2025-03-27T14:56:51.548Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3531.855 ms) ====== [2025-03-27T14:56:51.548Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-27T14:56:51.548Z] GC before operation: completed in 332.922 ms, heap usage 398.586 MB -> 73.355 MB. [2025-03-27T14:56:54.173Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2858.281 ms) ====== [2025-03-27T14:56:54.173Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-27T14:56:54.173Z] GC before operation: completed in 291.448 ms, heap usage 315.579 MB -> 90.085 MB. [2025-03-27T14:56:57.793Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3300.458 ms) ====== [2025-03-27T14:56:57.793Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-27T14:56:57.793Z] GC before operation: completed in 364.856 ms, heap usage 485.506 MB -> 73.660 MB. [2025-03-27T14:57:01.397Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3247.857 ms) ====== [2025-03-27T14:57:01.397Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-27T14:57:01.397Z] GC before operation: completed in 333.809 ms, heap usage 311.536 MB -> 73.719 MB. [2025-03-27T14:57:05.022Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3477.078 ms) ====== [2025-03-27T14:57:05.022Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-27T14:57:05.844Z] GC before operation: completed in 314.591 ms, heap usage 307.701 MB -> 73.811 MB. [2025-03-27T14:57:08.466Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3103.734 ms) ====== [2025-03-27T14:57:08.466Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-27T14:57:09.278Z] GC before operation: completed in 309.347 ms, heap usage 326.181 MB -> 73.856 MB. [2025-03-27T14:57:12.888Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3456.266 ms) ====== [2025-03-27T14:57:12.888Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-27T14:57:12.888Z] GC before operation: completed in 283.166 ms, heap usage 320.134 MB -> 73.820 MB. [2025-03-27T14:57:16.513Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3636.920 ms) ====== [2025-03-27T14:57:16.513Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-27T14:57:16.513Z] GC before operation: completed in 297.831 ms, heap usage 124.340 MB -> 73.437 MB. [2025-03-27T14:57:20.140Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3324.631 ms) ====== [2025-03-27T14:57:20.141Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-27T14:57:20.141Z] GC before operation: completed in 308.700 ms, heap usage 536.741 MB -> 74.397 MB. [2025-03-27T14:57:23.763Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2976.897 ms) ====== [2025-03-27T14:57:23.763Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-27T14:57:23.763Z] GC before operation: completed in 289.471 ms, heap usage 284.513 MB -> 74.233 MB. [2025-03-27T14:57:27.372Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3392.130 ms) ====== [2025-03-27T14:57:27.372Z] ----------------------------------- [2025-03-27T14:57:27.372Z] renaissance-dec-tree_0_PASSED [2025-03-27T14:57:27.373Z] ----------------------------------- [2025-03-27T14:57:27.373Z] [2025-03-27T14:57:27.373Z] TEST TEARDOWN: [2025-03-27T14:57:27.373Z] Nothing to be done for teardown. [2025-03-27T14:57:27.373Z] renaissance-dec-tree_0 Finish Time: Thu Mar 27 14:57:27 2025 Epoch Time (ms): 1743087447067