[2024-08-23T20:53:15.486Z] Running test renaissance-dec-tree_0 ... [2024-08-23T20:53:15.486Z] =============================================== [2024-08-23T20:53:15.486Z] renaissance-dec-tree_0 Start Time: Fri Aug 23 20:53:14 2024 Epoch Time (ms): 1724446394885 [2024-08-23T20:53:15.486Z] variation: NoOptions [2024-08-23T20:53:15.486Z] JVM_OPTIONS: [2024-08-23T20:53:15.486Z] { \ [2024-08-23T20:53:15.486Z] echo ""; echo "TEST SETUP:"; \ [2024-08-23T20:53:15.486Z] echo "Nothing to be done for setup."; \ [2024-08-23T20:53:15.486Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17244458861675/renaissance-dec-tree_0"; \ [2024-08-23T20:53:15.486Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17244458861675/renaissance-dec-tree_0"; \ [2024-08-23T20:53:15.486Z] echo ""; echo "TESTING:"; \ [2024-08-23T20:53:15.486Z] "/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_17244458861675/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-23T20:53:15.486Z] 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_17244458861675/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-23T20:53:15.486Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-23T20:53:15.486Z] echo "Nothing to be done for teardown."; \ [2024-08-23T20:53:15.486Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17244458861675/TestTargetResult"; [2024-08-23T20:53:15.486Z] [2024-08-23T20:53:15.486Z] TEST SETUP: [2024-08-23T20:53:15.486Z] Nothing to be done for setup. [2024-08-23T20:53:15.486Z] [2024-08-23T20:53:15.486Z] TESTING: [2024-08-23T20:53:18.429Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-23T20:53:20.338Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-23T20:53:21.266Z] WARNING: An illegal reflective access operation has occurred [2024-08-23T20:53:21.266Z] 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_17244458861675/renaissance-dec-tree_0/harness-205315-14044671007696742852/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-23T20:53:21.266Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-23T20:53:21.266Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-23T20:53:21.266Z] WARNING: All illegal access operations will be denied in a future release [2024-08-23T20:53:24.213Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-23T20:53:24.213Z] GC before operation: completed in 41.663 ms, heap usage 62.285 MB -> 33.759 MB. [2024-08-23T20:53:32.192Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7905.418 ms) ====== [2024-08-23T20:53:32.192Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-23T20:53:32.192Z] GC before operation: completed in 129.982 ms, heap usage 357.820 MB -> 64.072 MB. [2024-08-23T20:53:34.107Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2377.799 ms) ====== [2024-08-23T20:53:34.107Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-23T20:53:35.035Z] GC before operation: completed in 122.329 ms, heap usage 213.637 MB -> 63.995 MB. [2024-08-23T20:53:36.957Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1961.841 ms) ====== [2024-08-23T20:53:36.957Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-23T20:53:36.957Z] GC before operation: completed in 96.353 ms, heap usage 192.753 MB -> 64.411 MB. [2024-08-23T20:53:38.864Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1852.864 ms) ====== [2024-08-23T20:53:38.864Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-23T20:53:38.864Z] GC before operation: completed in 98.884 ms, heap usage 254.127 MB -> 64.492 MB. [2024-08-23T20:53:40.775Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1745.104 ms) ====== [2024-08-23T20:53:40.775Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-23T20:53:40.775Z] GC before operation: completed in 109.468 ms, heap usage 139.961 MB -> 64.419 MB. [2024-08-23T20:53:42.681Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1726.255 ms) ====== [2024-08-23T20:53:42.681Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-23T20:53:42.681Z] GC before operation: completed in 95.730 ms, heap usage 106.866 MB -> 64.336 MB. [2024-08-23T20:53:44.565Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1688.122 ms) ====== [2024-08-23T20:53:44.565Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-23T20:53:44.565Z] GC before operation: completed in 97.152 ms, heap usage 146.862 MB -> 64.815 MB. [2024-08-23T20:53:45.492Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1666.296 ms) ====== [2024-08-23T20:53:45.492Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-23T20:53:45.492Z] GC before operation: completed in 100.132 ms, heap usage 254.120 MB -> 65.371 MB. [2024-08-23T20:53:47.404Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1657.551 ms) ====== [2024-08-23T20:53:47.404Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-23T20:53:47.404Z] GC before operation: completed in 98.493 ms, heap usage 239.554 MB -> 65.476 MB. [2024-08-23T20:53:49.316Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1593.625 ms) ====== [2024-08-23T20:53:49.316Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-23T20:53:49.316Z] GC before operation: completed in 99.551 ms, heap usage 201.790 MB -> 65.593 MB. [2024-08-23T20:53:51.226Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1604.228 ms) ====== [2024-08-23T20:53:51.226Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-23T20:53:51.226Z] GC before operation: completed in 100.535 ms, heap usage 153.571 MB -> 65.256 MB. [2024-08-23T20:53:53.180Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2201.208 ms) ====== [2024-08-23T20:53:53.180Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-23T20:53:53.180Z] GC before operation: completed in 99.887 ms, heap usage 261.500 MB -> 66.012 MB. [2024-08-23T20:53:55.101Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1583.895 ms) ====== [2024-08-23T20:53:55.101Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-23T20:53:55.101Z] GC before operation: completed in 97.251 ms, heap usage 158.835 MB -> 65.906 MB. [2024-08-23T20:53:57.016Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1558.406 ms) ====== [2024-08-23T20:53:57.016Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-23T20:53:57.016Z] GC before operation: completed in 100.976 ms, heap usage 164.843 MB -> 65.603 MB. [2024-08-23T20:53:57.943Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1623.930 ms) ====== [2024-08-23T20:53:57.943Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-23T20:53:57.943Z] GC before operation: completed in 102.965 ms, heap usage 222.198 MB -> 66.126 MB. [2024-08-23T20:53:59.847Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1632.817 ms) ====== [2024-08-23T20:53:59.847Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-23T20:53:59.847Z] GC before operation: completed in 102.104 ms, heap usage 173.224 MB -> 66.025 MB. [2024-08-23T20:54:01.759Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1570.437 ms) ====== [2024-08-23T20:54:01.759Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-23T20:54:01.759Z] GC before operation: completed in 106.798 ms, heap usage 131.419 MB -> 65.942 MB. [2024-08-23T20:54:03.663Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1531.304 ms) ====== [2024-08-23T20:54:03.663Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-23T20:54:03.663Z] GC before operation: completed in 107.547 ms, heap usage 255.925 MB -> 82.871 MB. [2024-08-23T20:54:04.590Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1551.034 ms) ====== [2024-08-23T20:54:04.590Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-23T20:54:05.526Z] GC before operation: completed in 106.766 ms, heap usage 225.068 MB -> 66.446 MB. [2024-08-23T20:54:06.452Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1587.304 ms) ====== [2024-08-23T20:54:06.452Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-23T20:54:06.452Z] GC before operation: completed in 108.637 ms, heap usage 192.460 MB -> 66.690 MB. [2024-08-23T20:54:08.373Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1509.914 ms) ====== [2024-08-23T20:54:08.373Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-23T20:54:08.373Z] GC before operation: completed in 105.198 ms, heap usage 277.023 MB -> 66.647 MB. [2024-08-23T20:54:10.276Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1599.283 ms) ====== [2024-08-23T20:54:10.276Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-23T20:54:10.276Z] GC before operation: completed in 107.214 ms, heap usage 280.805 MB -> 83.243 MB. [2024-08-23T20:54:12.181Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1564.782 ms) ====== [2024-08-23T20:54:12.181Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-23T20:54:12.181Z] GC before operation: completed in 129.573 ms, heap usage 264.127 MB -> 66.891 MB. [2024-08-23T20:54:13.111Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1604.382 ms) ====== [2024-08-23T20:54:13.111Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-23T20:54:13.111Z] GC before operation: completed in 132.386 ms, heap usage 128.894 MB -> 72.786 MB. [2024-08-23T20:54:15.015Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1578.254 ms) ====== [2024-08-23T20:54:15.015Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-23T20:54:15.015Z] GC before operation: completed in 126.735 ms, heap usage 154.296 MB -> 66.793 MB. [2024-08-23T20:54:16.923Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1560.619 ms) ====== [2024-08-23T20:54:16.923Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-23T20:54:16.923Z] GC before operation: completed in 133.535 ms, heap usage 100.857 MB -> 66.766 MB. [2024-08-23T20:54:18.831Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1553.588 ms) ====== [2024-08-23T20:54:18.831Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-23T20:54:18.831Z] GC before operation: completed in 120.327 ms, heap usage 125.980 MB -> 67.141 MB. [2024-08-23T20:54:19.758Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1578.145 ms) ====== [2024-08-23T20:54:19.758Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-23T20:54:20.685Z] GC before operation: completed in 121.642 ms, heap usage 209.971 MB -> 67.545 MB. [2024-08-23T20:54:21.613Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1566.860 ms) ====== [2024-08-23T20:54:21.613Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-23T20:54:21.613Z] GC before operation: completed in 125.637 ms, heap usage 213.470 MB -> 78.595 MB. [2024-08-23T20:54:23.519Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1559.715 ms) ====== [2024-08-23T20:54:23.519Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-23T20:54:23.519Z] GC before operation: completed in 121.877 ms, heap usage 305.262 MB -> 78.877 MB. [2024-08-23T20:54:25.423Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1532.992 ms) ====== [2024-08-23T20:54:25.423Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-23T20:54:25.423Z] GC before operation: completed in 120.966 ms, heap usage 159.520 MB -> 78.465 MB. [2024-08-23T20:54:27.331Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1527.628 ms) ====== [2024-08-23T20:54:27.331Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-23T20:54:27.331Z] GC before operation: completed in 116.841 ms, heap usage 318.486 MB -> 67.782 MB. [2024-08-23T20:54:28.258Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1525.832 ms) ====== [2024-08-23T20:54:28.258Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-23T20:54:28.258Z] GC before operation: completed in 116.548 ms, heap usage 132.279 MB -> 67.743 MB. [2024-08-23T20:54:30.186Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1582.757 ms) ====== [2024-08-23T20:54:30.186Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-23T20:54:30.186Z] GC before operation: completed in 117.269 ms, heap usage 149.414 MB -> 73.498 MB. [2024-08-23T20:54:32.092Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1530.139 ms) ====== [2024-08-23T20:54:32.092Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-23T20:54:32.092Z] GC before operation: completed in 119.053 ms, heap usage 257.668 MB -> 68.081 MB. [2024-08-23T20:54:33.998Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1520.308 ms) ====== [2024-08-23T20:54:33.998Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-23T20:54:33.998Z] GC before operation: completed in 114.693 ms, heap usage 115.831 MB -> 78.918 MB. [2024-08-23T20:54:34.930Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1509.485 ms) ====== [2024-08-23T20:54:34.930Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-23T20:54:34.930Z] GC before operation: completed in 116.471 ms, heap usage 264.818 MB -> 68.425 MB. [2024-08-23T20:54:36.838Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1497.425 ms) ====== [2024-08-23T20:54:36.838Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-23T20:54:36.838Z] GC before operation: completed in 117.312 ms, heap usage 101.463 MB -> 67.961 MB. [2024-08-23T20:54:39.919Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1528.454 ms) ====== [2024-08-23T20:54:39.919Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-23T20:54:39.919Z] GC before operation: completed in 118.723 ms, heap usage 201.399 MB -> 79.763 MB. [2024-08-23T20:54:39.919Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1500.119 ms) ====== [2024-08-23T20:54:39.919Z] ----------------------------------- [2024-08-23T20:54:39.919Z] renaissance-dec-tree_0_PASSED [2024-08-23T20:54:39.919Z] ----------------------------------- [2024-08-23T20:54:39.919Z] [2024-08-23T20:54:39.919Z] TEST TEARDOWN: [2024-08-23T20:54:39.919Z] Nothing to be done for teardown. [2024-08-23T20:54:39.919Z] renaissance-dec-tree_0 Finish Time: Fri Aug 23 20:54:39 2024 Epoch Time (ms): 1724446479724