[2023-04-19T10:58:26.705Z] Running test renaissance-dec-tree_0 ... [2023-04-19T10:58:26.705Z] =============================================== [2023-04-19T10:58:26.705Z] renaissance-dec-tree_0 Start Time: Wed Apr 19 05:58:17 2023 Epoch Time (ms): 1681901897708 [2023-04-19T10:58:26.705Z] variation: NoOptions [2023-04-19T10:58:26.705Z] JVM_OPTIONS: [2023-04-19T10:58:26.705Z] { \ [2023-04-19T10:58:26.705Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T10:58:26.705Z] echo "Nothing to be done for setup."; \ [2023-04-19T10:58:26.705Z] mkdir -p "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-dec-tree_0"; \ [2023-04-19T10:58:26.705Z] cd "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-dec-tree_0"; \ [2023-04-19T10:58:26.705Z] echo ""; echo "TESTING:"; \ [2023-04-19T10:58:26.705Z] "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/openjdkbinary/j2sdk-image\\bin\\java" -jar "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2023-04-19T10:58:26.705Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T10:58:26.705Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T10:58:26.705Z] echo "Nothing to be done for teardown."; \ [2023-04-19T10:58:26.705Z] } 2>&1 | tee -a "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\TestTargetResult"; [2023-04-19T10:58:26.705Z] [2023-04-19T10:58:26.705Z] TEST SETUP: [2023-04-19T10:58:26.705Z] Nothing to be done for setup. [2023-04-19T10:58:26.705Z] [2023-04-19T10:58:26.705Z] TESTING: [2023-04-19T10:58:26.705Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-19T10:58:29.214Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2023-04-19T10:58:33.463Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2023-04-19T10:58:34.008Z] GC before operation: completed in 181.852 ms, heap usage 150.126 MB -> 30.922 MB. [2023-04-19T10:58:43.416Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8731.384 ms) ====== [2023-04-19T10:58:43.416Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2023-04-19T10:58:43.416Z] GC before operation: completed in 353.961 ms, heap usage 550.152 MB -> 55.295 MB. [2023-04-19T10:58:44.550Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1854.900 ms) ====== [2023-04-19T10:58:44.551Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2023-04-19T10:58:45.093Z] GC before operation: completed in 444.009 ms, heap usage 317.053 MB -> 77.144 MB. [2023-04-19T10:58:46.881Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1583.090 ms) ====== [2023-04-19T10:58:46.881Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2023-04-19T10:58:46.881Z] GC before operation: completed in 229.926 ms, heap usage 352.937 MB -> 55.767 MB. [2023-04-19T10:58:48.670Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1515.307 ms) ====== [2023-04-19T10:58:48.670Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2023-04-19T10:58:48.670Z] GC before operation: completed in 254.583 ms, heap usage 290.472 MB -> 67.049 MB. [2023-04-19T10:58:50.478Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1439.527 ms) ====== [2023-04-19T10:58:50.478Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2023-04-19T10:58:50.478Z] GC before operation: completed in 179.360 ms, heap usage 292.574 MB -> 55.828 MB. [2023-04-19T10:58:51.613Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1434.281 ms) ====== [2023-04-19T10:58:51.613Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2023-04-19T10:58:52.156Z] GC before operation: completed in 224.778 ms, heap usage 272.075 MB -> 56.210 MB. [2023-04-19T10:58:53.291Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1398.038 ms) ====== [2023-04-19T10:58:53.291Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2023-04-19T10:58:53.830Z] GC before operation: completed in 224.640 ms, heap usage 253.691 MB -> 67.336 MB. [2023-04-19T10:58:54.962Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1353.573 ms) ====== [2023-04-19T10:58:54.962Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2023-04-19T10:58:55.507Z] GC before operation: completed in 223.551 ms, heap usage 263.274 MB -> 56.445 MB. [2023-04-19T10:58:56.675Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1394.228 ms) ====== [2023-04-19T10:58:56.675Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2023-04-19T10:58:56.675Z] GC before operation: completed in 231.420 ms, heap usage 246.071 MB -> 67.480 MB. [2023-04-19T10:58:58.485Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1432.961 ms) ====== [2023-04-19T10:58:58.485Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2023-04-19T10:58:58.485Z] GC before operation: completed in 228.141 ms, heap usage 254.626 MB -> 67.646 MB. [2023-04-19T10:59:00.273Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1464.529 ms) ====== [2023-04-19T10:59:00.273Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2023-04-19T10:59:00.273Z] GC before operation: completed in 218.595 ms, heap usage 252.725 MB -> 56.914 MB. [2023-04-19T10:59:01.410Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1347.499 ms) ====== [2023-04-19T10:59:01.410Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2023-04-19T10:59:01.952Z] GC before operation: completed in 220.370 ms, heap usage 239.779 MB -> 57.256 MB. [2023-04-19T10:59:03.088Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1300.365 ms) ====== [2023-04-19T10:59:03.088Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2023-04-19T10:59:03.088Z] GC before operation: completed in 240.090 ms, heap usage 243.343 MB -> 57.241 MB. [2023-04-19T10:59:04.878Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1276.958 ms) ====== [2023-04-19T10:59:04.878Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2023-04-19T10:59:04.878Z] GC before operation: completed in 198.792 ms, heap usage 241.323 MB -> 57.386 MB. [2023-04-19T10:59:06.016Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1345.118 ms) ====== [2023-04-19T10:59:06.016Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2023-04-19T10:59:06.558Z] GC before operation: completed in 239.688 ms, heap usage 247.272 MB -> 74.061 MB. [2023-04-19T10:59:07.697Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1303.757 ms) ====== [2023-04-19T10:59:07.697Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2023-04-19T10:59:07.697Z] GC before operation: completed in 226.089 ms, heap usage 262.110 MB -> 57.879 MB. [2023-04-19T10:59:09.489Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1291.510 ms) ====== [2023-04-19T10:59:09.489Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2023-04-19T10:59:09.489Z] GC before operation: completed in 219.304 ms, heap usage 245.812 MB -> 57.917 MB. [2023-04-19T10:59:10.704Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1384.622 ms) ====== [2023-04-19T10:59:10.704Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2023-04-19T10:59:11.248Z] GC before operation: completed in 237.089 ms, heap usage 243.580 MB -> 68.884 MB. [2023-04-19T10:59:12.386Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1418.891 ms) ====== [2023-04-19T10:59:12.386Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2023-04-19T10:59:12.930Z] GC before operation: completed in 240.058 ms, heap usage 250.521 MB -> 58.185 MB. [2023-04-19T10:59:14.067Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1366.637 ms) ====== [2023-04-19T10:59:14.068Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2023-04-19T10:59:14.068Z] GC before operation: completed in 207.218 ms, heap usage 242.546 MB -> 58.311 MB. [2023-04-19T10:59:15.861Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1408.951 ms) ====== [2023-04-19T10:59:15.861Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2023-04-19T10:59:15.861Z] GC before operation: completed in 217.693 ms, heap usage 241.110 MB -> 58.595 MB. [2023-04-19T10:59:17.654Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1372.807 ms) ====== [2023-04-19T10:59:17.654Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2023-04-19T10:59:17.654Z] GC before operation: completed in 256.944 ms, heap usage 244.202 MB -> 64.216 MB. [2023-04-19T10:59:18.791Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1321.476 ms) ====== [2023-04-19T10:59:18.791Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2023-04-19T10:59:19.332Z] GC before operation: completed in 242.726 ms, heap usage 245.807 MB -> 69.796 MB. [2023-04-19T10:59:20.469Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1273.527 ms) ====== [2023-04-19T10:59:20.469Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2023-04-19T10:59:20.469Z] GC before operation: completed in 209.310 ms, heap usage 245.923 MB -> 64.481 MB. [2023-04-19T10:59:22.269Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1250.553 ms) ====== [2023-04-19T10:59:22.269Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2023-04-19T10:59:22.269Z] GC before operation: completed in 213.419 ms, heap usage 245.322 MB -> 69.883 MB. [2023-04-19T10:59:23.404Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1271.212 ms) ====== [2023-04-19T10:59:23.404Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2023-04-19T10:59:23.404Z] GC before operation: completed in 215.365 ms, heap usage 251.002 MB -> 59.269 MB. [2023-04-19T10:59:25.199Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1305.500 ms) ====== [2023-04-19T10:59:25.199Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2023-04-19T10:59:25.199Z] GC before operation: completed in 210.674 ms, heap usage 237.297 MB -> 75.773 MB. [2023-04-19T10:59:25.199Z] 23/04/19 05:59:20 WARN BlockManager: Asked to remove block rdd_1320_1, which does not exist [2023-04-19T10:59:26.338Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1276.987 ms) ====== [2023-04-19T10:59:26.338Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2023-04-19T10:59:26.881Z] GC before operation: completed in 258.655 ms, heap usage 258.548 MB -> 70.276 MB. [2023-04-19T10:59:28.022Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1293.674 ms) ====== [2023-04-19T10:59:28.022Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2023-04-19T10:59:28.022Z] GC before operation: completed in 248.285 ms, heap usage 257.969 MB -> 59.674 MB. [2023-04-19T10:59:29.815Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1361.269 ms) ====== [2023-04-19T10:59:29.815Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2023-04-19T10:59:29.815Z] GC before operation: completed in 287.074 ms, heap usage 244.948 MB -> 76.161 MB. [2023-04-19T10:59:30.955Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1256.512 ms) ====== [2023-04-19T10:59:30.955Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2023-04-19T10:59:31.500Z] GC before operation: completed in 205.714 ms, heap usage 260.669 MB -> 59.795 MB. [2023-04-19T10:59:32.638Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1342.926 ms) ====== [2023-04-19T10:59:32.638Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2023-04-19T10:59:32.638Z] GC before operation: completed in 221.900 ms, heap usage 247.465 MB -> 60.077 MB. [2023-04-19T10:59:34.429Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1328.230 ms) ====== [2023-04-19T10:59:34.429Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2023-04-19T10:59:34.429Z] GC before operation: completed in 227.615 ms, heap usage 252.656 MB -> 76.544 MB. [2023-04-19T10:59:35.567Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1321.035 ms) ====== [2023-04-19T10:59:35.568Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2023-04-19T10:59:36.111Z] GC before operation: completed in 238.427 ms, heap usage 267.435 MB -> 65.761 MB. [2023-04-19T10:59:37.251Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1309.428 ms) ====== [2023-04-19T10:59:37.251Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2023-04-19T10:59:37.251Z] GC before operation: completed in 199.636 ms, heap usage 253.667 MB -> 71.386 MB. [2023-04-19T10:59:39.040Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1401.862 ms) ====== [2023-04-19T10:59:39.040Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2023-04-19T10:59:39.040Z] GC before operation: completed in 211.504 ms, heap usage 248.686 MB -> 60.614 MB. [2023-04-19T10:59:40.179Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1275.858 ms) ====== [2023-04-19T10:59:40.179Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2023-04-19T10:59:40.723Z] GC before operation: completed in 269.093 ms, heap usage 243.317 MB -> 71.489 MB. [2023-04-19T10:59:41.861Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1212.089 ms) ====== [2023-04-19T10:59:41.861Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2023-04-19T10:59:41.861Z] GC before operation: completed in 218.596 ms, heap usage 251.779 MB -> 60.735 MB. [2023-04-19T10:59:43.654Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1314.073 ms) ====== [2023-04-19T10:59:43.654Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2023-04-19T10:59:43.654Z] GC before operation: completed in 224.434 ms, heap usage 246.398 MB -> 60.844 MB. [2023-04-19T10:59:44.791Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1219.221 ms) ====== [2023-04-19T10:59:45.334Z] ----------------------------------- [2023-04-19T10:59:45.334Z] renaissance-dec-tree_0_PASSED [2023-04-19T10:59:45.334Z] ----------------------------------- [2023-04-19T10:59:45.334Z] [2023-04-19T10:59:45.334Z] TEST TEARDOWN: [2023-04-19T10:59:45.334Z] Nothing to be done for teardown. [2023-04-19T10:59:45.334Z] renaissance-dec-tree_0 Finish Time: Wed Apr 19 05:59:40 2023 Epoch Time (ms): 1681901980629