[2024-11-21T22:24:48.037Z] Running test renaissance-dec-tree_0 ... [2024-11-21T22:24:48.362Z] =============================================== [2024-11-21T22:24:48.362Z] renaissance-dec-tree_0 Start Time: Thu Nov 21 22:24:48 2024 Epoch Time (ms): 1732227888321 [2024-11-21T22:24:48.690Z] variation: NoOptions [2024-11-21T22:24:48.690Z] JVM_OPTIONS: [2024-11-21T22:24:48.690Z] { \ [2024-11-21T22:24:48.690Z] echo ""; echo "TEST SETUP:"; \ [2024-11-21T22:24:48.690Z] echo "Nothing to be done for setup."; \ [2024-11-21T22:24:48.690Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322272919127\\renaissance-dec-tree_0"; \ [2024-11-21T22:24:48.690Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322272919127\\renaissance-dec-tree_0"; \ [2024-11-21T22:24:48.690Z] echo ""; echo "TESTING:"; \ [2024-11-21T22:24:48.690Z] "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_17322272919127\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-11-21T22:24:48.690Z] 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_17322272919127\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-21T22:24:48.690Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-21T22:24:48.690Z] echo "Nothing to be done for teardown."; \ [2024-11-21T22:24:48.690Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322272919127\\TestTargetResult"; [2024-11-21T22:24:48.690Z] [2024-11-21T22:24:48.690Z] TEST SETUP: [2024-11-21T22:24:48.690Z] Nothing to be done for setup. [2024-11-21T22:24:48.690Z] [2024-11-21T22:24:48.690Z] TESTING: [2024-11-21T22:24:59.405Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-21T22:25:00.115Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-21T22:25:03.022Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-21T22:25:03.337Z] GC before operation: completed in 58.566 ms, heap usage 90.091 MB -> 35.338 MB. [2024-11-21T22:25:12.216Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7619.098 ms) ====== [2024-11-21T22:25:12.216Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-21T22:25:12.216Z] GC before operation: completed in 65.553 ms, heap usage 262.410 MB -> 64.917 MB. [2024-11-21T22:25:13.146Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2306.547 ms) ====== [2024-11-21T22:25:13.146Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-21T22:25:13.146Z] GC before operation: completed in 65.902 ms, heap usage 225.808 MB -> 76.320 MB. [2024-11-21T22:25:15.625Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2098.800 ms) ====== [2024-11-21T22:25:15.625Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-21T22:25:15.625Z] GC before operation: completed in 71.442 ms, heap usage 161.591 MB -> 65.181 MB. [2024-11-21T22:25:17.296Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1647.321 ms) ====== [2024-11-21T22:25:17.296Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-21T22:25:17.296Z] GC before operation: completed in 70.902 ms, heap usage 109.224 MB -> 65.151 MB. [2024-11-21T22:25:18.999Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1508.930 ms) ====== [2024-11-21T22:25:18.999Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-21T22:25:18.999Z] GC before operation: completed in 67.582 ms, heap usage 209.445 MB -> 77.213 MB. [2024-11-21T22:25:20.181Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1497.221 ms) ====== [2024-11-21T22:25:20.181Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-21T22:25:20.528Z] GC before operation: completed in 67.146 ms, heap usage 205.363 MB -> 66.546 MB. [2024-11-21T22:25:21.706Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1415.491 ms) ====== [2024-11-21T22:25:21.706Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-21T22:25:21.706Z] GC before operation: completed in 72.069 ms, heap usage 323.905 MB -> 82.857 MB. [2024-11-21T22:25:23.335Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1406.335 ms) ====== [2024-11-21T22:25:23.335Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-21T22:25:23.335Z] GC before operation: completed in 72.572 ms, heap usage 163.508 MB -> 77.279 MB. [2024-11-21T22:25:25.128Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1358.115 ms) ====== [2024-11-21T22:25:25.128Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-21T22:25:25.128Z] GC before operation: completed in 73.495 ms, heap usage 115.439 MB -> 77.351 MB. [2024-11-21T22:25:26.285Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1418.465 ms) ====== [2024-11-21T22:25:26.285Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-21T22:25:26.285Z] GC before operation: completed in 76.826 ms, heap usage 333.012 MB -> 83.311 MB. [2024-11-21T22:25:27.933Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1362.438 ms) ====== [2024-11-21T22:25:27.934Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-21T22:25:27.934Z] GC before operation: completed in 78.744 ms, heap usage 429.717 MB -> 77.975 MB. [2024-11-21T22:25:29.153Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1305.718 ms) ====== [2024-11-21T22:25:29.153Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-21T22:25:29.153Z] GC before operation: completed in 86.681 ms, heap usage 228.531 MB -> 78.164 MB. [2024-11-21T22:25:30.370Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1293.828 ms) ====== [2024-11-21T22:25:30.370Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-21T22:25:30.370Z] GC before operation: completed in 77.305 ms, heap usage 176.030 MB -> 83.392 MB. [2024-11-21T22:25:32.023Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1245.093 ms) ====== [2024-11-21T22:25:32.023Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-21T22:25:32.023Z] GC before operation: completed in 82.443 ms, heap usage 421.166 MB -> 89.251 MB. [2024-11-21T22:25:33.147Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1229.340 ms) ====== [2024-11-21T22:25:33.147Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-21T22:25:33.147Z] GC before operation: completed in 83.406 ms, heap usage 373.735 MB -> 83.921 MB. [2024-11-21T22:25:33.147Z] 24/11/21 22:25:32 WARN BlockManager: Asked to remove block broadcast_204, which does not exist [2024-11-21T22:25:34.281Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1242.026 ms) ====== [2024-11-21T22:25:34.281Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-21T22:25:34.281Z] GC before operation: completed in 79.069 ms, heap usage 408.957 MB -> 67.769 MB. [2024-11-21T22:25:35.908Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1226.673 ms) ====== [2024-11-21T22:25:35.908Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-21T22:25:35.908Z] GC before operation: completed in 80.615 ms, heap usage 294.040 MB -> 67.703 MB. [2024-11-21T22:25:37.031Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1258.629 ms) ====== [2024-11-21T22:25:37.031Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-21T22:25:37.031Z] GC before operation: completed in 86.431 ms, heap usage 289.814 MB -> 78.868 MB. [2024-11-21T22:25:38.167Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1236.368 ms) ====== [2024-11-21T22:25:38.167Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-21T22:25:38.513Z] GC before operation: completed in 85.722 ms, heap usage 144.034 MB -> 78.886 MB. [2024-11-21T22:25:39.637Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1300.778 ms) ====== [2024-11-21T22:25:39.637Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-21T22:25:39.637Z] GC before operation: completed in 88.007 ms, heap usage 442.824 MB -> 84.907 MB. [2024-11-21T22:25:41.285Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1213.928 ms) ====== [2024-11-21T22:25:41.285Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-21T22:25:41.285Z] GC before operation: completed in 83.227 ms, heap usage 125.283 MB -> 84.153 MB. [2024-11-21T22:25:42.464Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1382.349 ms) ====== [2024-11-21T22:25:42.464Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-21T22:25:42.464Z] GC before operation: completed in 86.732 ms, heap usage 273.602 MB -> 84.636 MB. [2024-11-21T22:25:43.691Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1275.086 ms) ====== [2024-11-21T22:25:43.691Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-21T22:25:44.051Z] GC before operation: completed in 92.884 ms, heap usage 407.467 MB -> 90.284 MB. [2024-11-21T22:25:45.176Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1251.490 ms) ====== [2024-11-21T22:25:45.176Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-21T22:25:45.176Z] GC before operation: completed in 85.994 ms, heap usage 337.045 MB -> 84.957 MB. [2024-11-21T22:25:46.326Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1204.076 ms) ====== [2024-11-21T22:25:46.326Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-21T22:25:46.657Z] GC before operation: completed in 90.312 ms, heap usage 365.891 MB -> 85.074 MB. [2024-11-21T22:25:47.782Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1237.527 ms) ====== [2024-11-21T22:25:47.782Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-21T22:25:47.782Z] GC before operation: completed in 90.973 ms, heap usage 379.037 MB -> 85.103 MB. [2024-11-21T22:25:48.922Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1171.412 ms) ====== [2024-11-21T22:25:48.922Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-21T22:25:49.253Z] GC before operation: completed in 94.635 ms, heap usage 375.113 MB -> 85.265 MB. [2024-11-21T22:25:50.383Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1158.789 ms) ====== [2024-11-21T22:25:50.383Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-21T22:25:50.383Z] GC before operation: completed in 88.652 ms, heap usage 377.532 MB -> 85.396 MB. [2024-11-21T22:25:51.510Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1227.878 ms) ====== [2024-11-21T22:25:51.510Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-21T22:25:51.836Z] GC before operation: completed in 94.445 ms, heap usage 367.841 MB -> 90.945 MB. [2024-11-21T22:25:52.970Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1177.864 ms) ====== [2024-11-21T22:25:52.970Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-21T22:25:52.970Z] GC before operation: completed in 89.145 ms, heap usage 306.541 MB -> 85.576 MB. [2024-11-21T22:25:54.100Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1163.323 ms) ====== [2024-11-21T22:25:54.100Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-21T22:25:54.100Z] GC before operation: completed in 92.441 ms, heap usage 376.683 MB -> 91.055 MB. [2024-11-21T22:25:55.729Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1208.463 ms) ====== [2024-11-21T22:25:55.729Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-21T22:25:55.729Z] GC before operation: completed in 94.216 ms, heap usage 330.080 MB -> 91.165 MB. [2024-11-21T22:25:56.839Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1222.031 ms) ====== [2024-11-21T22:25:56.839Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-21T22:25:56.839Z] GC before operation: completed in 93.107 ms, heap usage 328.457 MB -> 85.781 MB. [2024-11-21T22:25:58.005Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1181.872 ms) ====== [2024-11-21T22:25:58.005Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-21T22:25:58.005Z] GC before operation: completed in 92.140 ms, heap usage 373.875 MB -> 85.891 MB. [2024-11-21T22:25:59.209Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1186.941 ms) ====== [2024-11-21T22:25:59.209Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-21T22:25:59.569Z] GC before operation: completed in 94.969 ms, heap usage 365.347 MB -> 91.487 MB. [2024-11-21T22:26:00.689Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1360.064 ms) ====== [2024-11-21T22:26:00.689Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-21T22:26:01.066Z] GC before operation: completed in 95.404 ms, heap usage 328.397 MB -> 86.155 MB. [2024-11-21T22:26:02.189Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1193.194 ms) ====== [2024-11-21T22:26:02.189Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-21T22:26:02.189Z] GC before operation: completed in 93.107 ms, heap usage 350.490 MB -> 86.284 MB. [2024-11-21T22:26:03.334Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1226.279 ms) ====== [2024-11-21T22:26:03.334Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-21T22:26:03.334Z] GC before operation: completed in 96.630 ms, heap usage 361.532 MB -> 86.350 MB. [2024-11-21T22:26:04.973Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1174.827 ms) ====== [2024-11-21T22:26:04.973Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-21T22:26:04.973Z] GC before operation: completed in 96.574 ms, heap usage 352.244 MB -> 86.436 MB. [2024-11-21T22:26:06.098Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1192.444 ms) ====== [2024-11-21T22:26:06.098Z] ----------------------------------- [2024-11-21T22:26:06.098Z] renaissance-dec-tree_0_PASSED [2024-11-21T22:26:06.098Z] ----------------------------------- [2024-11-21T22:26:06.434Z] [2024-11-21T22:26:06.434Z] TEST TEARDOWN: [2024-11-21T22:26:06.434Z] Nothing to be done for teardown. [2024-11-21T22:26:06.434Z] renaissance-dec-tree_0 Finish Time: Thu Nov 21 22:26:06 2024 Epoch Time (ms): 1732227966195