[2025-06-27T01:20:49.997Z] Running test renaissance-dec-tree_0 ... [2025-06-27T01:20:49.997Z] =============================================== [2025-06-27T01:20:49.997Z] renaissance-dec-tree_0 Start Time: Fri Jun 27 01:20:49 2025 Epoch Time (ms): 1750987249413 [2025-06-27T01:20:49.997Z] variation: NoOptions [2025-06-27T01:20:49.997Z] JVM_OPTIONS: [2025-06-27T01:20:49.997Z] { \ [2025-06-27T01:20:49.997Z] echo ""; echo "TEST SETUP:"; \ [2025-06-27T01:20:49.997Z] echo "Nothing to be done for setup."; \ [2025-06-27T01:20:49.997Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17509863158443/renaissance-dec-tree_0"; \ [2025-06-27T01:20:49.997Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17509863158443/renaissance-dec-tree_0"; \ [2025-06-27T01:20:49.997Z] echo ""; echo "TESTING:"; \ [2025-06-27T01:20:49.997Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-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_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17509863158443/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-27T01:20:49.997Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17509863158443/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-27T01:20:49.997Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-27T01:20:49.997Z] echo "Nothing to be done for teardown."; \ [2025-06-27T01:20:49.997Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17509863158443/TestTargetResult"; [2025-06-27T01:20:49.997Z] [2025-06-27T01:20:49.997Z] TEST SETUP: [2025-06-27T01:20:49.997Z] Nothing to be done for setup. [2025-06-27T01:20:49.997Z] [2025-06-27T01:20:49.997Z] TESTING: [2025-06-27T01:20:50.806Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-06-27T01:20:50.806Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_17509863158443/renaissance-dec-tree_0/launcher-012049-11464870248298949799/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-06-27T01:20:50.806Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-06-27T01:20:50.806Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-06-27T01:20:57.972Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-27T01:21:02.702Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-27T01:21:02.702Z] GC before operation: completed in 62.777 ms, heap usage 50.782 MB -> 34.953 MB. [2025-06-27T01:21:22.394Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (18294.461 ms) ====== [2025-06-27T01:21:22.394Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-27T01:21:22.394Z] GC before operation: completed in 166.456 ms, heap usage 135.088 MB -> 67.193 MB. [2025-06-27T01:21:24.093Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3876.337 ms) ====== [2025-06-27T01:21:24.093Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-27T01:21:24.933Z] GC before operation: completed in 167.388 ms, heap usage 309.464 MB -> 69.179 MB. [2025-06-27T01:21:27.572Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2736.835 ms) ====== [2025-06-27T01:21:27.572Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-27T01:21:27.572Z] GC before operation: completed in 163.523 ms, heap usage 163.255 MB -> 87.370 MB. [2025-06-27T01:21:27.572Z] 01:21:27.057 WARN [block-manager-storage-async-thread-pool-79] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_37, which does not exist [2025-06-27T01:21:30.193Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2401.167 ms) ====== [2025-06-27T01:21:30.193Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-27T01:21:30.193Z] GC before operation: completed in 155.323 ms, heap usage 272.357 MB -> 68.479 MB. [2025-06-27T01:21:31.913Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2136.390 ms) ====== [2025-06-27T01:21:31.913Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-27T01:21:33.021Z] GC before operation: completed in 154.824 ms, heap usage 286.087 MB -> 68.672 MB. [2025-06-27T01:21:34.874Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2166.909 ms) ====== [2025-06-27T01:21:34.874Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-27T01:21:34.874Z] GC before operation: completed in 161.863 ms, heap usage 287.202 MB -> 69.228 MB. [2025-06-27T01:21:36.993Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2135.845 ms) ====== [2025-06-27T01:21:36.993Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-27T01:21:36.993Z] GC before operation: completed in 165.582 ms, heap usage 285.564 MB -> 96.702 MB. [2025-06-27T01:21:36.993Z] 01:21:36.534 WARN [block-manager-storage-async-thread-pool-50] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_93, which does not exist [2025-06-27T01:21:38.734Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2033.247 ms) ====== [2025-06-27T01:21:38.734Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-27T01:21:39.575Z] GC before operation: completed in 157.491 ms, heap usage 373.497 MB -> 77.777 MB. [2025-06-27T01:21:41.773Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2009.594 ms) ====== [2025-06-27T01:21:41.773Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-27T01:21:41.773Z] GC before operation: completed in 173.609 ms, heap usage 197.101 MB -> 96.698 MB. [2025-06-27T01:21:41.773Z] 01:21:40.909 WARN [block-manager-storage-async-thread-pool-96] org.apache.spark.storage.BlockManager - Asked to remove block rdd_429_1, which does not exist [2025-06-27T01:21:43.474Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1882.295 ms) ====== [2025-06-27T01:21:43.474Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-27T01:21:43.474Z] GC before operation: completed in 158.276 ms, heap usage 111.998 MB -> 69.645 MB. [2025-06-27T01:21:45.319Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2019.942 ms) ====== [2025-06-27T01:21:45.319Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-27T01:21:45.319Z] GC before operation: completed in 184.737 ms, heap usage 446.563 MB -> 70.023 MB. [2025-06-27T01:21:47.937Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1828.371 ms) ====== [2025-06-27T01:21:47.937Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-27T01:21:47.937Z] GC before operation: completed in 158.087 ms, heap usage 167.412 MB -> 69.715 MB. [2025-06-27T01:21:49.633Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1825.419 ms) ====== [2025-06-27T01:21:49.633Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-27T01:21:49.633Z] GC before operation: completed in 180.429 ms, heap usage 236.345 MB -> 70.140 MB. [2025-06-27T01:21:51.329Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1719.851 ms) ====== [2025-06-27T01:21:51.329Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-27T01:21:51.329Z] GC before operation: completed in 185.804 ms, heap usage 114.884 MB -> 69.463 MB. [2025-06-27T01:21:53.921Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2046.936 ms) ====== [2025-06-27T01:21:53.921Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-27T01:21:53.921Z] GC before operation: completed in 174.261 ms, heap usage 290.293 MB -> 89.465 MB. [2025-06-27T01:21:55.734Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1784.268 ms) ====== [2025-06-27T01:21:55.734Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-27T01:21:55.734Z] GC before operation: completed in 192.170 ms, heap usage 257.843 MB -> 70.338 MB. [2025-06-27T01:21:57.412Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1707.760 ms) ====== [2025-06-27T01:21:57.412Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-27T01:21:57.412Z] GC before operation: completed in 184.202 ms, heap usage 108.729 MB -> 78.379 MB. [2025-06-27T01:22:00.015Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1905.554 ms) ====== [2025-06-27T01:22:00.015Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-27T01:22:00.015Z] GC before operation: completed in 174.886 ms, heap usage 209.156 MB -> 70.617 MB. [2025-06-27T01:22:01.694Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1760.396 ms) ====== [2025-06-27T01:22:01.694Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-27T01:22:01.694Z] GC before operation: completed in 185.461 ms, heap usage 237.927 MB -> 70.812 MB. [2025-06-27T01:22:03.363Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1667.390 ms) ====== [2025-06-27T01:22:03.363Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-27T01:22:03.363Z] GC before operation: completed in 184.644 ms, heap usage 413.279 MB -> 79.539 MB. [2025-06-27T01:22:05.974Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2029.666 ms) ====== [2025-06-27T01:22:05.974Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-27T01:22:05.974Z] GC before operation: completed in 183.409 ms, heap usage 190.327 MB -> 79.226 MB. [2025-06-27T01:22:07.678Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1760.195 ms) ====== [2025-06-27T01:22:07.678Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-27T01:22:07.678Z] GC before operation: completed in 194.557 ms, heap usage 320.092 MB -> 90.076 MB. [2025-06-27T01:22:09.354Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1574.076 ms) ====== [2025-06-27T01:22:09.355Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-27T01:22:09.355Z] GC before operation: completed in 202.627 ms, heap usage 109.257 MB -> 70.956 MB. [2025-06-27T01:22:11.034Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1810.043 ms) ====== [2025-06-27T01:22:11.034Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-27T01:22:11.866Z] GC before operation: completed in 200.770 ms, heap usage 184.669 MB -> 71.389 MB. [2025-06-27T01:22:13.545Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1784.377 ms) ====== [2025-06-27T01:22:13.545Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-27T01:22:13.545Z] GC before operation: completed in 197.301 ms, heap usage 432.321 MB -> 71.693 MB. [2025-06-27T01:22:15.241Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1886.061 ms) ====== [2025-06-27T01:22:15.241Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-27T01:22:15.241Z] GC before operation: completed in 253.138 ms, heap usage 212.799 MB -> 71.470 MB. [2025-06-27T01:22:16.941Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1617.369 ms) ====== [2025-06-27T01:22:16.941Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-27T01:22:17.763Z] GC before operation: completed in 201.045 ms, heap usage 460.327 MB -> 80.314 MB. [2025-06-27T01:22:19.474Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1636.783 ms) ====== [2025-06-27T01:22:19.474Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-27T01:22:19.474Z] GC before operation: completed in 223.619 ms, heap usage 308.690 MB -> 99.176 MB. [2025-06-27T01:22:20.837Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1653.754 ms) ====== [2025-06-27T01:22:20.837Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-27T01:22:20.837Z] GC before operation: completed in 198.272 ms, heap usage 355.143 MB -> 80.297 MB. [2025-06-27T01:22:22.525Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1576.945 ms) ====== [2025-06-27T01:22:22.525Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-27T01:22:22.525Z] GC before operation: completed in 219.657 ms, heap usage 165.880 MB -> 71.549 MB. [2025-06-27T01:22:24.224Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1567.035 ms) ====== [2025-06-27T01:22:24.225Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-27T01:22:25.036Z] GC before operation: completed in 194.672 ms, heap usage 312.407 MB -> 71.999 MB. [2025-06-27T01:22:26.714Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1637.126 ms) ====== [2025-06-27T01:22:26.714Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-27T01:22:26.714Z] GC before operation: completed in 209.081 ms, heap usage 415.113 MB -> 91.605 MB. [2025-06-27T01:22:28.535Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1658.677 ms) ====== [2025-06-27T01:22:28.535Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-27T01:22:28.535Z] GC before operation: completed in 201.164 ms, heap usage 403.489 MB -> 72.413 MB. [2025-06-27T01:22:30.219Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1582.181 ms) ====== [2025-06-27T01:22:30.219Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-27T01:22:30.219Z] GC before operation: completed in 211.899 ms, heap usage 253.854 MB -> 91.673 MB. [2025-06-27T01:22:31.894Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1552.811 ms) ====== [2025-06-27T01:22:31.894Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-27T01:22:31.894Z] GC before operation: completed in 218.116 ms, heap usage 242.446 MB -> 91.438 MB. [2025-06-27T01:22:33.593Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1536.335 ms) ====== [2025-06-27T01:22:33.593Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-27T01:22:33.593Z] GC before operation: completed in 221.053 ms, heap usage 394.915 MB -> 91.800 MB. [2025-06-27T01:22:35.279Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1719.894 ms) ====== [2025-06-27T01:22:35.279Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-27T01:22:35.279Z] GC before operation: completed in 197.898 ms, heap usage 134.302 MB -> 72.608 MB. [2025-06-27T01:22:36.974Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1734.587 ms) ====== [2025-06-27T01:22:36.974Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-27T01:22:37.784Z] GC before operation: completed in 211.683 ms, heap usage 564.013 MB -> 81.263 MB. [2025-06-27T01:22:39.473Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1640.538 ms) ====== [2025-06-27T01:22:39.473Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-27T01:22:39.473Z] GC before operation: completed in 208.387 ms, heap usage 351.945 MB -> 100.438 MB. [2025-06-27T01:22:39.473Z] 01:22:38.952 WARN [block-manager-storage-async-thread-pool-69] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_541, which does not exist [2025-06-27T01:22:41.148Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1421.139 ms) ====== [2025-06-27T01:22:41.148Z] ----------------------------------- [2025-06-27T01:22:41.148Z] renaissance-dec-tree_0_PASSED [2025-06-27T01:22:41.148Z] ----------------------------------- [2025-06-27T01:22:41.148Z] [2025-06-27T01:22:41.148Z] TEST TEARDOWN: [2025-06-27T01:22:41.148Z] Nothing to be done for teardown. [2025-06-27T01:22:41.148Z] renaissance-dec-tree_0 Finish Time: Fri Jun 27 01:22:40 2025 Epoch Time (ms): 1750987360993