[2025-06-07T22:27:06.897Z] Running test renaissance-dec-tree_0 ... [2025-06-07T22:27:06.897Z] =============================================== [2025-06-07T22:27:06.897Z] renaissance-dec-tree_0 Start Time: Sat Jun 7 22:27:06 2025 Epoch Time (ms): 1749335226768 [2025-06-07T22:27:06.897Z] variation: NoOptions [2025-06-07T22:27:06.897Z] JVM_OPTIONS: [2025-06-07T22:27:06.897Z] { \ [2025-06-07T22:27:06.897Z] echo ""; echo "TEST SETUP:"; \ [2025-06-07T22:27:06.897Z] echo "Nothing to be done for setup."; \ [2025-06-07T22:27:06.897Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17493350227136/renaissance-dec-tree_0"; \ [2025-06-07T22:27:06.897Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17493350227136/renaissance-dec-tree_0"; \ [2025-06-07T22:27:06.897Z] echo ""; echo "TESTING:"; \ [2025-06-07T22:27:06.897Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/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_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17493350227136/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-07T22:27:06.897Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17493350227136/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-07T22:27:06.897Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-07T22:27:06.897Z] echo "Nothing to be done for teardown."; \ [2025-06-07T22:27:06.897Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/../TKG/output_17493350227136/TestTargetResult"; [2025-06-07T22:27:06.897Z] [2025-06-07T22:27:06.897Z] TEST SETUP: [2025-06-07T22:27:06.897Z] Nothing to be done for setup. [2025-06-07T22:27:06.897Z] [2025-06-07T22:27:06.897Z] TESTING: [2025-06-07T22:27:08.006Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-06-07T22:27:08.006Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_1/aqa-tests/TKG/output_17493350227136/renaissance-dec-tree_0/launcher-222706-7368995577961135408/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-06-07T22:27:08.006Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-06-07T22:27:08.006Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-06-07T22:27:12.154Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-07T22:27:15.172Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-07T22:27:15.172Z] GC before operation: completed in 101.254 ms, heap usage 56.923 MB -> 35.002 MB. [2025-06-07T22:27:24.032Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8638.715 ms) ====== [2025-06-07T22:27:24.032Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-07T22:27:24.032Z] GC before operation: completed in 100.729 ms, heap usage 283.399 MB -> 85.362 MB. [2025-06-07T22:27:25.988Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2064.818 ms) ====== [2025-06-07T22:27:25.988Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-07T22:27:25.988Z] GC before operation: completed in 87.997 ms, heap usage 468.441 MB -> 74.688 MB. [2025-06-07T22:27:27.942Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1647.940 ms) ====== [2025-06-07T22:27:27.942Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-07T22:27:27.942Z] GC before operation: completed in 78.743 ms, heap usage 110.235 MB -> 68.773 MB. [2025-06-07T22:27:28.899Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1607.008 ms) ====== [2025-06-07T22:27:28.899Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-07T22:27:28.899Z] GC before operation: completed in 74.553 ms, heap usage 367.526 MB -> 69.674 MB. [2025-06-07T22:27:30.868Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1613.339 ms) ====== [2025-06-07T22:27:30.868Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-07T22:27:30.868Z] GC before operation: completed in 86.926 ms, heap usage 297.276 MB -> 70.106 MB. [2025-06-07T22:27:32.827Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1596.936 ms) ====== [2025-06-07T22:27:32.827Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-07T22:27:32.827Z] GC before operation: completed in 85.963 ms, heap usage 247.106 MB -> 70.104 MB. [2025-06-07T22:27:33.778Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1510.686 ms) ====== [2025-06-07T22:27:33.778Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-07T22:27:33.778Z] GC before operation: completed in 91.630 ms, heap usage 212.612 MB -> 70.196 MB. [2025-06-07T22:27:35.825Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1494.186 ms) ====== [2025-06-07T22:27:35.825Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-07T22:27:35.825Z] GC before operation: completed in 88.478 ms, heap usage 189.252 MB -> 70.193 MB. [2025-06-07T22:27:37.780Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1452.188 ms) ====== [2025-06-07T22:27:37.780Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-07T22:27:37.780Z] GC before operation: completed in 84.805 ms, heap usage 186.437 MB -> 70.212 MB. [2025-06-07T22:27:38.732Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1450.382 ms) ====== [2025-06-07T22:27:38.732Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-07T22:27:38.732Z] GC before operation: completed in 87.578 ms, heap usage 170.647 MB -> 70.437 MB. [2025-06-07T22:27:40.687Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1474.157 ms) ====== [2025-06-07T22:27:40.687Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-07T22:27:40.687Z] GC before operation: completed in 97.376 ms, heap usage 158.614 MB -> 70.425 MB. [2025-06-07T22:27:41.639Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1576.401 ms) ====== [2025-06-07T22:27:41.639Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-07T22:27:42.590Z] GC before operation: completed in 89.633 ms, heap usage 121.428 MB -> 81.551 MB. [2025-06-07T22:27:43.542Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1449.614 ms) ====== [2025-06-07T22:27:43.542Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-07T22:27:43.542Z] GC before operation: completed in 113.181 ms, heap usage 294.368 MB -> 71.174 MB. [2025-06-07T22:27:45.496Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1442.837 ms) ====== [2025-06-07T22:27:45.496Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-07T22:27:45.497Z] GC before operation: completed in 101.869 ms, heap usage 171.841 MB -> 71.237 MB. [2025-06-07T22:27:45.497Z] 22:27:44.664 WARN [block-manager-storage-async-thread-pool-41] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_191, which does not exist [2025-06-07T22:27:46.447Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1505.451 ms) ====== [2025-06-07T22:27:46.447Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-07T22:27:46.447Z] GC before operation: completed in 110.408 ms, heap usage 115.209 MB -> 70.670 MB. [2025-06-07T22:27:48.400Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1449.546 ms) ====== [2025-06-07T22:27:48.400Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-07T22:27:48.400Z] GC before operation: completed in 119.847 ms, heap usage 381.594 MB -> 82.315 MB. [2025-06-07T22:27:50.357Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1465.874 ms) ====== [2025-06-07T22:27:50.357Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-07T22:27:50.357Z] GC before operation: completed in 109.761 ms, heap usage 375.994 MB -> 71.651 MB. [2025-06-07T22:27:51.310Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1423.967 ms) ====== [2025-06-07T22:27:51.310Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-07T22:27:51.310Z] GC before operation: completed in 106.843 ms, heap usage 229.258 MB -> 82.576 MB. [2025-06-07T22:27:53.262Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1439.024 ms) ====== [2025-06-07T22:27:53.262Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-07T22:27:53.262Z] GC before operation: completed in 110.048 ms, heap usage 284.979 MB -> 71.878 MB. [2025-06-07T22:27:54.215Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1411.014 ms) ====== [2025-06-07T22:27:54.215Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-07T22:27:54.215Z] GC before operation: completed in 101.263 ms, heap usage 314.743 MB -> 72.000 MB. [2025-06-07T22:27:56.168Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1443.571 ms) ====== [2025-06-07T22:27:56.168Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-07T22:27:56.168Z] GC before operation: completed in 109.294 ms, heap usage 320.579 MB -> 88.659 MB. [2025-06-07T22:27:58.122Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1483.813 ms) ====== [2025-06-07T22:27:58.122Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-07T22:27:58.122Z] GC before operation: completed in 102.919 ms, heap usage 274.327 MB -> 72.252 MB. [2025-06-07T22:27:59.082Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1420.922 ms) ====== [2025-06-07T22:27:59.082Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-07T22:27:59.082Z] GC before operation: completed in 107.498 ms, heap usage 304.597 MB -> 72.378 MB. [2025-06-07T22:28:01.036Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1439.300 ms) ====== [2025-06-07T22:28:01.036Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-07T22:28:01.036Z] GC before operation: completed in 105.402 ms, heap usage 166.044 MB -> 72.099 MB. [2025-06-07T22:28:01.987Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1394.728 ms) ====== [2025-06-07T22:28:01.987Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-07T22:28:01.987Z] GC before operation: completed in 103.593 ms, heap usage 446.423 MB -> 72.616 MB. [2025-06-07T22:28:03.940Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1400.847 ms) ====== [2025-06-07T22:28:03.940Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-07T22:28:03.940Z] GC before operation: completed in 104.490 ms, heap usage 235.748 MB -> 72.434 MB. [2025-06-07T22:28:04.899Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1438.035 ms) ====== [2025-06-07T22:28:04.899Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-07T22:28:04.899Z] GC before operation: completed in 104.782 ms, heap usage 146.476 MB -> 83.570 MB. [2025-06-07T22:28:06.857Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1405.261 ms) ====== [2025-06-07T22:28:06.857Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-07T22:28:06.857Z] GC before operation: completed in 105.105 ms, heap usage 261.181 MB -> 83.780 MB. [2025-06-07T22:28:08.811Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1426.418 ms) ====== [2025-06-07T22:28:08.811Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-07T22:28:08.811Z] GC before operation: completed in 109.694 ms, heap usage 459.821 MB -> 84.149 MB. [2025-06-07T22:28:09.761Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1395.505 ms) ====== [2025-06-07T22:28:09.761Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-07T22:28:09.761Z] GC before operation: completed in 108.636 ms, heap usage 134.002 MB -> 83.580 MB. [2025-06-07T22:28:11.729Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1388.852 ms) ====== [2025-06-07T22:28:11.729Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-07T22:28:11.729Z] GC before operation: completed in 109.928 ms, heap usage 461.215 MB -> 78.928 MB. [2025-06-07T22:28:12.680Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1498.990 ms) ====== [2025-06-07T22:28:12.680Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-07T22:28:12.680Z] GC before operation: completed in 125.469 ms, heap usage 294.227 MB -> 100.820 MB. [2025-06-07T22:28:12.680Z] 22:28:12.459 WARN [block-manager-storage-async-thread-pool-77] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_443_piece0, which does not exist [2025-06-07T22:28:12.680Z] 22:28:12.459 WARN [block-manager-storage-async-thread-pool-77] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_443, which does not exist [2025-06-07T22:28:14.635Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1369.596 ms) ====== [2025-06-07T22:28:14.635Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-07T22:28:14.635Z] GC before operation: completed in 103.686 ms, heap usage 294.163 MB -> 73.335 MB. [2025-06-07T22:28:15.589Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1392.500 ms) ====== [2025-06-07T22:28:15.589Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-07T22:28:15.589Z] GC before operation: completed in 105.276 ms, heap usage 230.464 MB -> 73.538 MB. [2025-06-07T22:28:17.545Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1388.567 ms) ====== [2025-06-07T22:28:17.545Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-07T22:28:17.545Z] GC before operation: completed in 109.781 ms, heap usage 274.854 MB -> 84.655 MB. [2025-06-07T22:28:19.192Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1444.727 ms) ====== [2025-06-07T22:28:19.192Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-07T22:28:19.192Z] GC before operation: completed in 111.017 ms, heap usage 284.716 MB -> 84.568 MB. [2025-06-07T22:28:20.142Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1336.320 ms) ====== [2025-06-07T22:28:20.142Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-07T22:28:20.142Z] GC before operation: completed in 115.439 ms, heap usage 462.683 MB -> 85.069 MB. [2025-06-07T22:28:22.094Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1356.231 ms) ====== [2025-06-07T22:28:22.094Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-07T22:28:22.094Z] GC before operation: completed in 119.754 ms, heap usage 120.104 MB -> 78.996 MB. [2025-06-07T22:28:22.094Z] 22:28:21.415 WARN [block-manager-storage-async-thread-pool-9] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_527, which does not exist [2025-06-07T22:28:22.094Z] 22:28:21.415 WARN [block-manager-storage-async-thread-pool-9] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_527_piece0, which does not exist [2025-06-07T22:28:23.044Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1391.918 ms) ====== [2025-06-07T22:28:23.044Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-07T22:28:23.044Z] GC before operation: completed in 107.069 ms, heap usage 564.836 MB -> 74.330 MB. [2025-06-07T22:28:24.999Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1343.245 ms) ====== [2025-06-07T22:28:24.999Z] ----------------------------------- [2025-06-07T22:28:24.999Z] renaissance-dec-tree_0_PASSED [2025-06-07T22:28:24.999Z] ----------------------------------- [2025-06-07T22:28:24.999Z] [2025-06-07T22:28:24.999Z] TEST TEARDOWN: [2025-06-07T22:28:24.999Z] Nothing to be done for teardown. [2025-06-07T22:28:24.999Z] renaissance-dec-tree_0 Finish Time: Sat Jun 7 22:28:24 2025 Epoch Time (ms): 1749335304518