[2025-02-27T12:10:58.394Z] Running test renaissance-dec-tree_0 ... [2025-02-27T12:10:58.394Z] =============================================== [2025-02-27T12:10:58.394Z] renaissance-dec-tree_0 Start Time: Thu Feb 27 12:10:58 2025 Epoch Time (ms): 1740658258120 [2025-02-27T12:10:58.394Z] variation: NoOptions [2025-02-27T12:10:58.394Z] JVM_OPTIONS: [2025-02-27T12:10:58.394Z] { \ [2025-02-27T12:10:58.394Z] echo ""; echo "TEST SETUP:"; \ [2025-02-27T12:10:58.394Z] echo "Nothing to be done for setup."; \ [2025-02-27T12:10:58.394Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406569533119/renaissance-dec-tree_0"; \ [2025-02-27T12:10:58.394Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406569533119/renaissance-dec-tree_0"; \ [2025-02-27T12:10:58.394Z] echo ""; echo "TESTING:"; \ [2025-02-27T12:10:58.394Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406569533119/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-27T12:10:58.394Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406569533119/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-27T12:10:58.394Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-27T12:10:58.394Z] echo "Nothing to be done for teardown."; \ [2025-02-27T12:10:58.394Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17406569533119/TestTargetResult"; [2025-02-27T12:10:58.394Z] [2025-02-27T12:10:58.394Z] TEST SETUP: [2025-02-27T12:10:58.394Z] Nothing to be done for setup. [2025-02-27T12:10:58.394Z] [2025-02-27T12:10:58.394Z] TESTING: [2025-02-27T12:11:04.112Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-27T12:11:08.717Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-27T12:11:18.893Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-27T12:11:18.893Z] GC before operation: completed in 128.199 ms, heap usage 45.908 MB -> 34.956 MB. [2025-02-27T12:11:49.496Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (29626.912 ms) ====== [2025-02-27T12:11:49.496Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-27T12:11:49.496Z] GC before operation: completed in 319.239 ms, heap usage 159.546 MB -> 64.217 MB. [2025-02-27T12:11:56.914Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (8893.982 ms) ====== [2025-02-27T12:11:56.914Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-27T12:11:57.713Z] GC before operation: completed in 339.015 ms, heap usage 289.005 MB -> 64.708 MB. [2025-02-27T12:12:06.204Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (8209.759 ms) ====== [2025-02-27T12:12:06.204Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-27T12:12:06.204Z] GC before operation: completed in 246.863 ms, heap usage 221.705 MB -> 65.376 MB. [2025-02-27T12:12:11.970Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (6153.603 ms) ====== [2025-02-27T12:12:11.970Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-27T12:12:11.970Z] GC before operation: completed in 272.824 ms, heap usage 130.183 MB -> 65.089 MB. [2025-02-27T12:12:17.815Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5234.423 ms) ====== [2025-02-27T12:12:17.815Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-27T12:12:17.815Z] GC before operation: completed in 340.044 ms, heap usage 268.161 MB -> 65.735 MB. [2025-02-27T12:12:24.876Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (6530.013 ms) ====== [2025-02-27T12:12:24.876Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-27T12:12:24.876Z] GC before operation: completed in 364.912 ms, heap usage 329.595 MB -> 65.797 MB. [2025-02-27T12:12:30.697Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (6014.665 ms) ====== [2025-02-27T12:12:30.697Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-27T12:12:30.697Z] GC before operation: completed in 249.418 ms, heap usage 310.623 MB -> 77.307 MB. [2025-02-27T12:12:36.510Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4937.039 ms) ====== [2025-02-27T12:12:36.510Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-27T12:12:36.510Z] GC before operation: completed in 299.910 ms, heap usage 182.410 MB -> 65.751 MB. [2025-02-27T12:12:42.253Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5801.507 ms) ====== [2025-02-27T12:12:42.253Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-27T12:12:42.253Z] GC before operation: completed in 304.697 ms, heap usage 316.969 MB -> 66.195 MB. [2025-02-27T12:12:48.070Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (5452.580 ms) ====== [2025-02-27T12:12:48.070Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-27T12:12:48.070Z] GC before operation: completed in 275.351 ms, heap usage 302.136 MB -> 66.330 MB. [2025-02-27T12:12:52.655Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (4883.866 ms) ====== [2025-02-27T12:12:52.655Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-27T12:12:53.455Z] GC before operation: completed in 239.481 ms, heap usage 302.560 MB -> 66.516 MB. [2025-02-27T12:12:56.985Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3820.740 ms) ====== [2025-02-27T12:12:56.985Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-27T12:12:56.985Z] GC before operation: completed in 290.671 ms, heap usage 315.498 MB -> 93.993 MB. [2025-02-27T12:12:56.985Z] 25/02/27 12:12:56 WARN BlockManager: Asked to remove block broadcast_162_piece0, which does not exist [2025-02-27T12:12:56.985Z] 25/02/27 12:12:56 WARN BlockManager: Asked to remove block broadcast_162, which does not exist [2025-02-27T12:13:01.602Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4206.526 ms) ====== [2025-02-27T12:13:01.602Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-27T12:13:01.602Z] GC before operation: completed in 176.618 ms, heap usage 326.502 MB -> 66.544 MB. [2025-02-27T12:13:05.163Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3718.765 ms) ====== [2025-02-27T12:13:05.163Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-27T12:13:05.966Z] GC before operation: completed in 234.522 ms, heap usage 174.645 MB -> 66.372 MB. [2025-02-27T12:13:08.515Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3217.577 ms) ====== [2025-02-27T12:13:08.515Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-27T12:13:09.426Z] GC before operation: completed in 235.370 ms, heap usage 307.788 MB -> 66.982 MB. [2025-02-27T12:13:13.016Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3934.631 ms) ====== [2025-02-27T12:13:13.016Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-27T12:13:13.016Z] GC before operation: completed in 356.669 ms, heap usage 143.695 MB -> 66.796 MB. [2025-02-27T12:13:17.625Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3800.483 ms) ====== [2025-02-27T12:13:17.625Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-27T12:13:17.625Z] GC before operation: completed in 202.019 ms, heap usage 223.701 MB -> 67.212 MB. [2025-02-27T12:13:21.664Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3635.208 ms) ====== [2025-02-27T12:13:21.664Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-27T12:13:21.664Z] GC before operation: completed in 226.889 ms, heap usage 307.513 MB -> 67.386 MB. [2025-02-27T12:13:24.252Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2998.770 ms) ====== [2025-02-27T12:13:24.252Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-27T12:13:24.252Z] GC before operation: completed in 211.608 ms, heap usage 304.738 MB -> 94.972 MB. [2025-02-27T12:13:27.805Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3107.159 ms) ====== [2025-02-27T12:13:27.805Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-27T12:13:27.805Z] GC before operation: completed in 355.242 ms, heap usage 470.211 MB -> 67.588 MB. [2025-02-27T12:13:32.380Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3823.766 ms) ====== [2025-02-27T12:13:32.380Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-27T12:13:32.380Z] GC before operation: completed in 247.737 ms, heap usage 281.465 MB -> 67.661 MB. [2025-02-27T12:13:34.916Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2975.743 ms) ====== [2025-02-27T12:13:34.916Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-27T12:13:34.916Z] GC before operation: completed in 245.806 ms, heap usage 157.414 MB -> 67.793 MB. [2025-02-27T12:13:38.441Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3057.778 ms) ====== [2025-02-27T12:13:38.441Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-27T12:13:38.441Z] GC before operation: completed in 238.173 ms, heap usage 217.416 MB -> 67.875 MB. [2025-02-27T12:13:41.961Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2980.449 ms) ====== [2025-02-27T12:13:41.961Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-27T12:13:41.961Z] GC before operation: completed in 211.031 ms, heap usage 253.594 MB -> 68.019 MB. [2025-02-27T12:13:44.507Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2743.450 ms) ====== [2025-02-27T12:13:44.507Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-27T12:13:44.507Z] GC before operation: completed in 210.323 ms, heap usage 378.373 MB -> 68.332 MB. [2025-02-27T12:13:48.029Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2901.076 ms) ====== [2025-02-27T12:13:48.029Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-27T12:13:48.029Z] GC before operation: completed in 298.279 ms, heap usage 262.203 MB -> 68.121 MB. [2025-02-27T12:13:51.544Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3130.751 ms) ====== [2025-02-27T12:13:51.544Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-27T12:13:51.544Z] GC before operation: completed in 258.515 ms, heap usage 337.886 MB -> 68.148 MB. [2025-02-27T12:13:54.113Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2856.091 ms) ====== [2025-02-27T12:13:54.113Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-27T12:13:54.113Z] GC before operation: completed in 279.866 ms, heap usage 106.549 MB -> 68.076 MB. [2025-02-27T12:13:57.634Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3112.087 ms) ====== [2025-02-27T12:13:57.634Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-27T12:13:57.634Z] GC before operation: completed in 189.443 ms, heap usage 436.749 MB -> 68.738 MB. [2025-02-27T12:14:00.176Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2412.606 ms) ====== [2025-02-27T12:14:00.176Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-27T12:14:00.176Z] GC before operation: completed in 281.301 ms, heap usage 102.617 MB25/02/27 12:13:59 WARN BlockManager: Asked to remove block rdd_1467_2, which does not exist [2025-02-27T12:14:00.176Z] 25/02/27 12:13:59 WARN BlockManager: Asked to remove block rdd_1467_1, which does not exist [2025-02-27T12:14:00.176Z] -> 84.613 MB. [2025-02-27T12:14:02.732Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2606.923 ms) ====== [2025-02-27T12:14:02.732Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-27T12:14:03.524Z] GC before operation: completed in 165.267 ms, heap usage 275.084 MB -> 68.674 MB. [2025-02-27T12:14:06.065Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2664.749 ms) ====== [2025-02-27T12:14:06.065Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-27T12:14:06.065Z] GC before operation: completed in 258.975 ms, heap usage 134.493 MB -> 68.365 MB. [2025-02-27T12:14:08.603Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2748.855 ms) ====== [2025-02-27T12:14:08.603Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-27T12:14:09.424Z] GC before operation: completed in 224.043 ms, heap usage 187.502 MB -> 96.348 MB. [2025-02-27T12:14:11.963Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2566.625 ms) ====== [2025-02-27T12:14:11.963Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-27T12:14:11.963Z] GC before operation: completed in 201.422 ms, heap usage 314.145 MB -> 68.863 MB. [2025-02-27T12:14:14.524Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2721.566 ms) ====== [2025-02-27T12:14:14.524Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-27T12:14:14.524Z] GC before operation: completed in 218.060 ms, heap usage 107.793 MB -> 68.391 MB. [2025-02-27T12:14:18.173Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2882.147 ms) ====== [2025-02-27T12:14:18.173Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-27T12:14:18.173Z] GC before operation: completed in 305.561 ms, heap usage 288.821 MB -> 85.755 MB. [2025-02-27T12:14:20.721Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2968.193 ms) ====== [2025-02-27T12:14:20.721Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-27T12:14:21.511Z] GC before operation: completed in 253.221 ms, heap usage 389.072 MB -> 69.331 MB. [2025-02-27T12:14:24.051Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2820.253 ms) ====== [2025-02-27T12:14:24.051Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-27T12:14:24.051Z] GC before operation: completed in 246.360 ms, heap usage 362.481 MB -> 96.833 MB. [2025-02-27T12:14:27.587Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2768.468 ms) ====== [2025-02-27T12:14:27.587Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-27T12:14:27.587Z] GC before operation: completed in 284.206 ms, heap usage 279.744 MB -> 69.403 MB. [2025-02-27T12:14:30.124Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3001.496 ms) ====== [2025-02-27T12:14:30.929Z] ----------------------------------- [2025-02-27T12:14:30.930Z] renaissance-dec-tree_0_PASSED [2025-02-27T12:14:30.930Z] ----------------------------------- [2025-02-27T12:14:30.930Z] [2025-02-27T12:14:30.930Z] TEST TEARDOWN: [2025-02-27T12:14:30.930Z] Nothing to be done for teardown. [2025-02-27T12:14:30.930Z] renaissance-dec-tree_0 Finish Time: Thu Feb 27 12:14:30 2025 Epoch Time (ms): 1740658470484