[2025-03-27T05:21:48.538Z] Running test renaissance-dec-tree_0 ... [2025-03-27T05:21:48.538Z] =============================================== [2025-03-27T05:21:48.538Z] renaissance-dec-tree_0 Start Time: Thu Mar 27 05:21:48 2025 Epoch Time (ms): 1743052908068 [2025-03-27T05:21:48.538Z] variation: NoOptions [2025-03-27T05:21:48.538Z] JVM_OPTIONS: [2025-03-27T05:21:48.538Z] { \ [2025-03-27T05:21:48.538Z] echo ""; echo "TEST SETUP:"; \ [2025-03-27T05:21:48.538Z] echo "Nothing to be done for setup."; \ [2025-03-27T05:21:48.538Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-dec-tree_0"; \ [2025-03-27T05:21:48.538Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-dec-tree_0"; \ [2025-03-27T05:21:48.538Z] echo ""; echo "TESTING:"; \ [2025-03-27T05:21:48.538Z] "/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_17430516197944/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-03-27T05:21:48.538Z] 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_17430516197944/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-27T05:21:48.538Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-27T05:21:48.538Z] echo "Nothing to be done for teardown."; \ [2025-03-27T05:21:48.538Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/TestTargetResult"; [2025-03-27T05:21:48.538Z] [2025-03-27T05:21:48.538Z] TEST SETUP: [2025-03-27T05:21:48.538Z] Nothing to be done for setup. [2025-03-27T05:21:48.538Z] [2025-03-27T05:21:48.538Z] TESTING: [2025-03-27T05:21:56.330Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-03-27T05:22:01.549Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-27T05:22:01.549Z] GC before operation: completed in 70.073 ms, heap usage 63.521 MB -> 34.809 MB. [2025-03-27T05:22:28.032Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (25670.314 ms) ====== [2025-03-27T05:22:28.032Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-27T05:22:28.032Z] GC before operation: completed in 213.812 ms, heap usage 301.287 MB -> 67.795 MB. [2025-03-27T05:22:31.969Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (5049.975 ms) ====== [2025-03-27T05:22:31.969Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-27T05:22:32.633Z] GC before operation: completed in 254.046 ms, heap usage 253.193 MB -> 68.282 MB. [2025-03-27T05:22:39.710Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6669.968 ms) ====== [2025-03-27T05:22:39.710Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-27T05:22:39.710Z] GC before operation: completed in 139.351 ms, heap usage 229.465 MB -> 68.407 MB. [2025-03-27T05:22:42.621Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3103.288 ms) ====== [2025-03-27T05:22:42.621Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-27T05:22:42.621Z] GC before operation: completed in 115.006 ms, heap usage 239.262 MB -> 77.281 MB. [2025-03-27T05:22:48.545Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5562.237 ms) ====== [2025-03-27T05:22:48.545Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-27T05:22:48.545Z] GC before operation: completed in 279.189 ms, heap usage 299.910 MB -> 68.969 MB. [2025-03-27T05:22:53.226Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (5083.278 ms) ====== [2025-03-27T05:22:53.226Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-27T05:22:53.226Z] GC before operation: completed in 242.244 ms, heap usage 244.260 MB -> 69.020 MB. [2025-03-27T05:22:59.563Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5865.180 ms) ====== [2025-03-27T05:22:59.563Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-27T05:22:59.563Z] GC before operation: completed in 136.924 ms, heap usage 248.501 MB -> 69.429 MB. [2025-03-27T05:23:03.945Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4590.794 ms) ====== [2025-03-27T05:23:03.945Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-27T05:23:04.625Z] GC before operation: completed in 171.128 ms, heap usage 313.546 MB -> 69.406 MB. [2025-03-27T05:23:09.033Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4451.058 ms) ====== [2025-03-27T05:23:09.033Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-27T05:23:09.033Z] GC before operation: completed in 173.240 ms, heap usage 297.104 MB -> 69.690 MB. [2025-03-27T05:23:12.141Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2790.862 ms) ====== [2025-03-27T05:23:12.141Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-27T05:23:12.141Z] GC before operation: completed in 166.759 ms, heap usage 224.526 MB -> 70.021 MB. [2025-03-27T05:23:15.159Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3180.938 ms) ====== [2025-03-27T05:23:15.159Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-27T05:23:15.159Z] GC before operation: completed in 335.821 ms, heap usage 210.524 MB -> 69.815 MB. [2025-03-27T05:23:20.256Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4509.032 ms) ====== [2025-03-27T05:23:20.256Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-27T05:23:20.256Z] GC before operation: completed in 546.899 ms, heap usage 110.825 MB -> 69.945 MB. [2025-03-27T05:23:25.367Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4244.323 ms) ====== [2025-03-27T05:23:25.367Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-27T05:23:25.367Z] GC before operation: completed in 165.788 ms, heap usage 170.484 MB -> 69.864 MB. [2025-03-27T05:23:32.022Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (6400.688 ms) ====== [2025-03-27T05:23:32.022Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-27T05:23:32.022Z] GC before operation: completed in 363.108 ms, heap usage 311.929 MB -> 97.891 MB. [2025-03-27T05:23:35.112Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3718.645 ms) ====== [2025-03-27T05:23:35.112Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-27T05:23:35.802Z] GC before operation: completed in 280.478 ms, heap usage 259.525 MB -> 70.663 MB. [2025-03-27T05:23:38.986Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3541.402 ms) ====== [2025-03-27T05:23:38.986Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-27T05:23:39.696Z] GC before operation: completed in 373.623 ms, heap usage 172.663 MB -> 97.765 MB. [2025-03-27T05:23:44.022Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4040.604 ms) ====== [2025-03-27T05:23:44.022Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-27T05:23:44.022Z] GC before operation: completed in 223.185 ms, heap usage 439.249 MB -> 71.002 MB. [2025-03-27T05:23:51.950Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (7736.643 ms) ====== [2025-03-27T05:23:51.950Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-27T05:23:52.653Z] GC before operation: completed in 830.960 ms, heap usage 232.895 MB -> 98.442 MB. [2025-03-27T05:23:58.103Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (5023.963 ms) ====== [2025-03-27T05:23:58.103Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-27T05:23:58.103Z] GC before operation: completed in 313.282 ms, heap usage 256.748 MB -> 70.806 MB. [2025-03-27T05:24:00.516Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2786.502 ms) ====== [2025-03-27T05:24:00.516Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-27T05:24:00.516Z] GC before operation: completed in 294.263 ms, heap usage 272.601 MB -> 98.493 MB. [2025-03-27T05:24:04.545Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3920.645 ms) ====== [2025-03-27T05:24:04.545Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-27T05:24:05.363Z] GC before operation: completed in 391.980 ms, heap usage 255.632 MB -> 70.949 MB. [2025-03-27T05:24:10.612Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4994.710 ms) ====== [2025-03-27T05:24:10.612Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-27T05:24:10.612Z] GC before operation: completed in 702.983 ms, heap usage 166.566 MB -> 71.192 MB. [2025-03-27T05:24:16.493Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (4885.857 ms) ====== [2025-03-27T05:24:16.493Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-27T05:24:16.493Z] GC before operation: completed in 339.627 ms, heap usage 303.294 MB -> 71.343 MB. [2025-03-27T05:24:18.804Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2682.908 ms) ====== [2025-03-27T05:24:18.804Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-27T05:24:18.804Z] GC before operation: completed in 371.819 ms, heap usage 168.843 MB -> 70.979 MB. [2025-03-27T05:24:21.903Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3101.820 ms) ====== [2025-03-27T05:24:21.903Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-27T05:24:22.568Z] GC before operation: completed in 172.984 ms, heap usage 290.582 MB -> 71.777 MB. [2025-03-27T05:24:25.668Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3216.296 ms) ====== [2025-03-27T05:24:25.668Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-27T05:24:25.668Z] GC before operation: completed in 343.659 ms, heap usage 226.829 MB -> 99.252 MB. [2025-03-27T05:24:28.633Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2967.995 ms) ====== [2025-03-27T05:24:28.633Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-27T05:24:29.387Z] GC before operation: completed in 203.474 ms, heap usage 249.899 MB -> 71.791 MB. [2025-03-27T05:24:33.732Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4145.991 ms) ====== [2025-03-27T05:24:33.732Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-27T05:24:33.732Z] GC before operation: completed in 516.528 ms, heap usage 222.004 MB -> 71.664 MB. [2025-03-27T05:24:38.126Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4088.490 ms) ====== [2025-03-27T05:24:38.126Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-27T05:24:38.126Z] GC before operation: completed in 250.237 ms, heap usage 356.210 MB -> 71.787 MB. [2025-03-27T05:24:41.162Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2562.065 ms) ====== [2025-03-27T05:24:41.162Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-27T05:24:41.162Z] GC before operation: completed in 197.386 ms, heap usage 199.711 MB -> 99.562 MB. [2025-03-27T05:24:43.578Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2206.252 ms) ====== [2025-03-27T05:24:43.578Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-27T05:24:44.693Z] GC before operation: completed in 1113.630 ms, heap usage 252.562 MB -> 72.181 MB. [2025-03-27T05:24:49.245Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (5325.164 ms) ====== [2025-03-27T05:24:49.245Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-27T05:24:49.943Z] GC before operation: completed in 362.383 ms, heap usage 286.467 MB -> 72.528 MB. [2025-03-27T05:24:54.595Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (4415.910 ms) ====== [2025-03-27T05:24:54.595Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-27T05:24:55.374Z] GC before operation: completed in 684.769 ms, heap usage 177.067 MB -> 99.682 MB. [2025-03-27T05:25:00.933Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (5523.180 ms) ====== [2025-03-27T05:25:00.933Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-27T05:25:00.933Z] GC before operation: completed in 456.052 ms, heap usage 356.360 MB -> 72.498 MB. [2025-03-27T05:25:05.163Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (4312.661 ms) ====== [2025-03-27T05:25:05.163Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-27T05:25:05.837Z] GC before operation: completed in 549.139 ms, heap usage 235.468 MB -> 72.503 MB. [2025-03-27T05:25:08.878Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2632.175 ms) ====== [2025-03-27T05:25:08.878Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-27T05:25:08.878Z] GC before operation: completed in 222.376 ms, heap usage 322.466 MB -> 72.887 MB. [2025-03-27T05:25:08.878Z] 05:25:08.288 WARN [block-manager-storage-async-thread-pool-57] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_499, which does not exist [2025-03-27T05:25:11.451Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2750.677 ms) ====== [2025-03-27T05:25:11.451Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-27T05:25:12.769Z] GC before operation: completed in 1248.517 ms, heap usage 351.924 MB -> 100.326 MB. [2025-03-27T05:25:21.810Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (8931.498 ms) ====== [2025-03-27T05:25:21.810Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-27T05:25:21.810Z] GC before operation: completed in 212.951 ms, heap usage 369.966 MB -> 81.379 MB. [2025-03-27T05:25:26.149Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4347.950 ms) ====== [2025-03-27T05:25:26.149Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-27T05:25:26.940Z] GC before operation: completed in 933.556 ms, heap usage 134.864 MB -> 99.831 MB. [2025-03-27T05:25:31.320Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4237.164 ms) ====== [2025-03-27T05:25:31.990Z] ----------------------------------- [2025-03-27T05:25:31.990Z] renaissance-dec-tree_0_PASSED [2025-03-27T05:25:31.990Z] ----------------------------------- [2025-03-27T05:25:31.990Z] [2025-03-27T05:25:31.990Z] TEST TEARDOWN: [2025-03-27T05:25:31.990Z] Nothing to be done for teardown. [2025-03-27T05:25:31.990Z] renaissance-dec-tree_0 Finish Time: Thu Mar 27 05:25:31 2025 Epoch Time (ms): 1743053131637