[2024-09-04T21:18:36.177Z] Running test renaissance-dec-tree_0 ... [2024-09-04T21:18:36.177Z] =============================================== [2024-09-04T21:18:36.177Z] renaissance-dec-tree_0 Start Time: Wed Sep 4 21:18:35 2024 Epoch Time (ms): 1725484715509 [2024-09-04T21:18:36.177Z] variation: NoOptions [2024-09-04T21:18:36.177Z] JVM_OPTIONS: [2024-09-04T21:18:36.177Z] { \ [2024-09-04T21:18:36.177Z] echo ""; echo "TEST SETUP:"; \ [2024-09-04T21:18:36.177Z] echo "Nothing to be done for setup."; \ [2024-09-04T21:18:36.177Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17254842815826/renaissance-dec-tree_0"; \ [2024-09-04T21:18:36.177Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17254842815826/renaissance-dec-tree_0"; \ [2024-09-04T21:18:36.177Z] echo ""; echo "TESTING:"; \ [2024-09-04T21:18:36.177Z] "/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_17254842815826/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-09-04T21:18:36.177Z] 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_17254842815826/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-04T21:18:36.177Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-04T21:18:36.177Z] echo "Nothing to be done for teardown."; \ [2024-09-04T21:18:36.177Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17254842815826/TestTargetResult"; [2024-09-04T21:18:36.177Z] [2024-09-04T21:18:36.177Z] TEST SETUP: [2024-09-04T21:18:36.177Z] Nothing to be done for setup. [2024-09-04T21:18:36.177Z] [2024-09-04T21:18:36.177Z] TESTING: [2024-09-04T21:18:39.089Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-04T21:18:40.981Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-04T21:18:43.895Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-04T21:18:43.895Z] GC before operation: completed in 44.136 ms, heap usage 42.706 MB -> 35.100 MB. [2024-09-04T21:18:50.385Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6889.928 ms) ====== [2024-09-04T21:18:50.385Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-04T21:18:50.385Z] GC before operation: completed in 86.844 ms, heap usage 186.089 MB -> 64.399 MB. [2024-09-04T21:18:53.301Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2245.950 ms) ====== [2024-09-04T21:18:53.301Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-04T21:18:53.301Z] GC before operation: completed in 83.961 ms, heap usage 325.167 MB -> 65.081 MB. [2024-09-04T21:18:55.185Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1724.349 ms) ====== [2024-09-04T21:18:55.185Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-04T21:18:55.185Z] GC before operation: completed in 77.616 ms, heap usage 119.596 MB -> 64.677 MB. [2024-09-04T21:18:56.102Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1660.926 ms) ====== [2024-09-04T21:18:56.102Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-04T21:18:56.102Z] GC before operation: completed in 86.821 ms, heap usage 399.388 MB -> 82.311 MB. [2024-09-04T21:18:58.658Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1799.692 ms) ====== [2024-09-04T21:18:58.658Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-04T21:18:58.658Z] GC before operation: completed in 87.780 ms, heap usage 174.402 MB -> 76.708 MB. [2024-09-04T21:19:00.543Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1665.394 ms) ====== [2024-09-04T21:19:00.543Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-04T21:19:00.543Z] GC before operation: completed in 90.985 ms, heap usage 140.424 MB -> 76.656 MB. [2024-09-04T21:19:01.461Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1643.506 ms) ====== [2024-09-04T21:19:01.461Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-04T21:19:01.461Z] GC before operation: completed in 87.721 ms, heap usage 215.908 MB -> 66.275 MB. [2024-09-04T21:19:03.349Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1616.899 ms) ====== [2024-09-04T21:19:03.349Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-04T21:19:03.349Z] GC before operation: completed in 93.291 ms, heap usage 335.068 MB -> 66.419 MB. [2024-09-04T21:19:05.238Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1576.821 ms) ====== [2024-09-04T21:19:05.238Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-04T21:19:05.238Z] GC before operation: completed in 113.094 ms, heap usage 193.610 MB -> 66.797 MB. [2024-09-04T21:19:07.125Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1563.771 ms) ====== [2024-09-04T21:19:07.125Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-04T21:19:07.125Z] GC before operation: completed in 103.758 ms, heap usage 172.989 MB -> 66.360 MB. [2024-09-04T21:19:09.011Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1581.694 ms) ====== [2024-09-04T21:19:09.011Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-04T21:19:09.011Z] GC before operation: completed in 95.953 ms, heap usage 171.919 MB -> 77.645 MB. [2024-09-04T21:19:09.936Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1550.006 ms) ====== [2024-09-04T21:19:09.936Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-04T21:19:09.936Z] GC before operation: completed in 92.336 ms, heap usage 266.929 MB -> 66.692 MB. [2024-09-04T21:19:11.823Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1517.635 ms) ====== [2024-09-04T21:19:11.823Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-04T21:19:11.823Z] GC before operation: completed in 110.072 ms, heap usage 318.531 MB -> 77.961 MB. [2024-09-04T21:19:13.711Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1506.750 ms) ====== [2024-09-04T21:19:13.711Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-04T21:19:13.711Z] GC before operation: completed in 93.910 ms, heap usage 323.201 MB -> 67.099 MB. [2024-09-04T21:19:14.629Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1520.175 ms) ====== [2024-09-04T21:19:14.629Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-04T21:19:15.549Z] GC before operation: completed in 98.567 ms, heap usage 170.059 MB -> 83.519 MB. [2024-09-04T21:19:16.467Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1526.897 ms) ====== [2024-09-04T21:19:16.467Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-04T21:19:16.467Z] GC before operation: completed in 99.418 ms, heap usage 143.289 MB -> 67.308 MB. [2024-09-04T21:19:18.352Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1488.091 ms) ====== [2024-09-04T21:19:18.352Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-04T21:19:18.352Z] GC before operation: completed in 109.570 ms, heap usage 346.182 MB -> 67.349 MB. [2024-09-04T21:19:20.238Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1499.067 ms) ====== [2024-09-04T21:19:20.238Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-04T21:19:20.238Z] GC before operation: completed in 107.667 ms, heap usage 324.234 MB -> 67.777 MB. [2024-09-04T21:19:21.155Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1495.210 ms) ====== [2024-09-04T21:19:21.155Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-04T21:19:21.155Z] GC before operation: completed in 99.692 ms, heap usage 333.352 MB -> 78.477 MB. [2024-09-04T21:19:23.041Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1508.112 ms) ====== [2024-09-04T21:19:23.041Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-04T21:19:23.041Z] GC before operation: completed in 98.310 ms, heap usage 281.314 MB -> 73.677 MB. [2024-09-04T21:19:24.929Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1550.218 ms) ====== [2024-09-04T21:19:24.929Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-04T21:19:24.929Z] GC before operation: completed in 104.577 ms, heap usage 331.225 MB -> 79.125 MB. [2024-09-04T21:19:25.934Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1489.170 ms) ====== [2024-09-04T21:19:25.934Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-04T21:19:26.856Z] GC before operation: completed in 104.601 ms, heap usage 152.984 MB -> 84.315 MB. [2024-09-04T21:19:27.774Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1485.988 ms) ====== [2024-09-04T21:19:27.774Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-04T21:19:27.774Z] GC before operation: completed in 103.294 ms, heap usage 337.321 MB -> 79.144 MB. [2024-09-04T21:19:29.664Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1476.244 ms) ====== [2024-09-04T21:19:29.664Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-04T21:19:29.664Z] GC before operation: completed in 98.201 ms, heap usage 297.282 MB -> 68.442 MB. [2024-09-04T21:19:31.551Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1498.401 ms) ====== [2024-09-04T21:19:31.551Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-04T21:19:31.551Z] GC before operation: completed in 106.793 ms, heap usage 132.374 MB -> 78.877 MB. [2024-09-04T21:19:32.469Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1513.709 ms) ====== [2024-09-04T21:19:32.469Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-04T21:19:32.469Z] GC before operation: completed in 101.013 ms, heap usage 579.250 MB -> 68.890 MB. [2024-09-04T21:19:34.356Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1463.788 ms) ====== [2024-09-04T21:19:34.356Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-04T21:19:34.356Z] GC before operation: completed in 101.325 ms, heap usage 110.950 MB -> 68.044 MB. [2024-09-04T21:19:36.245Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1491.618 ms) ====== [2024-09-04T21:19:36.245Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-04T21:19:36.245Z] GC before operation: completed in 106.596 ms, heap usage 497.585 MB -> 68.853 MB. [2024-09-04T21:19:37.164Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1469.029 ms) ====== [2024-09-04T21:19:37.164Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-04T21:19:37.164Z] GC before operation: completed in 106.681 ms, heap usage 298.982 MB -> 90.804 MB. [2024-09-04T21:19:39.052Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1488.356 ms) ====== [2024-09-04T21:19:39.052Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-04T21:19:39.052Z] GC before operation: completed in 105.957 ms, heap usage 179.480 MB -> 79.484 MB. [2024-09-04T21:19:40.937Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1486.949 ms) ====== [2024-09-04T21:19:40.937Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-04T21:19:40.937Z] GC before operation: completed in 110.667 ms, heap usage 375.299 MB -> 79.702 MB. [2024-09-04T21:19:41.905Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1447.723 ms) ====== [2024-09-04T21:19:41.905Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-04T21:19:41.905Z] GC before operation: completed in 112.808 ms, heap usage 274.272 MB -> 68.929 MB. [2024-09-04T21:19:43.797Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1470.659 ms) ====== [2024-09-04T21:19:43.797Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-04T21:19:43.797Z] GC before operation: completed in 121.163 ms, heap usage 220.127 MB -> 80.344 MB. [2024-09-04T21:19:45.684Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1442.737 ms) ====== [2024-09-04T21:19:45.684Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-04T21:19:45.684Z] GC before operation: completed in 105.586 ms, heap usage 442.767 MB -> 69.581 MB. [2024-09-04T21:19:46.601Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1466.149 ms) ====== [2024-09-04T21:19:46.601Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-04T21:19:46.601Z] GC before operation: completed in 109.576 ms, heap usage 355.741 MB -> 69.390 MB. [2024-09-04T21:19:48.493Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1474.688 ms) ====== [2024-09-04T21:19:48.493Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-04T21:19:48.493Z] GC before operation: completed in 112.762 ms, heap usage 307.819 MB -> 75.145 MB. [2024-09-04T21:19:50.385Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1482.798 ms) ====== [2024-09-04T21:19:50.385Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-04T21:19:50.385Z] GC before operation: completed in 104.818 ms, heap usage 321.233 MB -> 80.642 MB. [2024-09-04T21:19:51.306Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1446.727 ms) ====== [2024-09-04T21:19:51.306Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-04T21:19:52.231Z] GC before operation: completed in 107.331 ms, heap usage 240.703 MB -> 75.115 MB. [2024-09-04T21:19:52.231Z] 24/09/04 21:19:51 WARN BlockManager: Asked to remove block broadcast_526_piece0, which does not exist [2024-09-04T21:19:53.155Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1482.096 ms) ====== [2024-09-04T21:19:53.155Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-04T21:19:53.155Z] GC before operation: completed in 101.423 ms, heap usage 336.307 MB -> 70.195 MB. [2024-09-04T21:19:55.044Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1431.737 ms) ====== [2024-09-04T21:19:55.044Z] ----------------------------------- [2024-09-04T21:19:55.044Z] renaissance-dec-tree_0_PASSED [2024-09-04T21:19:55.044Z] ----------------------------------- [2024-09-04T21:19:55.044Z] [2024-09-04T21:19:55.044Z] TEST TEARDOWN: [2024-09-04T21:19:55.044Z] Nothing to be done for teardown. [2024-09-04T21:19:55.044Z] renaissance-dec-tree_0 Finish Time: Wed Sep 4 21:19:54 2024 Epoch Time (ms): 1725484794493