[2025-02-20T19:04:31.894Z] Running test renaissance-dec-tree_0 ... [2025-02-20T19:04:31.894Z] =============================================== [2025-02-20T19:04:31.894Z] renaissance-dec-tree_0 Start Time: Thu Feb 20 19:04:31 2025 Epoch Time (ms): 1740078271275 [2025-02-20T19:04:31.894Z] variation: NoOptions [2025-02-20T19:04:31.894Z] JVM_OPTIONS: [2025-02-20T19:04:31.894Z] { \ [2025-02-20T19:04:31.894Z] echo ""; echo "TEST SETUP:"; \ [2025-02-20T19:04:31.894Z] echo "Nothing to be done for setup."; \ [2025-02-20T19:04:31.894Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17400760084674/renaissance-dec-tree_0"; \ [2025-02-20T19:04:31.894Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17400760084674/renaissance-dec-tree_0"; \ [2025-02-20T19:04:31.894Z] echo ""; echo "TESTING:"; \ [2025-02-20T19:04:31.894Z] "/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_17400760084674/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-20T19:04:31.894Z] 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_17400760084674/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-20T19:04:31.894Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-20T19:04:31.894Z] echo "Nothing to be done for teardown."; \ [2025-02-20T19:04:31.894Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17400760084674/TestTargetResult"; [2025-02-20T19:04:31.894Z] [2025-02-20T19:04:31.894Z] TEST SETUP: [2025-02-20T19:04:31.894Z] Nothing to be done for setup. [2025-02-20T19:04:31.894Z] [2025-02-20T19:04:31.894Z] TESTING: [2025-02-20T19:04:47.227Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-20T19:05:02.069Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-20T19:05:28.992Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-20T19:05:28.992Z] GC before operation: completed in 468.384 ms, heap usage 91.988 MB -> 35.044 MB. [2025-02-20T19:06:49.721Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (79131.458 ms) ====== [2025-02-20T19:06:49.721Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-20T19:06:49.721Z] GC before operation: completed in 627.489 ms, heap usage 232.394 MB -> 64.553 MB. [2025-02-20T19:07:09.685Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (20893.122 ms) ====== [2025-02-20T19:07:09.685Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-20T19:07:10.525Z] GC before operation: completed in 713.017 ms, heap usage 110.299 MB -> 64.520 MB. [2025-02-20T19:07:31.252Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (19305.283 ms) ====== [2025-02-20T19:07:31.252Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-20T19:07:31.252Z] GC before operation: completed in 880.476 ms, heap usage 255.391 MB -> 65.120 MB. [2025-02-20T19:07:48.495Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (18127.042 ms) ====== [2025-02-20T19:07:48.495Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-20T19:07:49.338Z] GC before operation: completed in 560.537 ms, heap usage 315.402 MB -> 65.509 MB. [2025-02-20T19:08:09.231Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (18434.973 ms) ====== [2025-02-20T19:08:09.231Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-20T19:08:09.231Z] GC before operation: completed in 710.400 ms, heap usage 193.132 MB -> 65.536 MB. [2025-02-20T19:08:24.137Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (15911.974 ms) ====== [2025-02-20T19:08:24.137Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-20T19:08:24.966Z] GC before operation: completed in 880.802 ms, heap usage 234.230 MB -> 66.109 MB. [2025-02-20T19:08:40.058Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (14609.265 ms) ====== [2025-02-20T19:08:40.058Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-20T19:08:40.900Z] GC before operation: completed in 690.609 ms, heap usage 323.943 MB -> 66.120 MB. [2025-02-20T19:08:55.662Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (14357.667 ms) ====== [2025-02-20T19:08:55.662Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-20T19:08:55.662Z] GC before operation: completed in 650.911 ms, heap usage 314.815 MB -> 66.446 MB. [2025-02-20T19:09:10.702Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (13908.562 ms) ====== [2025-02-20T19:09:10.702Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-20T19:09:10.702Z] GC before operation: completed in 762.190 ms, heap usage 309.571 MB -> 77.528 MB. [2025-02-20T19:09:25.643Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (13607.961 ms) ====== [2025-02-20T19:09:25.643Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-20T19:09:25.643Z] GC before operation: completed in 665.654 ms, heap usage 171.467 MB -> 66.162 MB. [2025-02-20T19:09:38.591Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (13366.545 ms) ====== [2025-02-20T19:09:38.591Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-20T19:09:38.591Z] GC before operation: completed in 887.753 ms, heap usage 307.460 MB -> 66.478 MB. [2025-02-20T19:09:51.323Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (12493.155 ms) ====== [2025-02-20T19:09:51.323Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-20T19:09:52.171Z] GC before operation: completed in 740.367 ms, heap usage 327.369 MB -> 66.730 MB. [2025-02-20T19:10:05.097Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (12142.149 ms) ====== [2025-02-20T19:10:05.097Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-20T19:10:05.097Z] GC before operation: completed in 776.779 ms, heap usage 300.618 MB -> 66.848 MB. [2025-02-20T19:10:20.127Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (14571.421 ms) ====== [2025-02-20T19:10:20.127Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-20T19:10:20.127Z] GC before operation: completed in 679.527 ms, heap usage 177.810 MB -> 66.541 MB. [2025-02-20T19:10:32.825Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (12277.863 ms) ====== [2025-02-20T19:10:32.825Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-20T19:10:33.672Z] GC before operation: completed in 764.598 ms, heap usage 273.533 MB -> 67.104 MB. [2025-02-20T19:10:44.439Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (10785.856 ms) ====== [2025-02-20T19:10:44.439Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-20T19:10:45.340Z] GC before operation: completed in 790.921 ms, heap usage 319.520 MB -> 67.236 MB. [2025-02-20T19:10:56.745Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (10911.992 ms) ====== [2025-02-20T19:10:56.745Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-20T19:10:56.745Z] GC before operation: completed in 773.391 ms, heap usage 305.943 MB -> 67.408 MB. [2025-02-20T19:11:09.452Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (11530.879 ms) ====== [2025-02-20T19:11:09.452Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-20T19:11:09.452Z] GC before operation: completed in 784.019 ms, heap usage 294.650 MB -> 67.540 MB. [2025-02-20T19:11:18.647Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (9931.819 ms) ====== [2025-02-20T19:11:18.647Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-20T19:11:19.524Z] GC before operation: completed in 824.182 ms, heap usage 295.791 MB -> 67.608 MB. [2025-02-20T19:11:32.153Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (10949.699 ms) ====== [2025-02-20T19:11:32.153Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-20T19:11:32.153Z] GC before operation: completed in 552.610 ms, heap usage 295.583 MB -> 67.716 MB. [2025-02-20T19:11:39.635Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (8788.902 ms) ====== [2025-02-20T19:11:39.635Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-20T19:11:40.535Z] GC before operation: completed in 690.382 ms, heap usage 292.897 MB -> 67.707 MB. [2025-02-20T19:11:53.213Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (11165.250 ms) ====== [2025-02-20T19:11:53.213Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-20T19:11:53.213Z] GC before operation: completed in 822.652 ms, heap usage 296.166 MB -> 67.710 MB. [2025-02-20T19:12:02.197Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (9681.370 ms) ====== [2025-02-20T19:12:02.197Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-20T19:12:03.065Z] GC before operation: completed in 489.778 ms, heap usage 295.103 MB -> 67.794 MB. [2025-02-20T19:12:12.658Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (8788.992 ms) ====== [2025-02-20T19:12:12.658Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-20T19:12:12.658Z] GC before operation: completed in 781.735 ms, heap usage 294.515 MB -> 68.083 MB. [2025-02-20T19:12:25.470Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (12088.821 ms) ====== [2025-02-20T19:12:25.470Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-20T19:12:25.470Z] GC before operation: completed in 877.951 ms, heap usage 281.330 MB -> 68.651 MB. [2025-02-20T19:12:34.566Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (8980.900 ms) ====== [2025-02-20T19:12:34.566Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-20T19:12:35.683Z] GC before operation: completed in 631.512 ms, heap usage 273.025 MB -> 68.091 MB. [2025-02-20T19:12:48.162Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (12066.073 ms) ====== [2025-02-20T19:12:48.162Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-20T19:12:48.162Z] GC before operation: completed in 849.648 ms, heap usage 291.530 MB -> 96.018 MB. [2025-02-20T19:12:48.162Z] 25/02/20 19:12:47 WARN BlockManager: Asked to remove block broadcast_372, which does not exist [2025-02-20T19:12:57.076Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (8963.422 ms) ====== [2025-02-20T19:12:57.076Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-20T19:12:57.903Z] GC before operation: completed in 873.373 ms, heap usage 316.564 MB -> 68.509 MB. [2025-02-20T19:13:06.751Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (8725.782 ms) ====== [2025-02-20T19:13:06.752Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-20T19:13:07.588Z] GC before operation: completed in 771.470 ms, heap usage 170.835 MB -> 68.301 MB. [2025-02-20T19:13:16.535Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (9377.849 ms) ====== [2025-02-20T19:13:16.535Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-20T19:13:18.018Z] GC before operation: completed in 563.358 ms, heap usage 218.275 MB -> 68.674 MB. [2025-02-20T19:13:26.853Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (9759.580 ms) ====== [2025-02-20T19:13:26.853Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-20T19:13:27.708Z] GC before operation: completed in 751.369 ms, heap usage 298.332 MB -> 68.610 MB. [2025-02-20T19:13:38.296Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (9859.900 ms) ====== [2025-02-20T19:13:38.296Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-20T19:13:38.296Z] GC before operation: completed in 597.772 ms, heap usage 133.416 MB -> 68.588 MB. [2025-02-20T19:13:48.758Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (9319.852 ms) ====== [2025-02-20T19:13:48.758Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-20T19:13:48.758Z] GC before operation: completed in 875.267 ms, heap usage 403.128 MB -> 69.053 MB. [2025-02-20T19:13:59.164Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (9772.806 ms) ====== [2025-02-20T19:13:59.164Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-20T19:13:59.164Z] GC before operation: completed in 723.214 ms, heap usage 266.202 MB -> 69.221 MB. [2025-02-20T19:14:08.035Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (8425.525 ms) ====== [2025-02-20T19:14:08.035Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-20T19:14:08.035Z] GC before operation: completed in 749.176 ms, heap usage 277.367 MB -> 69.188 MB. [2025-02-20T19:14:16.995Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (8895.019 ms) ====== [2025-02-20T19:14:16.995Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-20T19:14:17.909Z] GC before operation: completed in 832.479 ms, heap usage 250.399 MB -> 69.165 MB. [2025-02-20T19:14:28.539Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (9852.931 ms) ====== [2025-02-20T19:14:28.539Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-20T19:14:28.539Z] GC before operation: completed in 786.819 ms, heap usage 267.019 MB -> 69.391 MB. [2025-02-20T19:14:40.230Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (10752.566 ms) ====== [2025-02-20T19:14:40.230Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-20T19:14:40.230Z] GC before operation: completed in 596.620 ms, heap usage 282.478 MB -> 69.891 MB. [2025-02-20T19:14:48.678Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (8379.147 ms) ====== [2025-02-20T19:14:48.678Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-20T19:14:48.678Z] GC before operation: completed in 638.406 ms, heap usage 259.077 MB -> 69.571 MB. [2025-02-20T19:14:57.022Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (7671.066 ms) ====== [2025-02-20T19:14:58.613Z] ----------------------------------- [2025-02-20T19:14:58.613Z] renaissance-dec-tree_0_PASSED [2025-02-20T19:14:58.613Z] ----------------------------------- [2025-02-20T19:14:58.613Z] [2025-02-20T19:14:58.613Z] TEST TEARDOWN: [2025-02-20T19:14:58.613Z] Nothing to be done for teardown. [2025-02-20T19:14:58.613Z] renaissance-dec-tree_0 Finish Time: Thu Feb 20 19:14:57 2025 Epoch Time (ms): 1740078897813