[2025-02-06T06:43:02.643Z] Running test renaissance-dec-tree_0 ... [2025-02-06T06:43:02.643Z] =============================================== [2025-02-06T06:43:02.643Z] renaissance-dec-tree_0 Start Time: Thu Feb 6 06:43:02 2025 Epoch Time (ms): 1738824182538 [2025-02-06T06:43:02.643Z] variation: NoOptions [2025-02-06T06:43:02.643Z] JVM_OPTIONS: [2025-02-06T06:43:02.643Z] { \ [2025-02-06T06:43:02.643Z] echo ""; echo "TEST SETUP:"; \ [2025-02-06T06:43:02.643Z] echo "Nothing to be done for setup."; \ [2025-02-06T06:43:02.643Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1738823099730/renaissance-dec-tree_0"; \ [2025-02-06T06:43:02.643Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1738823099730/renaissance-dec-tree_0"; \ [2025-02-06T06:43:02.643Z] echo ""; echo "TESTING:"; \ [2025-02-06T06:43:02.643Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1738823099730/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-06T06:43:02.643Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1738823099730/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-06T06:43:02.643Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-06T06:43:02.643Z] echo "Nothing to be done for teardown."; \ [2025-02-06T06:43:02.643Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1738823099730/TestTargetResult"; [2025-02-06T06:43:02.643Z] [2025-02-06T06:43:02.643Z] TEST SETUP: [2025-02-06T06:43:02.643Z] Nothing to be done for setup. [2025-02-06T06:43:02.643Z] [2025-02-06T06:43:02.643Z] TESTING: [2025-02-06T06:43:07.835Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-06T06:43:11.047Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-02-06T06:43:16.047Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-06T06:43:16.047Z] GC before operation: completed in 76.135 ms, heap usage 53.965 MB -> 34.951 MB. [2025-02-06T06:43:33.830Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (16630.635 ms) ====== [2025-02-06T06:43:33.830Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-06T06:43:33.830Z] GC before operation: completed in 312.720 ms, heap usage 264.895 MB -> 90.835 MB. [2025-02-06T06:43:38.027Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (4914.821 ms) ====== [2025-02-06T06:43:38.028Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-06T06:43:38.028Z] GC before operation: completed in 344.331 ms, heap usage 180.771 MB -> 63.448 MB. [2025-02-06T06:43:41.103Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3407.081 ms) ====== [2025-02-06T06:43:41.103Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-06T06:43:41.103Z] GC before operation: completed in 158.918 ms, heap usage 222.259 MB -> 64.135 MB. [2025-02-06T06:43:45.234Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3748.475 ms) ====== [2025-02-06T06:43:45.234Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-06T06:43:45.234Z] GC before operation: completed in 220.571 ms, heap usage 304.876 MB -> 64.383 MB. [2025-02-06T06:43:48.809Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3839.078 ms) ====== [2025-02-06T06:43:48.809Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-06T06:43:49.617Z] GC before operation: completed in 216.589 ms, heap usage 135.005 MB -> 64.093 MB. [2025-02-06T06:43:53.655Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3849.787 ms) ====== [2025-02-06T06:43:53.655Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-06T06:43:53.655Z] GC before operation: completed in 152.180 ms, heap usage 186.430 MB -> 64.735 MB. [2025-02-06T06:43:56.748Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3683.861 ms) ====== [2025-02-06T06:43:56.748Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-06T06:43:57.463Z] GC before operation: completed in 420.130 ms, heap usage 265.735 MB -> 64.814 MB. [2025-02-06T06:44:01.200Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3978.589 ms) ====== [2025-02-06T06:44:01.200Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-06T06:44:01.870Z] GC before operation: completed in 289.478 ms, heap usage 262.923 MB -> 92.652 MB. [2025-02-06T06:44:04.947Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3104.986 ms) ====== [2025-02-06T06:44:04.947Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-06T06:44:04.947Z] GC before operation: completed in 284.698 ms, heap usage 373.526 MB -> 92.696 MB. [2025-02-06T06:44:08.899Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3948.850 ms) ====== [2025-02-06T06:44:08.899Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-06T06:44:09.616Z] GC before operation: completed in 410.744 ms, heap usage 262.778 MB -> 92.923 MB. [2025-02-06T06:44:13.657Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3842.832 ms) ====== [2025-02-06T06:44:13.657Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-06T06:44:13.657Z] GC before operation: completed in 439.468 ms, heap usage 307.660 MB -> 65.797 MB. [2025-02-06T06:44:13.657Z] 25/02/06 06:44:13 WARN BlockManager: Asked to remove block broadcast_148, which does not exist [2025-02-06T06:44:17.727Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3446.819 ms) ====== [2025-02-06T06:44:17.727Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-06T06:44:17.727Z] GC before operation: completed in 303.861 ms, heap usage 249.599 MB -> 65.394 MB. [2025-02-06T06:44:21.930Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4406.128 ms) ====== [2025-02-06T06:44:21.930Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-06T06:44:21.930Z] GC before operation: completed in 338.035 ms, heap usage 327.680 MB -> 65.939 MB. [2025-02-06T06:44:26.050Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3734.513 ms) ====== [2025-02-06T06:44:26.050Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-06T06:44:26.050Z] GC before operation: completed in 325.351 ms, heap usage 125.606 MB -> 73.537 MB. [2025-02-06T06:44:30.120Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3567.365 ms) ====== [2025-02-06T06:44:30.120Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-06T06:44:30.120Z] GC before operation: completed in 232.231 ms, heap usage 310.677 MB -> 66.119 MB. [2025-02-06T06:44:34.213Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3729.482 ms) ====== [2025-02-06T06:44:34.213Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-06T06:44:34.213Z] GC before operation: completed in 239.471 ms, heap usage 100.267 MB -> 65.550 MB. [2025-02-06T06:44:38.278Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3782.838 ms) ====== [2025-02-06T06:44:38.278Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-06T06:44:38.278Z] GC before operation: completed in 216.303 ms, heap usage 319.621 MB -> 66.056 MB. [2025-02-06T06:44:41.369Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3094.181 ms) ====== [2025-02-06T06:44:41.369Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-06T06:44:41.369Z] GC before operation: completed in 277.540 ms, heap usage 126.995 MB -> 65.872 MB. [2025-02-06T06:44:45.450Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3428.794 ms) ====== [2025-02-06T06:44:45.450Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-06T06:44:45.450Z] GC before operation: completed in 229.253 ms, heap usage 200.447 MB -> 66.466 MB. [2025-02-06T06:44:48.399Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3091.033 ms) ====== [2025-02-06T06:44:48.399Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-06T06:44:48.399Z] GC before operation: completed in 209.523 ms, heap usage 165.689 MB -> 66.126 MB. [2025-02-06T06:44:51.666Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3585.904 ms) ====== [2025-02-06T06:44:51.666Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-06T06:44:52.370Z] GC before operation: completed in 186.565 ms, heap usage 150.185 MB -> 66.600 MB. [2025-02-06T06:44:55.435Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2724.309 ms) ====== [2025-02-06T06:44:55.435Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-06T06:44:55.435Z] GC before operation: completed in 379.472 ms, heap usage 236.665 MB -> 66.780 MB. [2025-02-06T06:44:58.667Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3418.946 ms) ====== [2025-02-06T06:44:58.667Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-06T06:44:58.667Z] GC before operation: completed in 182.151 ms, heap usage 110.980 MB -> 66.176 MB. [2025-02-06T06:45:01.699Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2987.180 ms) ====== [2025-02-06T06:45:01.699Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-06T06:45:02.408Z] GC before operation: completed in 243.903 ms, heap usage 294.838 MB -> 67.261 MB. [2025-02-06T06:45:06.156Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4245.977 ms) ====== [2025-02-06T06:45:06.156Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-06T06:45:06.923Z] GC before operation: completed in 209.119 ms, heap usage 325.806 MB -> 66.938 MB. [2025-02-06T06:45:09.990Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3047.615 ms) ====== [2025-02-06T06:45:09.990Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-06T06:45:09.990Z] GC before operation: completed in 166.697 ms, heap usage 113.090 MB -> 66.503 MB. [2025-02-06T06:45:13.080Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2978.005 ms) ====== [2025-02-06T06:45:13.080Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-06T06:45:13.080Z] GC before operation: completed in 253.206 ms, heap usage 200.257 MB -> 67.573 MB. [2025-02-06T06:45:13.080Z] 25/02/06 06:45:12 WARN BlockManager: Asked to remove block broadcast_372, which does not exist [2025-02-06T06:45:16.148Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3381.705 ms) ====== [2025-02-06T06:45:16.148Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-06T06:45:16.842Z] GC before operation: completed in 204.182 ms, heap usage 143.524 MB -> 67.081 MB. [2025-02-06T06:45:20.006Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3058.977 ms) ====== [2025-02-06T06:45:20.006Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-06T06:45:20.006Z] GC before operation: completed in 284.078 ms, heap usage 326.412 MB -> 94.983 MB. [2025-02-06T06:45:22.133Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2348.157 ms) ====== [2025-02-06T06:45:22.133Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-06T06:45:22.821Z] GC before operation: completed in 244.898 ms, heap usage 244.570 MB -> 67.602 MB. [2025-02-06T06:45:27.037Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4011.500 ms) ====== [2025-02-06T06:45:27.038Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-06T06:45:27.038Z] GC before operation: completed in 710.471 ms, heap usage 364.858 MB -> 67.582 MB. [2025-02-06T06:45:31.040Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3434.673 ms) ====== [2025-02-06T06:45:31.040Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-06T06:45:31.040Z] GC before operation: completed in 502.714 ms, heap usage 205.311 MB -> 67.674 MB. [2025-02-06T06:45:34.158Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2802.709 ms) ====== [2025-02-06T06:45:34.158Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-06T06:45:34.158Z] GC before operation: completed in 401.008 ms, heap usage 253.111 MB -> 67.857 MB. [2025-02-06T06:45:38.233Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3721.416 ms) ====== [2025-02-06T06:45:38.233Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-06T06:45:38.233Z] GC before operation: completed in 225.218 ms, heap usage 284.621 MB -> 67.836 MB. [2025-02-06T06:45:41.291Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2718.462 ms) ====== [2025-02-06T06:45:41.291Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-06T06:45:41.292Z] GC before operation: completed in 382.604 ms, heap usage 220.158 MB -> 67.878 MB. [2025-02-06T06:45:43.447Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2247.302 ms) ====== [2025-02-06T06:45:43.447Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-06T06:45:44.157Z] GC before operation: completed in 145.080 ms, heap usage 220.402 MB -> 68.028 MB. [2025-02-06T06:45:46.451Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2874.760 ms) ====== [2025-02-06T06:45:46.451Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-06T06:45:47.122Z] GC before operation: completed in 182.071 ms, heap usage 240.032 MB -> 68.249 MB. [2025-02-06T06:45:49.464Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2725.562 ms) ====== [2025-02-06T06:45:49.464Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-06T06:45:50.143Z] GC before operation: completed in 370.769 ms, heap usage 240.691 MB -> 95.983 MB. [2025-02-06T06:45:53.190Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2862.302 ms) ====== [2025-02-06T06:45:53.190Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-06T06:45:53.190Z] GC before operation: completed in 311.077 ms, heap usage 270.046 MB -> 68.309 MB. [2025-02-06T06:45:56.228Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2645.749 ms) ====== [2025-02-06T06:45:56.228Z] ----------------------------------- [2025-02-06T06:45:56.228Z] renaissance-dec-tree_0_PASSED [2025-02-06T06:45:56.228Z] ----------------------------------- [2025-02-06T06:45:56.228Z] [2025-02-06T06:45:56.228Z] TEST TEARDOWN: [2025-02-06T06:45:56.228Z] Nothing to be done for teardown. [2025-02-06T06:45:56.228Z] renaissance-dec-tree_0 Finish Time: Thu Feb 6 06:45:55 2025 Epoch Time (ms): 1738824355943