[2024-08-01T03:29:49.630Z] Running test renaissance-dec-tree_0 ... [2024-08-01T03:29:49.946Z] =============================================== [2024-08-01T03:29:49.946Z] renaissance-dec-tree_0 Start Time: Thu Aug 1 03:29:49 2024 Epoch Time (ms): 1722482989734 [2024-08-01T03:29:49.946Z] variation: NoOptions [2024-08-01T03:29:50.267Z] JVM_OPTIONS: [2024-08-01T03:29:50.267Z] { \ [2024-08-01T03:29:50.267Z] echo ""; echo "TEST SETUP:"; \ [2024-08-01T03:29:50.267Z] echo "Nothing to be done for setup."; \ [2024-08-01T03:29:50.268Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17224823655998\\renaissance-dec-tree_0"; \ [2024-08-01T03:29:50.268Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17224823655998\\renaissance-dec-tree_0"; \ [2024-08-01T03:29:50.268Z] echo ""; echo "TESTING:"; \ [2024-08-01T03:29:50.268Z] "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_17224823655998\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-08-01T03:29:50.268Z] 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_17224823655998\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-01T03:29:50.268Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-01T03:29:50.268Z] echo "Nothing to be done for teardown."; \ [2024-08-01T03:29:50.268Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17224823655998\\TestTargetResult"; [2024-08-01T03:29:50.268Z] [2024-08-01T03:29:50.268Z] TEST SETUP: [2024-08-01T03:29:50.268Z] Nothing to be done for setup. [2024-08-01T03:29:50.268Z] [2024-08-01T03:29:50.268Z] TESTING: [2024-08-01T03:30:00.912Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-01T03:30:02.022Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-01T03:30:05.685Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-01T03:30:05.685Z] GC before operation: completed in 52.468 ms, heap usage 60.657 MB -> 35.282 MB. [2024-08-01T03:30:13.107Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7530.548 ms) ====== [2024-08-01T03:30:13.107Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-01T03:30:13.107Z] GC before operation: completed in 59.626 ms, heap usage 113.057 MB -> 64.441 MB. [2024-08-01T03:30:15.021Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2282.894 ms) ====== [2024-08-01T03:30:15.021Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-01T03:30:15.021Z] GC before operation: completed in 64.718 ms, heap usage 190.576 MB -> 65.060 MB. [2024-08-01T03:30:17.357Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1956.489 ms) ====== [2024-08-01T03:30:17.357Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-01T03:30:17.357Z] GC before operation: completed in 61.345 ms, heap usage 106.245 MB -> 64.790 MB. [2024-08-01T03:30:19.152Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1824.699 ms) ====== [2024-08-01T03:30:19.152Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-01T03:30:19.152Z] GC before operation: completed in 64.452 ms, heap usage 237.021 MB -> 65.672 MB. [2024-08-01T03:30:20.878Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1524.651 ms) ====== [2024-08-01T03:30:20.878Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-01T03:30:20.878Z] GC before operation: completed in 65.715 ms, heap usage 163.478 MB -> 76.776 MB. [2024-08-01T03:30:22.049Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1466.238 ms) ====== [2024-08-01T03:30:22.049Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-01T03:30:22.049Z] GC before operation: completed in 71.445 ms, heap usage 216.136 MB -> 77.433 MB. [2024-08-01T03:30:23.730Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1470.572 ms) ====== [2024-08-01T03:30:23.730Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-01T03:30:23.730Z] GC before operation: completed in 69.119 ms, heap usage 204.242 MB -> 77.368 MB. [2024-08-01T03:30:24.992Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1428.092 ms) ====== [2024-08-01T03:30:24.992Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-01T03:30:25.343Z] GC before operation: completed in 76.254 ms, heap usage 206.339 MB -> 77.687 MB. [2024-08-01T03:30:26.481Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1428.628 ms) ====== [2024-08-01T03:30:26.481Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-01T03:30:26.803Z] GC before operation: completed in 70.500 ms, heap usage 218.273 MB -> 77.579 MB. [2024-08-01T03:30:27.942Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1303.548 ms) ====== [2024-08-01T03:30:27.942Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-01T03:30:27.942Z] GC before operation: completed in 73.377 ms, heap usage 214.254 MB -> 83.169 MB. [2024-08-01T03:30:29.569Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1360.032 ms) ====== [2024-08-01T03:30:29.569Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-01T03:30:29.569Z] GC before operation: completed in 76.862 ms, heap usage 447.430 MB -> 78.298 MB. [2024-08-01T03:30:30.676Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1264.047 ms) ====== [2024-08-01T03:30:30.676Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-01T03:30:30.676Z] GC before operation: completed in 76.867 ms, heap usage 230.590 MB -> 78.210 MB. [2024-08-01T03:30:32.270Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1300.936 ms) ====== [2024-08-01T03:30:32.270Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-01T03:30:32.270Z] GC before operation: completed in 77.992 ms, heap usage 196.417 MB -> 83.572 MB. [2024-08-01T03:30:33.423Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1240.294 ms) ====== [2024-08-01T03:30:33.423Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-01T03:30:33.423Z] GC before operation: completed in 81.784 ms, heap usage 450.781 MB -> 89.570 MB. [2024-08-01T03:30:35.008Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1223.750 ms) ====== [2024-08-01T03:30:35.008Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-01T03:30:35.008Z] GC before operation: completed in 83.291 ms, heap usage 374.471 MB -> 83.816 MB. [2024-08-01T03:30:36.113Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1213.749 ms) ====== [2024-08-01T03:30:36.113Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-01T03:30:36.113Z] GC before operation: completed in 80.929 ms, heap usage 421.568 MB -> 67.528 MB. [2024-08-01T03:30:37.237Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1208.018 ms) ====== [2024-08-01T03:30:37.237Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-01T03:30:37.561Z] GC before operation: completed in 86.980 ms, heap usage 296.146 MB -> 67.624 MB. [2024-08-01T03:30:38.669Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1208.871 ms) ====== [2024-08-01T03:30:38.669Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-01T03:30:38.669Z] GC before operation: completed in 85.885 ms, heap usage 318.415 MB -> 84.136 MB. [2024-08-01T03:30:39.794Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1210.252 ms) ====== [2024-08-01T03:30:39.794Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-01T03:30:40.118Z] GC before operation: completed in 87.738 ms, heap usage 427.963 MB -> 84.288 MB. [2024-08-01T03:30:41.275Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1305.375 ms) ====== [2024-08-01T03:30:41.275Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-01T03:30:41.275Z] GC before operation: completed in 84.277 ms, heap usage 421.047 MB -> 84.446 MB. [2024-08-01T03:30:42.914Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1223.881 ms) ====== [2024-08-01T03:30:42.914Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-01T03:30:42.914Z] GC before operation: completed in 79.912 ms, heap usage 123.505 MB -> 67.384 MB. [2024-08-01T03:30:44.018Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1233.599 ms) ====== [2024-08-01T03:30:44.018Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-01T03:30:44.018Z] GC before operation: completed in 83.274 ms, heap usage 99.299 MB -> 67.495 MB. [2024-08-01T03:30:45.649Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1342.781 ms) ====== [2024-08-01T03:30:45.649Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-01T03:30:45.649Z] GC before operation: completed in 91.613 ms, heap usage 478.641 MB -> 90.371 MB. [2024-08-01T03:30:46.753Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1220.209 ms) ====== [2024-08-01T03:30:46.753Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-01T03:30:46.753Z] GC before operation: completed in 88.218 ms, heap usage 363.882 MB -> 84.784 MB. [2024-08-01T03:30:47.982Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1210.039 ms) ====== [2024-08-01T03:30:47.982Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-01T03:30:47.982Z] GC before operation: completed in 88.530 ms, heap usage 390.976 MB -> 85.017 MB. [2024-08-01T03:30:49.593Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1213.968 ms) ====== [2024-08-01T03:30:49.593Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-01T03:30:49.593Z] GC before operation: completed in 89.828 ms, heap usage 395.775 MB -> 84.981 MB. [2024-08-01T03:30:50.681Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1168.636 ms) ====== [2024-08-01T03:30:50.681Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-01T03:30:50.681Z] GC before operation: completed in 87.500 ms, heap usage 372.404 MB -> 85.117 MB. [2024-08-01T03:30:51.782Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1203.696 ms) ====== [2024-08-01T03:30:51.782Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-01T03:30:51.782Z] GC before operation: completed in 88.193 ms, heap usage 400.221 MB -> 85.245 MB. [2024-08-01T03:30:52.996Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1203.574 ms) ====== [2024-08-01T03:30:52.996Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-01T03:30:53.331Z] GC before operation: completed in 91.197 ms, heap usage 400.196 MB -> 90.851 MB. [2024-08-01T03:30:54.437Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1219.917 ms) ====== [2024-08-01T03:30:54.437Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-01T03:30:54.437Z] GC before operation: completed in 96.314 ms, heap usage 336.680 MB -> 85.470 MB. [2024-08-01T03:30:56.055Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1199.959 ms) ====== [2024-08-01T03:30:56.055Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-01T03:30:56.055Z] GC before operation: completed in 96.650 ms, heap usage 395.995 MB -> 90.944 MB. [2024-08-01T03:30:57.157Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1249.667 ms) ====== [2024-08-01T03:30:57.157Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-01T03:30:57.157Z] GC before operation: completed in 90.634 ms, heap usage 337.550 MB -> 91.156 MB. [2024-08-01T03:30:57.157Z] 24/08/01 03:30:56 WARN BlockManager: Asked to remove block broadcast_442, which does not exist [2024-08-01T03:30:58.262Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1153.223 ms) ====== [2024-08-01T03:30:58.262Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-01T03:30:58.262Z] GC before operation: completed in 101.094 ms, heap usage 321.744 MB -> 85.816 MB. [2024-08-01T03:30:59.861Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1200.759 ms) ====== [2024-08-01T03:30:59.861Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-01T03:30:59.861Z] GC before operation: completed in 92.869 ms, heap usage 373.588 MB -> 85.883 MB. [2024-08-01T03:31:01.001Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1218.035 ms) ====== [2024-08-01T03:31:01.001Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-01T03:31:01.001Z] GC before operation: completed in 94.979 ms, heap usage 373.971 MB -> 91.302 MB. [2024-08-01T03:31:02.160Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1199.889 ms) ====== [2024-08-01T03:31:02.160Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-01T03:31:02.160Z] GC before operation: completed in 94.272 ms, heap usage 334.230 MB -> 86.006 MB. [2024-08-01T03:31:02.161Z] 24/08/01 03:31:02 WARN BlockManager: Asked to remove block broadcast_498, which does not exist [2024-08-01T03:31:03.779Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1177.647 ms) ====== [2024-08-01T03:31:03.779Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-01T03:31:03.779Z] GC before operation: completed in 91.242 ms, heap usage 367.092 MB -> 86.088 MB. [2024-08-01T03:31:04.879Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1146.794 ms) ====== [2024-08-01T03:31:04.879Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-01T03:31:04.879Z] GC before operation: completed in 90.371 ms, heap usage 378.237 MB -> 86.251 MB. [2024-08-01T03:31:05.981Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1173.112 ms) ====== [2024-08-01T03:31:05.981Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-01T03:31:05.981Z] GC before operation: completed in 94.352 ms, heap usage 362.353 MB -> 86.346 MB. [2024-08-01T03:31:07.580Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1179.922 ms) ====== [2024-08-01T03:31:07.580Z] ----------------------------------- [2024-08-01T03:31:07.580Z] renaissance-dec-tree_0_PASSED [2024-08-01T03:31:07.580Z] ----------------------------------- [2024-08-01T03:31:07.901Z] [2024-08-01T03:31:07.901Z] TEST TEARDOWN: [2024-08-01T03:31:07.901Z] Nothing to be done for teardown. [2024-08-01T03:31:07.901Z] renaissance-dec-tree_0 Finish Time: Thu Aug 1 03:31:07 2024 Epoch Time (ms): 1722483067759