[2024-08-14T20:28:27.671Z] Running test renaissance-dec-tree_0 ... [2024-08-14T20:28:27.671Z] =============================================== [2024-08-14T20:28:27.671Z] renaissance-dec-tree_0 Start Time: Wed Aug 14 20:28:27 2024 Epoch Time (ms): 1723667307283 [2024-08-14T20:28:27.671Z] variation: NoOptions [2024-08-14T20:28:27.671Z] JVM_OPTIONS: [2024-08-14T20:28:27.671Z] { \ [2024-08-14T20:28:27.671Z] echo ""; echo "TEST SETUP:"; \ [2024-08-14T20:28:27.671Z] echo "Nothing to be done for setup."; \ [2024-08-14T20:28:27.671Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/renaissance-dec-tree_0"; \ [2024-08-14T20:28:27.671Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/renaissance-dec-tree_0"; \ [2024-08-14T20:28:27.671Z] echo ""; echo "TESTING:"; \ [2024-08-14T20:28:27.671Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/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_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-14T20:28:27.671Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-14T20:28:27.671Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-14T20:28:27.671Z] echo "Nothing to be done for teardown."; \ [2024-08-14T20:28:27.671Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/TestTargetResult"; [2024-08-14T20:28:27.671Z] [2024-08-14T20:28:27.671Z] TEST SETUP: [2024-08-14T20:28:27.671Z] Nothing to be done for setup. [2024-08-14T20:28:27.671Z] [2024-08-14T20:28:27.671Z] TESTING: [2024-08-14T20:28:30.623Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-14T20:28:32.532Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-14T20:28:35.481Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-14T20:28:35.481Z] GC before operation: completed in 87.028 ms, heap usage 44.960 MB -> 35.220 MB. [2024-08-14T20:28:42.050Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6811.561 ms) ====== [2024-08-14T20:28:42.050Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-14T20:28:42.050Z] GC before operation: completed in 73.487 ms, heap usage 162.512 MB -> 64.687 MB. [2024-08-14T20:28:43.963Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2108.920 ms) ====== [2024-08-14T20:28:43.963Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-14T20:28:43.963Z] GC before operation: completed in 72.164 ms, heap usage 127.270 MB -> 64.725 MB. [2024-08-14T20:28:45.878Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1689.688 ms) ====== [2024-08-14T20:28:45.878Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-14T20:28:45.878Z] GC before operation: completed in 62.167 ms, heap usage 202.464 MB -> 65.278 MB. [2024-08-14T20:28:47.797Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1594.806 ms) ====== [2024-08-14T20:28:47.797Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-14T20:28:47.797Z] GC before operation: completed in 69.446 ms, heap usage 172.380 MB -> 65.429 MB. [2024-08-14T20:28:49.713Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1594.302 ms) ====== [2024-08-14T20:28:49.713Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-14T20:28:49.713Z] GC before operation: completed in 71.301 ms, heap usage 96.979 MB -> 65.764 MB. [2024-08-14T20:28:50.642Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1582.034 ms) ====== [2024-08-14T20:28:50.642Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-14T20:28:51.571Z] GC before operation: completed in 66.062 ms, heap usage 499.665 MB -> 66.350 MB. [2024-08-14T20:28:52.511Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1513.273 ms) ====== [2024-08-14T20:28:52.511Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-14T20:28:52.511Z] GC before operation: completed in 69.678 ms, heap usage 226.948 MB -> 66.391 MB. [2024-08-14T20:28:54.444Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1519.390 ms) ====== [2024-08-14T20:28:54.444Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-14T20:28:54.444Z] GC before operation: completed in 74.373 ms, heap usage 110.441 MB -> 65.788 MB. [2024-08-14T20:28:55.374Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1503.762 ms) ====== [2024-08-14T20:28:55.374Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-14T20:28:56.308Z] GC before operation: completed in 78.981 ms, heap usage 206.729 MB -> 66.636 MB. [2024-08-14T20:28:57.238Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1540.192 ms) ====== [2024-08-14T20:28:57.238Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-14T20:28:57.238Z] GC before operation: completed in 79.316 ms, heap usage 324.695 MB -> 66.741 MB. [2024-08-14T20:28:59.162Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1862.993 ms) ====== [2024-08-14T20:28:59.162Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-14T20:28:59.162Z] GC before operation: completed in 74.298 ms, heap usage 186.244 MB -> 66.728 MB. [2024-08-14T20:29:01.071Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1476.698 ms) ====== [2024-08-14T20:29:01.071Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-14T20:29:01.071Z] GC before operation: completed in 74.342 ms, heap usage 336.114 MB -> 77.684 MB. [2024-08-14T20:29:02.980Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1460.780 ms) ====== [2024-08-14T20:29:02.981Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-14T20:29:02.981Z] GC before operation: completed in 73.751 ms, heap usage 276.483 MB -> 67.072 MB. [2024-08-14T20:29:03.910Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1445.569 ms) ====== [2024-08-14T20:29:03.910Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-14T20:29:03.910Z] GC before operation: completed in 79.192 ms, heap usage 147.070 MB -> 66.916 MB. [2024-08-14T20:29:05.834Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1466.948 ms) ====== [2024-08-14T20:29:05.834Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-14T20:29:05.834Z] GC before operation: completed in 82.489 ms, heap usage 291.610 MB -> 67.334 MB. [2024-08-14T20:29:07.747Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1980.051 ms) ====== [2024-08-14T20:29:07.747Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-14T20:29:07.747Z] GC before operation: completed in 87.208 ms, heap usage 341.817 MB -> 67.495 MB. [2024-08-14T20:29:08.693Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1432.846 ms) ====== [2024-08-14T20:29:08.693Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-14T20:29:09.622Z] GC before operation: completed in 81.873 ms, heap usage 334.570 MB -> 67.496 MB. [2024-08-14T20:29:10.631Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1429.743 ms) ====== [2024-08-14T20:29:10.631Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-14T20:29:10.631Z] GC before operation: completed in 93.485 ms, heap usage 329.459 MB -> 78.435 MB. [2024-08-14T20:29:12.545Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1407.589 ms) ====== [2024-08-14T20:29:12.545Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-14T20:29:12.545Z] GC before operation: completed in 78.527 ms, heap usage 227.054 MB -> 67.658 MB. [2024-08-14T20:29:13.475Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1430.954 ms) ====== [2024-08-14T20:29:13.476Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-14T20:29:13.476Z] GC before operation: completed in 89.123 ms, heap usage 306.618 MB -> 84.500 MB. [2024-08-14T20:29:15.397Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1422.005 ms) ====== [2024-08-14T20:29:15.397Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-14T20:29:15.397Z] GC before operation: completed in 100.302 ms, heap usage 120.773 MB -> 67.372 MB. [2024-08-14T20:29:16.335Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1438.792 ms) ====== [2024-08-14T20:29:16.335Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-14T20:29:17.266Z] GC before operation: completed in 100.618 ms, heap usage 487.521 MB -> 84.754 MB. [2024-08-14T20:29:18.339Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1460.522 ms) ====== [2024-08-14T20:29:18.339Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-14T20:29:18.339Z] GC before operation: completed in 96.541 ms, heap usage 299.889 MB -> 79.192 MB. [2024-08-14T20:29:20.249Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1422.094 ms) ====== [2024-08-14T20:29:20.250Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-14T20:29:20.250Z] GC before operation: completed in 87.681 ms, heap usage 333.797 MB -> 95.398 MB. [2024-08-14T20:29:20.250Z] 24/08/14 20:29:19 WARN BlockManager: Asked to remove block rdd_1173_1, which does not exist [2024-08-14T20:29:20.250Z] 24/08/14 20:29:19 WARN BlockManager: Asked to remove block rdd_1173_2, which does not exist [2024-08-14T20:29:21.188Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1410.202 ms) ====== [2024-08-14T20:29:21.188Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-14T20:29:21.188Z] GC before operation: completed in 90.168 ms, heap usage 286.171 MB -> 79.635 MB. [2024-08-14T20:29:23.101Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1429.261 ms) ====== [2024-08-14T20:29:23.101Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-14T20:29:23.101Z] GC before operation: completed in 88.949 ms, heap usage 248.844 MB -> 68.412 MB. [2024-08-14T20:29:24.031Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1447.778 ms) ====== [2024-08-14T20:29:24.031Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-14T20:29:24.031Z] GC before operation: completed in 100.748 ms, heap usage 202.276 MB -> 68.685 MB. [2024-08-14T20:29:25.962Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1491.666 ms) ====== [2024-08-14T20:29:25.963Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-14T20:29:25.963Z] GC before operation: completed in 96.240 ms, heap usage 309.669 MB -> 68.836 MB. [2024-08-14T20:29:27.878Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1447.228 ms) ====== [2024-08-14T20:29:27.878Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-14T20:29:27.878Z] GC before operation: completed in 103.108 ms, heap usage 312.993 MB -> 80.040 MB. [2024-08-14T20:29:28.811Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1455.620 ms) ====== [2024-08-14T20:29:28.811Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-14T20:29:28.811Z] GC before operation: completed in 126.682 ms, heap usage 161.920 MB -> 68.458 MB. [2024-08-14T20:29:30.724Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1468.340 ms) ====== [2024-08-14T20:29:30.724Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-14T20:29:30.724Z] GC before operation: completed in 121.188 ms, heap usage 249.548 MB -> 80.156 MB. [2024-08-14T20:29:32.642Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1492.069 ms) ====== [2024-08-14T20:29:32.642Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-14T20:29:32.642Z] GC before operation: completed in 97.606 ms, heap usage 121.789 MB -> 68.554 MB. [2024-08-14T20:29:33.578Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1506.125 ms) ====== [2024-08-14T20:29:33.578Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-14T20:29:33.578Z] GC before operation: completed in 112.165 ms, heap usage 486.945 MB -> 69.273 MB. [2024-08-14T20:29:37.701Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1441.529 ms) ====== [2024-08-14T20:29:37.701Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-14T20:29:37.701Z] GC before operation: completed in 103.530 ms, heap usage 318.670 MB -> 74.994 MB. [2024-08-14T20:29:37.701Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1441.804 ms) ====== [2024-08-14T20:29:37.701Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-14T20:29:37.701Z] GC before operation: completed in 104.135 ms, heap usage 243.722 MB -> 69.452 MB. [2024-08-14T20:29:39.080Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1446.416 ms) ====== [2024-08-14T20:29:39.080Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-14T20:29:39.080Z] GC before operation: completed in 121.605 ms, heap usage 295.962 MB -> 69.496 MB. [2024-08-14T20:29:40.013Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1456.825 ms) ====== [2024-08-14T20:29:40.013Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-14T20:29:40.013Z] GC before operation: completed in 131.495 ms, heap usage 270.839 MB -> 69.617 MB. [2024-08-14T20:29:41.926Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1413.993 ms) ====== [2024-08-14T20:29:41.926Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-14T20:29:41.926Z] GC before operation: completed in 107.939 ms, heap usage 295.078 MB -> 86.108 MB. [2024-08-14T20:29:41.926Z] 24/08/14 20:29:41 WARN BlockManager: Asked to remove block broadcast_526_piece0, which does not exist [2024-08-14T20:29:42.857Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1415.625 ms) ====== [2024-08-14T20:29:42.857Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-14T20:29:42.857Z] GC before operation: completed in 124.657 ms, heap usage 415.170 MB -> 69.796 MB. [2024-08-14T20:29:44.769Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1427.415 ms) ====== [2024-08-14T20:29:44.769Z] ----------------------------------- [2024-08-14T20:29:44.769Z] renaissance-dec-tree_0_PASSED [2024-08-14T20:29:44.769Z] ----------------------------------- [2024-08-14T20:29:44.769Z] [2024-08-14T20:29:44.769Z] TEST TEARDOWN: [2024-08-14T20:29:44.769Z] Nothing to be done for teardown. [2024-08-14T20:29:44.769Z] renaissance-dec-tree_0 Finish Time: Wed Aug 14 20:29:44 2024 Epoch Time (ms): 1723667384410