[2024-08-07T21:12:43.528Z] Running test renaissance-dec-tree_0 ... [2024-08-07T21:12:43.528Z] =============================================== [2024-08-07T21:12:43.528Z] renaissance-dec-tree_0 Start Time: Wed Aug 7 21:12:43 2024 Epoch Time (ms): 1723065163099 [2024-08-07T21:12:43.528Z] variation: NoOptions [2024-08-07T21:12:43.528Z] JVM_OPTIONS: [2024-08-07T21:12:43.528Z] { \ [2024-08-07T21:12:43.528Z] echo ""; echo "TEST SETUP:"; \ [2024-08-07T21:12:43.528Z] echo "Nothing to be done for setup."; \ [2024-08-07T21:12:43.528Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723064333819/renaissance-dec-tree_0"; \ [2024-08-07T21:12:43.528Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723064333819/renaissance-dec-tree_0"; \ [2024-08-07T21:12:43.528Z] echo ""; echo "TESTING:"; \ [2024-08-07T21:12:43.528Z] "/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_1723064333819/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-07T21:12:43.529Z] 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_1723064333819/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-07T21:12:43.529Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-07T21:12:43.529Z] echo "Nothing to be done for teardown."; \ [2024-08-07T21:12:43.529Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723064333819/TestTargetResult"; [2024-08-07T21:12:43.529Z] [2024-08-07T21:12:43.529Z] TEST SETUP: [2024-08-07T21:12:43.529Z] Nothing to be done for setup. [2024-08-07T21:12:43.529Z] [2024-08-07T21:12:43.529Z] TESTING: [2024-08-07T21:12:47.836Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-07T21:12:49.117Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-07T21:12:51.989Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-07T21:12:51.989Z] GC before operation: completed in 82.741 ms, heap usage 56.274 MB -> 35.086 MB. [2024-08-07T21:13:04.431Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11595.696 ms) ====== [2024-08-07T21:13:04.431Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-07T21:13:04.431Z] GC before operation: completed in 205.988 ms, heap usage 217.958 MB -> 63.744 MB. [2024-08-07T21:13:07.388Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3810.123 ms) ====== [2024-08-07T21:13:07.388Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-07T21:13:07.388Z] GC before operation: completed in 198.452 ms, heap usage 166.814 MB -> 63.482 MB. [2024-08-07T21:13:11.159Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3234.818 ms) ====== [2024-08-07T21:13:11.159Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-07T21:13:11.159Z] GC before operation: completed in 105.685 ms, heap usage 279.379 MB -> 64.154 MB. [2024-08-07T21:13:15.225Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4099.010 ms) ====== [2024-08-07T21:13:15.226Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-07T21:13:15.226Z] GC before operation: completed in 344.551 ms, heap usage 162.387 MB -> 64.143 MB. [2024-08-07T21:13:18.106Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2950.756 ms) ====== [2024-08-07T21:13:18.106Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-07T21:13:18.844Z] GC before operation: completed in 193.762 ms, heap usage 195.665 MB -> 92.162 MB. [2024-08-07T21:13:21.851Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3259.470 ms) ====== [2024-08-07T21:13:21.851Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-07T21:13:21.851Z] GC before operation: completed in 174.817 ms, heap usage 181.552 MB -> 64.758 MB. [2024-08-07T21:13:25.869Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3371.702 ms) ====== [2024-08-07T21:13:25.869Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-07T21:13:25.869Z] GC before operation: completed in 174.722 ms, heap usage 138.761 MB -> 64.572 MB. [2024-08-07T21:13:30.229Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3842.279 ms) ====== [2024-08-07T21:13:30.229Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-07T21:13:30.229Z] GC before operation: completed in 154.316 ms, heap usage 258.444 MB -> 65.210 MB. [2024-08-07T21:13:32.377Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3082.370 ms) ====== [2024-08-07T21:13:32.377Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-07T21:13:33.131Z] GC before operation: completed in 221.864 ms, heap usage 228.311 MB -> 73.734 MB. [2024-08-07T21:13:36.097Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3080.287 ms) ====== [2024-08-07T21:13:36.097Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-07T21:13:36.097Z] GC before operation: completed in 117.159 ms, heap usage 312.277 MB -> 73.743 MB. [2024-08-07T21:13:38.950Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2510.485 ms) ====== [2024-08-07T21:13:38.951Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-07T21:13:38.951Z] GC before operation: completed in 144.839 ms, heap usage 162.101 MB -> 92.716 MB. [2024-08-07T21:13:38.951Z] 24/08/07 21:13:38 WARN BlockManager: Asked to remove block broadcast_148, which does not exist [2024-08-07T21:13:40.967Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2389.427 ms) ====== [2024-08-07T21:13:40.967Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-07T21:13:40.967Z] GC before operation: completed in 165.118 ms, heap usage 315.314 MB -> 65.660 MB. [2024-08-07T21:13:43.897Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2592.643 ms) ====== [2024-08-07T21:13:43.897Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-07T21:13:43.897Z] GC before operation: completed in 244.934 ms, heap usage 290.893 MB -> 84.909 MB. [2024-08-07T21:13:46.826Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2604.926 ms) ====== [2024-08-07T21:13:46.826Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-07T21:13:46.826Z] GC before operation: completed in 180.644 ms, heap usage 200.911 MB -> 65.698 MB. [2024-08-07T21:13:49.870Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2844.064 ms) ====== [2024-08-07T21:13:49.870Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-07T21:13:49.870Z] GC before operation: completed in 218.231 ms, heap usage 262.065 MB -> 65.970 MB. [2024-08-07T21:13:53.093Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3159.757 ms) ====== [2024-08-07T21:13:53.093Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-07T21:13:53.093Z] GC before operation: completed in 197.268 ms, heap usage 258.488 MB -> 66.204 MB. [2024-08-07T21:13:56.004Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2814.955 ms) ====== [2024-08-07T21:13:56.004Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-07T21:13:56.004Z] GC before operation: completed in 165.488 ms, heap usage 271.353 MB -> 66.267 MB. [2024-08-07T21:13:59.012Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2434.756 ms) ====== [2024-08-07T21:13:59.012Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-07T21:13:59.012Z] GC before operation: completed in 178.971 ms, heap usage 112.799 MB -> 65.817 MB. [2024-08-07T21:14:01.156Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2372.681 ms) ====== [2024-08-07T21:14:01.156Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-07T21:14:01.156Z] GC before operation: completed in 164.196 ms, heap usage 364.022 MB -> 66.540 MB. [2024-08-07T21:14:04.039Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2419.478 ms) ====== [2024-08-07T21:14:04.039Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-07T21:14:04.039Z] GC before operation: completed in 128.317 ms, heap usage 227.202 MB -> 66.617 MB. [2024-08-07T21:14:06.866Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2512.935 ms) ====== [2024-08-07T21:14:06.866Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-07T21:14:06.866Z] GC before operation: completed in 143.657 ms, heap usage 248.629 MB -> 85.991 MB. [2024-08-07T21:14:08.907Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2486.929 ms) ====== [2024-08-07T21:14:08.907Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-07T21:14:09.530Z] GC before operation: completed in 167.384 ms, heap usage 214.764 MB -> 66.669 MB. [2024-08-07T21:14:11.598Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2442.075 ms) ====== [2024-08-07T21:14:11.598Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-07T21:14:11.598Z] GC before operation: completed in 143.343 ms, heap usage 127.006 MB -> 66.904 MB. [2024-08-07T21:14:14.476Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2400.063 ms) ====== [2024-08-07T21:14:14.476Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-07T21:14:14.476Z] GC before operation: completed in 189.436 ms, heap usage 167.100 MB -> 86.236 MB. [2024-08-07T21:14:17.786Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2931.911 ms) ====== [2024-08-07T21:14:17.786Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-07T21:14:17.786Z] GC before operation: completed in 290.250 ms, heap usage 121.688 MB -> 66.558 MB. [2024-08-07T21:14:20.837Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3120.770 ms) ====== [2024-08-07T21:14:20.837Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-07T21:14:20.837Z] GC before operation: completed in 174.687 ms, heap usage 380.363 MB -> 67.047 MB. [2024-08-07T21:14:23.894Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2734.427 ms) ====== [2024-08-07T21:14:23.894Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-07T21:14:23.894Z] GC before operation: completed in 253.966 ms, heap usage 144.149 MB -> 66.901 MB. [2024-08-07T21:14:26.782Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2746.214 ms) ====== [2024-08-07T21:14:26.782Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-07T21:14:26.782Z] GC before operation: completed in 154.396 ms, heap usage 381.571 MB -> 67.503 MB. [2024-08-07T21:14:29.786Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2890.241 ms) ====== [2024-08-07T21:14:29.786Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-07T21:14:29.786Z] GC before operation: completed in 216.242 ms, heap usage 215.756 MB -> 67.736 MB. [2024-08-07T21:14:29.786Z] 24/08/07 21:14:29 WARN BlockManager: Asked to remove block broadcast_400, which does not exist [2024-08-07T21:14:32.644Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2591.651 ms) ====== [2024-08-07T21:14:32.644Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-07T21:14:32.644Z] GC before operation: completed in 160.388 ms, heap usage 249.569 MB -> 67.799 MB. [2024-08-07T21:14:34.824Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2140.583 ms) ====== [2024-08-07T21:14:34.824Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-07T21:14:34.824Z] GC before operation: completed in 224.456 ms, heap usage 136.190 MB -> 95.131 MB. [2024-08-07T21:14:36.886Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2068.202 ms) ====== [2024-08-07T21:14:36.886Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-07T21:14:37.488Z] GC before operation: completed in 126.022 ms, heap usage 183.169 MB -> 67.347 MB. [2024-08-07T21:14:39.535Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2228.038 ms) ====== [2024-08-07T21:14:39.535Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-07T21:14:39.535Z] GC before operation: completed in 243.271 ms, heap usage 137.920 MB -> 95.322 MB. [2024-08-07T21:14:41.551Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2064.651 ms) ====== [2024-08-07T21:14:41.551Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-07T21:14:42.174Z] GC before operation: completed in 329.300 ms, heap usage 131.858 MB -> 67.737 MB. [2024-08-07T21:14:44.266Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2249.352 ms) ====== [2024-08-07T21:14:44.266Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-07T21:14:44.931Z] GC before operation: completed in 207.330 ms, heap usage 301.155 MB -> 68.250 MB. [2024-08-07T21:14:46.926Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2254.602 ms) ====== [2024-08-07T21:14:46.926Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-07T21:14:46.926Z] GC before operation: completed in 146.522 ms, heap usage 229.197 MB -> 87.479 MB. [2024-08-07T21:14:49.759Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2336.805 ms) ====== [2024-08-07T21:14:49.759Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-07T21:14:49.759Z] GC before operation: completed in 215.840 ms, heap usage 218.041 MB -> 95.857 MB. [2024-08-07T21:14:52.751Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2559.613 ms) ====== [2024-08-07T21:14:52.751Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-07T21:14:52.751Z] GC before operation: completed in 294.049 ms, heap usage 349.977 MB -> 95.903 MB. [2024-08-07T21:14:55.707Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3520.320 ms) ====== [2024-08-07T21:14:55.707Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-07T21:14:56.870Z] GC before operation: completed in 280.416 ms, heap usage 256.609 MB -> 68.502 MB. [2024-08-07T21:14:58.953Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2306.838 ms) ====== [2024-08-07T21:14:58.953Z] ----------------------------------- [2024-08-07T21:14:58.953Z] renaissance-dec-tree_0_PASSED [2024-08-07T21:14:58.953Z] ----------------------------------- [2024-08-07T21:14:58.953Z] [2024-08-07T21:14:58.953Z] TEST TEARDOWN: [2024-08-07T21:14:58.953Z] Nothing to be done for teardown. [2024-08-07T21:14:58.953Z] renaissance-dec-tree_0 Finish Time: Wed Aug 7 21:14:58 2024 Epoch Time (ms): 1723065298552