[2025-06-18T20:23:26.032Z] Running test renaissance-dec-tree_0 ... [2025-06-18T20:23:26.032Z] =============================================== [2025-06-18T20:23:26.032Z] renaissance-dec-tree_0 Start Time: Wed Jun 18 20:23:25 2025 Epoch Time (ms): 1750278205550 [2025-06-18T20:23:26.032Z] variation: NoOptions [2025-06-18T20:23:26.032Z] JVM_OPTIONS: [2025-06-18T20:23:26.032Z] { \ [2025-06-18T20:23:26.032Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T20:23:26.032Z] echo "Nothing to be done for setup."; \ [2025-06-18T20:23:26.032Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17502778682548/renaissance-dec-tree_0"; \ [2025-06-18T20:23:26.032Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17502778682548/renaissance-dec-tree_0"; \ [2025-06-18T20:23:26.032Z] echo ""; echo "TESTING:"; \ [2025-06-18T20:23:26.032Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17502778682548/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-18T20:23:26.032Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17502778682548/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T20:23:26.032Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T20:23:26.032Z] echo "Nothing to be done for teardown."; \ [2025-06-18T20:23:26.032Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17502778682548/TestTargetResult"; [2025-06-18T20:23:26.032Z] [2025-06-18T20:23:26.032Z] TEST SETUP: [2025-06-18T20:23:26.032Z] Nothing to be done for setup. [2025-06-18T20:23:26.032Z] [2025-06-18T20:23:26.032Z] TESTING: [2025-06-18T20:23:32.963Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2025-06-18T20:23:36.416Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-18T20:23:36.416Z] GC before operation: completed in 71.219 ms, heap usage 85.461 MB -> 34.548 MB. [2025-06-18T20:23:46.420Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9376.367 ms) ====== [2025-06-18T20:23:46.421Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-18T20:23:46.421Z] GC before operation: completed in 110.184 ms, heap usage 113.516 MB -> 70.148 MB. [2025-06-18T20:23:47.197Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1904.616 ms) ====== [2025-06-18T20:23:47.197Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-18T20:23:47.197Z] GC before operation: completed in 98.451 ms, heap usage 499.132 MB -> 76.531 MB. [2025-06-18T20:23:48.805Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1603.715 ms) ====== [2025-06-18T20:23:48.805Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-18T20:23:49.577Z] GC before operation: completed in 104.460 ms, heap usage 226.087 MB -> 71.499 MB. [2025-06-18T20:23:51.187Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1578.301 ms) ====== [2025-06-18T20:23:51.187Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-18T20:23:51.187Z] GC before operation: completed in 105.064 ms, heap usage 173.606 MB -> 71.411 MB. [2025-06-18T20:23:52.790Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1505.637 ms) ====== [2025-06-18T20:23:52.790Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-18T20:23:52.790Z] GC before operation: completed in 103.645 ms, heap usage 117.995 MB -> 71.199 MB. [2025-06-18T20:23:54.388Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1516.131 ms) ====== [2025-06-18T20:23:54.388Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-18T20:23:54.388Z] GC before operation: completed in 114.993 ms, heap usage 638.412 MB -> 83.384 MB. [2025-06-18T20:23:55.994Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1454.466 ms) ====== [2025-06-18T20:23:55.994Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-18T20:23:55.994Z] GC before operation: completed in 113.852 ms, heap usage 310.592 MB -> 72.246 MB. [2025-06-18T20:23:57.590Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1437.360 ms) ====== [2025-06-18T20:23:57.590Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-18T20:23:57.590Z] GC before operation: completed in 112.016 ms, heap usage 401.630 MB -> 72.508 MB. [2025-06-18T20:23:59.187Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1448.650 ms) ====== [2025-06-18T20:23:59.187Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-18T20:23:59.187Z] GC before operation: completed in 109.724 ms, heap usage 400.930 MB -> 83.340 MB. [2025-06-18T20:23:59.965Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1417.503 ms) ====== [2025-06-18T20:23:59.965Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-18T20:24:00.744Z] GC before operation: completed in 109.320 ms, heap usage 339.242 MB -> 78.237 MB. [2025-06-18T20:24:01.519Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1396.073 ms) ====== [2025-06-18T20:24:01.519Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-18T20:24:02.292Z] GC before operation: completed in 123.888 ms, heap usage 404.459 MB -> 89.231 MB. [2025-06-18T20:24:03.063Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1395.862 ms) ====== [2025-06-18T20:24:03.063Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-18T20:24:03.838Z] GC before operation: completed in 132.413 ms, heap usage 270.565 MB -> 72.699 MB. [2025-06-18T20:24:04.613Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1382.988 ms) ====== [2025-06-18T20:24:04.613Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-18T20:24:04.613Z] GC before operation: completed in 119.640 ms, heap usage 150.861 MB -> 72.830 MB. [2025-06-18T20:24:06.217Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1398.390 ms) ====== [2025-06-18T20:24:06.217Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-18T20:24:06.217Z] GC before operation: completed in 120.243 ms, heap usage 331.824 MB -> 84.200 MB. [2025-06-18T20:24:07.814Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1393.402 ms) ====== [2025-06-18T20:24:07.814Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-18T20:24:07.814Z] GC before operation: completed in 136.920 ms, heap usage 614.134 MB -> 73.291 MB. [2025-06-18T20:24:09.463Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1362.917 ms) ====== [2025-06-18T20:24:09.463Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-18T20:24:09.463Z] GC before operation: completed in 136.489 ms, heap usage 235.372 MB -> 84.145 MB. [2025-06-18T20:24:11.075Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1377.687 ms) ====== [2025-06-18T20:24:11.075Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-18T20:24:11.075Z] GC before operation: completed in 137.621 ms, heap usage 642.082 MB -> 73.843 MB. [2025-06-18T20:24:12.689Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1377.074 ms) ====== [2025-06-18T20:24:12.689Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-18T20:24:12.689Z] GC before operation: completed in 136.561 ms, heap usage 111.030 MB -> 73.088 MB. [2025-06-18T20:24:12.689Z] 20:24:12.056 WARN [block-manager-storage-async-thread-pool-20] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_247_piece0, which does not exist [2025-06-18T20:24:14.294Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1410.798 ms) ====== [2025-06-18T20:24:14.294Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-18T20:24:14.294Z] GC before operation: completed in 133.167 ms, heap usage 599.930 MB -> 84.976 MB. [2025-06-18T20:24:15.913Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1382.840 ms) ====== [2025-06-18T20:24:15.913Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-18T20:24:15.913Z] GC before operation: completed in 132.090 ms, heap usage 128.360 MB -> 73.155 MB. [2025-06-18T20:24:16.696Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1407.512 ms) ====== [2025-06-18T20:24:16.696Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-18T20:24:17.477Z] GC before operation: completed in 134.850 ms, heap usage 121.236 MB -> 73.405 MB. [2025-06-18T20:24:18.255Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1382.829 ms) ====== [2025-06-18T20:24:18.255Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-18T20:24:19.027Z] GC before operation: completed in 141.103 ms, heap usage 605.765 MB -> 90.801 MB. [2025-06-18T20:24:19.801Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1362.296 ms) ====== [2025-06-18T20:24:19.801Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-18T20:24:19.801Z] GC before operation: completed in 148.832 ms, heap usage 553.580 MB -> 79.971 MB. [2025-06-18T20:24:21.401Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1354.609 ms) ====== [2025-06-18T20:24:21.401Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-18T20:24:21.401Z] GC before operation: completed in 151.335 ms, heap usage 604.971 MB -> 91.021 MB. [2025-06-18T20:24:22.996Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1359.698 ms) ====== [2025-06-18T20:24:22.996Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-18T20:24:22.996Z] GC before operation: completed in 146.635 ms, heap usage 585.257 MB -> 80.355 MB. [2025-06-18T20:24:24.605Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1357.505 ms) ====== [2025-06-18T20:24:24.605Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-18T20:24:24.605Z] GC before operation: completed in 133.210 ms, heap usage 605.617 MB -> 74.709 MB. [2025-06-18T20:24:26.222Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1344.775 ms) ====== [2025-06-18T20:24:26.222Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-18T20:24:26.222Z] GC before operation: completed in 139.213 ms, heap usage 324.992 MB -> 85.465 MB. [2025-06-18T20:24:27.824Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1373.807 ms) ====== [2025-06-18T20:24:27.824Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-18T20:24:27.824Z] GC before operation: completed in 142.832 ms, heap usage 299.236 MB -> 75.063 MB. [2025-06-18T20:24:29.425Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1360.065 ms) ====== [2025-06-18T20:24:29.425Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-18T20:24:29.425Z] GC before operation: completed in 147.221 ms, heap usage 325.747 MB -> 85.863 MB. [2025-06-18T20:24:30.200Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1356.722 ms) ====== [2025-06-18T20:24:30.201Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-18T20:24:30.972Z] GC before operation: completed in 147.305 ms, heap usage 578.558 MB -> 86.055 MB. [2025-06-18T20:24:31.747Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1324.838 ms) ====== [2025-06-18T20:24:31.747Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-18T20:24:32.519Z] GC before operation: completed in 140.533 ms, heap usage 300.828 MB -> 91.716 MB. [2025-06-18T20:24:32.519Z] 20:24:31.675 WARN [block-manager-storage-async-thread-pool-8] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429_piece0, which does not exist [2025-06-18T20:24:33.293Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1358.521 ms) ====== [2025-06-18T20:24:33.293Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-18T20:24:33.293Z] GC before operation: completed in 148.638 ms, heap usage 308.169 MB -> 75.131 MB. [2025-06-18T20:24:34.890Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1369.582 ms) ====== [2025-06-18T20:24:34.890Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-18T20:24:34.890Z] GC before operation: completed in 145.811 ms, heap usage 599.251 MB -> 75.489 MB. [2025-06-18T20:24:36.489Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1330.863 ms) ====== [2025-06-18T20:24:36.489Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-18T20:24:36.489Z] GC before operation: completed in 145.263 ms, heap usage 114.272 MB -> 74.851 MB. [2025-06-18T20:24:38.105Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1391.141 ms) ====== [2025-06-18T20:24:38.106Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-18T20:24:38.106Z] GC before operation: completed in 144.583 ms, heap usage 858.411 MB -> 86.600 MB. [2025-06-18T20:24:39.701Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1321.749 ms) ====== [2025-06-18T20:24:39.701Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-18T20:24:39.701Z] GC before operation: completed in 150.731 ms, heap usage 301.952 MB -> 86.462 MB. [2025-06-18T20:24:41.313Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1338.136 ms) ====== [2025-06-18T20:24:41.313Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-18T20:24:41.313Z] GC before operation: completed in 133.366 ms, heap usage 609.687 MB -> 92.397 MB. [2025-06-18T20:24:42.913Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1345.229 ms) ====== [2025-06-18T20:24:42.913Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-18T20:24:42.913Z] GC before operation: completed in 131.057 ms, heap usage 551.370 MB -> 92.505 MB. [2025-06-18T20:24:43.689Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1332.009 ms) ====== [2025-06-18T20:24:43.689Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-18T20:24:44.467Z] GC before operation: completed in 122.988 ms, heap usage 268.012 MB -> 87.155 MB. [2025-06-18T20:24:45.247Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1331.002 ms) ====== [2025-06-18T20:24:46.021Z] ----------------------------------- [2025-06-18T20:24:46.021Z] renaissance-dec-tree_0_PASSED [2025-06-18T20:24:46.021Z] ----------------------------------- [2025-06-18T20:24:46.021Z] [2025-06-18T20:24:46.021Z] TEST TEARDOWN: [2025-06-18T20:24:46.021Z] Nothing to be done for teardown. [2025-06-18T20:24:46.021Z] renaissance-dec-tree_0 Finish Time: Wed Jun 18 20:24:45 2025 Epoch Time (ms): 1750278285280