[2024-09-25T22:55:40.171Z] Running test renaissance-dec-tree_0 ... [2024-09-25T22:55:40.171Z] =============================================== [2024-09-25T22:55:40.171Z] renaissance-dec-tree_0 Start Time: Wed Sep 25 22:55:39 2024 Epoch Time (ms): 1727304939746 [2024-09-25T22:55:40.171Z] variation: NoOptions [2024-09-25T22:55:40.171Z] JVM_OPTIONS: [2024-09-25T22:55:40.171Z] { \ [2024-09-25T22:55:40.171Z] echo ""; echo "TEST SETUP:"; \ [2024-09-25T22:55:40.171Z] echo "Nothing to be done for setup."; \ [2024-09-25T22:55:40.171Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17273013765094/renaissance-dec-tree_0"; \ [2024-09-25T22:55:40.171Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17273013765094/renaissance-dec-tree_0"; \ [2024-09-25T22:55:40.171Z] echo ""; echo "TESTING:"; \ [2024-09-25T22:55:40.171Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-11.0.25+7/bin/..//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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17273013765094/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-09-25T22:55:40.171Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17273013765094/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-25T22:55:40.171Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-25T22:55:40.171Z] echo "Nothing to be done for teardown."; \ [2024-09-25T22:55:40.171Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17273013765094/TestTargetResult"; [2024-09-25T22:55:40.171Z] [2024-09-25T22:55:40.171Z] TEST SETUP: [2024-09-25T22:55:40.171Z] Nothing to be done for setup. [2024-09-25T22:55:40.171Z] [2024-09-25T22:55:40.171Z] TESTING: [2024-09-25T22:55:54.173Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-25T22:56:03.955Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-25T22:56:09.648Z] WARNING: An illegal reflective access operation has occurred [2024-09-25T22:56:09.648Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17273013765094/renaissance-dec-tree_0/harness-225543-15328017375150971145/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-25T22:56:09.648Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-25T22:56:09.648Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-25T22:56:09.648Z] WARNING: All illegal access operations will be denied in a future release [2024-09-25T22:56:21.214Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-25T22:56:21.962Z] GC before operation: completed in 363.135 ms, heap usage 66.327 MB -> 34.007 MB. [2024-09-25T22:57:29.362Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (63809.773 ms) ====== [2024-09-25T22:57:29.362Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-25T22:57:29.362Z] GC before operation: completed in 1092.569 ms, heap usage 183.522 MB -> 91.001 MB. [2024-09-25T22:57:51.558Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (22281.212 ms) ====== [2024-09-25T22:57:51.558Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-25T22:57:51.558Z] GC before operation: completed in 971.131 ms, heap usage 238.018 MB -> 64.193 MB. [2024-09-25T22:58:07.676Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (15888.658 ms) ====== [2024-09-25T22:58:07.676Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-25T22:58:07.676Z] GC before operation: completed in 776.265 ms, heap usage 198.721 MB -> 64.838 MB. [2024-09-25T22:58:22.254Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (14277.153 ms) ====== [2024-09-25T22:58:22.254Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-25T22:58:22.254Z] GC before operation: completed in 752.867 ms, heap usage 194.135 MB -> 64.819 MB. [2024-09-25T22:58:38.542Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (15398.233 ms) ====== [2024-09-25T22:58:38.542Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-25T22:58:38.542Z] GC before operation: completed in 926.888 ms, heap usage 232.665 MB -> 65.263 MB. [2024-09-25T22:58:52.360Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (12859.827 ms) ====== [2024-09-25T22:58:52.360Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-25T22:58:52.360Z] GC before operation: completed in 799.520 ms, heap usage 187.735 MB -> 65.324 MB. [2024-09-25T22:59:04.168Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (12717.710 ms) ====== [2024-09-25T22:59:04.168Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-25T22:59:04.958Z] GC before operation: completed in 806.702 ms, heap usage 130.796 MB -> 65.120 MB. [2024-09-25T22:59:16.567Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (10962.880 ms) ====== [2024-09-25T22:59:16.567Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-25T22:59:16.567Z] GC before operation: completed in 634.417 ms, heap usage 105.455 MB -> 65.244 MB. [2024-09-25T22:59:28.201Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (10228.462 ms) ====== [2024-09-25T22:59:28.201Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-25T22:59:28.201Z] GC before operation: completed in 601.565 ms, heap usage 130.407 MB -> 65.638 MB. [2024-09-25T22:59:40.372Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (12019.667 ms) ====== [2024-09-25T22:59:40.372Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-25T22:59:40.372Z] GC before operation: completed in 939.763 ms, heap usage 227.426 MB -> 65.597 MB. [2024-09-25T22:59:52.097Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (10520.468 ms) ====== [2024-09-25T22:59:52.097Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-25T22:59:52.097Z] GC before operation: completed in 795.801 ms, heap usage 285.028 MB -> 65.928 MB. [2024-09-25T23:00:01.984Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (9384.453 ms) ====== [2024-09-25T23:00:01.984Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-25T23:00:01.984Z] GC before operation: completed in 884.053 ms, heap usage 261.872 MB -> 77.108 MB. [2024-09-25T23:00:11.800Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (9870.381 ms) ====== [2024-09-25T23:00:11.800Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-25T23:00:12.559Z] GC before operation: completed in 747.315 ms, heap usage 287.681 MB -> 66.070 MB. [2024-09-25T23:00:22.340Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (9295.980 ms) ====== [2024-09-25T23:00:22.340Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-25T23:00:23.104Z] GC before operation: completed in 727.199 ms, heap usage 196.895 MB -> 66.032 MB. [2024-09-25T23:00:32.977Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (9192.731 ms) ====== [2024-09-25T23:00:32.977Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-25T23:00:32.977Z] GC before operation: completed in 710.630 ms, heap usage 282.102 MB -> 66.136 MB. [2024-09-25T23:00:41.256Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (8719.313 ms) ====== [2024-09-25T23:00:41.256Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-25T23:00:42.832Z] GC before operation: completed in 999.870 ms, heap usage 295.282 MB -> 66.451 MB. [2024-09-25T23:00:53.115Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (9687.153 ms) ====== [2024-09-25T23:00:53.115Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-25T23:00:53.115Z] GC before operation: completed in 968.954 ms, heap usage 301.353 MB -> 66.406 MB. [2024-09-25T23:01:01.888Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (9032.477 ms) ====== [2024-09-25T23:01:01.888Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-25T23:01:02.644Z] GC before operation: completed in 799.888 ms, heap usage 158.896 MB -> 66.380 MB. [2024-09-25T23:01:12.400Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (8282.421 ms) ====== [2024-09-25T23:01:12.400Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-25T23:01:12.400Z] GC before operation: completed in 896.643 ms, heap usage 116.688 MB -> 66.312 MB. [2024-09-25T23:01:20.588Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (8002.650 ms) ====== [2024-09-25T23:01:20.588Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-25T23:01:20.588Z] GC before operation: completed in 759.343 ms, heap usage 193.155 MB -> 66.840 MB. [2024-09-25T23:01:30.521Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (8844.680 ms) ====== [2024-09-25T23:01:30.521Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-25T23:01:30.521Z] GC before operation: completed in 862.824 ms, heap usage 189.477 MB -> 66.795 MB. [2024-09-25T23:01:40.666Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (9299.517 ms) ====== [2024-09-25T23:01:40.666Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-25T23:01:40.666Z] GC before operation: completed in 801.930 ms, heap usage 129.888 MB -> 66.895 MB. [2024-09-25T23:01:49.019Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (8593.172 ms) ====== [2024-09-25T23:01:49.019Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-25T23:01:50.617Z] GC before operation: completed in 929.980 ms, heap usage 260.895 MB -> 66.993 MB. [2024-09-25T23:01:57.360Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (7459.768 ms) ====== [2024-09-25T23:01:57.360Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-25T23:01:58.116Z] GC before operation: completed in 659.813 ms, heap usage 109.228 MB -> 94.129 MB. [2024-09-25T23:02:08.027Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (9102.127 ms) ====== [2024-09-25T23:02:08.027Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-25T23:02:08.027Z] GC before operation: completed in 825.940 ms, heap usage 324.603 MB -> 83.896 MB. [2024-09-25T23:02:18.336Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (9669.652 ms) ====== [2024-09-25T23:02:18.336Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-25T23:02:19.092Z] GC before operation: completed in 849.864 ms, heap usage 115.287 MB -> 66.959 MB. [2024-09-25T23:02:29.164Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (9783.715 ms) ====== [2024-09-25T23:02:29.165Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-25T23:02:29.927Z] GC before operation: completed in 1026.219 ms, heap usage 183.199 MB -> 67.568 MB. [2024-09-25T23:02:38.395Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (8834.837 ms) ====== [2024-09-25T23:02:38.395Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-25T23:02:39.152Z] GC before operation: completed in 809.897 ms, heap usage 128.056 MB -> 67.383 MB. [2024-09-25T23:02:47.397Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (8314.938 ms) ====== [2024-09-25T23:02:47.397Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-25T23:02:48.139Z] GC before operation: completed in 880.030 ms, heap usage 133.210 MB -> 94.953 MB. [2024-09-25T23:02:57.958Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (9096.244 ms) ====== [2024-09-25T23:02:57.958Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-25T23:02:58.708Z] GC before operation: completed in 882.625 ms, heap usage 292.210 MB -> 67.893 MB. [2024-09-25T23:03:08.512Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (9502.511 ms) ====== [2024-09-25T23:03:08.512Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-25T23:03:08.512Z] GC before operation: completed in 843.043 ms, heap usage 198.016 MB -> 67.992 MB. [2024-09-25T23:03:18.319Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (8967.211 ms) ====== [2024-09-25T23:03:18.319Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-25T23:03:18.319Z] GC before operation: completed in 857.796 ms, heap usage 245.782 MB -> 67.943 MB. [2024-09-25T23:03:28.749Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (9670.803 ms) ====== [2024-09-25T23:03:28.749Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-25T23:03:29.495Z] GC before operation: completed in 926.577 ms, heap usage 329.309 MB -> 68.182 MB. [2024-09-25T23:03:37.764Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (8743.018 ms) ====== [2024-09-25T23:03:37.765Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-25T23:03:38.548Z] GC before operation: completed in 939.444 ms, heap usage 146.525 MB -> 67.988 MB. [2024-09-25T23:03:48.281Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (8228.231 ms) ====== [2024-09-25T23:03:48.281Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-25T23:03:48.281Z] GC before operation: completed in 750.245 ms, heap usage 102.291 MB -> 67.820 MB. [2024-09-25T23:03:58.003Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (9645.966 ms) ====== [2024-09-25T23:03:58.003Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-25T23:03:58.755Z] GC before operation: completed in 956.834 ms, heap usage 327.980 MB -> 68.325 MB. [2024-09-25T23:04:08.467Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (9032.206 ms) ====== [2024-09-25T23:04:08.467Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-25T23:04:08.467Z] GC before operation: completed in 871.435 ms, heap usage 186.562 MB -> 68.619 MB. [2024-09-25T23:04:18.339Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (9133.045 ms) ====== [2024-09-25T23:04:18.339Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-25T23:04:18.339Z] GC before operation: completed in 848.098 ms, heap usage 100.092 MB -> 68.245 MB. [2024-09-25T23:04:26.499Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (7938.353 ms) ====== [2024-09-25T23:04:26.499Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-25T23:04:27.264Z] GC before operation: completed in 926.345 ms, heap usage 337.245 MB -> 79.909 MB. [2024-09-25T23:04:35.487Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (8406.925 ms) ====== [2024-09-25T23:04:37.140Z] ----------------------------------- [2024-09-25T23:04:37.140Z] renaissance-dec-tree_0_PASSED [2024-09-25T23:04:37.140Z] ----------------------------------- [2024-09-25T23:04:37.140Z] [2024-09-25T23:04:37.140Z] TEST TEARDOWN: [2024-09-25T23:04:37.140Z] Nothing to be done for teardown. [2024-09-25T23:04:37.140Z] renaissance-dec-tree_0 Finish Time: Wed Sep 25 23:04:36 2024 Epoch Time (ms): 1727305476587