[2024-06-27T10:13:53.444Z] Running test renaissance-dec-tree_0 ... [2024-06-27T10:13:53.761Z] =============================================== [2024-06-27T10:13:53.761Z] renaissance-dec-tree_0 Start Time: Thu Jun 27 10:13:53 2024 Epoch Time (ms): 1719483233558 [2024-06-27T10:13:54.085Z] variation: NoOptions [2024-06-27T10:13:54.085Z] JVM_OPTIONS: [2024-06-27T10:13:54.085Z] { \ [2024-06-27T10:13:54.085Z] echo ""; echo "TEST SETUP:"; \ [2024-06-27T10:13:54.085Z] echo "Nothing to be done for setup."; \ [2024-06-27T10:13:54.085Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-dec-tree_0"; \ [2024-06-27T10:13:54.085Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-dec-tree_0"; \ [2024-06-27T10:13:54.085Z] echo ""; echo "TESTING:"; \ [2024-06-27T10:13:54.085Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/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_testList_0/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-06-27T10:13:54.085Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-27T10:13:54.085Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-27T10:13:54.085Z] echo "Nothing to be done for teardown."; \ [2024-06-27T10:13:54.085Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\TestTargetResult"; [2024-06-27T10:13:54.415Z] [2024-06-27T10:13:54.415Z] TEST SETUP: [2024-06-27T10:13:54.415Z] Nothing to be done for setup. [2024-06-27T10:13:54.415Z] [2024-06-27T10:13:54.415Z] TESTING: [2024-06-27T10:14:05.113Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-27T10:14:05.817Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-06-27T10:14:08.724Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-06-27T10:14:08.725Z] GC before operation: completed in 51.242 ms, heap usage 58.684 MB -> 35.241 MB. [2024-06-27T10:14:16.216Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7525.397 ms) ====== [2024-06-27T10:14:16.216Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-06-27T10:14:16.216Z] GC before operation: completed in 62.813 ms, heap usage 110.433 MB -> 64.747 MB. [2024-06-27T10:14:18.872Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2326.333 ms) ====== [2024-06-27T10:14:18.872Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-06-27T10:14:18.872Z] GC before operation: completed in 65.461 ms, heap usage 299.448 MB -> 76.213 MB. [2024-06-27T10:14:20.724Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2014.578 ms) ====== [2024-06-27T10:14:20.725Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-06-27T10:14:20.725Z] GC before operation: completed in 63.948 ms, heap usage 206.715 MB -> 65.438 MB. [2024-06-27T10:14:22.395Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1615.069 ms) ====== [2024-06-27T10:14:22.395Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-06-27T10:14:22.395Z] GC before operation: completed in 62.955 ms, heap usage 183.913 MB -> 76.550 MB. [2024-06-27T10:14:24.096Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1522.305 ms) ====== [2024-06-27T10:14:24.096Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-06-27T10:14:24.096Z] GC before operation: completed in 64.855 ms, heap usage 238.624 MB -> 77.211 MB. [2024-06-27T10:14:25.394Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1408.486 ms) ====== [2024-06-27T10:14:25.394Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-06-27T10:14:25.774Z] GC before operation: completed in 86.812 ms, heap usage 252.727 MB -> 66.096 MB. [2024-06-27T10:14:26.911Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1451.387 ms) ====== [2024-06-27T10:14:26.911Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-06-27T10:14:26.911Z] GC before operation: completed in 70.887 ms, heap usage 346.840 MB -> 82.826 MB. [2024-06-27T10:14:28.542Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1401.430 ms) ====== [2024-06-27T10:14:28.542Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-06-27T10:14:28.542Z] GC before operation: completed in 71.091 ms, heap usage 184.526 MB -> 77.348 MB. [2024-06-27T10:14:29.812Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1395.165 ms) ====== [2024-06-27T10:14:29.812Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-06-27T10:14:29.812Z] GC before operation: completed in 72.671 ms, heap usage 171.808 MB -> 77.357 MB. [2024-06-27T10:14:31.443Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1362.155 ms) ====== [2024-06-27T10:14:31.443Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-06-27T10:14:31.444Z] GC before operation: completed in 73.595 ms, heap usage 175.915 MB -> 83.018 MB. [2024-06-27T10:14:32.593Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1325.361 ms) ====== [2024-06-27T10:14:32.593Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-06-27T10:14:32.931Z] GC before operation: completed in 76.252 ms, heap usage 460.572 MB -> 83.575 MB. [2024-06-27T10:14:34.063Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1274.021 ms) ====== [2024-06-27T10:14:34.063Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-06-27T10:14:34.063Z] GC before operation: completed in 74.803 ms, heap usage 203.738 MB -> 78.054 MB. [2024-06-27T10:14:35.756Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1347.692 ms) ====== [2024-06-27T10:14:35.756Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-06-27T10:14:35.756Z] GC before operation: completed in 77.846 ms, heap usage 181.735 MB -> 83.253 MB. [2024-06-27T10:14:35.756Z] 24/06/27 10:14:35 WARN BlockManager: Asked to remove block rdd_634_2, which does not exist [2024-06-27T10:14:36.935Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1302.273 ms) ====== [2024-06-27T10:14:36.935Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-06-27T10:14:36.935Z] GC before operation: completed in 98.264 ms, heap usage 454.337 MB -> 89.575 MB. [2024-06-27T10:14:38.108Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1249.073 ms) ====== [2024-06-27T10:14:38.108Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-06-27T10:14:38.441Z] GC before operation: completed in 91.388 ms, heap usage 384.048 MB -> 83.807 MB. [2024-06-27T10:14:38.441Z] 24/06/27 10:14:38 WARN BlockManager: Asked to remove block broadcast_204, which does not exist [2024-06-27T10:14:39.560Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1206.933 ms) ====== [2024-06-27T10:14:39.560Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-06-27T10:14:39.560Z] GC before operation: completed in 76.493 ms, heap usage 417.463 MB -> 67.696 MB. [2024-06-27T10:14:40.703Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1229.889 ms) ====== [2024-06-27T10:14:40.703Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-06-27T10:14:41.033Z] GC before operation: completed in 79.049 ms, heap usage 296.803 MB -> 67.530 MB. [2024-06-27T10:14:42.166Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1265.818 ms) ====== [2024-06-27T10:14:42.166Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-06-27T10:14:42.166Z] GC before operation: completed in 82.465 ms, heap usage 287.870 MB -> 84.236 MB. [2024-06-27T10:14:43.359Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1224.049 ms) ====== [2024-06-27T10:14:43.359Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-06-27T10:14:43.680Z] GC before operation: completed in 87.490 ms, heap usage 425.626 MB -> 84.368 MB. [2024-06-27T10:14:44.804Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1232.360 ms) ====== [2024-06-27T10:14:44.804Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-06-27T10:14:44.804Z] GC before operation: completed in 93.310 ms, heap usage 424.670 MB -> 84.460 MB. [2024-06-27T10:14:45.969Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1225.820 ms) ====== [2024-06-27T10:14:45.969Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-06-27T10:14:46.306Z] GC before operation: completed in 87.098 ms, heap usage 412.055 MB -> 84.552 MB. [2024-06-27T10:14:47.490Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1286.662 ms) ====== [2024-06-27T10:14:47.490Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-06-27T10:14:47.490Z] GC before operation: completed in 84.418 ms, heap usage 420.347 MB -> 84.633 MB. [2024-06-27T10:14:48.639Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1218.170 ms) ====== [2024-06-27T10:14:48.639Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-06-27T10:14:49.002Z] GC before operation: completed in 81.371 ms, heap usage 117.200 MB -> 67.629 MB. [2024-06-27T10:14:50.129Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1268.876 ms) ====== [2024-06-27T10:14:50.129Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-06-27T10:14:50.130Z] GC before operation: completed in 86.606 ms, heap usage 475.232 MB -> 85.022 MB. [2024-06-27T10:14:51.285Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1207.987 ms) ====== [2024-06-27T10:14:51.285Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-06-27T10:14:51.622Z] GC before operation: completed in 84.622 ms, heap usage 128.518 MB -> 84.391 MB. [2024-06-27T10:14:52.763Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1300.001 ms) ====== [2024-06-27T10:14:52.763Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-06-27T10:14:52.763Z] GC before operation: completed in 94.311 ms, heap usage 288.512 MB -> 84.998 MB. [2024-06-27T10:14:54.400Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1347.761 ms) ====== [2024-06-27T10:14:54.400Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-06-27T10:14:54.401Z] GC before operation: completed in 89.666 ms, heap usage 400.018 MB -> 85.103 MB. [2024-06-27T10:14:55.522Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1173.844 ms) ====== [2024-06-27T10:14:55.522Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-06-27T10:14:55.522Z] GC before operation: completed in 87.580 ms, heap usage 414.295 MB -> 85.261 MB. [2024-06-27T10:14:56.704Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1232.164 ms) ====== [2024-06-27T10:14:56.704Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-06-27T10:14:57.029Z] GC before operation: completed in 93.253 ms, heap usage 423.275 MB -> 90.862 MB. [2024-06-27T10:14:58.150Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1180.603 ms) ====== [2024-06-27T10:14:58.150Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-06-27T10:14:58.150Z] GC before operation: completed in 88.470 ms, heap usage 338.156 MB -> 85.456 MB. [2024-06-27T10:14:59.282Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1185.436 ms) ====== [2024-06-27T10:14:59.282Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-06-27T10:14:59.653Z] GC before operation: completed in 102.687 ms, heap usage 384.962 MB -> 90.939 MB. [2024-06-27T10:15:00.780Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1230.725 ms) ====== [2024-06-27T10:15:00.780Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-06-27T10:15:00.780Z] GC before operation: completed in 91.580 ms, heap usage 322.939 MB -> 91.101 MB. [2024-06-27T10:15:01.897Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1165.664 ms) ====== [2024-06-27T10:15:01.898Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-06-27T10:15:01.898Z] GC before operation: completed in 96.386 ms, heap usage 337.191 MB -> 85.694 MB. [2024-06-27T10:15:03.573Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1245.399 ms) ====== [2024-06-27T10:15:03.573Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-06-27T10:15:03.573Z] GC before operation: completed in 93.168 ms, heap usage 373.935 MB -> 85.783 MB. [2024-06-27T10:15:04.699Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1373.119 ms) ====== [2024-06-27T10:15:04.699Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-06-27T10:15:05.254Z] GC before operation: completed in 93.572 ms, heap usage 386.373 MB -> 91.313 MB. [2024-06-27T10:15:06.393Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1265.358 ms) ====== [2024-06-27T10:15:06.393Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-06-27T10:15:06.393Z] GC before operation: completed in 94.671 ms, heap usage 336.747 MB -> 86.034 MB. [2024-06-27T10:15:07.520Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1187.530 ms) ====== [2024-06-27T10:15:07.520Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-06-27T10:15:07.520Z] GC before operation: completed in 96.856 ms, heap usage 378.144 MB -> 86.063 MB. [2024-06-27T10:15:08.652Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1233.533 ms) ====== [2024-06-27T10:15:08.652Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-06-27T10:15:08.982Z] GC before operation: completed in 100.884 ms, heap usage 372.394 MB -> 86.144 MB. [2024-06-27T10:15:10.141Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1188.599 ms) ====== [2024-06-27T10:15:10.141Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-06-27T10:15:10.141Z] GC before operation: completed in 107.172 ms, heap usage 369.702 MB -> 86.293 MB. [2024-06-27T10:15:11.269Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1280.482 ms) ====== [2024-06-27T10:15:11.601Z] ----------------------------------- [2024-06-27T10:15:11.601Z] renaissance-dec-tree_0_PASSED [2024-06-27T10:15:11.601Z] ----------------------------------- [2024-06-27T10:15:11.928Z] [2024-06-27T10:15:11.928Z] TEST TEARDOWN: [2024-06-27T10:15:11.929Z] Nothing to be done for teardown. [2024-06-27T10:15:11.929Z] renaissance-dec-tree_0 Finish Time: Thu Jun 27 10:15:11 2024 Epoch Time (ms): 1719483311693