[2024-08-22T10:39:44.986Z] Running test renaissance-dec-tree_0 ... [2024-08-22T10:39:44.986Z] =============================================== [2024-08-22T10:39:44.986Z] renaissance-dec-tree_0 Start Time: Thu Aug 22 10:39:44 2024 Epoch Time (ms): 1724323184647 [2024-08-22T10:39:44.986Z] variation: NoOptions [2024-08-22T10:39:44.986Z] JVM_OPTIONS: [2024-08-22T10:39:44.986Z] { \ [2024-08-22T10:39:44.986Z] echo ""; echo "TEST SETUP:"; \ [2024-08-22T10:39:44.986Z] echo "Nothing to be done for setup."; \ [2024-08-22T10:39:44.986Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17243227179404/renaissance-dec-tree_0"; \ [2024-08-22T10:39:44.986Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17243227179404/renaissance-dec-tree_0"; \ [2024-08-22T10:39:44.986Z] echo ""; echo "TESTING:"; \ [2024-08-22T10:39:44.987Z] "/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_17243227179404/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-22T10:39:44.987Z] 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_17243227179404/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-22T10:39:44.987Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-22T10:39:44.987Z] echo "Nothing to be done for teardown."; \ [2024-08-22T10:39:44.987Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17243227179404/TestTargetResult"; [2024-08-22T10:39:44.987Z] [2024-08-22T10:39:44.987Z] TEST SETUP: [2024-08-22T10:39:44.987Z] Nothing to be done for setup. [2024-08-22T10:39:44.987Z] [2024-08-22T10:39:44.987Z] TESTING: [2024-08-22T10:39:49.836Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-22T10:39:52.311Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2024-08-22T10:39:53.906Z] WARNING: An illegal reflective access operation has occurred [2024-08-22T10:39:53.906Z] 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_17243227179404/renaissance-dec-tree_0/harness-103945-2424496763996164828/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-22T10:39:53.906Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-22T10:39:53.906Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-22T10:39:53.906Z] WARNING: All illegal access operations will be denied in a future release [2024-08-22T10:39:57.338Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-22T10:39:57.338Z] GC before operation: completed in 77.149 ms, heap usage 61.617 MB -> 35.235 MB. [2024-08-22T10:40:07.264Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9564.662 ms) ====== [2024-08-22T10:40:07.264Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-22T10:40:07.264Z] GC before operation: completed in 87.050 ms, heap usage 259.484 MB -> 67.203 MB. [2024-08-22T10:40:09.748Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2565.210 ms) ====== [2024-08-22T10:40:09.748Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-22T10:40:09.748Z] GC before operation: completed in 107.828 ms, heap usage 116.979 MB -> 67.241 MB. [2024-08-22T10:40:11.348Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2191.201 ms) ====== [2024-08-22T10:40:11.348Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-22T10:40:12.119Z] GC before operation: completed in 99.523 ms, heap usage 103.581 MB -> 67.232 MB. [2024-08-22T10:40:13.729Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2097.626 ms) ====== [2024-08-22T10:40:13.729Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-22T10:40:13.729Z] GC before operation: completed in 107.215 ms, heap usage 197.435 MB -> 68.021 MB. [2024-08-22T10:40:16.208Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2068.777 ms) ====== [2024-08-22T10:40:16.208Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-22T10:40:16.208Z] GC before operation: completed in 98.446 ms, heap usage 214.849 MB -> 68.097 MB. [2024-08-22T10:40:18.685Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2025.067 ms) ====== [2024-08-22T10:40:18.685Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-22T10:40:18.685Z] GC before operation: completed in 112.293 ms, heap usage 201.015 MB -> 95.914 MB. [2024-08-22T10:40:20.278Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1947.509 ms) ====== [2024-08-22T10:40:20.278Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-22T10:40:20.278Z] GC before operation: completed in 117.833 ms, heap usage 317.126 MB -> 68.664 MB. [2024-08-22T10:40:22.758Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1892.491 ms) ====== [2024-08-22T10:40:22.758Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-22T10:40:22.758Z] GC before operation: completed in 105.704 ms, heap usage 292.547 MB -> 68.628 MB. [2024-08-22T10:40:24.357Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1885.618 ms) ====== [2024-08-22T10:40:24.357Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-22T10:40:24.357Z] GC before operation: completed in 116.659 ms, heap usage 164.843 MB -> 68.607 MB. [2024-08-22T10:40:25.965Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1945.676 ms) ====== [2024-08-22T10:40:25.965Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-22T10:40:26.744Z] GC before operation: completed in 115.665 ms, heap usage 227.298 MB -> 68.868 MB. [2024-08-22T10:40:28.339Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1842.329 ms) ====== [2024-08-22T10:40:28.339Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-22T10:40:28.339Z] GC before operation: completed in 123.643 ms, heap usage 152.363 MB -> 68.832 MB. [2024-08-22T10:40:29.939Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1822.913 ms) ====== [2024-08-22T10:40:29.939Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-22T10:40:30.712Z] GC before operation: completed in 130.937 ms, heap usage 221.088 MB -> 69.085 MB. [2024-08-22T10:40:32.326Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1830.987 ms) ====== [2024-08-22T10:40:32.326Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-22T10:40:32.326Z] GC before operation: completed in 122.274 ms, heap usage 214.298 MB -> 69.202 MB. [2024-08-22T10:40:33.920Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1831.469 ms) ====== [2024-08-22T10:40:33.920Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-22T10:40:33.920Z] GC before operation: completed in 136.058 ms, heap usage 204.569 MB -> 69.451 MB. [2024-08-22T10:40:36.394Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1791.874 ms) ====== [2024-08-22T10:40:36.394Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-22T10:40:36.394Z] GC before operation: completed in 124.718 ms, heap usage 206.461 MB -> 69.560 MB. [2024-08-22T10:40:37.988Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1836.500 ms) ====== [2024-08-22T10:40:37.988Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-22T10:40:37.988Z] GC before operation: completed in 129.922 ms, heap usage 199.844 MB -> 69.723 MB. [2024-08-22T10:40:39.589Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1774.494 ms) ====== [2024-08-22T10:40:39.589Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-22T10:40:40.362Z] GC before operation: completed in 139.748 ms, heap usage 269.043 MB -> 69.676 MB. [2024-08-22T10:40:41.958Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1767.615 ms) ====== [2024-08-22T10:40:41.958Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-22T10:40:41.958Z] GC before operation: completed in 134.604 ms, heap usage 253.523 MB -> 70.143 MB. [2024-08-22T10:40:43.557Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1792.475 ms) ====== [2024-08-22T10:40:43.557Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-22T10:40:44.330Z] GC before operation: completed in 158.994 ms, heap usage 266.881 MB -> 69.869 MB. [2024-08-22T10:40:45.928Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1818.212 ms) ====== [2024-08-22T10:40:45.928Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-22T10:40:45.928Z] GC before operation: completed in 138.431 ms, heap usage 246.474 MB -> 70.367 MB. [2024-08-22T10:40:45.928Z] 24/08/22 10:40:45 WARN BlockManager: Asked to remove block broadcast_274, which does not exist [2024-08-22T10:40:47.522Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1820.417 ms) ====== [2024-08-22T10:40:47.522Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-22T10:40:47.522Z] GC before operation: completed in 141.253 ms, heap usage 367.991 MB -> 70.472 MB. [2024-08-22T10:40:50.022Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1799.947 ms) ====== [2024-08-22T10:40:50.022Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-22T10:40:50.022Z] GC before operation: completed in 155.129 ms, heap usage 292.937 MB -> 75.772 MB. [2024-08-22T10:40:51.618Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1846.448 ms) ====== [2024-08-22T10:40:51.618Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-22T10:40:51.618Z] GC before operation: completed in 152.884 ms, heap usage 321.262 MB -> 81.598 MB. [2024-08-22T10:40:51.618Z] 24/08/22 10:40:51 WARN BlockManager: Asked to remove block broadcast_316_piece0, which does not exist [2024-08-22T10:40:54.096Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1792.224 ms) ====== [2024-08-22T10:40:54.096Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-22T10:40:54.096Z] GC before operation: completed in 161.319 ms, heap usage 211.274 MB -> 70.387 MB. [2024-08-22T10:40:55.694Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1750.821 ms) ====== [2024-08-22T10:40:55.694Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-22T10:40:55.694Z] GC before operation: completed in 150.734 ms, heap usage 228.762 MB -> 70.848 MB. [2024-08-22T10:40:57.468Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1731.192 ms) ====== [2024-08-22T10:40:57.468Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-22T10:40:57.468Z] GC before operation: completed in 142.280 ms, heap usage 242.353 MB -> 81.879 MB. [2024-08-22T10:40:59.066Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1763.691 ms) ====== [2024-08-22T10:40:59.067Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-22T10:40:59.864Z] GC before operation: completed in 139.201 ms, heap usage 271.928 MB -> 71.051 MB. [2024-08-22T10:41:01.471Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1760.971 ms) ====== [2024-08-22T10:41:01.471Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-22T10:41:01.471Z] GC before operation: completed in 137.257 ms, heap usage 220.885 MB -> 76.195 MB. [2024-08-22T10:41:03.069Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1797.562 ms) ====== [2024-08-22T10:41:03.069Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-22T10:41:03.069Z] GC before operation: completed in 144.236 ms, heap usage 121.561 MB -> 70.671 MB. [2024-08-22T10:41:05.549Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1754.865 ms) ====== [2024-08-22T10:41:05.549Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-22T10:41:05.549Z] GC before operation: completed in 142.941 ms, heap usage 119.695 MB -> 70.840 MB. [2024-08-22T10:41:07.157Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1779.017 ms) ====== [2024-08-22T10:41:07.157Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-22T10:41:07.157Z] GC before operation: completed in 187.371 ms, heap usage 163.699 MB -> 70.793 MB. [2024-08-22T10:41:08.756Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1759.432 ms) ====== [2024-08-22T10:41:08.756Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-22T10:41:08.756Z] GC before operation: completed in 144.848 ms, heap usage 182.822 MB -> 71.292 MB. [2024-08-22T10:41:11.233Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1730.509 ms) ====== [2024-08-22T10:41:11.233Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-22T10:41:11.233Z] GC before operation: completed in 149.595 ms, heap usage 139.100 MB -> 70.963 MB. [2024-08-22T10:41:12.847Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1732.853 ms) ====== [2024-08-22T10:41:12.847Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-22T10:41:12.847Z] GC before operation: completed in 148.700 ms, heap usage 179.567 MB -> 71.241 MB. [2024-08-22T10:41:14.452Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1796.547 ms) ====== [2024-08-22T10:41:14.452Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-22T10:41:14.452Z] GC before operation: completed in 155.428 ms, heap usage 342.792 MB -> 77.020 MB. [2024-08-22T10:41:16.953Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1721.933 ms) ====== [2024-08-22T10:41:16.953Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-22T10:41:16.953Z] GC before operation: completed in 153.173 ms, heap usage 308.729 MB -> 71.929 MB. [2024-08-22T10:41:18.562Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1771.310 ms) ====== [2024-08-22T10:41:18.562Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-22T10:41:18.562Z] GC before operation: completed in 182.732 ms, heap usage 196.547 MB -> 72.071 MB. [2024-08-22T10:41:20.167Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1726.381 ms) ====== [2024-08-22T10:41:20.167Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-22T10:41:20.167Z] GC before operation: completed in 187.150 ms, heap usage 166.067 MB -> 99.092 MB. [2024-08-22T10:41:22.641Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1692.578 ms) ====== [2024-08-22T10:41:22.641Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-22T10:41:22.641Z] GC before operation: completed in 165.989 ms, heap usage 248.168 MB -> 72.224 MB. [2024-08-22T10:41:24.233Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1805.454 ms) ====== [2024-08-22T10:41:24.233Z] ----------------------------------- [2024-08-22T10:41:24.233Z] renaissance-dec-tree_0_PASSED [2024-08-22T10:41:24.233Z] ----------------------------------- [2024-08-22T10:41:24.233Z] [2024-08-22T10:41:24.233Z] TEST TEARDOWN: [2024-08-22T10:41:24.233Z] Nothing to be done for teardown. [2024-08-22T10:41:24.233Z] renaissance-dec-tree_0 Finish Time: Thu Aug 22 10:41:24 2024 Epoch Time (ms): 1724323284057