[2024-09-05T05:28:09.580Z] Running test renaissance-dec-tree_0 ... [2024-09-05T05:28:09.893Z] =============================================== [2024-09-05T05:28:09.893Z] renaissance-dec-tree_0 Start Time: Thu Sep 5 05:28:09 2024 Epoch Time (ms): 1725514089821 [2024-09-05T05:28:10.205Z] variation: NoOptions [2024-09-05T05:28:10.205Z] JVM_OPTIONS: [2024-09-05T05:28:10.205Z] { \ [2024-09-05T05:28:10.205Z] echo ""; echo "TEST SETUP:"; \ [2024-09-05T05:28:10.205Z] echo "Nothing to be done for setup."; \ [2024-09-05T05:28:10.205Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17255134842312\\renaissance-dec-tree_0"; \ [2024-09-05T05:28:10.205Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17255134842312\\renaissance-dec-tree_0"; \ [2024-09-05T05:28:10.205Z] echo ""; echo "TESTING:"; \ [2024-09-05T05:28:10.205Z] "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_17255134842312\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-09-05T05:28:10.205Z] 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_17255134842312\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-05T05:28:10.205Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-05T05:28:10.205Z] echo "Nothing to be done for teardown."; \ [2024-09-05T05:28:10.205Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17255134842312\\TestTargetResult"; [2024-09-05T05:28:10.531Z] [2024-09-05T05:28:10.531Z] TEST SETUP: [2024-09-05T05:28:10.531Z] Nothing to be done for setup. [2024-09-05T05:28:10.531Z] [2024-09-05T05:28:10.531Z] TESTING: [2024-09-05T05:28:21.136Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-05T05:28:22.249Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-05T05:28:25.925Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-05T05:28:25.925Z] GC before operation: completed in 47.804 ms, heap usage 40.260 MB -> 35.379 MB. [2024-09-05T05:28:33.691Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7553.581 ms) ====== [2024-09-05T05:28:33.691Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-05T05:28:33.691Z] GC before operation: completed in 69.651 ms, heap usage 160.631 MB -> 64.749 MB. [2024-09-05T05:28:35.472Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2207.108 ms) ====== [2024-09-05T05:28:35.472Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-05T05:28:35.472Z] GC before operation: completed in 62.733 ms, heap usage 134.165 MB -> 64.971 MB. [2024-09-05T05:28:37.349Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1912.247 ms) ====== [2024-09-05T05:28:37.349Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-05T05:28:37.349Z] GC before operation: completed in 68.467 ms, heap usage 98.179 MB -> 64.984 MB. [2024-09-05T05:28:38.970Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1647.968 ms) ====== [2024-09-05T05:28:38.970Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-05T05:28:39.310Z] GC before operation: completed in 63.158 ms, heap usage 192.114 MB -> 77.081 MB. [2024-09-05T05:28:40.911Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1500.774 ms) ====== [2024-09-05T05:28:40.911Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-05T05:28:40.911Z] GC before operation: completed in 65.648 ms, heap usage 197.727 MB -> 77.142 MB. [2024-09-05T05:28:42.062Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1468.736 ms) ====== [2024-09-05T05:28:42.062Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-05T05:28:42.396Z] GC before operation: completed in 66.862 ms, heap usage 196.642 MB -> 66.666 MB. [2024-09-05T05:28:43.525Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1392.408 ms) ====== [2024-09-05T05:28:43.525Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-05T05:28:43.869Z] GC before operation: completed in 72.845 ms, heap usage 327.220 MB -> 83.188 MB. [2024-09-05T05:28:44.996Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1362.725 ms) ====== [2024-09-05T05:28:44.996Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-05T05:28:44.996Z] GC before operation: completed in 70.493 ms, heap usage 132.517 MB -> 82.595 MB. [2024-09-05T05:28:46.631Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1370.448 ms) ====== [2024-09-05T05:28:46.632Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-05T05:28:46.632Z] GC before operation: completed in 78.184 ms, heap usage 318.607 MB -> 77.841 MB. [2024-09-05T05:28:48.287Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1386.179 ms) ====== [2024-09-05T05:28:48.287Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-05T05:28:48.287Z] GC before operation: completed in 70.137 ms, heap usage 198.155 MB -> 77.729 MB. [2024-09-05T05:28:49.471Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1412.718 ms) ====== [2024-09-05T05:28:49.471Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-05T05:28:49.471Z] GC before operation: completed in 72.270 ms, heap usage 205.077 MB -> 78.245 MB. [2024-09-05T05:28:51.075Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1333.606 ms) ====== [2024-09-05T05:28:51.075Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-05T05:28:51.075Z] GC before operation: completed in 75.258 ms, heap usage 193.203 MB -> 77.921 MB. [2024-09-05T05:28:52.234Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1296.672 ms) ====== [2024-09-05T05:28:52.234Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-05T05:28:52.234Z] GC before operation: completed in 76.745 ms, heap usage 192.737 MB -> 83.672 MB. [2024-09-05T05:28:53.860Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1238.176 ms) ====== [2024-09-05T05:28:53.860Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-05T05:28:53.860Z] GC before operation: completed in 93.531 ms, heap usage 450.064 MB -> 89.656 MB. [2024-09-05T05:28:55.010Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1287.867 ms) ====== [2024-09-05T05:28:55.010Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-05T05:28:55.010Z] GC before operation: completed in 80.333 ms, heap usage 360.977 MB -> 83.957 MB. [2024-09-05T05:28:56.208Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1252.462 ms) ====== [2024-09-05T05:28:56.208Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-05T05:28:56.208Z] GC before operation: completed in 81.136 ms, heap usage 411.241 MB -> 67.821 MB. [2024-09-05T05:28:57.811Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1245.702 ms) ====== [2024-09-05T05:28:57.811Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-05T05:28:57.811Z] GC before operation: completed in 76.674 ms, heap usage 302.267 MB -> 67.783 MB. [2024-09-05T05:28:58.951Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1219.755 ms) ====== [2024-09-05T05:28:58.951Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-05T05:28:58.951Z] GC before operation: completed in 81.903 ms, heap usage 274.950 MB -> 84.414 MB. [2024-09-05T05:29:00.103Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1266.096 ms) ====== [2024-09-05T05:29:00.103Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-05T05:29:00.441Z] GC before operation: completed in 84.744 ms, heap usage 409.183 MB -> 84.536 MB. [2024-09-05T05:29:01.547Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1200.089 ms) ====== [2024-09-05T05:29:01.547Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-05T05:29:01.547Z] GC before operation: completed in 85.706 ms, heap usage 410.431 MB -> 84.689 MB. [2024-09-05T05:29:02.677Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1194.521 ms) ====== [2024-09-05T05:29:02.677Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-05T05:29:03.021Z] GC before operation: completed in 94.383 ms, heap usage 394.956 MB -> 84.715 MB. [2024-09-05T05:29:04.138Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1234.088 ms) ====== [2024-09-05T05:29:04.138Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-05T05:29:04.138Z] GC before operation: completed in 86.398 ms, heap usage 392.154 MB -> 84.772 MB. [2024-09-05T05:29:05.340Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1251.686 ms) ====== [2024-09-05T05:29:05.340Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-05T05:29:05.689Z] GC before operation: completed in 86.922 ms, heap usage 378.332 MB -> 90.581 MB. [2024-09-05T05:29:06.800Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1213.618 ms) ====== [2024-09-05T05:29:06.800Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-05T05:29:06.800Z] GC before operation: completed in 82.847 ms, heap usage 330.333 MB -> 85.109 MB. [2024-09-05T05:29:07.941Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1185.146 ms) ====== [2024-09-05T05:29:07.941Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-05T05:29:08.268Z] GC before operation: completed in 85.346 ms, heap usage 388.552 MB -> 85.230 MB. [2024-09-05T05:29:09.379Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1189.061 ms) ====== [2024-09-05T05:29:09.379Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-05T05:29:09.379Z] GC before operation: completed in 84.965 ms, heap usage 402.332 MB -> 85.164 MB. [2024-09-05T05:29:10.498Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1195.741 ms) ====== [2024-09-05T05:29:10.498Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-05T05:29:10.831Z] GC before operation: completed in 87.416 ms, heap usage 388.263 MB -> 85.340 MB. [2024-09-05T05:29:10.831Z] 24/09/05 05:29:10 WARN BlockManager: Asked to remove block rdd_1320_1, which does not exist [2024-09-05T05:29:11.944Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1190.317 ms) ====== [2024-09-05T05:29:11.944Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-05T05:29:11.944Z] GC before operation: completed in 85.614 ms, heap usage 367.950 MB -> 85.362 MB. [2024-09-05T05:29:13.065Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1213.509 ms) ====== [2024-09-05T05:29:13.065Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-05T05:29:13.389Z] GC before operation: completed in 88.315 ms, heap usage 371.339 MB -> 90.995 MB. [2024-09-05T05:29:14.511Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1207.490 ms) ====== [2024-09-05T05:29:14.511Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-05T05:29:14.511Z] GC before operation: completed in 90.228 ms, heap usage 321.565 MB -> 85.715 MB. [2024-09-05T05:29:16.119Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1355.889 ms) ====== [2024-09-05T05:29:16.119Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-05T05:29:16.119Z] GC before operation: completed in 91.372 ms, heap usage 362.154 MB -> 91.176 MB. [2024-09-05T05:29:17.247Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1168.208 ms) ====== [2024-09-05T05:29:17.247Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-05T05:29:17.247Z] GC before operation: completed in 91.908 ms, heap usage 339.263 MB -> 91.267 MB. [2024-09-05T05:29:18.434Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1286.889 ms) ====== [2024-09-05T05:29:18.434Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-05T05:29:18.760Z] GC before operation: completed in 99.120 ms, heap usage 316.089 MB -> 85.872 MB. [2024-09-05T05:29:19.873Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1194.478 ms) ====== [2024-09-05T05:29:19.873Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-05T05:29:19.873Z] GC before operation: completed in 87.400 ms, heap usage 354.123 MB -> 85.986 MB. [2024-09-05T05:29:21.074Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1221.930 ms) ====== [2024-09-05T05:29:21.074Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-05T05:29:21.074Z] GC before operation: completed in 89.577 ms, heap usage 360.910 MB -> 91.550 MB. [2024-09-05T05:29:22.725Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1365.029 ms) ====== [2024-09-05T05:29:22.725Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-05T05:29:22.725Z] GC before operation: completed in 89.862 ms, heap usage 479.710 MB -> 86.612 MB. [2024-09-05T05:29:23.864Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1140.696 ms) ====== [2024-09-05T05:29:23.864Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-05T05:29:23.864Z] GC before operation: completed in 88.856 ms, heap usage 241.920 MB -> 86.291 MB. [2024-09-05T05:29:24.999Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1194.004 ms) ====== [2024-09-05T05:29:24.999Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-05T05:29:25.329Z] GC before operation: completed in 91.107 ms, heap usage 390.222 MB -> 86.392 MB. [2024-09-05T05:29:26.435Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1189.503 ms) ====== [2024-09-05T05:29:26.435Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-05T05:29:26.435Z] GC before operation: completed in 92.396 ms, heap usage 377.464 MB -> 86.752 MB. [2024-09-05T05:29:27.535Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1157.988 ms) ====== [2024-09-05T05:29:27.857Z] ----------------------------------- [2024-09-05T05:29:27.857Z] renaissance-dec-tree_0_PASSED [2024-09-05T05:29:27.857Z] ----------------------------------- [2024-09-05T05:29:28.169Z] [2024-09-05T05:29:28.169Z] TEST TEARDOWN: [2024-09-05T05:29:28.169Z] Nothing to be done for teardown. [2024-09-05T05:29:28.169Z] renaissance-dec-tree_0 Finish Time: Thu Sep 5 05:29:27 2024 Epoch Time (ms): 1725514167952