[2025-01-14T10:16:59.779Z] Running test renaissance-dec-tree_0 ... [2025-01-14T10:17:00.096Z] =============================================== [2025-01-14T10:17:00.096Z] renaissance-dec-tree_0 Start Time: Tue Jan 14 10:16:59 2025 Epoch Time (ms): 1736849819917 [2025-01-14T10:17:00.431Z] variation: NoOptions [2025-01-14T10:17:00.431Z] JVM_OPTIONS: [2025-01-14T10:17:00.431Z] { \ [2025-01-14T10:17:00.431Z] echo ""; echo "TEST SETUP:"; \ [2025-01-14T10:17:00.431Z] echo "Nothing to be done for setup."; \ [2025-01-14T10:17:00.431Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17368492198530\\renaissance-dec-tree_0"; \ [2025-01-14T10:17:00.431Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17368492198530\\renaissance-dec-tree_0"; \ [2025-01-14T10:17:00.431Z] echo ""; echo "TESTING:"; \ [2025-01-14T10:17:00.431Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_rerun/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_rerun/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17368492198530\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-01-14T10:17:00.431Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_rerun/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17368492198530\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-14T10:17:00.431Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-14T10:17:00.431Z] echo "Nothing to be done for teardown."; \ [2025-01-14T10:17:00.431Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17368492198530\\TestTargetResult"; [2025-01-14T10:17:00.769Z] [2025-01-14T10:17:00.769Z] TEST SETUP: [2025-01-14T10:17:00.770Z] Nothing to be done for setup. [2025-01-14T10:17:00.770Z] [2025-01-14T10:17:00.770Z] TESTING: [2025-01-14T10:17:13.777Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-14T10:17:16.002Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-01-14T10:17:19.724Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-01-14T10:17:19.724Z] GC before operation: completed in 49.760 ms, heap usage 59.379 MB -> 35.415 MB. [2025-01-14T10:17:28.899Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8439.272 ms) ====== [2025-01-14T10:17:28.899Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-01-14T10:17:28.899Z] GC before operation: completed in 70.986 ms, heap usage 115.870 MB -> 75.890 MB. [2025-01-14T10:17:30.313Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2658.049 ms) ====== [2025-01-14T10:17:30.313Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-01-14T10:17:30.706Z] GC before operation: completed in 70.857 ms, heap usage 161.072 MB -> 64.964 MB. [2025-01-14T10:17:32.796Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2326.583 ms) ====== [2025-01-14T10:17:32.796Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-01-14T10:17:33.147Z] GC before operation: completed in 75.986 ms, heap usage 264.472 MB -> 76.900 MB. [2025-01-14T10:17:34.960Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1821.686 ms) ====== [2025-01-14T10:17:34.960Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-01-14T10:17:34.960Z] GC before operation: completed in 72.439 ms, heap usage 286.790 MB -> 76.930 MB. [2025-01-14T10:17:36.144Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1511.999 ms) ====== [2025-01-14T10:17:36.144Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-01-14T10:17:36.489Z] GC before operation: completed in 74.450 ms, heap usage 258.241 MB -> 77.126 MB. [2025-01-14T10:17:37.801Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1581.978 ms) ====== [2025-01-14T10:17:37.801Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-01-14T10:17:38.149Z] GC before operation: completed in 76.202 ms, heap usage 222.145 MB -> 66.253 MB. [2025-01-14T10:17:39.818Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1639.094 ms) ====== [2025-01-14T10:17:39.818Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-01-14T10:17:39.818Z] GC before operation: completed in 80.386 ms, heap usage 320.146 MB -> 77.487 MB. [2025-01-14T10:17:41.476Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1469.244 ms) ====== [2025-01-14T10:17:41.476Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-01-14T10:17:41.476Z] GC before operation: completed in 82.824 ms, heap usage 246.053 MB -> 77.719 MB. [2025-01-14T10:17:43.214Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1580.109 ms) ====== [2025-01-14T10:17:43.214Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-01-14T10:17:43.214Z] GC before operation: completed in 82.802 ms, heap usage 197.634 MB -> 77.852 MB. [2025-01-14T10:17:44.352Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1376.089 ms) ====== [2025-01-14T10:17:44.352Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-01-14T10:17:44.352Z] GC before operation: completed in 80.915 ms, heap usage 184.591 MB -> 83.121 MB. [2025-01-14T10:17:46.021Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1420.363 ms) ====== [2025-01-14T10:17:46.022Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-01-14T10:17:46.022Z] GC before operation: completed in 85.225 ms, heap usage 461.225 MB -> 78.264 MB. [2025-01-14T10:17:47.283Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1348.027 ms) ====== [2025-01-14T10:17:47.283Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-01-14T10:17:47.283Z] GC before operation: completed in 86.697 ms, heap usage 247.071 MB -> 78.246 MB. [2025-01-14T10:17:48.959Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1360.404 ms) ====== [2025-01-14T10:17:48.959Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-01-14T10:17:48.959Z] GC before operation: completed in 85.997 ms, heap usage 194.771 MB -> 83.776 MB. [2025-01-14T10:17:50.107Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1361.416 ms) ====== [2025-01-14T10:17:50.107Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-01-14T10:17:50.107Z] GC before operation: completed in 91.257 ms, heap usage 447.580 MB -> 89.522 MB. [2025-01-14T10:17:51.736Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1375.193 ms) ====== [2025-01-14T10:17:51.736Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-01-14T10:17:51.736Z] GC before operation: completed in 92.341 ms, heap usage 371.372 MB -> 84.153 MB. [2025-01-14T10:17:52.915Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1324.734 ms) ====== [2025-01-14T10:17:52.915Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-01-14T10:17:53.255Z] GC before operation: completed in 91.132 ms, heap usage 397.485 MB -> 67.587 MB. [2025-01-14T10:17:54.398Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1331.779 ms) ====== [2025-01-14T10:17:54.398Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-01-14T10:17:54.730Z] GC before operation: completed in 92.395 ms, heap usage 285.274 MB -> 67.703 MB. [2025-01-14T10:17:55.868Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1344.689 ms) ====== [2025-01-14T10:17:55.868Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-01-14T10:17:55.868Z] GC before operation: completed in 99.164 ms, heap usage 310.097 MB -> 78.939 MB. [2025-01-14T10:17:57.493Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1458.349 ms) ====== [2025-01-14T10:17:57.493Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-01-14T10:17:57.493Z] GC before operation: completed in 104.141 ms, heap usage 140.349 MB -> 84.223 MB. [2025-01-14T10:17:59.250Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1398.187 ms) ====== [2025-01-14T10:17:59.250Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-01-14T10:17:59.250Z] GC before operation: completed in 94.042 ms, heap usage 362.191 MB -> 84.562 MB. [2025-01-14T10:18:00.379Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1336.061 ms) ====== [2025-01-14T10:18:00.379Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-01-14T10:18:00.379Z] GC before operation: completed in 93.653 ms, heap usage 417.395 MB -> 84.745 MB. [2025-01-14T10:18:00.379Z] 25/01/14 10:18:00 WARN BlockManager: Asked to remove block rdd_1026_0, which does not exist [2025-01-14T10:18:02.068Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1316.693 ms) ====== [2025-01-14T10:18:02.068Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-01-14T10:18:02.068Z] GC before operation: completed in 100.425 ms, heap usage 420.691 MB -> 84.755 MB. [2025-01-14T10:18:03.201Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1307.189 ms) ====== [2025-01-14T10:18:03.201Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-01-14T10:18:03.201Z] GC before operation: completed in 98.946 ms, heap usage 378.571 MB -> 90.496 MB. [2025-01-14T10:18:04.847Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1334.090 ms) ====== [2025-01-14T10:18:04.847Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-01-14T10:18:04.847Z] GC before operation: completed in 95.244 ms, heap usage 328.393 MB -> 84.944 MB. [2025-01-14T10:18:04.847Z] 25/01/14 10:18:04 WARN BlockManager: Asked to remove block rdd_1173_2, which does not exist [2025-01-14T10:18:06.042Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1272.195 ms) ====== [2025-01-14T10:18:06.042Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-01-14T10:18:06.042Z] GC before operation: completed in 97.392 ms, heap usage 390.630 MB -> 85.292 MB. [2025-01-14T10:18:07.685Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1322.587 ms) ====== [2025-01-14T10:18:07.685Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-01-14T10:18:07.685Z] GC before operation: completed in 99.141 ms, heap usage 380.212 MB -> 85.173 MB. [2025-01-14T10:18:08.817Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1302.885 ms) ====== [2025-01-14T10:18:08.817Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-01-14T10:18:08.817Z] GC before operation: completed in 97.731 ms, heap usage 365.760 MB -> 85.343 MB. [2025-01-14T10:18:10.449Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1273.033 ms) ====== [2025-01-14T10:18:10.449Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-01-14T10:18:10.449Z] GC before operation: completed in 101.371 ms, heap usage 380.479 MB -> 85.380 MB. [2025-01-14T10:18:11.587Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1272.253 ms) ====== [2025-01-14T10:18:11.587Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-01-14T10:18:11.587Z] GC before operation: completed in 109.845 ms, heap usage 367.306 MB -> 90.940 MB. [2025-01-14T10:18:13.245Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1396.752 ms) ====== [2025-01-14T10:18:13.245Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-01-14T10:18:13.245Z] GC before operation: completed in 102.482 ms, heap usage 309.441 MB -> 85.601 MB. [2025-01-14T10:18:14.426Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1322.164 ms) ====== [2025-01-14T10:18:14.426Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-01-14T10:18:14.770Z] GC before operation: completed in 123.847 ms, heap usage 372.209 MB -> 91.135 MB. [2025-01-14T10:18:15.973Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1416.176 ms) ====== [2025-01-14T10:18:15.973Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-01-14T10:18:16.331Z] GC before operation: completed in 108.880 ms, heap usage 314.392 MB -> 80.371 MB. [2025-01-14T10:18:17.525Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1390.885 ms) ====== [2025-01-14T10:18:17.526Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-01-14T10:18:17.526Z] GC before operation: completed in 125.753 ms, heap usage 160.968 MB -> 85.634 MB. [2025-01-14T10:18:19.133Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1291.420 ms) ====== [2025-01-14T10:18:19.133Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-01-14T10:18:19.133Z] GC before operation: completed in 106.918 ms, heap usage 353.273 MB -> 85.955 MB. [2025-01-14T10:18:19.133Z] 25/01/14 10:18:18 WARN BlockManager: Asked to remove block rdd_1663_0, which does not exist [2025-01-14T10:18:19.133Z] 25/01/14 10:18:18 WARN BlockManager: Asked to remove block rdd_1663_2, which does not exist [2025-01-14T10:18:20.965Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1391.628 ms) ====== [2025-01-14T10:18:20.965Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-01-14T10:18:20.965Z] GC before operation: completed in 103.257 ms, heap usage 370.377 MB -> 91.529 MB. [2025-01-14T10:18:22.094Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1346.068 ms) ====== [2025-01-14T10:18:22.094Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-01-14T10:18:22.094Z] GC before operation: completed in 102.110 ms, heap usage 322.460 MB -> 86.261 MB. [2025-01-14T10:18:23.253Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1319.738 ms) ====== [2025-01-14T10:18:23.254Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-01-14T10:18:23.619Z] GC before operation: completed in 116.743 ms, heap usage 362.961 MB -> 86.320 MB. [2025-01-14T10:18:24.759Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1329.237 ms) ====== [2025-01-14T10:18:24.759Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-01-14T10:18:24.759Z] GC before operation: completed in 104.519 ms, heap usage 353.573 MB -> 86.417 MB. [2025-01-14T10:18:26.404Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1361.207 ms) ====== [2025-01-14T10:18:26.404Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-01-14T10:18:26.404Z] GC before operation: completed in 105.570 ms, heap usage 356.336 MB -> 86.497 MB. [2025-01-14T10:18:27.538Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1270.035 ms) ====== [2025-01-14T10:18:27.885Z] ----------------------------------- [2025-01-14T10:18:27.885Z] renaissance-dec-tree_0_PASSED [2025-01-14T10:18:27.885Z] ----------------------------------- [2025-01-14T10:18:28.222Z] [2025-01-14T10:18:28.222Z] TEST TEARDOWN: [2025-01-14T10:18:28.222Z] Nothing to be done for teardown. [2025-01-14T10:18:28.222Z] renaissance-dec-tree_0 Finish Time: Tue Jan 14 10:18:27 2025 Epoch Time (ms): 1736849907988