[2025-01-10T20:33:59.560Z] Running test renaissance-dec-tree_0 ... [2025-01-10T20:33:59.560Z] =============================================== [2025-01-10T20:33:59.560Z] renaissance-dec-tree_0 Start Time: Fri Jan 10 20:33:59 2025 Epoch Time (ms): 1736541239452 [2025-01-10T20:33:59.560Z] variation: NoOptions [2025-01-10T20:33:59.560Z] JVM_OPTIONS: [2025-01-10T20:33:59.560Z] { \ [2025-01-10T20:33:59.560Z] echo ""; echo "TEST SETUP:"; \ [2025-01-10T20:33:59.560Z] echo "Nothing to be done for setup."; \ [2025-01-10T20:33:59.560Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17365384955262/renaissance-dec-tree_0"; \ [2025-01-10T20:33:59.560Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17365384955262/renaissance-dec-tree_0"; \ [2025-01-10T20:33:59.560Z] echo ""; echo "TESTING:"; \ [2025-01-10T20:33:59.560Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17365384955262/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-01-10T20:33:59.560Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17365384955262/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-10T20:33:59.560Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-10T20:33:59.560Z] echo "Nothing to be done for teardown."; \ [2025-01-10T20:33:59.560Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17365384955262/TestTargetResult"; [2025-01-10T20:33:59.560Z] [2025-01-10T20:33:59.560Z] TEST SETUP: [2025-01-10T20:33:59.560Z] Nothing to be done for setup. [2025-01-10T20:33:59.560Z] [2025-01-10T20:33:59.560Z] TESTING: [2025-01-10T20:34:14.272Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-10T20:34:26.437Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-01-10T20:34:32.239Z] WARNING: An illegal reflective access operation has occurred [2025-01-10T20:34:32.239Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17365384955262/renaissance-dec-tree_0/harness-203402-7197659769931436627/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-01-10T20:34:32.239Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-01-10T20:34:32.239Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-01-10T20:34:32.239Z] WARNING: All illegal access operations will be denied in a future release [2025-01-10T20:34:46.414Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-01-10T20:34:46.414Z] GC before operation: completed in 416.771 ms, heap usage 52.834 MB -> 33.807 MB. [2025-01-10T20:35:55.074Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (65585.183 ms) ====== [2025-01-10T20:35:55.074Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-01-10T20:35:55.074Z] GC before operation: completed in 942.664 ms, heap usage 201.911 MB -> 63.812 MB. [2025-01-10T20:36:17.959Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (25833.987 ms) ====== [2025-01-10T20:36:17.959Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-01-10T20:36:18.772Z] GC before operation: completed in 946.118 ms, heap usage 169.544 MB -> 64.611 MB. [2025-01-10T20:36:41.225Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (21253.506 ms) ====== [2025-01-10T20:36:41.225Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-01-10T20:36:41.225Z] GC before operation: completed in 856.281 ms, heap usage 95.982 MB -> 64.310 MB. [2025-01-10T20:36:57.671Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (16629.802 ms) ====== [2025-01-10T20:36:57.671Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-01-10T20:36:58.482Z] GC before operation: completed in 721.604 ms, heap usage 185.524 MB -> 64.431 MB. [2025-01-10T20:37:17.667Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (17162.143 ms) ====== [2025-01-10T20:37:17.667Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-01-10T20:37:17.668Z] GC before operation: completed in 677.873 ms, heap usage 112.079 MB -> 64.784 MB. [2025-01-10T20:37:30.174Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (14316.935 ms) ====== [2025-01-10T20:37:30.174Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-01-10T20:37:31.798Z] GC before operation: completed in 852.916 ms, heap usage 199.811 MB -> 65.134 MB. [2025-01-10T20:37:45.998Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (14741.280 ms) ====== [2025-01-10T20:37:45.998Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-01-10T20:37:46.799Z] GC before operation: completed in 760.924 ms, heap usage 104.356 MB -> 64.960 MB. [2025-01-10T20:37:58.836Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (12132.808 ms) ====== [2025-01-10T20:37:58.836Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-01-10T20:37:59.646Z] GC before operation: completed in 869.761 ms, heap usage 116.308 MB -> 65.033 MB. [2025-01-10T20:38:13.807Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (12508.629 ms) ====== [2025-01-10T20:38:13.807Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-01-10T20:38:13.807Z] GC before operation: completed in 766.156 ms, heap usage 270.930 MB -> 65.537 MB. [2025-01-10T20:38:23.937Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (10629.038 ms) ====== [2025-01-10T20:38:23.937Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-01-10T20:38:24.728Z] GC before operation: completed in 832.045 ms, heap usage 104.106 MB -> 65.246 MB. [2025-01-10T20:38:37.056Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (11367.021 ms) ====== [2025-01-10T20:38:37.056Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-01-10T20:38:37.056Z] GC before operation: completed in 709.975 ms, heap usage 178.969 MB -> 65.642 MB. [2025-01-10T20:38:47.168Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (10475.124 ms) ====== [2025-01-10T20:38:47.168Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-01-10T20:38:47.966Z] GC before operation: completed in 959.864 ms, heap usage 193.159 MB -> 65.752 MB. [2025-01-10T20:39:02.118Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (12252.014 ms) ====== [2025-01-10T20:39:02.118Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-01-10T20:39:02.118Z] GC before operation: completed in 869.018 ms, heap usage 201.065 MB -> 65.904 MB. [2025-01-10T20:39:14.278Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (11803.945 ms) ====== [2025-01-10T20:39:14.278Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-01-10T20:39:14.278Z] GC before operation: completed in 840.692 ms, heap usage 187.468 MB -> 65.959 MB. [2025-01-10T20:39:24.600Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (9622.996 ms) ====== [2025-01-10T20:39:24.600Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-01-10T20:39:24.600Z] GC before operation: completed in 778.532 ms, heap usage 166.509 MB -> 65.924 MB. [2025-01-10T20:39:34.743Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (10175.678 ms) ====== [2025-01-10T20:39:34.743Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-01-10T20:39:35.549Z] GC before operation: completed in 850.498 ms, heap usage 132.907 MB -> 66.219 MB. [2025-01-10T20:39:46.395Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (10288.262 ms) ====== [2025-01-10T20:39:46.395Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-01-10T20:39:46.395Z] GC before operation: completed in 886.068 ms, heap usage 173.330 MB -> 66.095 MB. [2025-01-10T20:39:56.726Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (10042.794 ms) ====== [2025-01-10T20:39:56.726Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-01-10T20:39:57.558Z] GC before operation: completed in 894.719 ms, heap usage 287.010 MB -> 66.320 MB. [2025-01-10T20:40:07.783Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (10516.871 ms) ====== [2025-01-10T20:40:07.783Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-01-10T20:40:08.607Z] GC before operation: completed in 843.502 ms, heap usage 143.440 MB -> 66.549 MB. [2025-01-10T20:40:18.786Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (9918.665 ms) ====== [2025-01-10T20:40:18.786Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-01-10T20:40:19.582Z] GC before operation: completed in 765.696 ms, heap usage 234.824 MB -> 66.707 MB. [2025-01-10T20:40:29.794Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (10180.392 ms) ====== [2025-01-10T20:40:29.794Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-01-10T20:40:30.585Z] GC before operation: completed in 888.430 ms, heap usage 273.495 MB -> 66.759 MB. [2025-01-10T20:40:40.743Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (9935.246 ms) ====== [2025-01-10T20:40:40.743Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-01-10T20:40:41.540Z] GC before operation: completed in 790.754 ms, heap usage 214.870 MB -> 66.854 MB. [2025-01-10T20:40:50.709Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (9185.533 ms) ====== [2025-01-10T20:40:50.709Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-01-10T20:40:51.507Z] GC before operation: completed in 705.821 ms, heap usage 146.962 MB -> 66.678 MB. [2025-01-10T20:41:00.148Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (8908.042 ms) ====== [2025-01-10T20:41:00.148Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-01-10T20:41:00.943Z] GC before operation: completed in 806.054 ms, heap usage 255.145 MB -> 67.056 MB. [2025-01-10T20:41:11.217Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (10419.728 ms) ====== [2025-01-10T20:41:11.217Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-01-10T20:41:12.022Z] GC before operation: completed in 917.949 ms, heap usage 268.682 MB -> 67.020 MB. [2025-01-10T20:41:22.364Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (10336.488 ms) ====== [2025-01-10T20:41:22.364Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-01-10T20:41:23.174Z] GC before operation: completed in 839.348 ms, heap usage 114.165 MB -> 66.827 MB. [2025-01-10T20:41:33.303Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (9563.265 ms) ====== [2025-01-10T20:41:33.303Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-01-10T20:41:34.091Z] GC before operation: completed in 969.289 ms, heap usage 205.892 MB -> 67.700 MB. [2025-01-10T20:41:44.240Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (9166.143 ms) ====== [2025-01-10T20:41:44.240Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-01-10T20:41:44.240Z] GC before operation: completed in 786.370 ms, heap usage 203.130 MB -> 67.459 MB. [2025-01-10T20:41:53.056Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (9101.114 ms) ====== [2025-01-10T20:41:53.057Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-01-10T20:41:54.132Z] GC before operation: completed in 1023.695 ms, heap usage 128.616 MB -> 94.468 MB. [2025-01-10T20:42:04.485Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (9286.243 ms) ====== [2025-01-10T20:42:04.485Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-01-10T20:42:04.485Z] GC before operation: completed in 850.615 ms, heap usage 241.691 MB -> 67.684 MB. [2025-01-10T20:42:14.857Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (10254.704 ms) ====== [2025-01-10T20:42:14.857Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-01-10T20:42:15.655Z] GC before operation: completed in 1221.977 ms, heap usage 174.867 MB -> 67.364 MB. [2025-01-10T20:42:26.000Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (10395.501 ms) ====== [2025-01-10T20:42:26.000Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-01-10T20:42:27.680Z] GC before operation: completed in 1044.540 ms, heap usage 103.323 MB -> 67.448 MB. [2025-01-10T20:42:38.413Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (10286.683 ms) ====== [2025-01-10T20:42:38.414Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-01-10T20:42:38.414Z] GC before operation: completed in 1034.423 ms, heap usage 209.143 MB -> 67.985 MB. [2025-01-10T20:42:49.036Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (9735.734 ms) ====== [2025-01-10T20:42:49.036Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-01-10T20:42:49.036Z] GC before operation: completed in 961.920 ms, heap usage 173.605 MB -> 67.799 MB. [2025-01-10T20:42:59.623Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (9367.167 ms) ====== [2025-01-10T20:42:59.623Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-01-10T20:42:59.623Z] GC before operation: completed in 889.261 ms, heap usage 215.110 MB -> 68.177 MB. [2025-01-10T20:43:10.924Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (10990.650 ms) ====== [2025-01-10T20:43:10.924Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-01-10T20:43:11.783Z] GC before operation: completed in 1370.542 ms, heap usage 158.013 MB -> 68.272 MB. [2025-01-10T20:43:22.472Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (9856.081 ms) ====== [2025-01-10T20:43:22.473Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-01-10T20:43:22.473Z] GC before operation: completed in 962.249 ms, heap usage 254.943 MB -> 68.438 MB. [2025-01-10T20:43:31.717Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (9142.967 ms) ====== [2025-01-10T20:43:31.717Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-01-10T20:43:32.583Z] GC before operation: completed in 835.464 ms, heap usage 199.730 MB -> 68.462 MB. [2025-01-10T20:43:43.234Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (10025.526 ms) ====== [2025-01-10T20:43:43.234Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-01-10T20:43:44.086Z] GC before operation: completed in 1337.831 ms, heap usage 169.169 MB -> 68.287 MB. [2025-01-10T20:43:53.082Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (9316.660 ms) ====== [2025-01-10T20:43:55.834Z] ----------------------------------- [2025-01-10T20:43:55.834Z] renaissance-dec-tree_0_PASSED [2025-01-10T20:43:55.834Z] ----------------------------------- [2025-01-10T20:43:55.834Z] [2025-01-10T20:43:55.834Z] TEST TEARDOWN: [2025-01-10T20:43:55.834Z] Nothing to be done for teardown. [2025-01-10T20:43:55.834Z] renaissance-dec-tree_0 Finish Time: Fri Jan 10 20:43:54 2025 Epoch Time (ms): 1736541834900