[2025-02-26T22:46:32.025Z] Running test renaissance-dec-tree_0 ... [2025-02-26T22:46:32.025Z] =============================================== [2025-02-26T22:46:32.025Z] renaissance-dec-tree_0 Start Time: Wed Feb 26 16:46:32 2025 Epoch Time (ms): 1740609992124 [2025-02-26T22:46:32.025Z] variation: NoOptions [2025-02-26T22:46:32.025Z] JVM_OPTIONS: [2025-02-26T22:46:32.025Z] { \ [2025-02-26T22:46:32.025Z] echo ""; echo "TEST SETUP:"; \ [2025-02-26T22:46:32.025Z] echo "Nothing to be done for setup."; \ [2025-02-26T22:46:32.025Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17406095219659/renaissance-dec-tree_0"; \ [2025-02-26T22:46:32.025Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17406095219659/renaissance-dec-tree_0"; \ [2025-02-26T22:46:32.025Z] echo ""; echo "TESTING:"; \ [2025-02-26T22:46:32.025Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17406095219659/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-26T22:46:32.025Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17406095219659/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-26T22:46:32.025Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-26T22:46:32.025Z] echo "Nothing to be done for teardown."; \ [2025-02-26T22:46:32.025Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17406095219659/TestTargetResult"; [2025-02-26T22:46:32.025Z] [2025-02-26T22:46:32.025Z] TEST SETUP: [2025-02-26T22:46:32.025Z] Nothing to be done for setup. [2025-02-26T22:46:32.025Z] [2025-02-26T22:46:32.025Z] TESTING: [2025-02-26T22:46:35.109Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-26T22:46:37.324Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2025-02-26T22:46:38.011Z] WARNING: An illegal reflective access operation has occurred [2025-02-26T22:46:38.011Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17406095219659/renaissance-dec-tree_0/harness-164632-11445331818374590198/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-26T22:46:38.011Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-26T22:46:38.011Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-26T22:46:38.011Z] WARNING: All illegal access operations will be denied in a future release [2025-02-26T22:46:40.225Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-26T22:46:40.225Z] GC before operation: completed in 48.928 ms, heap usage 63.594 MB -> 34.340 MB. [2025-02-26T22:46:47.900Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8008.279 ms) ====== [2025-02-26T22:46:47.900Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-26T22:46:47.900Z] GC before operation: completed in 116.038 ms, heap usage 261.803 MB -> 64.277 MB. [2025-02-26T22:46:50.124Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1957.405 ms) ====== [2025-02-26T22:46:50.124Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-26T22:46:50.124Z] GC before operation: completed in 111.549 ms, heap usage 145.233 MB -> 64.567 MB. [2025-02-26T22:46:51.568Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1804.903 ms) ====== [2025-02-26T22:46:51.568Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-26T22:46:52.254Z] GC before operation: completed in 130.709 ms, heap usage 126.239 MB -> 64.789 MB. [2025-02-26T22:46:53.685Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1622.151 ms) ====== [2025-02-26T22:46:53.685Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-26T22:46:53.685Z] GC before operation: completed in 99.276 ms, heap usage 107.135 MB -> 64.532 MB. [2025-02-26T22:46:55.112Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1547.166 ms) ====== [2025-02-26T22:46:55.112Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-26T22:46:55.112Z] GC before operation: completed in 98.565 ms, heap usage 132.398 MB -> 65.159 MB. [2025-02-26T22:46:57.330Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1629.970 ms) ====== [2025-02-26T22:46:57.330Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-26T22:46:57.330Z] GC before operation: completed in 96.511 ms, heap usage 215.314 MB -> 76.786 MB. [2025-02-26T22:46:58.774Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1321.231 ms) ====== [2025-02-26T22:46:58.774Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-26T22:46:58.774Z] GC before operation: completed in 106.497 ms, heap usage 271.547 MB -> 65.717 MB. [2025-02-26T22:47:00.228Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1451.084 ms) ====== [2025-02-26T22:47:00.228Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-26T22:47:00.228Z] GC before operation: completed in 121.057 ms, heap usage 209.268 MB -> 65.832 MB. [2025-02-26T22:47:01.651Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1643.683 ms) ====== [2025-02-26T22:47:01.651Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-26T22:47:01.651Z] GC before operation: completed in 96.145 ms, heap usage 173.773 MB -> 87.687 MB. [2025-02-26T22:47:03.078Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1392.777 ms) ====== [2025-02-26T22:47:03.078Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-26T22:47:03.078Z] GC before operation: completed in 95.043 ms, heap usage 252.896 MB -> 82.548 MB. [2025-02-26T22:47:04.496Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1287.242 ms) ====== [2025-02-26T22:47:04.496Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-26T22:47:04.496Z] GC before operation: completed in 116.825 ms, heap usage 384.975 MB -> 82.692 MB. [2025-02-26T22:47:05.923Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1406.897 ms) ====== [2025-02-26T22:47:05.923Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-26T22:47:06.607Z] GC before operation: completed in 112.106 ms, heap usage 228.959 MB -> 82.949 MB. [2025-02-26T22:47:08.080Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1484.185 ms) ====== [2025-02-26T22:47:08.080Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-26T22:47:08.080Z] GC before operation: completed in 93.491 ms, heap usage 233.587 MB -> 93.968 MB. [2025-02-26T22:47:09.762Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1522.261 ms) ====== [2025-02-26T22:47:09.763Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-26T22:47:09.763Z] GC before operation: completed in 134.547 ms, heap usage 318.389 MB -> 77.551 MB. [2025-02-26T22:47:10.454Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1267.876 ms) ====== [2025-02-26T22:47:10.454Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-26T22:47:11.144Z] GC before operation: completed in 103.278 ms, heap usage 115.953 MB -> 66.027 MB. [2025-02-26T22:47:12.563Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1363.467 ms) ====== [2025-02-26T22:47:12.563Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-26T22:47:12.563Z] GC before operation: completed in 121.235 ms, heap usage 138.921 MB -> 66.642 MB. [2025-02-26T22:47:13.984Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1371.344 ms) ====== [2025-02-26T22:47:13.984Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-26T22:47:13.984Z] GC before operation: completed in 98.080 ms, heap usage 98.188 MB -> 66.327 MB. [2025-02-26T22:47:15.410Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1246.393 ms) ====== [2025-02-26T22:47:15.410Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-26T22:47:15.410Z] GC before operation: completed in 101.013 ms, heap usage 105.119 MB -> 82.956 MB. [2025-02-26T22:47:16.835Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1298.266 ms) ====== [2025-02-26T22:47:16.835Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-26T22:47:16.835Z] GC before operation: completed in 114.339 ms, heap usage 117.269 MB -> 82.854 MB. [2025-02-26T22:47:17.519Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1195.551 ms) ====== [2025-02-26T22:47:17.519Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-26T22:47:18.206Z] GC before operation: completed in 118.518 ms, heap usage 252.621 MB -> 67.326 MB. [2025-02-26T22:47:19.627Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1336.726 ms) ====== [2025-02-26T22:47:19.627Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-26T22:47:19.627Z] GC before operation: completed in 101.491 ms, heap usage 219.433 MB -> 83.811 MB. [2025-02-26T22:47:21.050Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1498.663 ms) ====== [2025-02-26T22:47:21.050Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-26T22:47:21.050Z] GC before operation: completed in 107.663 ms, heap usage 182.823 MB -> 83.605 MB. [2025-02-26T22:47:22.492Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1343.097 ms) ====== [2025-02-26T22:47:22.492Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-26T22:47:22.492Z] GC before operation: completed in 119.805 ms, heap usage 150.258 MB -> 89.382 MB. [2025-02-26T22:47:23.912Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1270.593 ms) ====== [2025-02-26T22:47:23.912Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-26T22:47:23.912Z] GC before operation: completed in 128.345 ms, heap usage 224.046 MB -> 67.750 MB. [2025-02-26T22:47:25.332Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1320.079 ms) ====== [2025-02-26T22:47:25.332Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-26T22:47:25.332Z] GC before operation: completed in 120.986 ms, heap usage 105.969 MB -> 83.802 MB. [2025-02-26T22:47:25.332Z] 25/02/26 16:47:25 WARN BlockManager: Asked to remove block broadcast_344, which does not exist [2025-02-26T22:47:26.757Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1261.810 ms) ====== [2025-02-26T22:47:26.757Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-26T22:47:26.757Z] GC before operation: completed in 122.218 ms, heap usage 268.509 MB -> 89.768 MB. [2025-02-26T22:47:28.180Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1284.014 ms) ====== [2025-02-26T22:47:28.180Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-26T22:47:28.180Z] GC before operation: completed in 138.330 ms, heap usage 282.796 MB -> 78.753 MB. [2025-02-26T22:47:29.637Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1243.130 ms) ====== [2025-02-26T22:47:29.637Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-26T22:47:29.637Z] GC before operation: completed in 119.950 ms, heap usage 292.741 MB -> 95.564 MB. [2025-02-26T22:47:31.056Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1242.561 ms) ====== [2025-02-26T22:47:31.056Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-26T22:47:31.056Z] GC before operation: completed in 118.617 ms, heap usage 129.080 MB -> 67.920 MB. [2025-02-26T22:47:31.742Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1191.711 ms) ====== [2025-02-26T22:47:31.742Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-26T22:47:32.429Z] GC before operation: completed in 116.374 ms, heap usage 272.377 MB -> 90.347 MB. [2025-02-26T22:47:33.115Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1269.201 ms) ====== [2025-02-26T22:47:33.115Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-26T22:47:33.803Z] GC before operation: completed in 132.911 ms, heap usage 307.417 MB -> 68.257 MB. [2025-02-26T22:47:35.225Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1486.787 ms) ====== [2025-02-26T22:47:35.225Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-26T22:47:35.225Z] GC before operation: completed in 127.233 ms, heap usage 210.489 MB -> 68.359 MB. [2025-02-26T22:47:36.655Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1214.848 ms) ====== [2025-02-26T22:47:36.655Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-26T22:47:36.655Z] GC before operation: completed in 124.854 ms, heap usage 175.352 MB -> 84.674 MB. [2025-02-26T22:47:37.347Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1140.748 ms) ====== [2025-02-26T22:47:37.347Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-26T22:47:38.034Z] GC before operation: completed in 112.181 ms, heap usage 210.141 MB -> 96.132 MB. [2025-02-26T22:47:39.464Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1435.523 ms) ====== [2025-02-26T22:47:39.464Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-26T22:47:39.464Z] GC before operation: completed in 141.300 ms, heap usage 201.880 MB -> 84.892 MB. [2025-02-26T22:47:40.891Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1247.497 ms) ====== [2025-02-26T22:47:40.891Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-26T22:47:40.891Z] GC before operation: completed in 126.524 ms, heap usage 304.281 MB -> 85.442 MB. [2025-02-26T22:47:40.891Z] 25/02/26 16:47:40 WARN BlockManager: Asked to remove block broadcast_498_piece0, which does not exist [2025-02-26T22:47:40.891Z] 25/02/26 16:47:40 WARN BlockManager: Asked to remove block broadcast_498, which does not exist [2025-02-26T22:47:41.579Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1194.032 ms) ====== [2025-02-26T22:47:41.579Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-26T22:47:42.264Z] GC before operation: completed in 122.756 ms, heap usage 276.733 MB -> 79.731 MB. [2025-02-26T22:47:43.686Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1307.387 ms) ====== [2025-02-26T22:47:43.686Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-26T22:47:43.686Z] GC before operation: completed in 116.514 ms, heap usage 218.281 MB -> 85.424 MB. [2025-02-26T22:47:43.686Z] 25/02/26 16:47:43 WARN BlockManager: Asked to remove block rdd_1859_2, which does not exist [2025-02-26T22:47:45.117Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1388.464 ms) ====== [2025-02-26T22:47:45.117Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-26T22:47:45.117Z] GC before operation: completed in 153.311 ms, heap usage 118.532 MB -> 68.466 MB. [2025-02-26T22:47:46.563Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1295.321 ms) ====== [2025-02-26T22:47:46.563Z] ----------------------------------- [2025-02-26T22:47:46.563Z] renaissance-dec-tree_0_PASSED [2025-02-26T22:47:46.563Z] ----------------------------------- [2025-02-26T22:47:46.563Z] [2025-02-26T22:47:46.563Z] TEST TEARDOWN: [2025-02-26T22:47:46.563Z] Nothing to be done for teardown. [2025-02-26T22:47:46.563Z] renaissance-dec-tree_0 Finish Time: Wed Feb 26 16:47:46 2025 Epoch Time (ms): 1740610066717