[2024-08-28T22:55:20.248Z] Running test renaissance-dec-tree_0 ... [2024-08-28T22:55:20.248Z] =============================================== [2024-08-28T22:55:20.248Z] renaissance-dec-tree_0 Start Time: Wed Aug 28 22:55:16 2024 Epoch Time (ms): 1724885716948 [2024-08-28T22:55:20.248Z] variation: NoOptions [2024-08-28T22:55:20.248Z] JVM_OPTIONS: [2024-08-28T22:55:20.248Z] { \ [2024-08-28T22:55:20.248Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T22:55:20.248Z] echo "Nothing to be done for setup."; \ [2024-08-28T22:55:20.248Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_172488200392/renaissance-dec-tree_0"; \ [2024-08-28T22:55:20.248Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_172488200392/renaissance-dec-tree_0"; \ [2024-08-28T22:55:20.248Z] echo ""; echo "TESTING:"; \ [2024-08-28T22:55:20.249Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-11.0.25+5/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_172488200392/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-28T22:55:20.249Z] 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_172488200392/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T22:55:20.249Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T22:55:20.249Z] echo "Nothing to be done for teardown."; \ [2024-08-28T22:55:20.249Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_172488200392/TestTargetResult"; [2024-08-28T22:55:20.249Z] [2024-08-28T22:55:20.249Z] TEST SETUP: [2024-08-28T22:55:20.249Z] Nothing to be done for setup. [2024-08-28T22:55:20.249Z] [2024-08-28T22:55:20.249Z] TESTING: [2024-08-28T22:55:32.156Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T22:55:46.365Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-28T22:55:52.097Z] WARNING: An illegal reflective access operation has occurred [2024-08-28T22:55:52.097Z] 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_172488200392/renaissance-dec-tree_0/harness-225520-16373794393414023358/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-28T22:55:52.097Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-28T22:55:52.097Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-28T22:55:52.097Z] WARNING: All illegal access operations will be denied in a future release [2024-08-28T22:56:08.672Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-28T22:56:08.673Z] GC before operation: completed in 445.935 ms, heap usage 42.236 MB -> 33.978 MB. [2024-08-28T22:57:28.800Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (75084.128 ms) ====== [2024-08-28T22:57:28.800Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-28T22:57:28.800Z] GC before operation: completed in 835.149 ms, heap usage 322.145 MB -> 63.868 MB. [2024-08-28T22:57:51.232Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (26522.646 ms) ====== [2024-08-28T22:57:51.232Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-28T22:57:51.232Z] GC before operation: completed in 826.278 ms, heap usage 186.899 MB -> 64.356 MB. [2024-08-28T22:58:14.262Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (22414.780 ms) ====== [2024-08-28T22:58:14.262Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-28T22:58:14.262Z] GC before operation: completed in 770.671 ms, heap usage 241.204 MB -> 64.462 MB. [2024-08-28T22:58:33.287Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (18513.645 ms) ====== [2024-08-28T22:58:33.287Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-28T22:58:34.044Z] GC before operation: completed in 1140.653 ms, heap usage 132.467 MB -> 64.889 MB. [2024-08-28T22:58:53.081Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (17292.031 ms) ====== [2024-08-28T22:58:53.081Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-28T22:58:53.081Z] GC before operation: completed in 851.450 ms, heap usage 123.532 MB -> 64.591 MB. [2024-08-28T22:59:07.389Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (15570.307 ms) ====== [2024-08-28T22:59:07.389Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-28T22:59:08.149Z] GC before operation: completed in 1010.918 ms, heap usage 180.032 MB -> 65.081 MB. [2024-08-28T22:59:22.076Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (11958.160 ms) ====== [2024-08-28T22:59:22.076Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-28T22:59:22.076Z] GC before operation: completed in 881.201 ms, heap usage 263.095 MB -> 65.259 MB. [2024-08-28T22:59:35.970Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (13602.066 ms) ====== [2024-08-28T22:59:35.970Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-28T22:59:35.970Z] GC before operation: completed in 902.653 ms, heap usage 303.708 MB -> 65.794 MB. [2024-08-28T22:59:48.308Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (11117.048 ms) ====== [2024-08-28T22:59:48.308Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-28T22:59:48.308Z] GC before operation: completed in 1010.655 ms, heap usage 170.195 MB -> 65.221 MB. [2024-08-28T23:00:00.071Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (12247.776 ms) ====== [2024-08-28T23:00:00.071Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-28T23:00:00.844Z] GC before operation: completed in 933.649 ms, heap usage 227.767 MB -> 65.743 MB. [2024-08-28T23:00:14.369Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (11795.821 ms) ====== [2024-08-28T23:00:14.369Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-28T23:00:14.369Z] GC before operation: completed in 705.358 ms, heap usage 160.184 MB -> 65.581 MB. [2024-08-28T23:00:27.952Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (12879.241 ms) ====== [2024-08-28T23:00:27.952Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-28T23:00:27.952Z] GC before operation: completed in 703.808 ms, heap usage 283.581 MB -> 65.815 MB. [2024-08-28T23:00:37.714Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (10226.664 ms) ====== [2024-08-28T23:00:37.714Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-28T23:00:38.492Z] GC before operation: completed in 797.252 ms, heap usage 112.783 MB -> 65.860 MB. [2024-08-28T23:00:48.243Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (9672.159 ms) ====== [2024-08-28T23:00:48.243Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-28T23:00:48.243Z] GC before operation: completed in 676.625 ms, heap usage 285.424 MB -> 66.175 MB. [2024-08-28T23:00:59.853Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (11469.979 ms) ====== [2024-08-28T23:00:59.853Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-28T23:01:00.612Z] GC before operation: completed in 779.283 ms, heap usage 236.999 MB -> 66.147 MB. [2024-08-28T23:01:10.384Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (9546.115 ms) ====== [2024-08-28T23:01:10.384Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-28T23:01:11.132Z] GC before operation: completed in 772.415 ms, heap usage 118.526 MB -> 66.025 MB. [2024-08-28T23:01:21.484Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (9580.694 ms) ====== [2024-08-28T23:01:21.484Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-28T23:01:21.484Z] GC before operation: completed in 900.702 ms, heap usage 297.469 MB -> 66.555 MB. [2024-08-28T23:01:31.269Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (9962.742 ms) ====== [2024-08-28T23:01:31.269Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-28T23:01:32.833Z] GC before operation: completed in 850.343 ms, heap usage 253.362 MB -> 66.652 MB. [2024-08-28T23:01:42.580Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (9801.072 ms) ====== [2024-08-28T23:01:42.580Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-28T23:01:43.340Z] GC before operation: completed in 850.074 ms, heap usage 255.992 MB -> 66.603 MB. [2024-08-28T23:01:53.145Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (9383.618 ms) ====== [2024-08-28T23:01:53.145Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-28T23:01:53.145Z] GC before operation: completed in 650.417 ms, heap usage 240.687 MB -> 66.697 MB. [2024-08-28T23:02:02.887Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (8727.353 ms) ====== [2024-08-28T23:02:02.887Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-28T23:02:02.887Z] GC before operation: completed in 824.345 ms, heap usage 257.961 MB -> 66.946 MB. [2024-08-28T23:02:14.452Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (10743.357 ms) ====== [2024-08-28T23:02:14.452Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-28T23:02:14.452Z] GC before operation: completed in 664.315 ms, heap usage 115.960 MB -> 66.477 MB. [2024-08-28T23:02:24.180Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (9022.937 ms) ====== [2024-08-28T23:02:24.180Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-28T23:02:24.180Z] GC before operation: completed in 799.376 ms, heap usage 193.091 MB -> 67.131 MB. [2024-08-28T23:02:32.355Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (8127.869 ms) ====== [2024-08-28T23:02:32.355Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-28T23:02:33.122Z] GC before operation: completed in 850.682 ms, heap usage 240.149 MB -> 67.261 MB. [2024-08-28T23:02:42.992Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (8897.530 ms) ====== [2024-08-28T23:02:42.992Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-28T23:02:42.992Z] GC before operation: completed in 1098.062 ms, heap usage 290.852 MB -> 67.216 MB. [2024-08-28T23:02:52.803Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (9611.574 ms) ====== [2024-08-28T23:02:52.803Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-28T23:02:53.546Z] GC before operation: completed in 738.782 ms, heap usage 149.788 MB -> 67.024 MB. [2024-08-28T23:03:01.697Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (8097.669 ms) ====== [2024-08-28T23:03:01.697Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-28T23:03:02.452Z] GC before operation: completed in 830.327 ms, heap usage 215.257 MB -> 67.560 MB. [2024-08-28T23:03:12.224Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (8886.627 ms) ====== [2024-08-28T23:03:12.224Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-28T23:03:12.224Z] GC before operation: completed in 802.457 ms, heap usage 103.853 MB -> 67.105 MB. [2024-08-28T23:03:21.946Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (9366.362 ms) ====== [2024-08-28T23:03:21.946Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-28T23:03:21.946Z] GC before operation: completed in 971.250 ms, heap usage 117.188 MB -> 67.057 MB. [2024-08-28T23:03:32.023Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (8844.832 ms) ====== [2024-08-28T23:03:32.023Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-28T23:03:32.023Z] GC before operation: completed in 822.325 ms, heap usage 108.790 MB -> 67.547 MB. [2024-08-28T23:03:42.211Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (9925.999 ms) ====== [2024-08-28T23:03:42.211Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-28T23:03:43.007Z] GC before operation: completed in 966.554 ms, heap usage 289.763 MB -> 67.805 MB. [2024-08-28T23:03:53.156Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (9468.619 ms) ====== [2024-08-28T23:03:53.156Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-28T23:03:53.156Z] GC before operation: completed in 837.829 ms, heap usage 271.399 MB -> 67.900 MB. [2024-08-28T23:04:02.264Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (8424.934 ms) ====== [2024-08-28T23:04:02.264Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-28T23:04:02.264Z] GC before operation: completed in 765.876 ms, heap usage 149.459 MB -> 67.854 MB. [2024-08-28T23:04:10.862Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (8108.746 ms) ====== [2024-08-28T23:04:10.862Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-28T23:04:10.862Z] GC before operation: completed in 782.072 ms, heap usage 263.530 MB -> 68.238 MB. [2024-08-28T23:04:21.305Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (9081.802 ms) ====== [2024-08-28T23:04:21.305Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-28T23:04:21.305Z] GC before operation: completed in 725.541 ms, heap usage 234.313 MB -> 68.194 MB. [2024-08-28T23:04:29.911Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (8194.272 ms) ====== [2024-08-28T23:04:29.911Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-28T23:04:29.911Z] GC before operation: completed in 892.234 ms, heap usage 300.105 MB -> 68.286 MB. [2024-08-28T23:04:40.143Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (9876.919 ms) ====== [2024-08-28T23:04:40.143Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-28T23:04:40.967Z] GC before operation: completed in 991.524 ms, heap usage 238.334 MB -> 68.529 MB. [2024-08-28T23:04:51.131Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (9074.121 ms) ====== [2024-08-28T23:04:51.131Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-28T23:04:51.131Z] GC before operation: completed in 725.889 ms, heap usage 324.215 MB -> 68.478 MB. [2024-08-28T23:04:59.635Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (8174.962 ms) ====== [2024-08-28T23:04:59.635Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-28T23:04:59.635Z] GC before operation: completed in 794.688 ms, heap usage 104.951 MB -> 68.283 MB. [2024-08-28T23:05:08.165Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (7716.240 ms) ====== [2024-08-28T23:05:08.958Z] ----------------------------------- [2024-08-28T23:05:08.958Z] renaissance-dec-tree_0_PASSED [2024-08-28T23:05:08.958Z] ----------------------------------- [2024-08-28T23:05:08.958Z] [2024-08-28T23:05:08.958Z] TEST TEARDOWN: [2024-08-28T23:05:08.958Z] Nothing to be done for teardown. [2024-08-28T23:05:08.958Z] renaissance-dec-tree_0 Finish Time: Wed Aug 28 23:05:08 2024 Epoch Time (ms): 1724886308180