[2024-09-26T05:40:17.867Z] Running test renaissance-dec-tree_0 ... [2024-09-26T05:40:17.867Z] =============================================== [2024-09-26T05:40:17.867Z] renaissance-dec-tree_0 Start Time: Thu Sep 26 05:40:17 2024 Epoch Time (ms): 1727329217655 [2024-09-26T05:40:17.867Z] variation: NoOptions [2024-09-26T05:40:17.867Z] JVM_OPTIONS: [2024-09-26T05:40:17.868Z] { \ [2024-09-26T05:40:17.868Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T05:40:17.868Z] echo "Nothing to be done for setup."; \ [2024-09-26T05:40:17.868Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17273288001084/renaissance-dec-tree_0"; \ [2024-09-26T05:40:17.868Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17273288001084/renaissance-dec-tree_0"; \ [2024-09-26T05:40:17.868Z] echo ""; echo "TESTING:"; \ [2024-09-26T05:40:17.868Z] "/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_17273288001084/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-09-26T05:40:17.868Z] 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_17273288001084/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T05:40:17.868Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T05:40:17.868Z] echo "Nothing to be done for teardown."; \ [2024-09-26T05:40:17.868Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17273288001084/TestTargetResult"; [2024-09-26T05:40:17.868Z] [2024-09-26T05:40:17.868Z] TEST SETUP: [2024-09-26T05:40:17.868Z] Nothing to be done for setup. [2024-09-26T05:40:17.868Z] [2024-09-26T05:40:17.868Z] TESTING: [2024-09-26T05:40:22.808Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T05:40:27.218Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-26T05:40:29.658Z] WARNING: An illegal reflective access operation has occurred [2024-09-26T05:40:29.658Z] 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_17273288001084/renaissance-dec-tree_0/harness-054018-15971214218558833210/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-26T05:40:29.658Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-26T05:40:29.658Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-26T05:40:29.658Z] WARNING: All illegal access operations will be denied in a future release [2024-09-26T05:40:33.043Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-26T05:40:33.810Z] GC before operation: completed in 125.417 ms, heap usage 56.793 MB -> 33.752 MB. [2024-09-26T05:40:55.778Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (20153.273 ms) ====== [2024-09-26T05:40:55.778Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-26T05:40:55.778Z] GC before operation: completed in 249.670 ms, heap usage 137.052 MB -> 63.378 MB. [2024-09-26T05:41:00.212Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (6451.261 ms) ====== [2024-09-26T05:41:00.212Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-26T05:41:00.977Z] GC before operation: completed in 237.755 ms, heap usage 228.976 MB -> 63.956 MB. [2024-09-26T05:41:05.396Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (4895.558 ms) ====== [2024-09-26T05:41:05.396Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-26T05:41:05.396Z] GC before operation: completed in 243.344 ms, heap usage 169.228 MB -> 64.627 MB. [2024-09-26T05:41:09.823Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4282.221 ms) ====== [2024-09-26T05:41:09.823Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-26T05:41:10.587Z] GC before operation: completed in 258.734 ms, heap usage 158.676 MB -> 64.643 MB. [2024-09-26T05:41:14.001Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3985.282 ms) ====== [2024-09-26T05:41:14.002Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-26T05:41:14.765Z] GC before operation: completed in 269.119 ms, heap usage 192.508 MB -> 64.664 MB. [2024-09-26T05:41:18.168Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3743.576 ms) ====== [2024-09-26T05:41:18.168Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-26T05:41:18.168Z] GC before operation: completed in 239.715 ms, heap usage 186.032 MB -> 65.005 MB. [2024-09-26T05:41:21.587Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3283.264 ms) ====== [2024-09-26T05:41:21.587Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-26T05:41:22.343Z] GC before operation: completed in 229.077 ms, heap usage 263.464 MB -> 65.215 MB. [2024-09-26T05:41:24.786Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3069.429 ms) ====== [2024-09-26T05:41:24.786Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-26T05:41:25.562Z] GC before operation: completed in 248.182 ms, heap usage 253.303 MB -> 65.338 MB. [2024-09-26T05:41:28.959Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3148.428 ms) ====== [2024-09-26T05:41:28.959Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-26T05:41:28.959Z] GC before operation: completed in 260.584 ms, heap usage 199.524 MB -> 76.612 MB. [2024-09-26T05:41:32.358Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3088.543 ms) ====== [2024-09-26T05:41:32.358Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-26T05:41:32.358Z] GC before operation: completed in 259.745 ms, heap usage 191.893 MB -> 65.557 MB. [2024-09-26T05:41:35.775Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3583.704 ms) ====== [2024-09-26T05:41:35.775Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-26T05:41:35.775Z] GC before operation: completed in 213.804 ms, heap usage 289.218 MB -> 65.661 MB. [2024-09-26T05:41:39.724Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3223.875 ms) ====== [2024-09-26T05:41:39.724Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-26T05:41:39.724Z] GC before operation: completed in 265.089 ms, heap usage 240.225 MB -> 65.764 MB. [2024-09-26T05:41:43.120Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3171.857 ms) ====== [2024-09-26T05:41:43.120Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-26T05:41:43.120Z] GC before operation: completed in 241.040 ms, heap usage 307.905 MB -> 65.873 MB. [2024-09-26T05:41:45.767Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2777.245 ms) ====== [2024-09-26T05:41:45.767Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-26T05:41:45.767Z] GC before operation: completed in 196.425 ms, heap usage 249.465 MB -> 65.839 MB. [2024-09-26T05:41:48.407Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2652.104 ms) ====== [2024-09-26T05:41:48.407Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-26T05:41:48.407Z] GC before operation: completed in 240.927 ms, heap usage 256.172 MB -> 66.082 MB. [2024-09-26T05:41:52.050Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3129.902 ms) ====== [2024-09-26T05:41:52.050Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-26T05:41:52.050Z] GC before operation: completed in 245.073 ms, heap usage 275.252 MB -> 66.257 MB. [2024-09-26T05:41:55.696Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2986.161 ms) ====== [2024-09-26T05:41:55.696Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-26T05:41:55.696Z] GC before operation: completed in 263.068 ms, heap usage 258.433 MB -> 66.211 MB. [2024-09-26T05:41:58.339Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2989.172 ms) ====== [2024-09-26T05:41:58.339Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-26T05:41:58.339Z] GC before operation: completed in 238.818 ms, heap usage 209.400 MB -> 66.451 MB. [2024-09-26T05:42:01.967Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2985.616 ms) ====== [2024-09-26T05:42:01.967Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-26T05:42:01.967Z] GC before operation: completed in 251.241 ms, heap usage 301.952 MB -> 66.808 MB. [2024-09-26T05:42:04.603Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2741.338 ms) ====== [2024-09-26T05:42:04.603Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-26T05:42:04.603Z] GC before operation: completed in 271.247 ms, heap usage 238.259 MB -> 77.400 MB. [2024-09-26T05:42:08.247Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3149.996 ms) ====== [2024-09-26T05:42:08.247Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-26T05:42:08.247Z] GC before operation: completed in 263.193 ms, heap usage 205.221 MB -> 66.745 MB. [2024-09-26T05:42:11.876Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3305.971 ms) ====== [2024-09-26T05:42:11.876Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-26T05:42:11.876Z] GC before operation: completed in 357.877 ms, heap usage 170.147 MB -> 66.575 MB. [2024-09-26T05:42:15.517Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3683.628 ms) ====== [2024-09-26T05:42:15.517Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-26T05:42:16.345Z] GC before operation: completed in 340.285 ms, heap usage 198.886 MB -> 66.797 MB. [2024-09-26T05:42:18.967Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3282.437 ms) ====== [2024-09-26T05:42:18.967Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-26T05:42:19.779Z] GC before operation: completed in 267.206 ms, heap usage 245.127 MB -> 94.508 MB. [2024-09-26T05:42:22.345Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3078.176 ms) ====== [2024-09-26T05:42:22.345Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-26T05:42:23.145Z] GC before operation: completed in 248.454 ms, heap usage 229.131 MB -> 67.180 MB. [2024-09-26T05:42:25.713Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3005.468 ms) ====== [2024-09-26T05:42:25.713Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-26T05:42:26.517Z] GC before operation: completed in 298.576 ms, heap usage 260.345 MB -> 97.205 MB. [2024-09-26T05:42:29.084Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2669.401 ms) ====== [2024-09-26T05:42:29.084Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-26T05:42:29.084Z] GC before operation: completed in 258.442 ms, heap usage 189.250 MB -> 67.333 MB. [2024-09-26T05:42:31.660Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2400.446 ms) ====== [2024-09-26T05:42:31.660Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-26T05:42:31.660Z] GC before operation: completed in 272.596 ms, heap usage 207.235 MB -> 67.302 MB. [2024-09-26T05:42:34.243Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2565.649 ms) ====== [2024-09-26T05:42:34.243Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-26T05:42:34.243Z] GC before operation: completed in 311.898 ms, heap usage 259.408 MB -> 67.546 MB. [2024-09-26T05:42:36.831Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2575.097 ms) ====== [2024-09-26T05:42:36.831Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-26T05:42:37.630Z] GC before operation: completed in 292.825 ms, heap usage 103.644 MB -> 67.129 MB. [2024-09-26T05:42:40.209Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2530.307 ms) ====== [2024-09-26T05:42:40.209Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-26T05:42:40.209Z] GC before operation: completed in 286.080 ms, heap usage 174.472 MB -> 94.515 MB. [2024-09-26T05:42:42.786Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2578.300 ms) ====== [2024-09-26T05:42:42.786Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-26T05:42:42.786Z] GC before operation: completed in 264.518 ms, heap usage 183.405 MB -> 67.575 MB. [2024-09-26T05:42:45.369Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2427.057 ms) ====== [2024-09-26T05:42:45.369Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-26T05:42:46.167Z] GC before operation: completed in 286.201 ms, heap usage 228.111 MB -> 95.410 MB. [2024-09-26T05:42:48.742Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2735.815 ms) ====== [2024-09-26T05:42:48.742Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-26T05:42:48.742Z] GC before operation: completed in 247.589 ms, heap usage 326.459 MB -> 68.123 MB. [2024-09-26T05:42:51.901Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3130.541 ms) ====== [2024-09-26T05:42:51.901Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-26T05:42:51.901Z] GC before operation: completed in 231.119 ms, heap usage 254.033 MB -> 68.163 MB. [2024-09-26T05:42:54.473Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2447.395 ms) ====== [2024-09-26T05:42:54.473Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-26T05:42:55.276Z] GC before operation: completed in 254.064 ms, heap usage 169.732 MB -> 67.982 MB. [2024-09-26T05:42:57.848Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2703.697 ms) ====== [2024-09-26T05:42:57.848Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-26T05:42:57.848Z] GC before operation: completed in 258.056 ms, heap usage 234.160 MB -> 68.610 MB. [2024-09-26T05:43:00.427Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2488.037 ms) ====== [2024-09-26T05:43:00.427Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-26T05:43:00.427Z] GC before operation: completed in 269.643 ms, heap usage 103.404 MB -> 67.745 MB. [2024-09-26T05:43:03.003Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2419.692 ms) ====== [2024-09-26T05:43:03.003Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-26T05:43:03.003Z] GC before operation: completed in 247.204 ms, heap usage 107.015 MB -> 67.836 MB. [2024-09-26T05:43:05.559Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2475.668 ms) ====== [2024-09-26T05:43:06.358Z] ----------------------------------- [2024-09-26T05:43:06.358Z] renaissance-dec-tree_0_PASSED [2024-09-26T05:43:06.358Z] ----------------------------------- [2024-09-26T05:43:06.358Z] [2024-09-26T05:43:06.358Z] TEST TEARDOWN: [2024-09-26T05:43:06.358Z] Nothing to be done for teardown. [2024-09-26T05:43:06.358Z] renaissance-dec-tree_0 Finish Time: Thu Sep 26 05:43:05 2024 Epoch Time (ms): 1727329385747