[2024-12-06T22:34:38.846Z] Running test renaissance-dec-tree_0 ... [2024-12-06T22:34:38.846Z] =============================================== [2024-12-06T22:34:38.846Z] renaissance-dec-tree_0 Start Time: Fri Dec 6 22:34:38 2024 Epoch Time (ms): 1733524478415 [2024-12-06T22:34:38.846Z] variation: NoOptions [2024-12-06T22:34:38.846Z] JVM_OPTIONS: [2024-12-06T22:34:38.846Z] { \ [2024-12-06T22:34:38.846Z] echo ""; echo "TEST SETUP:"; \ [2024-12-06T22:34:38.846Z] echo "Nothing to be done for setup."; \ [2024-12-06T22:34:38.846Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/renaissance-dec-tree_0"; \ [2024-12-06T22:34:38.846Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/renaissance-dec-tree_0"; \ [2024-12-06T22:34:38.846Z] echo ""; echo "TESTING:"; \ [2024-12-06T22:34:38.846Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-12-06T22:34:38.846Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-06T22:34:38.846Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-06T22:34:38.846Z] echo "Nothing to be done for teardown."; \ [2024-12-06T22:34:38.846Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/TestTargetResult"; [2024-12-06T22:34:38.846Z] [2024-12-06T22:34:38.846Z] TEST SETUP: [2024-12-06T22:34:38.846Z] Nothing to be done for setup. [2024-12-06T22:34:38.846Z] [2024-12-06T22:34:38.846Z] TESTING: [2024-12-06T22:34:44.463Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-06T22:34:46.945Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2024-12-06T22:34:48.537Z] WARNING: An illegal reflective access operation has occurred [2024-12-06T22:34:48.537Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17335240229460/renaissance-dec-tree_0/harness-223439-14231499102833651875/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-12-06T22:34:48.537Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-12-06T22:34:48.537Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-12-06T22:34:48.537Z] WARNING: All illegal access operations will be denied in a future release [2024-12-06T22:34:51.986Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-12-06T22:34:51.986Z] GC before operation: completed in 91.846 ms, heap usage 201.649 MB -> 35.185 MB. [2024-12-06T22:35:02.266Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9628.583 ms) ====== [2024-12-06T22:35:02.266Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-12-06T22:35:02.266Z] GC before operation: completed in 100.232 ms, heap usage 177.999 MB -> 66.943 MB. [2024-12-06T22:35:03.863Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2310.493 ms) ====== [2024-12-06T22:35:03.863Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-12-06T22:35:04.637Z] GC before operation: completed in 85.278 ms, heap usage 109.297 MB -> 67.249 MB. [2024-12-06T22:35:06.233Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2081.166 ms) ====== [2024-12-06T22:35:06.234Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-12-06T22:35:06.234Z] GC before operation: completed in 95.233 ms, heap usage 111.342 MB -> 67.239 MB. [2024-12-06T22:35:08.706Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2053.800 ms) ====== [2024-12-06T22:35:08.706Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-12-06T22:35:08.706Z] GC before operation: completed in 100.729 ms, heap usage 161.946 MB -> 67.748 MB. [2024-12-06T22:35:10.302Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1949.276 ms) ====== [2024-12-06T22:35:10.302Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-12-06T22:35:11.073Z] GC before operation: completed in 102.693 ms, heap usage 162.367 MB -> 67.806 MB. [2024-12-06T22:35:12.667Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1899.341 ms) ====== [2024-12-06T22:35:12.667Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-12-06T22:35:12.667Z] GC before operation: completed in 109.621 ms, heap usage 149.837 MB -> 68.363 MB. [2024-12-06T22:35:14.277Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1871.267 ms) ====== [2024-12-06T22:35:14.277Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-12-06T22:35:15.054Z] GC before operation: completed in 118.457 ms, heap usage 116.566 MB -> 68.084 MB. [2024-12-06T22:35:16.665Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1818.063 ms) ====== [2024-12-06T22:35:16.665Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-12-06T22:35:16.665Z] GC before operation: completed in 123.276 ms, heap usage 220.725 MB -> 68.752 MB. [2024-12-06T22:35:18.278Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1822.761 ms) ====== [2024-12-06T22:35:18.278Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-12-06T22:35:18.278Z] GC before operation: completed in 111.142 ms, heap usage 196.035 MB -> 69.074 MB. [2024-12-06T22:35:20.762Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1785.146 ms) ====== [2024-12-06T22:35:20.762Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-12-06T22:35:20.762Z] GC before operation: completed in 115.645 ms, heap usage 178.844 MB -> 68.728 MB. [2024-12-06T22:35:22.357Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1731.058 ms) ====== [2024-12-06T22:35:22.357Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-12-06T22:35:22.357Z] GC before operation: completed in 117.216 ms, heap usage 240.843 MB -> 80.226 MB. [2024-12-06T22:35:23.955Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1752.546 ms) ====== [2024-12-06T22:35:23.955Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-12-06T22:35:23.955Z] GC before operation: completed in 133.743 ms, heap usage 235.385 MB -> 69.065 MB. [2024-12-06T22:35:26.437Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1800.830 ms) ====== [2024-12-06T22:35:26.437Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-12-06T22:35:26.437Z] GC before operation: completed in 123.177 ms, heap usage 164.796 MB -> 69.063 MB. [2024-12-06T22:35:28.034Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1716.337 ms) ====== [2024-12-06T22:35:28.034Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-12-06T22:35:28.034Z] GC before operation: completed in 125.049 ms, heap usage 313.696 MB -> 69.439 MB. [2024-12-06T22:35:29.637Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1736.703 ms) ====== [2024-12-06T22:35:29.637Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-12-06T22:35:29.637Z] GC before operation: completed in 135.755 ms, heap usage 199.722 MB -> 69.398 MB. [2024-12-06T22:35:31.228Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1735.109 ms) ====== [2024-12-06T22:35:31.228Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-12-06T22:35:31.996Z] GC before operation: completed in 152.891 ms, heap usage 234.651 MB -> 69.714 MB. [2024-12-06T22:35:33.585Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1694.124 ms) ====== [2024-12-06T22:35:33.585Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-12-06T22:35:33.585Z] GC before operation: completed in 134.683 ms, heap usage 165.953 MB -> 69.401 MB. [2024-12-06T22:35:35.183Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1670.871 ms) ====== [2024-12-06T22:35:35.183Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-12-06T22:35:35.183Z] GC before operation: completed in 129.463 ms, heap usage 260.733 MB -> 70.139 MB. [2024-12-06T22:35:36.814Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1692.567 ms) ====== [2024-12-06T22:35:36.814Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-12-06T22:35:37.594Z] GC before operation: completed in 135.185 ms, heap usage 287.079 MB -> 75.449 MB. [2024-12-06T22:35:39.185Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1708.058 ms) ====== [2024-12-06T22:35:39.185Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-12-06T22:35:39.185Z] GC before operation: completed in 140.956 ms, heap usage 240.798 MB -> 80.988 MB. [2024-12-06T22:35:40.790Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1694.672 ms) ====== [2024-12-06T22:35:40.790Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-12-06T22:35:40.790Z] GC before operation: completed in 140.639 ms, heap usage 247.532 MB -> 70.425 MB. [2024-12-06T22:35:42.394Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1675.770 ms) ====== [2024-12-06T22:35:42.394Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-12-06T22:35:42.394Z] GC before operation: completed in 129.530 ms, heap usage 114.191 MB -> 69.749 MB. [2024-12-06T22:35:44.878Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1678.515 ms) ====== [2024-12-06T22:35:44.878Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-12-06T22:35:44.878Z] GC before operation: completed in 140.104 ms, heap usage 209.636 MB -> 81.586 MB. [2024-12-06T22:35:46.467Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1678.936 ms) ====== [2024-12-06T22:35:46.467Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-12-06T22:35:46.467Z] GC before operation: completed in 139.560 ms, heap usage 225.668 MB -> 70.790 MB. [2024-12-06T22:35:48.059Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1727.443 ms) ====== [2024-12-06T22:35:48.059Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-12-06T22:35:48.059Z] GC before operation: completed in 148.829 ms, heap usage 211.156 MB -> 70.870 MB. [2024-12-06T22:35:49.652Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1652.614 ms) ====== [2024-12-06T22:35:49.652Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-12-06T22:35:50.422Z] GC before operation: completed in 172.545 ms, heap usage 188.956 MB -> 98.170 MB. [2024-12-06T22:35:52.021Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1677.329 ms) ====== [2024-12-06T22:35:52.021Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-12-06T22:35:52.021Z] GC before operation: completed in 132.415 ms, heap usage 309.044 MB -> 81.707 MB. [2024-12-06T22:35:53.616Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1680.998 ms) ====== [2024-12-06T22:35:53.616Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-12-06T22:35:53.616Z] GC before operation: completed in 150.675 ms, heap usage 306.221 MB -> 76.329 MB. [2024-12-06T22:35:55.209Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1654.712 ms) ====== [2024-12-06T22:35:55.209Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-12-06T22:35:55.209Z] GC before operation: completed in 138.004 ms, heap usage 232.649 MB -> 71.269 MB. [2024-12-06T22:35:56.808Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1645.015 ms) ====== [2024-12-06T22:35:56.808Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-12-06T22:35:57.578Z] GC before operation: completed in 175.056 ms, heap usage 212.903 MB -> 82.027 MB. [2024-12-06T22:35:59.182Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1716.144 ms) ====== [2024-12-06T22:35:59.182Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-12-06T22:35:59.182Z] GC before operation: completed in 131.231 ms, heap usage 313.336 MB -> 81.939 MB. [2024-12-06T22:36:00.778Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1650.611 ms) ====== [2024-12-06T22:36:00.778Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-12-06T22:36:00.778Z] GC before operation: completed in 146.749 ms, heap usage 214.726 MB -> 71.150 MB. [2024-12-06T22:36:02.582Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1645.298 ms) ====== [2024-12-06T22:36:02.582Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-12-06T22:36:02.582Z] GC before operation: completed in 149.606 ms, heap usage 179.017 MB -> 71.121 MB. [2024-12-06T22:36:04.185Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1612.176 ms) ====== [2024-12-06T22:36:04.185Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-12-06T22:36:04.185Z] GC before operation: completed in 155.349 ms, heap usage 262.016 MB -> 77.170 MB. [2024-12-06T22:36:04.185Z] 24/12/06 22:36:04 WARN BlockManager: Asked to remove block broadcast_470, which does not exist [2024-12-06T22:36:04.185Z] 24/12/06 22:36:04 WARN BlockManager: Asked to remove block broadcast_470_piece0, which does not exist [2024-12-06T22:36:06.665Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1667.341 ms) ====== [2024-12-06T22:36:06.665Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-12-06T22:36:06.666Z] GC before operation: completed in 158.962 ms, heap usage 275.210 MB -> 71.468 MB. [2024-12-06T22:36:08.273Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1653.050 ms) ====== [2024-12-06T22:36:08.273Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-12-06T22:36:08.273Z] GC before operation: completed in 137.555 ms, heap usage 110.425 MB -> 82.038 MB. [2024-12-06T22:36:09.874Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1648.483 ms) ====== [2024-12-06T22:36:09.874Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-12-06T22:36:09.874Z] GC before operation: completed in 153.784 ms, heap usage 242.917 MB -> 82.950 MB. [2024-12-06T22:36:11.467Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1657.334 ms) ====== [2024-12-06T22:36:11.467Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-12-06T22:36:11.467Z] GC before operation: completed in 181.138 ms, heap usage 130.741 MB -> 71.340 MB. [2024-12-06T22:36:13.069Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1603.895 ms) ====== [2024-12-06T22:36:13.069Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-12-06T22:36:13.842Z] GC before operation: completed in 183.541 ms, heap usage 224.745 MB -> 82.887 MB. [2024-12-06T22:36:15.442Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1658.249 ms) ====== [2024-12-06T22:36:15.442Z] ----------------------------------- [2024-12-06T22:36:15.442Z] renaissance-dec-tree_0_PASSED [2024-12-06T22:36:15.442Z] ----------------------------------- [2024-12-06T22:36:15.442Z] [2024-12-06T22:36:15.442Z] TEST TEARDOWN: [2024-12-06T22:36:15.442Z] Nothing to be done for teardown. [2024-12-06T22:36:15.442Z] renaissance-dec-tree_0 Finish Time: Fri Dec 6 22:36:15 2024 Epoch Time (ms): 1733524575096