[2025-02-06T01:55:11.693Z] Running test renaissance-dec-tree_0 ... [2025-02-06T01:55:11.693Z] =============================================== [2025-02-06T01:55:11.693Z] renaissance-dec-tree_0 Start Time: Wed Feb 5 19:55:11 2025 Epoch Time (ms): 1738806911549 [2025-02-06T01:55:11.693Z] variation: NoOptions [2025-02-06T01:55:11.693Z] JVM_OPTIONS: [2025-02-06T01:55:11.693Z] { \ [2025-02-06T01:55:11.693Z] echo ""; echo "TEST SETUP:"; \ [2025-02-06T01:55:11.693Z] echo "Nothing to be done for setup."; \ [2025-02-06T01:55:11.693Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17388064545269/renaissance-dec-tree_0"; \ [2025-02-06T01:55:11.693Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17388064545269/renaissance-dec-tree_0"; \ [2025-02-06T01:55:11.693Z] echo ""; echo "TESTING:"; \ [2025-02-06T01:55:11.693Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17388064545269/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-06T01:55:11.693Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17388064545269/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-06T01:55:11.693Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-06T01:55:11.693Z] echo "Nothing to be done for teardown."; \ [2025-02-06T01:55:11.693Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17388064545269/TestTargetResult"; [2025-02-06T01:55:11.693Z] [2025-02-06T01:55:11.693Z] TEST SETUP: [2025-02-06T01:55:11.693Z] Nothing to be done for setup. [2025-02-06T01:55:11.693Z] [2025-02-06T01:55:11.693Z] TESTING: [2025-02-06T01:55:14.743Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-06T01:55:16.145Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2025-02-06T01:55:17.551Z] WARNING: An illegal reflective access operation has occurred [2025-02-06T01:55:17.551Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17388064545269/renaissance-dec-tree_0/harness-195512-18431071963910125417/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-06T01:55:17.551Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-06T01:55:17.551Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-06T01:55:17.551Z] WARNING: All illegal access operations will be denied in a future release [2025-02-06T01:55:19.778Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-06T01:55:19.778Z] GC before operation: completed in 55.104 ms, heap usage 71.695 MB -> 34.338 MB. [2025-02-06T01:55:28.877Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8448.294 ms) ====== [2025-02-06T01:55:28.877Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-06T01:55:28.877Z] GC before operation: completed in 97.495 ms, heap usage 250.956 MB -> 64.395 MB. [2025-02-06T01:55:30.292Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2165.543 ms) ====== [2025-02-06T01:55:30.292Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-06T01:55:30.292Z] GC before operation: completed in 95.311 ms, heap usage 190.006 MB -> 86.739 MB. [2025-02-06T01:55:32.482Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2065.065 ms) ====== [2025-02-06T01:55:32.482Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-06T01:55:32.482Z] GC before operation: completed in 113.413 ms, heap usage 147.712 MB -> 64.553 MB. [2025-02-06T01:55:34.670Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1762.116 ms) ====== [2025-02-06T01:55:34.670Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-06T01:55:34.670Z] GC before operation: completed in 91.095 ms, heap usage 189.101 MB -> 65.448 MB. [2025-02-06T01:55:36.069Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1548.105 ms) ====== [2025-02-06T01:55:36.069Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-06T01:55:36.069Z] GC before operation: completed in 111.907 ms, heap usage 292.647 MB -> 76.404 MB. [2025-02-06T01:55:37.472Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1674.235 ms) ====== [2025-02-06T01:55:37.472Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-06T01:55:38.153Z] GC before operation: completed in 144.487 ms, heap usage 327.471 MB -> 82.179 MB. [2025-02-06T01:55:39.568Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1526.609 ms) ====== [2025-02-06T01:55:39.568Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-06T01:55:39.568Z] GC before operation: completed in 86.821 ms, heap usage 130.646 MB -> 65.574 MB. [2025-02-06T01:55:40.970Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1333.019 ms) ====== [2025-02-06T01:55:40.970Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-06T01:55:40.970Z] GC before operation: completed in 97.012 ms, heap usage 155.369 MB -> 65.970 MB. [2025-02-06T01:55:42.408Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1387.998 ms) ====== [2025-02-06T01:55:42.408Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-06T01:55:42.408Z] GC before operation: completed in 112.517 ms, heap usage 237.900 MB -> 65.927 MB. [2025-02-06T01:55:43.815Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1314.964 ms) ====== [2025-02-06T01:55:43.815Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-06T01:55:43.815Z] GC before operation: completed in 91.155 ms, heap usage 146.676 MB -> 93.345 MB. [2025-02-06T01:55:45.231Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1426.649 ms) ====== [2025-02-06T01:55:45.232Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-06T01:55:45.232Z] GC before operation: completed in 107.899 ms, heap usage 138.365 MB -> 65.728 MB. [2025-02-06T01:55:46.665Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1386.990 ms) ====== [2025-02-06T01:55:46.665Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-06T01:55:46.665Z] GC before operation: completed in 114.752 ms, heap usage 217.531 MB -> 77.541 MB. [2025-02-06T01:55:48.074Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1295.301 ms) ====== [2025-02-06T01:55:48.074Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-06T01:55:48.074Z] GC before operation: completed in 106.272 ms, heap usage 218.523 MB -> 66.397 MB. [2025-02-06T01:55:49.477Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1362.144 ms) ====== [2025-02-06T01:55:49.477Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-06T01:55:49.477Z] GC before operation: completed in 86.309 ms, heap usage 99.499 MB -> 88.215 MB. [2025-02-06T01:55:50.895Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1179.737 ms) ====== [2025-02-06T01:55:50.895Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-06T01:55:50.895Z] GC before operation: completed in 110.397 ms, heap usage 234.028 MB -> 66.627 MB. [2025-02-06T01:55:52.300Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1343.143 ms) ====== [2025-02-06T01:55:52.300Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-06T01:55:52.300Z] GC before operation: completed in 121.146 ms, heap usage 224.326 MB -> 66.922 MB. [2025-02-06T01:55:53.711Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1385.724 ms) ====== [2025-02-06T01:55:53.711Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-06T01:55:54.407Z] GC before operation: completed in 113.462 ms, heap usage 132.213 MB -> 66.858 MB. [2025-02-06T01:55:55.838Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1531.145 ms) ====== [2025-02-06T01:55:55.838Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-06T01:55:55.838Z] GC before operation: completed in 125.021 ms, heap usage 142.277 MB -> 83.365 MB. [2025-02-06T01:55:57.243Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1551.537 ms) ====== [2025-02-06T01:55:57.243Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-06T01:55:57.243Z] GC before operation: completed in 137.969 ms, heap usage 220.761 MB -> 67.223 MB. [2025-02-06T01:55:58.648Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1545.473 ms) ====== [2025-02-06T01:55:58.648Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-06T01:55:59.326Z] GC before operation: completed in 124.588 ms, heap usage 161.417 MB -> 67.130 MB. [2025-02-06T01:56:00.003Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1192.449 ms) ====== [2025-02-06T01:56:00.003Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-06T01:56:00.681Z] GC before operation: completed in 138.494 ms, heap usage 232.210 MB -> 83.989 MB. [2025-02-06T01:56:01.387Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1256.277 ms) ====== [2025-02-06T01:56:01.387Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-06T01:56:02.077Z] GC before operation: completed in 111.361 ms, heap usage 197.989 MB -> 84.091 MB. [2025-02-06T01:56:02.077Z] 25/02/05 19:56:01 WARN BlockManager: Asked to remove block broadcast_302_piece0, which does not exist [2025-02-06T01:56:03.488Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1387.866 ms) ====== [2025-02-06T01:56:03.488Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-06T01:56:03.488Z] GC before operation: completed in 125.321 ms, heap usage 183.280 MB -> 89.413 MB. [2025-02-06T01:56:04.182Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1175.832 ms) ====== [2025-02-06T01:56:04.182Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-06T01:56:04.860Z] GC before operation: completed in 111.799 ms, heap usage 223.056 MB -> 84.301 MB. [2025-02-06T01:56:04.860Z] 25/02/05 19:56:04 WARN BlockManager: Asked to remove block rdd_1173_0, which does not exist [2025-02-06T01:56:04.860Z] 25/02/05 19:56:04 WARN BlockManager: Asked to remove block rdd_1173_2, which does not exist [2025-02-06T01:56:05.537Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1295.725 ms) ====== [2025-02-06T01:56:05.537Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-06T01:56:06.227Z] GC before operation: completed in 173.906 ms, heap usage 309.764 MB -> 78.590 MB. [2025-02-06T01:56:07.632Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1298.360 ms) ====== [2025-02-06T01:56:07.632Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-06T01:56:07.632Z] GC before operation: completed in 120.457 ms, heap usage 175.263 MB -> 67.930 MB. [2025-02-06T01:56:09.041Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1279.067 ms) ====== [2025-02-06T01:56:09.041Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-06T01:56:09.041Z] GC before operation: completed in 117.743 ms, heap usage 196.114 MB -> 79.037 MB. [2025-02-06T01:56:10.448Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1487.334 ms) ====== [2025-02-06T01:56:10.448Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-06T01:56:10.448Z] GC before operation: completed in 113.039 ms, heap usage 286.133 MB -> 68.181 MB. [2025-02-06T01:56:11.850Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1291.822 ms) ====== [2025-02-06T01:56:11.850Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-06T01:56:11.850Z] GC before operation: completed in 121.074 ms, heap usage 215.193 MB -> 68.099 MB. [2025-02-06T01:56:13.254Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1370.884 ms) ====== [2025-02-06T01:56:13.254Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-06T01:56:13.254Z] GC before operation: completed in 109.423 ms, heap usage 154.779 MB -> 67.902 MB. [2025-02-06T01:56:14.658Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1167.831 ms) ====== [2025-02-06T01:56:14.658Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-06T01:56:14.658Z] GC before operation: completed in 113.157 ms, heap usage 192.234 MB -> 68.267 MB. [2025-02-06T01:56:16.065Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1139.365 ms) ====== [2025-02-06T01:56:16.065Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-06T01:56:16.065Z] GC before operation: completed in 116.797 ms, heap usage 154.513 MB -> 68.226 MB. [2025-02-06T01:56:17.488Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1252.935 ms) ====== [2025-02-06T01:56:17.488Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-06T01:56:17.488Z] GC before operation: completed in 115.625 ms, heap usage 146.014 MB -> 68.181 MB. [2025-02-06T01:56:18.169Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1191.593 ms) ====== [2025-02-06T01:56:18.169Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-06T01:56:18.848Z] GC before operation: completed in 108.459 ms, heap usage 187.804 MB -> 84.879 MB. [2025-02-06T01:56:19.540Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1277.483 ms) ====== [2025-02-06T01:56:19.540Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-06T01:56:20.220Z] GC before operation: completed in 114.510 ms, heap usage 163.263 MB -> 84.904 MB. [2025-02-06T01:56:20.900Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1185.239 ms) ====== [2025-02-06T01:56:20.900Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-06T01:56:21.580Z] GC before operation: completed in 123.425 ms, heap usage 203.456 MB -> 85.444 MB. [2025-02-06T01:56:22.992Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1385.871 ms) ====== [2025-02-06T01:56:22.992Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-06T01:56:22.992Z] GC before operation: completed in 127.494 ms, heap usage 199.757 MB -> 85.340 MB. [2025-02-06T01:56:23.667Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1213.010 ms) ====== [2025-02-06T01:56:23.667Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-06T01:56:24.345Z] GC before operation: completed in 110.896 ms, heap usage 117.169 MB -> 68.657 MB. [2025-02-06T01:56:25.748Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1348.873 ms) ====== [2025-02-06T01:56:25.748Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-06T01:56:25.748Z] GC before operation: completed in 172.577 ms, heap usage 319.687 MB -> 91.196 MB. [2025-02-06T01:56:27.153Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1316.190 ms) ====== [2025-02-06T01:56:27.153Z] ----------------------------------- [2025-02-06T01:56:27.153Z] renaissance-dec-tree_0_PASSED [2025-02-06T01:56:27.153Z] ----------------------------------- [2025-02-06T01:56:27.153Z] [2025-02-06T01:56:27.153Z] TEST TEARDOWN: [2025-02-06T01:56:27.153Z] Nothing to be done for teardown. [2025-02-06T01:56:27.153Z] renaissance-dec-tree_0 Finish Time: Wed Feb 5 19:56:27 2025 Epoch Time (ms): 1738806987261