[2025-06-18T21:33:13.415Z] Running test renaissance-dec-tree_0 ... [2025-06-18T21:33:13.415Z] =============================================== [2025-06-18T21:33:13.415Z] renaissance-dec-tree_0 Start Time: Wed Jun 18 21:33:13 2025 Epoch Time (ms): 1750282393331 [2025-06-18T21:33:13.415Z] variation: NoOptions [2025-06-18T21:33:13.415Z] JVM_OPTIONS: [2025-06-18T21:33:13.415Z] { \ [2025-06-18T21:33:13.415Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T21:33:13.415Z] echo "Nothing to be done for setup."; \ [2025-06-18T21:33:13.415Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/renaissance-dec-tree_0"; \ [2025-06-18T21:33:13.415Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/renaissance-dec-tree_0"; \ [2025-06-18T21:33:13.415Z] echo ""; echo "TESTING:"; \ [2025-06-18T21:33:13.415Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-18T21:33:13.415Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T21:33:13.415Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T21:33:13.415Z] echo "Nothing to be done for teardown."; \ [2025-06-18T21:33:13.415Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/TestTargetResult"; [2025-06-18T21:33:13.415Z] [2025-06-18T21:33:13.415Z] TEST SETUP: [2025-06-18T21:33:13.415Z] Nothing to be done for setup. [2025-06-18T21:33:13.415Z] [2025-06-18T21:33:13.415Z] TESTING: [2025-06-18T21:33:22.615Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-18T21:33:25.128Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-18T21:33:25.799Z] GC before operation: completed in 130.148 ms, heap usage 73.909 MB -> 35.102 MB. [2025-06-18T21:33:43.395Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (17748.344 ms) ====== [2025-06-18T21:33:43.395Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-18T21:33:44.173Z] GC before operation: completed in 610.755 ms, heap usage 213.785 MB -> 68.085 MB. [2025-06-18T21:33:50.516Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (6777.297 ms) ====== [2025-06-18T21:33:50.516Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-18T21:33:51.231Z] GC before operation: completed in 667.628 ms, heap usage 249.816 MB -> 68.503 MB. [2025-06-18T21:33:55.564Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4503.412 ms) ====== [2025-06-18T21:33:55.564Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-18T21:33:56.246Z] GC before operation: completed in 171.028 ms, heap usage 231.901 MB -> 68.595 MB. [2025-06-18T21:34:00.276Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4085.282 ms) ====== [2025-06-18T21:34:00.276Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-18T21:34:00.276Z] GC before operation: completed in 384.078 ms, heap usage 273.508 MB -> 68.804 MB. [2025-06-18T21:34:04.394Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3264.586 ms) ====== [2025-06-18T21:34:04.394Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-18T21:34:04.394Z] GC before operation: completed in 171.533 ms, heap usage 316.858 MB -> 69.125 MB. [2025-06-18T21:34:09.470Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4033.656 ms) ====== [2025-06-18T21:34:09.470Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-18T21:34:09.470Z] GC before operation: completed in 123.403 ms, heap usage 159.153 MB -> 69.325 MB. [2025-06-18T21:34:12.629Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4792.736 ms) ====== [2025-06-18T21:34:12.629Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-18T21:34:13.289Z] GC before operation: completed in 216.334 ms, heap usage 267.284 MB -> 69.513 MB. [2025-06-18T21:34:17.402Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3680.077 ms) ====== [2025-06-18T21:34:17.402Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-18T21:34:17.402Z] GC before operation: completed in 215.253 ms, heap usage 278.112 MB -> 69.578 MB. [2025-06-18T21:34:21.551Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4091.171 ms) ====== [2025-06-18T21:34:21.551Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-18T21:34:21.551Z] GC before operation: completed in 238.573 ms, heap usage 219.070 MB -> 69.889 MB. [2025-06-18T21:34:24.660Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3535.557 ms) ====== [2025-06-18T21:34:24.660Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-18T21:34:25.376Z] GC before operation: completed in 277.671 ms, heap usage 328.966 MB -> 89.273 MB. [2025-06-18T21:34:28.720Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3158.039 ms) ====== [2025-06-18T21:34:28.720Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-18T21:34:28.720Z] GC before operation: completed in 542.028 ms, heap usage 273.963 MB -> 97.583 MB. [2025-06-18T21:34:38.777Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (9637.166 ms) ====== [2025-06-18T21:34:38.777Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-18T21:34:38.777Z] GC before operation: completed in 479.386 ms, heap usage 244.155 MB -> 70.104 MB. [2025-06-18T21:34:42.870Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4058.570 ms) ====== [2025-06-18T21:34:42.870Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-18T21:34:43.531Z] GC before operation: completed in 190.809 ms, heap usage 115.525 MB -> 69.550 MB. [2025-06-18T21:34:49.325Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (5645.282 ms) ====== [2025-06-18T21:34:49.325Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-18T21:34:49.325Z] GC before operation: completed in 292.159 ms, heap usage 245.633 MB -> 70.478 MB. [2025-06-18T21:34:54.883Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (5471.397 ms) ====== [2025-06-18T21:34:54.883Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-18T21:34:56.707Z] GC before operation: completed in 1907.399 ms, heap usage 226.129 MB -> 70.572 MB. [2025-06-18T21:35:01.329Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3594.996 ms) ====== [2025-06-18T21:35:01.329Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-18T21:35:01.329Z] GC before operation: completed in 267.740 ms, heap usage 143.048 MB -> 70.403 MB. [2025-06-18T21:35:03.801Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3523.509 ms) ====== [2025-06-18T21:35:03.801Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-18T21:35:04.480Z] GC before operation: completed in 396.455 ms, heap usage 300.143 MB -> 70.626 MB. [2025-06-18T21:35:07.561Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3244.928 ms) ====== [2025-06-18T21:35:07.561Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-18T21:35:08.255Z] GC before operation: completed in 263.839 ms, heap usage 341.626 MB -> 70.908 MB. [2025-06-18T21:35:11.466Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3599.854 ms) ====== [2025-06-18T21:35:11.466Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-18T21:35:11.466Z] GC before operation: completed in 275.530 ms, heap usage 180.467 MB -> 70.616 MB. [2025-06-18T21:35:16.706Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4167.832 ms) ====== [2025-06-18T21:35:16.706Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-18T21:35:16.706Z] GC before operation: completed in 459.540 ms, heap usage 314.242 MB -> 71.010 MB. [2025-06-18T21:35:23.372Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (6100.982 ms) ====== [2025-06-18T21:35:23.373Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-18T21:35:24.844Z] GC before operation: completed in 1931.938 ms, heap usage 293.283 MB -> 71.135 MB. [2025-06-18T21:35:29.373Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4565.117 ms) ====== [2025-06-18T21:35:29.373Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-18T21:35:29.373Z] GC before operation: completed in 338.990 ms, heap usage 104.720 MB -> 70.636 MB. [2025-06-18T21:35:32.462Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3221.562 ms) ====== [2025-06-18T21:35:32.462Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-18T21:35:33.178Z] GC before operation: completed in 384.921 ms, heap usage 218.792 MB -> 71.390 MB. [2025-06-18T21:35:37.455Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4400.179 ms) ====== [2025-06-18T21:35:37.455Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-18T21:35:37.455Z] GC before operation: completed in 436.150 ms, heap usage 308.890 MB -> 71.449 MB. [2025-06-18T21:35:42.928Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4453.577 ms) ====== [2025-06-18T21:35:42.928Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-18T21:35:42.928Z] GC before operation: completed in 328.961 ms, heap usage 171.147 MB -> 71.343 MB. [2025-06-18T21:35:48.265Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (5143.424 ms) ====== [2025-06-18T21:35:48.265Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-18T21:35:48.265Z] GC before operation: completed in 447.252 ms, heap usage 110.356 MB -> 71.441 MB. [2025-06-18T21:35:52.806Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4888.988 ms) ====== [2025-06-18T21:35:52.806Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-18T21:35:53.486Z] GC before operation: completed in 424.807 ms, heap usage 194.278 MB -> 72.159 MB. [2025-06-18T21:35:58.957Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4783.676 ms) ====== [2025-06-18T21:35:58.957Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-18T21:35:58.957Z] GC before operation: completed in 453.141 ms, heap usage 195.299 MB -> 71.878 MB. [2025-06-18T21:36:03.593Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4471.845 ms) ====== [2025-06-18T21:36:03.594Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-18T21:36:04.743Z] GC before operation: completed in 894.266 ms, heap usage 215.349 MB -> 99.616 MB. [2025-06-18T21:36:04.743Z] 21:36:04.183 WARN [block-manager-storage-async-thread-pool-68] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_405, which does not exist [2025-06-18T21:36:13.423Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (8383.749 ms) ====== [2025-06-18T21:36:13.423Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-18T21:36:13.423Z] GC before operation: completed in 187.314 ms, heap usage 322.825 MB -> 72.066 MB. [2025-06-18T21:36:15.722Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3173.042 ms) ====== [2025-06-18T21:36:15.722Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-18T21:36:16.396Z] GC before operation: completed in 556.255 ms, heap usage 304.066 MB -> 91.684 MB. [2025-06-18T21:36:20.734Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4532.302 ms) ====== [2025-06-18T21:36:20.734Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-18T21:36:21.400Z] GC before operation: completed in 600.157 ms, heap usage 246.221 MB -> 72.347 MB. [2025-06-18T21:36:25.406Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3386.525 ms) ====== [2025-06-18T21:36:25.406Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-18T21:36:25.407Z] GC before operation: completed in 298.221 ms, heap usage 118.940 MB -> 71.872 MB. [2025-06-18T21:36:28.559Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3344.655 ms) ====== [2025-06-18T21:36:28.559Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-18T21:36:28.559Z] GC before operation: completed in 252.766 ms, heap usage 200.173 MB -> 72.692 MB. [2025-06-18T21:36:32.954Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (4444.825 ms) ====== [2025-06-18T21:36:32.954Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-18T21:36:34.470Z] GC before operation: completed in 772.602 ms, heap usage 323.211 MB -> 72.710 MB. [2025-06-18T21:36:38.220Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4040.584 ms) ====== [2025-06-18T21:36:38.220Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-18T21:36:38.220Z] GC before operation: completed in 420.592 ms, heap usage 323.855 MB -> 72.860 MB. [2025-06-18T21:36:42.383Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3272.295 ms) ====== [2025-06-18T21:36:42.383Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-18T21:36:42.383Z] GC before operation: completed in 658.760 ms, heap usage 240.025 MB -> 72.806 MB. [2025-06-18T21:36:46.751Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4580.708 ms) ====== [2025-06-18T21:36:46.751Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-18T21:36:47.678Z] GC before operation: completed in 664.918 ms, heap usage 119.963 MB -> 73.004 MB. [2025-06-18T21:36:51.931Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4543.109 ms) ====== [2025-06-18T21:36:51.931Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-18T21:36:52.591Z] GC before operation: completed in 250.566 ms, heap usage 302.595 MB -> 100.615 MB. [2025-06-18T21:36:55.839Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2972.359 ms) ====== [2025-06-18T21:36:55.839Z] ----------------------------------- [2025-06-18T21:36:55.839Z] renaissance-dec-tree_0_PASSED [2025-06-18T21:36:55.839Z] ----------------------------------- [2025-06-18T21:36:55.839Z] [2025-06-18T21:36:55.839Z] TEST TEARDOWN: [2025-06-18T21:36:55.839Z] Nothing to be done for teardown. [2025-06-18T21:36:55.839Z] renaissance-dec-tree_0 Finish Time: Wed Jun 18 21:36:55 2025 Epoch Time (ms): 1750282615627