[2024-09-25T21:33:35.693Z] Running test renaissance-dec-tree_0 ... [2024-09-25T21:33:35.693Z] =============================================== [2024-09-25T21:33:35.693Z] renaissance-dec-tree_0 Start Time: Wed Sep 25 21:33:34 2024 Epoch Time (ms): 1727300014640 [2024-09-25T21:33:35.693Z] variation: NoOptions [2024-09-25T21:33:35.693Z] JVM_OPTIONS: [2024-09-25T21:33:35.693Z] { \ [2024-09-25T21:33:35.693Z] echo ""; echo "TEST SETUP:"; \ [2024-09-25T21:33:35.693Z] echo "Nothing to be done for setup."; \ [2024-09-25T21:33:35.693Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1727299738973/renaissance-dec-tree_0"; \ [2024-09-25T21:33:35.693Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1727299738973/renaissance-dec-tree_0"; \ [2024-09-25T21:33:35.693Z] echo ""; echo "TESTING:"; \ [2024-09-25T21:33:35.693Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1727299738973/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-09-25T21:33:35.693Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1727299738973/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-25T21:33:35.693Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-25T21:33:35.693Z] echo "Nothing to be done for teardown."; \ [2024-09-25T21:33:35.693Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1727299738973/TestTargetResult"; [2024-09-25T21:33:35.693Z] [2024-09-25T21:33:35.693Z] TEST SETUP: [2024-09-25T21:33:35.693Z] Nothing to be done for setup. [2024-09-25T21:33:35.694Z] [2024-09-25T21:33:35.694Z] TESTING: [2024-09-25T21:33:37.627Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-25T21:33:40.613Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2024-09-25T21:33:41.555Z] WARNING: An illegal reflective access operation has occurred [2024-09-25T21:33:41.556Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_1727299738973/renaissance-dec-tree_0/harness-213335-11615348420424224869/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-25T21:33:41.556Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-25T21:33:41.556Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-25T21:33:41.556Z] WARNING: All illegal access operations will be denied in a future release [2024-09-25T21:33:43.490Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-25T21:33:43.490Z] GC before operation: completed in 52.049 ms, heap usage 79.492 MB -> 34.323 MB. [2024-09-25T21:33:51.556Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7634.778 ms) ====== [2024-09-25T21:33:51.556Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-25T21:33:51.556Z] GC before operation: completed in 120.509 ms, heap usage 320.233 MB -> 64.193 MB. [2024-09-25T21:33:54.536Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2902.682 ms) ====== [2024-09-25T21:33:54.536Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-25T21:33:54.536Z] GC before operation: completed in 103.265 ms, heap usage 160.507 MB -> 64.610 MB. [2024-09-25T21:33:55.478Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1499.531 ms) ====== [2024-09-25T21:33:55.478Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-25T21:33:55.478Z] GC before operation: completed in 115.867 ms, heap usage 140.146 MB -> 64.534 MB. [2024-09-25T21:33:57.414Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1427.559 ms) ====== [2024-09-25T21:33:57.415Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-25T21:33:57.415Z] GC before operation: completed in 128.981 ms, heap usage 225.347 MB -> 65.059 MB. [2024-09-25T21:33:58.358Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1351.799 ms) ====== [2024-09-25T21:33:58.358Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-25T21:33:59.471Z] GC before operation: completed in 117.645 ms, heap usage 100.334 MB -> 64.853 MB. [2024-09-25T21:34:00.414Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1719.644 ms) ====== [2024-09-25T21:34:00.414Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-25T21:34:00.414Z] GC before operation: completed in 126.996 ms, heap usage 272.242 MB -> 65.613 MB. [2024-09-25T21:34:02.346Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1293.014 ms) ====== [2024-09-25T21:34:02.346Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-25T21:34:02.346Z] GC before operation: completed in 126.592 ms, heap usage 107.555 MB -> 65.267 MB. [2024-09-25T21:34:03.299Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1221.841 ms) ====== [2024-09-25T21:34:03.299Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-25T21:34:03.299Z] GC before operation: completed in 125.370 ms, heap usage 180.655 MB -> 65.933 MB. [2024-09-25T21:34:05.229Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1220.183 ms) ====== [2024-09-25T21:34:05.229Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-25T21:34:05.229Z] GC before operation: completed in 137.153 ms, heap usage 166.036 MB -> 65.624 MB. [2024-09-25T21:34:06.169Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1213.565 ms) ====== [2024-09-25T21:34:06.169Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-25T21:34:06.169Z] GC before operation: completed in 137.789 ms, heap usage 140.372 MB -> 65.716 MB. [2024-09-25T21:34:07.111Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1172.430 ms) ====== [2024-09-25T21:34:07.111Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-25T21:34:07.111Z] GC before operation: completed in 141.874 ms, heap usage 140.465 MB -> 65.968 MB. [2024-09-25T21:34:09.045Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1207.686 ms) ====== [2024-09-25T21:34:09.045Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-25T21:34:09.045Z] GC before operation: completed in 141.492 ms, heap usage 138.210 MB -> 66.077 MB. [2024-09-25T21:34:10.973Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1663.792 ms) ====== [2024-09-25T21:34:10.973Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-25T21:34:10.973Z] GC before operation: completed in 145.885 ms, heap usage 166.792 MB -> 66.208 MB. [2024-09-25T21:34:11.915Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1185.637 ms) ====== [2024-09-25T21:34:11.915Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-25T21:34:11.915Z] GC before operation: completed in 146.415 ms, heap usage 143.903 MB -> 66.184 MB. [2024-09-25T21:34:12.854Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1203.342 ms) ====== [2024-09-25T21:34:12.854Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-25T21:34:13.926Z] GC before operation: completed in 157.137 ms, heap usage 158.070 MB -> 66.454 MB. [2024-09-25T21:34:14.866Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1119.369 ms) ====== [2024-09-25T21:34:14.866Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-25T21:34:14.866Z] GC before operation: completed in 156.720 ms, heap usage 246.454 MB -> 66.875 MB. [2024-09-25T21:34:15.806Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1169.286 ms) ====== [2024-09-25T21:34:15.806Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-25T21:34:15.806Z] GC before operation: completed in 165.915 ms, heap usage 210.008 MB -> 66.976 MB. [2024-09-25T21:34:16.745Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1137.270 ms) ====== [2024-09-25T21:34:16.745Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-25T21:34:17.685Z] GC before operation: completed in 159.988 ms, heap usage 179.273 MB -> 67.079 MB. [2024-09-25T21:34:18.625Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1361.896 ms) ====== [2024-09-25T21:34:18.625Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-25T21:34:18.625Z] GC before operation: completed in 157.182 ms, heap usage 163.331 MB -> 66.916 MB. [2024-09-25T21:34:19.567Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1139.051 ms) ====== [2024-09-25T21:34:19.567Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-25T21:34:20.506Z] GC before operation: completed in 162.307 ms, heap usage 207.614 MB -> 67.283 MB. [2024-09-25T21:34:21.446Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1093.272 ms) ====== [2024-09-25T21:34:21.446Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-25T21:34:21.446Z] GC before operation: completed in 167.608 ms, heap usage 223.477 MB -> 67.241 MB. [2024-09-25T21:34:22.389Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1124.829 ms) ====== [2024-09-25T21:34:22.389Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-25T21:34:22.389Z] GC before operation: completed in 162.057 ms, heap usage 167.828 MB -> 67.072 MB. [2024-09-25T21:34:24.350Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1092.928 ms) ====== [2024-09-25T21:34:24.350Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-25T21:34:24.350Z] GC before operation: completed in 163.565 ms, heap usage 199.153 MB -> 67.443 MB. [2024-09-25T21:34:25.289Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1628.797 ms) ====== [2024-09-25T21:34:25.289Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-25T21:34:26.230Z] GC before operation: completed in 171.456 ms, heap usage 230.809 MB -> 67.663 MB. [2024-09-25T21:34:27.169Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1111.706 ms) ====== [2024-09-25T21:34:27.169Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-25T21:34:27.169Z] GC before operation: completed in 164.794 ms, heap usage 194.991 MB -> 67.768 MB. [2024-09-25T21:34:29.101Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1545.490 ms) ====== [2024-09-25T21:34:29.101Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-25T21:34:29.101Z] GC before operation: completed in 168.528 ms, heap usage 218.572 MB -> 67.863 MB. [2024-09-25T21:34:30.040Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1139.807 ms) ====== [2024-09-25T21:34:30.040Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-25T21:34:30.040Z] GC before operation: completed in 226.547 ms, heap usage 186.825 MB -> 67.825 MB. [2024-09-25T21:34:30.980Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1116.977 ms) ====== [2024-09-25T21:34:30.980Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-25T21:34:30.980Z] GC before operation: completed in 172.358 ms, heap usage 155.377 MB -> 67.657 MB. [2024-09-25T21:34:32.909Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1474.836 ms) ====== [2024-09-25T21:34:32.909Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-25T21:34:32.909Z] GC before operation: completed in 179.705 ms, heap usage 219.820 MB -> 68.026 MB. [2024-09-25T21:34:33.860Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1065.337 ms) ====== [2024-09-25T21:34:33.860Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-25T21:34:33.860Z] GC before operation: completed in 179.372 ms, heap usage 204.930 MB -> 68.247 MB. [2024-09-25T21:34:35.788Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1079.587 ms) ====== [2024-09-25T21:34:35.788Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-25T21:34:35.788Z] GC before operation: completed in 172.226 ms, heap usage 206.105 MB -> 68.199 MB. [2024-09-25T21:34:36.756Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1190.698 ms) ====== [2024-09-25T21:34:36.756Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-25T21:34:36.756Z] GC before operation: completed in 167.561 ms, heap usage 160.717 MB -> 68.179 MB. [2024-09-25T21:34:37.698Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1072.158 ms) ====== [2024-09-25T21:34:37.698Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-25T21:34:39.347Z] GC before operation: completed in 174.941 ms, heap usage 161.692 MB -> 68.131 MB. [2024-09-25T21:34:39.347Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1440.424 ms) ====== [2024-09-25T21:34:39.347Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-25T21:34:39.347Z] GC before operation: completed in 178.587 ms, heap usage 252.815 MB -> 68.517 MB. [2024-09-25T21:34:41.277Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1069.219 ms) ====== [2024-09-25T21:34:41.277Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-25T21:34:41.277Z] GC before operation: completed in 178.811 ms, heap usage 290.473 MB -> 68.624 MB. [2024-09-25T21:34:42.217Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1294.583 ms) ====== [2024-09-25T21:34:42.217Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-25T21:34:42.217Z] GC before operation: completed in 174.834 ms, heap usage 267.577 MB -> 68.730 MB. [2024-09-25T21:34:43.160Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1078.387 ms) ====== [2024-09-25T21:34:43.160Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-25T21:34:44.099Z] GC before operation: completed in 177.853 ms, heap usage 238.308 MB -> 79.910 MB. [2024-09-25T21:34:45.043Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1098.094 ms) ====== [2024-09-25T21:34:45.043Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-25T21:34:45.043Z] GC before operation: completed in 172.670 ms, heap usage 204.001 MB -> 68.898 MB. [2024-09-25T21:34:45.993Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1051.170 ms) ====== [2024-09-25T21:34:45.993Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-25T21:34:45.993Z] GC before operation: completed in 176.275 ms, heap usage 312.509 MB -> 69.144 MB. [2024-09-25T21:34:47.923Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1914.666 ms) ====== [2024-09-25T21:34:48.864Z] ----------------------------------- [2024-09-25T21:34:48.864Z] renaissance-dec-tree_0_PASSED [2024-09-25T21:34:48.864Z] ----------------------------------- [2024-09-25T21:34:48.864Z] [2024-09-25T21:34:48.864Z] TEST TEARDOWN: [2024-09-25T21:34:48.864Z] Nothing to be done for teardown. [2024-09-25T21:34:48.864Z] renaissance-dec-tree_0 Finish Time: Wed Sep 25 21:34:48 2024 Epoch Time (ms): 1727300088045