[2024-09-26T04:48:28.544Z] Running test renaissance-dec-tree_0 ... [2024-09-26T04:48:28.544Z] =============================================== [2024-09-26T04:48:28.544Z] renaissance-dec-tree_0 Start Time: Thu Sep 26 04:48:28 2024 Epoch Time (ms): 1727326108032 [2024-09-26T04:48:28.544Z] variation: NoOptions [2024-09-26T04:48:28.544Z] JVM_OPTIONS: [2024-09-26T04:48:28.544Z] { \ [2024-09-26T04:48:28.544Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T04:48:28.544Z] echo "Nothing to be done for setup."; \ [2024-09-26T04:48:28.544Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273249167590/renaissance-dec-tree_0"; \ [2024-09-26T04:48:28.544Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273249167590/renaissance-dec-tree_0"; \ [2024-09-26T04:48:28.544Z] echo ""; echo "TESTING:"; \ [2024-09-26T04:48:28.544Z] "/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_17273249167590/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-09-26T04:48:28.544Z] 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_17273249167590/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T04:48:28.544Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T04:48:28.544Z] echo "Nothing to be done for teardown."; \ [2024-09-26T04:48:28.544Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17273249167590/TestTargetResult"; [2024-09-26T04:48:28.544Z] [2024-09-26T04:48:28.544Z] TEST SETUP: [2024-09-26T04:48:28.544Z] Nothing to be done for setup. [2024-09-26T04:48:28.544Z] [2024-09-26T04:48:28.544Z] TESTING: [2024-09-26T04:48:38.120Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T04:48:45.740Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-09-26T04:48:49.090Z] WARNING: An illegal reflective access operation has occurred [2024-09-26T04:48:49.090Z] 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_17273249167590/renaissance-dec-tree_0/harness-044831-11281896140803753422/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-26T04:48:49.090Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-26T04:48:49.090Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-26T04:48:49.090Z] WARNING: All illegal access operations will be denied in a future release [2024-09-26T04:48:59.670Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-26T04:48:59.670Z] GC before operation: completed in 378.306 ms, heap usage 76.339 MB -> 33.768 MB. [2024-09-26T04:49:22.030Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (22912.381 ms) ====== [2024-09-26T04:49:22.030Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-26T04:49:22.680Z] GC before operation: completed in 325.671 ms, heap usage 205.400 MB -> 62.553 MB. [2024-09-26T04:49:30.547Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7918.987 ms) ====== [2024-09-26T04:49:30.547Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-26T04:49:30.547Z] GC before operation: completed in 558.948 ms, heap usage 157.398 MB -> 90.240 MB. [2024-09-26T04:49:38.302Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (7157.927 ms) ====== [2024-09-26T04:49:38.302Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-26T04:49:38.302Z] GC before operation: completed in 436.222 ms, heap usage 216.187 MB -> 63.381 MB. [2024-09-26T04:49:47.838Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (9092.522 ms) ====== [2024-09-26T04:49:47.838Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-26T04:49:47.838Z] GC before operation: completed in 348.732 ms, heap usage 204.724 MB -> 91.004 MB. [2024-09-26T04:49:54.085Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5457.374 ms) ====== [2024-09-26T04:49:54.086Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-26T04:49:54.086Z] GC before operation: completed in 414.493 ms, heap usage 129.720 MB -> 63.309 MB. [2024-09-26T04:49:57.898Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3611.288 ms) ====== [2024-09-26T04:49:57.898Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-26T04:49:57.898Z] GC before operation: completed in 553.581 ms, heap usage 259.325 MB -> 64.147 MB. [2024-09-26T04:50:06.089Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (7438.604 ms) ====== [2024-09-26T04:50:06.089Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-26T04:50:06.089Z] GC before operation: completed in 848.630 ms, heap usage 194.080 MB -> 91.573 MB. [2024-09-26T04:50:11.426Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (5249.173 ms) ====== [2024-09-26T04:50:11.426Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-26T04:50:12.114Z] GC before operation: completed in 346.178 ms, heap usage 355.820 MB -> 64.135 MB. [2024-09-26T04:50:18.742Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (6384.404 ms) ====== [2024-09-26T04:50:18.742Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-26T04:50:18.742Z] GC before operation: completed in 618.585 ms, heap usage 151.386 MB -> 64.237 MB. [2024-09-26T04:50:24.776Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (6062.703 ms) ====== [2024-09-26T04:50:24.776Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-26T04:50:25.393Z] GC before operation: completed in 717.570 ms, heap usage 241.815 MB -> 64.338 MB. [2024-09-26T04:50:31.125Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (5757.372 ms) ====== [2024-09-26T04:50:31.125Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-26T04:50:31.910Z] GC before operation: completed in 457.746 ms, heap usage 242.028 MB -> 64.799 MB. [2024-09-26T04:50:37.124Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (5035.086 ms) ====== [2024-09-26T04:50:37.124Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-26T04:50:37.124Z] GC before operation: completed in 425.532 ms, heap usage 215.229 MB -> 64.899 MB. [2024-09-26T04:50:43.696Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (6381.268 ms) ====== [2024-09-26T04:50:43.696Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-26T04:50:44.338Z] GC before operation: completed in 481.852 ms, heap usage 221.511 MB -> 64.783 MB. [2024-09-26T04:50:49.146Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4371.108 ms) ====== [2024-09-26T04:50:49.146Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-26T04:50:49.146Z] GC before operation: completed in 516.421 ms, heap usage 213.198 MB -> 64.730 MB. [2024-09-26T04:50:54.337Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4843.386 ms) ====== [2024-09-26T04:50:54.337Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-26T04:50:54.337Z] GC before operation: completed in 423.451 ms, heap usage 238.576 MB -> 64.983 MB. [2024-09-26T04:51:00.876Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (6047.406 ms) ====== [2024-09-26T04:51:00.876Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-26T04:51:00.876Z] GC before operation: completed in 752.145 ms, heap usage 141.607 MB -> 64.858 MB. [2024-09-26T04:51:07.261Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (5543.196 ms) ====== [2024-09-26T04:51:07.261Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-26T04:51:07.261Z] GC before operation: completed in 360.055 ms, heap usage 141.379 MB -> 64.820 MB. [2024-09-26T04:51:11.644Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4890.603 ms) ====== [2024-09-26T04:51:11.644Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-26T04:51:12.304Z] GC before operation: completed in 337.958 ms, heap usage 218.789 MB -> 65.578 MB. [2024-09-26T04:51:15.608Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3070.479 ms) ====== [2024-09-26T04:51:15.608Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-26T04:51:15.608Z] GC before operation: completed in 249.531 ms, heap usage 229.766 MB -> 65.317 MB. [2024-09-26T04:51:18.524Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2488.769 ms) ====== [2024-09-26T04:51:18.524Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-26T04:51:18.524Z] GC before operation: completed in 417.426 ms, heap usage 211.525 MB -> 65.559 MB. [2024-09-26T04:51:24.108Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (5011.216 ms) ====== [2024-09-26T04:51:24.108Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-26T04:51:24.108Z] GC before operation: completed in 446.245 ms, heap usage 215.937 MB -> 93.118 MB. [2024-09-26T04:51:29.049Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4920.524 ms) ====== [2024-09-26T04:51:29.049Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-26T04:51:29.049Z] GC before operation: completed in 392.988 ms, heap usage 321.976 MB -> 65.754 MB. [2024-09-26T04:51:35.137Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (5370.299 ms) ====== [2024-09-26T04:51:35.137Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-26T04:51:35.137Z] GC before operation: completed in 750.811 ms, heap usage 179.747 MB -> 93.322 MB. [2024-09-26T04:51:40.240Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4493.562 ms) ====== [2024-09-26T04:51:40.240Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-26T04:51:40.950Z] GC before operation: completed in 1111.357 ms, heap usage 118.371 MB -> 65.519 MB. [2024-09-26T04:51:44.979Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3839.108 ms) ====== [2024-09-26T04:51:44.979Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-26T04:51:44.979Z] GC before operation: completed in 363.988 ms, heap usage 190.422 MB -> 66.049 MB. [2024-09-26T04:51:50.540Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4796.014 ms) ====== [2024-09-26T04:51:50.540Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-26T04:51:51.510Z] GC before operation: completed in 1801.131 ms, heap usage 179.552 MB -> 93.592 MB. [2024-09-26T04:51:55.661Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3708.363 ms) ====== [2024-09-26T04:51:55.661Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-26T04:51:56.458Z] GC before operation: completed in 555.591 ms, heap usage 322.367 MB -> 66.453 MB. [2024-09-26T04:52:00.363Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3874.983 ms) ====== [2024-09-26T04:52:00.363Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-26T04:52:00.363Z] GC before operation: completed in 294.059 ms, heap usage 104.569 MB -> 93.502 MB. [2024-09-26T04:52:03.289Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2934.601 ms) ====== [2024-09-26T04:52:03.289Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-26T04:52:03.289Z] GC before operation: completed in 456.328 ms, heap usage 154.870 MB -> 66.484 MB. [2024-09-26T04:52:07.519Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3281.459 ms) ====== [2024-09-26T04:52:07.519Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-26T04:52:07.519Z] GC before operation: completed in 323.895 ms, heap usage 171.078 MB -> 66.283 MB. [2024-09-26T04:52:10.349Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2682.641 ms) ====== [2024-09-26T04:52:10.349Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-26T04:52:10.349Z] GC before operation: completed in 275.428 ms, heap usage 199.008 MB -> 66.499 MB. [2024-09-26T04:52:13.238Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2901.654 ms) ====== [2024-09-26T04:52:13.238Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-26T04:52:13.238Z] GC before operation: completed in 307.920 ms, heap usage 140.575 MB -> 66.459 MB. [2024-09-26T04:52:16.097Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2852.032 ms) ====== [2024-09-26T04:52:16.097Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-26T04:52:16.715Z] GC before operation: completed in 424.172 ms, heap usage 169.974 MB -> 66.814 MB. [2024-09-26T04:52:19.611Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2571.748 ms) ====== [2024-09-26T04:52:19.611Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-26T04:52:19.611Z] GC before operation: completed in 243.812 ms, heap usage 203.993 MB -> 66.917 MB. [2024-09-26T04:52:22.520Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3144.844 ms) ====== [2024-09-26T04:52:22.520Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-26T04:52:23.122Z] GC before operation: completed in 470.902 ms, heap usage 144.746 MB -> 67.228 MB. [2024-09-26T04:52:26.056Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3070.162 ms) ====== [2024-09-26T04:52:26.056Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-26T04:52:26.694Z] GC before operation: completed in 643.490 ms, heap usage 192.497 MB -> 67.338 MB. [2024-09-26T04:52:31.738Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (4402.894 ms) ====== [2024-09-26T04:52:31.738Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-26T04:52:32.419Z] GC before operation: completed in 898.581 ms, heap usage 129.687 MB -> 94.643 MB. [2024-09-26T04:52:32.419Z] 24/09/26 04:52:31 WARN BlockManager: Asked to remove block broadcast_512, which does not exist [2024-09-26T04:52:32.419Z] 24/09/26 04:52:31 WARN BlockManager: Asked to remove block broadcast_512_piece0, which does not exist [2024-09-26T04:52:37.355Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4812.446 ms) ====== [2024-09-26T04:52:37.355Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-26T04:52:38.049Z] GC before operation: completed in 945.809 ms, heap usage 279.585 MB -> 67.152 MB. [2024-09-26T04:52:43.120Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (5126.038 ms) ====== [2024-09-26T04:52:43.120Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-26T04:52:43.789Z] GC before operation: completed in 424.531 ms, heap usage 287.991 MB -> 75.548 MB. [2024-09-26T04:52:46.711Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2837.194 ms) ====== [2024-09-26T04:52:46.711Z] ----------------------------------- [2024-09-26T04:52:46.711Z] renaissance-dec-tree_0_PASSED [2024-09-26T04:52:46.711Z] ----------------------------------- [2024-09-26T04:52:46.711Z] [2024-09-26T04:52:46.711Z] TEST TEARDOWN: [2024-09-26T04:52:46.711Z] Nothing to be done for teardown. [2024-09-26T04:52:46.711Z] renaissance-dec-tree_0 Finish Time: Thu Sep 26 04:52:46 2024 Epoch Time (ms): 1727326366571