[2024-08-23T22:15:41.705Z] Running test renaissance-dec-tree_0 ... [2024-08-23T22:15:41.705Z] =============================================== [2024-08-23T22:15:41.705Z] renaissance-dec-tree_0 Start Time: Fri Aug 23 22:15:38 2024 Epoch Time (ms): 1724451338738 [2024-08-23T22:15:41.705Z] variation: NoOptions [2024-08-23T22:15:41.705Z] JVM_OPTIONS: [2024-08-23T22:15:41.705Z] { \ [2024-08-23T22:15:41.705Z] echo ""; echo "TEST SETUP:"; \ [2024-08-23T22:15:41.705Z] echo "Nothing to be done for setup."; \ [2024-08-23T22:15:41.705Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1724445910903/renaissance-dec-tree_0"; \ [2024-08-23T22:15:41.705Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1724445910903/renaissance-dec-tree_0"; \ [2024-08-23T22:15:41.705Z] echo ""; echo "TESTING:"; \ [2024-08-23T22:15:41.705Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-11.0.25+4/bin/..//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_1724445910903/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-23T22:15:41.705Z] 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_1724445910903/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-23T22:15:41.705Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-23T22:15:41.705Z] echo "Nothing to be done for teardown."; \ [2024-08-23T22:15:41.705Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1724445910903/TestTargetResult"; [2024-08-23T22:15:41.705Z] [2024-08-23T22:15:41.705Z] TEST SETUP: [2024-08-23T22:15:41.705Z] Nothing to be done for setup. [2024-08-23T22:15:41.705Z] [2024-08-23T22:15:41.705Z] TESTING: [2024-08-23T22:16:04.150Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-23T22:16:20.608Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-23T22:16:32.442Z] WARNING: An illegal reflective access operation has occurred [2024-08-23T22:16:32.442Z] 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_1724445910903/renaissance-dec-tree_0/harness-221545-15340660472642739436/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-23T22:16:32.442Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-23T22:16:32.442Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-23T22:16:32.442Z] WARNING: All illegal access operations will be denied in a future release [2024-08-23T22:16:51.556Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-23T22:16:53.007Z] GC before operation: completed in 640.807 ms, heap usage 71.939 MB -> 34.003 MB. [2024-08-23T22:18:42.331Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (96552.652 ms) ====== [2024-08-23T22:18:42.331Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-23T22:18:42.331Z] GC before operation: completed in 807.077 ms, heap usage 235.347 MB -> 64.020 MB. [2024-08-23T22:19:05.042Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (35432.831 ms) ====== [2024-08-23T22:19:05.042Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-23T22:19:06.627Z] GC before operation: completed in 1322.323 ms, heap usage 119.581 MB -> 63.888 MB. [2024-08-23T22:19:37.041Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (28517.234 ms) ====== [2024-08-23T22:19:37.041Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-23T22:19:37.041Z] GC before operation: completed in 1204.200 ms, heap usage 198.685 MB -> 64.814 MB. [2024-08-23T22:20:03.086Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (26081.118 ms) ====== [2024-08-23T22:20:03.086Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-23T22:20:04.151Z] GC before operation: completed in 1299.455 ms, heap usage 118.890 MB -> 64.846 MB. [2024-08-23T22:20:30.335Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (23425.256 ms) ====== [2024-08-23T22:20:30.336Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-23T22:20:30.336Z] GC before operation: completed in 1212.134 ms, heap usage 108.578 MB -> 64.750 MB. [2024-08-23T22:20:49.574Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (20288.093 ms) ====== [2024-08-23T22:20:49.574Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-23T22:20:51.201Z] GC before operation: completed in 1340.233 ms, heap usage 219.303 MB -> 65.078 MB. [2024-08-23T22:21:10.201Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (20435.333 ms) ====== [2024-08-23T22:21:10.201Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-23T22:21:11.872Z] GC before operation: completed in 1172.626 ms, heap usage 134.438 MB -> 65.238 MB. [2024-08-23T22:21:30.932Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (18203.949 ms) ====== [2024-08-23T22:21:30.932Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-23T22:21:30.932Z] GC before operation: completed in 893.145 ms, heap usage 105.509 MB -> 65.091 MB. [2024-08-23T22:21:47.428Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (16943.704 ms) ====== [2024-08-23T22:21:47.428Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-23T22:21:49.027Z] GC before operation: completed in 1244.797 ms, heap usage 242.308 MB -> 65.494 MB. [2024-08-23T22:22:08.407Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (18310.304 ms) ====== [2024-08-23T22:22:08.407Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-23T22:22:08.407Z] GC before operation: completed in 1091.090 ms, heap usage 202.723 MB -> 65.699 MB. [2024-08-23T22:22:30.799Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (22391.227 ms) ====== [2024-08-23T22:22:30.799Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-23T22:22:32.383Z] GC before operation: completed in 1226.481 ms, heap usage 238.192 MB -> 65.699 MB. [2024-08-23T22:22:54.590Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (20267.766 ms) ====== [2024-08-23T22:22:54.590Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-23T22:22:54.590Z] GC before operation: completed in 1473.659 ms, heap usage 167.258 MB -> 66.194 MB. [2024-08-23T22:23:13.462Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (19841.542 ms) ====== [2024-08-23T22:23:13.463Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-23T22:23:15.081Z] GC before operation: completed in 1360.954 ms, heap usage 238.576 MB -> 66.058 MB. [2024-08-23T22:23:34.338Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (19343.986 ms) ====== [2024-08-23T22:23:34.338Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-23T22:23:35.928Z] GC before operation: completed in 1319.479 ms, heap usage 255.715 MB -> 67.604 MB. [2024-08-23T22:23:52.437Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (14843.729 ms) ====== [2024-08-23T22:23:52.437Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-23T22:23:52.437Z] GC before operation: completed in 1154.842 ms, heap usage 172.024 MB -> 66.124 MB. [2024-08-23T22:24:08.685Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (16156.305 ms) ====== [2024-08-23T22:24:08.685Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-23T22:24:08.685Z] GC before operation: completed in 1201.266 ms, heap usage 271.499 MB -> 66.443 MB. [2024-08-23T22:24:27.986Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (17598.550 ms) ====== [2024-08-23T22:24:27.986Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-23T22:24:27.986Z] GC before operation: completed in 1326.274 ms, heap usage 114.551 MB -> 66.290 MB. [2024-08-23T22:24:44.108Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (15629.715 ms) ====== [2024-08-23T22:24:44.108Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-23T22:24:44.870Z] GC before operation: completed in 1081.487 ms, heap usage 220.985 MB -> 66.648 MB. [2024-08-23T22:25:00.999Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (15369.188 ms) ====== [2024-08-23T22:25:00.999Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-23T22:25:01.779Z] GC before operation: completed in 1241.380 ms, heap usage 206.952 MB -> 66.599 MB. [2024-08-23T22:25:17.938Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (15991.551 ms) ====== [2024-08-23T22:25:17.938Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-23T22:25:18.707Z] GC before operation: completed in 1390.933 ms, heap usage 255.594 MB -> 67.253 MB. [2024-08-23T22:25:35.528Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (16325.501 ms) ====== [2024-08-23T22:25:35.528Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-23T22:25:36.287Z] GC before operation: completed in 1080.799 ms, heap usage 131.149 MB -> 66.795 MB. [2024-08-23T22:25:52.457Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (16529.469 ms) ====== [2024-08-23T22:25:52.457Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-23T22:25:54.033Z] GC before operation: completed in 1392.121 ms, heap usage 208.350 MB -> 66.893 MB. [2024-08-23T22:26:12.999Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (17798.685 ms) ====== [2024-08-23T22:26:12.999Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-23T22:26:12.999Z] GC before operation: completed in 1387.474 ms, heap usage 231.626 MB -> 67.175 MB. [2024-08-23T22:26:29.290Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (15544.196 ms) ====== [2024-08-23T22:26:29.290Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-23T22:26:30.064Z] GC before operation: completed in 1413.893 ms, heap usage 204.525 MB -> 67.405 MB. [2024-08-23T22:26:48.997Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (16563.911 ms) ====== [2024-08-23T22:26:48.997Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-23T22:26:48.997Z] GC before operation: completed in 1257.741 ms, heap usage 136.047 MB -> 67.318 MB. [2024-08-23T22:27:05.326Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (16048.744 ms) ====== [2024-08-23T22:27:05.326Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-23T22:27:05.326Z] GC before operation: completed in 1146.091 ms, heap usage 191.221 MB -> 67.277 MB. [2024-08-23T22:27:22.185Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (16525.032 ms) ====== [2024-08-23T22:27:22.186Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-23T22:27:22.962Z] GC before operation: completed in 1293.131 ms, heap usage 103.082 MB -> 67.090 MB. [2024-08-23T22:27:39.329Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (16353.181 ms) ====== [2024-08-23T22:27:39.329Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-23T22:27:41.019Z] GC before operation: completed in 1811.892 ms, heap usage 256.776 MB -> 67.507 MB. [2024-08-23T22:28:00.088Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (16829.786 ms) ====== [2024-08-23T22:28:00.088Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-23T22:28:00.088Z] GC before operation: completed in 1434.560 ms, heap usage 201.615 MB -> 67.622 MB. [2024-08-23T22:28:16.483Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (16085.717 ms) ====== [2024-08-23T22:28:16.483Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-23T22:28:17.264Z] GC before operation: completed in 1283.700 ms, heap usage 241.094 MB -> 68.372 MB. [2024-08-23T22:28:33.649Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (17049.010 ms) ====== [2024-08-23T22:28:33.649Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-23T22:28:35.356Z] GC before operation: completed in 1273.629 ms, heap usage 237.458 MB -> 67.802 MB. [2024-08-23T22:28:51.712Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (16412.054 ms) ====== [2024-08-23T22:28:51.712Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-23T22:28:53.834Z] GC before operation: completed in 1494.283 ms, heap usage 113.721 MB -> 67.764 MB. [2024-08-23T22:29:10.117Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (15890.792 ms) ====== [2024-08-23T22:29:10.117Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-23T22:29:10.907Z] GC before operation: completed in 1497.368 ms, heap usage 246.657 MB -> 67.999 MB. [2024-08-23T22:29:27.210Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (16327.126 ms) ====== [2024-08-23T22:29:27.210Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-23T22:29:27.958Z] GC before operation: completed in 1374.062 ms, heap usage 112.619 MB -> 67.806 MB. [2024-08-23T22:29:47.123Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (17615.817 ms) ====== [2024-08-23T22:29:47.123Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-23T22:29:47.123Z] GC before operation: completed in 1295.144 ms, heap usage 210.560 MB -> 68.764 MB. [2024-08-23T22:29:47.123Z] 24/08/23 22:29:46 WARN BlockManager: Asked to remove block broadcast_488_piece0, which does not exist [2024-08-23T22:30:05.976Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (17676.725 ms) ====== [2024-08-23T22:30:05.976Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-23T22:30:05.976Z] GC before operation: completed in 1453.046 ms, heap usage 273.721 MB -> 68.440 MB. [2024-08-23T22:30:22.272Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (15868.505 ms) ====== [2024-08-23T22:30:22.272Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-23T22:30:23.865Z] GC before operation: completed in 1405.874 ms, heap usage 235.184 MB -> 68.458 MB. [2024-08-23T22:30:43.128Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (19229.399 ms) ====== [2024-08-23T22:30:43.128Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-23T22:30:44.734Z] GC before operation: completed in 1506.410 ms, heap usage 120.422 MB -> 68.543 MB. [2024-08-23T22:31:01.142Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (16313.585 ms) ====== [2024-08-23T22:31:01.142Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-23T22:31:01.892Z] GC before operation: completed in 1456.817 ms, heap usage 197.225 MB -> 68.722 MB. [2024-08-23T22:31:18.336Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (16023.336 ms) ====== [2024-08-23T22:31:20.826Z] ----------------------------------- [2024-08-23T22:31:20.826Z] renaissance-dec-tree_0_PASSED [2024-08-23T22:31:20.826Z] ----------------------------------- [2024-08-23T22:31:20.826Z] [2024-08-23T22:31:20.826Z] TEST TEARDOWN: [2024-08-23T22:31:20.826Z] Nothing to be done for teardown. [2024-08-23T22:31:20.826Z] renaissance-dec-tree_0 Finish Time: Fri Aug 23 22:31:20 2024 Epoch Time (ms): 1724452280622