[2024-06-27T04:13:59.519Z] Running test renaissance-dec-tree_0 ... [2024-06-27T04:13:59.519Z] =============================================== [2024-06-27T04:13:59.519Z] renaissance-dec-tree_0 Start Time: Thu Jun 27 00:13:58 2024 Epoch Time (ms): 1719461638936 [2024-06-27T04:13:59.519Z] variation: NoOptions [2024-06-27T04:13:59.519Z] JVM_OPTIONS: [2024-06-27T04:13:59.519Z] { \ [2024-06-27T04:13:59.519Z] echo ""; echo "TEST SETUP:"; \ [2024-06-27T04:13:59.519Z] echo "Nothing to be done for setup."; \ [2024-06-27T04:13:59.519Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/renaissance-dec-tree_0"; \ [2024-06-27T04:13:59.519Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/renaissance-dec-tree_0"; \ [2024-06-27T04:13:59.519Z] echo ""; echo "TESTING:"; \ [2024-06-27T04:13:59.519Z] "/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_17194609237698/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-06-27T04:13:59.519Z] 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_17194609237698/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-27T04:13:59.519Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-27T04:13:59.519Z] echo "Nothing to be done for teardown."; \ [2024-06-27T04:13:59.519Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194609237698/TestTargetResult"; [2024-06-27T04:13:59.519Z] [2024-06-27T04:13:59.519Z] TEST SETUP: [2024-06-27T04:13:59.519Z] Nothing to be done for setup. [2024-06-27T04:13:59.519Z] [2024-06-27T04:13:59.519Z] TESTING: [2024-06-27T04:14:03.398Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-27T04:14:05.550Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-06-27T04:14:09.631Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-06-27T04:14:09.631Z] GC before operation: completed in 76.193 ms, heap usage 59.933 MB -> 34.987 MB. [2024-06-27T04:14:22.581Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (12340.780 ms) ====== [2024-06-27T04:14:22.581Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-06-27T04:14:22.581Z] GC before operation: completed in 154.633 ms, heap usage 224.590 MB -> 63.220 MB. [2024-06-27T04:14:26.612Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3772.354 ms) ====== [2024-06-27T04:14:26.612Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-06-27T04:14:26.612Z] GC before operation: completed in 143.111 ms, heap usage 168.505 MB -> 63.413 MB. [2024-06-27T04:14:29.649Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3298.010 ms) ====== [2024-06-27T04:14:29.649Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-06-27T04:14:29.649Z] GC before operation: completed in 191.875 ms, heap usage 166.931 MB -> 63.657 MB. [2024-06-27T04:14:33.025Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3014.290 ms) ====== [2024-06-27T04:14:33.025Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-06-27T04:14:33.025Z] GC before operation: completed in 137.574 ms, heap usage 195.765 MB -> 64.313 MB. [2024-06-27T04:14:35.973Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3207.919 ms) ====== [2024-06-27T04:14:35.973Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-06-27T04:14:35.973Z] GC before operation: completed in 192.366 ms, heap usage 195.565 MB -> 64.519 MB. [2024-06-27T04:14:39.858Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3226.595 ms) ====== [2024-06-27T04:14:39.858Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-06-27T04:14:39.858Z] GC before operation: completed in 145.882 ms, heap usage 335.568 MB -> 64.967 MB. [2024-06-27T04:14:39.858Z] 24/06/27 00:14:39 WARN BlockManager: Asked to remove block broadcast_82, which does not exist [2024-06-27T04:14:42.014Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2428.973 ms) ====== [2024-06-27T04:14:42.014Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-06-27T04:14:42.014Z] GC before operation: completed in 127.265 ms, heap usage 179.069 MB -> 92.471 MB. [2024-06-27T04:14:44.197Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2352.278 ms) ====== [2024-06-27T04:14:44.197Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-06-27T04:14:44.935Z] GC before operation: completed in 133.101 ms, heap usage 315.446 MB -> 64.935 MB. [2024-06-27T04:14:47.956Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2902.915 ms) ====== [2024-06-27T04:14:47.956Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-06-27T04:14:47.956Z] GC before operation: completed in 234.964 ms, heap usage 205.023 MB -> 65.077 MB. [2024-06-27T04:14:51.002Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2811.882 ms) ====== [2024-06-27T04:14:51.002Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-06-27T04:14:51.002Z] GC before operation: completed in 204.691 ms, heap usage 199.501 MB -> 65.210 MB. [2024-06-27T04:14:53.969Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3135.783 ms) ====== [2024-06-27T04:14:53.969Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-06-27T04:14:53.969Z] GC before operation: completed in 201.590 ms, heap usage 195.672 MB -> 65.367 MB. [2024-06-27T04:14:58.056Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3495.152 ms) ====== [2024-06-27T04:14:58.056Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-06-27T04:14:58.056Z] GC before operation: completed in 175.946 ms, heap usage 186.625 MB -> 65.385 MB. [2024-06-27T04:15:01.143Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3352.774 ms) ====== [2024-06-27T04:15:01.143Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-06-27T04:15:01.143Z] GC before operation: completed in 228.334 ms, heap usage 301.824 MB -> 65.679 MB. [2024-06-27T04:15:05.106Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3160.363 ms) ====== [2024-06-27T04:15:05.106Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-06-27T04:15:05.106Z] GC before operation: completed in 146.348 ms, heap usage 162.724 MB -> 65.479 MB. [2024-06-27T04:15:08.089Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3407.562 ms) ====== [2024-06-27T04:15:08.089Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-06-27T04:15:08.089Z] GC before operation: completed in 164.072 ms, heap usage 186.723 MB -> 65.880 MB. [2024-06-27T04:15:11.238Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2758.162 ms) ====== [2024-06-27T04:15:11.238Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-06-27T04:15:11.238Z] GC before operation: completed in 235.817 ms, heap usage 192.610 MB -> 66.089 MB. [2024-06-27T04:15:14.292Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2652.164 ms) ====== [2024-06-27T04:15:14.292Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-06-27T04:15:14.292Z] GC before operation: completed in 209.003 ms, heap usage 327.077 MB -> 66.137 MB. [2024-06-27T04:15:16.609Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2731.955 ms) ====== [2024-06-27T04:15:16.609Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-06-27T04:15:17.271Z] GC before operation: completed in 182.940 ms, heap usage 278.530 MB -> 66.134 MB. [2024-06-27T04:15:19.407Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2273.403 ms) ====== [2024-06-27T04:15:19.407Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-06-27T04:15:19.407Z] GC before operation: completed in 171.104 ms, heap usage 279.555 MB -> 66.208 MB. [2024-06-27T04:15:22.323Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2561.001 ms) ====== [2024-06-27T04:15:22.323Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-06-27T04:15:22.323Z] GC before operation: completed in 235.765 ms, heap usage 257.344 MB -> 66.354 MB. [2024-06-27T04:15:24.994Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2627.777 ms) ====== [2024-06-27T04:15:24.994Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-06-27T04:15:24.994Z] GC before operation: completed in 232.361 ms, heap usage 115.489 MB -> 65.890 MB. [2024-06-27T04:15:27.427Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2321.844 ms) ====== [2024-06-27T04:15:27.427Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-06-27T04:15:27.427Z] GC before operation: completed in 239.581 ms, heap usage 284.039 MB -> 66.757 MB. [2024-06-27T04:15:30.445Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2264.851 ms) ====== [2024-06-27T04:15:30.445Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-06-27T04:15:30.445Z] GC before operation: completed in 183.940 ms, heap usage 168.981 MB -> 66.591 MB. [2024-06-27T04:15:32.580Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2349.243 ms) ====== [2024-06-27T04:15:32.580Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-06-27T04:15:32.580Z] GC before operation: completed in 244.576 ms, heap usage 244.035 MB -> 66.814 MB. [2024-06-27T04:15:35.778Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2630.367 ms) ====== [2024-06-27T04:15:35.778Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-06-27T04:15:35.778Z] GC before operation: completed in 262.377 ms, heap usage 189.203 MB -> 67.078 MB. [2024-06-27T04:15:37.972Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2308.776 ms) ====== [2024-06-27T04:15:37.972Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-06-27T04:15:37.972Z] GC before operation: completed in 165.442 ms, heap usage 330.265 MB -> 67.012 MB. [2024-06-27T04:15:40.149Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2079.918 ms) ====== [2024-06-27T04:15:40.149Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-06-27T04:15:40.149Z] GC before operation: completed in 253.414 ms, heap usage 166.587 MB -> 67.278 MB. [2024-06-27T04:15:40.828Z] 24/06/27 00:15:40 WARN BlockManager: Asked to remove block broadcast_372, which does not exist [2024-06-27T04:15:42.910Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2456.380 ms) ====== [2024-06-27T04:15:42.910Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-06-27T04:15:42.910Z] GC before operation: completed in 149.421 ms, heap usage 225.137 MB -> 67.359 MB. [2024-06-27T04:15:45.987Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2444.696 ms) ====== [2024-06-27T04:15:45.987Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-06-27T04:15:45.987Z] GC before operation: completed in 225.944 ms, heap usage 176.904 MB -> 67.410 MB. [2024-06-27T04:15:48.119Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2082.949 ms) ====== [2024-06-27T04:15:48.119Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-06-27T04:15:48.119Z] GC before operation: completed in 224.419 ms, heap usage 331.279 MB -> 67.538 MB. [2024-06-27T04:15:50.264Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2194.298 ms) ====== [2024-06-27T04:15:50.264Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-06-27T04:15:50.264Z] GC before operation: completed in 219.910 ms, heap usage 222.520 MB -> 67.566 MB. [2024-06-27T04:15:53.271Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2586.243 ms) ====== [2024-06-27T04:15:53.271Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-06-27T04:15:53.271Z] GC before operation: completed in 158.915 ms, heap usage 274.716 MB -> 67.711 MB. [2024-06-27T04:15:56.289Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2737.557 ms) ====== [2024-06-27T04:15:56.289Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-06-27T04:15:56.289Z] GC before operation: completed in 251.754 ms, heap usage 271.488 MB -> 67.825 MB. [2024-06-27T04:15:58.550Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2365.170 ms) ====== [2024-06-27T04:15:58.550Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-06-27T04:15:58.550Z] GC before operation: completed in 254.731 ms, heap usage 130.595 MB -> 67.623 MB. [2024-06-27T04:16:00.755Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2161.755 ms) ====== [2024-06-27T04:16:00.756Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-06-27T04:16:01.472Z] GC before operation: completed in 327.799 ms, heap usage 165.179 MB -> 67.787 MB. [2024-06-27T04:16:03.560Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2283.530 ms) ====== [2024-06-27T04:16:03.560Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-06-27T04:16:03.560Z] GC before operation: completed in 259.784 ms, heap usage 181.921 MB -> 68.120 MB. [2024-06-27T04:16:06.441Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2200.691 ms) ====== [2024-06-27T04:16:06.441Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-06-27T04:16:06.441Z] GC before operation: completed in 159.417 ms, heap usage 276.904 MB -> 68.143 MB. [2024-06-27T04:16:08.576Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1900.265 ms) ====== [2024-06-27T04:16:08.576Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-06-27T04:16:08.576Z] GC before operation: completed in 159.502 ms, heap usage 271.159 MB -> 68.269 MB. [2024-06-27T04:16:10.849Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2299.936 ms) ====== [2024-06-27T04:16:10.849Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-06-27T04:16:10.849Z] GC before operation: completed in 187.765 ms, heap usage 239.226 MB -> 68.402 MB. [2024-06-27T04:16:12.995Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2257.368 ms) ====== [2024-06-27T04:16:13.661Z] ----------------------------------- [2024-06-27T04:16:13.661Z] renaissance-dec-tree_0_PASSED [2024-06-27T04:16:13.661Z] ----------------------------------- [2024-06-27T04:16:13.661Z] [2024-06-27T04:16:13.661Z] TEST TEARDOWN: [2024-06-27T04:16:13.661Z] Nothing to be done for teardown. [2024-06-27T04:16:13.661Z] renaissance-dec-tree_0 Finish Time: Thu Jun 27 00:16:13 2024 Epoch Time (ms): 1719461773062