[2025-06-26T23:13:20.628Z] Running test renaissance-dec-tree_0 ... [2025-06-26T23:13:20.628Z] =============================================== [2025-06-26T23:13:20.628Z] renaissance-dec-tree_0 Start Time: Thu Jun 26 23:13:20 2025 Epoch Time (ms): 1750979600167 [2025-06-26T23:13:20.628Z] variation: NoOptions [2025-06-26T23:13:20.628Z] JVM_OPTIONS: [2025-06-26T23:13:20.628Z] { \ [2025-06-26T23:13:20.628Z] echo ""; echo "TEST SETUP:"; \ [2025-06-26T23:13:20.628Z] echo "Nothing to be done for setup."; \ [2025-06-26T23:13:20.628Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17509790757672/renaissance-dec-tree_0"; \ [2025-06-26T23:13:20.628Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17509790757672/renaissance-dec-tree_0"; \ [2025-06-26T23:13:20.628Z] echo ""; echo "TESTING:"; \ [2025-06-26T23:13:20.628Z] "/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_17509790757672/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-26T23:13:20.628Z] 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_17509790757672/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-26T23:13:20.628Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-26T23:13:20.628Z] echo "Nothing to be done for teardown."; \ [2025-06-26T23:13:20.628Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17509790757672/TestTargetResult"; [2025-06-26T23:13:20.628Z] [2025-06-26T23:13:20.628Z] TEST SETUP: [2025-06-26T23:13:20.628Z] Nothing to be done for setup. [2025-06-26T23:13:20.628Z] [2025-06-26T23:13:20.628Z] TESTING: [2025-06-26T23:13:26.040Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-26T23:13:27.995Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-26T23:13:27.995Z] GC before operation: completed in 57.664 ms, heap usage 53.275 MB -> 35.087 MB. [2025-06-26T23:13:37.757Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8643.540 ms) ====== [2025-06-26T23:13:37.757Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-26T23:13:37.757Z] GC before operation: completed in 106.323 ms, heap usage 121.026 MB -> 68.587 MB. [2025-06-26T23:13:40.099Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2399.825 ms) ====== [2025-06-26T23:13:40.099Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-26T23:13:40.099Z] GC before operation: completed in 122.373 ms, heap usage 442.859 MB -> 86.252 MB. [2025-06-26T23:13:41.050Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1797.577 ms) ====== [2025-06-26T23:13:41.050Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-26T23:13:41.050Z] GC before operation: completed in 94.429 ms, heap usage 292.324 MB -> 69.937 MB. [2025-06-26T23:13:43.030Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1770.953 ms) ====== [2025-06-26T23:13:43.031Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-26T23:13:43.031Z] GC before operation: completed in 115.242 ms, heap usage 219.819 MB -> 70.036 MB. [2025-06-26T23:13:44.985Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1659.084 ms) ====== [2025-06-26T23:13:44.985Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-26T23:13:44.985Z] GC before operation: completed in 105.265 ms, heap usage 123.989 MB -> 69.757 MB. [2025-06-26T23:13:46.938Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1650.848 ms) ====== [2025-06-26T23:13:46.938Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-26T23:13:46.938Z] GC before operation: completed in 89.500 ms, heap usage 319.179 MB -> 70.477 MB. [2025-06-26T23:13:47.890Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1640.521 ms) ====== [2025-06-26T23:13:47.890Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-26T23:13:48.843Z] GC before operation: completed in 92.963 ms, heap usage 211.671 MB -> 71.064 MB. [2025-06-26T23:13:49.811Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1568.606 ms) ====== [2025-06-26T23:13:49.811Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-26T23:13:49.811Z] GC before operation: completed in 100.364 ms, heap usage 406.019 MB -> 93.018 MB. [2025-06-26T23:13:51.765Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1601.098 ms) ====== [2025-06-26T23:13:51.765Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-26T23:13:51.765Z] GC before operation: completed in 107.307 ms, heap usage 132.704 MB -> 70.741 MB. [2025-06-26T23:13:53.720Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1627.142 ms) ====== [2025-06-26T23:13:53.720Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-26T23:13:53.720Z] GC before operation: completed in 97.734 ms, heap usage 232.904 MB -> 76.478 MB. [2025-06-26T23:13:54.674Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1561.531 ms) ====== [2025-06-26T23:13:54.674Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-26T23:13:55.627Z] GC before operation: completed in 106.179 ms, heap usage 360.178 MB -> 87.733 MB. [2025-06-26T23:13:56.580Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1542.671 ms) ====== [2025-06-26T23:13:56.580Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-26T23:13:56.580Z] GC before operation: completed in 112.652 ms, heap usage 202.381 MB -> 71.376 MB. [2025-06-26T23:13:58.538Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1543.210 ms) ====== [2025-06-26T23:13:58.538Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-26T23:13:58.538Z] GC before operation: completed in 110.794 ms, heap usage 274.965 MB -> 82.375 MB. [2025-06-26T23:14:00.520Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1523.343 ms) ====== [2025-06-26T23:14:00.520Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-26T23:14:00.520Z] GC before operation: completed in 120.376 ms, heap usage 233.063 MB -> 82.886 MB. [2025-06-26T23:14:01.472Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1493.233 ms) ====== [2025-06-26T23:14:01.472Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-26T23:14:01.472Z] GC before operation: completed in 107.351 ms, heap usage 233.386 MB -> 88.579 MB. [2025-06-26T23:14:03.426Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1490.384 ms) ====== [2025-06-26T23:14:03.426Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-26T23:14:03.426Z] GC before operation: completed in 101.957 ms, heap usage 125.073 MB -> 71.236 MB. [2025-06-26T23:14:05.381Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1533.664 ms) ====== [2025-06-26T23:14:05.381Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-26T23:14:05.381Z] GC before operation: completed in 105.460 ms, heap usage 184.011 MB -> 71.706 MB. [2025-06-26T23:14:06.338Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1511.544 ms) ====== [2025-06-26T23:14:06.338Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-26T23:14:06.338Z] GC before operation: completed in 107.561 ms, heap usage 378.470 MB -> 71.978 MB. [2025-06-26T23:14:08.293Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1492.775 ms) ====== [2025-06-26T23:14:08.293Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-26T23:14:08.293Z] GC before operation: completed in 103.643 ms, heap usage 113.310 MB -> 71.484 MB. [2025-06-26T23:14:09.246Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1485.631 ms) ====== [2025-06-26T23:14:09.246Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-26T23:14:10.198Z] GC before operation: completed in 103.826 ms, heap usage 245.592 MB -> 83.350 MB. [2025-06-26T23:14:11.149Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1517.826 ms) ====== [2025-06-26T23:14:11.149Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-26T23:14:11.149Z] GC before operation: completed in 119.526 ms, heap usage 233.965 MB -> 83.328 MB. [2025-06-26T23:14:13.117Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1583.419 ms) ====== [2025-06-26T23:14:13.117Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-26T23:14:13.117Z] GC before operation: completed in 117.899 ms, heap usage 367.659 MB -> 89.111 MB. [2025-06-26T23:14:15.073Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1520.821 ms) ====== [2025-06-26T23:14:15.073Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-26T23:14:15.073Z] GC before operation: completed in 117.625 ms, heap usage 345.678 MB -> 72.717 MB. [2025-06-26T23:14:16.025Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1493.111 ms) ====== [2025-06-26T23:14:16.025Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-26T23:14:16.025Z] GC before operation: completed in 115.767 ms, heap usage 262.836 MB -> 83.614 MB. [2025-06-26T23:14:17.980Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1491.009 ms) ====== [2025-06-26T23:14:17.980Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-26T23:14:17.980Z] GC before operation: completed in 114.976 ms, heap usage 209.221 MB -> 78.069 MB. [2025-06-26T23:14:19.931Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1490.129 ms) ====== [2025-06-26T23:14:19.931Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-26T23:14:19.931Z] GC before operation: completed in 113.349 ms, heap usage 313.166 MB -> 73.333 MB. [2025-06-26T23:14:23.375Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1471.911 ms) ====== [2025-06-26T23:14:23.375Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-26T23:14:23.375Z] GC before operation: completed in 120.271 ms, heap usage 393.465 MB -> 84.143 MB. [2025-06-26T23:14:24.328Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1526.682 ms) ====== [2025-06-26T23:14:24.328Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-26T23:14:24.328Z] GC before operation: completed in 125.997 ms, heap usage 205.739 MB -> 84.038 MB. [2025-06-26T23:14:24.328Z] 23:14:22.268 WARN [block-manager-storage-async-thread-pool-6] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_387_piece0, which does not exist [2025-06-26T23:14:24.328Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1466.796 ms) ====== [2025-06-26T23:14:24.328Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-26T23:14:24.328Z] GC before operation: completed in 134.039 ms, heap usage 193.650 MB -> 83.980 MB. [2025-06-26T23:14:26.283Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1522.210 ms) ====== [2025-06-26T23:14:26.283Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-26T23:14:26.283Z] GC before operation: completed in 133.951 ms, heap usage 521.351 MB -> 90.058 MB. [2025-06-26T23:14:27.234Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1450.933 ms) ====== [2025-06-26T23:14:27.234Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-26T23:14:27.234Z] GC before operation: completed in 134.987 ms, heap usage 354.565 MB -> 79.028 MB. [2025-06-26T23:14:29.188Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1498.868 ms) ====== [2025-06-26T23:14:29.188Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-26T23:14:29.188Z] GC before operation: completed in 112.201 ms, heap usage 152.609 MB -> 73.252 MB. [2025-06-26T23:14:31.148Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1461.859 ms) ====== [2025-06-26T23:14:31.148Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-26T23:14:31.148Z] GC before operation: completed in 123.068 ms, heap usage 296.843 MB -> 73.656 MB. [2025-06-26T23:14:32.135Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1471.843 ms) ====== [2025-06-26T23:14:32.135Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-26T23:14:32.135Z] GC before operation: completed in 121.006 ms, heap usage 380.776 MB -> 73.921 MB. [2025-06-26T23:14:34.302Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1460.038 ms) ====== [2025-06-26T23:14:34.302Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-26T23:14:34.302Z] GC before operation: completed in 118.495 ms, heap usage 369.419 MB -> 90.633 MB. [2025-06-26T23:14:35.261Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1469.623 ms) ====== [2025-06-26T23:14:35.261Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-26T23:14:35.261Z] GC before operation: completed in 118.741 ms, heap usage 321.688 MB -> 85.177 MB. [2025-06-26T23:14:37.417Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1464.744 ms) ====== [2025-06-26T23:14:37.417Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-26T23:14:37.417Z] GC before operation: completed in 118.913 ms, heap usage 352.759 MB -> 74.306 MB. [2025-06-26T23:14:40.287Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1444.840 ms) ====== [2025-06-26T23:14:40.287Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-26T23:14:40.287Z] GC before operation: completed in 131.004 ms, heap usage 233.907 MB -> 85.142 MB. [2025-06-26T23:14:40.287Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1422.985 ms) ====== [2025-06-26T23:14:40.287Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-26T23:14:40.287Z] GC before operation: completed in 124.065 ms, heap usage 162.277 MB -> 74.143 MB. [2025-06-26T23:14:42.242Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1445.875 ms) ====== [2025-06-26T23:14:42.242Z] ----------------------------------- [2025-06-26T23:14:42.242Z] renaissance-dec-tree_0_PASSED [2025-06-26T23:14:42.242Z] ----------------------------------- [2025-06-26T23:14:42.242Z] [2025-06-26T23:14:42.242Z] TEST TEARDOWN: [2025-06-26T23:14:42.242Z] Nothing to be done for teardown. [2025-06-26T23:14:42.242Z] renaissance-dec-tree_0 Finish Time: Thu Jun 26 23:14:41 2025 Epoch Time (ms): 1750979681462