[2024-08-10T02:00:47.182Z] Running test renaissance-dec-tree_0 ... [2024-08-10T02:00:47.182Z] =============================================== [2024-08-10T02:00:47.182Z] renaissance-dec-tree_0 Start Time: Sat Aug 10 02:00:46 2024 Epoch Time (ms): 1723255246903 [2024-08-10T02:00:47.182Z] variation: NoOptions [2024-08-10T02:00:47.182Z] JVM_OPTIONS: [2024-08-10T02:00:47.182Z] { \ [2024-08-10T02:00:47.182Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T02:00:47.182Z] echo "Nothing to be done for setup."; \ [2024-08-10T02:00:47.182Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232547063536/renaissance-dec-tree_0"; \ [2024-08-10T02:00:47.182Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232547063536/renaissance-dec-tree_0"; \ [2024-08-10T02:00:47.182Z] echo ""; echo "TESTING:"; \ [2024-08-10T02:00:47.182Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_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_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232547063536/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-10T02:00:47.182Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232547063536/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T02:00:47.182Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T02:00:47.182Z] echo "Nothing to be done for teardown."; \ [2024-08-10T02:00:47.182Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232547063536/TestTargetResult"; [2024-08-10T02:00:47.182Z] [2024-08-10T02:00:47.182Z] TEST SETUP: [2024-08-10T02:00:47.182Z] Nothing to be done for setup. [2024-08-10T02:00:47.182Z] [2024-08-10T02:00:47.182Z] TESTING: [2024-08-10T02:00:50.819Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T02:00:52.085Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-10T02:00:54.887Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-10T02:00:54.887Z] GC before operation: completed in 47.955 ms, heap usage 65.001 MB -> 34.961 MB. [2024-08-10T02:01:03.328Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8033.818 ms) ====== [2024-08-10T02:01:03.328Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-10T02:01:03.328Z] GC before operation: completed in 127.029 ms, heap usage 179.207 MB -> 63.318 MB. [2024-08-10T02:01:06.222Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3089.682 ms) ====== [2024-08-10T02:01:06.222Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-10T02:01:06.222Z] GC before operation: completed in 138.881 ms, heap usage 254.439 MB -> 64.287 MB. [2024-08-10T02:01:09.057Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2717.107 ms) ====== [2024-08-10T02:01:09.057Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-10T02:01:09.057Z] GC before operation: completed in 138.545 ms, heap usage 154.322 MB -> 64.098 MB. [2024-08-10T02:01:11.058Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2478.989 ms) ====== [2024-08-10T02:01:11.058Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-10T02:01:11.693Z] GC before operation: completed in 112.269 ms, heap usage 194.292 MB -> 64.397 MB. [2024-08-10T02:01:13.722Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2375.263 ms) ====== [2024-08-10T02:01:13.722Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-10T02:01:13.722Z] GC before operation: completed in 119.302 ms, heap usage 285.245 MB -> 64.488 MB. [2024-08-10T02:01:16.542Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2218.107 ms) ====== [2024-08-10T02:01:16.542Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-10T02:01:16.542Z] GC before operation: completed in 111.315 ms, heap usage 167.008 MB -> 64.521 MB. [2024-08-10T02:01:18.561Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2403.125 ms) ====== [2024-08-10T02:01:18.561Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-10T02:01:18.561Z] GC before operation: completed in 144.844 ms, heap usage 325.089 MB -> 92.244 MB. [2024-08-10T02:01:21.354Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2122.377 ms) ====== [2024-08-10T02:01:21.354Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-10T02:01:21.354Z] GC before operation: completed in 145.237 ms, heap usage 208.386 MB -> 73.386 MB. [2024-08-10T02:01:23.387Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2193.501 ms) ====== [2024-08-10T02:01:23.387Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-10T02:01:23.387Z] GC before operation: completed in 134.890 ms, heap usage 168.680 MB -> 92.808 MB. [2024-08-10T02:01:25.376Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2043.699 ms) ====== [2024-08-10T02:01:25.376Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-10T02:01:25.376Z] GC before operation: completed in 156.376 ms, heap usage 154.722 MB -> 73.543 MB. [2024-08-10T02:01:27.420Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2028.084 ms) ====== [2024-08-10T02:01:27.420Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-10T02:01:28.051Z] GC before operation: completed in 144.660 ms, heap usage 205.243 MB -> 65.473 MB. [2024-08-10T02:01:30.580Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2474.107 ms) ====== [2024-08-10T02:01:30.580Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-10T02:01:30.580Z] GC before operation: completed in 132.185 ms, heap usage 270.700 MB -> 74.002 MB. [2024-08-10T02:01:32.584Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2303.649 ms) ====== [2024-08-10T02:01:32.584Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-10T02:01:32.584Z] GC before operation: completed in 156.872 ms, heap usage 296.274 MB -> 65.694 MB. [2024-08-10T02:01:35.434Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2160.723 ms) ====== [2024-08-10T02:01:35.434Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-10T02:01:35.434Z] GC before operation: completed in 154.522 ms, heap usage 160.853 MB -> 65.435 MB. [2024-08-10T02:01:37.418Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2202.741 ms) ====== [2024-08-10T02:01:37.418Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-10T02:01:37.418Z] GC before operation: completed in 118.220 ms, heap usage 106.509 MB -> 65.824 MB. [2024-08-10T02:01:40.211Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2266.431 ms) ====== [2024-08-10T02:01:40.211Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-10T02:01:40.211Z] GC before operation: completed in 136.105 ms, heap usage 240.154 MB -> 66.099 MB. [2024-08-10T02:01:42.275Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2028.941 ms) ====== [2024-08-10T02:01:42.275Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-10T02:01:42.275Z] GC before operation: completed in 147.665 ms, heap usage 180.865 MB -> 66.081 MB. [2024-08-10T02:01:44.303Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2048.888 ms) ====== [2024-08-10T02:01:44.303Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-10T02:01:44.303Z] GC before operation: completed in 160.437 ms, heap usage 123.682 MB -> 66.072 MB. [2024-08-10T02:01:46.270Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1910.514 ms) ====== [2024-08-10T02:01:46.270Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-10T02:01:46.270Z] GC before operation: completed in 127.450 ms, heap usage 357.115 MB -> 66.380 MB. [2024-08-10T02:01:48.344Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2062.255 ms) ====== [2024-08-10T02:01:48.344Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-10T02:01:48.344Z] GC before operation: completed in 182.373 ms, heap usage 253.134 MB -> 94.156 MB. [2024-08-10T02:01:50.303Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1823.899 ms) ====== [2024-08-10T02:01:50.303Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-10T02:01:50.303Z] GC before operation: completed in 128.817 ms, heap usage 271.497 MB -> 94.152 MB. [2024-08-10T02:01:53.131Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2120.500 ms) ====== [2024-08-10T02:01:53.131Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-10T02:01:53.131Z] GC before operation: completed in 144.685 ms, heap usage 337.184 MB -> 66.691 MB. [2024-08-10T02:01:55.131Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1969.927 ms) ====== [2024-08-10T02:01:55.131Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-10T02:01:55.131Z] GC before operation: completed in 151.936 ms, heap usage 165.993 MB -> 66.677 MB. [2024-08-10T02:01:57.143Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2248.573 ms) ====== [2024-08-10T02:01:57.143Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-10T02:01:57.143Z] GC before operation: completed in 174.698 ms, heap usage 298.251 MB -> 66.846 MB. [2024-08-10T02:01:59.978Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2231.055 ms) ====== [2024-08-10T02:01:59.978Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-10T02:01:59.978Z] GC before operation: completed in 165.586 ms, heap usage 307.936 MB -> 94.594 MB. [2024-08-10T02:01:59.978Z] 24/08/10 02:01:59 WARN BlockManager: Asked to remove block broadcast_344_piece0, which does not exist [2024-08-10T02:01:59.978Z] 24/08/10 02:01:59 WARN BlockManager: Asked to remove block broadcast_344, which does not exist [2024-08-10T02:02:02.009Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1915.045 ms) ====== [2024-08-10T02:02:02.009Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-10T02:02:02.009Z] GC before operation: completed in 131.463 ms, heap usage 243.681 MB -> 67.062 MB. [2024-08-10T02:02:04.001Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1855.896 ms) ====== [2024-08-10T02:02:04.001Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-10T02:02:04.001Z] GC before operation: completed in 124.182 ms, heap usage 237.057 MB -> 67.316 MB. [2024-08-10T02:02:05.999Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2134.121 ms) ====== [2024-08-10T02:02:05.999Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-10T02:02:05.999Z] GC before operation: completed in 143.778 ms, heap usage 129.478 MB -> 67.100 MB. [2024-08-10T02:02:08.028Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1801.874 ms) ====== [2024-08-10T02:02:08.028Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-10T02:02:08.028Z] GC before operation: completed in 153.496 ms, heap usage 288.618 MB -> 86.397 MB. [2024-08-10T02:02:10.027Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1796.052 ms) ====== [2024-08-10T02:02:10.027Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-10T02:02:10.027Z] GC before operation: completed in 131.003 ms, heap usage 185.473 MB -> 67.309 MB. [2024-08-10T02:02:12.031Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1870.195 ms) ====== [2024-08-10T02:02:12.031Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-10T02:02:12.031Z] GC before operation: completed in 130.479 ms, heap usage 99.664 MB -> 67.018 MB. [2024-08-10T02:02:14.406Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2011.194 ms) ====== [2024-08-10T02:02:14.406Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-10T02:02:14.406Z] GC before operation: completed in 148.987 ms, heap usage 382.675 MB -> 67.892 MB. [2024-08-10T02:02:16.388Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1902.244 ms) ====== [2024-08-10T02:02:16.388Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-10T02:02:16.388Z] GC before operation: completed in 149.459 ms, heap usage 157.436 MB -> 95.164 MB. [2024-08-10T02:02:17.699Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1826.159 ms) ====== [2024-08-10T02:02:17.699Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-10T02:02:18.341Z] GC before operation: completed in 200.552 ms, heap usage 280.304 MB -> 95.583 MB. [2024-08-10T02:02:20.351Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1824.923 ms) ====== [2024-08-10T02:02:20.351Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-10T02:02:20.351Z] GC before operation: completed in 144.509 ms, heap usage 187.182 MB -> 67.832 MB. [2024-08-10T02:02:22.315Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1973.171 ms) ====== [2024-08-10T02:02:22.315Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-10T02:02:22.315Z] GC before operation: completed in 153.092 ms, heap usage 208.076 MB -> 68.390 MB. [2024-08-10T02:02:24.363Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1835.422 ms) ====== [2024-08-10T02:02:24.363Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-10T02:02:24.363Z] GC before operation: completed in 180.369 ms, heap usage 222.425 MB -> 68.299 MB. [2024-08-10T02:02:26.367Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1908.504 ms) ====== [2024-08-10T02:02:26.367Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-10T02:02:26.367Z] GC before operation: completed in 143.548 ms, heap usage 110.157 MB -> 67.849 MB. [2024-08-10T02:02:28.401Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2040.373 ms) ====== [2024-08-10T02:02:28.401Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-10T02:02:28.401Z] GC before operation: completed in 176.722 ms, heap usage 206.094 MB -> 76.488 MB. [2024-08-10T02:02:30.426Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1964.873 ms) ====== [2024-08-10T02:02:31.031Z] ----------------------------------- [2024-08-10T02:02:31.031Z] renaissance-dec-tree_0_PASSED [2024-08-10T02:02:31.031Z] ----------------------------------- [2024-08-10T02:02:31.031Z] [2024-08-10T02:02:31.031Z] TEST TEARDOWN: [2024-08-10T02:02:31.031Z] Nothing to be done for teardown. [2024-08-10T02:02:31.031Z] renaissance-dec-tree_0 Finish Time: Sat Aug 10 02:02:30 2024 Epoch Time (ms): 1723255350569