[2025-08-25T21:38:44.943Z] Running test renaissance-dec-tree_0 ... [2025-08-25T21:38:44.943Z] =============================================== [2025-08-25T21:38:44.943Z] renaissance-dec-tree_0 Start Time: Mon Aug 25 21:38:44 2025 Epoch Time (ms): 1756157924350 [2025-08-25T21:38:44.943Z] variation: NoOptions [2025-08-25T21:38:44.943Z] JVM_OPTIONS: [2025-08-25T21:38:44.943Z] { \ [2025-08-25T21:38:44.943Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T21:38:44.943Z] echo "Nothing to be done for setup."; \ [2025-08-25T21:38:44.943Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-dec-tree_0"; \ [2025-08-25T21:38:44.943Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-dec-tree_0"; \ [2025-08-25T21:38:44.943Z] echo ""; echo "TESTING:"; \ [2025-08-25T21:38:44.943Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-25T21:38:44.943Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T21:38:44.943Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T21:38:44.943Z] echo "Nothing to be done for teardown."; \ [2025-08-25T21:38:44.943Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17561573814621/TestTargetResult"; [2025-08-25T21:38:44.943Z] [2025-08-25T21:38:44.943Z] TEST SETUP: [2025-08-25T21:38:44.943Z] Nothing to be done for setup. [2025-08-25T21:38:44.943Z] [2025-08-25T21:38:44.943Z] TESTING: [2025-08-25T21:38:53.261Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2025-08-25T21:38:54.859Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T21:38:54.859Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17561573814621/renaissance-dec-tree_0/harness-213845-4791037096424594718/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T21:38:54.859Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T21:38:54.859Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T21:38:54.859Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T21:38:57.352Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-25T21:38:57.352Z] GC before operation: completed in 91.271 ms, heap usage 94.368 MB -> 35.131 MB. [2025-08-25T21:39:09.086Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11684.495 ms) ====== [2025-08-25T21:39:09.086Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-25T21:39:09.086Z] GC before operation: completed in 112.297 ms, heap usage 409.445 MB -> 71.232 MB. [2025-08-25T21:39:11.921Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2764.347 ms) ====== [2025-08-25T21:39:11.921Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-25T21:39:11.921Z] GC before operation: completed in 99.574 ms, heap usage 267.364 MB -> 71.433 MB. [2025-08-25T21:39:14.408Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2444.826 ms) ====== [2025-08-25T21:39:14.409Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-25T21:39:14.409Z] GC before operation: completed in 108.405 ms, heap usage 275.427 MB -> 71.830 MB. [2025-08-25T21:39:16.901Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2383.566 ms) ====== [2025-08-25T21:39:16.901Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-25T21:39:16.901Z] GC before operation: completed in 122.940 ms, heap usage 249.962 MB -> 72.066 MB. [2025-08-25T21:39:19.390Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2321.846 ms) ====== [2025-08-25T21:39:19.390Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-25T21:39:19.390Z] GC before operation: completed in 118.260 ms, heap usage 300.151 MB -> 72.271 MB. [2025-08-25T21:39:21.881Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2253.027 ms) ====== [2025-08-25T21:39:21.881Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-25T21:39:21.881Z] GC before operation: completed in 112.138 ms, heap usage 300.150 MB -> 72.490 MB. [2025-08-25T21:39:24.370Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2138.566 ms) ====== [2025-08-25T21:39:24.370Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-25T21:39:24.370Z] GC before operation: completed in 128.141 ms, heap usage 301.021 MB -> 72.691 MB. [2025-08-25T21:39:25.978Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2160.586 ms) ====== [2025-08-25T21:39:25.978Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-25T21:39:26.754Z] GC before operation: completed in 130.393 ms, heap usage 246.427 MB -> 100.259 MB. [2025-08-25T21:39:26.754Z] 21:39:25.940 WARN [block-manager-storage-async-thread-pool-20] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_107, which does not exist [2025-08-25T21:39:28.370Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2127.070 ms) ====== [2025-08-25T21:39:28.370Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-25T21:39:28.370Z] GC before operation: completed in 147.496 ms, heap usage 420.274 MB -> 72.957 MB. [2025-08-25T21:39:30.854Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2196.923 ms) ====== [2025-08-25T21:39:30.854Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-25T21:39:30.854Z] GC before operation: completed in 134.599 ms, heap usage 196.727 MB -> 72.758 MB. [2025-08-25T21:39:33.344Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2083.648 ms) ====== [2025-08-25T21:39:33.344Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-25T21:39:33.344Z] GC before operation: completed in 155.492 ms, heap usage 249.075 MB -> 73.409 MB. [2025-08-25T21:39:35.835Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2118.039 ms) ====== [2025-08-25T21:39:35.836Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-25T21:39:35.836Z] GC before operation: completed in 143.160 ms, heap usage 260.909 MB -> 73.304 MB. [2025-08-25T21:39:37.445Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2072.943 ms) ====== [2025-08-25T21:39:37.445Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-25T21:39:37.445Z] GC before operation: completed in 142.467 ms, heap usage 124.151 MB -> 72.647 MB. [2025-08-25T21:39:39.940Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2062.675 ms) ====== [2025-08-25T21:39:39.940Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-25T21:39:39.940Z] GC before operation: completed in 136.254 ms, heap usage 271.191 MB -> 73.797 MB. [2025-08-25T21:39:42.608Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2033.162 ms) ====== [2025-08-25T21:39:42.608Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-25T21:39:42.608Z] GC before operation: completed in 137.096 ms, heap usage 274.496 MB -> 73.657 MB. [2025-08-25T21:39:44.213Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2040.634 ms) ====== [2025-08-25T21:39:44.213Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-25T21:39:44.213Z] GC before operation: completed in 149.548 ms, heap usage 292.545 MB -> 73.827 MB. [2025-08-25T21:39:46.708Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2020.295 ms) ====== [2025-08-25T21:39:46.708Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-25T21:39:46.708Z] GC before operation: completed in 139.565 ms, heap usage 276.848 MB -> 74.153 MB. [2025-08-25T21:39:48.314Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2038.264 ms) ====== [2025-08-25T21:39:48.314Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-25T21:39:48.314Z] GC before operation: completed in 139.969 ms, heap usage 218.169 MB -> 73.903 MB. [2025-08-25T21:39:50.801Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2013.436 ms) ====== [2025-08-25T21:39:50.801Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-25T21:39:50.801Z] GC before operation: completed in 157.334 ms, heap usage 286.564 MB -> 85.289 MB. [2025-08-25T21:39:53.644Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2030.192 ms) ====== [2025-08-25T21:39:53.644Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-25T21:39:53.644Z] GC before operation: completed in 163.363 ms, heap usage 244.084 MB -> 74.103 MB. [2025-08-25T21:39:55.249Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1981.241 ms) ====== [2025-08-25T21:39:55.249Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-25T21:39:55.249Z] GC before operation: completed in 156.675 ms, heap usage 317.185 MB -> 79.771 MB. [2025-08-25T21:39:56.859Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1989.931 ms) ====== [2025-08-25T21:39:56.859Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-25T21:39:57.633Z] GC before operation: completed in 157.413 ms, heap usage 295.379 MB -> 79.885 MB. [2025-08-25T21:39:59.242Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1967.945 ms) ====== [2025-08-25T21:39:59.243Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-25T21:39:59.243Z] GC before operation: completed in 160.795 ms, heap usage 308.730 MB -> 85.717 MB. [2025-08-25T21:40:01.750Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1997.264 ms) ====== [2025-08-25T21:40:01.750Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-25T21:40:01.750Z] GC before operation: completed in 150.067 ms, heap usage 377.838 MB -> 85.840 MB. [2025-08-25T21:40:03.348Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1990.048 ms) ====== [2025-08-25T21:40:03.348Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-25T21:40:03.348Z] GC before operation: completed in 163.880 ms, heap usage 335.637 MB -> 80.204 MB. [2025-08-25T21:40:05.837Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2005.374 ms) ====== [2025-08-25T21:40:05.837Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-25T21:40:05.837Z] GC before operation: completed in 155.974 ms, heap usage 243.822 MB -> 102.310 MB. [2025-08-25T21:40:05.837Z] 21:40:05.412 WARN [block-manager-storage-async-thread-pool-70] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1245_1, which does not exist [2025-08-25T21:40:08.343Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1964.044 ms) ====== [2025-08-25T21:40:08.343Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-25T21:40:08.343Z] GC before operation: completed in 183.929 ms, heap usage 396.162 MB -> 85.871 MB. [2025-08-25T21:40:09.943Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2004.289 ms) ====== [2025-08-25T21:40:09.943Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-25T21:40:09.943Z] GC before operation: completed in 156.186 ms, heap usage 276.126 MB -> 75.318 MB. [2025-08-25T21:40:12.426Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2032.100 ms) ====== [2025-08-25T21:40:12.426Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-25T21:40:12.426Z] GC before operation: completed in 154.834 ms, heap usage 315.118 MB -> 86.324 MB. [2025-08-25T21:40:14.177Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1930.624 ms) ====== [2025-08-25T21:40:14.177Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-25T21:40:14.955Z] GC before operation: completed in 167.990 ms, heap usage 322.221 MB -> 86.436 MB. [2025-08-25T21:40:16.557Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1948.431 ms) ====== [2025-08-25T21:40:16.557Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-25T21:40:16.557Z] GC before operation: completed in 160.320 ms, heap usage 357.298 MB -> 91.744 MB. [2025-08-25T21:40:18.158Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1910.428 ms) ====== [2025-08-25T21:40:18.158Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-25T21:40:18.941Z] GC before operation: completed in 160.894 ms, heap usage 167.237 MB -> 85.937 MB. [2025-08-25T21:40:20.544Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1946.985 ms) ====== [2025-08-25T21:40:20.544Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-25T21:40:20.544Z] GC before operation: completed in 167.574 ms, heap usage 242.897 MB -> 75.600 MB. [2025-08-25T21:40:23.030Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1940.737 ms) ====== [2025-08-25T21:40:23.030Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-25T21:40:23.030Z] GC before operation: completed in 173.496 ms, heap usage 266.056 MB -> 86.491 MB. [2025-08-25T21:40:24.641Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1958.165 ms) ====== [2025-08-25T21:40:24.641Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-25T21:40:24.641Z] GC before operation: completed in 163.812 ms, heap usage 196.135 MB -> 80.819 MB. [2025-08-25T21:40:27.134Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1929.994 ms) ====== [2025-08-25T21:40:27.134Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-25T21:40:27.134Z] GC before operation: completed in 178.095 ms, heap usage 341.442 MB -> 87.109 MB. [2025-08-25T21:40:28.737Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1872.462 ms) ====== [2025-08-25T21:40:28.737Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-25T21:40:29.511Z] GC before operation: completed in 187.367 ms, heap usage 322.469 MB -> 87.028 MB. [2025-08-25T21:40:31.111Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1984.359 ms) ====== [2025-08-25T21:40:31.111Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-25T21:40:31.111Z] GC before operation: completed in 171.927 ms, heap usage 125.801 MB -> 75.573 MB. [2025-08-25T21:40:33.594Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1924.544 ms) ====== [2025-08-25T21:40:33.594Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-25T21:40:33.594Z] GC before operation: completed in 182.334 ms, heap usage 307.855 MB -> 87.419 MB. [2025-08-25T21:40:35.612Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1843.015 ms) ====== [2025-08-25T21:40:35.612Z] ----------------------------------- [2025-08-25T21:40:35.612Z] renaissance-dec-tree_0_PASSED [2025-08-25T21:40:35.613Z] ----------------------------------- [2025-08-25T21:40:35.613Z] [2025-08-25T21:40:35.613Z] TEST TEARDOWN: [2025-08-25T21:40:35.613Z] Nothing to be done for teardown. [2025-08-25T21:40:35.613Z] renaissance-dec-tree_0 Finish Time: Mon Aug 25 21:40:35 2025 Epoch Time (ms): 1756158035307