[2025-08-25T22:31:41.781Z] Running test renaissance-dec-tree_0 ... [2025-08-25T22:31:41.781Z] =============================================== [2025-08-25T22:31:41.781Z] renaissance-dec-tree_0 Start Time: Mon Aug 25 22:31:41 2025 Epoch Time (ms): 1756161101160 [2025-08-25T22:31:41.781Z] variation: NoOptions [2025-08-25T22:31:41.781Z] JVM_OPTIONS: [2025-08-25T22:31:41.781Z] { \ [2025-08-25T22:31:41.781Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T22:31:41.781Z] echo "Nothing to be done for setup."; \ [2025-08-25T22:31:41.781Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17561604265130/renaissance-dec-tree_0"; \ [2025-08-25T22:31:41.781Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17561604265130/renaissance-dec-tree_0"; \ [2025-08-25T22:31:41.781Z] echo ""; echo "TESTING:"; \ [2025-08-25T22:31:41.781Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17561604265130/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-25T22:31:41.781Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17561604265130/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T22:31:41.781Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T22:31:41.781Z] echo "Nothing to be done for teardown."; \ [2025-08-25T22:31:41.781Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17561604265130/TestTargetResult"; [2025-08-25T22:31:41.781Z] [2025-08-25T22:31:41.781Z] TEST SETUP: [2025-08-25T22:31:41.781Z] Nothing to be done for setup. [2025-08-25T22:31:41.781Z] [2025-08-25T22:31:41.781Z] TESTING: [2025-08-25T22:31:52.257Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-25T22:31:53.035Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T22:31:53.035Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17561604265130/renaissance-dec-tree_0/harness-223142-16878362759708241301/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T22:31:53.035Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T22:31:53.035Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T22:31:53.035Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T22:31:55.518Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-25T22:31:55.518Z] GC before operation: completed in 80.858 ms, heap usage 77.361 MB -> 34.384 MB. [2025-08-25T22:32:17.646Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (20930.985 ms) ====== [2025-08-25T22:32:17.646Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-25T22:32:17.646Z] GC before operation: completed in 294.330 ms, heap usage 314.461 MB -> 68.319 MB. [2025-08-25T22:32:20.148Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3914.378 ms) ====== [2025-08-25T22:32:20.148Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-25T22:32:20.927Z] GC before operation: completed in 189.027 ms, heap usage 176.744 MB -> 68.620 MB. [2025-08-25T22:32:27.857Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (7552.272 ms) ====== [2025-08-25T22:32:27.857Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-25T22:32:28.633Z] GC before operation: completed in 149.765 ms, heap usage 229.987 MB -> 68.912 MB. [2025-08-25T22:32:34.263Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5485.357 ms) ====== [2025-08-25T22:32:34.263Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-25T22:32:34.263Z] GC before operation: completed in 126.838 ms, heap usage 212.907 MB -> 69.254 MB. [2025-08-25T22:32:37.719Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3608.620 ms) ====== [2025-08-25T22:32:37.719Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-25T22:32:37.719Z] GC before operation: completed in 323.912 ms, heap usage 187.428 MB -> 69.460 MB. [2025-08-25T22:32:41.380Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2989.867 ms) ====== [2025-08-25T22:32:41.380Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-25T22:32:41.380Z] GC before operation: completed in 154.567 ms, heap usage 105.307 MB -> 69.364 MB. [2025-08-25T22:32:43.885Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2822.730 ms) ====== [2025-08-25T22:32:43.885Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-25T22:32:43.885Z] GC before operation: completed in 254.192 ms, heap usage 280.299 MB -> 81.020 MB. [2025-08-25T22:32:46.374Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2484.303 ms) ====== [2025-08-25T22:32:46.374Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-25T22:32:47.150Z] GC before operation: completed in 143.772 ms, heap usage 279.943 MB -> 80.751 MB. [2025-08-25T22:32:48.750Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1758.699 ms) ====== [2025-08-25T22:32:48.750Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-25T22:32:48.750Z] GC before operation: completed in 177.736 ms, heap usage 391.309 MB -> 69.975 MB. [2025-08-25T22:32:51.249Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2071.046 ms) ====== [2025-08-25T22:32:51.249Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-25T22:32:51.249Z] GC before operation: completed in 226.268 ms, heap usage 213.248 MB -> 70.105 MB. [2025-08-25T22:32:52.856Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2149.797 ms) ====== [2025-08-25T22:32:52.856Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-25T22:32:53.635Z] GC before operation: completed in 132.307 ms, heap usage 119.196 MB -> 70.035 MB. [2025-08-25T22:32:55.227Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1692.766 ms) ====== [2025-08-25T22:32:55.227Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-25T22:32:55.227Z] GC before operation: completed in 135.521 ms, heap usage 165.890 MB -> 97.626 MB. [2025-08-25T22:32:55.227Z] 22:32:54.680 WARN [block-manager-storage-async-thread-pool-94] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_163, which does not exist [2025-08-25T22:33:00.303Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (5541.812 ms) ====== [2025-08-25T22:33:00.303Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-25T22:33:01.076Z] GC before operation: completed in 200.064 ms, heap usage 169.235 MB -> 81.401 MB. [2025-08-25T22:33:02.727Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2027.089 ms) ====== [2025-08-25T22:33:02.727Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-25T22:33:02.727Z] GC before operation: completed in 129.539 ms, heap usage 131.225 MB -> 70.425 MB. [2025-08-25T22:33:05.208Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1706.624 ms) ====== [2025-08-25T22:33:05.208Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-25T22:33:05.208Z] GC before operation: completed in 191.553 ms, heap usage 251.216 MB -> 70.701 MB. [2025-08-25T22:33:07.734Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2544.889 ms) ====== [2025-08-25T22:33:07.734Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-25T22:33:07.734Z] GC before operation: completed in 220.583 ms, heap usage 146.183 MB -> 70.667 MB. [2025-08-25T22:33:10.233Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2106.520 ms) ====== [2025-08-25T22:33:10.233Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-25T22:33:10.233Z] GC before operation: completed in 193.994 ms, heap usage 333.584 MB -> 93.109 MB. [2025-08-25T22:33:12.724Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2422.788 ms) ====== [2025-08-25T22:33:12.724Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-25T22:33:12.724Z] GC before operation: completed in 174.405 ms, heap usage 289.332 MB -> 71.066 MB. [2025-08-25T22:33:14.341Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1981.054 ms) ====== [2025-08-25T22:33:14.341Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-25T22:33:15.192Z] GC before operation: completed in 215.502 ms, heap usage 228.625 MB -> 71.174 MB. [2025-08-25T22:33:17.686Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2770.757 ms) ====== [2025-08-25T22:33:17.687Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-25T22:33:17.687Z] GC before operation: completed in 175.227 ms, heap usage 248.582 MB -> 82.524 MB. [2025-08-25T22:33:20.180Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1960.168 ms) ====== [2025-08-25T22:33:20.180Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-25T22:33:20.180Z] GC before operation: completed in 251.289 ms, heap usage 174.234 MB -> 71.501 MB. [2025-08-25T22:33:22.670Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2362.678 ms) ====== [2025-08-25T22:33:22.670Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-25T22:33:22.670Z] GC before operation: completed in 155.044 ms, heap usage 141.301 MB -> 87.829 MB. [2025-08-25T22:33:24.285Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2018.465 ms) ====== [2025-08-25T22:33:24.285Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-25T22:33:25.067Z] GC before operation: completed in 256.365 ms, heap usage 222.119 MB -> 99.178 MB. [2025-08-25T22:33:26.668Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2285.557 ms) ====== [2025-08-25T22:33:26.668Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-25T22:33:27.438Z] GC before operation: completed in 151.557 ms, heap usage 242.954 MB -> 82.795 MB. [2025-08-25T22:33:29.037Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1604.804 ms) ====== [2025-08-25T22:33:29.037Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-25T22:33:29.037Z] GC before operation: completed in 198.883 ms, heap usage 332.362 MB -> 71.791 MB. [2025-08-25T22:33:31.537Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2439.610 ms) ====== [2025-08-25T22:33:31.537Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-25T22:33:31.537Z] GC before operation: completed in 188.650 ms, heap usage 220.461 MB -> 83.198 MB. [2025-08-25T22:33:33.233Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1801.004 ms) ====== [2025-08-25T22:33:33.233Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-25T22:33:34.013Z] GC before operation: completed in 223.924 ms, heap usage 305.179 MB -> 99.462 MB. [2025-08-25T22:33:36.529Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2436.673 ms) ====== [2025-08-25T22:33:36.529Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-25T22:33:36.529Z] GC before operation: completed in 349.053 ms, heap usage 195.365 MB -> 71.791 MB. [2025-08-25T22:33:39.022Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2183.562 ms) ====== [2025-08-25T22:33:39.022Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-25T22:33:39.022Z] GC before operation: completed in 182.836 ms, heap usage 232.013 MB -> 83.518 MB. [2025-08-25T22:33:41.531Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2237.188 ms) ====== [2025-08-25T22:33:41.531Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-25T22:33:41.531Z] GC before operation: completed in 185.307 ms, heap usage 118.627 MB -> 72.165 MB. [2025-08-25T22:33:43.144Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1979.822 ms) ====== [2025-08-25T22:33:43.144Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-25T22:33:43.144Z] GC before operation: completed in 256.453 ms, heap usage 177.051 MB -> 72.445 MB. [2025-08-25T22:33:45.649Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2555.326 ms) ====== [2025-08-25T22:33:45.649Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-25T22:33:46.428Z] GC before operation: completed in 290.801 ms, heap usage 234.260 MB -> 72.710 MB. [2025-08-25T22:33:48.098Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2107.291 ms) ====== [2025-08-25T22:33:48.098Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-25T22:33:49.419Z] GC before operation: completed in 212.322 ms, heap usage 217.888 MB -> 78.065 MB. [2025-08-25T22:33:51.023Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2255.826 ms) ====== [2025-08-25T22:33:51.023Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-25T22:33:51.023Z] GC before operation: completed in 204.508 ms, heap usage 229.296 MB -> 72.761 MB. [2025-08-25T22:33:52.624Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1755.230 ms) ====== [2025-08-25T22:33:52.624Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-25T22:33:53.414Z] GC before operation: completed in 229.956 ms, heap usage 161.569 MB -> 72.999 MB. [2025-08-25T22:33:55.907Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2689.048 ms) ====== [2025-08-25T22:33:55.907Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-25T22:33:55.907Z] GC before operation: completed in 314.387 ms, heap usage 216.565 MB -> 84.260 MB. [2025-08-25T22:33:55.907Z] 22:33:55.623 WARN [block-manager-storage-async-thread-pool-62] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1725_0, which does not exist [2025-08-25T22:33:57.511Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1760.960 ms) ====== [2025-08-25T22:33:57.511Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-25T22:33:58.287Z] GC before operation: completed in 205.777 ms, heap usage 226.682 MB -> 84.190 MB. [2025-08-25T22:34:00.781Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2232.900 ms) ====== [2025-08-25T22:34:00.781Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-25T22:34:00.781Z] GC before operation: completed in 200.836 ms, heap usage 249.228 MB -> 73.189 MB. [2025-08-25T22:34:03.279Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2477.687 ms) ====== [2025-08-25T22:34:03.279Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-25T22:34:03.279Z] GC before operation: completed in 348.260 ms, heap usage 219.087 MB -> 73.697 MB. [2025-08-25T22:34:05.769Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2505.656 ms) ====== [2025-08-25T22:34:06.545Z] ----------------------------------- [2025-08-25T22:34:06.545Z] renaissance-dec-tree_0_PASSED [2025-08-25T22:34:06.545Z] ----------------------------------- [2025-08-25T22:34:06.545Z] [2025-08-25T22:34:06.545Z] TEST TEARDOWN: [2025-08-25T22:34:06.545Z] Nothing to be done for teardown. [2025-08-25T22:34:06.545Z] renaissance-dec-tree_0 Finish Time: Mon Aug 25 22:34:05 2025 Epoch Time (ms): 1756161245894