[2024-08-23T22:21:46.046Z] Running test renaissance-dec-tree_0 ... [2024-08-23T22:21:46.046Z] =============================================== [2024-08-23T22:21:46.046Z] renaissance-dec-tree_0 Start Time: Fri Aug 23 18:21:45 2024 Epoch Time (ms): 1724451705676 [2024-08-23T22:21:46.046Z] variation: NoOptions [2024-08-23T22:21:46.046Z] JVM_OPTIONS: [2024-08-23T22:21:46.046Z] { \ [2024-08-23T22:21:46.046Z] echo ""; echo "TEST SETUP:"; \ [2024-08-23T22:21:46.046Z] echo "Nothing to be done for setup."; \ [2024-08-23T22:21:46.046Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17244503772563/renaissance-dec-tree_0"; \ [2024-08-23T22:21:46.046Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17244503772563/renaissance-dec-tree_0"; \ [2024-08-23T22:21:46.046Z] echo ""; echo "TESTING:"; \ [2024-08-23T22:21:46.046Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17244503772563/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-23T22:21:46.046Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17244503772563/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-23T22:21:46.046Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-23T22:21:46.046Z] echo "Nothing to be done for teardown."; \ [2024-08-23T22:21:46.046Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17244503772563/TestTargetResult"; [2024-08-23T22:21:46.046Z] [2024-08-23T22:21:46.046Z] TEST SETUP: [2024-08-23T22:21:46.046Z] Nothing to be done for setup. [2024-08-23T22:21:46.046Z] [2024-08-23T22:21:46.046Z] TESTING: [2024-08-23T22:21:50.876Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-23T22:21:53.793Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-23T22:21:55.980Z] WARNING: An illegal reflective access operation has occurred [2024-08-23T22:21:55.981Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17244503772563/renaissance-dec-tree_0/harness-182147-15775305624933202497/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-23T22:21:55.981Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-23T22:21:55.981Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-23T22:21:55.981Z] WARNING: All illegal access operations will be denied in a future release [2024-08-23T22:21:59.106Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-23T22:21:59.106Z] GC before operation: completed in 255.772 ms, heap usage 54.116 MB -> 33.737 MB. [2024-08-23T22:22:20.486Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (19783.755 ms) ====== [2024-08-23T22:22:20.486Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-23T22:22:20.486Z] GC before operation: completed in 614.696 ms, heap usage 93.884 MB -> 62.315 MB. [2024-08-23T22:22:26.956Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7327.962 ms) ====== [2024-08-23T22:22:26.956Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-23T22:22:26.956Z] GC before operation: completed in 273.476 ms, heap usage 198.835 MB -> 90.507 MB. [2024-08-23T22:22:31.957Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4848.544 ms) ====== [2024-08-23T22:22:31.957Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-23T22:22:31.957Z] GC before operation: completed in 277.705 ms, heap usage 141.390 MB -> 90.767 MB. [2024-08-23T22:22:36.054Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3628.152 ms) ====== [2024-08-23T22:22:36.054Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-23T22:22:36.054Z] GC before operation: completed in 202.681 ms, heap usage 275.197 MB -> 63.561 MB. [2024-08-23T22:22:39.010Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3129.328 ms) ====== [2024-08-23T22:22:39.010Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-23T22:22:39.713Z] GC before operation: completed in 362.788 ms, heap usage 215.468 MB -> 63.745 MB. [2024-08-23T22:22:42.756Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3285.902 ms) ====== [2024-08-23T22:22:42.756Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-23T22:22:43.400Z] GC before operation: completed in 364.907 ms, heap usage 267.709 MB -> 63.793 MB. [2024-08-23T22:22:47.314Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3677.662 ms) ====== [2024-08-23T22:22:47.314Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-23T22:22:47.314Z] GC before operation: completed in 197.527 ms, heap usage 137.162 MB -> 63.963 MB. [2024-08-23T22:22:51.225Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4295.356 ms) ====== [2024-08-23T22:22:51.225Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-23T22:22:51.947Z] GC before operation: completed in 271.736 ms, heap usage 103.773 MB -> 63.742 MB. [2024-08-23T22:22:57.088Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5007.782 ms) ====== [2024-08-23T22:22:57.088Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-23T22:22:57.088Z] GC before operation: completed in 208.328 ms, heap usage 188.570 MB -> 64.410 MB. [2024-08-23T22:23:01.074Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3675.506 ms) ====== [2024-08-23T22:23:01.074Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-23T22:23:01.074Z] GC before operation: completed in 395.833 ms, heap usage 108.951 MB -> 64.076 MB. [2024-08-23T22:23:05.149Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3679.423 ms) ====== [2024-08-23T22:23:05.149Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-23T22:23:05.149Z] GC before operation: completed in 333.430 ms, heap usage 182.643 MB -> 64.467 MB. [2024-08-23T22:23:08.095Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3004.124 ms) ====== [2024-08-23T22:23:08.095Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-23T22:23:08.095Z] GC before operation: completed in 430.713 ms, heap usage 188.536 MB -> 64.568 MB. [2024-08-23T22:23:11.942Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3186.015 ms) ====== [2024-08-23T22:23:11.942Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-23T22:23:11.942Z] GC before operation: completed in 355.127 ms, heap usage 113.054 MB -> 64.391 MB. [2024-08-23T22:23:14.916Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2824.606 ms) ====== [2024-08-23T22:23:14.916Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-23T22:23:14.916Z] GC before operation: completed in 236.037 ms, heap usage 190.249 MB -> 64.920 MB. [2024-08-23T22:23:17.934Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3114.644 ms) ====== [2024-08-23T22:23:17.934Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-23T22:23:18.575Z] GC before operation: completed in 263.641 ms, heap usage 188.759 MB -> 64.882 MB. [2024-08-23T22:23:21.047Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2949.668 ms) ====== [2024-08-23T22:23:21.047Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-23T22:23:21.660Z] GC before operation: completed in 253.394 ms, heap usage 140.339 MB -> 65.022 MB. [2024-08-23T22:23:24.612Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2760.360 ms) ====== [2024-08-23T22:23:24.612Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-23T22:23:24.612Z] GC before operation: completed in 275.080 ms, heap usage 200.308 MB -> 65.292 MB. [2024-08-23T22:23:27.528Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2560.936 ms) ====== [2024-08-23T22:23:27.528Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-23T22:23:27.528Z] GC before operation: completed in 418.822 ms, heap usage 165.657 MB -> 65.255 MB. [2024-08-23T22:23:30.426Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2754.362 ms) ====== [2024-08-23T22:23:30.426Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-23T22:23:30.426Z] GC before operation: completed in 297.486 ms, heap usage 198.866 MB -> 92.977 MB. [2024-08-23T22:23:33.405Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2723.530 ms) ====== [2024-08-23T22:23:33.405Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-23T22:23:33.405Z] GC before operation: completed in 228.596 ms, heap usage 259.843 MB -> 65.827 MB. [2024-08-23T22:23:36.448Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2570.682 ms) ====== [2024-08-23T22:23:36.448Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-23T22:23:36.448Z] GC before operation: completed in 267.751 ms, heap usage 184.558 MB -> 65.560 MB. [2024-08-23T22:23:39.371Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2823.620 ms) ====== [2024-08-23T22:23:39.371Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-23T22:23:40.022Z] GC before operation: completed in 473.438 ms, heap usage 183.362 MB -> 65.646 MB. [2024-08-23T22:23:42.143Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2591.285 ms) ====== [2024-08-23T22:23:42.143Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-23T22:23:42.884Z] GC before operation: completed in 293.250 ms, heap usage 179.028 MB -> 66.140 MB. [2024-08-23T22:23:45.870Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3153.740 ms) ====== [2024-08-23T22:23:45.870Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-23T22:23:45.870Z] GC before operation: completed in 265.838 ms, heap usage 99.736 MB -> 65.708 MB. [2024-08-23T22:23:49.048Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3071.302 ms) ====== [2024-08-23T22:23:49.048Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-23T22:23:49.705Z] GC before operation: completed in 370.719 ms, heap usage 102.522 MB -> 65.794 MB. [2024-08-23T22:23:52.675Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3320.452 ms) ====== [2024-08-23T22:23:52.675Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-23T22:23:53.284Z] GC before operation: completed in 559.084 ms, heap usage 241.826 MB -> 66.026 MB. [2024-08-23T22:23:57.254Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3670.777 ms) ====== [2024-08-23T22:23:57.254Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-23T22:23:57.254Z] GC before operation: completed in 445.158 ms, heap usage 228.248 MB -> 66.122 MB. [2024-08-23T22:24:01.669Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3964.829 ms) ====== [2024-08-23T22:24:01.669Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-23T22:24:02.322Z] GC before operation: completed in 581.035 ms, heap usage 208.200 MB -> 66.204 MB. [2024-08-23T22:24:07.121Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4621.204 ms) ====== [2024-08-23T22:24:07.121Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-23T22:24:07.983Z] GC before operation: completed in 1321.561 ms, heap usage 284.026 MB -> 66.301 MB. [2024-08-23T22:24:13.769Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (6065.664 ms) ====== [2024-08-23T22:24:13.769Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-23T22:24:14.462Z] GC before operation: completed in 507.116 ms, heap usage 105.903 MB -> 66.260 MB. [2024-08-23T22:24:18.524Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3681.826 ms) ====== [2024-08-23T22:24:18.525Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-23T22:24:18.525Z] GC before operation: completed in 339.732 ms, heap usage 209.977 MB -> 66.486 MB. [2024-08-23T22:24:25.733Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (7196.919 ms) ====== [2024-08-23T22:24:25.733Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-23T22:24:26.413Z] GC before operation: completed in 505.383 ms, heap usage 104.600 MB -> 66.026 MB. [2024-08-23T22:24:31.171Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (4664.837 ms) ====== [2024-08-23T22:24:31.171Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-23T22:24:32.012Z] GC before operation: completed in 621.848 ms, heap usage 270.502 MB -> 66.671 MB. [2024-08-23T22:24:36.394Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4236.674 ms) ====== [2024-08-23T22:24:36.394Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-23T22:24:36.394Z] GC before operation: completed in 452.379 ms, heap usage 112.772 MB -> 66.512 MB. [2024-08-23T22:24:39.534Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3487.818 ms) ====== [2024-08-23T22:24:39.534Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-23T22:24:40.178Z] GC before operation: completed in 399.384 ms, heap usage 216.507 MB -> 94.492 MB. [2024-08-23T22:24:43.124Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3042.954 ms) ====== [2024-08-23T22:24:43.124Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-23T22:24:43.761Z] GC before operation: completed in 408.207 ms, heap usage 215.419 MB -> 66.982 MB. [2024-08-23T22:24:46.868Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2749.994 ms) ====== [2024-08-23T22:24:46.868Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-23T22:24:46.868Z] GC before operation: completed in 259.693 ms, heap usage 228.944 MB -> 67.242 MB. [2024-08-23T22:24:49.614Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2606.428 ms) ====== [2024-08-23T22:24:49.614Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-23T22:24:49.614Z] GC before operation: completed in 312.167 ms, heap usage 234.166 MB -> 67.182 MB. [2024-08-23T22:24:52.833Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2712.937 ms) ====== [2024-08-23T22:24:52.833Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-23T22:24:52.833Z] GC before operation: completed in 254.623 ms, heap usage 194.090 MB -> 67.281 MB. [2024-08-23T22:24:55.999Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3208.617 ms) ====== [2024-08-23T22:24:58.226Z] ----------------------------------- [2024-08-23T22:24:58.226Z] renaissance-dec-tree_0_PASSED [2024-08-23T22:24:58.226Z] ----------------------------------- [2024-08-23T22:24:58.226Z] [2024-08-23T22:24:58.226Z] TEST TEARDOWN: [2024-08-23T22:24:58.226Z] Nothing to be done for teardown. [2024-08-23T22:24:58.226Z] renaissance-dec-tree_0 Finish Time: Fri Aug 23 18:24:57 2024 Epoch Time (ms): 1724451897438