[2023-04-18T22:01:00.951Z] Running test renaissance-dec-tree_0 ... [2023-04-18T22:01:00.951Z] =============================================== [2023-04-18T22:01:00.951Z] renaissance-dec-tree_0 Start Time: Tue Apr 18 22:01:00 2023 Epoch Time (ms): 1681855260938 [2023-04-18T22:01:01.292Z] variation: NoOptions [2023-04-18T22:01:01.292Z] JVM_OPTIONS: [2023-04-18T22:01:01.292Z] { \ [2023-04-18T22:01:01.292Z] echo ""; echo "TEST SETUP:"; \ [2023-04-18T22:01:01.292Z] echo "Nothing to be done for setup."; \ [2023-04-18T22:01:01.292Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-dec-tree_0"; \ [2023-04-18T22:01:01.292Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-dec-tree_0"; \ [2023-04-18T22:01:01.292Z] echo ""; echo "TESTING:"; \ [2023-04-18T22:01:01.292Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/openjdkbinary/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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2023-04-18T22:01:01.292Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-18T22:01:01.292Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-18T22:01:01.292Z] echo "Nothing to be done for teardown."; \ [2023-04-18T22:01:01.292Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/TestTargetResult"; [2023-04-18T22:01:01.292Z] [2023-04-18T22:01:01.292Z] TEST SETUP: [2023-04-18T22:01:01.292Z] Nothing to be done for setup. [2023-04-18T22:01:01.292Z] [2023-04-18T22:01:01.292Z] TESTING: [2023-04-18T22:01:04.337Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-18T22:01:06.046Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2023-04-18T22:01:09.075Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2023-04-18T22:01:09.076Z] GC before operation: completed in 46.492 ms, heap usage 87.142 MB -> 34.794 MB. [2023-04-18T22:01:22.549Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (12295.032 ms) ====== [2023-04-18T22:01:22.549Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2023-04-18T22:01:22.549Z] GC before operation: completed in 117.884 ms, heap usage 263.301 MB -> 63.443 MB. [2023-04-18T22:01:24.256Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3030.448 ms) ====== [2023-04-18T22:01:24.256Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2023-04-18T22:01:24.612Z] GC before operation: completed in 75.256 ms, heap usage 120.981 MB -> 63.220 MB. [2023-04-18T22:01:26.928Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2258.635 ms) ====== [2023-04-18T22:01:26.928Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2023-04-18T22:01:26.928Z] GC before operation: completed in 102.348 ms, heap usage 414.401 MB -> 83.536 MB. [2023-04-18T22:01:29.243Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2405.475 ms) ====== [2023-04-18T22:01:29.243Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2023-04-18T22:01:29.243Z] GC before operation: completed in 113.799 ms, heap usage 216.705 MB -> 64.160 MB. [2023-04-18T22:01:31.574Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2177.302 ms) ====== [2023-04-18T22:01:31.574Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2023-04-18T22:01:31.574Z] GC before operation: completed in 119.755 ms, heap usage 256.581 MB -> 83.842 MB. [2023-04-18T22:01:33.894Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1988.795 ms) ====== [2023-04-18T22:01:33.894Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2023-04-18T22:01:33.894Z] GC before operation: completed in 115.533 ms, heap usage 242.417 MB -> 64.691 MB. [2023-04-18T22:01:36.236Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2089.409 ms) ====== [2023-04-18T22:01:36.236Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2023-04-18T22:01:36.236Z] GC before operation: completed in 110.436 ms, heap usage 231.407 MB -> 73.100 MB. [2023-04-18T22:01:37.944Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1869.566 ms) ====== [2023-04-18T22:01:37.944Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2023-04-18T22:01:37.944Z] GC before operation: completed in 124.101 ms, heap usage 194.058 MB -> 64.920 MB. [2023-04-18T22:01:39.692Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1774.149 ms) ====== [2023-04-18T22:01:39.694Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2023-04-18T22:01:39.694Z] GC before operation: completed in 91.342 ms, heap usage 194.222 MB -> 65.259 MB. [2023-04-18T22:01:41.410Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1705.755 ms) ====== [2023-04-18T22:01:41.410Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2023-04-18T22:01:41.751Z] GC before operation: completed in 102.875 ms, heap usage 294.958 MB -> 65.162 MB. [2023-04-18T22:01:43.458Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1766.841 ms) ====== [2023-04-18T22:01:43.458Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2023-04-18T22:01:43.458Z] GC before operation: completed in 90.070 ms, heap usage 326.167 MB -> 84.458 MB. [2023-04-18T22:01:45.165Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1623.644 ms) ====== [2023-04-18T22:01:45.165Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2023-04-18T22:01:45.166Z] GC before operation: completed in 82.928 ms, heap usage 204.742 MB -> 65.353 MB. [2023-04-18T22:01:46.888Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1778.147 ms) ====== [2023-04-18T22:01:46.888Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2023-04-18T22:01:46.888Z] GC before operation: completed in 103.295 ms, heap usage 347.014 MB -> 84.504 MB. [2023-04-18T22:01:49.209Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1779.086 ms) ====== [2023-04-18T22:01:49.209Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2023-04-18T22:01:49.209Z] GC before operation: completed in 93.483 ms, heap usage 215.826 MB -> 65.703 MB. [2023-04-18T22:01:50.398Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1606.929 ms) ====== [2023-04-18T22:01:50.399Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2023-04-18T22:01:50.739Z] GC before operation: completed in 110.164 ms, heap usage 110.133 MB -> 65.290 MB. [2023-04-18T22:01:52.445Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1651.998 ms) ====== [2023-04-18T22:01:52.445Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2023-04-18T22:01:52.445Z] GC before operation: completed in 84.710 ms, heap usage 328.413 MB -> 66.022 MB. [2023-04-18T22:01:54.207Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1531.505 ms) ====== [2023-04-18T22:01:54.207Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2023-04-18T22:01:54.207Z] GC before operation: completed in 91.417 ms, heap usage 207.809 MB -> 66.168 MB. [2023-04-18T22:01:55.395Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1548.612 ms) ====== [2023-04-18T22:01:55.395Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2023-04-18T22:01:55.737Z] GC before operation: completed in 86.135 ms, heap usage 288.795 MB -> 66.480 MB. [2023-04-18T22:01:57.441Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1586.191 ms) ====== [2023-04-18T22:01:57.441Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2023-04-18T22:01:57.441Z] GC before operation: completed in 98.361 ms, heap usage 308.576 MB -> 85.384 MB. [2023-04-18T22:01:59.148Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1524.592 ms) ====== [2023-04-18T22:01:59.148Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2023-04-18T22:01:59.148Z] GC before operation: completed in 82.900 ms, heap usage 198.404 MB -> 66.433 MB. [2023-04-18T22:02:00.326Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1518.495 ms) ====== [2023-04-18T22:02:00.326Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2023-04-18T22:02:00.665Z] GC before operation: completed in 88.916 ms, heap usage 190.550 MB -> 66.432 MB. [2023-04-18T22:02:02.368Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1664.760 ms) ====== [2023-04-18T22:02:02.368Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2023-04-18T22:02:02.368Z] GC before operation: completed in 85.686 ms, heap usage 178.705 MB -> 85.566 MB. [2023-04-18T22:02:04.071Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1466.639 ms) ====== [2023-04-18T22:02:04.071Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2023-04-18T22:02:04.071Z] GC before operation: completed in 120.150 ms, heap usage 244.087 MB -> 86.098 MB. [2023-04-18T22:02:05.257Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1491.129 ms) ====== [2023-04-18T22:02:05.257Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2023-04-18T22:02:05.597Z] GC before operation: completed in 117.073 ms, heap usage 222.066 MB -> 66.636 MB. [2023-04-18T22:02:06.773Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1399.664 ms) ====== [2023-04-18T22:02:06.773Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2023-04-18T22:02:07.116Z] GC before operation: completed in 86.714 ms, heap usage 186.238 MB -> 66.785 MB. [2023-04-18T22:02:08.819Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1550.639 ms) ====== [2023-04-18T22:02:08.819Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2023-04-18T22:02:08.819Z] GC before operation: completed in 89.628 ms, heap usage 278.719 MB -> 66.919 MB. [2023-04-18T22:02:10.517Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1565.489 ms) ====== [2023-04-18T22:02:10.517Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2023-04-18T22:02:10.517Z] GC before operation: completed in 103.236 ms, heap usage 178.940 MB -> 67.054 MB. [2023-04-18T22:02:11.769Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1500.489 ms) ====== [2023-04-18T22:02:11.769Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2023-04-18T22:02:11.769Z] GC before operation: completed in 108.513 ms, heap usage 264.869 MB -> 86.167 MB. [2023-04-18T22:02:13.492Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1624.445 ms) ====== [2023-04-18T22:02:13.492Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2023-04-18T22:02:13.492Z] GC before operation: completed in 103.358 ms, heap usage 227.657 MB -> 67.524 MB. [2023-04-18T22:02:15.196Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1515.457 ms) ====== [2023-04-18T22:02:15.196Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2023-04-18T22:02:15.196Z] GC before operation: completed in 89.560 ms, heap usage 150.922 MB -> 67.032 MB. [2023-04-18T22:02:16.900Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1420.934 ms) ====== [2023-04-18T22:02:16.900Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2023-04-18T22:02:16.900Z] GC before operation: completed in 94.259 ms, heap usage 216.249 MB -> 67.376 MB. [2023-04-18T22:02:18.080Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1480.042 ms) ====== [2023-04-18T22:02:18.080Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2023-04-18T22:02:18.422Z] GC before operation: completed in 103.793 ms, heap usage 279.849 MB -> 75.806 MB. [2023-04-18T22:02:19.602Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1443.901 ms) ====== [2023-04-18T22:02:19.602Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2023-04-18T22:02:19.941Z] GC before operation: completed in 94.389 ms, heap usage 198.196 MB -> 67.582 MB. [2023-04-18T22:02:21.643Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1456.616 ms) ====== [2023-04-18T22:02:21.643Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2023-04-18T22:02:21.643Z] GC before operation: completed in 111.388 ms, heap usage 249.811 MB -> 86.980 MB. [2023-04-18T22:02:22.819Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1453.538 ms) ====== [2023-04-18T22:02:22.819Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2023-04-18T22:02:22.819Z] GC before operation: completed in 91.590 ms, heap usage 219.801 MB -> 67.847 MB. [2023-04-18T22:02:24.523Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1449.067 ms) ====== [2023-04-18T22:02:24.523Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2023-04-18T22:02:24.523Z] GC before operation: completed in 104.777 ms, heap usage 144.869 MB -> 67.631 MB. [2023-04-18T22:02:26.224Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1475.097 ms) ====== [2023-04-18T22:02:26.224Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2023-04-18T22:02:26.224Z] GC before operation: completed in 92.656 ms, heap usage 184.234 MB -> 67.950 MB. [2023-04-18T22:02:27.407Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1394.539 ms) ====== [2023-04-18T22:02:27.407Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2023-04-18T22:02:27.746Z] GC before operation: completed in 114.546 ms, heap usage 264.257 MB -> 87.308 MB. [2023-04-18T22:02:28.931Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1376.981 ms) ====== [2023-04-18T22:02:28.931Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2023-04-18T22:02:29.272Z] GC before operation: completed in 134.730 ms, heap usage 345.443 MB -> 95.826 MB. [2023-04-18T22:02:30.492Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1357.295 ms) ====== [2023-04-18T22:02:30.832Z] ----------------------------------- [2023-04-18T22:02:30.832Z] renaissance-dec-tree_0_PASSED [2023-04-18T22:02:30.832Z] ----------------------------------- [2023-04-18T22:02:30.832Z] [2023-04-18T22:02:30.832Z] TEST TEARDOWN: [2023-04-18T22:02:30.832Z] Nothing to be done for teardown. [2023-04-18T22:02:30.832Z] renaissance-dec-tree_0 Finish Time: Tue Apr 18 22:02:30 2023 Epoch Time (ms): 1681855350622