[2024-08-15T02:50:10.370Z] Running test renaissance-dec-tree_0 ... [2024-08-15T02:50:10.370Z] =============================================== [2024-08-15T02:50:10.370Z] renaissance-dec-tree_0 Start Time: Wed Aug 14 21:50:10 2024 Epoch Time (ms): 1723690210170 [2024-08-15T02:50:10.370Z] variation: NoOptions [2024-08-15T02:50:10.370Z] JVM_OPTIONS: [2024-08-15T02:50:10.370Z] { \ [2024-08-15T02:50:10.370Z] echo ""; echo "TEST SETUP:"; \ [2024-08-15T02:50:10.370Z] echo "Nothing to be done for setup."; \ [2024-08-15T02:50:10.370Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723689961300/renaissance-dec-tree_0"; \ [2024-08-15T02:50:10.370Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723689961300/renaissance-dec-tree_0"; \ [2024-08-15T02:50:10.370Z] echo ""; echo "TESTING:"; \ [2024-08-15T02:50:10.370Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+3/bin/..//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_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723689961300/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-15T02:50:10.370Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723689961300/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-15T02:50:10.370Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-15T02:50:10.370Z] echo "Nothing to be done for teardown."; \ [2024-08-15T02:50:10.370Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723689961300/TestTargetResult"; [2024-08-15T02:50:10.370Z] [2024-08-15T02:50:10.370Z] TEST SETUP: [2024-08-15T02:50:10.370Z] Nothing to be done for setup. [2024-08-15T02:50:10.370Z] [2024-08-15T02:50:10.370Z] TESTING: [2024-08-15T02:50:13.424Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-15T02:50:14.828Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2024-08-15T02:50:17.940Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-15T02:50:17.940Z] GC before operation: completed in 91.489 ms, heap usage 42.981 MB -> 35.607 MB. [2024-08-15T02:50:25.505Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6803.732 ms) ====== [2024-08-15T02:50:25.505Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-15T02:50:25.505Z] GC before operation: completed in 116.628 ms, heap usage 210.664 MB -> 65.018 MB. [2024-08-15T02:50:26.945Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1969.212 ms) ====== [2024-08-15T02:50:26.945Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-15T02:50:26.945Z] GC before operation: completed in 148.633 ms, heap usage 213.925 MB -> 76.499 MB. [2024-08-15T02:50:28.356Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1488.013 ms) ====== [2024-08-15T02:50:28.356Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-15T02:50:29.047Z] GC before operation: completed in 79.034 ms, heap usage 197.095 MB -> 76.566 MB. [2024-08-15T02:50:30.451Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1520.768 ms) ====== [2024-08-15T02:50:30.451Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-15T02:50:30.451Z] GC before operation: completed in 68.202 ms, heap usage 155.306 MB -> 65.780 MB. [2024-08-15T02:50:31.885Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1382.812 ms) ====== [2024-08-15T02:50:31.885Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-15T02:50:31.885Z] GC before operation: completed in 98.655 ms, heap usage 254.208 MB -> 66.166 MB. [2024-08-15T02:50:33.308Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1612.791 ms) ====== [2024-08-15T02:50:33.308Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-15T02:50:33.308Z] GC before operation: completed in 87.106 ms, heap usage 309.987 MB -> 83.084 MB. [2024-08-15T02:50:35.505Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1608.591 ms) ====== [2024-08-15T02:50:35.505Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-15T02:50:35.505Z] GC before operation: completed in 98.761 ms, heap usage 355.962 MB -> 77.685 MB. [2024-08-15T02:50:36.910Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1445.066 ms) ====== [2024-08-15T02:50:36.910Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-15T02:50:36.910Z] GC before operation: completed in 105.478 ms, heap usage 382.644 MB -> 88.734 MB. [2024-08-15T02:50:38.320Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1343.728 ms) ====== [2024-08-15T02:50:38.320Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-15T02:50:38.320Z] GC before operation: completed in 91.344 ms, heap usage 293.162 MB -> 78.208 MB. [2024-08-15T02:50:39.732Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1428.655 ms) ====== [2024-08-15T02:50:39.732Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-15T02:50:39.732Z] GC before operation: completed in 101.444 ms, heap usage 442.030 MB -> 94.773 MB. [2024-08-15T02:50:41.137Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1484.710 ms) ====== [2024-08-15T02:50:41.137Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-15T02:50:41.137Z] GC before operation: completed in 106.000 ms, heap usage 268.325 MB -> 83.782 MB. [2024-08-15T02:50:42.543Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1235.128 ms) ====== [2024-08-15T02:50:42.543Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-15T02:50:42.543Z] GC before operation: completed in 101.977 ms, heap usage 363.653 MB -> 78.087 MB. [2024-08-15T02:50:43.949Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1322.085 ms) ====== [2024-08-15T02:50:43.949Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-15T02:50:43.949Z] GC before operation: completed in 96.743 ms, heap usage 402.966 MB -> 84.054 MB. [2024-08-15T02:50:45.357Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1203.950 ms) ====== [2024-08-15T02:50:45.357Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-15T02:50:45.357Z] GC before operation: completed in 117.134 ms, heap usage 331.253 MB -> 89.663 MB. [2024-08-15T02:50:45.357Z] 24/08/14 21:50:44 WARN BlockManager: Asked to remove block broadcast_190, which does not exist [2024-08-15T02:50:45.357Z] 24/08/14 21:50:44 WARN BlockManager: Asked to remove block broadcast_190_piece0, which does not exist [2024-08-15T02:50:46.764Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1211.346 ms) ====== [2024-08-15T02:50:46.764Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-15T02:50:46.764Z] GC before operation: completed in 118.327 ms, heap usage 287.344 MB -> 95.346 MB. [2024-08-15T02:50:48.172Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1233.870 ms) ====== [2024-08-15T02:50:48.172Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-15T02:50:48.172Z] GC before operation: completed in 93.775 ms, heap usage 251.982 MB -> 67.894 MB. [2024-08-15T02:50:49.611Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1288.030 ms) ====== [2024-08-15T02:50:49.611Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-15T02:50:49.611Z] GC before operation: completed in 96.430 ms, heap usage 281.586 MB -> 67.853 MB. [2024-08-15T02:50:51.020Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1293.772 ms) ====== [2024-08-15T02:50:51.020Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-15T02:50:51.020Z] GC before operation: completed in 1.233 ms, heap usage 288.074 MB -> 288.083 MB. [2024-08-15T02:50:51.707Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1183.599 ms) ====== [2024-08-15T02:50:51.707Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-15T02:50:51.707Z] GC before operation: completed in 101.025 ms, heap usage 241.839 MB -> 84.529 MB. [2024-08-15T02:50:53.130Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1282.876 ms) ====== [2024-08-15T02:50:53.130Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-15T02:50:53.130Z] GC before operation: completed in 121.761 ms, heap usage 192.904 MB -> 84.438 MB. [2024-08-15T02:50:54.546Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1281.962 ms) ====== [2024-08-15T02:50:54.546Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-15T02:50:54.546Z] GC before operation: completed in 121.772 ms, heap usage 367.865 MB -> 95.837 MB. [2024-08-15T02:50:56.744Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1554.499 ms) ====== [2024-08-15T02:50:56.744Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-15T02:50:56.744Z] GC before operation: completed in 109.930 ms, heap usage 257.071 MB -> 96.292 MB. [2024-08-15T02:50:58.152Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1462.073 ms) ====== [2024-08-15T02:50:58.152Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-15T02:50:58.152Z] GC before operation: completed in 100.910 ms, heap usage 486.992 MB -> 68.657 MB. [2024-08-15T02:50:59.557Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1512.365 ms) ====== [2024-08-15T02:50:59.557Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-15T02:50:59.557Z] GC before operation: completed in 100.143 ms, heap usage 146.313 MB -> 85.387 MB. [2024-08-15T02:51:00.962Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1326.446 ms) ====== [2024-08-15T02:51:00.962Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-15T02:51:00.962Z] GC before operation: completed in 114.451 ms, heap usage 337.736 MB -> 96.340 MB. [2024-08-15T02:51:02.366Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1342.361 ms) ====== [2024-08-15T02:51:02.367Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-15T02:51:02.367Z] GC before operation: completed in 102.486 ms, heap usage 251.264 MB -> 85.201 MB. [2024-08-15T02:51:03.886Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1380.506 ms) ====== [2024-08-15T02:51:03.886Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-15T02:51:03.886Z] GC before operation: completed in 94.854 ms, heap usage 304.797 MB -> 68.996 MB. [2024-08-15T02:51:05.294Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1289.884 ms) ====== [2024-08-15T02:51:05.294Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-15T02:51:05.294Z] GC before operation: completed in 111.430 ms, heap usage 257.335 MB -> 85.494 MB. [2024-08-15T02:51:06.698Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1528.184 ms) ====== [2024-08-15T02:51:06.698Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-15T02:51:07.375Z] GC before operation: completed in 122.509 ms, heap usage 356.094 MB -> 91.072 MB. [2024-08-15T02:51:08.788Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1359.847 ms) ====== [2024-08-15T02:51:08.788Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-15T02:51:08.788Z] GC before operation: completed in 110.165 ms, heap usage 297.929 MB -> 80.204 MB. [2024-08-15T02:51:10.216Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1252.991 ms) ====== [2024-08-15T02:51:10.216Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-15T02:51:10.216Z] GC before operation: completed in 109.977 ms, heap usage 427.072 MB -> 91.097 MB. [2024-08-15T02:51:10.216Z] 24/08/14 21:51:09 WARN BlockManager: Asked to remove block rdd_1516_1, which does not exist [2024-08-15T02:51:10.894Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1180.910 ms) ====== [2024-08-15T02:51:10.895Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-15T02:51:10.895Z] GC before operation: completed in 124.131 ms, heap usage 273.652 MB -> 91.627 MB. [2024-08-15T02:51:12.299Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1224.767 ms) ====== [2024-08-15T02:51:12.299Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-15T02:51:12.299Z] GC before operation: completed in 128.494 ms, heap usage 305.379 MB -> 69.615 MB. [2024-08-15T02:51:13.714Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1304.637 ms) ====== [2024-08-15T02:51:13.714Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-15T02:51:13.714Z] GC before operation: completed in 111.157 ms, heap usage 270.376 MB -> 97.264 MB. [2024-08-15T02:51:15.126Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1399.716 ms) ====== [2024-08-15T02:51:15.126Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-15T02:51:15.941Z] GC before operation: completed in 127.322 ms, heap usage 255.164 MB -> 91.640 MB. [2024-08-15T02:51:16.621Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1209.571 ms) ====== [2024-08-15T02:51:16.621Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-15T02:51:16.621Z] GC before operation: completed in 115.460 ms, heap usage 318.048 MB -> 86.445 MB. [2024-08-15T02:51:18.029Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1325.465 ms) ====== [2024-08-15T02:51:18.029Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-15T02:51:18.029Z] GC before operation: completed in 115.385 ms, heap usage 329.859 MB -> 97.503 MB. [2024-08-15T02:51:18.029Z] 24/08/14 21:51:17 WARN BlockManager: Asked to remove block broadcast_512_piece0, which does not exist [2024-08-15T02:51:19.444Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1207.733 ms) ====== [2024-08-15T02:51:19.444Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-15T02:51:19.444Z] GC before operation: completed in 126.323 ms, heap usage 239.035 MB -> 97.690 MB. [2024-08-15T02:51:20.855Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1289.958 ms) ====== [2024-08-15T02:51:20.855Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-15T02:51:20.855Z] GC before operation: completed in 105.789 ms, heap usage 237.338 MB -> 86.786 MB. [2024-08-15T02:51:22.259Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1251.067 ms) ====== [2024-08-15T02:51:22.939Z] ----------------------------------- [2024-08-15T02:51:22.939Z] renaissance-dec-tree_0_PASSED [2024-08-15T02:51:22.939Z] ----------------------------------- [2024-08-15T02:51:22.939Z] [2024-08-15T02:51:22.939Z] TEST TEARDOWN: [2024-08-15T02:51:22.939Z] Nothing to be done for teardown. [2024-08-15T02:51:22.939Z] renaissance-dec-tree_0 Finish Time: Wed Aug 14 21:51:22 2024 Epoch Time (ms): 1723690282382