[2025-02-07T04:14:58.499Z] Running test renaissance-dec-tree_0 ... [2025-02-07T04:14:58.820Z] =============================================== [2025-02-07T04:14:58.820Z] renaissance-dec-tree_0 Start Time: Fri Feb 7 04:14:58 2025 Epoch Time (ms): 1738901698631 [2025-02-07T04:14:58.820Z] variation: NoOptions [2025-02-07T04:14:58.820Z] JVM_OPTIONS: [2025-02-07T04:14:58.820Z] { \ [2025-02-07T04:14:58.820Z] echo ""; echo "TEST SETUP:"; \ [2025-02-07T04:14:58.820Z] echo "Nothing to be done for setup."; \ [2025-02-07T04:14:58.820Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\renaissance-dec-tree_0"; \ [2025-02-07T04:14:58.820Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\renaissance-dec-tree_0"; \ [2025-02-07T04:14:58.820Z] echo ""; echo "TESTING:"; \ [2025-02-07T04:14:58.820Z] "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_17389007255844\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-02-07T04:14:58.820Z] 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_17389007255844\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-07T04:14:58.820Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-07T04:14:58.820Z] echo "Nothing to be done for teardown."; \ [2025-02-07T04:14:58.820Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17389007255844\\TestTargetResult"; [2025-02-07T04:14:59.144Z] [2025-02-07T04:14:59.144Z] TEST SETUP: [2025-02-07T04:14:59.144Z] Nothing to be done for setup. [2025-02-07T04:14:59.144Z] [2025-02-07T04:14:59.144Z] TESTING: [2025-02-07T04:15:12.052Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-07T04:15:13.160Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-07T04:15:14.758Z] WARNING: An illegal reflective access operation has occurred [2025-02-07T04:15:14.758Z] 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_17389007255844/renaissance-dec-tree_0/harness-041459-865141807201243341/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-07T04:15:14.758Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-07T04:15:14.758Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-07T04:15:14.758Z] WARNING: All illegal access operations will be denied in a future release [2025-02-07T04:15:16.959Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-07T04:15:16.959Z] GC before operation: completed in 54.532 ms, heap usage 66.443 MB -> 34.263 MB. [2025-02-07T04:15:27.783Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9481.525 ms) ====== [2025-02-07T04:15:27.784Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-07T04:15:27.784Z] GC before operation: completed in 105.199 ms, heap usage 274.874 MB -> 64.267 MB. [2025-02-07T04:15:29.464Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2699.457 ms) ====== [2025-02-07T04:15:29.464Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-07T04:15:29.464Z] GC before operation: completed in 119.585 ms, heap usage 158.348 MB -> 64.302 MB. [2025-02-07T04:15:31.839Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2230.492 ms) ====== [2025-02-07T04:15:31.839Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-07T04:15:31.839Z] GC before operation: completed in 96.302 ms, heap usage 201.903 MB -> 76.022 MB. [2025-02-07T04:15:34.235Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2224.279 ms) ====== [2025-02-07T04:15:34.235Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-07T04:15:34.235Z] GC before operation: completed in 105.278 ms, heap usage 274.799 MB -> 76.259 MB. [2025-02-07T04:15:36.182Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2222.799 ms) ====== [2025-02-07T04:15:36.182Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-07T04:15:36.569Z] GC before operation: completed in 133.095 ms, heap usage 152.207 MB -> 76.176 MB. [2025-02-07T04:15:38.420Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1967.070 ms) ====== [2025-02-07T04:15:38.420Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-07T04:15:38.420Z] GC before operation: completed in 106.479 ms, heap usage 246.255 MB -> 65.382 MB. [2025-02-07T04:15:40.109Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1654.222 ms) ====== [2025-02-07T04:15:40.109Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-07T04:15:40.109Z] GC before operation: completed in 108.808 ms, heap usage 234.762 MB -> 76.654 MB. [2025-02-07T04:15:41.871Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1617.687 ms) ====== [2025-02-07T04:15:41.871Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-07T04:15:41.871Z] GC before operation: completed in 108.944 ms, heap usage 176.175 MB -> 76.462 MB. [2025-02-07T04:15:43.485Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1535.926 ms) ====== [2025-02-07T04:15:43.485Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-07T04:15:43.485Z] GC before operation: completed in 112.218 ms, heap usage 200.748 MB -> 76.845 MB. [2025-02-07T04:15:43.485Z] 25/02/07 04:15:43 WARN BlockManager: Asked to remove block rdd_438_1, which does not exist [2025-02-07T04:15:45.136Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1498.828 ms) ====== [2025-02-07T04:15:45.136Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-07T04:15:45.136Z] GC before operation: completed in 117.449 ms, heap usage 211.785 MB -> 82.435 MB. [2025-02-07T04:15:46.756Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1444.863 ms) ====== [2025-02-07T04:15:46.756Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-07T04:15:46.756Z] GC before operation: completed in 116.530 ms, heap usage 242.907 MB -> 82.576 MB. [2025-02-07T04:15:48.372Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1319.571 ms) ====== [2025-02-07T04:15:48.372Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-07T04:15:48.372Z] GC before operation: completed in 116.638 ms, heap usage 263.795 MB -> 82.682 MB. [2025-02-07T04:15:48.372Z] 25/02/07 04:15:48 WARN BlockManager: Asked to remove block rdd_585_1, which does not exist [2025-02-07T04:15:49.616Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1437.068 ms) ====== [2025-02-07T04:15:49.616Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-07T04:15:49.616Z] GC before operation: completed in 120.903 ms, heap usage 223.243 MB -> 77.271 MB. [2025-02-07T04:15:51.238Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1491.147 ms) ====== [2025-02-07T04:15:51.238Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-07T04:15:51.238Z] GC before operation: completed in 119.692 ms, heap usage 251.224 MB -> 77.426 MB. [2025-02-07T04:15:52.880Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1326.786 ms) ====== [2025-02-07T04:15:52.880Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-07T04:15:52.880Z] GC before operation: completed in 121.359 ms, heap usage 272.430 MB -> 83.186 MB. [2025-02-07T04:15:54.503Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1341.099 ms) ====== [2025-02-07T04:15:54.503Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-07T04:15:54.503Z] GC before operation: completed in 123.707 ms, heap usage 203.584 MB -> 66.781 MB. [2025-02-07T04:15:55.610Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1355.569 ms) ====== [2025-02-07T04:15:55.610Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-07T04:15:55.941Z] GC before operation: completed in 127.251 ms, heap usage 130.460 MB -> 66.451 MB. [2025-02-07T04:15:57.048Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1334.063 ms) ====== [2025-02-07T04:15:57.048Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-07T04:15:57.371Z] GC before operation: completed in 123.043 ms, heap usage 127.443 MB -> 83.380 MB. [2025-02-07T04:15:59.002Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1407.380 ms) ====== [2025-02-07T04:15:59.002Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-07T04:15:59.002Z] GC before operation: completed in 124.799 ms, heap usage 236.209 MB -> 83.441 MB. [2025-02-07T04:15:59.002Z] 25/02/07 04:15:58 WARN BlockManager: Asked to remove block broadcast_260_piece0, which does not exist [2025-02-07T04:16:00.115Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1351.496 ms) ====== [2025-02-07T04:16:00.115Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-07T04:16:00.115Z] GC before operation: completed in 130.728 ms, heap usage 221.207 MB -> 78.122 MB. [2025-02-07T04:16:01.733Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1318.181 ms) ====== [2025-02-07T04:16:01.733Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-07T04:16:01.733Z] GC before operation: completed in 126.189 ms, heap usage 284.122 MB -> 83.670 MB. [2025-02-07T04:16:01.734Z] 25/02/07 04:16:01 WARN BlockManager: Asked to remove block rdd_1026_2, which does not exist [2025-02-07T04:16:03.332Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1325.198 ms) ====== [2025-02-07T04:16:03.332Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-07T04:16:03.332Z] GC before operation: completed in 152.073 ms, heap usage 149.595 MB -> 83.453 MB. [2025-02-07T04:16:03.332Z] 25/02/07 04:16:02 WARN BlockManager: Asked to remove block broadcast_302_piece0, which does not exist [2025-02-07T04:16:04.491Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1320.894 ms) ====== [2025-02-07T04:16:04.491Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-07T04:16:04.491Z] GC before operation: completed in 130.928 ms, heap usage 237.952 MB -> 89.509 MB. [2025-02-07T04:16:06.132Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1366.117 ms) ====== [2025-02-07T04:16:06.132Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-07T04:16:06.132Z] GC before operation: completed in 128.133 ms, heap usage 263.465 MB -> 83.946 MB. [2025-02-07T04:16:06.132Z] 25/02/07 04:16:05 WARN BlockManager: Asked to remove block broadcast_330_piece0, which does not exist [2025-02-07T04:16:07.760Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1313.006 ms) ====== [2025-02-07T04:16:07.760Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-07T04:16:07.760Z] GC before operation: completed in 151.081 ms, heap usage 291.864 MB -> 84.085 MB. [2025-02-07T04:16:08.871Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1317.776 ms) ====== [2025-02-07T04:16:08.871Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-07T04:16:09.198Z] GC before operation: completed in 139.271 ms, heap usage 151.284 MB -> 67.782 MB. [2025-02-07T04:16:10.308Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1290.227 ms) ====== [2025-02-07T04:16:10.308Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-07T04:16:10.308Z] GC before operation: completed in 129.919 ms, heap usage 102.810 MB -> 78.351 MB. [2025-02-07T04:16:11.917Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1301.769 ms) ====== [2025-02-07T04:16:11.917Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-07T04:16:11.917Z] GC before operation: completed in 129.708 ms, heap usage 116.565 MB -> 84.068 MB. [2025-02-07T04:16:13.527Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1297.723 ms) ====== [2025-02-07T04:16:13.527Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-07T04:16:13.527Z] GC before operation: completed in 135.036 ms, heap usage 114.215 MB -> 67.393 MB. [2025-02-07T04:16:14.635Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1297.381 ms) ====== [2025-02-07T04:16:14.635Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-07T04:16:14.635Z] GC before operation: completed in 133.889 ms, heap usage 239.556 MB -> 89.813 MB. [2025-02-07T04:16:16.284Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1353.865 ms) ====== [2025-02-07T04:16:16.284Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-07T04:16:16.284Z] GC before operation: completed in 144.660 ms, heap usage 308.191 MB -> 90.097 MB. [2025-02-07T04:16:16.284Z] 25/02/07 04:16:16 WARN BlockManager: Asked to remove block rdd_1516_1, which does not exist [2025-02-07T04:16:17.538Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1355.959 ms) ====== [2025-02-07T04:16:17.538Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-07T04:16:17.864Z] GC before operation: completed in 136.190 ms, heap usage 220.878 MB -> 68.247 MB. [2025-02-07T04:16:19.496Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1391.337 ms) ====== [2025-02-07T04:16:19.496Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-07T04:16:19.496Z] GC before operation: completed in 153.220 ms, heap usage 266.899 MB -> 84.824 MB. [2025-02-07T04:16:20.620Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1272.220 ms) ====== [2025-02-07T04:16:20.620Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-07T04:16:20.620Z] GC before operation: completed in 138.399 ms, heap usage 152.039 MB -> 84.908 MB. [2025-02-07T04:16:22.230Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1264.327 ms) ====== [2025-02-07T04:16:22.230Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-07T04:16:22.230Z] GC before operation: completed in 137.861 ms, heap usage 217.933 MB -> 85.061 MB. [2025-02-07T04:16:23.405Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1351.414 ms) ====== [2025-02-07T04:16:23.405Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-07T04:16:23.733Z] GC before operation: completed in 133.885 ms, heap usage 178.897 MB -> 79.240 MB. [2025-02-07T04:16:24.843Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1272.577 ms) ====== [2025-02-07T04:16:24.843Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-07T04:16:25.189Z] GC before operation: completed in 136.234 ms, heap usage 130.977 MB -> 79.049 MB. [2025-02-07T04:16:25.189Z] 25/02/07 04:16:24 WARN BlockManager: Asked to remove block rdd_1810_0, which does not exist [2025-02-07T04:16:26.283Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1326.893 ms) ====== [2025-02-07T04:16:26.283Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-07T04:16:26.620Z] GC before operation: completed in 149.448 ms, heap usage 213.718 MB -> 85.320 MB. [2025-02-07T04:16:27.817Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1391.274 ms) ====== [2025-02-07T04:16:27.817Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-07T04:16:28.151Z] GC before operation: completed in 140.139 ms, heap usage 152.374 MB -> 80.003 MB. [2025-02-07T04:16:29.262Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1241.538 ms) ====== [2025-02-07T04:16:29.584Z] ----------------------------------- [2025-02-07T04:16:29.584Z] renaissance-dec-tree_0_PASSED [2025-02-07T04:16:29.584Z] ----------------------------------- [2025-02-07T04:16:29.584Z] [2025-02-07T04:16:29.584Z] TEST TEARDOWN: [2025-02-07T04:16:29.584Z] Nothing to be done for teardown. [2025-02-07T04:16:29.909Z] renaissance-dec-tree_0 Finish Time: Fri Feb 7 04:16:29 2025 Epoch Time (ms): 1738901789713