[2025-02-06T05:47:25.277Z] Running test renaissance-dec-tree_0 ... [2025-02-06T05:47:25.277Z] =============================================== [2025-02-06T05:47:25.587Z] renaissance-dec-tree_0 Start Time: Thu Feb 6 05:47:25 2025 Epoch Time (ms): 1738820845318 [2025-02-06T05:47:25.587Z] variation: NoOptions [2025-02-06T05:47:25.920Z] JVM_OPTIONS: [2025-02-06T05:47:25.920Z] { \ [2025-02-06T05:47:25.920Z] echo ""; echo "TEST SETUP:"; \ [2025-02-06T05:47:25.920Z] echo "Nothing to be done for setup."; \ [2025-02-06T05:47:25.920Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17388201555792\\renaissance-dec-tree_0"; \ [2025-02-06T05:47:25.920Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17388201555792\\renaissance-dec-tree_0"; \ [2025-02-06T05:47:25.920Z] echo ""; echo "TESTING:"; \ [2025-02-06T05:47:25.920Z] "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_17388201555792\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-02-06T05:47:25.920Z] 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_17388201555792\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-06T05:47:25.920Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-06T05:47:25.920Z] echo "Nothing to be done for teardown."; \ [2025-02-06T05:47:25.920Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17388201555792\\TestTargetResult"; [2025-02-06T05:47:25.920Z] [2025-02-06T05:47:25.920Z] TEST SETUP: [2025-02-06T05:47:25.920Z] Nothing to be done for setup. [2025-02-06T05:47:25.920Z] [2025-02-06T05:47:25.920Z] TESTING: [2025-02-06T05:47:41.542Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-06T05:47:41.542Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-06T05:47:45.205Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-06T05:47:45.205Z] GC before operation: completed in 55.403 ms, heap usage 90.309 MB -> 35.395 MB. [2025-02-06T05:47:54.291Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8554.251 ms) ====== [2025-02-06T05:47:54.291Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-06T05:47:54.291Z] GC before operation: completed in 66.004 ms, heap usage 150.481 MB -> 64.697 MB. [2025-02-06T05:47:55.990Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2391.256 ms) ====== [2025-02-06T05:47:55.990Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-06T05:47:55.990Z] GC before operation: completed in 73.075 ms, heap usage 286.000 MB -> 87.321 MB. [2025-02-06T05:47:58.381Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2146.252 ms) ====== [2025-02-06T05:47:58.381Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-06T05:47:58.381Z] GC before operation: completed in 83.079 ms, heap usage 299.089 MB -> 65.683 MB. [2025-02-06T05:48:00.084Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1751.272 ms) ====== [2025-02-06T05:48:00.084Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-06T05:48:00.084Z] GC before operation: completed in 82.038 ms, heap usage 163.725 MB -> 65.642 MB. [2025-02-06T05:48:01.697Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1627.614 ms) ====== [2025-02-06T05:48:01.697Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-06T05:48:02.061Z] GC before operation: completed in 79.056 ms, heap usage 345.775 MB -> 77.261 MB. [2025-02-06T05:48:03.720Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1496.816 ms) ====== [2025-02-06T05:48:03.720Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-06T05:48:03.720Z] GC before operation: completed in 106.343 ms, heap usage 234.366 MB -> 66.347 MB. [2025-02-06T05:48:05.325Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1632.932 ms) ====== [2025-02-06T05:48:05.325Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-06T05:48:05.325Z] GC before operation: completed in 80.583 ms, heap usage 331.601 MB -> 83.119 MB. [2025-02-06T05:48:07.073Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1663.359 ms) ====== [2025-02-06T05:48:07.073Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-06T05:48:07.073Z] GC before operation: completed in 97.801 ms, heap usage 130.975 MB -> 77.426 MB. [2025-02-06T05:48:08.677Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1696.088 ms) ====== [2025-02-06T05:48:08.677Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-06T05:48:08.677Z] GC before operation: completed in 90.913 ms, heap usage 349.637 MB -> 77.943 MB. [2025-02-06T05:48:10.374Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1597.176 ms) ====== [2025-02-06T05:48:10.374Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-06T05:48:10.374Z] GC before operation: completed in 86.064 ms, heap usage 177.187 MB -> 83.243 MB. [2025-02-06T05:48:11.970Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1533.331 ms) ====== [2025-02-06T05:48:11.970Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-06T05:48:11.970Z] GC before operation: completed in 88.969 ms, heap usage 427.595 MB -> 83.611 MB. [2025-02-06T05:48:13.573Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1413.628 ms) ====== [2025-02-06T05:48:13.573Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-06T05:48:13.573Z] GC before operation: completed in 84.396 ms, heap usage 132.794 MB -> 77.809 MB. [2025-02-06T05:48:15.176Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1554.933 ms) ====== [2025-02-06T05:48:15.176Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-06T05:48:15.176Z] GC before operation: completed in 91.816 ms, heap usage 341.150 MB -> 83.820 MB. [2025-02-06T05:48:16.775Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1471.504 ms) ====== [2025-02-06T05:48:16.775Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-06T05:48:16.775Z] GC before operation: completed in 96.030 ms, heap usage 418.170 MB -> 89.421 MB. [2025-02-06T05:48:18.375Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1388.511 ms) ====== [2025-02-06T05:48:18.375Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-06T05:48:18.375Z] GC before operation: completed in 94.575 ms, heap usage 353.156 MB -> 84.054 MB. [2025-02-06T05:48:19.534Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1402.177 ms) ====== [2025-02-06T05:48:19.534Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-06T05:48:19.859Z] GC before operation: completed in 88.720 ms, heap usage 420.505 MB -> 67.736 MB. [2025-02-06T05:48:21.474Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1461.875 ms) ====== [2025-02-06T05:48:21.474Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-06T05:48:21.474Z] GC before operation: completed in 92.128 ms, heap usage 293.665 MB -> 67.868 MB. [2025-02-06T05:48:22.601Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1372.099 ms) ====== [2025-02-06T05:48:22.601Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-06T05:48:22.930Z] GC before operation: completed in 91.858 ms, heap usage 290.468 MB -> 84.512 MB. [2025-02-06T05:48:24.058Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1400.585 ms) ====== [2025-02-06T05:48:24.058Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-06T05:48:24.388Z] GC before operation: completed in 99.210 ms, heap usage 388.629 MB -> 84.603 MB. [2025-02-06T05:48:25.513Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1344.186 ms) ====== [2025-02-06T05:48:25.513Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-06T05:48:25.838Z] GC before operation: completed in 96.669 ms, heap usage 367.858 MB -> 84.792 MB. [2025-02-06T05:48:26.984Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1380.896 ms) ====== [2025-02-06T05:48:26.984Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-06T05:48:27.308Z] GC before operation: completed in 104.168 ms, heap usage 365.905 MB -> 84.956 MB. [2025-02-06T05:48:28.963Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1407.022 ms) ====== [2025-02-06T05:48:28.963Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-06T05:48:28.963Z] GC before operation: completed in 104.129 ms, heap usage 367.215 MB -> 84.832 MB. [2025-02-06T05:48:30.097Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1453.603 ms) ====== [2025-02-06T05:48:30.097Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-06T05:48:30.097Z] GC before operation: completed in 98.292 ms, heap usage 354.597 MB -> 90.375 MB. [2025-02-06T05:48:31.707Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1383.578 ms) ====== [2025-02-06T05:48:31.707Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-06T05:48:31.707Z] GC before operation: completed in 99.334 ms, heap usage 322.902 MB -> 85.072 MB. [2025-02-06T05:48:33.302Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1381.989 ms) ====== [2025-02-06T05:48:33.302Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-06T05:48:33.302Z] GC before operation: completed in 96.366 ms, heap usage 366.444 MB -> 85.262 MB. [2025-02-06T05:48:34.892Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1391.436 ms) ====== [2025-02-06T05:48:34.892Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-06T05:48:34.892Z] GC before operation: completed in 99.247 ms, heap usage 380.739 MB -> 85.351 MB. [2025-02-06T05:48:36.036Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1430.141 ms) ====== [2025-02-06T05:48:36.036Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-06T05:48:36.376Z] GC before operation: completed in 104.937 ms, heap usage 364.088 MB -> 85.418 MB. [2025-02-06T05:48:37.533Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1393.464 ms) ====== [2025-02-06T05:48:37.533Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-06T05:48:37.868Z] GC before operation: completed in 102.090 ms, heap usage 364.220 MB -> 85.463 MB. [2025-02-06T05:48:38.975Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1273.474 ms) ====== [2025-02-06T05:48:38.975Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-06T05:48:38.975Z] GC before operation: completed in 106.670 ms, heap usage 350.258 MB -> 91.097 MB. [2025-02-06T05:48:38.975Z] 25/02/06 05:48:38 WARN BlockManager: Asked to remove block broadcast_400, which does not exist [2025-02-06T05:48:40.576Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1395.023 ms) ====== [2025-02-06T05:48:40.576Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-06T05:48:40.576Z] GC before operation: completed in 104.987 ms, heap usage 311.378 MB -> 80.322 MB. [2025-02-06T05:48:42.178Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1376.899 ms) ====== [2025-02-06T05:48:42.178Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-06T05:48:42.178Z] GC before operation: completed in 110.777 ms, heap usage 438.170 MB -> 91.668 MB. [2025-02-06T05:48:43.349Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1342.089 ms) ====== [2025-02-06T05:48:43.349Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-06T05:48:43.677Z] GC before operation: completed in 106.811 ms, heap usage 309.843 MB -> 91.347 MB. [2025-02-06T05:48:44.812Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1333.155 ms) ====== [2025-02-06T05:48:44.812Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-06T05:48:45.139Z] GC before operation: completed in 115.468 ms, heap usage 308.879 MB -> 80.570 MB. [2025-02-06T05:48:46.317Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1368.700 ms) ====== [2025-02-06T05:48:46.317Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-06T05:48:46.667Z] GC before operation: completed in 108.353 ms, heap usage 439.844 MB -> 86.319 MB. [2025-02-06T05:48:47.816Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1413.787 ms) ====== [2025-02-06T05:48:47.816Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-06T05:48:48.125Z] GC before operation: completed in 110.115 ms, heap usage 379.089 MB -> 91.833 MB. [2025-02-06T05:48:49.300Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1392.546 ms) ====== [2025-02-06T05:48:49.300Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-06T05:48:49.626Z] GC before operation: completed in 106.592 ms, heap usage 308.772 MB -> 86.377 MB. [2025-02-06T05:48:50.792Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1284.218 ms) ====== [2025-02-06T05:48:50.792Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-06T05:48:50.792Z] GC before operation: completed in 104.363 ms, heap usage 353.258 MB -> 86.645 MB. [2025-02-06T05:48:52.390Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1302.973 ms) ====== [2025-02-06T05:48:52.390Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-06T05:48:52.390Z] GC before operation: completed in 101.985 ms, heap usage 349.559 MB -> 86.491 MB. [2025-02-06T05:48:53.549Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1342.059 ms) ====== [2025-02-06T05:48:53.549Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-06T05:48:53.898Z] GC before operation: completed in 111.112 ms, heap usage 347.076 MB -> 86.588 MB. [2025-02-06T05:48:55.007Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1308.503 ms) ====== [2025-02-06T05:48:55.328Z] ----------------------------------- [2025-02-06T05:48:55.328Z] renaissance-dec-tree_0_PASSED [2025-02-06T05:48:55.328Z] ----------------------------------- [2025-02-06T05:48:55.649Z] [2025-02-06T05:48:55.649Z] TEST TEARDOWN: [2025-02-06T05:48:55.649Z] Nothing to be done for teardown. [2025-02-06T05:48:55.649Z] renaissance-dec-tree_0 Finish Time: Thu Feb 6 05:48:55 2025 Epoch Time (ms): 1738820935421