[2025-05-11T07:45:35.881Z] Running test renaissance-dec-tree_0 ... [2025-05-11T07:45:36.196Z] =============================================== [2025-05-11T07:45:36.196Z] renaissance-dec-tree_0 Start Time: Sun May 11 07:45:36 2025 Epoch Time (ms): 1746949536090 [2025-05-11T07:45:36.196Z] variation: NoOptions [2025-05-11T07:45:36.517Z] JVM_OPTIONS: [2025-05-11T07:45:36.517Z] { \ [2025-05-11T07:45:36.517Z] echo ""; echo "TEST SETUP:"; \ [2025-05-11T07:45:36.517Z] echo "Nothing to be done for setup."; \ [2025-05-11T07:45:36.517Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-dec-tree_0"; \ [2025-05-11T07:45:36.517Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-dec-tree_0"; \ [2025-05-11T07:45:36.517Z] echo ""; echo "TESTING:"; \ [2025-05-11T07:45:36.517Z] "c:/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-05-11T07:45:36.517Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-11T07:45:36.517Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-11T07:45:36.517Z] echo "Nothing to be done for teardown."; \ [2025-05-11T07:45:36.517Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\TestTargetResult"; [2025-05-11T07:45:36.517Z] [2025-05-11T07:45:36.517Z] TEST SETUP: [2025-05-11T07:45:36.517Z] Nothing to be done for setup. [2025-05-11T07:45:36.517Z] [2025-05-11T07:45:36.517Z] TESTING: [2025-05-11T07:45:49.332Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-11T07:45:50.910Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-11T07:45:50.910Z] GC before operation: completed in 35.952 ms, heap usage 56.006 MB -> 35.215 MB. [2025-05-11T07:46:01.794Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10348.802 ms) ====== [2025-05-11T07:46:01.794Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-11T07:46:01.794Z] GC before operation: completed in 105.873 ms, heap usage 134.903 MB -> 69.335 MB. [2025-05-11T07:46:04.234Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2727.779 ms) ====== [2025-05-11T07:46:04.234Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-11T07:46:04.234Z] GC before operation: completed in 71.440 ms, heap usage 180.335 MB -> 69.324 MB. [2025-05-11T07:46:06.587Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2315.708 ms) ====== [2025-05-11T07:46:06.587Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-11T07:46:06.587Z] GC before operation: completed in 71.871 ms, heap usage 384.985 MB -> 70.050 MB. [2025-05-11T07:46:08.765Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1857.987 ms) ====== [2025-05-11T07:46:08.765Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-11T07:46:08.765Z] GC before operation: completed in 92.854 ms, heap usage 333.404 MB -> 70.126 MB. [2025-05-11T07:46:10.340Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1638.035 ms) ====== [2025-05-11T07:46:10.340Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-11T07:46:10.340Z] GC before operation: completed in 89.607 ms, heap usage 300.537 MB -> 81.235 MB. [2025-05-11T07:46:12.093Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1524.625 ms) ====== [2025-05-11T07:46:12.093Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-11T07:46:12.093Z] GC before operation: completed in 83.225 ms, heap usage 155.731 MB -> 81.802 MB. [2025-05-11T07:46:13.399Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1535.969 ms) ====== [2025-05-11T07:46:13.399Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-11T07:46:13.399Z] GC before operation: completed in 79.628 ms, heap usage 226.249 MB -> 82.096 MB. [2025-05-11T07:46:15.064Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1488.906 ms) ====== [2025-05-11T07:46:15.064Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-11T07:46:15.064Z] GC before operation: completed in 79.111 ms, heap usage 169.369 MB -> 81.871 MB. [2025-05-11T07:46:16.671Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1489.955 ms) ====== [2025-05-11T07:46:16.671Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-11T07:46:16.671Z] GC before operation: completed in 83.677 ms, heap usage 449.490 MB -> 87.610 MB. [2025-05-11T07:46:18.299Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1440.029 ms) ====== [2025-05-11T07:46:18.299Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-11T07:46:18.299Z] GC before operation: completed in 88.681 ms, heap usage 592.195 MB -> 88.130 MB. [2025-05-11T07:46:19.903Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1444.894 ms) ====== [2025-05-11T07:46:19.903Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-11T07:46:19.903Z] GC before operation: completed in 91.054 ms, heap usage 584.888 MB -> 88.471 MB. [2025-05-11T07:46:21.020Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1413.327 ms) ====== [2025-05-11T07:46:21.021Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-11T07:46:21.021Z] GC before operation: completed in 90.091 ms, heap usage 404.888 MB -> 88.140 MB. [2025-05-11T07:46:22.600Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1503.312 ms) ====== [2025-05-11T07:46:22.600Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-11T07:46:22.600Z] GC before operation: completed in 90.891 ms, heap usage 332.441 MB -> 83.030 MB. [2025-05-11T07:46:24.206Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1453.836 ms) ====== [2025-05-11T07:46:24.206Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-11T07:46:24.206Z] GC before operation: completed in 90.612 ms, heap usage 224.574 MB -> 88.407 MB. [2025-05-11T07:46:25.792Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1442.314 ms) ====== [2025-05-11T07:46:25.793Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-11T07:46:25.793Z] GC before operation: completed in 102.211 ms, heap usage 566.126 MB -> 88.827 MB. [2025-05-11T07:46:27.402Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1410.794 ms) ====== [2025-05-11T07:46:27.403Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-11T07:46:27.403Z] GC before operation: completed in 89.297 ms, heap usage 598.323 MB -> 72.604 MB. [2025-05-11T07:46:29.017Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1369.560 ms) ====== [2025-05-11T07:46:29.017Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-11T07:46:29.017Z] GC before operation: completed in 92.806 ms, heap usage 225.991 MB -> 94.426 MB. [2025-05-11T07:46:30.130Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1367.924 ms) ====== [2025-05-11T07:46:30.130Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-11T07:46:30.130Z] GC before operation: completed in 97.678 ms, heap usage 192.146 MB -> 88.744 MB. [2025-05-11T07:46:31.726Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1521.749 ms) ====== [2025-05-11T07:46:31.726Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-11T07:46:32.047Z] GC before operation: completed in 107.885 ms, heap usage 508.129 MB -> 94.569 MB. [2025-05-11T07:46:33.192Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1379.193 ms) ====== [2025-05-11T07:46:33.192Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-11T07:46:33.514Z] GC before operation: completed in 102.938 ms, heap usage 372.726 MB -> 94.717 MB. [2025-05-11T07:46:34.653Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1350.284 ms) ====== [2025-05-11T07:46:34.653Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-11T07:46:34.975Z] GC before operation: completed in 107.275 ms, heap usage 482.845 MB -> 89.325 MB. [2025-05-11T07:46:36.124Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1371.220 ms) ====== [2025-05-11T07:46:36.124Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-11T07:46:36.445Z] GC before operation: completed in 103.394 ms, heap usage 571.970 MB -> 89.692 MB. [2025-05-11T07:46:37.579Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1353.411 ms) ====== [2025-05-11T07:46:37.579Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-11T07:46:37.899Z] GC before operation: completed in 106.750 ms, heap usage 592.976 MB -> 89.728 MB. [2025-05-11T07:46:39.592Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1384.215 ms) ====== [2025-05-11T07:46:39.592Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-11T07:46:39.592Z] GC before operation: completed in 98.700 ms, heap usage 273.261 MB -> 89.609 MB. [2025-05-11T07:46:40.709Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1417.184 ms) ====== [2025-05-11T07:46:40.709Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-11T07:46:40.709Z] GC before operation: completed in 107.464 ms, heap usage 190.578 MB -> 89.522 MB. [2025-05-11T07:46:42.405Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1391.709 ms) ====== [2025-05-11T07:46:42.405Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-11T07:46:42.405Z] GC before operation: completed in 102.814 ms, heap usage 218.979 MB -> 89.616 MB. [2025-05-11T07:46:43.549Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1359.768 ms) ====== [2025-05-11T07:46:43.549Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-11T07:46:43.870Z] GC before operation: completed in 105.553 ms, heap usage 564.114 MB -> 90.200 MB. [2025-05-11T07:46:44.989Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1317.510 ms) ====== [2025-05-11T07:46:44.989Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-11T07:46:45.308Z] GC before operation: completed in 102.884 ms, heap usage 591.481 MB -> 90.262 MB. [2025-05-11T07:46:46.456Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1349.036 ms) ====== [2025-05-11T07:46:46.456Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-11T07:46:46.778Z] GC before operation: completed in 122.173 ms, heap usage 582.577 MB -> 95.845 MB. [2025-05-11T07:46:47.895Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1332.168 ms) ====== [2025-05-11T07:46:47.895Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-11T07:46:48.214Z] GC before operation: completed in 102.502 ms, heap usage 307.506 MB -> 90.392 MB. [2025-05-11T07:46:49.356Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1377.253 ms) ====== [2025-05-11T07:46:49.356Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-11T07:46:49.662Z] GC before operation: completed in 103.062 ms, heap usage 236.023 MB -> 90.477 MB. [2025-05-11T07:46:51.240Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1490.851 ms) ====== [2025-05-11T07:46:51.240Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-11T07:46:51.240Z] GC before operation: completed in 102.169 ms, heap usage 111.623 MB -> 73.300 MB. [2025-05-11T07:46:52.800Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1367.108 ms) ====== [2025-05-11T07:46:52.800Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-11T07:46:52.800Z] GC before operation: completed in 104.185 ms, heap usage 301.430 MB -> 90.327 MB. [2025-05-11T07:46:54.413Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1430.524 ms) ====== [2025-05-11T07:46:54.413Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-11T07:46:54.413Z] GC before operation: completed in 107.122 ms, heap usage 125.692 MB -> 90.219 MB. [2025-05-11T07:46:55.522Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1424.802 ms) ====== [2025-05-11T07:46:55.522Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-11T07:46:55.850Z] GC before operation: completed in 109.313 ms, heap usage 468.866 MB -> 90.613 MB. [2025-05-11T07:46:57.103Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1500.661 ms) ====== [2025-05-11T07:46:57.103Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-11T07:46:57.424Z] GC before operation: completed in 107.714 ms, heap usage 228.976 MB -> 96.553 MB. [2025-05-11T07:46:58.561Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1344.307 ms) ====== [2025-05-11T07:46:58.561Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-11T07:46:58.883Z] GC before operation: completed in 108.009 ms, heap usage 433.303 MB -> 91.062 MB. [2025-05-11T07:46:58.883Z] 07:46:58.577 WARN [block-manager-storage-async-thread-pool-33] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1773_1, which does not exist [2025-05-11T07:47:00.517Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1389.885 ms) ====== [2025-05-11T07:47:00.517Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-11T07:47:00.517Z] GC before operation: completed in 112.079 ms, heap usage 537.113 MB -> 91.405 MB. [2025-05-11T07:47:01.646Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1476.437 ms) ====== [2025-05-11T07:47:01.646Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-11T07:47:01.964Z] GC before operation: completed in 109.036 ms, heap usage 134.863 MB -> 85.165 MB. [2025-05-11T07:47:03.056Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1300.992 ms) ====== [2025-05-11T07:47:03.377Z] ----------------------------------- [2025-05-11T07:47:03.377Z] renaissance-dec-tree_0_PASSED [2025-05-11T07:47:03.377Z] ----------------------------------- [2025-05-11T07:47:03.683Z] [2025-05-11T07:47:03.683Z] TEST TEARDOWN: [2025-05-11T07:47:03.683Z] Nothing to be done for teardown. [2025-05-11T07:47:03.683Z] renaissance-dec-tree_0 Finish Time: Sun May 11 07:47:03 2025 Epoch Time (ms): 1746949623515