[2024-08-10T06:52:03.779Z] Running test renaissance-dec-tree_0 ... [2024-08-10T06:52:03.779Z] =============================================== [2024-08-10T06:52:03.779Z] renaissance-dec-tree_0 Start Time: Sat Aug 10 06:52:03 2024 Epoch Time (ms): 1723272723736 [2024-08-10T06:52:04.090Z] variation: NoOptions [2024-08-10T06:52:04.090Z] JVM_OPTIONS: [2024-08-10T06:52:04.090Z] { \ [2024-08-10T06:52:04.090Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T06:52:04.090Z] echo "Nothing to be done for setup."; \ [2024-08-10T06:52:04.090Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\renaissance-dec-tree_0"; \ [2024-08-10T06:52:04.090Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\renaissance-dec-tree_0"; \ [2024-08-10T06:52:04.090Z] echo ""; echo "TESTING:"; \ [2024-08-10T06:52:04.090Z] "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_17232721172834\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-08-10T06:52:04.090Z] 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_17232721172834\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T06:52:04.090Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T06:52:04.090Z] echo "Nothing to be done for teardown."; \ [2024-08-10T06:52:04.090Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\TestTargetResult"; [2024-08-10T06:52:04.090Z] [2024-08-10T06:52:04.090Z] TEST SETUP: [2024-08-10T06:52:04.090Z] Nothing to be done for setup. [2024-08-10T06:52:04.090Z] [2024-08-10T06:52:04.090Z] TESTING: [2024-08-10T06:52:14.602Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T06:52:15.690Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-10T06:52:18.504Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-10T06:52:18.504Z] GC before operation: completed in 37.515 ms, heap usage 59.359 MB -> 35.340 MB. [2024-08-10T06:52:27.264Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7352.381 ms) ====== [2024-08-10T06:52:27.264Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-10T06:52:27.264Z] GC before operation: completed in 80.073 ms, heap usage 206.825 MB -> 75.774 MB. [2024-08-10T06:52:28.161Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2118.674 ms) ====== [2024-08-10T06:52:28.161Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-10T06:52:28.161Z] GC before operation: completed in 62.752 ms, heap usage 233.155 MB -> 65.281 MB. [2024-08-10T06:52:30.549Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1876.569 ms) ====== [2024-08-10T06:52:30.549Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-10T06:52:30.549Z] GC before operation: completed in 73.534 ms, heap usage 165.973 MB -> 65.319 MB. [2024-08-10T06:52:31.715Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1598.923 ms) ====== [2024-08-10T06:52:31.715Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-10T06:52:32.044Z] GC before operation: completed in 68.190 ms, heap usage 226.081 MB -> 66.057 MB. [2024-08-10T06:52:33.699Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1456.830 ms) ====== [2024-08-10T06:52:33.699Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-10T06:52:33.699Z] GC before operation: completed in 64.766 ms, heap usage 162.042 MB -> 76.813 MB. [2024-08-10T06:52:34.799Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1384.668 ms) ====== [2024-08-10T06:52:34.799Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-10T06:52:34.799Z] GC before operation: completed in 67.595 ms, heap usage 204.074 MB -> 77.563 MB. [2024-08-10T06:52:36.458Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1457.873 ms) ====== [2024-08-10T06:52:36.458Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-10T06:52:36.458Z] GC before operation: completed in 71.779 ms, heap usage 198.101 MB -> 77.486 MB. [2024-08-10T06:52:38.125Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1358.362 ms) ====== [2024-08-10T06:52:38.125Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-10T06:52:38.125Z] GC before operation: completed in 71.564 ms, heap usage 199.997 MB -> 77.646 MB. [2024-08-10T06:52:39.249Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1472.002 ms) ====== [2024-08-10T06:52:39.249Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-10T06:52:39.583Z] GC before operation: completed in 72.486 ms, heap usage 209.375 MB -> 77.771 MB. [2024-08-10T06:52:40.690Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1401.180 ms) ====== [2024-08-10T06:52:40.690Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-10T06:52:41.034Z] GC before operation: completed in 73.529 ms, heap usage 196.223 MB -> 83.323 MB. [2024-08-10T06:52:42.137Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1221.760 ms) ====== [2024-08-10T06:52:42.137Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-10T06:52:42.137Z] GC before operation: completed in 75.735 ms, heap usage 453.949 MB -> 83.847 MB. [2024-08-10T06:52:43.703Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1266.818 ms) ====== [2024-08-10T06:52:43.703Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-10T06:52:43.703Z] GC before operation: completed in 75.706 ms, heap usage 180.817 MB -> 77.915 MB. [2024-08-10T06:52:44.799Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1286.925 ms) ====== [2024-08-10T06:52:44.799Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-10T06:52:44.799Z] GC before operation: completed in 75.242 ms, heap usage 142.359 MB -> 83.392 MB. [2024-08-10T06:52:46.370Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1279.498 ms) ====== [2024-08-10T06:52:46.370Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-10T06:52:46.370Z] GC before operation: completed in 80.939 ms, heap usage 375.440 MB -> 89.227 MB. [2024-08-10T06:52:47.454Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1242.378 ms) ====== [2024-08-10T06:52:47.454Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-10T06:52:47.454Z] GC before operation: completed in 81.896 ms, heap usage 361.314 MB -> 84.142 MB. [2024-08-10T06:52:49.023Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1197.073 ms) ====== [2024-08-10T06:52:49.023Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-10T06:52:49.023Z] GC before operation: completed in 77.656 ms, heap usage 393.134 MB -> 67.716 MB. [2024-08-10T06:52:50.124Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1218.050 ms) ====== [2024-08-10T06:52:50.124Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-10T06:52:50.124Z] GC before operation: completed in 77.881 ms, heap usage 280.136 MB -> 67.741 MB. [2024-08-10T06:52:51.238Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1227.689 ms) ====== [2024-08-10T06:52:51.238Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-10T06:52:51.567Z] GC before operation: completed in 83.848 ms, heap usage 288.408 MB -> 84.378 MB. [2024-08-10T06:52:52.651Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1197.636 ms) ====== [2024-08-10T06:52:52.651Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-10T06:52:52.651Z] GC before operation: completed in 85.418 ms, heap usage 394.503 MB -> 84.470 MB. [2024-08-10T06:52:54.266Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1198.980 ms) ====== [2024-08-10T06:52:54.266Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-10T06:52:54.266Z] GC before operation: completed in 97.362 ms, heap usage 396.552 MB -> 84.752 MB. [2024-08-10T06:52:55.346Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1160.556 ms) ====== [2024-08-10T06:52:55.346Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-10T06:52:55.346Z] GC before operation: completed in 85.016 ms, heap usage 389.782 MB -> 84.853 MB. [2024-08-10T06:52:55.346Z] 24/08/10 06:52:55 WARN BlockManager: Asked to remove block broadcast_288, which does not exist [2024-08-10T06:52:56.445Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1206.859 ms) ====== [2024-08-10T06:52:56.445Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-10T06:52:56.445Z] GC before operation: completed in 85.059 ms, heap usage 390.465 MB -> 84.751 MB. [2024-08-10T06:52:58.112Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1266.525 ms) ====== [2024-08-10T06:52:58.113Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-10T06:52:58.113Z] GC before operation: completed in 85.928 ms, heap usage 373.298 MB -> 90.223 MB. [2024-08-10T06:52:59.204Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1203.178 ms) ====== [2024-08-10T06:52:59.204Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-10T06:52:59.204Z] GC before operation: completed in 85.821 ms, heap usage 337.420 MB -> 84.951 MB. [2024-08-10T06:53:00.403Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1171.778 ms) ====== [2024-08-10T06:53:00.403Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-10T06:53:00.403Z] GC before operation: completed in 87.076 ms, heap usage 380.466 MB -> 85.207 MB. [2024-08-10T06:53:01.565Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1215.065 ms) ====== [2024-08-10T06:53:01.566Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-10T06:53:01.893Z] GC before operation: completed in 90.827 ms, heap usage 378.188 MB -> 85.144 MB. [2024-08-10T06:53:03.001Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1157.080 ms) ====== [2024-08-10T06:53:03.001Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-10T06:53:03.001Z] GC before operation: completed in 87.877 ms, heap usage 359.740 MB -> 85.251 MB. [2024-08-10T06:53:04.116Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1216.414 ms) ====== [2024-08-10T06:53:04.116Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-10T06:53:04.438Z] GC before operation: completed in 88.040 ms, heap usage 369.833 MB -> 85.402 MB. [2024-08-10T06:53:05.524Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1183.856 ms) ====== [2024-08-10T06:53:05.525Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-10T06:53:05.525Z] GC before operation: completed in 91.977 ms, heap usage 379.647 MB -> 90.959 MB. [2024-08-10T06:53:06.630Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1149.961 ms) ====== [2024-08-10T06:53:06.630Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-10T06:53:06.954Z] GC before operation: completed in 89.561 ms, heap usage 328.048 MB -> 85.457 MB. [2024-08-10T06:53:08.045Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1131.662 ms) ====== [2024-08-10T06:53:08.045Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-10T06:53:08.045Z] GC before operation: completed in 90.319 ms, heap usage 362.218 MB -> 91.023 MB. [2024-08-10T06:53:09.133Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1149.435 ms) ====== [2024-08-10T06:53:09.133Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-10T06:53:09.133Z] GC before operation: completed in 90.378 ms, heap usage 325.116 MB -> 91.181 MB. [2024-08-10T06:53:10.723Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1203.869 ms) ====== [2024-08-10T06:53:10.723Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-10T06:53:10.723Z] GC before operation: completed in 103.561 ms, heap usage 326.624 MB -> 85.812 MB. [2024-08-10T06:53:11.807Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1148.830 ms) ====== [2024-08-10T06:53:11.807Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-10T06:53:11.807Z] GC before operation: completed in 89.710 ms, heap usage 366.099 MB -> 85.930 MB. [2024-08-10T06:53:12.975Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1226.814 ms) ====== [2024-08-10T06:53:12.975Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-10T06:53:13.290Z] GC before operation: completed in 92.688 ms, heap usage 383.019 MB -> 91.710 MB. [2024-08-10T06:53:14.389Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1148.471 ms) ====== [2024-08-10T06:53:14.389Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-10T06:53:14.389Z] GC before operation: completed in 90.037 ms, heap usage 313.947 MB -> 86.197 MB. [2024-08-10T06:53:14.389Z] 24/08/10 06:53:14 WARN BlockManager: Asked to remove block rdd_1761_2, which does not exist [2024-08-10T06:53:15.475Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1173.223 ms) ====== [2024-08-10T06:53:15.475Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-10T06:53:15.805Z] GC before operation: completed in 99.991 ms, heap usage 366.975 MB -> 80.804 MB. [2024-08-10T06:53:16.887Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1144.532 ms) ====== [2024-08-10T06:53:16.887Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-10T06:53:16.887Z] GC before operation: completed in 94.040 ms, heap usage 420.274 MB -> 86.341 MB. [2024-08-10T06:53:18.057Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1167.708 ms) ====== [2024-08-10T06:53:18.057Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-10T06:53:18.057Z] GC before operation: completed in 105.085 ms, heap usage 356.269 MB -> 86.467 MB. [2024-08-10T06:53:19.648Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1184.049 ms) ====== [2024-08-10T06:53:19.648Z] ----------------------------------- [2024-08-10T06:53:19.648Z] renaissance-dec-tree_0_PASSED [2024-08-10T06:53:19.648Z] ----------------------------------- [2024-08-10T06:53:19.952Z] [2024-08-10T06:53:19.952Z] TEST TEARDOWN: [2024-08-10T06:53:19.952Z] Nothing to be done for teardown. [2024-08-10T06:53:19.952Z] renaissance-dec-tree_0 Finish Time: Sat Aug 10 06:53:19 2024 Epoch Time (ms): 1723272799739