[2024-10-02T21:00:27.966Z] Running test renaissance-dec-tree_0 ... [2024-10-02T21:00:27.966Z] =============================================== [2024-10-02T21:00:27.966Z] renaissance-dec-tree_0 Start Time: Wed Oct 2 21:00:27 2024 Epoch Time (ms): 1727902827214 [2024-10-02T21:00:27.966Z] variation: NoOptions [2024-10-02T21:00:27.966Z] JVM_OPTIONS: [2024-10-02T21:00:27.966Z] { \ [2024-10-02T21:00:27.966Z] echo ""; echo "TEST SETUP:"; \ [2024-10-02T21:00:27.966Z] echo "Nothing to be done for setup."; \ [2024-10-02T21:00:27.966Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/renaissance-dec-tree_0"; \ [2024-10-02T21:00:27.966Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/renaissance-dec-tree_0"; \ [2024-10-02T21:00:27.966Z] echo ""; echo "TESTING:"; \ [2024-10-02T21:00:27.966Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-10-02T21:00:27.966Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-02T21:00:27.966Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-02T21:00:27.966Z] echo "Nothing to be done for teardown."; \ [2024-10-02T21:00:27.966Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17279023323367/TestTargetResult"; [2024-10-02T21:00:27.966Z] [2024-10-02T21:00:27.966Z] TEST SETUP: [2024-10-02T21:00:27.966Z] Nothing to be done for setup. [2024-10-02T21:00:27.966Z] [2024-10-02T21:00:27.966Z] TESTING: [2024-10-02T21:00:30.948Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-02T21:00:32.887Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-02T21:00:33.829Z] WARNING: An illegal reflective access operation has occurred [2024-10-02T21:00:33.829Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17279023323367/renaissance-dec-tree_0/harness-210027-8086424539159469184/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-10-02T21:00:33.829Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-10-02T21:00:33.829Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-10-02T21:00:33.829Z] WARNING: All illegal access operations will be denied in a future release [2024-10-02T21:00:36.817Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-10-02T21:00:36.817Z] GC before operation: completed in 50.901 ms, heap usage 64.766 MB -> 33.759 MB. [2024-10-02T21:00:44.904Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7820.661 ms) ====== [2024-10-02T21:00:44.904Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-10-02T21:00:44.904Z] GC before operation: completed in 93.929 ms, heap usage 223.209 MB -> 63.641 MB. [2024-10-02T21:00:46.840Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2262.866 ms) ====== [2024-10-02T21:00:46.840Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-10-02T21:00:46.840Z] GC before operation: completed in 87.156 ms, heap usage 213.420 MB -> 64.109 MB. [2024-10-02T21:00:48.781Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1908.895 ms) ====== [2024-10-02T21:00:48.781Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-10-02T21:00:48.781Z] GC before operation: completed in 100.997 ms, heap usage 166.226 MB -> 64.116 MB. [2024-10-02T21:00:50.720Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1795.933 ms) ====== [2024-10-02T21:00:50.720Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-10-02T21:00:50.720Z] GC before operation: completed in 88.345 ms, heap usage 229.232 MB -> 64.626 MB. [2024-10-02T21:00:52.656Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1792.016 ms) ====== [2024-10-02T21:00:52.656Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-10-02T21:00:52.656Z] GC before operation: completed in 102.455 ms, heap usage 270.547 MB -> 92.301 MB. [2024-10-02T21:00:54.592Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1747.639 ms) ====== [2024-10-02T21:00:54.592Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-10-02T21:00:54.592Z] GC before operation: completed in 94.038 ms, heap usage 289.148 MB -> 64.912 MB. [2024-10-02T21:00:56.527Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1699.495 ms) ====== [2024-10-02T21:00:56.527Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-10-02T21:00:56.527Z] GC before operation: completed in 94.759 ms, heap usage 206.840 MB -> 65.265 MB. [2024-10-02T21:00:57.586Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1734.387 ms) ====== [2024-10-02T21:00:57.586Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-10-02T21:00:58.528Z] GC before operation: completed in 96.478 ms, heap usage 125.763 MB -> 65.364 MB. [2024-10-02T21:00:59.497Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1633.123 ms) ====== [2024-10-02T21:00:59.497Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-10-02T21:00:59.498Z] GC before operation: completed in 101.865 ms, heap usage 106.948 MB -> 64.938 MB. [2024-10-02T21:01:01.444Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1672.909 ms) ====== [2024-10-02T21:01:01.444Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-10-02T21:01:01.444Z] GC before operation: completed in 104.781 ms, heap usage 138.615 MB -> 65.314 MB. [2024-10-02T21:01:03.381Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1678.844 ms) ====== [2024-10-02T21:01:03.381Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-10-02T21:01:03.381Z] GC before operation: completed in 97.822 ms, heap usage 159.098 MB -> 65.442 MB. [2024-10-02T21:01:05.336Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1605.934 ms) ====== [2024-10-02T21:01:05.336Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-10-02T21:01:05.336Z] GC before operation: completed in 116.598 ms, heap usage 105.261 MB -> 76.283 MB. [2024-10-02T21:01:07.275Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1671.579 ms) ====== [2024-10-02T21:01:07.275Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-10-02T21:01:07.275Z] GC before operation: completed in 125.301 ms, heap usage 190.986 MB -> 65.933 MB. [2024-10-02T21:01:08.218Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1733.766 ms) ====== [2024-10-02T21:01:08.218Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-10-02T21:01:09.171Z] GC before operation: completed in 111.180 ms, heap usage 112.887 MB -> 65.337 MB. [2024-10-02T21:01:10.113Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1674.212 ms) ====== [2024-10-02T21:01:10.113Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-10-02T21:01:10.113Z] GC before operation: completed in 108.476 ms, heap usage 186.891 MB -> 66.152 MB. [2024-10-02T21:01:12.068Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1652.982 ms) ====== [2024-10-02T21:01:12.068Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-10-02T21:01:12.068Z] GC before operation: completed in 111.771 ms, heap usage 168.114 MB -> 66.060 MB. [2024-10-02T21:01:14.010Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1629.198 ms) ====== [2024-10-02T21:01:14.010Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-10-02T21:01:14.010Z] GC before operation: completed in 113.028 ms, heap usage 185.404 MB -> 66.267 MB. [2024-10-02T21:01:15.946Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1615.343 ms) ====== [2024-10-02T21:01:15.946Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-10-02T21:01:15.946Z] GC before operation: completed in 107.436 ms, heap usage 110.508 MB -> 65.952 MB. [2024-10-02T21:01:17.886Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1604.161 ms) ====== [2024-10-02T21:01:17.886Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-10-02T21:01:17.886Z] GC before operation: completed in 105.506 ms, heap usage 224.650 MB -> 83.175 MB. [2024-10-02T21:01:20.037Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1577.244 ms) ====== [2024-10-02T21:01:20.037Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-10-02T21:01:20.037Z] GC before operation: completed in 119.605 ms, heap usage 269.301 MB -> 66.742 MB. [2024-10-02T21:01:21.412Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1579.571 ms) ====== [2024-10-02T21:01:21.413Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-10-02T21:01:21.413Z] GC before operation: completed in 111.265 ms, heap usage 170.023 MB -> 72.248 MB. [2024-10-02T21:01:22.581Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1540.894 ms) ====== [2024-10-02T21:01:22.581Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-10-02T21:01:22.581Z] GC before operation: completed in 112.801 ms, heap usage 291.367 MB -> 66.806 MB. [2024-10-02T21:01:24.517Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1548.342 ms) ====== [2024-10-02T21:01:24.517Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-10-02T21:01:24.517Z] GC before operation: completed in 113.056 ms, heap usage 225.153 MB -> 66.920 MB. [2024-10-02T21:01:25.464Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1540.905 ms) ====== [2024-10-02T21:01:25.464Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-10-02T21:01:25.464Z] GC before operation: completed in 106.133 ms, heap usage 133.070 MB -> 72.541 MB. [2024-10-02T21:01:27.400Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1544.239 ms) ====== [2024-10-02T21:01:27.400Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-10-02T21:01:27.400Z] GC before operation: completed in 112.993 ms, heap usage 228.251 MB -> 67.265 MB. [2024-10-02T21:01:29.339Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1551.105 ms) ====== [2024-10-02T21:01:29.339Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-10-02T21:01:29.339Z] GC before operation: completed in 109.628 ms, heap usage 159.518 MB -> 66.949 MB. [2024-10-02T21:01:30.284Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1560.369 ms) ====== [2024-10-02T21:01:30.284Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-10-02T21:01:31.228Z] GC before operation: completed in 109.129 ms, heap usage 238.391 MB -> 67.724 MB. [2024-10-02T21:01:32.172Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1536.981 ms) ====== [2024-10-02T21:01:32.172Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-10-02T21:01:32.172Z] GC before operation: completed in 107.012 ms, heap usage 157.304 MB -> 67.149 MB. [2024-10-02T21:01:34.106Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1514.820 ms) ====== [2024-10-02T21:01:34.106Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-10-02T21:01:34.106Z] GC before operation: completed in 110.160 ms, heap usage 154.984 MB -> 67.215 MB. [2024-10-02T21:01:36.043Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1516.037 ms) ====== [2024-10-02T21:01:36.043Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-10-02T21:01:36.044Z] GC before operation: completed in 110.980 ms, heap usage 180.528 MB -> 67.598 MB. [2024-10-02T21:01:36.986Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1526.090 ms) ====== [2024-10-02T21:01:36.986Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-10-02T21:01:36.986Z] GC before operation: completed in 108.094 ms, heap usage 157.825 MB -> 67.569 MB. [2024-10-02T21:01:38.926Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1520.495 ms) ====== [2024-10-02T21:01:38.926Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-10-02T21:01:38.926Z] GC before operation: completed in 108.700 ms, heap usage 133.963 MB -> 67.505 MB. [2024-10-02T21:01:40.863Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1528.992 ms) ====== [2024-10-02T21:01:40.863Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-10-02T21:01:40.863Z] GC before operation: completed in 108.049 ms, heap usage 164.908 MB -> 67.769 MB. [2024-10-02T21:01:41.808Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1521.223 ms) ====== [2024-10-02T21:01:41.808Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-10-02T21:01:41.808Z] GC before operation: completed in 110.900 ms, heap usage 111.147 MB -> 67.575 MB. [2024-10-02T21:01:43.746Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1529.771 ms) ====== [2024-10-02T21:01:43.746Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-10-02T21:01:43.746Z] GC before operation: completed in 112.600 ms, heap usage 291.141 MB -> 84.612 MB. [2024-10-02T21:01:45.683Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1509.942 ms) ====== [2024-10-02T21:01:45.684Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-10-02T21:01:45.684Z] GC before operation: completed in 113.523 ms, heap usage 174.014 MB -> 67.918 MB. [2024-10-02T21:01:46.627Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1544.898 ms) ====== [2024-10-02T21:01:46.627Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-10-02T21:01:47.571Z] GC before operation: completed in 113.677 ms, heap usage 140.583 MB -> 68.144 MB. [2024-10-02T21:01:48.526Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1522.027 ms) ====== [2024-10-02T21:01:48.526Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-10-02T21:01:48.526Z] GC before operation: completed in 118.159 ms, heap usage 134.176 MB -> 73.510 MB. [2024-10-02T21:01:50.465Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1514.521 ms) ====== [2024-10-02T21:01:50.465Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-10-02T21:01:50.465Z] GC before operation: completed in 138.004 ms, heap usage 211.803 MB -> 79.769 MB. [2024-10-02T21:01:52.403Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1604.972 ms) ====== [2024-10-02T21:01:52.404Z] ----------------------------------- [2024-10-02T21:01:52.404Z] renaissance-dec-tree_0_PASSED [2024-10-02T21:01:52.404Z] ----------------------------------- [2024-10-02T21:01:52.404Z] [2024-10-02T21:01:52.404Z] TEST TEARDOWN: [2024-10-02T21:01:52.404Z] Nothing to be done for teardown. [2024-10-02T21:01:52.404Z] renaissance-dec-tree_0 Finish Time: Wed Oct 2 21:01:51 2024 Epoch Time (ms): 1727902911819