[2025-07-16T05:39:25.013Z] Running test renaissance-dec-tree_0 ... [2025-07-16T05:39:25.013Z] =============================================== [2025-07-16T05:39:25.013Z] renaissance-dec-tree_0 Start Time: Wed Jul 16 05:39:24 2025 Epoch Time (ms): 1752644364859 [2025-07-16T05:39:25.013Z] variation: NoOptions [2025-07-16T05:39:25.013Z] JVM_OPTIONS: [2025-07-16T05:39:25.013Z] { \ [2025-07-16T05:39:25.013Z] echo ""; echo "TEST SETUP:"; \ [2025-07-16T05:39:25.013Z] echo "Nothing to be done for setup."; \ [2025-07-16T05:39:25.013Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-dec-tree_0"; \ [2025-07-16T05:39:25.013Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-dec-tree_0"; \ [2025-07-16T05:39:25.013Z] echo ""; echo "TESTING:"; \ [2025-07-16T05:39:25.013Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-07-16T05:39:25.013Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-16T05:39:25.013Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-16T05:39:25.013Z] echo "Nothing to be done for teardown."; \ [2025-07-16T05:39:25.013Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17526418129189/TestTargetResult"; [2025-07-16T05:39:25.013Z] [2025-07-16T05:39:25.013Z] TEST SETUP: [2025-07-16T05:39:25.013Z] Nothing to be done for setup. [2025-07-16T05:39:25.013Z] [2025-07-16T05:39:25.013Z] TESTING: [2025-07-16T05:40:01.408Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-16T05:40:17.908Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-07-16T05:40:17.908Z] GC before operation: completed in 286.037 ms, heap usage 55.808 MB -> 34.963 MB. [2025-07-16T05:41:37.669Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (79234.576 ms) ====== [2025-07-16T05:41:37.669Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-07-16T05:41:37.669Z] GC before operation: completed in 714.283 ms, heap usage 243.956 MB -> 69.020 MB. [2025-07-16T05:42:00.505Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (23134.250 ms) ====== [2025-07-16T05:42:00.505Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-07-16T05:42:00.505Z] GC before operation: completed in 736.349 ms, heap usage 276.609 MB -> 69.207 MB. [2025-07-16T05:42:19.965Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (18025.558 ms) ====== [2025-07-16T05:42:19.965Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-07-16T05:42:19.965Z] GC before operation: completed in 626.636 ms, heap usage 323.553 MB -> 81.323 MB. [2025-07-16T05:42:36.419Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (16936.861 ms) ====== [2025-07-16T05:42:36.419Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-07-16T05:42:36.419Z] GC before operation: completed in 591.256 ms, heap usage 298.018 MB -> 80.662 MB. [2025-07-16T05:42:52.918Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (14631.220 ms) ====== [2025-07-16T05:42:52.918Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-07-16T05:42:52.918Z] GC before operation: completed in 638.716 ms, heap usage 304.096 MB -> 81.243 MB. [2025-07-16T05:43:07.374Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (13880.066 ms) ====== [2025-07-16T05:43:07.374Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-07-16T05:43:07.374Z] GC before operation: completed in 586.797 ms, heap usage 374.279 MB -> 70.157 MB. [2025-07-16T05:43:19.656Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (13544.873 ms) ====== [2025-07-16T05:43:19.656Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-07-16T05:43:20.504Z] GC before operation: completed in 609.039 ms, heap usage 316.428 MB -> 92.590 MB. [2025-07-16T05:43:35.421Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (13914.510 ms) ====== [2025-07-16T05:43:35.421Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-07-16T05:43:35.421Z] GC before operation: completed in 695.900 ms, heap usage 215.091 MB -> 92.780 MB. [2025-07-16T05:43:47.442Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (11804.167 ms) ====== [2025-07-16T05:43:47.442Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-07-16T05:43:47.442Z] GC before operation: completed in 663.184 ms, heap usage 235.392 MB -> 70.594 MB. [2025-07-16T05:44:01.557Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (12439.568 ms) ====== [2025-07-16T05:44:01.557Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-07-16T05:44:01.557Z] GC before operation: completed in 704.054 ms, heap usage 416.457 MB -> 70.751 MB. [2025-07-16T05:44:13.619Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (12413.426 ms) ====== [2025-07-16T05:44:13.619Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-07-16T05:44:13.619Z] GC before operation: completed in 619.563 ms, heap usage 364.120 MB -> 71.009 MB. [2025-07-16T05:44:23.797Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (10044.052 ms) ====== [2025-07-16T05:44:23.797Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-07-16T05:44:24.589Z] GC before operation: completed in 723.854 ms, heap usage 150.798 MB -> 70.649 MB. [2025-07-16T05:44:34.674Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (9912.414 ms) ====== [2025-07-16T05:44:34.674Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-07-16T05:44:35.506Z] GC before operation: completed in 612.611 ms, heap usage 275.845 MB -> 71.283 MB. [2025-07-16T05:44:45.863Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (11039.058 ms) ====== [2025-07-16T05:44:45.863Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-07-16T05:44:46.674Z] GC before operation: completed in 728.869 ms, heap usage 183.430 MB -> 71.069 MB. [2025-07-16T05:44:58.732Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (11732.238 ms) ====== [2025-07-16T05:44:58.733Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-07-16T05:44:59.526Z] GC before operation: completed in 606.314 ms, heap usage 276.003 MB -> 71.405 MB. [2025-07-16T05:45:09.580Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (10364.794 ms) ====== [2025-07-16T05:45:09.580Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-07-16T05:45:10.380Z] GC before operation: completed in 848.147 ms, heap usage 158.327 MB -> 71.373 MB. [2025-07-16T05:45:22.315Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (10704.537 ms) ====== [2025-07-16T05:45:22.315Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-07-16T05:45:22.315Z] GC before operation: completed in 667.309 ms, heap usage 177.025 MB -> 71.469 MB. [2025-07-16T05:45:32.465Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (10961.601 ms) ====== [2025-07-16T05:45:32.465Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-07-16T05:45:34.138Z] GC before operation: completed in 881.768 ms, heap usage 361.135 MB -> 72.068 MB. [2025-07-16T05:45:46.045Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (11646.519 ms) ====== [2025-07-16T05:45:46.045Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-07-16T05:45:46.045Z] GC before operation: completed in 759.886 ms, heap usage 118.819 MB -> 82.199 MB. [2025-07-16T05:45:57.968Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (12048.431 ms) ====== [2025-07-16T05:45:57.968Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-07-16T05:45:58.764Z] GC before operation: completed in 675.839 ms, heap usage 363.872 MB -> 72.019 MB. [2025-07-16T05:46:09.586Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (10694.481 ms) ====== [2025-07-16T05:46:09.586Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-07-16T05:46:10.372Z] GC before operation: completed in 868.663 ms, heap usage 308.994 MB -> 72.203 MB. [2025-07-16T05:46:20.561Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (10418.370 ms) ====== [2025-07-16T05:46:20.561Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-07-16T05:46:21.376Z] GC before operation: completed in 790.928 ms, heap usage 152.998 MB -> 71.974 MB. [2025-07-16T05:46:31.621Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (10248.958 ms) ====== [2025-07-16T05:46:31.621Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-07-16T05:46:32.415Z] GC before operation: completed in 700.819 ms, heap usage 355.242 MB -> 72.295 MB. [2025-07-16T05:46:42.690Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (9654.237 ms) ====== [2025-07-16T05:46:42.690Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-07-16T05:46:42.690Z] GC before operation: completed in 691.052 ms, heap usage 308.371 MB -> 72.377 MB. [2025-07-16T05:46:54.610Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (10404.691 ms) ====== [2025-07-16T05:46:54.610Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-07-16T05:46:54.610Z] GC before operation: completed in 823.286 ms, heap usage 121.067 MB -> 71.864 MB. [2025-07-16T05:47:06.733Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (11076.961 ms) ====== [2025-07-16T05:47:06.733Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-07-16T05:47:06.733Z] GC before operation: completed in 773.370 ms, heap usage 247.451 MB -> 72.584 MB. [2025-07-16T05:47:17.038Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (10584.696 ms) ====== [2025-07-16T05:47:17.038Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-07-16T05:47:17.824Z] GC before operation: completed in 794.688 ms, heap usage 119.239 MB -> 72.268 MB. [2025-07-16T05:47:27.944Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (10438.622 ms) ====== [2025-07-16T05:47:27.944Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-07-16T05:47:28.745Z] GC before operation: completed in 776.699 ms, heap usage 162.982 MB -> 72.608 MB. [2025-07-16T05:47:40.684Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (11177.942 ms) ====== [2025-07-16T05:47:40.684Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-07-16T05:47:40.684Z] GC before operation: completed in 718.321 ms, heap usage 181.147 MB -> 72.785 MB. [2025-07-16T05:47:50.798Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (10175.270 ms) ====== [2025-07-16T05:47:50.798Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-07-16T05:47:51.591Z] GC before operation: completed in 963.529 ms, heap usage 345.907 MB -> 73.240 MB. [2025-07-16T05:48:01.911Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (9945.848 ms) ====== [2025-07-16T05:48:01.911Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-07-16T05:48:02.800Z] GC before operation: completed in 838.855 ms, heap usage 417.994 MB -> 73.342 MB. [2025-07-16T05:48:13.045Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (10171.428 ms) ====== [2025-07-16T05:48:13.045Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-07-16T05:48:13.852Z] GC before operation: completed in 806.805 ms, heap usage 397.474 MB -> 73.296 MB. [2025-07-16T05:48:23.984Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (9820.911 ms) ====== [2025-07-16T05:48:23.984Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-07-16T05:48:23.984Z] GC before operation: completed in 855.282 ms, heap usage 408.470 MB -> 73.550 MB. [2025-07-16T05:48:33.117Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (9155.745 ms) ====== [2025-07-16T05:48:33.117Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-07-16T05:48:33.928Z] GC before operation: completed in 704.001 ms, heap usage 406.491 MB -> 73.591 MB. [2025-07-16T05:48:44.144Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (9934.156 ms) ====== [2025-07-16T05:48:44.144Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-07-16T05:48:44.955Z] GC before operation: completed in 678.493 ms, heap usage 396.482 MB -> 73.583 MB. [2025-07-16T05:48:55.351Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (10578.262 ms) ====== [2025-07-16T05:48:55.351Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-07-16T05:48:56.157Z] GC before operation: completed in 913.904 ms, heap usage 409.815 MB -> 85.033 MB. [2025-07-16T05:48:56.157Z] 05:48:55.747 WARN [block-manager-storage-async-thread-pool-279] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1725_0, which does not exist [2025-07-16T05:49:08.227Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (11577.099 ms) ====== [2025-07-16T05:49:08.227Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-07-16T05:49:09.047Z] GC before operation: completed in 958.400 ms, heap usage 287.734 MB -> 73.997 MB. [2025-07-16T05:49:19.195Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (10025.546 ms) ====== [2025-07-16T05:49:19.195Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-07-16T05:49:19.195Z] GC before operation: completed in 835.751 ms, heap usage 405.096 MB -> 74.348 MB. [2025-07-16T05:49:29.371Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (10107.254 ms) ====== [2025-07-16T05:49:29.371Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-07-16T05:49:30.163Z] GC before operation: completed in 820.555 ms, heap usage 274.842 MB -> 74.188 MB. [2025-07-16T05:49:40.266Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (9689.204 ms) ====== [2025-07-16T05:49:41.898Z] ----------------------------------- [2025-07-16T05:49:41.898Z] renaissance-dec-tree_0_PASSED [2025-07-16T05:49:41.898Z] ----------------------------------- [2025-07-16T05:49:41.898Z] [2025-07-16T05:49:41.898Z] TEST TEARDOWN: [2025-07-16T05:49:41.898Z] Nothing to be done for teardown. [2025-07-16T05:49:41.898Z] renaissance-dec-tree_0 Finish Time: Wed Jul 16 05:49:41 2025 Epoch Time (ms): 1752644981351