[2024-08-28T21:21:54.651Z] Running test renaissance-dec-tree_0 ... [2024-08-28T21:21:54.651Z] =============================================== [2024-08-28T21:21:54.651Z] renaissance-dec-tree_0 Start Time: Wed Aug 28 21:21:54 2024 Epoch Time (ms): 1724880114011 [2024-08-28T21:21:54.651Z] variation: NoOptions [2024-08-28T21:21:54.651Z] JVM_OPTIONS: [2024-08-28T21:21:54.651Z] { \ [2024-08-28T21:21:54.651Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T21:21:54.651Z] echo "Nothing to be done for setup."; \ [2024-08-28T21:21:54.651Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-dec-tree_0"; \ [2024-08-28T21:21:54.651Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-dec-tree_0"; \ [2024-08-28T21:21:54.651Z] echo ""; echo "TESTING:"; \ [2024-08-28T21:21:54.651Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-28T21:21:54.651Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T21:21:54.651Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T21:21:54.651Z] echo "Nothing to be done for teardown."; \ [2024-08-28T21:21:54.651Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/TestTargetResult"; [2024-08-28T21:21:54.651Z] [2024-08-28T21:21:54.651Z] TEST SETUP: [2024-08-28T21:21:54.651Z] Nothing to be done for setup. [2024-08-28T21:21:54.651Z] [2024-08-28T21:21:54.651Z] TESTING: [2024-08-28T21:21:57.568Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T21:21:59.457Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-28T21:22:02.373Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-28T21:22:02.373Z] GC before operation: completed in 40.098 ms, heap usage 70.162 MB -> 35.215 MB. [2024-08-28T21:22:09.736Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6949.189 ms) ====== [2024-08-28T21:22:09.736Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-28T21:22:09.736Z] GC before operation: completed in 84.986 ms, heap usage 283.408 MB -> 64.761 MB. [2024-08-28T21:22:11.629Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2099.149 ms) ====== [2024-08-28T21:22:11.629Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-28T21:22:11.629Z] GC before operation: completed in 80.918 ms, heap usage 214.334 MB -> 76.082 MB. [2024-08-28T21:22:13.515Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1722.448 ms) ====== [2024-08-28T21:22:13.515Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-28T21:22:13.515Z] GC before operation: completed in 71.302 ms, heap usage 235.909 MB -> 65.423 MB. [2024-08-28T21:22:15.406Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1646.488 ms) ====== [2024-08-28T21:22:15.406Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-28T21:22:15.406Z] GC before operation: completed in 72.944 ms, heap usage 106.388 MB -> 65.141 MB. [2024-08-28T21:22:16.340Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1678.849 ms) ====== [2024-08-28T21:22:16.340Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-28T21:22:16.340Z] GC before operation: completed in 72.070 ms, heap usage 191.905 MB -> 65.760 MB. [2024-08-28T21:22:18.233Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1609.298 ms) ====== [2024-08-28T21:22:18.233Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-28T21:22:18.233Z] GC before operation: completed in 89.460 ms, heap usage 343.807 MB -> 77.258 MB. [2024-08-28T21:22:20.125Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1613.398 ms) ====== [2024-08-28T21:22:20.125Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-28T21:22:20.125Z] GC before operation: completed in 87.419 ms, heap usage 265.454 MB -> 77.467 MB. [2024-08-28T21:22:22.018Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1544.435 ms) ====== [2024-08-28T21:22:22.018Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-28T21:22:22.018Z] GC before operation: completed in 86.408 ms, heap usage 238.870 MB -> 77.463 MB. [2024-08-28T21:22:22.939Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1573.935 ms) ====== [2024-08-28T21:22:22.939Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-28T21:22:23.871Z] GC before operation: completed in 83.369 ms, heap usage 185.734 MB -> 66.420 MB. [2024-08-28T21:22:24.794Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1508.738 ms) ====== [2024-08-28T21:22:24.794Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-28T21:22:24.794Z] GC before operation: completed in 79.676 ms, heap usage 337.448 MB -> 66.712 MB. [2024-08-28T21:22:26.685Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1520.986 ms) ====== [2024-08-28T21:22:26.685Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-28T21:22:26.685Z] GC before operation: completed in 86.619 ms, heap usage 235.848 MB -> 66.703 MB. [2024-08-28T21:22:28.576Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1511.993 ms) ====== [2024-08-28T21:22:28.577Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-28T21:22:28.577Z] GC before operation: completed in 88.982 ms, heap usage 321.810 MB -> 66.907 MB. [2024-08-28T21:22:29.497Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1510.606 ms) ====== [2024-08-28T21:22:29.497Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-28T21:22:29.497Z] GC before operation: completed in 92.696 ms, heap usage 330.056 MB -> 67.043 MB. [2024-08-28T21:22:31.430Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1530.195 ms) ====== [2024-08-28T21:22:31.430Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-28T21:22:31.430Z] GC before operation: completed in 87.422 ms, heap usage 327.681 MB -> 67.170 MB. [2024-08-28T21:22:33.322Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1468.269 ms) ====== [2024-08-28T21:22:33.322Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-28T21:22:33.322Z] GC before operation: completed in 90.203 ms, heap usage 100.221 MB -> 66.594 MB. [2024-08-28T21:22:34.242Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1558.442 ms) ====== [2024-08-28T21:22:34.242Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-28T21:22:34.242Z] GC before operation: completed in 88.839 ms, heap usage 491.330 MB -> 67.622 MB. [2024-08-28T21:22:36.146Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1469.428 ms) ====== [2024-08-28T21:22:36.146Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-28T21:22:36.146Z] GC before operation: completed in 100.705 ms, heap usage 311.023 MB -> 67.400 MB. [2024-08-28T21:22:38.038Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1466.752 ms) ====== [2024-08-28T21:22:38.038Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-28T21:22:38.038Z] GC before operation: completed in 99.196 ms, heap usage 309.656 MB -> 78.865 MB. [2024-08-28T21:22:38.959Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1462.689 ms) ====== [2024-08-28T21:22:38.959Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-28T21:22:38.959Z] GC before operation: completed in 94.606 ms, heap usage 161.447 MB -> 67.579 MB. [2024-08-28T21:22:40.853Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1512.962 ms) ====== [2024-08-28T21:22:40.853Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-28T21:22:40.853Z] GC before operation: completed in 103.907 ms, heap usage 246.107 MB -> 84.293 MB. [2024-08-28T21:22:42.753Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1469.342 ms) ====== [2024-08-28T21:22:42.753Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-28T21:22:42.753Z] GC before operation: completed in 109.156 ms, heap usage 394.576 MB -> 79.188 MB. [2024-08-28T21:22:43.672Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1486.201 ms) ====== [2024-08-28T21:22:43.672Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-28T21:22:44.594Z] GC before operation: completed in 120.266 ms, heap usage 157.253 MB -> 67.650 MB. [2024-08-28T21:22:45.517Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1490.715 ms) ====== [2024-08-28T21:22:45.517Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-28T21:22:45.517Z] GC before operation: completed in 103.010 ms, heap usage 214.398 MB -> 79.397 MB. [2024-08-28T21:22:45.517Z] 24/08/28 21:22:45 WARN BlockManager: Asked to remove block broadcast_316, which does not exist [2024-08-28T21:22:45.517Z] 24/08/28 21:22:45 WARN BlockManager: Asked to remove block broadcast_316_piece0, which does not exist [2024-08-28T21:22:47.409Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1502.845 ms) ====== [2024-08-28T21:22:47.409Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-28T21:22:47.409Z] GC before operation: completed in 135.495 ms, heap usage 128.267 MB -> 95.220 MB. [2024-08-28T21:22:49.307Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1516.505 ms) ====== [2024-08-28T21:22:49.307Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-28T21:22:49.307Z] GC before operation: completed in 97.456 ms, heap usage 199.372 MB -> 68.142 MB. [2024-08-28T21:22:50.230Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1494.148 ms) ====== [2024-08-28T21:22:50.230Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-28T21:22:50.230Z] GC before operation: completed in 108.704 ms, heap usage 257.776 MB -> 68.330 MB. [2024-08-28T21:22:52.120Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1494.607 ms) ====== [2024-08-28T21:22:52.120Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-28T21:22:52.120Z] GC before operation: completed in 102.063 ms, heap usage 296.921 MB -> 79.574 MB. [2024-08-28T21:22:54.011Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1502.375 ms) ====== [2024-08-28T21:22:54.011Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-28T21:22:54.011Z] GC before operation: completed in 104.538 ms, heap usage 146.326 MB -> 79.591 MB. [2024-08-28T21:22:54.932Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1464.357 ms) ====== [2024-08-28T21:22:54.932Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-28T21:22:55.854Z] GC before operation: completed in 113.926 ms, heap usage 413.042 MB -> 79.964 MB. [2024-08-28T21:22:56.785Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1480.913 ms) ====== [2024-08-28T21:22:56.785Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-28T21:22:56.785Z] GC before operation: completed in 111.486 ms, heap usage 309.858 MB -> 79.660 MB. [2024-08-28T21:22:58.676Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1493.740 ms) ====== [2024-08-28T21:22:58.676Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-28T21:22:58.676Z] GC before operation: completed in 109.083 ms, heap usage 430.256 MB -> 79.925 MB. [2024-08-28T21:23:00.569Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1508.198 ms) ====== [2024-08-28T21:23:00.569Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-28T21:23:00.569Z] GC before operation: completed in 103.354 ms, heap usage 171.218 MB -> 68.882 MB. [2024-08-28T21:23:01.489Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1486.481 ms) ====== [2024-08-28T21:23:01.489Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-28T21:23:01.489Z] GC before operation: completed in 117.849 ms, heap usage 231.773 MB -> 80.431 MB. [2024-08-28T21:23:03.378Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1472.902 ms) ====== [2024-08-28T21:23:03.378Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-28T21:23:03.378Z] GC before operation: completed in 117.264 ms, heap usage 145.611 MB -> 74.509 MB. [2024-08-28T21:23:06.290Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1439.343 ms) ====== [2024-08-28T21:23:06.290Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-28T21:23:06.290Z] GC before operation: completed in 109.514 ms, heap usage 458.846 MB -> 69.672 MB. [2024-08-28T21:23:06.290Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1456.426 ms) ====== [2024-08-28T21:23:06.290Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-28T21:23:06.290Z] GC before operation: completed in 110.057 ms, heap usage 271.807 MB -> 86.209 MB. [2024-08-28T21:23:08.178Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1461.663 ms) ====== [2024-08-28T21:23:08.178Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-28T21:23:08.178Z] GC before operation: completed in 104.242 ms, heap usage 365.662 MB -> 80.593 MB. [2024-08-28T21:23:09.098Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1453.217 ms) ====== [2024-08-28T21:23:09.098Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-28T21:23:10.018Z] GC before operation: completed in 104.788 ms, heap usage 426.781 MB -> 86.377 MB. [2024-08-28T21:23:10.938Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1482.961 ms) ====== [2024-08-28T21:23:10.938Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-28T21:23:10.938Z] GC before operation: completed in 104.753 ms, heap usage 368.516 MB -> 69.901 MB. [2024-08-28T21:23:12.849Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1435.220 ms) ====== [2024-08-28T21:23:12.850Z] ----------------------------------- [2024-08-28T21:23:12.850Z] renaissance-dec-tree_0_PASSED [2024-08-28T21:23:12.850Z] ----------------------------------- [2024-08-28T21:23:12.850Z] [2024-08-28T21:23:12.850Z] TEST TEARDOWN: [2024-08-28T21:23:12.850Z] Nothing to be done for teardown. [2024-08-28T21:23:12.850Z] renaissance-dec-tree_0 Finish Time: Wed Aug 28 21:23:12 2024 Epoch Time (ms): 1724880192352