[2024-12-05T07:38:52.529Z] Running test renaissance-dec-tree_0 ... [2024-12-05T07:38:52.529Z] =============================================== [2024-12-05T07:38:52.529Z] renaissance-dec-tree_0 Start Time: Thu Dec 5 07:38:52 2024 Epoch Time (ms): 1733384332112 [2024-12-05T07:38:52.529Z] variation: NoOptions [2024-12-05T07:38:52.529Z] JVM_OPTIONS: [2024-12-05T07:38:52.529Z] { \ [2024-12-05T07:38:52.529Z] echo ""; echo "TEST SETUP:"; \ [2024-12-05T07:38:52.529Z] echo "Nothing to be done for setup."; \ [2024-12-05T07:38:52.529Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/renaissance-dec-tree_0"; \ [2024-12-05T07:38:52.529Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/renaissance-dec-tree_0"; \ [2024-12-05T07:38:52.529Z] echo ""; echo "TESTING:"; \ [2024-12-05T07:38:52.529Z] "/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_17333831718225/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-12-05T07:38:52.529Z] 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_17333831718225/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-05T07:38:52.529Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-05T07:38:52.529Z] echo "Nothing to be done for teardown."; \ [2024-12-05T07:38:52.529Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17333831718225/TestTargetResult"; [2024-12-05T07:38:52.529Z] [2024-12-05T07:38:52.529Z] TEST SETUP: [2024-12-05T07:38:52.529Z] Nothing to be done for setup. [2024-12-05T07:38:52.529Z] [2024-12-05T07:38:52.529Z] TESTING: [2024-12-05T07:38:56.585Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-05T07:38:58.178Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-12-05T07:39:02.250Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-12-05T07:39:02.250Z] GC before operation: completed in 71.218 ms, heap usage 60.500 MB -> 35.036 MB. [2024-12-05T07:39:17.860Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (16231.561 ms) ====== [2024-12-05T07:39:17.860Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-12-05T07:39:18.699Z] GC before operation: completed in 456.736 ms, heap usage 166.220 MB -> 63.054 MB. [2024-12-05T07:39:25.147Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (6543.851 ms) ====== [2024-12-05T07:39:25.147Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-12-05T07:39:25.147Z] GC before operation: completed in 264.396 ms, heap usage 100.555 MB -> 63.470 MB. [2024-12-05T07:39:30.413Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4790.804 ms) ====== [2024-12-05T07:39:30.413Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-12-05T07:39:31.176Z] GC before operation: completed in 806.904 ms, heap usage 204.138 MB -> 64.155 MB. [2024-12-05T07:39:35.242Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4458.006 ms) ====== [2024-12-05T07:39:35.242Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-12-05T07:39:35.938Z] GC before operation: completed in 338.025 ms, heap usage 124.457 MB -> 91.760 MB. [2024-12-05T07:39:35.938Z] 24/12/05 07:39:35 WARN BlockManager: Asked to remove block broadcast_50, which does not exist [2024-12-05T07:39:41.260Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5375.321 ms) ====== [2024-12-05T07:39:41.260Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-12-05T07:39:41.260Z] GC before operation: completed in 295.894 ms, heap usage 379.331 MB -> 91.993 MB. [2024-12-05T07:39:46.386Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4196.967 ms) ====== [2024-12-05T07:39:46.387Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-12-05T07:39:46.387Z] GC before operation: completed in 336.814 ms, heap usage 164.471 MB -> 64.449 MB. [2024-12-05T07:39:50.429Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3894.851 ms) ====== [2024-12-05T07:39:50.429Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-12-05T07:39:50.429Z] GC before operation: completed in 264.826 ms, heap usage 175.578 MB -> 73.239 MB. [2024-12-05T07:39:55.554Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4268.902 ms) ====== [2024-12-05T07:39:55.554Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-12-05T07:39:55.554Z] GC before operation: completed in 180.717 ms, heap usage 199.072 MB -> 92.653 MB. [2024-12-05T07:39:55.554Z] 24/12/05 07:39:54 WARN BlockManager: Asked to remove block broadcast_106_piece0, which does not exist [2024-12-05T07:39:55.554Z] 24/12/05 07:39:54 WARN BlockManager: Asked to remove block broadcast_106, which does not exist [2024-12-05T07:39:57.916Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3440.417 ms) ====== [2024-12-05T07:39:57.916Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-12-05T07:39:58.647Z] GC before operation: completed in 369.268 ms, heap usage 256.463 MB -> 65.129 MB. [2024-12-05T07:40:03.171Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4112.592 ms) ====== [2024-12-05T07:40:03.171Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-12-05T07:40:03.171Z] GC before operation: completed in 323.306 ms, heap usage 274.468 MB -> 92.840 MB. [2024-12-05T07:40:07.227Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (4152.354 ms) ====== [2024-12-05T07:40:07.227Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-12-05T07:40:07.227Z] GC before operation: completed in 369.020 ms, heap usage 285.033 MB -> 65.331 MB. [2024-12-05T07:40:10.432Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3114.450 ms) ====== [2024-12-05T07:40:10.432Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-12-05T07:40:11.072Z] GC before operation: completed in 271.720 ms, heap usage 266.787 MB -> 65.415 MB. [2024-12-05T07:40:15.177Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3931.685 ms) ====== [2024-12-05T07:40:15.177Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-12-05T07:40:15.177Z] GC before operation: completed in 273.205 ms, heap usage 198.994 MB -> 65.563 MB. [2024-12-05T07:40:19.266Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4122.716 ms) ====== [2024-12-05T07:40:19.266Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-12-05T07:40:19.266Z] GC before operation: completed in 195.405 ms, heap usage 255.556 MB -> 65.698 MB. [2024-12-05T07:40:22.374Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3105.017 ms) ====== [2024-12-05T07:40:22.374Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-12-05T07:40:22.374Z] GC before operation: completed in 255.500 ms, heap usage 277.100 MB -> 66.124 MB. [2024-12-05T07:40:25.508Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2821.728 ms) ====== [2024-12-05T07:40:25.508Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-12-05T07:40:25.508Z] GC before operation: completed in 206.249 ms, heap usage 158.844 MB -> 65.791 MB. [2024-12-05T07:40:29.558Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3662.758 ms) ====== [2024-12-05T07:40:29.558Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-12-05T07:40:29.558Z] GC before operation: completed in 210.060 ms, heap usage 278.829 MB -> 66.006 MB. [2024-12-05T07:40:32.681Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2816.588 ms) ====== [2024-12-05T07:40:32.681Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-12-05T07:40:32.681Z] GC before operation: completed in 313.355 ms, heap usage 267.580 MB -> 66.146 MB. [2024-12-05T07:40:36.930Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4354.769 ms) ====== [2024-12-05T07:40:36.930Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-12-05T07:40:37.677Z] GC before operation: completed in 238.270 ms, heap usage 253.323 MB -> 66.235 MB. [2024-12-05T07:40:40.759Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3042.649 ms) ====== [2024-12-05T07:40:40.759Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-12-05T07:40:40.759Z] GC before operation: completed in 281.863 ms, heap usage 108.543 MB -> 65.822 MB. [2024-12-05T07:40:44.925Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3619.238 ms) ====== [2024-12-05T07:40:44.925Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-12-05T07:40:44.925Z] GC before operation: completed in 219.182 ms, heap usage 214.211 MB -> 66.667 MB. [2024-12-05T07:40:48.925Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4289.916 ms) ====== [2024-12-05T07:40:48.925Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-12-05T07:40:48.925Z] GC before operation: completed in 227.721 ms, heap usage 237.476 MB -> 66.934 MB. [2024-12-05T07:40:52.104Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2853.930 ms) ====== [2024-12-05T07:40:52.104Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-12-05T07:40:52.104Z] GC before operation: completed in 246.065 ms, heap usage 224.340 MB -> 94.363 MB. [2024-12-05T07:40:55.180Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3035.326 ms) ====== [2024-12-05T07:40:55.180Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-12-05T07:40:55.180Z] GC before operation: completed in 256.924 ms, heap usage 136.884 MB -> 66.505 MB. [2024-12-05T07:40:58.345Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2820.688 ms) ====== [2024-12-05T07:40:58.345Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-12-05T07:40:58.345Z] GC before operation: completed in 247.702 ms, heap usage 237.981 MB -> 67.252 MB. [2024-12-05T07:41:01.891Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3504.393 ms) ====== [2024-12-05T07:41:01.891Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-12-05T07:41:01.891Z] GC before operation: completed in 229.084 ms, heap usage 235.678 MB -> 86.537 MB. [2024-12-05T07:41:06.527Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3896.326 ms) ====== [2024-12-05T07:41:06.527Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-12-05T07:41:06.527Z] GC before operation: completed in 448.080 ms, heap usage 203.969 MB -> 94.871 MB. [2024-12-05T07:41:12.276Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (5212.525 ms) ====== [2024-12-05T07:41:12.276Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-12-05T07:41:12.276Z] GC before operation: completed in 553.510 ms, heap usage 154.857 MB -> 67.140 MB. [2024-12-05T07:41:15.474Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3119.553 ms) ====== [2024-12-05T07:41:15.474Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-12-05T07:41:15.474Z] GC before operation: completed in 318.668 ms, heap usage 237.266 MB -> 67.641 MB. [2024-12-05T07:41:19.785Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3731.310 ms) ====== [2024-12-05T07:41:19.785Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-12-05T07:41:19.785Z] GC before operation: completed in 350.278 ms, heap usage 202.421 MB -> 67.508 MB. [2024-12-05T07:41:23.143Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3084.068 ms) ====== [2024-12-05T07:41:23.143Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-12-05T07:41:23.143Z] GC before operation: completed in 208.676 ms, heap usage 241.059 MB -> 95.194 MB. [2024-12-05T07:41:27.294Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3763.698 ms) ====== [2024-12-05T07:41:27.294Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-12-05T07:41:27.294Z] GC before operation: completed in 315.658 ms, heap usage 205.049 MB -> 95.316 MB. [2024-12-05T07:41:31.486Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (4384.476 ms) ====== [2024-12-05T07:41:31.486Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-12-05T07:41:32.177Z] GC before operation: completed in 332.298 ms, heap usage 294.542 MB -> 67.752 MB. [2024-12-05T07:41:35.247Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3080.657 ms) ====== [2024-12-05T07:41:35.247Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-12-05T07:41:35.247Z] GC before operation: completed in 267.339 ms, heap usage 145.548 MB -> 67.521 MB. [2024-12-05T07:41:37.903Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2906.783 ms) ====== [2024-12-05T07:41:37.903Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-12-05T07:41:38.693Z] GC before operation: completed in 204.626 ms, heap usage 117.995 MB -> 67.361 MB. [2024-12-05T07:41:42.944Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4219.835 ms) ====== [2024-12-05T07:41:42.944Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-12-05T07:41:42.944Z] GC before operation: completed in 197.534 ms, heap usage 353.173 MB -> 67.940 MB. [2024-12-05T07:41:46.156Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3545.486 ms) ====== [2024-12-05T07:41:46.156Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-12-05T07:42:04.686Z] GC before operation: completed in 294.572 ms, heap usage 318.238 MB -> 68.366 MB. [2024-12-05T07:42:04.686Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2739.748 ms) ====== [2024-12-05T07:42:04.686Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-12-05T07:42:04.686Z] GC before operation: completed in 246.275 ms, heap usage 99.303 MB -> 67.698 MB. [2024-12-05T07:42:04.686Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3070.954 ms) ====== [2024-12-05T07:42:04.686Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-12-05T07:42:04.686Z] GC before operation: completed in 661.248 ms, heap usage 112.475 MB -> 67.734 MB. [2024-12-05T07:42:04.687Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3377.553 ms) ====== [2024-12-05T07:42:04.687Z] ----------------------------------- [2024-12-05T07:42:04.687Z] renaissance-dec-tree_0_PASSED [2024-12-05T07:42:04.687Z] ----------------------------------- [2024-12-05T07:42:04.687Z] [2024-12-05T07:42:04.687Z] TEST TEARDOWN: [2024-12-05T07:42:04.687Z] Nothing to be done for teardown. [2024-12-05T07:42:04.687Z] renaissance-dec-tree_0 Finish Time: Thu Dec 5 07:41:56 2024 Epoch Time (ms): 1733384516883