[2025-03-31T01:34:08.254Z] Running test renaissance-dec-tree_0 ... [2025-03-31T01:34:08.254Z] =============================================== [2025-03-31T01:34:08.579Z] renaissance-dec-tree_0 Start Time: Mon Mar 31 01:34:08 2025 Epoch Time (ms): 1743384848313 [2025-03-31T01:34:08.579Z] variation: NoOptions [2025-03-31T01:34:08.579Z] JVM_OPTIONS: [2025-03-31T01:34:08.579Z] { \ [2025-03-31T01:34:08.579Z] echo ""; echo "TEST SETUP:"; \ [2025-03-31T01:34:08.579Z] echo "Nothing to be done for setup."; \ [2025-03-31T01:34:08.579Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17433840294711\\renaissance-dec-tree_0"; \ [2025-03-31T01:34:08.579Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17433840294711\\renaissance-dec-tree_0"; \ [2025-03-31T01:34:08.579Z] echo ""; echo "TESTING:"; \ [2025-03-31T01:34:08.579Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17433840294711\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-03-31T01:34:08.579Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17433840294711\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-31T01:34:08.579Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-31T01:34:08.579Z] echo "Nothing to be done for teardown."; \ [2025-03-31T01:34:08.579Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17433840294711\\TestTargetResult"; [2025-03-31T01:34:08.579Z] [2025-03-31T01:34:08.579Z] TEST SETUP: [2025-03-31T01:34:08.579Z] Nothing to be done for setup. [2025-03-31T01:34:08.579Z] [2025-03-31T01:34:08.579Z] TESTING: [2025-03-31T01:34:21.491Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-31T01:34:23.102Z] WARNING: An illegal reflective access operation has occurred [2025-03-31T01:34:23.102Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17433840294711/renaissance-dec-tree_0/harness-013409-5794386547446940310/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-03-31T01:34:23.102Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-31T01:34:23.102Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-31T01:34:23.102Z] WARNING: All illegal access operations will be denied in a future release [2025-03-31T01:34:24.218Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-31T01:34:24.530Z] GC before operation: completed in 53.973 ms, heap usage 70.990 MB -> 34.862 MB. [2025-03-31T01:34:37.556Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11096.400 ms) ====== [2025-03-31T01:34:37.556Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-31T01:34:37.556Z] GC before operation: completed in 145.256 ms, heap usage 123.505 MB -> 68.129 MB. [2025-03-31T01:34:38.690Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2914.636 ms) ====== [2025-03-31T01:34:38.690Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-31T01:34:38.690Z] GC before operation: completed in 106.130 ms, heap usage 119.769 MB -> 68.756 MB. [2025-03-31T01:34:41.238Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2559.858 ms) ====== [2025-03-31T01:34:41.238Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-31T01:34:41.238Z] GC before operation: completed in 110.450 ms, heap usage 244.504 MB -> 69.344 MB. [2025-03-31T01:34:43.501Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2223.447 ms) ====== [2025-03-31T01:34:43.501Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-31T01:34:43.832Z] GC before operation: completed in 105.691 ms, heap usage 176.119 MB -> 69.580 MB. [2025-03-31T01:34:45.615Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1954.207 ms) ====== [2025-03-31T01:34:45.615Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-31T01:34:45.615Z] GC before operation: completed in 120.754 ms, heap usage 182.140 MB -> 69.794 MB. [2025-03-31T01:34:47.793Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1850.575 ms) ====== [2025-03-31T01:34:47.793Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-31T01:34:47.793Z] GC before operation: completed in 112.595 ms, heap usage 116.220 MB -> 69.700 MB. [2025-03-31T01:34:49.449Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1609.939 ms) ====== [2025-03-31T01:34:49.449Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-31T01:34:49.449Z] GC before operation: completed in 110.842 ms, heap usage 244.062 MB -> 81.462 MB. [2025-03-31T01:34:51.064Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1547.425 ms) ====== [2025-03-31T01:34:51.065Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-31T01:34:51.065Z] GC before operation: completed in 120.680 ms, heap usage 294.776 MB -> 92.278 MB. [2025-03-31T01:34:52.731Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1583.373 ms) ====== [2025-03-31T01:34:52.731Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-31T01:34:52.731Z] GC before operation: completed in 138.931 ms, heap usage 445.102 MB -> 86.936 MB. [2025-03-31T01:34:54.411Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1559.719 ms) ====== [2025-03-31T01:34:54.411Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-31T01:34:54.411Z] GC before operation: completed in 123.226 ms, heap usage 241.996 MB -> 75.987 MB. [2025-03-31T01:34:56.121Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1493.266 ms) ====== [2025-03-31T01:34:56.121Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-31T01:34:56.121Z] GC before operation: completed in 124.630 ms, heap usage 108.815 MB -> 70.714 MB. [2025-03-31T01:34:57.794Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1430.378 ms) ====== [2025-03-31T01:34:57.794Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-31T01:34:57.794Z] GC before operation: completed in 122.716 ms, heap usage 115.715 MB -> 70.492 MB. [2025-03-31T01:34:59.406Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1494.854 ms) ====== [2025-03-31T01:34:59.406Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-31T01:34:59.406Z] GC before operation: completed in 122.873 ms, heap usage 291.073 MB -> 87.459 MB. [2025-03-31T01:34:59.406Z] 01:34:59.103 WARN [block-manager-storage-async-thread-pool-70] org.apache.spark.storage.BlockManager - Asked to remove block rdd_621_1, which does not exist [2025-03-31T01:35:01.017Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1476.771 ms) ====== [2025-03-31T01:35:01.017Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-31T01:35:01.017Z] GC before operation: completed in 155.512 ms, heap usage 190.382 MB -> 81.753 MB. [2025-03-31T01:35:02.664Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1452.035 ms) ====== [2025-03-31T01:35:02.664Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-31T01:35:02.664Z] GC before operation: completed in 125.484 ms, heap usage 240.577 MB -> 82.228 MB. [2025-03-31T01:35:03.775Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1321.095 ms) ====== [2025-03-31T01:35:03.775Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-31T01:35:04.102Z] GC before operation: completed in 137.309 ms, heap usage 287.060 MB -> 71.490 MB. [2025-03-31T01:35:05.265Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1364.846 ms) ====== [2025-03-31T01:35:05.265Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-31T01:35:05.593Z] GC before operation: completed in 136.162 ms, heap usage 251.822 MB -> 82.484 MB. [2025-03-31T01:35:06.809Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1391.384 ms) ====== [2025-03-31T01:35:06.809Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-31T01:35:07.136Z] GC before operation: completed in 134.280 ms, heap usage 245.911 MB -> 82.448 MB. [2025-03-31T01:35:08.303Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1375.049 ms) ====== [2025-03-31T01:35:08.303Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-31T01:35:08.628Z] GC before operation: completed in 127.709 ms, heap usage 320.235 MB -> 93.619 MB. [2025-03-31T01:35:10.273Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1409.015 ms) ====== [2025-03-31T01:35:10.274Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-31T01:35:10.274Z] GC before operation: completed in 156.657 ms, heap usage 167.032 MB -> 82.485 MB. [2025-03-31T01:35:10.274Z] 01:35:09.870 WARN [block-manager-storage-async-thread-pool-71] org.apache.spark.storage.BlockManager - Asked to remove block rdd_957_1, which does not exist [2025-03-31T01:35:11.512Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1465.543 ms) ====== [2025-03-31T01:35:11.512Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-31T01:35:11.512Z] GC before operation: completed in 131.518 ms, heap usage 232.238 MB -> 82.788 MB. [2025-03-31T01:35:11.512Z] 01:35:11.467 WARN [block-manager-storage-async-thread-pool-20] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1005_1, which does not exist [2025-03-31T01:35:13.116Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1294.097 ms) ====== [2025-03-31T01:35:13.116Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-31T01:35:13.116Z] GC before operation: completed in 133.463 ms, heap usage 273.590 MB -> 88.540 MB. [2025-03-31T01:35:13.116Z] 01:35:12.895 WARN [block-manager-storage-async-thread-pool-90] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1053_0, which does not exist [2025-03-31T01:35:13.116Z] 01:35:12.896 WARN [block-manager-storage-async-thread-pool-90] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1053_2, which does not exist [2025-03-31T01:35:14.353Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1326.889 ms) ====== [2025-03-31T01:35:14.353Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-31T01:35:14.679Z] GC before operation: completed in 138.703 ms, heap usage 163.732 MB -> 82.684 MB. [2025-03-31T01:35:15.850Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1338.626 ms) ====== [2025-03-31T01:35:15.850Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-31T01:35:16.190Z] GC before operation: completed in 143.392 ms, heap usage 289.866 MB -> 88.769 MB. [2025-03-31T01:35:17.308Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1292.015 ms) ====== [2025-03-31T01:35:17.308Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-31T01:35:17.640Z] GC before operation: completed in 137.369 ms, heap usage 257.212 MB -> 88.845 MB. [2025-03-31T01:35:18.734Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1284.719 ms) ====== [2025-03-31T01:35:18.734Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-31T01:35:19.073Z] GC before operation: completed in 153.141 ms, heap usage 224.241 MB -> 83.523 MB. [2025-03-31T01:35:20.200Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1335.308 ms) ====== [2025-03-31T01:35:20.200Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-31T01:35:20.532Z] GC before operation: completed in 143.466 ms, heap usage 156.526 MB -> 83.435 MB. [2025-03-31T01:35:21.658Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1320.606 ms) ====== [2025-03-31T01:35:21.658Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-31T01:35:21.971Z] GC before operation: completed in 145.223 ms, heap usage 126.226 MB -> 72.505 MB. [2025-03-31T01:35:23.143Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1334.904 ms) ====== [2025-03-31T01:35:23.143Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-31T01:35:23.470Z] GC before operation: completed in 146.774 ms, heap usage 236.459 MB -> 83.654 MB. [2025-03-31T01:35:24.619Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1332.811 ms) ====== [2025-03-31T01:35:24.619Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-31T01:35:24.930Z] GC before operation: completed in 142.719 ms, heap usage 246.822 MB -> 89.406 MB. [2025-03-31T01:35:26.092Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1324.419 ms) ====== [2025-03-31T01:35:26.092Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-31T01:35:26.420Z] GC before operation: completed in 147.108 ms, heap usage 337.831 MB -> 89.486 MB. [2025-03-31T01:35:27.545Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1283.035 ms) ====== [2025-03-31T01:35:27.545Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-31T01:35:27.884Z] GC before operation: completed in 143.795 ms, heap usage 331.924 MB -> 84.158 MB. [2025-03-31T01:35:28.996Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1254.624 ms) ====== [2025-03-31T01:35:28.996Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-31T01:35:28.996Z] GC before operation: completed in 140.467 ms, heap usage 245.833 MB -> 89.703 MB. [2025-03-31T01:35:28.996Z] 01:35:28.914 WARN [block-manager-storage-async-thread-pool-38] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1581_2, which does not exist [2025-03-31T01:35:30.624Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1262.186 ms) ====== [2025-03-31T01:35:30.624Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-31T01:35:30.624Z] GC before operation: completed in 144.828 ms, heap usage 238.640 MB -> 84.174 MB. [2025-03-31T01:35:31.774Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1328.462 ms) ====== [2025-03-31T01:35:31.774Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-31T01:35:32.101Z] GC before operation: completed in 143.747 ms, heap usage 261.162 MB -> 89.904 MB. [2025-03-31T01:35:32.101Z] 01:35:31.794 WARN [block-manager-storage-async-thread-pool-85] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_485_piece0, which does not exist [2025-03-31T01:35:33.215Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1277.153 ms) ====== [2025-03-31T01:35:33.216Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-31T01:35:33.557Z] GC before operation: completed in 141.775 ms, heap usage 127.587 MB -> 84.281 MB. [2025-03-31T01:35:34.756Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1342.992 ms) ====== [2025-03-31T01:35:34.756Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-31T01:35:34.756Z] GC before operation: completed in 139.393 ms, heap usage 232.016 MB -> 84.500 MB. [2025-03-31T01:35:36.379Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1319.133 ms) ====== [2025-03-31T01:35:36.379Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-31T01:35:36.379Z] GC before operation: completed in 141.773 ms, heap usage 310.031 MB -> 95.663 MB. [2025-03-31T01:35:37.986Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1386.893 ms) ====== [2025-03-31T01:35:37.986Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-31T01:35:37.986Z] GC before operation: completed in 169.466 ms, heap usage 140.972 MB -> 89.727 MB. [2025-03-31T01:35:39.111Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1242.529 ms) ====== [2025-03-31T01:35:39.436Z] ----------------------------------- [2025-03-31T01:35:39.436Z] renaissance-dec-tree_0_PASSED [2025-03-31T01:35:39.436Z] ----------------------------------- [2025-03-31T01:35:39.761Z] [2025-03-31T01:35:39.761Z] TEST TEARDOWN: [2025-03-31T01:35:39.761Z] Nothing to be done for teardown. [2025-03-31T01:35:39.761Z] renaissance-dec-tree_0 Finish Time: Mon Mar 31 01:35:39 2025 Epoch Time (ms): 1743384939643