[2025-06-12T19:46:17.995Z] Running test renaissance-dec-tree_0 ... [2025-06-12T19:46:17.995Z] =============================================== [2025-06-12T19:46:17.995Z] renaissance-dec-tree_0 Start Time: Thu Jun 12 19:46:17 2025 Epoch Time (ms): 1749757577523 [2025-06-12T19:46:17.995Z] variation: NoOptions [2025-06-12T19:46:17.995Z] JVM_OPTIONS: [2025-06-12T19:46:17.995Z] { \ [2025-06-12T19:46:17.995Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T19:46:17.995Z] echo "Nothing to be done for setup."; \ [2025-06-12T19:46:17.995Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-dec-tree_0"; \ [2025-06-12T19:46:17.995Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-dec-tree_0"; \ [2025-06-12T19:46:17.995Z] echo ""; echo "TESTING:"; \ [2025-06-12T19:46:17.995Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-06-12T19:46:17.995Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T19:46:17.995Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T19:46:17.995Z] echo "Nothing to be done for teardown."; \ [2025-06-12T19:46:17.995Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17497568542689/TestTargetResult"; [2025-06-12T19:46:17.995Z] [2025-06-12T19:46:17.995Z] TEST SETUP: [2025-06-12T19:46:17.995Z] Nothing to be done for setup. [2025-06-12T19:46:17.995Z] [2025-06-12T19:46:17.995Z] TESTING: [2025-06-12T19:46:28.775Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-12T19:46:35.010Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-06-12T19:46:35.010Z] GC before operation: completed in 137.093 ms, heap usage 63.090 MB -> 34.996 MB. [2025-06-12T19:47:03.003Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (26389.406 ms) ====== [2025-06-12T19:47:03.003Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-06-12T19:47:03.003Z] GC before operation: completed in 311.857 ms, heap usage 323.273 MB -> 69.065 MB. [2025-06-12T19:47:08.006Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7012.253 ms) ====== [2025-06-12T19:47:08.006Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-06-12T19:47:08.006Z] GC before operation: completed in 247.760 ms, heap usage 123.691 MB -> 68.942 MB. [2025-06-12T19:47:15.637Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6856.789 ms) ====== [2025-06-12T19:47:15.637Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-06-12T19:47:15.637Z] GC before operation: completed in 250.144 ms, heap usage 164.676 MB -> 81.370 MB. [2025-06-12T19:47:20.645Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4873.602 ms) ====== [2025-06-12T19:47:20.645Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-06-12T19:47:20.645Z] GC before operation: completed in 351.932 ms, heap usage 374.149 MB -> 70.065 MB. [2025-06-12T19:47:25.629Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (4661.027 ms) ====== [2025-06-12T19:47:25.629Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-06-12T19:47:25.629Z] GC before operation: completed in 213.755 ms, heap usage 175.188 MB -> 70.012 MB. [2025-06-12T19:47:29.581Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (4206.017 ms) ====== [2025-06-12T19:47:29.581Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-06-12T19:47:30.455Z] GC before operation: completed in 289.322 ms, heap usage 188.782 MB -> 70.181 MB. [2025-06-12T19:47:34.284Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3947.887 ms) ====== [2025-06-12T19:47:34.284Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-06-12T19:47:34.284Z] GC before operation: completed in 311.015 ms, heap usage 406.390 MB -> 70.702 MB. [2025-06-12T19:47:38.129Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3906.232 ms) ====== [2025-06-12T19:47:38.129Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-06-12T19:47:38.129Z] GC before operation: completed in 233.750 ms, heap usage 161.978 MB -> 70.382 MB. [2025-06-12T19:47:43.115Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4247.403 ms) ====== [2025-06-12T19:47:43.115Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-06-12T19:47:43.115Z] GC before operation: completed in 228.654 ms, heap usage 370.609 MB -> 71.000 MB. [2025-06-12T19:47:46.909Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4165.579 ms) ====== [2025-06-12T19:47:46.909Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-06-12T19:47:47.777Z] GC before operation: completed in 223.763 ms, heap usage 143.296 MB -> 70.745 MB. [2025-06-12T19:47:51.600Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3685.032 ms) ====== [2025-06-12T19:47:51.600Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-06-12T19:47:51.600Z] GC before operation: completed in 0.760 ms, heap usage 341.417 MB -> 341.417 MB. [2025-06-12T19:47:54.369Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3393.840 ms) ====== [2025-06-12T19:47:54.369Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-06-12T19:47:54.369Z] GC before operation: completed in 212.036 ms, heap usage 156.004 MB -> 98.680 MB. [2025-06-12T19:47:54.369Z] 19:47:54.188 WARN [block-manager-storage-async-thread-pool-93] org.apache.spark.storage.BlockManager - Asked to remove block rdd_573_1, which does not exist [2025-06-12T19:47:58.223Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3565.084 ms) ====== [2025-06-12T19:47:58.223Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-06-12T19:47:58.223Z] GC before operation: completed in 249.516 ms, heap usage 352.934 MB -> 71.549 MB. [2025-06-12T19:48:02.711Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3973.951 ms) ====== [2025-06-12T19:48:02.711Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-06-12T19:48:02.711Z] GC before operation: completed in 284.719 ms, heap usage 293.770 MB -> 88.271 MB. [2025-06-12T19:48:06.544Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3368.300 ms) ====== [2025-06-12T19:48:06.544Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-06-12T19:48:06.544Z] GC before operation: completed in 227.257 ms, heap usage 383.542 MB -> 71.772 MB. [2025-06-12T19:48:09.324Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3299.767 ms) ====== [2025-06-12T19:48:09.324Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-06-12T19:48:10.262Z] GC before operation: completed in 347.025 ms, heap usage 111.993 MB -> 71.587 MB. [2025-06-12T19:48:13.026Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3112.337 ms) ====== [2025-06-12T19:48:13.026Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-06-12T19:48:13.896Z] GC before operation: completed in 387.160 ms, heap usage 214.799 MB -> 71.833 MB. [2025-06-12T19:48:17.746Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3666.400 ms) ====== [2025-06-12T19:48:17.746Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-06-12T19:48:17.746Z] GC before operation: completed in 239.666 ms, heap usage 335.273 MB -> 71.988 MB. [2025-06-12T19:48:21.574Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3696.556 ms) ====== [2025-06-12T19:48:21.574Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-06-12T19:48:21.574Z] GC before operation: completed in 233.775 ms, heap usage 114.268 MB -> 71.579 MB. [2025-06-12T19:48:24.334Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3058.556 ms) ====== [2025-06-12T19:48:24.334Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-06-12T19:48:24.334Z] GC before operation: completed in 306.532 ms, heap usage 184.218 MB -> 71.914 MB. [2025-06-12T19:48:28.152Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3097.747 ms) ====== [2025-06-12T19:48:28.152Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-06-12T19:48:28.153Z] GC before operation: completed in 253.469 ms, heap usage 337.081 MB -> 72.504 MB. [2025-06-12T19:48:30.936Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2947.051 ms) ====== [2025-06-12T19:48:30.936Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-06-12T19:48:30.936Z] GC before operation: completed in 310.558 ms, heap usage 233.305 MB -> 72.603 MB. [2025-06-12T19:48:34.763Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3116.782 ms) ====== [2025-06-12T19:48:34.763Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-06-12T19:48:34.763Z] GC before operation: completed in 257.448 ms, heap usage 318.729 MB -> 72.530 MB. [2025-06-12T19:48:37.549Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2766.584 ms) ====== [2025-06-12T19:48:37.549Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-06-12T19:48:37.549Z] GC before operation: completed in 310.921 ms, heap usage 107.703 MB -> 72.649 MB. [2025-06-12T19:48:41.375Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3238.123 ms) ====== [2025-06-12T19:48:41.375Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-06-12T19:48:41.375Z] GC before operation: completed in 288.446 ms, heap usage 596.550 MB -> 72.995 MB. [2025-06-12T19:48:44.126Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3018.182 ms) ====== [2025-06-12T19:48:44.126Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-06-12T19:48:44.990Z] GC before operation: completed in 223.152 ms, heap usage 578.568 MB -> 73.243 MB. [2025-06-12T19:48:47.764Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2959.836 ms) ====== [2025-06-12T19:48:47.764Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-06-12T19:48:47.764Z] GC before operation: completed in 296.799 ms, heap usage 390.741 MB -> 73.007 MB. [2025-06-12T19:48:50.538Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2830.219 ms) ====== [2025-06-12T19:48:50.538Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-06-12T19:48:51.410Z] GC before operation: completed in 311.990 ms, heap usage 402.697 MB -> 84.443 MB. [2025-06-12T19:48:54.272Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3248.065 ms) ====== [2025-06-12T19:48:54.272Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-06-12T19:48:54.272Z] GC before operation: completed in 250.604 ms, heap usage 286.620 MB -> 73.189 MB. [2025-06-12T19:48:57.698Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3101.187 ms) ====== [2025-06-12T19:48:57.698Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-06-12T19:48:57.698Z] GC before operation: completed in 309.128 ms, heap usage 379.725 MB -> 100.929 MB. [2025-06-12T19:49:00.534Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2939.234 ms) ====== [2025-06-12T19:49:00.534Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-06-12T19:49:01.408Z] GC before operation: completed in 238.871 ms, heap usage 464.265 MB -> 79.204 MB. [2025-06-12T19:49:01.408Z] 19:49:00.540 WARN [block-manager-storage-async-thread-pool-42] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_429_piece0, which does not exist [2025-06-12T19:49:04.182Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3159.566 ms) ====== [2025-06-12T19:49:04.183Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-06-12T19:49:04.183Z] GC before operation: completed in 273.524 ms, heap usage 345.020 MB -> 73.871 MB. [2025-06-12T19:49:06.975Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2818.309 ms) ====== [2025-06-12T19:49:06.975Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-06-12T19:49:07.847Z] GC before operation: completed in 338.917 ms, heap usage 397.098 MB -> 73.601 MB. [2025-06-12T19:49:10.723Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3215.991 ms) ====== [2025-06-12T19:49:10.723Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-06-12T19:49:11.593Z] GC before operation: completed in 283.232 ms, heap usage 349.806 MB -> 73.760 MB. [2025-06-12T19:49:14.361Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3141.841 ms) ====== [2025-06-12T19:49:14.361Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-06-12T19:49:14.361Z] GC before operation: completed in 290.940 ms, heap usage 344.751 MB -> 73.965 MB. [2025-06-12T19:49:18.465Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3193.761 ms) ====== [2025-06-12T19:49:18.465Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-06-12T19:49:18.465Z] GC before operation: completed in 236.536 ms, heap usage 411.995 MB -> 74.108 MB. [2025-06-12T19:49:21.267Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3290.556 ms) ====== [2025-06-12T19:49:21.267Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-06-12T19:49:21.267Z] GC before operation: completed in 282.185 ms, heap usage 400.596 MB -> 74.135 MB. [2025-06-12T19:49:24.043Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2726.056 ms) ====== [2025-06-12T19:49:24.043Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-06-12T19:49:24.913Z] GC before operation: completed in 246.056 ms, heap usage 401.764 MB -> 74.122 MB. [2025-06-12T19:49:27.747Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3385.681 ms) ====== [2025-06-12T19:49:27.747Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-06-12T19:49:28.629Z] GC before operation: completed in 364.025 ms, heap usage 392.724 MB -> 74.365 MB. [2025-06-12T19:49:31.399Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3246.582 ms) ====== [2025-06-12T19:49:32.271Z] ----------------------------------- [2025-06-12T19:49:32.271Z] renaissance-dec-tree_0_PASSED [2025-06-12T19:49:32.271Z] ----------------------------------- [2025-06-12T19:49:32.271Z] [2025-06-12T19:49:32.271Z] TEST TEARDOWN: [2025-06-12T19:49:32.271Z] Nothing to be done for teardown. [2025-06-12T19:49:32.271Z] renaissance-dec-tree_0 Finish Time: Thu Jun 12 19:49:31 2025 Epoch Time (ms): 1749757771701