[2025-06-26T16:40:20.646Z] Running test renaissance-dec-tree_0 ... [2025-06-26T16:40:20.646Z] =============================================== [2025-06-26T16:40:20.646Z] renaissance-dec-tree_0 Start Time: Thu Jun 26 16:40:20 2025 Epoch Time (ms): 1750956020255 [2025-06-26T16:40:20.646Z] variation: NoOptions [2025-06-26T16:40:20.646Z] JVM_OPTIONS: [2025-06-26T16:40:20.646Z] { \ [2025-06-26T16:40:20.646Z] echo ""; echo "TEST SETUP:"; \ [2025-06-26T16:40:20.646Z] echo "Nothing to be done for setup."; \ [2025-06-26T16:40:20.646Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17509550664507/renaissance-dec-tree_0"; \ [2025-06-26T16:40:20.646Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17509550664507/renaissance-dec-tree_0"; \ [2025-06-26T16:40:20.646Z] echo ""; echo "TESTING:"; \ [2025-06-26T16:40:20.646Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17509550664507/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-26T16:40:20.646Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17509550664507/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-26T16:40:20.646Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-26T16:40:20.646Z] echo "Nothing to be done for teardown."; \ [2025-06-26T16:40:20.646Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17509550664507/TestTargetResult"; [2025-06-26T16:40:20.646Z] [2025-06-26T16:40:20.646Z] TEST SETUP: [2025-06-26T16:40:20.646Z] Nothing to be done for setup. [2025-06-26T16:40:20.646Z] [2025-06-26T16:40:20.646Z] TESTING: [2025-06-26T16:40:29.535Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-26T16:40:32.456Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-26T16:40:32.456Z] GC before operation: completed in 56.752 ms, heap usage 76.208 MB -> 34.852 MB. [2025-06-26T16:40:48.232Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (14217.538 ms) ====== [2025-06-26T16:40:48.232Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-26T16:40:48.232Z] GC before operation: completed in 129.895 ms, heap usage 277.766 MB -> 68.927 MB. [2025-06-26T16:40:49.862Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3302.469 ms) ====== [2025-06-26T16:40:49.862Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-26T16:40:49.862Z] GC before operation: completed in 136.290 ms, heap usage 203.385 MB -> 68.197 MB. [2025-06-26T16:40:52.780Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2923.740 ms) ====== [2025-06-26T16:40:52.780Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-26T16:40:53.100Z] GC before operation: completed in 131.198 ms, heap usage 255.260 MB -> 68.418 MB. [2025-06-26T16:40:56.008Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2724.092 ms) ====== [2025-06-26T16:40:56.008Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-26T16:40:56.008Z] GC before operation: completed in 142.089 ms, heap usage 374.423 MB -> 68.766 MB. [2025-06-26T16:40:58.942Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2583.952 ms) ====== [2025-06-26T16:40:58.942Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-26T16:40:58.942Z] GC before operation: completed in 132.970 ms, heap usage 234.766 MB -> 68.952 MB. [2025-06-26T16:41:01.160Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2430.052 ms) ====== [2025-06-26T16:41:01.160Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-26T16:41:01.160Z] GC before operation: completed in 132.929 ms, heap usage 359.803 MB -> 69.017 MB. [2025-06-26T16:41:03.435Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2117.456 ms) ====== [2025-06-26T16:41:03.435Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-26T16:41:03.435Z] GC before operation: completed in 118.550 ms, heap usage 189.186 MB -> 69.341 MB. [2025-06-26T16:41:05.650Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2134.145 ms) ====== [2025-06-26T16:41:05.650Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-26T16:41:05.650Z] GC before operation: completed in 132.263 ms, heap usage 347.792 MB -> 69.421 MB. [2025-06-26T16:41:07.864Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2329.114 ms) ====== [2025-06-26T16:41:07.864Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-26T16:41:08.188Z] GC before operation: completed in 132.984 ms, heap usage 110.786 MB -> 68.981 MB. [2025-06-26T16:41:10.406Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2199.785 ms) ====== [2025-06-26T16:41:10.406Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-26T16:41:10.406Z] GC before operation: completed in 135.229 ms, heap usage 169.929 MB -> 69.505 MB. [2025-06-26T16:41:12.624Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2185.033 ms) ====== [2025-06-26T16:41:12.624Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-26T16:41:12.624Z] GC before operation: completed in 129.413 ms, heap usage 328.010 MB -> 69.808 MB. [2025-06-26T16:41:14.856Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2111.168 ms) ====== [2025-06-26T16:41:14.856Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-26T16:41:15.176Z] GC before operation: completed in 217.354 ms, heap usage 112.799 MB -> 69.319 MB. [2025-06-26T16:41:17.397Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2177.102 ms) ====== [2025-06-26T16:41:17.397Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-26T16:41:17.397Z] GC before operation: completed in 158.803 ms, heap usage 99.910 MB -> 70.733 MB. [2025-06-26T16:41:19.613Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1922.160 ms) ====== [2025-06-26T16:41:19.613Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-26T16:41:19.613Z] GC before operation: completed in 149.036 ms, heap usage 516.751 MB -> 70.514 MB. [2025-06-26T16:41:21.851Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1943.934 ms) ====== [2025-06-26T16:41:21.851Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-26T16:41:21.851Z] GC before operation: completed in 142.675 ms, heap usage 152.681 MB -> 70.117 MB. [2025-06-26T16:41:23.478Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1911.471 ms) ====== [2025-06-26T16:41:23.478Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-26T16:41:23.800Z] GC before operation: completed in 143.979 ms, heap usage 270.756 MB -> 70.466 MB. [2025-06-26T16:41:25.430Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1893.862 ms) ====== [2025-06-26T16:41:25.430Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-26T16:41:25.755Z] GC before operation: completed in 140.932 ms, heap usage 346.135 MB -> 70.667 MB. [2025-06-26T16:41:27.972Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1866.737 ms) ====== [2025-06-26T16:41:27.972Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-26T16:41:27.973Z] GC before operation: completed in 151.559 ms, heap usage 334.296 MB -> 70.682 MB. [2025-06-26T16:41:29.605Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1968.557 ms) ====== [2025-06-26T16:41:29.605Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-26T16:41:29.929Z] GC before operation: completed in 146.418 ms, heap usage 352.741 MB -> 70.747 MB. [2025-06-26T16:41:31.559Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1839.243 ms) ====== [2025-06-26T16:41:31.559Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-26T16:41:31.881Z] GC before operation: completed in 147.669 ms, heap usage 347.906 MB -> 70.959 MB. [2025-06-26T16:41:33.511Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1784.454 ms) ====== [2025-06-26T16:41:33.511Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-26T16:41:33.833Z] GC before operation: completed in 173.619 ms, heap usage 334.459 MB -> 71.105 MB. [2025-06-26T16:41:35.476Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1856.786 ms) ====== [2025-06-26T16:41:35.476Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-26T16:41:35.798Z] GC before operation: completed in 161.277 ms, heap usage 334.002 MB -> 90.538 MB. [2025-06-26T16:41:38.026Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1831.694 ms) ====== [2025-06-26T16:41:38.026Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-26T16:41:38.026Z] GC before operation: completed in 159.038 ms, heap usage 376.981 MB -> 98.811 MB. [2025-06-26T16:41:38.026Z] 16:41:37.621 WARN [block-manager-storage-async-thread-pool-50] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_321_piece0, which does not exist [2025-06-26T16:41:39.735Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1969.243 ms) ====== [2025-06-26T16:41:39.735Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-26T16:41:40.056Z] GC before operation: completed in 167.597 ms, heap usage 286.427 MB -> 71.308 MB. [2025-06-26T16:41:41.686Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1873.115 ms) ====== [2025-06-26T16:41:41.686Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-26T16:41:42.008Z] GC before operation: completed in 156.917 ms, heap usage 330.637 MB -> 71.430 MB. [2025-06-26T16:41:43.637Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1811.965 ms) ====== [2025-06-26T16:41:43.637Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-26T16:41:43.969Z] GC before operation: completed in 155.004 ms, heap usage 210.041 MB -> 71.509 MB. [2025-06-26T16:41:45.608Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1811.427 ms) ====== [2025-06-26T16:41:45.608Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-26T16:41:45.930Z] GC before operation: completed in 145.891 ms, heap usage 308.627 MB -> 71.633 MB. [2025-06-26T16:41:47.561Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1756.410 ms) ====== [2025-06-26T16:41:47.561Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-26T16:41:47.883Z] GC before operation: completed in 168.456 ms, heap usage 323.489 MB -> 99.362 MB. [2025-06-26T16:41:49.510Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1823.684 ms) ====== [2025-06-26T16:41:49.510Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-26T16:41:49.832Z] GC before operation: completed in 148.903 ms, heap usage 302.362 MB -> 71.843 MB. [2025-06-26T16:41:51.459Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1713.369 ms) ====== [2025-06-26T16:41:51.459Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-26T16:41:51.785Z] GC before operation: completed in 153.158 ms, heap usage 195.450 MB -> 71.968 MB. [2025-06-26T16:41:53.417Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1762.940 ms) ====== [2025-06-26T16:41:53.417Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-26T16:41:53.417Z] GC before operation: completed in 153.030 ms, heap usage 273.084 MB -> 72.048 MB. [2025-06-26T16:41:55.634Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1719.861 ms) ====== [2025-06-26T16:41:55.634Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-26T16:41:55.634Z] GC before operation: completed in 151.418 ms, heap usage 323.157 MB -> 72.203 MB. [2025-06-26T16:41:57.269Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1739.445 ms) ====== [2025-06-26T16:41:57.269Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-26T16:41:57.269Z] GC before operation: completed in 157.195 ms, heap usage 323.142 MB -> 72.328 MB. [2025-06-26T16:41:59.500Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1742.869 ms) ====== [2025-06-26T16:41:59.500Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-26T16:41:59.500Z] GC before operation: completed in 150.378 ms, heap usage 324.819 MB -> 72.447 MB. [2025-06-26T16:42:01.196Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1816.867 ms) ====== [2025-06-26T16:42:01.196Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-26T16:42:01.196Z] GC before operation: completed in 159.501 ms, heap usage 324.885 MB -> 72.618 MB. [2025-06-26T16:42:02.861Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1674.717 ms) ====== [2025-06-26T16:42:02.861Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-26T16:42:03.185Z] GC before operation: completed in 168.026 ms, heap usage 311.735 MB -> 72.964 MB. [2025-06-26T16:42:04.816Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1725.869 ms) ====== [2025-06-26T16:42:04.816Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-26T16:42:04.816Z] GC before operation: completed in 148.359 ms, heap usage 311.579 MB -> 72.688 MB. [2025-06-26T16:42:07.035Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1726.421 ms) ====== [2025-06-26T16:42:07.035Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-26T16:42:07.035Z] GC before operation: completed in 155.355 ms, heap usage 324.688 MB -> 81.353 MB. [2025-06-26T16:42:08.662Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1697.681 ms) ====== [2025-06-26T16:42:08.662Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-26T16:42:08.662Z] GC before operation: completed in 161.481 ms, heap usage 157.874 MB -> 100.261 MB. [2025-06-26T16:42:10.293Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1769.671 ms) ====== [2025-06-26T16:42:10.614Z] ----------------------------------- [2025-06-26T16:42:10.614Z] renaissance-dec-tree_0_PASSED [2025-06-26T16:42:10.614Z] ----------------------------------- [2025-06-26T16:42:10.614Z] [2025-06-26T16:42:10.614Z] TEST TEARDOWN: [2025-06-26T16:42:10.614Z] Nothing to be done for teardown. [2025-06-26T16:42:10.614Z] renaissance-dec-tree_0 Finish Time: Thu Jun 26 16:42:10 2025 Epoch Time (ms): 1750956130506