[2024-10-30T00:06:30.550Z] Running test renaissance-dec-tree_0 ... [2024-10-30T00:06:30.550Z] =============================================== [2024-10-30T00:06:30.550Z] renaissance-dec-tree_0 Start Time: Wed Oct 30 00:06:29 2024 Epoch Time (ms): 1730246789528 [2024-10-30T00:06:30.550Z] variation: NoOptions [2024-10-30T00:06:30.550Z] JVM_OPTIONS: [2024-10-30T00:06:30.550Z] { \ [2024-10-30T00:06:30.550Z] echo ""; echo "TEST SETUP:"; \ [2024-10-30T00:06:30.550Z] echo "Nothing to be done for setup."; \ [2024-10-30T00:06:30.550Z] mkdir -p "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17302459809478\\renaissance-dec-tree_0"; \ [2024-10-30T00:06:30.550Z] cd "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17302459809478\\renaissance-dec-tree_0"; \ [2024-10-30T00:06:30.550Z] echo ""; echo "TESTING:"; \ [2024-10-30T00:06:30.550Z] "c:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/jdkbinary/j2sdk-image\\bin\\java" -jar "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17302459809478\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-10-30T00:06:30.550Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17302459809478\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-30T00:06:30.550Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-30T00:06:30.550Z] echo "Nothing to be done for teardown."; \ [2024-10-30T00:06:30.550Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17302459809478\\TestTargetResult"; [2024-10-30T00:06:30.550Z] [2024-10-30T00:06:30.550Z] TEST SETUP: [2024-10-30T00:06:30.550Z] Nothing to be done for setup. [2024-10-30T00:06:30.550Z] [2024-10-30T00:06:30.550Z] TESTING: [2024-10-30T00:06:39.193Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-30T00:06:41.344Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-30T00:06:44.177Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-10-30T00:06:44.489Z] GC before operation: completed in 170.563 ms, heap usage 122.391 MB -> 28.239 MB. [2024-10-30T00:06:53.434Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8054.309 ms) ====== [2024-10-30T00:06:53.434Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-10-30T00:06:53.434Z] GC before operation: completed in 474.522 ms, heap usage 112.278 MB -> 54.431 MB. [2024-10-30T00:06:55.139Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2115.379 ms) ====== [2024-10-30T00:06:55.139Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-10-30T00:06:55.139Z] GC before operation: completed in 250.016 ms, heap usage 262.019 MB -> 54.771 MB. [2024-10-30T00:06:57.451Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1731.751 ms) ====== [2024-10-30T00:06:57.451Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-10-30T00:06:57.451Z] GC before operation: completed in 236.527 ms, heap usage 289.038 MB -> 56.177 MB. [2024-10-30T00:06:59.038Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1595.232 ms) ====== [2024-10-30T00:06:59.038Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-10-30T00:06:59.038Z] GC before operation: completed in 197.239 ms, heap usage 261.962 MB -> 66.045 MB. [2024-10-30T00:07:00.664Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1429.898 ms) ====== [2024-10-30T00:07:00.664Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-10-30T00:07:00.664Z] GC before operation: completed in 195.709 ms, heap usage 267.030 MB -> 66.214 MB. [2024-10-30T00:07:02.266Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1390.696 ms) ====== [2024-10-30T00:07:02.266Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-10-30T00:07:02.266Z] GC before operation: completed in 217.956 ms, heap usage 266.467 MB -> 55.404 MB. [2024-10-30T00:07:03.885Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1316.660 ms) ====== [2024-10-30T00:07:03.885Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-10-30T00:07:03.885Z] GC before operation: completed in 216.934 ms, heap usage 247.011 MB -> 66.522 MB. [2024-10-30T00:07:05.515Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1374.905 ms) ====== [2024-10-30T00:07:05.515Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-10-30T00:07:05.515Z] GC before operation: completed in 253.255 ms, heap usage 258.048 MB -> 72.293 MB. [2024-10-30T00:07:06.619Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1290.891 ms) ====== [2024-10-30T00:07:06.619Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-10-30T00:07:06.934Z] GC before operation: completed in 264.990 ms, heap usage 268.536 MB -> 67.201 MB. [2024-10-30T00:07:08.515Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1217.640 ms) ====== [2024-10-30T00:07:08.515Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-10-30T00:07:08.515Z] GC before operation: completed in 228.431 ms, heap usage 257.423 MB -> 66.947 MB. [2024-10-30T00:07:09.843Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1323.103 ms) ====== [2024-10-30T00:07:09.843Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-10-30T00:07:10.161Z] GC before operation: completed in 231.513 ms, heap usage 253.674 MB -> 72.749 MB. [2024-10-30T00:07:10.161Z] 24/10/30 00:07:09 WARN BlockManager: Asked to remove block rdd_536_0, which does not exist [2024-10-30T00:07:11.250Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1216.136 ms) ====== [2024-10-30T00:07:11.250Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-10-30T00:07:11.573Z] GC before operation: completed in 227.602 ms, heap usage 259.260 MB -> 72.904 MB. [2024-10-30T00:07:11.573Z] 24/10/30 00:07:11 WARN BlockManager: Asked to remove block rdd_585_1, which does not exist [2024-10-30T00:07:12.790Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1321.645 ms) ====== [2024-10-30T00:07:12.790Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-10-30T00:07:13.104Z] GC before operation: completed in 204.683 ms, heap usage 262.953 MB -> 73.040 MB. [2024-10-30T00:07:14.217Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1222.679 ms) ====== [2024-10-30T00:07:14.217Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-10-30T00:07:14.547Z] GC before operation: completed in 203.560 ms, heap usage 268.940 MB -> 78.684 MB. [2024-10-30T00:07:15.669Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1240.432 ms) ====== [2024-10-30T00:07:15.669Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-10-30T00:07:15.987Z] GC before operation: completed in 196.386 ms, heap usage 275.535 MB -> 73.421 MB. [2024-10-30T00:07:17.079Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1200.083 ms) ====== [2024-10-30T00:07:17.079Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-10-30T00:07:17.398Z] GC before operation: completed in 240.136 ms, heap usage 271.709 MB -> 57.203 MB. [2024-10-30T00:07:18.484Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1234.763 ms) ====== [2024-10-30T00:07:18.484Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-10-30T00:07:18.790Z] GC before operation: completed in 251.602 ms, heap usage 247.499 MB -> 57.241 MB. [2024-10-30T00:07:19.875Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1186.856 ms) ====== [2024-10-30T00:07:19.875Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-10-30T00:07:20.195Z] GC before operation: completed in 268.058 ms, heap usage 246.411 MB -> 68.206 MB. [2024-10-30T00:07:21.347Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1230.100 ms) ====== [2024-10-30T00:07:21.347Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-10-30T00:07:21.665Z] GC before operation: completed in 231.235 ms, heap usage 255.399 MB -> 73.957 MB. [2024-10-30T00:07:22.776Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1180.393 ms) ====== [2024-10-30T00:07:22.776Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-10-30T00:07:23.093Z] GC before operation: completed in 214.684 ms, heap usage 263.143 MB -> 74.136 MB. [2024-10-30T00:07:24.182Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1126.299 ms) ====== [2024-10-30T00:07:24.182Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-10-30T00:07:24.495Z] GC before operation: completed in 226.488 ms, heap usage 263.038 MB -> 74.272 MB. [2024-10-30T00:07:25.584Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1207.863 ms) ====== [2024-10-30T00:07:25.584Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-10-30T00:07:25.900Z] GC before operation: completed in 218.634 ms, heap usage 267.129 MB -> 74.375 MB. [2024-10-30T00:07:27.015Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1146.160 ms) ====== [2024-10-30T00:07:27.015Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-10-30T00:07:27.329Z] GC before operation: completed in 210.841 ms, heap usage 265.155 MB -> 69.019 MB. [2024-10-30T00:07:28.414Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1246.696 ms) ====== [2024-10-30T00:07:28.414Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-10-30T00:07:28.736Z] GC before operation: completed in 215.436 ms, heap usage 258.735 MB -> 74.674 MB. [2024-10-30T00:07:28.736Z] 24/10/30 00:07:28 WARN BlockManager: Asked to remove block broadcast_330_piece0, which does not exist [2024-10-30T00:07:29.826Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1139.967 ms) ====== [2024-10-30T00:07:29.826Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-10-30T00:07:30.143Z] GC before operation: completed in 195.921 ms, heap usage 257.352 MB -> 69.191 MB. [2024-10-30T00:07:31.239Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1152.632 ms) ====== [2024-10-30T00:07:31.239Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-10-30T00:07:31.239Z] GC before operation: completed in 193.854 ms, heap usage 252.697 MB -> 74.754 MB. [2024-10-30T00:07:32.868Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1218.979 ms) ====== [2024-10-30T00:07:32.868Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-10-30T00:07:32.868Z] GC before operation: completed in 216.560 ms, heap usage 257.857 MB -> 69.650 MB. [2024-10-30T00:07:34.034Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1261.069 ms) ====== [2024-10-30T00:07:34.034Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-10-30T00:07:34.339Z] GC before operation: completed in 175.216 ms, heap usage 252.729 MB -> 75.225 MB. [2024-10-30T00:07:35.428Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1135.999 ms) ====== [2024-10-30T00:07:35.428Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-10-30T00:07:35.741Z] GC before operation: completed in 207.125 ms, heap usage 264.692 MB -> 69.737 MB. [2024-10-30T00:07:36.828Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1122.484 ms) ====== [2024-10-30T00:07:36.828Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-10-30T00:07:36.828Z] GC before operation: completed in 196.935 ms, heap usage 256.253 MB -> 75.463 MB. [2024-10-30T00:07:37.959Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1147.802 ms) ====== [2024-10-30T00:07:37.959Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-10-30T00:07:38.275Z] GC before operation: completed in 175.979 ms, heap usage 254.654 MB -> 81.044 MB. [2024-10-30T00:07:39.366Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1100.481 ms) ====== [2024-10-30T00:07:39.366Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-10-30T00:07:39.679Z] GC before operation: completed in 211.268 ms, heap usage 275.942 MB -> 81.361 MB. [2024-10-30T00:07:40.767Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1144.125 ms) ====== [2024-10-30T00:07:40.767Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-10-30T00:07:40.767Z] GC before operation: completed in 215.189 ms, heap usage 294.550 MB -> 75.867 MB. [2024-10-30T00:07:40.767Z] 24/10/30 00:07:40 WARN BlockManager: Asked to remove block rdd_1614_1, which does not exist [2024-10-30T00:07:42.361Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1100.845 ms) ====== [2024-10-30T00:07:42.361Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-10-30T00:07:42.361Z] GC before operation: completed in 157.977 ms, heap usage 270.469 MB -> 76.026 MB. [2024-10-30T00:07:43.451Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1138.652 ms) ====== [2024-10-30T00:07:43.451Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-10-30T00:07:43.451Z] GC before operation: completed in 209.368 ms, heap usage 262.955 MB -> 81.607 MB. [2024-10-30T00:07:44.556Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1139.383 ms) ====== [2024-10-30T00:07:44.556Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-10-30T00:07:44.871Z] GC before operation: completed in 188.665 ms, heap usage 277.458 MB -> 76.335 MB. [2024-10-30T00:07:46.022Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1123.091 ms) ====== [2024-10-30T00:07:46.022Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-10-30T00:07:46.339Z] GC before operation: completed in 204.651 ms, heap usage 274.660 MB -> 76.445 MB. [2024-10-30T00:07:47.428Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1195.494 ms) ====== [2024-10-30T00:07:47.428Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-10-30T00:07:47.428Z] GC before operation: completed in 174.557 ms, heap usage 271.165 MB -> 76.548 MB. [2024-10-30T00:07:49.198Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1162.063 ms) ====== [2024-10-30T00:07:49.198Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-10-30T00:07:49.198Z] GC before operation: completed in 162.387 ms, heap usage 263.746 MB -> 71.426 MB. [2024-10-30T00:07:50.291Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1201.963 ms) ====== [2024-10-30T00:07:50.291Z] ----------------------------------- [2024-10-30T00:07:50.291Z] renaissance-dec-tree_0_PASSED [2024-10-30T00:07:50.291Z] ----------------------------------- [2024-10-30T00:07:50.609Z] [2024-10-30T00:07:50.609Z] TEST TEARDOWN: [2024-10-30T00:07:50.609Z] Nothing to be done for teardown. [2024-10-30T00:07:50.609Z] renaissance-dec-tree_0 Finish Time: Wed Oct 30 00:07:50 2024 Epoch Time (ms): 1730246870389