[2024-09-25T20:53:28.006Z] Running test renaissance-dec-tree_0 ... [2024-09-25T20:53:28.006Z] =============================================== [2024-09-25T20:53:28.006Z] renaissance-dec-tree_0 Start Time: Wed Sep 25 20:53:27 2024 Epoch Time (ms): 1727297607165 [2024-09-25T20:53:28.006Z] variation: NoOptions [2024-09-25T20:53:28.006Z] JVM_OPTIONS: [2024-09-25T20:53:28.006Z] { \ [2024-09-25T20:53:28.006Z] echo ""; echo "TEST SETUP:"; \ [2024-09-25T20:53:28.006Z] echo "Nothing to be done for setup."; \ [2024-09-25T20:53:28.006Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17272970993569/renaissance-dec-tree_0"; \ [2024-09-25T20:53:28.006Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17272970993569/renaissance-dec-tree_0"; \ [2024-09-25T20:53:28.006Z] echo ""; echo "TESTING:"; \ [2024-09-25T20:53:28.006Z] "/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_17272970993569/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-09-25T20:53:28.007Z] 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_17272970993569/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-25T20:53:28.007Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-25T20:53:28.007Z] echo "Nothing to be done for teardown."; \ [2024-09-25T20:53:28.007Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17272970993569/TestTargetResult"; [2024-09-25T20:53:28.007Z] [2024-09-25T20:53:28.007Z] TEST SETUP: [2024-09-25T20:53:28.007Z] Nothing to be done for setup. [2024-09-25T20:53:28.007Z] [2024-09-25T20:53:28.007Z] TESTING: [2024-09-25T20:53:30.974Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-25T20:53:32.884Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-25T20:53:33.813Z] WARNING: An illegal reflective access operation has occurred [2024-09-25T20:53:33.813Z] 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_17272970993569/renaissance-dec-tree_0/harness-205327-14833202391708359422/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-25T20:53:33.813Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-25T20:53:33.813Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-25T20:53:33.813Z] WARNING: All illegal access operations will be denied in a future release [2024-09-25T20:53:36.767Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-25T20:53:36.767Z] GC before operation: completed in 50.170 ms, heap usage 44.888 MB -> 33.746 MB. [2024-09-25T20:53:44.804Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8127.493 ms) ====== [2024-09-25T20:53:44.804Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-25T20:53:44.804Z] GC before operation: completed in 110.195 ms, heap usage 228.544 MB -> 63.652 MB. [2024-09-25T20:53:47.768Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2298.742 ms) ====== [2024-09-25T20:53:47.768Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-25T20:53:47.768Z] GC before operation: completed in 118.345 ms, heap usage 157.133 MB -> 75.377 MB. [2024-09-25T20:53:49.695Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1951.035 ms) ====== [2024-09-25T20:53:49.695Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-25T20:53:49.695Z] GC before operation: completed in 108.053 ms, heap usage 217.824 MB -> 64.428 MB. [2024-09-25T20:53:51.605Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1850.998 ms) ====== [2024-09-25T20:53:51.605Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-25T20:53:51.605Z] GC before operation: completed in 101.567 ms, heap usage 108.538 MB -> 74.987 MB. [2024-09-25T20:53:53.522Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1790.210 ms) ====== [2024-09-25T20:53:53.522Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-25T20:53:53.522Z] GC before operation: completed in 122.831 ms, heap usage 257.577 MB -> 64.860 MB. [2024-09-25T20:53:55.429Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1843.168 ms) ====== [2024-09-25T20:53:55.429Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-25T20:53:55.429Z] GC before operation: completed in 109.597 ms, heap usage 276.031 MB -> 65.285 MB. [2024-09-25T20:53:57.359Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1788.411 ms) ====== [2024-09-25T20:53:57.359Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-25T20:53:57.359Z] GC before operation: completed in 107.724 ms, heap usage 247.558 MB -> 65.132 MB. [2024-09-25T20:53:59.298Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1684.894 ms) ====== [2024-09-25T20:53:59.298Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-25T20:53:59.299Z] GC before operation: completed in 112.094 ms, heap usage 230.640 MB -> 65.374 MB. [2024-09-25T20:54:00.242Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1710.421 ms) ====== [2024-09-25T20:54:00.242Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-25T20:54:02.152Z] GC before operation: completed in 123.098 ms, heap usage 196.832 MB -> 65.716 MB. [2024-09-25T20:54:04.083Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3214.338 ms) ====== [2024-09-25T20:54:04.083Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-25T20:54:04.083Z] GC before operation: completed in 118.329 ms, heap usage 99.924 MB -> 65.164 MB. [2024-09-25T20:54:06.013Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1677.839 ms) ====== [2024-09-25T20:54:06.013Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-25T20:54:06.013Z] GC before operation: completed in 121.010 ms, heap usage 239.565 MB -> 65.700 MB. [2024-09-25T20:54:07.940Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1639.003 ms) ====== [2024-09-25T20:54:07.940Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-25T20:54:07.940Z] GC before operation: completed in 121.174 ms, heap usage 280.687 MB -> 76.570 MB. [2024-09-25T20:54:09.887Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1678.714 ms) ====== [2024-09-25T20:54:09.887Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-25T20:54:09.887Z] GC before operation: completed in 124.461 ms, heap usage 193.504 MB -> 77.066 MB. [2024-09-25T20:54:10.823Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1630.988 ms) ====== [2024-09-25T20:54:10.823Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-25T20:54:10.823Z] GC before operation: completed in 120.997 ms, heap usage 240.073 MB -> 65.882 MB. [2024-09-25T20:54:12.737Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1651.068 ms) ====== [2024-09-25T20:54:12.737Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-25T20:54:12.737Z] GC before operation: completed in 123.954 ms, heap usage 195.587 MB -> 65.991 MB. [2024-09-25T20:54:14.726Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1654.282 ms) ====== [2024-09-25T20:54:14.726Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-25T20:54:14.726Z] GC before operation: completed in 127.000 ms, heap usage 235.958 MB -> 66.309 MB. [2024-09-25T20:54:16.642Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1696.899 ms) ====== [2024-09-25T20:54:16.642Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-25T20:54:16.642Z] GC before operation: completed in 128.164 ms, heap usage 130.720 MB -> 66.119 MB. [2024-09-25T20:54:18.553Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1635.947 ms) ====== [2024-09-25T20:54:18.553Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-25T20:54:18.553Z] GC before operation: completed in 135.947 ms, heap usage 280.010 MB -> 66.516 MB. [2024-09-25T20:54:20.467Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1617.481 ms) ====== [2024-09-25T20:54:20.467Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-25T20:54:20.467Z] GC before operation: completed in 134.673 ms, heap usage 202.601 MB -> 94.087 MB. [2024-09-25T20:54:20.467Z] 24/09/25 20:54:19 WARN BlockManager: Asked to remove block rdd_928_0, which does not exist [2024-09-25T20:54:20.467Z] 24/09/25 20:54:19 WARN BlockManager: Asked to remove block rdd_928_2, which does not exist [2024-09-25T20:54:21.408Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1658.179 ms) ====== [2024-09-25T20:54:21.408Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-25T20:54:22.346Z] GC before operation: completed in 133.292 ms, heap usage 285.862 MB -> 77.841 MB. [2024-09-25T20:54:23.279Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1590.396 ms) ====== [2024-09-25T20:54:23.279Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-25T20:54:23.279Z] GC before operation: completed in 125.126 ms, heap usage 155.829 MB -> 71.815 MB. [2024-09-25T20:54:25.296Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1568.709 ms) ====== [2024-09-25T20:54:25.296Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-25T20:54:25.296Z] GC before operation: completed in 129.836 ms, heap usage 231.625 MB -> 66.943 MB. [2024-09-25T20:54:27.212Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1573.487 ms) ====== [2024-09-25T20:54:27.212Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-25T20:54:27.212Z] GC before operation: completed in 129.969 ms, heap usage 232.422 MB -> 78.195 MB. [2024-09-25T20:54:29.280Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1642.240 ms) ====== [2024-09-25T20:54:29.280Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-25T20:54:29.280Z] GC before operation: completed in 129.427 ms, heap usage 110.121 MB -> 72.231 MB. [2024-09-25T20:54:30.208Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1569.181 ms) ====== [2024-09-25T20:54:30.208Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-25T20:54:30.208Z] GC before operation: completed in 130.641 ms, heap usage 251.695 MB -> 67.081 MB. [2024-09-25T20:54:32.132Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1563.982 ms) ====== [2024-09-25T20:54:32.132Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-25T20:54:32.132Z] GC before operation: completed in 121.525 ms, heap usage 237.694 MB -> 78.474 MB. [2024-09-25T20:54:34.040Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1534.350 ms) ====== [2024-09-25T20:54:34.040Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-25T20:54:34.040Z] GC before operation: completed in 131.302 ms, heap usage 239.044 MB -> 67.282 MB. [2024-09-25T20:54:35.953Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1599.655 ms) ====== [2024-09-25T20:54:35.953Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-25T20:54:35.953Z] GC before operation: completed in 132.138 ms, heap usage 103.127 MB -> 83.528 MB. [2024-09-25T20:54:35.953Z] 24/09/25 20:54:35 WARN BlockManager: Asked to remove block rdd_1369_2, which does not exist [2024-09-25T20:54:36.885Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1565.354 ms) ====== [2024-09-25T20:54:36.885Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-25T20:54:37.812Z] GC before operation: completed in 134.354 ms, heap usage 157.111 MB -> 89.342 MB. [2024-09-25T20:54:38.741Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1549.628 ms) ====== [2024-09-25T20:54:38.741Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-25T20:54:38.741Z] GC before operation: completed in 132.015 ms, heap usage 193.513 MB -> 89.261 MB. [2024-09-25T20:54:40.659Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1608.071 ms) ====== [2024-09-25T20:54:40.659Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-25T20:54:40.659Z] GC before operation: completed in 150.575 ms, heap usage 269.622 MB -> 78.555 MB. [2024-09-25T20:54:42.578Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1577.427 ms) ====== [2024-09-25T20:54:42.578Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-25T20:54:42.578Z] GC before operation: completed in 138.168 ms, heap usage 129.750 MB -> 83.693 MB. [2024-09-25T20:54:44.484Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1550.375 ms) ====== [2024-09-25T20:54:44.484Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-25T20:54:44.484Z] GC before operation: completed in 137.218 ms, heap usage 228.923 MB -> 68.011 MB. [2024-09-25T20:54:45.560Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1559.727 ms) ====== [2024-09-25T20:54:45.560Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-25T20:54:45.560Z] GC before operation: completed in 146.902 ms, heap usage 118.002 MB -> 67.404 MB. [2024-09-25T20:54:47.471Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1619.654 ms) ====== [2024-09-25T20:54:47.471Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-25T20:54:47.471Z] GC before operation: completed in 143.240 ms, heap usage 218.001 MB -> 68.211 MB. [2024-09-25T20:54:49.493Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1599.770 ms) ====== [2024-09-25T20:54:49.493Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-25T20:54:49.493Z] GC before operation: completed in 147.126 ms, heap usage 185.664 MB -> 68.144 MB. [2024-09-25T20:54:51.404Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1591.022 ms) ====== [2024-09-25T20:54:51.404Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-25T20:54:51.404Z] GC before operation: completed in 143.518 ms, heap usage 188.458 MB -> 79.540 MB. [2024-09-25T20:54:52.332Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1543.967 ms) ====== [2024-09-25T20:54:52.332Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-25T20:54:53.264Z] GC before operation: completed in 141.513 ms, heap usage 203.717 MB -> 84.835 MB. [2024-09-25T20:54:54.194Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1554.758 ms) ====== [2024-09-25T20:54:54.194Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-25T20:54:54.194Z] GC before operation: completed in 134.579 ms, heap usage 270.436 MB -> 79.476 MB. [2024-09-25T20:54:56.110Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1544.780 ms) ====== [2024-09-25T20:54:56.110Z] ----------------------------------- [2024-09-25T20:54:56.110Z] renaissance-dec-tree_0_PASSED [2024-09-25T20:54:56.110Z] ----------------------------------- [2024-09-25T20:54:56.110Z] [2024-09-25T20:54:56.110Z] TEST TEARDOWN: [2024-09-25T20:54:56.110Z] Nothing to be done for teardown. [2024-09-25T20:54:56.110Z] renaissance-dec-tree_0 Finish Time: Wed Sep 25 20:54:55 2024 Epoch Time (ms): 1727297695811