[2024-11-23T01:31:07.871Z] Running test renaissance-dec-tree_0 ... [2024-11-23T01:31:07.871Z] =============================================== [2024-11-23T01:31:07.871Z] renaissance-dec-tree_0 Start Time: Fri Nov 22 19:31:07 2024 Epoch Time (ms): 1732325467277 [2024-11-23T01:31:07.871Z] variation: NoOptions [2024-11-23T01:31:07.871Z] JVM_OPTIONS: [2024-11-23T01:31:07.871Z] { \ [2024-11-23T01:31:07.871Z] echo ""; echo "TEST SETUP:"; \ [2024-11-23T01:31:07.871Z] echo "Nothing to be done for setup."; \ [2024-11-23T01:31:07.871Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17323252272945/renaissance-dec-tree_0"; \ [2024-11-23T01:31:07.871Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17323252272945/renaissance-dec-tree_0"; \ [2024-11-23T01:31:07.871Z] echo ""; echo "TESTING:"; \ [2024-11-23T01:31:07.871Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17323252272945/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-11-23T01:31:07.871Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17323252272945/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-23T01:31:07.871Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-23T01:31:07.871Z] echo "Nothing to be done for teardown."; \ [2024-11-23T01:31:07.871Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17323252272945/TestTargetResult"; [2024-11-23T01:31:07.871Z] [2024-11-23T01:31:07.872Z] TEST SETUP: [2024-11-23T01:31:07.872Z] Nothing to be done for setup. [2024-11-23T01:31:07.872Z] [2024-11-23T01:31:07.872Z] TESTING: [2024-11-23T01:31:10.091Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-23T01:31:12.309Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2024-11-23T01:31:15.463Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-23T01:31:15.463Z] GC before operation: completed in 59.763 ms, heap usage 41.341 MB -> 35.624 MB. [2024-11-23T01:31:21.758Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6684.277 ms) ====== [2024-11-23T01:31:21.758Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-23T01:31:22.444Z] GC before operation: completed in 143.378 ms, heap usage 225.949 MB -> 64.945 MB. [2024-11-23T01:31:23.907Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1972.191 ms) ====== [2024-11-23T01:31:23.907Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-23T01:31:24.594Z] GC before operation: completed in 126.088 ms, heap usage 162.462 MB -> 65.078 MB. [2024-11-23T01:31:26.012Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1659.354 ms) ====== [2024-11-23T01:31:26.012Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-23T01:31:26.012Z] GC before operation: completed in 68.524 ms, heap usage 352.499 MB -> 65.764 MB. [2024-11-23T01:31:27.444Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1345.218 ms) ====== [2024-11-23T01:31:27.444Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-23T01:31:27.444Z] GC before operation: completed in 77.621 ms, heap usage 300.683 MB -> 77.183 MB. [2024-11-23T01:31:28.866Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1452.891 ms) ====== [2024-11-23T01:31:28.866Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-23T01:31:28.866Z] GC before operation: completed in 77.021 ms, heap usage 157.267 MB -> 65.951 MB. [2024-11-23T01:31:30.284Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1340.103 ms) ====== [2024-11-23T01:31:30.285Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-23T01:31:30.285Z] GC before operation: completed in 79.522 ms, heap usage 237.229 MB -> 77.583 MB. [2024-11-23T01:31:31.703Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1548.501 ms) ====== [2024-11-23T01:31:31.703Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-23T01:31:32.387Z] GC before operation: completed in 80.749 ms, heap usage 184.007 MB -> 77.336 MB. [2024-11-23T01:31:33.808Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1622.375 ms) ====== [2024-11-23T01:31:33.808Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-23T01:31:33.808Z] GC before operation: completed in 71.014 ms, heap usage 147.289 MB -> 77.514 MB. [2024-11-23T01:31:35.240Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1535.733 ms) ====== [2024-11-23T01:31:35.240Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-23T01:31:35.240Z] GC before operation: completed in 134.976 ms, heap usage 414.087 MB -> 89.062 MB. [2024-11-23T01:31:36.668Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1437.726 ms) ====== [2024-11-23T01:31:36.668Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-23T01:31:36.668Z] GC before operation: completed in 99.412 ms, heap usage 310.586 MB -> 83.615 MB. [2024-11-23T01:31:38.101Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1316.253 ms) ====== [2024-11-23T01:31:38.101Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-23T01:31:38.101Z] GC before operation: completed in 91.070 ms, heap usage 358.672 MB -> 94.620 MB. [2024-11-23T01:31:39.525Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1265.256 ms) ====== [2024-11-23T01:31:39.525Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-23T01:31:39.525Z] GC before operation: completed in 98.166 ms, heap usage 274.959 MB -> 83.665 MB. [2024-11-23T01:31:40.945Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1343.391 ms) ====== [2024-11-23T01:31:40.945Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-23T01:31:40.945Z] GC before operation: completed in 92.651 ms, heap usage 396.044 MB -> 67.263 MB. [2024-11-23T01:31:42.374Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1245.006 ms) ====== [2024-11-23T01:31:42.374Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-23T01:31:42.374Z] GC before operation: completed in 88.808 ms, heap usage 261.367 MB -> 67.418 MB. [2024-11-23T01:31:43.796Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1601.945 ms) ====== [2024-11-23T01:31:43.796Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-23T01:31:44.485Z] GC before operation: completed in 102.475 ms, heap usage 280.949 MB -> 84.100 MB. [2024-11-23T01:31:45.909Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1334.559 ms) ====== [2024-11-23T01:31:45.909Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-23T01:31:45.909Z] GC before operation: completed in 86.780 ms, heap usage 390.841 MB -> 67.864 MB. [2024-11-23T01:31:47.335Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1345.167 ms) ====== [2024-11-23T01:31:47.335Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-23T01:31:47.335Z] GC before operation: completed in 95.498 ms, heap usage 259.875 MB -> 67.854 MB. [2024-11-23T01:31:48.756Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1293.043 ms) ====== [2024-11-23T01:31:48.756Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-23T01:31:48.756Z] GC before operation: completed in 85.730 ms, heap usage 285.687 MB -> 73.521 MB. [2024-11-23T01:31:49.442Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1186.931 ms) ====== [2024-11-23T01:31:49.442Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-23T01:31:49.442Z] GC before operation: completed in 89.156 ms, heap usage 219.575 MB -> 95.744 MB. [2024-11-23T01:31:49.442Z] 24/11/22 19:31:49 WARN BlockManager: Asked to remove block rdd_928_1, which does not exist [2024-11-23T01:31:49.442Z] 24/11/22 19:31:49 WARN BlockManager: Asked to remove block rdd_928_0, which does not exist [2024-11-23T01:31:49.442Z] 24/11/22 19:31:49 WARN BlockManager: Asked to remove block rdd_928_2, which does not exist [2024-11-23T01:31:50.922Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1407.227 ms) ====== [2024-11-23T01:31:50.922Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-23T01:31:50.922Z] GC before operation: completed in 98.783 ms, heap usage 291.708 MB -> 90.523 MB. [2024-11-23T01:31:52.345Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1319.845 ms) ====== [2024-11-23T01:31:52.345Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-23T01:31:52.345Z] GC before operation: completed in 105.273 ms, heap usage 326.459 MB -> 95.944 MB. [2024-11-23T01:31:53.774Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1432.590 ms) ====== [2024-11-23T01:31:53.774Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-23T01:31:54.481Z] GC before operation: completed in 111.727 ms, heap usage 257.289 MB -> 85.081 MB. [2024-11-23T01:31:55.903Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1521.145 ms) ====== [2024-11-23T01:31:55.903Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-23T01:31:55.903Z] GC before operation: completed in 107.041 ms, heap usage 383.222 MB -> 90.417 MB. [2024-11-23T01:31:57.325Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1332.177 ms) ====== [2024-11-23T01:31:57.325Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-23T01:31:57.325Z] GC before operation: completed in 96.801 ms, heap usage 296.014 MB -> 85.085 MB. [2024-11-23T01:31:58.749Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1249.770 ms) ====== [2024-11-23T01:31:58.749Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-23T01:31:58.749Z] GC before operation: completed in 90.693 ms, heap usage 363.188 MB -> 79.795 MB. [2024-11-23T01:32:00.169Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1305.422 ms) ====== [2024-11-23T01:32:00.169Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-23T01:32:00.169Z] GC before operation: completed in 96.705 ms, heap usage 394.527 MB -> 69.088 MB. [2024-11-23T01:32:01.594Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1228.658 ms) ====== [2024-11-23T01:32:01.594Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-23T01:32:01.594Z] GC before operation: completed in 112.374 ms, heap usage 241.884 MB -> 85.564 MB. [2024-11-23T01:32:01.594Z] 24/11/22 19:32:00 WARN BlockManager: Asked to remove block broadcast_372_piece0, which does not exist [2024-11-23T01:32:01.594Z] 24/11/22 19:32:00 WARN BlockManager: Asked to remove block broadcast_372, which does not exist [2024-11-23T01:32:02.282Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1201.605 ms) ====== [2024-11-23T01:32:02.282Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-23T01:32:02.978Z] GC before operation: completed in 112.344 ms, heap usage 354.155 MB -> 85.216 MB. [2024-11-23T01:32:04.411Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1442.223 ms) ====== [2024-11-23T01:32:04.411Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-23T01:32:04.411Z] GC before operation: completed in 107.964 ms, heap usage 347.123 MB -> 91.251 MB. [2024-11-23T01:32:05.097Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1210.394 ms) ====== [2024-11-23T01:32:05.097Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-23T01:32:05.781Z] GC before operation: completed in 115.552 ms, heap usage 288.087 MB -> 91.134 MB. [2024-11-23T01:32:05.781Z] 24/11/22 19:32:05 WARN BlockManager: Asked to remove block broadcast_414_piece0, which does not exist [2024-11-23T01:32:07.201Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1550.880 ms) ====== [2024-11-23T01:32:07.201Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-23T01:32:07.201Z] GC before operation: completed in 108.847 ms, heap usage 321.392 MB -> 91.045 MB. [2024-11-23T01:32:08.725Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1269.587 ms) ====== [2024-11-23T01:32:08.725Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-23T01:32:08.725Z] GC before operation: completed in 100.254 ms, heap usage 303.137 MB -> 69.347 MB. [2024-11-23T01:32:10.142Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1206.198 ms) ====== [2024-11-23T01:32:10.142Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-23T01:32:10.142Z] GC before operation: completed in 107.820 ms, heap usage 249.929 MB -> 80.643 MB. [2024-11-23T01:32:11.563Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1499.419 ms) ====== [2024-11-23T01:32:11.563Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-23T01:32:11.563Z] GC before operation: completed in 116.519 ms, heap usage 429.227 MB -> 85.994 MB. [2024-11-23T01:32:12.993Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1234.499 ms) ====== [2024-11-23T01:32:12.993Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-23T01:32:12.993Z] GC before operation: completed in 103.286 ms, heap usage 342.738 MB -> 86.098 MB. [2024-11-23T01:32:14.412Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1333.069 ms) ====== [2024-11-23T01:32:14.412Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-23T01:32:14.412Z] GC before operation: completed in 115.228 ms, heap usage 332.680 MB -> 86.386 MB. [2024-11-23T01:32:15.848Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1452.787 ms) ====== [2024-11-23T01:32:15.848Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-23T01:32:15.848Z] GC before operation: completed in 111.956 ms, heap usage 347.931 MB -> 97.386 MB. [2024-11-23T01:32:17.301Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1418.295 ms) ====== [2024-11-23T01:32:17.301Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-23T01:32:17.301Z] GC before operation: completed in 96.467 ms, heap usage 241.175 MB -> 75.432 MB. [2024-11-23T01:32:18.718Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1552.461 ms) ====== [2024-11-23T01:32:18.718Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-23T01:32:18.718Z] GC before operation: completed in 91.420 ms, heap usage 175.323 MB -> 69.671 MB. [2024-11-23T01:32:20.166Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1245.835 ms) ====== [2024-11-23T01:32:20.851Z] ----------------------------------- [2024-11-23T01:32:20.851Z] renaissance-dec-tree_0_PASSED [2024-11-23T01:32:20.851Z] ----------------------------------- [2024-11-23T01:32:20.851Z] [2024-11-23T01:32:20.851Z] TEST TEARDOWN: [2024-11-23T01:32:20.851Z] Nothing to be done for teardown. [2024-11-23T01:32:20.851Z] renaissance-dec-tree_0 Finish Time: Fri Nov 22 19:32:20 2024 Epoch Time (ms): 1732325540389