[2024-11-15T20:12:21.258Z] Running test renaissance-dec-tree_0 ... [2024-11-15T20:12:21.258Z] =============================================== [2024-11-15T20:12:21.258Z] renaissance-dec-tree_0 Start Time: Fri Nov 15 20:12:18 2024 Epoch Time (ms): 1731701538739 [2024-11-15T20:12:21.258Z] variation: NoOptions [2024-11-15T20:12:21.258Z] JVM_OPTIONS: [2024-11-15T20:12:21.258Z] { \ [2024-11-15T20:12:21.258Z] echo ""; echo "TEST SETUP:"; \ [2024-11-15T20:12:21.258Z] echo "Nothing to be done for setup."; \ [2024-11-15T20:12:21.258Z] mkdir -p "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-dec-tree_0"; \ [2024-11-15T20:12:21.258Z] cd "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-dec-tree_0"; \ [2024-11-15T20:12:21.258Z] echo ""; echo "TESTING:"; \ [2024-11-15T20:12:21.258Z] "c:/workspace/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:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-11-15T20:12:21.258Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-15T20:12:21.258Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-15T20:12:21.258Z] echo "Nothing to be done for teardown."; \ [2024-11-15T20:12:21.258Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\TestTargetResult"; [2024-11-15T20:12:21.258Z] [2024-11-15T20:12:21.258Z] TEST SETUP: [2024-11-15T20:12:21.258Z] Nothing to be done for setup. [2024-11-15T20:12:21.259Z] [2024-11-15T20:12:21.259Z] TESTING: [2024-11-15T20:12:34.185Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-15T20:12:37.068Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-15T20:12:38.677Z] WARNING: An illegal reflective access operation has occurred [2024-11-15T20:12:38.677Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17317002903352/renaissance-dec-tree_0/harness-201220-14465109202068355458/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-15T20:12:38.677Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-15T20:12:38.677Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-15T20:12:38.677Z] WARNING: All illegal access operations will be denied in a future release [2024-11-15T20:12:41.572Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-15T20:12:41.896Z] GC before operation: completed in 73.850 ms, heap usage 105.449 MB -> 34.276 MB. [2024-11-15T20:12:54.986Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11985.455 ms) ====== [2024-11-15T20:12:54.986Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-15T20:12:54.986Z] GC before operation: completed in 138.269 ms, heap usage 135.670 MB -> 63.878 MB. [2024-11-15T20:12:58.109Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3810.054 ms) ====== [2024-11-15T20:12:58.109Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-15T20:12:58.109Z] GC before operation: completed in 159.263 ms, heap usage 236.297 MB -> 64.664 MB. [2024-11-15T20:13:00.821Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2932.510 ms) ====== [2024-11-15T20:13:00.821Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-15T20:13:01.230Z] GC before operation: completed in 128.238 ms, heap usage 173.495 MB -> 64.498 MB. [2024-11-15T20:13:03.534Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2589.117 ms) ====== [2024-11-15T20:13:03.534Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-15T20:13:03.534Z] GC before operation: completed in 125.095 ms, heap usage 104.455 MB -> 64.706 MB. [2024-11-15T20:13:06.535Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2759.242 ms) ====== [2024-11-15T20:13:06.535Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-15T20:13:06.535Z] GC before operation: completed in 151.101 ms, heap usage 194.999 MB -> 65.190 MB. [2024-11-15T20:13:09.114Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2564.743 ms) ====== [2024-11-15T20:13:09.114Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-15T20:13:09.114Z] GC before operation: completed in 128.915 ms, heap usage 203.282 MB -> 65.393 MB. [2024-11-15T20:13:11.377Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2200.766 ms) ====== [2024-11-15T20:13:11.377Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-15T20:13:11.747Z] GC before operation: completed in 135.535 ms, heap usage 145.559 MB -> 65.338 MB. [2024-11-15T20:13:14.042Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2118.919 ms) ====== [2024-11-15T20:13:14.042Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-15T20:13:14.042Z] GC before operation: completed in 143.173 ms, heap usage 197.417 MB -> 66.083 MB. [2024-11-15T20:13:16.264Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2021.795 ms) ====== [2024-11-15T20:13:16.264Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-15T20:13:16.264Z] GC before operation: completed in 139.365 ms, heap usage 127.596 MB -> 76.638 MB. [2024-11-15T20:13:17.963Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1992.705 ms) ====== [2024-11-15T20:13:17.963Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-15T20:13:18.297Z] GC before operation: completed in 139.571 ms, heap usage 111.799 MB -> 81.882 MB. [2024-11-15T20:13:19.953Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1870.675 ms) ====== [2024-11-15T20:13:19.953Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-15T20:13:20.288Z] GC before operation: completed in 145.154 ms, heap usage 160.499 MB -> 76.799 MB. [2024-11-15T20:13:21.919Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1762.174 ms) ====== [2024-11-15T20:13:21.919Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-15T20:13:22.241Z] GC before operation: completed in 152.275 ms, heap usage 267.363 MB -> 77.044 MB. [2024-11-15T20:13:23.938Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1882.914 ms) ====== [2024-11-15T20:13:23.938Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-15T20:13:24.268Z] GC before operation: completed in 145.955 ms, heap usage 296.490 MB -> 82.775 MB. [2024-11-15T20:13:25.879Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1789.306 ms) ====== [2024-11-15T20:13:25.879Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-15T20:13:26.206Z] GC before operation: completed in 153.545 ms, heap usage 193.708 MB -> 76.985 MB. [2024-11-15T20:13:27.810Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1697.033 ms) ====== [2024-11-15T20:13:27.810Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-15T20:13:27.810Z] GC before operation: completed in 148.073 ms, heap usage 265.363 MB -> 83.197 MB. [2024-11-15T20:13:30.011Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1766.408 ms) ====== [2024-11-15T20:13:30.011Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-15T20:13:30.011Z] GC before operation: completed in 170.205 ms, heap usage 186.430 MB -> 66.533 MB. [2024-11-15T20:13:31.619Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1778.974 ms) ====== [2024-11-15T20:13:31.619Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-15T20:13:31.619Z] GC before operation: completed in 156.902 ms, heap usage 220.673 MB -> 66.759 MB. [2024-11-15T20:13:33.813Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1756.566 ms) ====== [2024-11-15T20:13:33.813Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-15T20:13:33.813Z] GC before operation: completed in 149.949 ms, heap usage 188.333 MB -> 72.665 MB. [2024-11-15T20:13:35.448Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1826.790 ms) ====== [2024-11-15T20:13:35.448Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-15T20:13:35.778Z] GC before operation: completed in 153.593 ms, heap usage 268.211 MB -> 83.451 MB. [2024-11-15T20:13:37.389Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1694.988 ms) ====== [2024-11-15T20:13:37.389Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-15T20:13:37.389Z] GC before operation: completed in 149.269 ms, heap usage 180.383 MB -> 77.879 MB. [2024-11-15T20:13:39.587Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1732.912 ms) ====== [2024-11-15T20:13:39.587Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-15T20:13:39.587Z] GC before operation: completed in 155.458 ms, heap usage 277.209 MB -> 83.682 MB. [2024-11-15T20:13:41.355Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1840.877 ms) ====== [2024-11-15T20:13:41.355Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-15T20:13:41.355Z] GC before operation: completed in 163.027 ms, heap usage 165.858 MB -> 83.459 MB. [2024-11-15T20:13:43.039Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1695.673 ms) ====== [2024-11-15T20:13:43.039Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-15T20:13:43.365Z] GC before operation: completed in 155.756 ms, heap usage 206.815 MB -> 78.259 MB. [2024-11-15T20:13:44.973Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1759.098 ms) ====== [2024-11-15T20:13:44.973Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-15T20:13:45.305Z] GC before operation: completed in 164.556 ms, heap usage 242.148 MB -> 83.965 MB. [2024-11-15T20:13:46.945Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1733.580 ms) ====== [2024-11-15T20:13:46.945Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-15T20:13:47.262Z] GC before operation: completed in 170.206 ms, heap usage 143.295 MB -> 78.376 MB. [2024-11-15T20:13:48.871Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1702.999 ms) ====== [2024-11-15T20:13:48.871Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-15T20:13:48.871Z] GC before operation: completed in 152.552 ms, heap usage 277.619 MB -> 78.726 MB. [2024-11-15T20:13:48.871Z] 24/11/15 20:13:48 WARN BlockManager: Asked to remove block rdd_1271_0, which does not exist [2024-11-15T20:13:50.573Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1701.442 ms) ====== [2024-11-15T20:13:50.573Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-15T20:13:50.902Z] GC before operation: completed in 164.050 ms, heap usage 237.883 MB -> 84.302 MB. [2024-11-15T20:13:52.515Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1709.124 ms) ====== [2024-11-15T20:13:52.515Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-15T20:13:52.846Z] GC before operation: completed in 160.878 ms, heap usage 119.424 MB -> 72.929 MB. [2024-11-15T20:13:54.464Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1698.629 ms) ====== [2024-11-15T20:13:54.464Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-15T20:13:54.464Z] GC before operation: completed in 160.494 ms, heap usage 135.896 MB -> 78.968 MB. [2024-11-15T20:13:56.173Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1696.915 ms) ====== [2024-11-15T20:13:56.173Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-15T20:13:56.484Z] GC before operation: completed in 165.536 ms, heap usage 220.573 MB -> 90.030 MB. [2024-11-15T20:13:58.090Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1699.373 ms) ====== [2024-11-15T20:13:58.090Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-15T20:13:58.417Z] GC before operation: completed in 174.122 ms, heap usage 351.671 MB -> 90.131 MB. [2024-11-15T20:14:00.036Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1693.092 ms) ====== [2024-11-15T20:14:00.036Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-15T20:14:00.036Z] GC before operation: completed in 167.319 ms, heap usage 310.284 MB -> 84.779 MB. [2024-11-15T20:14:02.254Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1771.243 ms) ====== [2024-11-15T20:14:02.254Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-15T20:14:02.254Z] GC before operation: completed in 180.079 ms, heap usage 262.948 MB -> 79.446 MB. [2024-11-15T20:14:03.863Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1652.051 ms) ====== [2024-11-15T20:14:03.863Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-15T20:14:03.863Z] GC before operation: completed in 162.228 ms, heap usage 275.858 MB -> 84.979 MB. [2024-11-15T20:14:05.558Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1720.948 ms) ====== [2024-11-15T20:14:05.558Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-15T20:14:05.902Z] GC before operation: completed in 171.458 ms, heap usage 229.560 MB -> 85.096 MB. [2024-11-15T20:14:07.511Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1697.749 ms) ====== [2024-11-15T20:14:07.511Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-15T20:14:07.512Z] GC before operation: completed in 164.231 ms, heap usage 125.244 MB -> 84.634 MB. [2024-11-15T20:14:09.722Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1741.598 ms) ====== [2024-11-15T20:14:09.722Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-15T20:14:09.722Z] GC before operation: completed in 165.581 ms, heap usage 231.049 MB -> 85.259 MB. [2024-11-15T20:14:09.722Z] 24/11/15 20:14:09 WARN BlockManager: Asked to remove block rdd_1810_0, which does not exist [2024-11-15T20:14:11.398Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1692.599 ms) ====== [2024-11-15T20:14:11.398Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-15T20:14:11.398Z] GC before operation: completed in 174.308 ms, heap usage 229.910 MB -> 85.354 MB. [2024-11-15T20:14:13.200Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1638.909 ms) ====== [2024-11-15T20:14:13.200Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-15T20:14:13.200Z] GC before operation: completed in 165.758 ms, heap usage 306.327 MB -> 91.113 MB. [2024-11-15T20:14:14.826Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1629.585 ms) ====== [2024-11-15T20:14:15.154Z] ----------------------------------- [2024-11-15T20:14:15.154Z] renaissance-dec-tree_0_PASSED [2024-11-15T20:14:15.154Z] ----------------------------------- [2024-11-15T20:14:15.468Z] [2024-11-15T20:14:15.468Z] TEST TEARDOWN: [2024-11-15T20:14:15.468Z] Nothing to be done for teardown. [2024-11-15T20:14:15.468Z] renaissance-dec-tree_0 Finish Time: Fri Nov 15 20:14:15 2024 Epoch Time (ms): 1731701655373