[2025-05-07T18:39:09.168Z] Running test renaissance-dec-tree_0 ... [2025-05-07T18:39:09.963Z] =============================================== [2025-05-07T18:39:09.963Z] renaissance-dec-tree_0 Start Time: Wed May 7 18:39:09 2025 Epoch Time (ms): 1746643149120 [2025-05-07T18:39:09.963Z] variation: NoOptions [2025-05-07T18:39:09.963Z] JVM_OPTIONS: [2025-05-07T18:39:09.963Z] { \ [2025-05-07T18:39:09.963Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T18:39:09.963Z] echo "Nothing to be done for setup."; \ [2025-05-07T18:39:09.963Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17466416504249/renaissance-dec-tree_0"; \ [2025-05-07T18:39:09.963Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17466416504249/renaissance-dec-tree_0"; \ [2025-05-07T18:39:09.963Z] echo ""; echo "TESTING:"; \ [2025-05-07T18:39:09.963Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/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_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17466416504249/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-07T18:39:09.963Z] 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_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17466416504249/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T18:39:09.963Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T18:39:09.963Z] echo "Nothing to be done for teardown."; \ [2025-05-07T18:39:09.963Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17466416504249/TestTargetResult"; [2025-05-07T18:39:09.963Z] [2025-05-07T18:39:09.963Z] TEST SETUP: [2025-05-07T18:39:09.963Z] Nothing to be done for setup. [2025-05-07T18:39:09.963Z] [2025-05-07T18:39:09.963Z] TESTING: [2025-05-07T18:39:26.541Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-07T18:39:31.100Z] WARNING: An illegal reflective access operation has occurred [2025-05-07T18:39:31.100Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/output_17466416504249/renaissance-dec-tree_0/harness-183911-17430863174996814119/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-07T18:39:31.100Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-07T18:39:31.100Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-07T18:39:31.100Z] WARNING: All illegal access operations will be denied in a future release [2025-05-07T18:39:35.818Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-07T18:39:35.818Z] GC before operation: completed in 187.653 ms, heap usage 82.684 MB -> 34.395 MB. [2025-05-07T18:40:18.033Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (41941.986 ms) ====== [2025-05-07T18:40:18.033Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-07T18:40:18.833Z] GC before operation: completed in 562.091 ms, heap usage 165.025 MB -> 67.991 MB. [2025-05-07T18:40:33.043Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (12947.095 ms) ====== [2025-05-07T18:40:33.043Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-07T18:40:33.043Z] GC before operation: completed in 506.513 ms, heap usage 158.277 MB -> 68.589 MB. [2025-05-07T18:40:43.304Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (10098.870 ms) ====== [2025-05-07T18:40:43.304Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-07T18:40:43.304Z] GC before operation: completed in 524.791 ms, heap usage 217.279 MB -> 69.016 MB. [2025-05-07T18:40:51.879Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (9282.526 ms) ====== [2025-05-07T18:40:51.879Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-07T18:40:52.689Z] GC before operation: completed in 585.880 ms, heap usage 204.340 MB -> 69.240 MB. [2025-05-07T18:41:01.182Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (8112.915 ms) ====== [2025-05-07T18:41:01.182Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-07T18:41:01.182Z] GC before operation: completed in 504.344 ms, heap usage 235.258 MB -> 69.423 MB. [2025-05-07T18:41:09.691Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (8470.338 ms) ====== [2025-05-07T18:41:09.691Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-07T18:41:10.487Z] GC before operation: completed in 638.110 ms, heap usage 170.987 MB -> 69.632 MB. [2025-05-07T18:41:19.542Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (8184.757 ms) ====== [2025-05-07T18:41:19.542Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-07T18:41:19.542Z] GC before operation: completed in 387.477 ms, heap usage 292.095 MB -> 69.700 MB. [2025-05-07T18:41:26.602Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (7387.326 ms) ====== [2025-05-07T18:41:26.602Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-07T18:41:26.602Z] GC before operation: completed in 545.518 ms, heap usage 261.949 MB -> 69.803 MB. [2025-05-07T18:41:33.788Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (6345.779 ms) ====== [2025-05-07T18:41:33.788Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-07T18:41:33.788Z] GC before operation: completed in 527.791 ms, heap usage 181.520 MB -> 69.920 MB. [2025-05-07T18:41:39.549Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (6135.832 ms) ====== [2025-05-07T18:41:39.549Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-07T18:41:40.339Z] GC before operation: completed in 446.543 ms, heap usage 234.925 MB -> 70.173 MB. [2025-05-07T18:41:47.381Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (7234.708 ms) ====== [2025-05-07T18:41:47.381Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-07T18:41:48.184Z] GC before operation: completed in 500.288 ms, heap usage 243.335 MB -> 70.309 MB. [2025-05-07T18:41:53.942Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (5959.072 ms) ====== [2025-05-07T18:41:53.942Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-07T18:41:54.751Z] GC before operation: completed in 406.749 ms, heap usage 248.925 MB -> 70.225 MB. [2025-05-07T18:42:00.547Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (5913.358 ms) ====== [2025-05-07T18:42:00.547Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-07T18:42:00.547Z] GC before operation: completed in 475.942 ms, heap usage 116.619 MB -> 70.191 MB. [2025-05-07T18:42:06.342Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (5052.756 ms) ====== [2025-05-07T18:42:06.342Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-07T18:42:06.342Z] GC before operation: completed in 482.083 ms, heap usage 220.903 MB -> 70.613 MB. [2025-05-07T18:42:12.111Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (5110.315 ms) ====== [2025-05-07T18:42:12.111Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-07T18:42:12.111Z] GC before operation: completed in 437.090 ms, heap usage 109.099 MB -> 70.259 MB. [2025-05-07T18:42:17.310Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (5196.549 ms) ====== [2025-05-07T18:42:17.310Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-07T18:42:17.310Z] GC before operation: completed in 513.923 ms, heap usage 224.148 MB -> 70.904 MB. [2025-05-07T18:42:23.147Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (5277.962 ms) ====== [2025-05-07T18:42:23.147Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-07T18:42:23.147Z] GC before operation: completed in 544.704 ms, heap usage 242.371 MB -> 70.900 MB. [2025-05-07T18:42:30.230Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (6300.039 ms) ====== [2025-05-07T18:42:30.230Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-07T18:42:30.230Z] GC before operation: completed in 447.208 ms, heap usage 207.475 MB -> 70.980 MB. [2025-05-07T18:42:35.985Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (5544.746 ms) ====== [2025-05-07T18:42:35.985Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-07T18:42:35.985Z] GC before operation: completed in 532.199 ms, heap usage 157.495 MB -> 71.219 MB. [2025-05-07T18:42:41.820Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (5880.945 ms) ====== [2025-05-07T18:42:41.820Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-07T18:42:42.627Z] GC before operation: completed in 496.050 ms, heap usage 243.047 MB -> 71.191 MB. [2025-05-07T18:42:48.444Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (5256.087 ms) ====== [2025-05-07T18:42:48.444Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-07T18:42:48.444Z] GC before operation: completed in 528.246 ms, heap usage 221.727 MB -> 71.300 MB. [2025-05-07T18:42:54.271Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (5776.750 ms) ====== [2025-05-07T18:42:54.271Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-07T18:42:55.073Z] GC before operation: completed in 525.804 ms, heap usage 157.355 MB -> 71.553 MB. [2025-05-07T18:43:00.879Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (5489.225 ms) ====== [2025-05-07T18:43:00.879Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-07T18:43:00.879Z] GC before operation: completed in 507.777 ms, heap usage 211.502 MB -> 71.656 MB. [2025-05-07T18:43:05.526Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4829.363 ms) ====== [2025-05-07T18:43:05.526Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-07T18:43:06.320Z] GC before operation: completed in 447.834 ms, heap usage 141.932 MB -> 71.435 MB. [2025-05-07T18:43:10.944Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (5110.265 ms) ====== [2025-05-07T18:43:10.944Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-07T18:43:11.736Z] GC before operation: completed in 453.732 ms, heap usage 240.298 MB -> 71.874 MB. [2025-05-07T18:43:16.579Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4815.240 ms) ====== [2025-05-07T18:43:16.579Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-07T18:43:16.579Z] GC before operation: completed in 458.239 ms, heap usage 295.255 MB -> 72.379 MB. [2025-05-07T18:43:22.389Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (5174.552 ms) ====== [2025-05-07T18:43:22.389Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-07T18:43:22.389Z] GC before operation: completed in 597.225 ms, heap usage 216.348 MB -> 88.308 MB. [2025-05-07T18:43:27.025Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4712.089 ms) ====== [2025-05-07T18:43:27.025Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-07T18:43:27.839Z] GC before operation: completed in 611.309 ms, heap usage 159.910 MB -> 71.881 MB. [2025-05-07T18:43:32.489Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4871.715 ms) ====== [2025-05-07T18:43:32.489Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-07T18:43:33.299Z] GC before operation: completed in 452.730 ms, heap usage 174.144 MB -> 72.643 MB. [2025-05-07T18:43:39.086Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (5257.508 ms) ====== [2025-05-07T18:43:39.086Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-07T18:43:39.086Z] GC before operation: completed in 543.006 ms, heap usage 289.234 MB -> 72.263 MB. [2025-05-07T18:43:43.698Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4854.355 ms) ====== [2025-05-07T18:43:43.698Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-07T18:43:44.518Z] GC before operation: completed in 618.015 ms, heap usage 218.599 MB -> 77.985 MB. [2025-05-07T18:43:44.518Z] 18:43:44.131 WARN [block-manager-storage-async-thread-pool-110] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429_piece0, which does not exist [2025-05-07T18:43:50.306Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4989.211 ms) ====== [2025-05-07T18:43:50.306Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-07T18:43:50.306Z] GC before operation: completed in 451.943 ms, heap usage 258.638 MB -> 72.646 MB. [2025-05-07T18:43:54.928Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (5275.501 ms) ====== [2025-05-07T18:43:54.928Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-07T18:43:55.725Z] GC before operation: completed in 514.373 ms, heap usage 310.106 MB -> 72.749 MB. [2025-05-07T18:44:00.317Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4837.309 ms) ====== [2025-05-07T18:44:00.317Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-07T18:44:01.118Z] GC before operation: completed in 466.425 ms, heap usage 158.209 MB -> 72.846 MB. [2025-05-07T18:44:06.913Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (5333.155 ms) ====== [2025-05-07T18:44:06.913Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-07T18:44:06.913Z] GC before operation: completed in 598.808 ms, heap usage 230.188 MB -> 72.970 MB. [2025-05-07T18:44:13.332Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4932.173 ms) ====== [2025-05-07T18:44:13.332Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-07T18:44:13.332Z] GC before operation: completed in 498.664 ms, heap usage 288.482 MB -> 73.062 MB. [2025-05-07T18:44:17.924Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (5108.819 ms) ====== [2025-05-07T18:44:17.924Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-07T18:44:17.924Z] GC before operation: completed in 472.025 ms, heap usage 229.131 MB -> 73.077 MB. [2025-05-07T18:44:23.684Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (5246.515 ms) ====== [2025-05-07T18:44:23.684Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-07T18:44:23.684Z] GC before operation: completed in 482.182 ms, heap usage 176.736 MB -> 73.125 MB. [2025-05-07T18:44:28.258Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (4817.964 ms) ====== [2025-05-07T18:44:28.258Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-07T18:44:29.049Z] GC before operation: completed in 471.751 ms, heap usage 281.752 MB -> 73.451 MB. [2025-05-07T18:44:33.654Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4727.968 ms) ====== [2025-05-07T18:44:34.443Z] ----------------------------------- [2025-05-07T18:44:34.443Z] renaissance-dec-tree_0_PASSED [2025-05-07T18:44:34.443Z] ----------------------------------- [2025-05-07T18:44:34.443Z] [2025-05-07T18:44:34.443Z] TEST TEARDOWN: [2025-05-07T18:44:34.443Z] Nothing to be done for teardown. [2025-05-07T18:44:34.443Z] renaissance-dec-tree_0 Finish Time: Wed May 7 18:44:34 2025 Epoch Time (ms): 1746643474334