[2025-05-16T21:23:33.583Z] Running test renaissance-dec-tree_0 ... [2025-05-16T21:23:33.583Z] =============================================== [2025-05-16T21:23:33.583Z] renaissance-dec-tree_0 Start Time: Fri May 16 21:23:33 2025 Epoch Time (ms): 1747430613352 [2025-05-16T21:23:33.583Z] variation: NoOptions [2025-05-16T21:23:33.583Z] JVM_OPTIONS: [2025-05-16T21:23:33.583Z] { \ [2025-05-16T21:23:33.583Z] echo ""; echo "TEST SETUP:"; \ [2025-05-16T21:23:33.583Z] echo "Nothing to be done for setup."; \ [2025-05-16T21:23:33.583Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-dec-tree_0"; \ [2025-05-16T21:23:33.583Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-dec-tree_0"; \ [2025-05-16T21:23:33.583Z] echo ""; echo "TESTING:"; \ [2025-05-16T21:23:33.583Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-16T21:23:33.583Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-16T21:23:33.583Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-16T21:23:33.583Z] echo "Nothing to be done for teardown."; \ [2025-05-16T21:23:33.583Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/TestTargetResult"; [2025-05-16T21:23:33.583Z] [2025-05-16T21:23:33.583Z] TEST SETUP: [2025-05-16T21:23:33.583Z] Nothing to be done for setup. [2025-05-16T21:23:33.583Z] [2025-05-16T21:23:33.583Z] TESTING: [2025-05-16T21:23:38.967Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-16T21:23:40.943Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-16T21:23:40.943Z] GC before operation: completed in 39.364 ms, heap usage 64.284 MB -> 35.036 MB. [2025-05-16T21:23:50.682Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8570.339 ms) ====== [2025-05-16T21:23:50.682Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-16T21:23:50.682Z] GC before operation: completed in 82.242 ms, heap usage 214.713 MB -> 68.731 MB. [2025-05-16T21:23:52.635Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2197.872 ms) ====== [2025-05-16T21:23:52.635Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-16T21:23:52.635Z] GC before operation: completed in 90.726 ms, heap usage 340.935 MB -> 85.791 MB. [2025-05-16T21:23:54.589Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1873.485 ms) ====== [2025-05-16T21:23:54.589Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-16T21:23:54.589Z] GC before operation: completed in 91.409 ms, heap usage 334.791 MB -> 69.680 MB. [2025-05-16T21:23:56.544Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1844.244 ms) ====== [2025-05-16T21:23:56.544Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-16T21:23:56.544Z] GC before operation: completed in 84.279 ms, heap usage 270.199 MB -> 69.796 MB. [2025-05-16T21:23:57.499Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1736.452 ms) ====== [2025-05-16T21:23:57.499Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-16T21:23:58.450Z] GC before operation: completed in 101.965 ms, heap usage 252.711 MB -> 70.005 MB. [2025-05-16T21:23:59.418Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1692.797 ms) ====== [2025-05-16T21:23:59.418Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-16T21:23:59.418Z] GC before operation: completed in 97.011 ms, heap usage 173.792 MB -> 69.926 MB. [2025-05-16T21:24:01.386Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1635.437 ms) ====== [2025-05-16T21:24:01.386Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-16T21:24:01.386Z] GC before operation: completed in 103.112 ms, heap usage 145.086 MB -> 70.123 MB. [2025-05-16T21:24:03.338Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1632.657 ms) ====== [2025-05-16T21:24:03.338Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-16T21:24:03.338Z] GC before operation: completed in 114.281 ms, heap usage 391.950 MB -> 81.897 MB. [2025-05-16T21:24:05.297Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1646.187 ms) ====== [2025-05-16T21:24:05.297Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-16T21:24:05.297Z] GC before operation: completed in 110.112 ms, heap usage 278.301 MB -> 71.179 MB. [2025-05-16T21:24:06.248Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1620.612 ms) ====== [2025-05-16T21:24:06.248Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-16T21:24:07.198Z] GC before operation: completed in 89.299 ms, heap usage 148.514 MB -> 70.684 MB. [2025-05-16T21:24:08.150Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1617.583 ms) ====== [2025-05-16T21:24:08.150Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-16T21:24:08.150Z] GC before operation: completed in 88.694 ms, heap usage 367.641 MB -> 71.137 MB. [2025-05-16T21:24:10.101Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1554.826 ms) ====== [2025-05-16T21:24:10.101Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-16T21:24:10.101Z] GC before operation: completed in 100.847 ms, heap usage 184.881 MB -> 70.834 MB. [2025-05-16T21:24:12.053Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1598.787 ms) ====== [2025-05-16T21:24:12.053Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-16T21:24:12.053Z] GC before operation: completed in 92.599 ms, heap usage 123.441 MB -> 70.647 MB. [2025-05-16T21:24:13.005Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1656.850 ms) ====== [2025-05-16T21:24:13.005Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-16T21:24:13.959Z] GC before operation: completed in 112.666 ms, heap usage 311.728 MB -> 71.498 MB. [2025-05-16T21:24:14.914Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1616.480 ms) ====== [2025-05-16T21:24:14.914Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-16T21:24:14.914Z] GC before operation: completed in 105.465 ms, heap usage 112.794 MB -> 71.270 MB. [2025-05-16T21:24:16.867Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1592.278 ms) ====== [2025-05-16T21:24:16.867Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-16T21:24:16.867Z] GC before operation: completed in 113.552 ms, heap usage 268.707 MB -> 71.821 MB. [2025-05-16T21:24:18.819Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1582.295 ms) ====== [2025-05-16T21:24:18.819Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-16T21:24:18.819Z] GC before operation: completed in 119.031 ms, heap usage 114.917 MB -> 71.143 MB. [2025-05-16T21:24:20.772Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1577.551 ms) ====== [2025-05-16T21:24:20.772Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-16T21:24:20.772Z] GC before operation: completed in 133.706 ms, heap usage 251.289 MB -> 71.879 MB. [2025-05-16T21:24:21.728Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1611.360 ms) ====== [2025-05-16T21:24:21.728Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-16T21:24:21.728Z] GC before operation: completed in 133.784 ms, heap usage 115.410 MB -> 82.831 MB. [2025-05-16T21:24:23.894Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1635.122 ms) ====== [2025-05-16T21:24:23.894Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-16T21:24:23.894Z] GC before operation: completed in 113.663 ms, heap usage 496.959 MB -> 94.354 MB. [2025-05-16T21:24:25.849Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1649.610 ms) ====== [2025-05-16T21:24:25.849Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-16T21:24:25.849Z] GC before operation: completed in 123.707 ms, heap usage 445.759 MB -> 83.107 MB. [2025-05-16T21:24:28.393Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1631.733 ms) ====== [2025-05-16T21:24:28.393Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-16T21:24:28.393Z] GC before operation: completed in 123.984 ms, heap usage 250.520 MB -> 89.010 MB. [2025-05-16T21:24:29.352Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1695.604 ms) ====== [2025-05-16T21:24:29.352Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-16T21:24:29.352Z] GC before operation: completed in 126.784 ms, heap usage 194.808 MB -> 83.184 MB. [2025-05-16T21:24:31.300Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1664.603 ms) ====== [2025-05-16T21:24:31.300Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-16T21:24:31.300Z] GC before operation: completed in 122.808 ms, heap usage 203.978 MB -> 72.254 MB. [2025-05-16T21:24:32.249Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1580.180 ms) ====== [2025-05-16T21:24:32.249Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-16T21:24:33.257Z] GC before operation: completed in 143.253 ms, heap usage 400.134 MB -> 72.693 MB. [2025-05-16T21:24:34.226Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1562.306 ms) ====== [2025-05-16T21:24:34.226Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-16T21:24:34.226Z] GC before operation: completed in 119.899 ms, heap usage 402.708 MB -> 72.966 MB. [2025-05-16T21:24:36.176Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1621.330 ms) ====== [2025-05-16T21:24:36.176Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-16T21:24:36.176Z] GC before operation: completed in 134.573 ms, heap usage 403.966 MB -> 84.246 MB. [2025-05-16T21:24:38.125Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1643.172 ms) ====== [2025-05-16T21:24:38.125Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-16T21:24:38.125Z] GC before operation: completed in 117.154 ms, heap usage 243.741 MB -> 84.321 MB. [2025-05-16T21:24:40.074Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1576.028 ms) ====== [2025-05-16T21:24:40.074Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-16T21:24:40.074Z] GC before operation: completed in 116.126 ms, heap usage 250.946 MB -> 73.134 MB. [2025-05-16T21:24:41.023Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1594.414 ms) ====== [2025-05-16T21:24:41.023Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-16T21:24:41.023Z] GC before operation: completed in 129.932 ms, heap usage 414.860 MB -> 73.420 MB. [2025-05-16T21:24:42.972Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1520.303 ms) ====== [2025-05-16T21:24:42.972Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-16T21:24:42.972Z] GC before operation: completed in 119.087 ms, heap usage 114.930 MB -> 89.478 MB. [2025-05-16T21:24:42.972Z] 21:24:42.475 WARN [block-manager-storage-async-thread-pool-27] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429, which does not exist [2025-05-16T21:24:42.972Z] 21:24:42.479 WARN [block-manager-storage-async-thread-pool-27] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429_piece0, which does not exist [2025-05-16T21:24:44.927Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1584.563 ms) ====== [2025-05-16T21:24:44.927Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-16T21:24:44.927Z] GC before operation: completed in 113.417 ms, heap usage 417.030 MB -> 73.594 MB. [2025-05-16T21:24:45.877Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1566.030 ms) ====== [2025-05-16T21:24:45.877Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-16T21:24:46.998Z] GC before operation: completed in 112.676 ms, heap usage 403.357 MB -> 73.732 MB. [2025-05-16T21:24:47.949Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1534.906 ms) ====== [2025-05-16T21:24:47.949Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-16T21:24:47.949Z] GC before operation: completed in 115.406 ms, heap usage 107.579 MB -> 73.184 MB. [2025-05-16T21:24:49.905Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1557.558 ms) ====== [2025-05-16T21:24:49.905Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-16T21:24:49.905Z] GC before operation: completed in 119.007 ms, heap usage 148.031 MB -> 73.489 MB. [2025-05-16T21:24:50.998Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1545.408 ms) ====== [2025-05-16T21:24:50.998Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-16T21:24:50.998Z] GC before operation: completed in 126.420 ms, heap usage 519.052 MB -> 85.302 MB. [2025-05-16T21:24:52.951Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1591.905 ms) ====== [2025-05-16T21:24:52.951Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-16T21:24:52.951Z] GC before operation: completed in 127.564 ms, heap usage 245.060 MB -> 74.021 MB. [2025-05-16T21:24:54.908Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1595.276 ms) ====== [2025-05-16T21:24:54.909Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-16T21:24:54.909Z] GC before operation: completed in 127.248 ms, heap usage 400.025 MB -> 74.293 MB. [2025-05-16T21:24:56.862Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1546.883 ms) ====== [2025-05-16T21:24:56.862Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-16T21:24:56.862Z] GC before operation: completed in 127.270 ms, heap usage 389.189 MB -> 85.529 MB. [2025-05-16T21:24:57.816Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1663.072 ms) ====== [2025-05-16T21:24:58.766Z] ----------------------------------- [2025-05-16T21:24:58.766Z] renaissance-dec-tree_0_PASSED [2025-05-16T21:24:58.766Z] ----------------------------------- [2025-05-16T21:24:58.766Z] [2025-05-16T21:24:58.766Z] TEST TEARDOWN: [2025-05-16T21:24:58.766Z] Nothing to be done for teardown. [2025-05-16T21:24:58.766Z] renaissance-dec-tree_0 Finish Time: Fri May 16 21:24:57 2025 Epoch Time (ms): 1747430697899