[2025-03-30T21:37:01.216Z] Running test renaissance-dec-tree_0 ... [2025-03-30T21:37:01.216Z] =============================================== [2025-03-30T21:37:01.216Z] renaissance-dec-tree_0 Start Time: Sun Mar 30 21:37:00 2025 Epoch Time (ms): 1743370620967 [2025-03-30T21:37:01.216Z] variation: NoOptions [2025-03-30T21:37:01.216Z] JVM_OPTIONS: [2025-03-30T21:37:01.216Z] { \ [2025-03-30T21:37:01.216Z] echo ""; echo "TEST SETUP:"; \ [2025-03-30T21:37:01.216Z] echo "Nothing to be done for setup."; \ [2025-03-30T21:37:01.216Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17433701016682/renaissance-dec-tree_0"; \ [2025-03-30T21:37:01.216Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17433701016682/renaissance-dec-tree_0"; \ [2025-03-30T21:37:01.216Z] echo ""; echo "TESTING:"; \ [2025-03-30T21:37:01.216Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17433701016682/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-03-30T21:37:01.216Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17433701016682/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-30T21:37:01.216Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-30T21:37:01.216Z] echo "Nothing to be done for teardown."; \ [2025-03-30T21:37:01.216Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17433701016682/TestTargetResult"; [2025-03-30T21:37:01.216Z] [2025-03-30T21:37:01.216Z] TEST SETUP: [2025-03-30T21:37:01.216Z] Nothing to be done for setup. [2025-03-30T21:37:01.216Z] [2025-03-30T21:37:01.216Z] TESTING: [2025-03-30T21:37:07.948Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-30T21:37:08.887Z] WARNING: An illegal reflective access operation has occurred [2025-03-30T21:37:08.887Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17433701016682/renaissance-dec-tree_0/harness-213701-4842886894520673712/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-03-30T21:37:08.887Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-30T21:37:08.887Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-30T21:37:08.887Z] WARNING: All illegal access operations will be denied in a future release [2025-03-30T21:37:10.813Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-30T21:37:10.813Z] GC before operation: completed in 58.344 ms, heap usage 92.823 MB -> 34.386 MB. [2025-03-30T21:37:20.451Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9226.529 ms) ====== [2025-03-30T21:37:20.451Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-30T21:37:20.451Z] GC before operation: completed in 107.815 ms, heap usage 344.556 MB -> 68.259 MB. [2025-03-30T21:37:22.392Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2409.740 ms) ====== [2025-03-30T21:37:22.392Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-30T21:37:22.392Z] GC before operation: completed in 127.826 ms, heap usage 139.781 MB -> 68.299 MB. [2025-03-30T21:37:24.320Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2015.757 ms) ====== [2025-03-30T21:37:24.320Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-30T21:37:24.320Z] GC before operation: completed in 94.708 ms, heap usage 262.065 MB -> 80.180 MB. [2025-03-30T21:37:27.005Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1933.119 ms) ====== [2025-03-30T21:37:27.005Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-30T21:37:27.005Z] GC before operation: completed in 126.222 ms, heap usage 297.188 MB -> 69.259 MB. [2025-03-30T21:37:27.943Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1905.409 ms) ====== [2025-03-30T21:37:27.943Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-30T21:37:28.881Z] GC before operation: completed in 98.027 ms, heap usage 213.941 MB -> 69.485 MB. [2025-03-30T21:37:29.818Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1815.803 ms) ====== [2025-03-30T21:37:29.818Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-30T21:37:30.757Z] GC before operation: completed in 103.312 ms, heap usage 252.931 MB -> 69.711 MB. [2025-03-30T21:37:32.706Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1817.036 ms) ====== [2025-03-30T21:37:32.706Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-30T21:37:32.706Z] GC before operation: completed in 118.661 ms, heap usage 219.115 MB -> 69.748 MB. [2025-03-30T21:37:34.646Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1919.981 ms) ====== [2025-03-30T21:37:34.646Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-30T21:37:34.646Z] GC before operation: completed in 117.263 ms, heap usage 117.856 MB -> 69.689 MB. [2025-03-30T21:37:36.581Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1861.552 ms) ====== [2025-03-30T21:37:36.581Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-30T21:37:36.581Z] GC before operation: completed in 122.632 ms, heap usage 213.799 MB -> 69.985 MB. [2025-03-30T21:37:38.532Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1883.664 ms) ====== [2025-03-30T21:37:38.532Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-30T21:37:38.532Z] GC before operation: completed in 134.826 ms, heap usage 145.839 MB -> 70.238 MB. [2025-03-30T21:37:40.467Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1846.781 ms) ====== [2025-03-30T21:37:40.467Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-30T21:37:40.467Z] GC before operation: completed in 135.138 ms, heap usage 335.001 MB -> 86.928 MB. [2025-03-30T21:37:42.407Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1760.352 ms) ====== [2025-03-30T21:37:42.407Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-30T21:37:42.407Z] GC before operation: completed in 138.169 ms, heap usage 202.044 MB -> 70.165 MB. [2025-03-30T21:37:44.341Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1831.881 ms) ====== [2025-03-30T21:37:44.341Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-30T21:37:44.341Z] GC before operation: completed in 133.883 ms, heap usage 314.666 MB -> 70.584 MB. [2025-03-30T21:37:46.268Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1800.124 ms) ====== [2025-03-30T21:37:46.268Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-30T21:37:46.268Z] GC before operation: completed in 133.803 ms, heap usage 287.073 MB -> 87.234 MB. [2025-03-30T21:37:48.214Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1775.903 ms) ====== [2025-03-30T21:37:48.214Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-30T21:37:48.214Z] GC before operation: completed in 135.276 ms, heap usage 129.289 MB -> 70.506 MB. [2025-03-30T21:37:50.150Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1770.651 ms) ====== [2025-03-30T21:37:50.150Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-30T21:37:50.150Z] GC before operation: completed in 141.010 ms, heap usage 208.853 MB -> 71.012 MB. [2025-03-30T21:37:52.083Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1757.863 ms) ====== [2025-03-30T21:37:52.083Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-30T21:37:52.083Z] GC before operation: completed in 138.720 ms, heap usage 282.564 MB -> 82.070 MB. [2025-03-30T21:37:54.013Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1711.989 ms) ====== [2025-03-30T21:37:54.013Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-30T21:37:54.013Z] GC before operation: completed in 138.368 ms, heap usage 133.317 MB -> 70.591 MB. [2025-03-30T21:37:55.946Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1813.895 ms) ====== [2025-03-30T21:37:55.946Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-30T21:37:55.946Z] GC before operation: completed in 139.656 ms, heap usage 247.248 MB -> 82.078 MB. [2025-03-30T21:37:57.871Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1730.776 ms) ====== [2025-03-30T21:37:57.871Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-30T21:37:57.871Z] GC before operation: completed in 139.819 ms, heap usage 323.221 MB -> 82.333 MB. [2025-03-30T21:37:58.809Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1668.382 ms) ====== [2025-03-30T21:37:58.809Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-30T21:37:59.749Z] GC before operation: completed in 137.583 ms, heap usage 284.612 MB -> 82.438 MB. [2025-03-30T21:38:00.702Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1759.457 ms) ====== [2025-03-30T21:38:00.702Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-30T21:38:01.641Z] GC before operation: completed in 143.068 ms, heap usage 180.059 MB -> 71.353 MB. [2025-03-30T21:38:02.617Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1760.362 ms) ====== [2025-03-30T21:38:02.617Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-30T21:38:03.555Z] GC before operation: completed in 140.629 ms, heap usage 323.752 MB -> 71.746 MB. [2025-03-30T21:38:05.481Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1867.039 ms) ====== [2025-03-30T21:38:05.481Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-30T21:38:05.481Z] GC before operation: completed in 143.297 ms, heap usage 310.225 MB -> 88.232 MB. [2025-03-30T21:38:06.419Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1667.567 ms) ====== [2025-03-30T21:38:06.419Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-30T21:38:07.358Z] GC before operation: completed in 150.798 ms, heap usage 133.933 MB -> 71.644 MB. [2025-03-30T21:38:08.302Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1761.166 ms) ====== [2025-03-30T21:38:08.302Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-30T21:38:09.240Z] GC before operation: completed in 151.139 ms, heap usage 169.680 MB -> 71.595 MB. [2025-03-30T21:38:10.186Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1743.943 ms) ====== [2025-03-30T21:38:10.186Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-30T21:38:11.123Z] GC before operation: completed in 141.307 ms, heap usage 268.977 MB -> 72.178 MB. [2025-03-30T21:38:12.062Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1741.968 ms) ====== [2025-03-30T21:38:12.062Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-30T21:38:13.000Z] GC before operation: completed in 135.220 ms, heap usage 185.399 MB -> 72.132 MB. [2025-03-30T21:38:14.943Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1797.608 ms) ====== [2025-03-30T21:38:14.943Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-30T21:38:14.943Z] GC before operation: completed in 134.891 ms, heap usage 118.986 MB -> 82.617 MB. [2025-03-30T21:38:15.880Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1619.054 ms) ====== [2025-03-30T21:38:15.880Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-30T21:38:17.761Z] GC before operation: completed in 142.866 ms, heap usage 190.724 MB -> 83.307 MB. [2025-03-30T21:38:17.761Z] 21:38:15.807 WARN [block-manager-storage-async-thread-pool-38] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-03-30T21:38:17.761Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1666.140 ms) ====== [2025-03-30T21:38:17.761Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-30T21:38:17.761Z] GC before operation: completed in 154.306 ms, heap usage 342.156 MB -> 88.940 MB. [2025-03-30T21:38:19.688Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1709.389 ms) ====== [2025-03-30T21:38:19.688Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-30T21:38:19.688Z] GC before operation: completed in 164.777 ms, heap usage 213.456 MB -> 72.400 MB. [2025-03-30T21:38:21.613Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1749.045 ms) ====== [2025-03-30T21:38:21.613Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-30T21:38:21.613Z] GC before operation: completed in 153.850 ms, heap usage 274.744 MB -> 78.062 MB. [2025-03-30T21:38:23.716Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1750.344 ms) ====== [2025-03-30T21:38:23.716Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-30T21:38:23.716Z] GC before operation: completed in 150.141 ms, heap usage 268.095 MB -> 72.762 MB. [2025-03-30T21:38:25.646Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1773.751 ms) ====== [2025-03-30T21:38:25.646Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-30T21:38:25.646Z] GC before operation: completed in 154.947 ms, heap usage 114.008 MB -> 72.691 MB. [2025-03-30T21:38:27.580Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1761.507 ms) ====== [2025-03-30T21:38:27.580Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-30T21:38:27.580Z] GC before operation: completed in 144.817 ms, heap usage 247.644 MB -> 84.270 MB. [2025-03-30T21:38:29.548Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1685.052 ms) ====== [2025-03-30T21:38:29.548Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-30T21:38:29.548Z] GC before operation: completed in 144.173 ms, heap usage 224.994 MB -> 73.079 MB. [2025-03-30T21:38:31.475Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1700.357 ms) ====== [2025-03-30T21:38:31.475Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-30T21:38:31.475Z] GC before operation: completed in 150.590 ms, heap usage 309.818 MB -> 73.186 MB. [2025-03-30T21:38:33.401Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1727.539 ms) ====== [2025-03-30T21:38:33.401Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-30T21:38:33.401Z] GC before operation: completed in 149.909 ms, heap usage 115.920 MB -> 72.961 MB. [2025-03-30T21:38:35.327Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1709.628 ms) ====== [2025-03-30T21:38:35.327Z] ----------------------------------- [2025-03-30T21:38:35.327Z] renaissance-dec-tree_0_PASSED [2025-03-30T21:38:35.327Z] ----------------------------------- [2025-03-30T21:38:35.327Z] [2025-03-30T21:38:35.327Z] TEST TEARDOWN: [2025-03-30T21:38:35.327Z] Nothing to be done for teardown. [2025-03-30T21:38:35.327Z] renaissance-dec-tree_0 Finish Time: Sun Mar 30 21:38:34 2025 Epoch Time (ms): 1743370714732