[2024-10-03T08:24:32.836Z] Running test renaissance-dec-tree_0 ... [2024-10-03T08:24:32.836Z] =============================================== [2024-10-03T08:24:32.836Z] renaissance-dec-tree_0 Start Time: Thu Oct 3 08:24:32 2024 Epoch Time (ms): 1727943872808 [2024-10-03T08:24:33.160Z] variation: NoOptions [2024-10-03T08:24:33.160Z] JVM_OPTIONS: [2024-10-03T08:24:33.160Z] { \ [2024-10-03T08:24:33.160Z] echo ""; echo "TEST SETUP:"; \ [2024-10-03T08:24:33.160Z] echo "Nothing to be done for setup."; \ [2024-10-03T08:24:33.160Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\renaissance-dec-tree_0"; \ [2024-10-03T08:24:33.160Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\renaissance-dec-tree_0"; \ [2024-10-03T08:24:33.160Z] echo ""; echo "TESTING:"; \ [2024-10-03T08:24:33.160Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-10-03T08:24:33.160Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-03T08:24:33.160Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-03T08:24:33.160Z] echo "Nothing to be done for teardown."; \ [2024-10-03T08:24:33.160Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17279429786926\\TestTargetResult"; [2024-10-03T08:24:33.160Z] [2024-10-03T08:24:33.160Z] TEST SETUP: [2024-10-03T08:24:33.160Z] Nothing to be done for setup. [2024-10-03T08:24:33.160Z] [2024-10-03T08:24:33.160Z] TESTING: [2024-10-03T08:24:43.812Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-03T08:24:45.402Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-03T08:24:46.507Z] WARNING: An illegal reflective access operation has occurred [2024-10-03T08:24:46.507Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17279429786926/renaissance-dec-tree_0/harness-082433-11435335034873306689/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-10-03T08:24:46.507Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-10-03T08:24:46.507Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-10-03T08:24:46.507Z] WARNING: All illegal access operations will be denied in a future release [2024-10-03T08:24:48.686Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-10-03T08:24:48.686Z] GC before operation: completed in 55.861 ms, heap usage 66.786 MB -> 34.269 MB. [2024-10-03T08:24:57.477Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8522.005 ms) ====== [2024-10-03T08:24:57.477Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-10-03T08:24:57.477Z] GC before operation: completed in 94.400 ms, heap usage 292.544 MB -> 64.126 MB. [2024-10-03T08:24:59.896Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2653.378 ms) ====== [2024-10-03T08:24:59.896Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-10-03T08:24:59.896Z] GC before operation: completed in 99.644 ms, heap usage 162.314 MB -> 64.223 MB. [2024-10-03T08:25:02.299Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1961.844 ms) ====== [2024-10-03T08:25:02.299Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-10-03T08:25:02.299Z] GC before operation: completed in 111.802 ms, heap usage 117.269 MB -> 64.838 MB. [2024-10-03T08:25:04.049Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1701.456 ms) ====== [2024-10-03T08:25:04.049Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-10-03T08:25:04.049Z] GC before operation: completed in 88.024 ms, heap usage 184.063 MB -> 64.990 MB. [2024-10-03T08:25:05.819Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1662.030 ms) ====== [2024-10-03T08:25:05.819Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-10-03T08:25:05.819Z] GC before operation: completed in 91.248 ms, heap usage 161.106 MB -> 75.957 MB. [2024-10-03T08:25:07.479Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1566.147 ms) ====== [2024-10-03T08:25:07.479Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-10-03T08:25:07.479Z] GC before operation: completed in 92.943 ms, heap usage 138.405 MB -> 65.406 MB. [2024-10-03T08:25:08.715Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1517.739 ms) ====== [2024-10-03T08:25:08.715Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-10-03T08:25:09.043Z] GC before operation: completed in 108.247 ms, heap usage 157.767 MB -> 65.357 MB. [2024-10-03T08:25:10.220Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1402.652 ms) ====== [2024-10-03T08:25:10.220Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-10-03T08:25:10.548Z] GC before operation: completed in 98.050 ms, heap usage 101.971 MB -> 65.433 MB. [2024-10-03T08:25:11.705Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1356.169 ms) ====== [2024-10-03T08:25:11.705Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-10-03T08:25:12.034Z] GC before operation: completed in 99.366 ms, heap usage 156.038 MB -> 77.111 MB. [2024-10-03T08:25:13.166Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1386.380 ms) ====== [2024-10-03T08:25:13.166Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-10-03T08:25:13.492Z] GC before operation: completed in 100.878 ms, heap usage 128.284 MB -> 71.239 MB. [2024-10-03T08:25:14.705Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1350.043 ms) ====== [2024-10-03T08:25:14.705Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-10-03T08:25:14.705Z] GC before operation: completed in 104.682 ms, heap usage 115.404 MB -> 76.796 MB. [2024-10-03T08:25:16.333Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1335.692 ms) ====== [2024-10-03T08:25:16.333Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-10-03T08:25:16.333Z] GC before operation: completed in 100.718 ms, heap usage 137.957 MB -> 65.870 MB. [2024-10-03T08:25:17.998Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1428.953 ms) ====== [2024-10-03T08:25:17.998Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-10-03T08:25:17.998Z] GC before operation: completed in 110.611 ms, heap usage 146.705 MB -> 66.124 MB. [2024-10-03T08:25:19.120Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1299.220 ms) ====== [2024-10-03T08:25:19.120Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-10-03T08:25:19.120Z] GC before operation: completed in 104.400 ms, heap usage 127.184 MB -> 76.972 MB. [2024-10-03T08:25:20.722Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1355.750 ms) ====== [2024-10-03T08:25:20.722Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-10-03T08:25:20.722Z] GC before operation: completed in 115.260 ms, heap usage 180.839 MB -> 82.736 MB. [2024-10-03T08:25:21.859Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1279.789 ms) ====== [2024-10-03T08:25:21.859Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-10-03T08:25:22.182Z] GC before operation: completed in 119.202 ms, heap usage 263.792 MB -> 66.788 MB. [2024-10-03T08:25:23.317Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1248.835 ms) ====== [2024-10-03T08:25:23.317Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-10-03T08:25:23.317Z] GC before operation: completed in 117.866 ms, heap usage 128.592 MB -> 66.598 MB. [2024-10-03T08:25:24.973Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1302.692 ms) ====== [2024-10-03T08:25:24.973Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-10-03T08:25:24.973Z] GC before operation: completed in 111.574 ms, heap usage 202.841 MB -> 83.377 MB. [2024-10-03T08:25:26.134Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1268.624 ms) ====== [2024-10-03T08:25:26.135Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-10-03T08:25:26.135Z] GC before operation: completed in 113.864 ms, heap usage 243.971 MB -> 83.443 MB. [2024-10-03T08:25:27.759Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1326.519 ms) ====== [2024-10-03T08:25:27.759Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-10-03T08:25:27.759Z] GC before operation: completed in 109.381 ms, heap usage 250.035 MB -> 89.187 MB. [2024-10-03T08:25:28.864Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1162.943 ms) ====== [2024-10-03T08:25:28.864Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-10-03T08:25:28.864Z] GC before operation: completed in 108.278 ms, heap usage 246.941 MB -> 83.701 MB. [2024-10-03T08:25:30.114Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1254.254 ms) ====== [2024-10-03T08:25:30.114Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-10-03T08:25:30.446Z] GC before operation: completed in 112.410 ms, heap usage 252.413 MB -> 83.748 MB. [2024-10-03T08:25:31.548Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1182.588 ms) ====== [2024-10-03T08:25:31.548Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-10-03T08:25:31.548Z] GC before operation: completed in 108.491 ms, heap usage 177.570 MB -> 78.413 MB. [2024-10-03T08:25:32.727Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1207.749 ms) ====== [2024-10-03T08:25:32.727Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-10-03T08:25:33.044Z] GC before operation: completed in 122.095 ms, heap usage 299.270 MB -> 83.979 MB. [2024-10-03T08:25:34.156Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1221.245 ms) ====== [2024-10-03T08:25:34.156Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-10-03T08:25:34.156Z] GC before operation: completed in 111.319 ms, heap usage 148.260 MB -> 78.441 MB. [2024-10-03T08:25:35.798Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1226.422 ms) ====== [2024-10-03T08:25:35.798Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-10-03T08:25:35.798Z] GC before operation: completed in 120.392 ms, heap usage 278.536 MB -> 89.620 MB. [2024-10-03T08:25:36.904Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1254.124 ms) ====== [2024-10-03T08:25:36.904Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-10-03T08:25:36.904Z] GC before operation: completed in 126.157 ms, heap usage 234.586 MB -> 84.308 MB. [2024-10-03T08:25:38.585Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1194.842 ms) ====== [2024-10-03T08:25:38.585Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-10-03T08:25:38.585Z] GC before operation: completed in 116.179 ms, heap usage 273.489 MB -> 84.377 MB. [2024-10-03T08:25:38.585Z] 24/10/03 08:25:38 WARN BlockManager: Asked to remove block rdd_1369_0, which does not exist [2024-10-03T08:25:38.585Z] 24/10/03 08:25:38 WARN BlockManager: Asked to remove block rdd_1369_2, which does not exist [2024-10-03T08:25:39.693Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1209.575 ms) ====== [2024-10-03T08:25:39.693Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-10-03T08:25:39.693Z] GC before operation: completed in 120.883 ms, heap usage 258.454 MB -> 90.104 MB. [2024-10-03T08:25:40.814Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1176.453 ms) ====== [2024-10-03T08:25:40.814Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-10-03T08:25:40.814Z] GC before operation: completed in 120.293 ms, heap usage 255.906 MB -> 90.026 MB. [2024-10-03T08:25:42.429Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1178.749 ms) ====== [2024-10-03T08:25:42.429Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-10-03T08:25:42.429Z] GC before operation: completed in 119.784 ms, heap usage 194.397 MB -> 89.854 MB. [2024-10-03T08:25:43.652Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1481.649 ms) ====== [2024-10-03T08:25:43.652Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-10-03T08:25:44.025Z] GC before operation: completed in 118.329 ms, heap usage 256.934 MB -> 84.777 MB. [2024-10-03T08:25:45.165Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1208.649 ms) ====== [2024-10-03T08:25:45.165Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-10-03T08:25:45.165Z] GC before operation: completed in 132.382 ms, heap usage 258.506 MB -> 84.873 MB. [2024-10-03T08:25:46.299Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1208.948 ms) ====== [2024-10-03T08:25:46.299Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-10-03T08:25:46.657Z] GC before operation: completed in 137.924 ms, heap usage 293.087 MB -> 84.975 MB. [2024-10-03T08:25:47.790Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1263.002 ms) ====== [2024-10-03T08:25:47.790Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-10-03T08:25:47.790Z] GC before operation: completed in 118.258 ms, heap usage 148.428 MB -> 85.097 MB. [2024-10-03T08:25:49.451Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1205.922 ms) ====== [2024-10-03T08:25:49.451Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-10-03T08:25:49.451Z] GC before operation: completed in 120.709 ms, heap usage 286.192 MB -> 85.207 MB. [2024-10-03T08:25:50.563Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1197.758 ms) ====== [2024-10-03T08:25:50.563Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-10-03T08:25:50.563Z] GC before operation: completed in 126.670 ms, heap usage 173.063 MB -> 84.961 MB. [2024-10-03T08:25:51.700Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1206.558 ms) ====== [2024-10-03T08:25:51.700Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-10-03T08:25:52.028Z] GC before operation: completed in 119.565 ms, heap usage 251.431 MB -> 85.343 MB. [2024-10-03T08:25:53.136Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1196.364 ms) ====== [2024-10-03T08:25:53.137Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-10-03T08:25:53.137Z] GC before operation: completed in 120.625 ms, heap usage 173.131 MB -> 90.784 MB. [2024-10-03T08:25:54.273Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1210.261 ms) ====== [2024-10-03T08:25:54.595Z] ----------------------------------- [2024-10-03T08:25:54.595Z] renaissance-dec-tree_0_PASSED [2024-10-03T08:25:54.595Z] ----------------------------------- [2024-10-03T08:25:54.907Z] [2024-10-03T08:25:54.907Z] TEST TEARDOWN: [2024-10-03T08:25:54.907Z] Nothing to be done for teardown. [2024-10-03T08:25:54.907Z] renaissance-dec-tree_0 Finish Time: Thu Oct 3 08:25:54 2024 Epoch Time (ms): 1727943954848