[2025-09-26T13:42:27.980Z] Running test renaissance-dec-tree_0 ... [2025-09-26T13:42:27.980Z] =============================================== [2025-09-26T13:42:27.980Z] renaissance-dec-tree_0 Start Time: Fri Sep 26 13:42:27 2025 Epoch Time (ms): 1758894147320 [2025-09-26T13:42:27.980Z] variation: NoOptions [2025-09-26T13:42:27.980Z] JVM_OPTIONS: [2025-09-26T13:42:27.980Z] { \ [2025-09-26T13:42:27.980Z] echo ""; echo "TEST SETUP:"; \ [2025-09-26T13:42:27.980Z] echo "Nothing to be done for setup."; \ [2025-09-26T13:42:27.980Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/renaissance-dec-tree_0"; \ [2025-09-26T13:42:27.980Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/renaissance-dec-tree_0"; \ [2025-09-26T13:42:27.980Z] echo ""; echo "TESTING:"; \ [2025-09-26T13:42:27.980Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-09-26T13:42:27.980Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-26T13:42:27.980Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-26T13:42:27.980Z] echo "Nothing to be done for teardown."; \ [2025-09-26T13:42:27.980Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/TestTargetResult"; [2025-09-26T13:42:27.980Z] [2025-09-26T13:42:27.980Z] TEST SETUP: [2025-09-26T13:42:27.980Z] Nothing to be done for setup. [2025-09-26T13:42:27.980Z] [2025-09-26T13:42:27.980Z] TESTING: [2025-09-26T13:42:40.716Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-09-26T13:42:41.019Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-09-26T13:42:41.019Z] GC before operation: completed in 37.891 ms, heap usage 54.607 MB -> 34.772 MB. [2025-09-26T13:42:53.780Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (12715.474 ms) ====== [2025-09-26T13:42:53.780Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-09-26T13:42:53.780Z] GC before operation: completed in 85.814 ms, heap usage 81.334 MB -> 67.774 MB. [2025-09-26T13:42:58.308Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3689.227 ms) ====== [2025-09-26T13:42:58.308Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-09-26T13:42:58.308Z] GC before operation: completed in 114.264 ms, heap usage 129.365 MB -> 67.610 MB. [2025-09-26T13:43:00.435Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2712.697 ms) ====== [2025-09-26T13:43:00.435Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-09-26T13:43:00.435Z] GC before operation: completed in 91.608 ms, heap usage 291.203 MB -> 68.457 MB. [2025-09-26T13:43:03.223Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2459.050 ms) ====== [2025-09-26T13:43:03.223Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-09-26T13:43:03.223Z] GC before operation: completed in 77.753 ms, heap usage 285.221 MB -> 68.695 MB. [2025-09-26T13:43:05.383Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2308.387 ms) ====== [2025-09-26T13:43:05.383Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-09-26T13:43:05.383Z] GC before operation: completed in 91.563 ms, heap usage 288.367 MB -> 68.894 MB. [2025-09-26T13:43:07.505Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2089.598 ms) ====== [2025-09-26T13:43:07.505Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-09-26T13:43:07.505Z] GC before operation: completed in 82.584 ms, heap usage 270.150 MB -> 69.093 MB. [2025-09-26T13:43:09.620Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1963.620 ms) ====== [2025-09-26T13:43:09.620Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-09-26T13:43:09.620Z] GC before operation: completed in 89.394 ms, heap usage 227.098 MB -> 69.287 MB. [2025-09-26T13:43:11.738Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1932.816 ms) ====== [2025-09-26T13:43:11.738Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-09-26T13:43:11.738Z] GC before operation: completed in 85.708 ms, heap usage 113.767 MB -> 68.840 MB. [2025-09-26T13:43:13.855Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1833.665 ms) ====== [2025-09-26T13:43:13.855Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-09-26T13:43:13.855Z] GC before operation: completed in 83.730 ms, heap usage 224.747 MB -> 69.439 MB. [2025-09-26T13:43:15.403Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1823.506 ms) ====== [2025-09-26T13:43:15.403Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-09-26T13:43:15.403Z] GC before operation: completed in 87.592 ms, heap usage 144.097 MB -> 69.396 MB. [2025-09-26T13:43:17.513Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1895.926 ms) ====== [2025-09-26T13:43:17.513Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-09-26T13:43:17.513Z] GC before operation: completed in 90.574 ms, heap usage 362.446 MB -> 70.089 MB. [2025-09-26T13:43:19.633Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1809.601 ms) ====== [2025-09-26T13:43:19.633Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-09-26T13:43:19.633Z] GC before operation: completed in 102.874 ms, heap usage 235.487 MB -> 69.988 MB. [2025-09-26T13:43:21.180Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1729.555 ms) ====== [2025-09-26T13:43:21.180Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-09-26T13:43:21.180Z] GC before operation: completed in 94.105 ms, heap usage 110.568 MB -> 69.433 MB. [2025-09-26T13:43:22.726Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1604.512 ms) ====== [2025-09-26T13:43:22.726Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-09-26T13:43:23.036Z] GC before operation: completed in 91.484 ms, heap usage 225.230 MB -> 70.189 MB. [2025-09-26T13:43:24.630Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1740.473 ms) ====== [2025-09-26T13:43:24.630Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-09-26T13:43:24.630Z] GC before operation: completed in 92.282 ms, heap usage 112.597 MB -> 69.721 MB. [2025-09-26T13:43:26.767Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1784.150 ms) ====== [2025-09-26T13:43:26.767Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-09-26T13:43:26.767Z] GC before operation: completed in 100.694 ms, heap usage 200.168 MB -> 70.468 MB. [2025-09-26T13:43:28.312Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1573.425 ms) ====== [2025-09-26T13:43:28.312Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-09-26T13:43:28.312Z] GC before operation: completed in 103.152 ms, heap usage 389.918 MB -> 70.476 MB. [2025-09-26T13:43:29.858Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1669.759 ms) ====== [2025-09-26T13:43:29.858Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-09-26T13:43:30.159Z] GC before operation: completed in 97.641 ms, heap usage 382.300 MB -> 70.551 MB. [2025-09-26T13:43:31.708Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1616.091 ms) ====== [2025-09-26T13:43:31.708Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-09-26T13:43:31.708Z] GC before operation: completed in 92.262 ms, heap usage 148.507 MB -> 70.500 MB. [2025-09-26T13:43:33.261Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1468.007 ms) ====== [2025-09-26T13:43:33.261Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-09-26T13:43:33.261Z] GC before operation: completed in 96.278 ms, heap usage 328.870 MB -> 71.200 MB. [2025-09-26T13:43:34.807Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1452.961 ms) ====== [2025-09-26T13:43:34.807Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-09-26T13:43:34.807Z] GC before operation: completed in 106.587 ms, heap usage 386.755 MB -> 98.418 MB. [2025-09-26T13:43:36.356Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1538.822 ms) ====== [2025-09-26T13:43:36.356Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-09-26T13:43:36.658Z] GC before operation: completed in 101.180 ms, heap usage 232.529 MB -> 71.117 MB. [2025-09-26T13:43:38.200Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1469.325 ms) ====== [2025-09-26T13:43:38.200Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-09-26T13:43:38.200Z] GC before operation: completed in 97.390 ms, heap usage 255.300 MB -> 72.416 MB. [2025-09-26T13:43:39.744Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1622.498 ms) ====== [2025-09-26T13:43:39.744Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-09-26T13:43:39.744Z] GC before operation: completed in 101.445 ms, heap usage 388.444 MB -> 71.185 MB. [2025-09-26T13:43:41.292Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1536.600 ms) ====== [2025-09-26T13:43:41.292Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-09-26T13:43:41.594Z] GC before operation: completed in 107.463 ms, heap usage 387.567 MB -> 79.619 MB. [2025-09-26T13:43:43.138Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1553.616 ms) ====== [2025-09-26T13:43:43.138Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-09-26T13:43:43.138Z] GC before operation: completed in 96.107 ms, heap usage 247.074 MB -> 71.532 MB. [2025-09-26T13:43:44.679Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1448.311 ms) ====== [2025-09-26T13:43:44.679Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-09-26T13:43:44.679Z] GC before operation: completed in 101.827 ms, heap usage 390.588 MB -> 79.623 MB. [2025-09-26T13:43:46.224Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1499.389 ms) ====== [2025-09-26T13:43:46.224Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-09-26T13:43:46.224Z] GC before operation: completed in 111.226 ms, heap usage 243.257 MB -> 71.743 MB. [2025-09-26T13:43:47.839Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1611.036 ms) ====== [2025-09-26T13:43:47.839Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-09-26T13:43:48.139Z] GC before operation: completed in 107.571 ms, heap usage 378.714 MB -> 71.729 MB. [2025-09-26T13:43:49.693Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1599.959 ms) ====== [2025-09-26T13:43:49.693Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-09-26T13:43:49.693Z] GC before operation: completed in 123.200 ms, heap usage 368.442 MB -> 91.341 MB. [2025-09-26T13:43:49.693Z] 13:43:49.593 WARN [block-manager-storage-async-thread-pool-82] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1437_0, which does not exist [2025-09-26T13:43:51.237Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1472.537 ms) ====== [2025-09-26T13:43:51.237Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-09-26T13:43:51.237Z] GC before operation: completed in 113.411 ms, heap usage 459.768 MB -> 91.484 MB. [2025-09-26T13:43:52.778Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1506.897 ms) ====== [2025-09-26T13:43:52.779Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-09-26T13:43:53.082Z] GC before operation: completed in 104.966 ms, heap usage 446.765 MB -> 72.240 MB. [2025-09-26T13:43:54.623Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1505.051 ms) ====== [2025-09-26T13:43:54.623Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-09-26T13:43:54.623Z] GC before operation: completed in 108.649 ms, heap usage 373.056 MB -> 72.361 MB. [2025-09-26T13:43:56.171Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1564.392 ms) ====== [2025-09-26T13:43:56.171Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-09-26T13:43:56.171Z] GC before operation: completed in 106.593 ms, heap usage 364.977 MB -> 91.563 MB. [2025-09-26T13:43:57.733Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1578.300 ms) ====== [2025-09-26T13:43:57.733Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-09-26T13:43:58.039Z] GC before operation: completed in 110.360 ms, heap usage 427.801 MB -> 72.475 MB. [2025-09-26T13:43:59.578Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1438.985 ms) ====== [2025-09-26T13:43:59.578Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-09-26T13:43:59.578Z] GC before operation: completed in 106.897 ms, heap usage 348.087 MB -> 91.673 MB. [2025-09-26T13:44:01.118Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1466.228 ms) ====== [2025-09-26T13:44:01.118Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-09-26T13:44:01.118Z] GC before operation: completed in 107.513 ms, heap usage 444.673 MB -> 81.119 MB. [2025-09-26T13:44:02.666Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1412.800 ms) ====== [2025-09-26T13:44:02.666Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-09-26T13:44:02.666Z] GC before operation: completed in 103.554 ms, heap usage 256.366 MB -> 72.687 MB. [2025-09-26T13:44:04.212Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1433.658 ms) ====== [2025-09-26T13:44:04.212Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-09-26T13:44:04.212Z] GC before operation: completed in 109.713 ms, heap usage 367.012 MB -> 92.279 MB. [2025-09-26T13:44:05.757Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1423.928 ms) ====== [2025-09-26T13:44:05.757Z] ----------------------------------- [2025-09-26T13:44:05.757Z] renaissance-dec-tree_0_PASSED [2025-09-26T13:44:05.757Z] ----------------------------------- [2025-09-26T13:44:05.757Z] [2025-09-26T13:44:05.757Z] TEST TEARDOWN: [2025-09-26T13:44:05.757Z] Nothing to be done for teardown. [2025-09-26T13:44:05.757Z] renaissance-dec-tree_0 Finish Time: Fri Sep 26 13:44:05 2025 Epoch Time (ms): 1758894245615