[2024-09-25T22:32:11.208Z] Running test renaissance-dec-tree_0 ... [2024-09-25T22:32:11.208Z] =============================================== [2024-09-25T22:32:11.208Z] renaissance-dec-tree_0 Start Time: Wed Sep 25 22:32:11 2024 Epoch Time (ms): 1727303531105 [2024-09-25T22:32:11.208Z] variation: NoOptions [2024-09-25T22:32:11.522Z] JVM_OPTIONS: [2024-09-25T22:32:11.522Z] { \ [2024-09-25T22:32:11.522Z] echo ""; echo "TEST SETUP:"; \ [2024-09-25T22:32:11.522Z] echo "Nothing to be done for setup."; \ [2024-09-25T22:32:11.522Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273026372534\\renaissance-dec-tree_0"; \ [2024-09-25T22:32:11.522Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273026372534\\renaissance-dec-tree_0"; \ [2024-09-25T22:32:11.522Z] echo ""; echo "TESTING:"; \ [2024-09-25T22:32:11.522Z] "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_17273026372534\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-09-25T22:32:11.522Z] 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_17273026372534\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-25T22:32:11.522Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-25T22:32:11.522Z] echo "Nothing to be done for teardown."; \ [2024-09-25T22:32:11.522Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273026372534\\TestTargetResult"; [2024-09-25T22:32:11.522Z] [2024-09-25T22:32:11.522Z] TEST SETUP: [2024-09-25T22:32:11.522Z] Nothing to be done for setup. [2024-09-25T22:32:11.522Z] [2024-09-25T22:32:11.522Z] TESTING: [2024-09-25T22:32:22.087Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-25T22:32:23.650Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-25T22:32:24.731Z] WARNING: An illegal reflective access operation has occurred [2024-09-25T22:32:24.732Z] 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_17273026372534/renaissance-dec-tree_0/harness-223212-13291265100902456145/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-25T22:32:24.732Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-25T22:32:24.732Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-25T22:32:24.732Z] WARNING: All illegal access operations will be denied in a future release [2024-09-25T22:32:26.878Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-25T22:32:26.878Z] GC before operation: completed in 47.447 ms, heap usage 70.856 MB -> 34.259 MB. [2024-09-25T22:32:35.774Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8821.136 ms) ====== [2024-09-25T22:32:35.774Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-25T22:32:35.774Z] GC before operation: completed in 106.454 ms, heap usage 279.897 MB -> 64.267 MB. [2024-09-25T22:32:38.204Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2538.356 ms) ====== [2024-09-25T22:32:38.204Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-25T22:32:38.204Z] GC before operation: completed in 86.829 ms, heap usage 234.718 MB -> 75.488 MB. [2024-09-25T22:32:40.648Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2159.837 ms) ====== [2024-09-25T22:32:40.648Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-25T22:32:40.648Z] GC before operation: completed in 111.038 ms, heap usage 204.183 MB -> 64.693 MB. [2024-09-25T22:32:42.591Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1954.469 ms) ====== [2024-09-25T22:32:42.591Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-25T22:32:42.591Z] GC before operation: completed in 90.669 ms, heap usage 226.691 MB -> 76.243 MB. [2024-09-25T22:32:44.936Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1831.804 ms) ====== [2024-09-25T22:32:44.936Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-25T22:32:44.936Z] GC before operation: completed in 89.448 ms, heap usage 201.295 MB -> 76.450 MB. [2024-09-25T22:32:46.064Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1589.203 ms) ====== [2024-09-25T22:32:46.064Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-25T22:32:46.393Z] GC before operation: completed in 95.786 ms, heap usage 151.821 MB -> 65.231 MB. [2024-09-25T22:32:48.171Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1707.764 ms) ====== [2024-09-25T22:32:48.171Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-25T22:32:48.171Z] GC before operation: completed in 120.038 ms, heap usage 134.411 MB -> 65.585 MB. [2024-09-25T22:32:49.928Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1592.940 ms) ====== [2024-09-25T22:32:49.928Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-25T22:32:49.928Z] GC before operation: completed in 97.859 ms, heap usage 186.923 MB -> 65.718 MB. [2024-09-25T22:32:51.058Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1372.241 ms) ====== [2024-09-25T22:32:51.058Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-25T22:32:51.376Z] GC before operation: completed in 103.037 ms, heap usage 204.561 MB -> 76.931 MB. [2024-09-25T22:32:51.376Z] 24/09/25 22:32:51 WARN BlockManager: Asked to remove block broadcast_120, which does not exist [2024-09-25T22:32:52.535Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1377.758 ms) ====== [2024-09-25T22:32:52.535Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-25T22:32:52.859Z] GC before operation: completed in 107.117 ms, heap usage 214.129 MB -> 82.639 MB. [2024-09-25T22:32:54.437Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1424.492 ms) ====== [2024-09-25T22:32:54.437Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-25T22:32:54.437Z] GC before operation: completed in 103.742 ms, heap usage 272.826 MB -> 76.916 MB. [2024-09-25T22:32:55.568Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1243.120 ms) ====== [2024-09-25T22:32:55.568Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-25T22:32:55.568Z] GC before operation: completed in 104.150 ms, heap usage 245.211 MB -> 77.239 MB. [2024-09-25T22:32:57.137Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1348.852 ms) ====== [2024-09-25T22:32:57.137Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-25T22:32:57.137Z] GC before operation: completed in 102.072 ms, heap usage 195.893 MB -> 77.280 MB. [2024-09-25T22:32:58.251Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1283.901 ms) ====== [2024-09-25T22:32:58.251Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-25T22:32:58.578Z] GC before operation: completed in 104.046 ms, heap usage 227.453 MB -> 88.314 MB. [2024-09-25T22:32:59.755Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1359.204 ms) ====== [2024-09-25T22:32:59.755Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-25T22:32:59.755Z] GC before operation: completed in 109.972 ms, heap usage 253.740 MB -> 83.012 MB. [2024-09-25T22:33:01.337Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1322.855 ms) ====== [2024-09-25T22:33:01.337Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-25T22:33:01.337Z] GC before operation: completed in 112.910 ms, heap usage 131.214 MB -> 66.512 MB. [2024-09-25T22:33:02.901Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1257.918 ms) ====== [2024-09-25T22:33:02.901Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-25T22:33:02.901Z] GC before operation: completed in 114.575 ms, heap usage 192.734 MB -> 66.875 MB. [2024-09-25T22:33:04.031Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1298.584 ms) ====== [2024-09-25T22:33:04.031Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-25T22:33:04.031Z] GC before operation: completed in 108.881 ms, heap usage 108.767 MB -> 66.547 MB. [2024-09-25T22:33:05.641Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1307.557 ms) ====== [2024-09-25T22:33:05.641Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-25T22:33:05.641Z] GC before operation: completed in 109.907 ms, heap usage 172.130 MB -> 83.253 MB. [2024-09-25T22:33:06.735Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1257.304 ms) ====== [2024-09-25T22:33:06.735Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-25T22:33:07.063Z] GC before operation: completed in 114.467 ms, heap usage 151.091 MB -> 77.772 MB. [2024-09-25T22:33:07.063Z] 24/09/25 22:33:06 WARN BlockManager: Asked to remove block rdd_977_1, which does not exist [2024-09-25T22:33:08.148Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1270.540 ms) ====== [2024-09-25T22:33:08.148Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-25T22:33:08.148Z] GC before operation: completed in 115.356 ms, heap usage 222.809 MB -> 83.672 MB. [2024-09-25T22:33:09.747Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1288.797 ms) ====== [2024-09-25T22:33:09.747Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-25T22:33:09.747Z] GC before operation: completed in 135.998 ms, heap usage 270.226 MB -> 83.749 MB. [2024-09-25T22:33:10.885Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1235.051 ms) ====== [2024-09-25T22:33:10.885Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-25T22:33:11.199Z] GC before operation: completed in 115.197 ms, heap usage 244.918 MB -> 78.442 MB. [2024-09-25T22:33:12.308Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1295.310 ms) ====== [2024-09-25T22:33:12.308Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-25T22:33:12.652Z] GC before operation: completed in 111.760 ms, heap usage 115.280 MB -> 83.486 MB. [2024-09-25T22:33:13.757Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1244.607 ms) ====== [2024-09-25T22:33:13.757Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-25T22:33:13.757Z] GC before operation: completed in 116.780 ms, heap usage 264.414 MB -> 84.093 MB. [2024-09-25T22:33:15.363Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1239.197 ms) ====== [2024-09-25T22:33:15.363Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-25T22:33:15.363Z] GC before operation: completed in 126.186 ms, heap usage 297.421 MB -> 89.606 MB. [2024-09-25T22:33:16.447Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1249.689 ms) ====== [2024-09-25T22:33:16.447Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-25T22:33:16.447Z] GC before operation: completed in 122.063 ms, heap usage 238.484 MB -> 84.293 MB. [2024-09-25T22:33:18.018Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1222.405 ms) ====== [2024-09-25T22:33:18.018Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-25T22:33:18.018Z] GC before operation: completed in 116.495 ms, heap usage 277.760 MB -> 84.364 MB. [2024-09-25T22:33:19.106Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1165.146 ms) ====== [2024-09-25T22:33:19.106Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-25T22:33:19.106Z] GC before operation: completed in 116.968 ms, heap usage 237.670 MB -> 89.977 MB. [2024-09-25T22:33:20.714Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1191.439 ms) ====== [2024-09-25T22:33:20.714Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-25T22:33:20.714Z] GC before operation: completed in 119.492 ms, heap usage 213.864 MB -> 79.157 MB. [2024-09-25T22:33:21.799Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1267.960 ms) ====== [2024-09-25T22:33:21.799Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-25T22:33:21.799Z] GC before operation: completed in 118.581 ms, heap usage 158.302 MB -> 89.824 MB. [2024-09-25T22:33:23.102Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1265.447 ms) ====== [2024-09-25T22:33:23.102Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-25T22:33:23.460Z] GC before operation: completed in 138.731 ms, heap usage 297.338 MB -> 84.982 MB. [2024-09-25T22:33:24.550Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1215.467 ms) ====== [2024-09-25T22:33:24.550Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-25T22:33:24.550Z] GC before operation: completed in 129.908 ms, heap usage 279.895 MB -> 84.860 MB. [2024-09-25T22:33:26.119Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1237.062 ms) ====== [2024-09-25T22:33:26.119Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-25T22:33:26.119Z] GC before operation: completed in 132.197 ms, heap usage 296.329 MB -> 84.959 MB. [2024-09-25T22:33:26.119Z] 24/09/25 22:33:25 WARN BlockManager: Asked to remove block rdd_1663_0, which does not exist [2024-09-25T22:33:26.119Z] 24/09/25 22:33:25 WARN BlockManager: Asked to remove block rdd_1663_2, which does not exist [2024-09-25T22:33:27.200Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1199.500 ms) ====== [2024-09-25T22:33:27.200Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-25T22:33:27.200Z] GC before operation: completed in 121.434 ms, heap usage 259.297 MB -> 85.094 MB. [2024-09-25T22:33:28.768Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1195.601 ms) ====== [2024-09-25T22:33:28.768Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-25T22:33:28.768Z] GC before operation: completed in 126.180 ms, heap usage 266.180 MB -> 85.196 MB. [2024-09-25T22:33:29.851Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1197.953 ms) ====== [2024-09-25T22:33:29.851Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-25T22:33:29.851Z] GC before operation: completed in 122.780 ms, heap usage 221.298 MB -> 85.263 MB. [2024-09-25T22:33:31.426Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1242.832 ms) ====== [2024-09-25T22:33:31.426Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-25T22:33:31.426Z] GC before operation: completed in 123.315 ms, heap usage 190.788 MB -> 85.087 MB. [2024-09-25T22:33:32.503Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1200.884 ms) ====== [2024-09-25T22:33:32.503Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-25T22:33:32.820Z] GC before operation: completed in 122.629 ms, heap usage 221.105 MB -> 80.174 MB. [2024-09-25T22:33:33.903Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1202.437 ms) ====== [2024-09-25T22:33:34.206Z] ----------------------------------- [2024-09-25T22:33:34.206Z] renaissance-dec-tree_0_PASSED [2024-09-25T22:33:34.206Z] ----------------------------------- [2024-09-25T22:33:34.206Z] [2024-09-25T22:33:34.206Z] TEST TEARDOWN: [2024-09-25T22:33:34.206Z] Nothing to be done for teardown. [2024-09-25T22:33:34.508Z] renaissance-dec-tree_0 Finish Time: Wed Sep 25 22:33:34 2024 Epoch Time (ms): 1727303614190