[2025-12-24T22:12:51.862Z] Running test renaissance-dec-tree_0 ... [2025-12-24T22:12:51.862Z] =============================================== [2025-12-24T22:12:51.862Z] renaissance-dec-tree_0 Start Time: Wed Dec 24 22:12:51 2025 Epoch Time (ms): 1766614371207 [2025-12-24T22:12:51.862Z] variation: NoOptions [2025-12-24T22:12:51.862Z] JVM_OPTIONS: [2025-12-24T22:12:51.862Z] { \ [2025-12-24T22:12:51.862Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T22:12:51.862Z] echo "Nothing to be done for setup."; \ [2025-12-24T22:12:51.862Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-dec-tree_0"; \ [2025-12-24T22:12:51.862Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-dec-tree_0"; \ [2025-12-24T22:12:51.862Z] echo ""; echo "TESTING:"; \ [2025-12-24T22:12:51.862Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-12-24T22:12:51.862Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T22:12:51.862Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T22:12:51.862Z] echo "Nothing to be done for teardown."; \ [2025-12-24T22:12:51.862Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17666138172698/TestTargetResult"; [2025-12-24T22:12:51.862Z] [2025-12-24T22:12:51.862Z] TEST SETUP: [2025-12-24T22:12:51.862Z] Nothing to be done for setup. [2025-12-24T22:12:51.862Z] [2025-12-24T22:12:51.862Z] TESTING: [2025-12-24T22:12:57.380Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-24T22:12:59.347Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-12-24T22:12:59.347Z] GC before operation: completed in 50.858 ms, heap usage 50.956 MB -> 35.070 MB. [2025-12-24T22:13:07.562Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8465.085 ms) ====== [2025-12-24T22:13:07.562Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-12-24T22:13:07.562Z] GC before operation: completed in 107.797 ms, heap usage 320.633 MB -> 69.335 MB. [2025-12-24T22:13:09.596Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2164.767 ms) ====== [2025-12-24T22:13:09.596Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-12-24T22:13:09.596Z] GC before operation: completed in 139.193 ms, heap usage 364.094 MB -> 86.226 MB. [2025-12-24T22:13:11.563Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1739.724 ms) ====== [2025-12-24T22:13:11.563Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-12-24T22:13:11.563Z] GC before operation: completed in 99.560 ms, heap usage 380.281 MB -> 69.976 MB. [2025-12-24T22:13:13.532Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1695.659 ms) ====== [2025-12-24T22:13:13.532Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-12-24T22:13:13.532Z] GC before operation: completed in 84.654 ms, heap usage 351.794 MB -> 70.212 MB. [2025-12-24T22:13:15.428Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1636.004 ms) ====== [2025-12-24T22:13:15.428Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-12-24T22:13:15.428Z] GC before operation: completed in 95.407 ms, heap usage 216.643 MB -> 70.483 MB. [2025-12-24T22:13:17.390Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1689.798 ms) ====== [2025-12-24T22:13:17.390Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-12-24T22:13:17.390Z] GC before operation: completed in 107.416 ms, heap usage 109.231 MB -> 70.064 MB. [2025-12-24T22:13:18.350Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1588.084 ms) ====== [2025-12-24T22:13:18.350Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-12-24T22:13:18.350Z] GC before operation: completed in 92.483 ms, heap usage 223.669 MB -> 70.908 MB. [2025-12-24T22:13:20.343Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1552.431 ms) ====== [2025-12-24T22:13:20.343Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-12-24T22:13:20.343Z] GC before operation: completed in 94.524 ms, heap usage 108.255 MB -> 70.461 MB. [2025-12-24T22:13:22.307Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1511.497 ms) ====== [2025-12-24T22:13:22.307Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-12-24T22:13:22.307Z] GC before operation: completed in 90.723 ms, heap usage 192.836 MB -> 70.751 MB. [2025-12-24T22:13:23.262Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1477.959 ms) ====== [2025-12-24T22:13:23.262Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-12-24T22:13:23.262Z] GC before operation: completed in 94.566 ms, heap usage 393.861 MB -> 76.707 MB. [2025-12-24T22:13:25.234Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1535.161 ms) ====== [2025-12-24T22:13:25.235Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-12-24T22:13:25.235Z] GC before operation: completed in 94.366 ms, heap usage 111.343 MB -> 70.663 MB. [2025-12-24T22:13:26.200Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1523.117 ms) ====== [2025-12-24T22:13:26.200Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-12-24T22:13:27.164Z] GC before operation: completed in 99.379 ms, heap usage 162.573 MB -> 71.451 MB. [2025-12-24T22:13:28.121Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1519.341 ms) ====== [2025-12-24T22:13:28.121Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-12-24T22:13:28.121Z] GC before operation: completed in 120.170 ms, heap usage 346.942 MB -> 82.837 MB. [2025-12-24T22:13:30.088Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1475.083 ms) ====== [2025-12-24T22:13:30.088Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-12-24T22:13:30.088Z] GC before operation: completed in 108.194 ms, heap usage 223.019 MB -> 82.882 MB. [2025-12-24T22:13:31.049Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1463.358 ms) ====== [2025-12-24T22:13:31.049Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-12-24T22:13:32.006Z] GC before operation: completed in 102.190 ms, heap usage 221.870 MB -> 71.911 MB. [2025-12-24T22:13:32.966Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1491.612 ms) ====== [2025-12-24T22:13:32.966Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-12-24T22:13:32.966Z] GC before operation: completed in 109.662 ms, heap usage 393.635 MB -> 71.928 MB. [2025-12-24T22:13:34.931Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1451.363 ms) ====== [2025-12-24T22:13:34.931Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-12-24T22:13:34.931Z] GC before operation: completed in 108.534 ms, heap usage 394.053 MB -> 72.180 MB. [2025-12-24T22:13:35.918Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1432.079 ms) ====== [2025-12-24T22:13:35.918Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-12-24T22:13:35.918Z] GC before operation: completed in 108.686 ms, heap usage 394.828 MB -> 72.319 MB. [2025-12-24T22:13:37.884Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1440.320 ms) ====== [2025-12-24T22:13:37.884Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-12-24T22:13:37.884Z] GC before operation: completed in 103.446 ms, heap usage 107.707 MB -> 71.760 MB. [2025-12-24T22:13:39.015Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1490.649 ms) ====== [2025-12-24T22:13:39.015Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-12-24T22:13:39.015Z] GC before operation: completed in 120.218 ms, heap usage 118.088 MB -> 71.754 MB. [2025-12-24T22:13:40.980Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1468.160 ms) ====== [2025-12-24T22:13:40.980Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-12-24T22:13:40.980Z] GC before operation: completed in 112.961 ms, heap usage 232.839 MB -> 72.459 MB. [2025-12-24T22:13:42.947Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1469.805 ms) ====== [2025-12-24T22:13:42.947Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-12-24T22:13:42.947Z] GC before operation: completed in 117.318 ms, heap usage 382.569 MB -> 72.588 MB. [2025-12-24T22:13:44.606Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1484.299 ms) ====== [2025-12-24T22:13:44.606Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-12-24T22:13:44.606Z] GC before operation: completed in 125.273 ms, heap usage 394.179 MB -> 72.704 MB. [2025-12-24T22:13:45.562Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1427.358 ms) ====== [2025-12-24T22:13:45.562Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-12-24T22:13:45.562Z] GC before operation: completed in 107.928 ms, heap usage 394.269 MB -> 72.952 MB. [2025-12-24T22:13:47.531Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1455.937 ms) ====== [2025-12-24T22:13:47.531Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-12-24T22:13:47.531Z] GC before operation: completed in 115.253 ms, heap usage 382.207 MB -> 78.356 MB. [2025-12-24T22:13:48.489Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1463.371 ms) ====== [2025-12-24T22:13:48.489Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-12-24T22:13:48.489Z] GC before operation: completed in 117.399 ms, heap usage 316.973 MB -> 73.137 MB. [2025-12-24T22:13:50.454Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1460.943 ms) ====== [2025-12-24T22:13:50.454Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-12-24T22:13:50.454Z] GC before operation: completed in 117.492 ms, heap usage 395.461 MB -> 73.125 MB. [2025-12-24T22:13:52.417Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1423.963 ms) ====== [2025-12-24T22:13:52.417Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-12-24T22:13:52.417Z] GC before operation: completed in 114.672 ms, heap usage 382.206 MB -> 73.251 MB. [2025-12-24T22:13:53.376Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1427.813 ms) ====== [2025-12-24T22:13:53.376Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-12-24T22:13:53.376Z] GC before operation: completed in 110.860 ms, heap usage 375.461 MB -> 73.368 MB. [2025-12-24T22:13:55.339Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1413.342 ms) ====== [2025-12-24T22:13:55.339Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-12-24T22:13:55.339Z] GC before operation: completed in 118.764 ms, heap usage 396.942 MB -> 73.844 MB. [2025-12-24T22:13:56.295Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1400.351 ms) ====== [2025-12-24T22:13:56.295Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-12-24T22:13:56.295Z] GC before operation: completed in 109.005 ms, heap usage 395.928 MB -> 73.581 MB. [2025-12-24T22:13:58.284Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1419.164 ms) ====== [2025-12-24T22:13:58.284Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-12-24T22:13:58.284Z] GC before operation: completed in 109.792 ms, heap usage 393.017 MB -> 73.701 MB. [2025-12-24T22:13:59.241Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1414.228 ms) ====== [2025-12-24T22:13:59.241Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-12-24T22:13:59.241Z] GC before operation: completed in 116.513 ms, heap usage 379.748 MB -> 90.514 MB. [2025-12-24T22:13:59.241Z] 22:13:59.083 WARN [block-manager-storage-async-thread-pool-51] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_457_piece0, which does not exist [2025-12-24T22:14:01.221Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1409.856 ms) ====== [2025-12-24T22:14:01.221Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-12-24T22:14:01.221Z] GC before operation: completed in 113.063 ms, heap usage 465.472 MB -> 73.953 MB. [2025-12-24T22:14:02.178Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1400.112 ms) ====== [2025-12-24T22:14:02.178Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-12-24T22:14:03.136Z] GC before operation: completed in 118.515 ms, heap usage 396.899 MB -> 90.760 MB. [2025-12-24T22:14:04.093Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1415.381 ms) ====== [2025-12-24T22:14:04.093Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-12-24T22:14:04.093Z] GC before operation: completed in 127.379 ms, heap usage 456.041 MB -> 96.317 MB. [2025-12-24T22:14:06.060Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1433.918 ms) ====== [2025-12-24T22:14:06.060Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-12-24T22:14:06.060Z] GC before operation: completed in 139.023 ms, heap usage 433.816 MB -> 90.966 MB. [2025-12-24T22:14:07.736Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1408.335 ms) ====== [2025-12-24T22:14:07.736Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-12-24T22:14:07.736Z] GC before operation: completed in 134.441 ms, heap usage 464.567 MB -> 85.391 MB. [2025-12-24T22:14:08.693Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1414.099 ms) ====== [2025-12-24T22:14:08.693Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-12-24T22:14:08.693Z] GC before operation: completed in 151.852 ms, heap usage 346.955 MB -> 85.738 MB. [2025-12-24T22:14:10.722Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1453.693 ms) ====== [2025-12-24T22:14:10.722Z] ----------------------------------- [2025-12-24T22:14:10.722Z] renaissance-dec-tree_0_PASSED [2025-12-24T22:14:10.722Z] ----------------------------------- [2025-12-24T22:14:10.722Z] [2025-12-24T22:14:10.722Z] TEST TEARDOWN: [2025-12-24T22:14:10.722Z] Nothing to be done for teardown. [2025-12-24T22:14:10.722Z] renaissance-dec-tree_0 Finish Time: Wed Dec 24 22:14:10 2025 Epoch Time (ms): 1766614450043