[2025-05-01T12:17:57.613Z] Running test renaissance-dec-tree_0 ... [2025-05-01T12:17:57.613Z] =============================================== [2025-05-01T12:17:57.613Z] renaissance-dec-tree_0 Start Time: Thu May 1 12:17:56 2025 Epoch Time (ms): 1746101876863 [2025-05-01T12:17:57.613Z] variation: NoOptions [2025-05-01T12:17:57.613Z] JVM_OPTIONS: [2025-05-01T12:17:57.613Z] { \ [2025-05-01T12:17:57.613Z] echo ""; echo "TEST SETUP:"; \ [2025-05-01T12:17:57.613Z] echo "Nothing to be done for setup."; \ [2025-05-01T12:17:57.613Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17461014681792/renaissance-dec-tree_0"; \ [2025-05-01T12:17:57.613Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17461014681792/renaissance-dec-tree_0"; \ [2025-05-01T12:17:57.613Z] echo ""; echo "TESTING:"; \ [2025-05-01T12:17:57.614Z] "/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_17461014681792/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-01T12:17:57.614Z] 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_17461014681792/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-01T12:17:57.614Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-01T12:17:57.614Z] echo "Nothing to be done for teardown."; \ [2025-05-01T12:17:57.614Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17461014681792/TestTargetResult"; [2025-05-01T12:17:57.614Z] [2025-05-01T12:17:57.614Z] TEST SETUP: [2025-05-01T12:17:57.614Z] Nothing to be done for setup. [2025-05-01T12:17:57.614Z] [2025-05-01T12:17:57.614Z] TESTING: [2025-05-01T12:18:02.996Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-01T12:18:04.962Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-01T12:18:04.962Z] GC before operation: completed in 91.192 ms, heap usage 65.818 MB -> 34.907 MB. [2025-05-01T12:18:13.155Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8552.737 ms) ====== [2025-05-01T12:18:13.155Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-01T12:18:13.155Z] GC before operation: completed in 100.547 ms, heap usage 364.700 MB -> 69.103 MB. [2025-05-01T12:18:16.188Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2244.983 ms) ====== [2025-05-01T12:18:16.188Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-01T12:18:16.188Z] GC before operation: completed in 107.153 ms, heap usage 313.464 MB -> 69.490 MB. [2025-05-01T12:18:17.142Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1856.006 ms) ====== [2025-05-01T12:18:17.142Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-01T12:18:18.097Z] GC before operation: completed in 77.219 ms, heap usage 210.149 MB -> 69.759 MB. [2025-05-01T12:18:19.052Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1824.466 ms) ====== [2025-05-01T12:18:19.052Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-01T12:18:20.006Z] GC before operation: completed in 92.160 ms, heap usage 255.438 MB -> 69.903 MB. [2025-05-01T12:18:20.960Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1785.514 ms) ====== [2025-05-01T12:18:20.960Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-01T12:18:21.914Z] GC before operation: completed in 88.282 ms, heap usage 186.073 MB -> 69.970 MB. [2025-05-01T12:18:22.869Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1705.963 ms) ====== [2025-05-01T12:18:22.869Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-01T12:18:22.869Z] GC before operation: completed in 96.871 ms, heap usage 216.110 MB -> 70.482 MB. [2025-05-01T12:18:24.833Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1678.454 ms) ====== [2025-05-01T12:18:24.833Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-01T12:18:24.833Z] GC before operation: completed in 102.926 ms, heap usage 161.256 MB -> 70.368 MB. [2025-05-01T12:18:26.802Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1683.940 ms) ====== [2025-05-01T12:18:26.803Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-01T12:18:26.803Z] GC before operation: completed in 96.388 ms, heap usage 413.888 MB -> 70.863 MB. [2025-05-01T12:18:28.764Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1666.182 ms) ====== [2025-05-01T12:18:28.764Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-01T12:18:28.764Z] GC before operation: completed in 109.388 ms, heap usage 212.192 MB -> 70.757 MB. [2025-05-01T12:18:29.849Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1653.622 ms) ====== [2025-05-01T12:18:29.849Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-01T12:18:30.810Z] GC before operation: completed in 111.884 ms, heap usage 158.544 MB -> 70.597 MB. [2025-05-01T12:18:31.765Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1682.556 ms) ====== [2025-05-01T12:18:31.765Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-01T12:18:31.765Z] GC before operation: completed in 121.626 ms, heap usage 399.266 MB -> 76.851 MB. [2025-05-01T12:18:33.900Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1651.205 ms) ====== [2025-05-01T12:18:33.900Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-01T12:18:33.900Z] GC before operation: completed in 106.468 ms, heap usage 370.797 MB -> 81.997 MB. [2025-05-01T12:18:35.859Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1619.969 ms) ====== [2025-05-01T12:18:35.859Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-01T12:18:35.859Z] GC before operation: completed in 99.092 ms, heap usage 291.606 MB -> 71.447 MB. [2025-05-01T12:18:36.822Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1644.126 ms) ====== [2025-05-01T12:18:36.822Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-01T12:18:37.777Z] GC before operation: completed in 101.664 ms, heap usage 159.631 MB -> 98.865 MB. [2025-05-01T12:18:38.731Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1698.887 ms) ====== [2025-05-01T12:18:38.731Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-01T12:18:38.731Z] GC before operation: completed in 95.044 ms, heap usage 441.212 MB -> 82.777 MB. [2025-05-01T12:18:40.694Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1553.708 ms) ====== [2025-05-01T12:18:40.694Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-01T12:18:40.694Z] GC before operation: completed in 95.959 ms, heap usage 283.935 MB -> 71.833 MB. [2025-05-01T12:18:42.660Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1565.016 ms) ====== [2025-05-01T12:18:42.660Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-01T12:18:42.660Z] GC before operation: completed in 95.050 ms, heap usage 110.449 MB -> 71.359 MB. [2025-05-01T12:18:43.614Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1578.545 ms) ====== [2025-05-01T12:18:43.614Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-01T12:18:44.570Z] GC before operation: completed in 111.905 ms, heap usage 258.071 MB -> 72.052 MB. [2025-05-01T12:18:45.527Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1530.972 ms) ====== [2025-05-01T12:18:45.527Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-01T12:18:45.527Z] GC before operation: completed in 98.353 ms, heap usage 124.469 MB -> 82.698 MB. [2025-05-01T12:18:47.500Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1558.344 ms) ====== [2025-05-01T12:18:47.500Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-01T12:18:47.500Z] GC before operation: completed in 116.075 ms, heap usage 385.974 MB -> 83.400 MB. [2025-05-01T12:18:49.467Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1559.147 ms) ====== [2025-05-01T12:18:49.467Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-01T12:18:49.467Z] GC before operation: completed in 102.861 ms, heap usage 275.684 MB -> 83.506 MB. [2025-05-01T12:18:50.423Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1542.784 ms) ====== [2025-05-01T12:18:50.423Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-01T12:18:50.423Z] GC before operation: completed in 129.154 ms, heap usage 270.487 MB -> 89.049 MB. [2025-05-01T12:18:52.381Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1518.442 ms) ====== [2025-05-01T12:18:52.382Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-01T12:18:52.382Z] GC before operation: completed in 110.745 ms, heap usage 192.306 MB -> 83.419 MB. [2025-05-01T12:18:54.342Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1506.928 ms) ====== [2025-05-01T12:18:54.342Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-01T12:18:54.342Z] GC before operation: completed in 103.037 ms, heap usage 223.025 MB -> 72.918 MB. [2025-05-01T12:18:55.296Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1535.785 ms) ====== [2025-05-01T12:18:55.296Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-01T12:18:55.296Z] GC before operation: completed in 121.167 ms, heap usage 302.923 MB -> 72.703 MB. [2025-05-01T12:18:57.257Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1583.289 ms) ====== [2025-05-01T12:18:57.257Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-01T12:18:57.257Z] GC before operation: completed in 119.999 ms, heap usage 123.120 MB -> 83.506 MB. [2025-05-01T12:18:59.218Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1541.278 ms) ====== [2025-05-01T12:18:59.218Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-01T12:18:59.218Z] GC before operation: completed in 118.689 ms, heap usage 551.514 MB -> 84.187 MB. [2025-05-01T12:19:00.177Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1546.059 ms) ====== [2025-05-01T12:19:00.177Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-01T12:19:01.131Z] GC before operation: completed in 112.740 ms, heap usage 259.069 MB -> 73.125 MB. [2025-05-01T12:19:02.087Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1566.379 ms) ====== [2025-05-01T12:19:02.087Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-01T12:19:02.087Z] GC before operation: completed in 108.373 ms, heap usage 123.593 MB -> 72.579 MB. [2025-05-01T12:19:04.048Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1521.531 ms) ====== [2025-05-01T12:19:04.048Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-01T12:19:04.048Z] GC before operation: completed in 109.986 ms, heap usage 295.699 MB -> 84.084 MB. [2025-05-01T12:19:05.142Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1488.882 ms) ====== [2025-05-01T12:19:05.142Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-01T12:19:06.095Z] GC before operation: completed in 120.472 ms, heap usage 442.637 MB -> 73.463 MB. [2025-05-01T12:19:07.051Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1508.023 ms) ====== [2025-05-01T12:19:07.051Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-01T12:19:07.051Z] GC before operation: completed in 112.578 ms, heap usage 123.258 MB -> 89.548 MB. [2025-05-01T12:19:07.051Z] 12:19:06.722 WARN [block-manager-storage-async-thread-pool-90] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_443_piece0, which does not exist [2025-05-01T12:19:08.631Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1572.775 ms) ====== [2025-05-01T12:19:08.631Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-01T12:19:09.643Z] GC before operation: completed in 116.756 ms, heap usage 457.034 MB -> 78.937 MB. [2025-05-01T12:19:10.607Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1541.451 ms) ====== [2025-05-01T12:19:10.607Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-01T12:19:10.607Z] GC before operation: completed in 126.557 ms, heap usage 364.943 MB -> 79.057 MB. [2025-05-01T12:19:12.572Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1503.661 ms) ====== [2025-05-01T12:19:12.572Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-01T12:19:12.572Z] GC before operation: completed in 123.335 ms, heap usage 359.890 MB -> 85.064 MB. [2025-05-01T12:19:13.526Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1505.794 ms) ====== [2025-05-01T12:19:13.526Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-01T12:19:13.526Z] GC before operation: completed in 135.990 ms, heap usage 433.530 MB -> 73.866 MB. [2025-05-01T12:19:15.487Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1526.807 ms) ====== [2025-05-01T12:19:15.487Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-01T12:19:15.487Z] GC before operation: completed in 131.752 ms, heap usage 280.350 MB -> 90.658 MB. [2025-05-01T12:19:17.458Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1495.530 ms) ====== [2025-05-01T12:19:17.458Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-01T12:19:17.458Z] GC before operation: completed in 126.586 ms, heap usage 160.783 MB -> 73.898 MB. [2025-05-01T12:19:18.422Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1544.201 ms) ====== [2025-05-01T12:19:18.422Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-01T12:19:18.422Z] GC before operation: completed in 123.178 ms, heap usage 122.424 MB -> 73.718 MB. [2025-05-01T12:19:20.381Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1529.748 ms) ====== [2025-05-01T12:19:20.381Z] ----------------------------------- [2025-05-01T12:19:20.381Z] renaissance-dec-tree_0_PASSED [2025-05-01T12:19:20.381Z] ----------------------------------- [2025-05-01T12:19:20.381Z] [2025-05-01T12:19:20.381Z] TEST TEARDOWN: [2025-05-01T12:19:20.381Z] Nothing to be done for teardown. [2025-05-01T12:19:20.381Z] renaissance-dec-tree_0 Finish Time: Thu May 1 12:19:20 2025 Epoch Time (ms): 1746101960100