[2025-08-30T16:28:02.608Z] Running test renaissance-dec-tree_0 ... [2025-08-30T16:28:02.608Z] =============================================== [2025-08-30T16:28:02.608Z] renaissance-dec-tree_0 Start Time: Sat Aug 30 16:28:02 2025 Epoch Time (ms): 1756571282390 [2025-08-30T16:28:02.608Z] variation: NoOptions [2025-08-30T16:28:02.608Z] JVM_OPTIONS: [2025-08-30T16:28:02.608Z] { \ [2025-08-30T16:28:02.608Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T16:28:02.608Z] echo "Nothing to be done for setup."; \ [2025-08-30T16:28:02.608Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17565707193831/renaissance-dec-tree_0"; \ [2025-08-30T16:28:02.608Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17565707193831/renaissance-dec-tree_0"; \ [2025-08-30T16:28:02.608Z] echo ""; echo "TESTING:"; \ [2025-08-30T16:28:02.608Z] "/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_17565707193831/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-30T16:28:02.608Z] 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_17565707193831/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T16:28:02.608Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T16:28:02.608Z] echo "Nothing to be done for teardown."; \ [2025-08-30T16:28:02.608Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17565707193831/TestTargetResult"; [2025-08-30T16:28:02.608Z] [2025-08-30T16:28:02.608Z] TEST SETUP: [2025-08-30T16:28:02.608Z] Nothing to be done for setup. [2025-08-30T16:28:02.608Z] [2025-08-30T16:28:02.608Z] TESTING: [2025-08-30T16:28:07.963Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-30T16:28:09.911Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-30T16:28:10.873Z] GC before operation: completed in 56.546 ms, heap usage 52.180 MB -> 35.044 MB. [2025-08-30T16:28:19.008Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8685.003 ms) ====== [2025-08-30T16:28:19.008Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-30T16:28:19.008Z] GC before operation: completed in 107.693 ms, heap usage 178.803 MB -> 69.621 MB. [2025-08-30T16:28:20.964Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2206.340 ms) ====== [2025-08-30T16:28:20.964Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-30T16:28:21.915Z] GC before operation: completed in 92.809 ms, heap usage 210.939 MB -> 69.608 MB. [2025-08-30T16:28:22.865Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1753.144 ms) ====== [2025-08-30T16:28:22.865Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-30T16:28:22.865Z] GC before operation: completed in 83.277 ms, heap usage 218.042 MB -> 70.000 MB. [2025-08-30T16:28:24.814Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1687.613 ms) ====== [2025-08-30T16:28:24.814Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-30T16:28:24.814Z] GC before operation: completed in 89.232 ms, heap usage 116.225 MB -> 69.655 MB. [2025-08-30T16:28:26.762Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1657.220 ms) ====== [2025-08-30T16:28:26.762Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-30T16:28:26.762Z] GC before operation: completed in 110.957 ms, heap usage 296.772 MB -> 70.507 MB. [2025-08-30T16:28:28.709Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1648.875 ms) ====== [2025-08-30T16:28:28.709Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-30T16:28:28.709Z] GC before operation: completed in 93.259 ms, heap usage 109.346 MB -> 69.978 MB. [2025-08-30T16:28:30.690Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1637.096 ms) ====== [2025-08-30T16:28:30.690Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-30T16:28:30.690Z] GC before operation: completed in 95.462 ms, heap usage 236.456 MB -> 70.820 MB. [2025-08-30T16:28:31.638Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1621.379 ms) ====== [2025-08-30T16:28:31.638Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-30T16:28:31.638Z] GC before operation: completed in 92.875 ms, heap usage 110.250 MB -> 70.302 MB. [2025-08-30T16:28:33.586Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1556.999 ms) ====== [2025-08-30T16:28:33.586Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-30T16:28:33.586Z] GC before operation: completed in 107.772 ms, heap usage 226.188 MB -> 71.222 MB. [2025-08-30T16:28:35.536Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1552.604 ms) ====== [2025-08-30T16:28:35.536Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-30T16:28:35.536Z] GC before operation: completed in 101.940 ms, heap usage 108.854 MB -> 76.153 MB. [2025-08-30T16:28:37.485Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1648.491 ms) ====== [2025-08-30T16:28:37.485Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-30T16:28:37.485Z] GC before operation: completed in 109.765 ms, heap usage 557.491 MB -> 71.573 MB. [2025-08-30T16:28:38.443Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1598.846 ms) ====== [2025-08-30T16:28:38.444Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-30T16:28:38.444Z] GC before operation: completed in 126.328 ms, heap usage 164.823 MB -> 71.263 MB. [2025-08-30T16:28:40.392Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1600.972 ms) ====== [2025-08-30T16:28:40.392Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-30T16:28:40.392Z] GC before operation: completed in 118.342 ms, heap usage 366.362 MB -> 82.564 MB. [2025-08-30T16:28:42.518Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1573.401 ms) ====== [2025-08-30T16:28:42.518Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-30T16:28:42.518Z] GC before operation: completed in 110.315 ms, heap usage 225.801 MB -> 71.798 MB. [2025-08-30T16:28:43.469Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1515.818 ms) ====== [2025-08-30T16:28:43.469Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-30T16:28:43.469Z] GC before operation: completed in 107.944 ms, heap usage 395.135 MB -> 71.930 MB. [2025-08-30T16:28:45.420Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1546.587 ms) ====== [2025-08-30T16:28:45.420Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-30T16:28:45.420Z] GC before operation: completed in 108.635 ms, heap usage 107.134 MB -> 71.282 MB. [2025-08-30T16:28:48.088Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1514.999 ms) ====== [2025-08-30T16:28:48.088Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-30T16:28:48.088Z] GC before operation: completed in 109.690 ms, heap usage 160.420 MB -> 71.705 MB. [2025-08-30T16:28:49.208Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1534.212 ms) ====== [2025-08-30T16:28:49.208Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-30T16:28:49.208Z] GC before operation: completed in 125.158 ms, heap usage 350.038 MB -> 72.276 MB. [2025-08-30T16:28:50.159Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1507.029 ms) ====== [2025-08-30T16:28:50.159Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-30T16:28:50.159Z] GC before operation: completed in 132.337 ms, heap usage 394.852 MB -> 83.300 MB. [2025-08-30T16:28:52.113Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1499.163 ms) ====== [2025-08-30T16:28:52.113Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-30T16:28:52.113Z] GC before operation: completed in 116.771 ms, heap usage 221.845 MB -> 72.380 MB. [2025-08-30T16:28:54.064Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1546.608 ms) ====== [2025-08-30T16:28:54.064Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-30T16:28:54.064Z] GC before operation: completed in 115.541 ms, heap usage 378.119 MB -> 72.489 MB. [2025-08-30T16:28:55.014Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1486.598 ms) ====== [2025-08-30T16:28:55.014Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-30T16:28:55.014Z] GC before operation: completed in 111.916 ms, heap usage 383.649 MB -> 72.688 MB. [2025-08-30T16:28:56.961Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1457.641 ms) ====== [2025-08-30T16:28:56.961Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-30T16:28:56.961Z] GC before operation: completed in 122.136 ms, heap usage 381.863 MB -> 89.158 MB. [2025-08-30T16:28:58.910Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1450.086 ms) ====== [2025-08-30T16:28:58.910Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-30T16:28:58.910Z] GC before operation: completed in 110.846 ms, heap usage 336.713 MB -> 89.570 MB. [2025-08-30T16:28:59.862Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1451.535 ms) ====== [2025-08-30T16:28:59.862Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-30T16:28:59.862Z] GC before operation: completed in 116.512 ms, heap usage 309.629 MB -> 78.633 MB. [2025-08-30T16:29:01.819Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1478.915 ms) ====== [2025-08-30T16:29:01.819Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-30T16:29:01.820Z] GC before operation: completed in 110.792 ms, heap usage 125.196 MB -> 72.362 MB. [2025-08-30T16:29:02.768Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1483.403 ms) ====== [2025-08-30T16:29:02.768Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-30T16:29:03.717Z] GC before operation: completed in 111.018 ms, heap usage 234.971 MB -> 84.380 MB. [2025-08-30T16:29:04.668Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1431.832 ms) ====== [2025-08-30T16:29:04.668Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-30T16:29:04.668Z] GC before operation: completed in 111.706 ms, heap usage 356.994 MB -> 73.385 MB. [2025-08-30T16:29:06.618Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1475.417 ms) ====== [2025-08-30T16:29:06.618Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-30T16:29:06.618Z] GC before operation: completed in 146.348 ms, heap usage 240.998 MB -> 73.337 MB. [2025-08-30T16:29:07.568Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1478.717 ms) ====== [2025-08-30T16:29:07.568Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-30T16:29:08.518Z] GC before operation: completed in 138.251 ms, heap usage 397.630 MB -> 84.749 MB. [2025-08-30T16:29:08.518Z] 16:29:07.484 WARN [block-manager-storage-async-thread-pool-39] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_415, which does not exist [2025-08-30T16:29:09.476Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1451.365 ms) ====== [2025-08-30T16:29:09.476Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-30T16:29:09.476Z] GC before operation: completed in 127.003 ms, heap usage 175.583 MB -> 73.243 MB. [2025-08-30T16:29:11.424Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1437.009 ms) ====== [2025-08-30T16:29:11.424Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-30T16:29:11.424Z] GC before operation: completed in 122.319 ms, heap usage 322.842 MB -> 90.236 MB. [2025-08-30T16:29:12.375Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1463.218 ms) ====== [2025-08-30T16:29:12.375Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-30T16:29:12.375Z] GC before operation: completed in 134.503 ms, heap usage 315.812 MB -> 90.564 MB. [2025-08-30T16:29:14.325Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1450.392 ms) ====== [2025-08-30T16:29:14.325Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-30T16:29:14.325Z] GC before operation: completed in 131.679 ms, heap usage 284.650 MB -> 79.692 MB. [2025-08-30T16:29:16.279Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1427.371 ms) ====== [2025-08-30T16:29:16.279Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-30T16:29:16.279Z] GC before operation: completed in 126.307 ms, heap usage 126.769 MB -> 73.510 MB. [2025-08-30T16:29:17.228Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1444.521 ms) ====== [2025-08-30T16:29:17.228Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-30T16:29:17.228Z] GC before operation: completed in 130.477 ms, heap usage 424.875 MB -> 85.449 MB. [2025-08-30T16:29:19.179Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1471.088 ms) ====== [2025-08-30T16:29:19.179Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-30T16:29:19.179Z] GC before operation: completed in 115.580 ms, heap usage 174.990 MB -> 74.053 MB. [2025-08-30T16:29:20.127Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1456.077 ms) ====== [2025-08-30T16:29:20.127Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-30T16:29:21.076Z] GC before operation: completed in 142.698 ms, heap usage 313.806 MB -> 79.789 MB. [2025-08-30T16:29:22.027Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1449.623 ms) ====== [2025-08-30T16:29:22.027Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-30T16:29:22.027Z] GC before operation: completed in 129.265 ms, heap usage 448.120 MB -> 74.575 MB. [2025-08-30T16:29:23.979Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1436.387 ms) ====== [2025-08-30T16:29:23.979Z] ----------------------------------- [2025-08-30T16:29:23.979Z] renaissance-dec-tree_0_PASSED [2025-08-30T16:29:23.979Z] ----------------------------------- [2025-08-30T16:29:23.979Z] [2025-08-30T16:29:23.979Z] TEST TEARDOWN: [2025-08-30T16:29:23.979Z] Nothing to be done for teardown. [2025-08-30T16:29:23.979Z] renaissance-dec-tree_0 Finish Time: Sat Aug 30 16:29:23 2025 Epoch Time (ms): 1756571363383