[2023-04-18T22:00:24.979Z] Running test renaissance-dec-tree_0 ... [2023-04-18T22:00:24.979Z] =============================================== [2023-04-18T22:00:24.979Z] renaissance-dec-tree_0 Start Time: Tue Apr 18 22:00:24 2023 Epoch Time (ms): 1681855224759 [2023-04-18T22:00:24.979Z] variation: NoOptions [2023-04-18T22:00:24.979Z] JVM_OPTIONS: [2023-04-18T22:00:24.979Z] { \ [2023-04-18T22:00:24.979Z] echo ""; echo "TEST SETUP:"; \ [2023-04-18T22:00:24.979Z] echo "Nothing to be done for setup."; \ [2023-04-18T22:00:24.979Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_16818550175499/renaissance-dec-tree_0"; \ [2023-04-18T22:00:24.979Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_16818550175499/renaissance-dec-tree_0"; \ [2023-04-18T22:00:24.979Z] echo ""; echo "TESTING:"; \ [2023-04-18T22:00:24.979Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/openjdkbinary/j2sdk-image/jdk-17.0.7+7/bin/..//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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_16818550175499/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2023-04-18T22:00:24.979Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_16818550175499/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-18T22:00:24.979Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-18T22:00:24.979Z] echo "Nothing to be done for teardown."; \ [2023-04-18T22:00:24.979Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_16818550175499/TestTargetResult"; [2023-04-18T22:00:24.979Z] [2023-04-18T22:00:24.979Z] TEST SETUP: [2023-04-18T22:00:24.979Z] Nothing to be done for setup. [2023-04-18T22:00:24.979Z] [2023-04-18T22:00:24.979Z] TESTING: [2023-04-18T22:00:29.889Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-18T22:00:30.621Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2023-04-18T22:00:32.948Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2023-04-18T22:00:32.948Z] GC before operation: completed in 44.356 ms, heap usage 49.498 MB -> 35.227 MB. [2023-04-18T22:00:38.957Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (5376.818 ms) ====== [2023-04-18T22:00:38.957Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2023-04-18T22:00:38.957Z] GC before operation: completed in 40.340 ms, heap usage 206.192 MB -> 64.717 MB. [2023-04-18T22:00:40.135Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1737.335 ms) ====== [2023-04-18T22:00:40.135Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2023-04-18T22:00:40.135Z] GC before operation: completed in 45.943 ms, heap usage 220.123 MB -> 65.243 MB. [2023-04-18T22:00:41.841Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1203.048 ms) ====== [2023-04-18T22:00:41.841Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2023-04-18T22:00:41.841Z] GC before operation: completed in 55.553 ms, heap usage 261.893 MB -> 65.518 MB. [2023-04-18T22:00:42.571Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1137.774 ms) ====== [2023-04-18T22:00:42.571Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2023-04-18T22:00:42.571Z] GC before operation: completed in 50.382 ms, heap usage 131.646 MB -> 65.501 MB. [2023-04-18T22:00:44.273Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1201.460 ms) ====== [2023-04-18T22:00:44.273Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2023-04-18T22:00:44.273Z] GC before operation: completed in 45.680 ms, heap usage 330.423 MB -> 66.094 MB. [2023-04-18T22:00:45.469Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1208.401 ms) ====== [2023-04-18T22:00:45.469Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2023-04-18T22:00:45.469Z] GC before operation: completed in 56.911 ms, heap usage 206.134 MB -> 66.085 MB. [2023-04-18T22:00:46.649Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1151.773 ms) ====== [2023-04-18T22:00:46.649Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2023-04-18T22:00:46.649Z] GC before operation: completed in 55.083 ms, heap usage 319.773 MB -> 66.291 MB. [2023-04-18T22:00:47.830Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1203.014 ms) ====== [2023-04-18T22:00:47.831Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2023-04-18T22:00:47.831Z] GC before operation: completed in 65.880 ms, heap usage 131.017 MB -> 66.290 MB. [2023-04-18T22:00:49.009Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1250.625 ms) ====== [2023-04-18T22:00:49.009Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2023-04-18T22:00:49.009Z] GC before operation: completed in 58.139 ms, heap usage 220.441 MB -> 77.872 MB. [2023-04-18T22:00:50.186Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1177.614 ms) ====== [2023-04-18T22:00:50.186Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2023-04-18T22:00:50.186Z] GC before operation: completed in 53.208 ms, heap usage 317.886 MB -> 72.262 MB. [2023-04-18T22:00:51.366Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1110.999 ms) ====== [2023-04-18T22:00:51.366Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2023-04-18T22:00:51.366Z] GC before operation: completed in 55.196 ms, heap usage 163.567 MB -> 66.612 MB. [2023-04-18T22:00:52.545Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1012.263 ms) ====== [2023-04-18T22:00:52.545Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2023-04-18T22:00:52.545Z] GC before operation: completed in 61.124 ms, heap usage 303.577 MB -> 83.441 MB. [2023-04-18T22:00:53.730Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1072.146 ms) ====== [2023-04-18T22:00:53.730Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2023-04-18T22:00:53.730Z] GC before operation: completed in 59.179 ms, heap usage 130.362 MB -> 66.566 MB. [2023-04-18T22:00:54.913Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1067.203 ms) ====== [2023-04-18T22:00:54.913Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2023-04-18T22:00:54.913Z] GC before operation: completed in 63.087 ms, heap usage 141.312 MB -> 67.021 MB. [2023-04-18T22:00:55.650Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1038.994 ms) ====== [2023-04-18T22:00:55.650Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2023-04-18T22:00:55.993Z] GC before operation: completed in 66.020 ms, heap usage 188.552 MB -> 67.467 MB. [2023-04-18T22:00:57.182Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1098.501 ms) ====== [2023-04-18T22:00:57.182Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2023-04-18T22:00:57.182Z] GC before operation: completed in 62.690 ms, heap usage 298.088 MB -> 67.553 MB. [2023-04-18T22:00:57.979Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1058.546 ms) ====== [2023-04-18T22:00:57.979Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2023-04-18T22:00:58.319Z] GC before operation: completed in 61.328 ms, heap usage 291.301 MB -> 67.711 MB. [2023-04-18T22:00:59.504Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1093.369 ms) ====== [2023-04-18T22:00:59.504Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2023-04-18T22:00:59.504Z] GC before operation: completed in 69.193 ms, heap usage 281.093 MB -> 84.462 MB. [2023-04-18T22:01:00.682Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1134.897 ms) ====== [2023-04-18T22:01:00.682Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2023-04-18T22:01:00.682Z] GC before operation: completed in 62.563 ms, heap usage 421.011 MB -> 67.960 MB. [2023-04-18T22:01:01.413Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1033.750 ms) ====== [2023-04-18T22:01:01.413Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2023-04-18T22:01:01.760Z] GC before operation: completed in 61.564 ms, heap usage 296.072 MB -> 67.828 MB. [2023-04-18T22:01:02.948Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1055.287 ms) ====== [2023-04-18T22:01:02.948Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2023-04-18T22:01:02.948Z] GC before operation: completed in 63.856 ms, heap usage 270.048 MB -> 84.714 MB. [2023-04-18T22:01:04.134Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1127.802 ms) ====== [2023-04-18T22:01:04.134Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2023-04-18T22:01:04.134Z] GC before operation: completed in 70.952 ms, heap usage 393.066 MB -> 95.692 MB. [2023-04-18T22:01:04.872Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1028.188 ms) ====== [2023-04-18T22:01:04.872Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2023-04-18T22:01:04.872Z] GC before operation: completed in 67.539 ms, heap usage 440.075 MB -> 90.228 MB. [2023-04-18T22:01:06.057Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1034.871 ms) ====== [2023-04-18T22:01:06.057Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2023-04-18T22:01:06.057Z] GC before operation: completed in 72.598 ms, heap usage 208.348 MB -> 68.280 MB. [2023-04-18T22:01:07.240Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1079.136 ms) ====== [2023-04-18T22:01:07.240Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2023-04-18T22:01:07.240Z] GC before operation: completed in 65.984 ms, heap usage 164.678 MB -> 79.074 MB. [2023-04-18T22:01:08.422Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1103.293 ms) ====== [2023-04-18T22:01:08.423Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2023-04-18T22:01:08.423Z] GC before operation: completed in 69.550 ms, heap usage 441.680 MB -> 85.264 MB. [2023-04-18T22:01:09.602Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1063.438 ms) ====== [2023-04-18T22:01:09.602Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2023-04-18T22:01:09.602Z] GC before operation: completed in 77.056 ms, heap usage 415.311 MB -> 79.746 MB. [2023-04-18T22:01:10.793Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1051.510 ms) ====== [2023-04-18T22:01:10.793Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2023-04-18T22:01:10.793Z] GC before operation: completed in 67.063 ms, heap usage 209.788 MB -> 80.042 MB. [2023-04-18T22:01:11.974Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1243.764 ms) ====== [2023-04-18T22:01:11.974Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2023-04-18T22:01:11.974Z] GC before operation: completed in 77.016 ms, heap usage 445.810 MB -> 80.227 MB. [2023-04-18T22:01:13.160Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1022.988 ms) ====== [2023-04-18T22:01:13.160Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2023-04-18T22:01:13.160Z] GC before operation: completed in 63.625 ms, heap usage 195.991 MB -> 69.022 MB. [2023-04-18T22:01:14.352Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1046.227 ms) ====== [2023-04-18T22:01:14.352Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2023-04-18T22:01:14.352Z] GC before operation: completed in 66.283 ms, heap usage 270.403 MB -> 69.179 MB. [2023-04-18T22:01:15.539Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1089.383 ms) ====== [2023-04-18T22:01:15.539Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2023-04-18T22:01:15.539Z] GC before operation: completed in 72.017 ms, heap usage 278.801 MB -> 69.260 MB. [2023-04-18T22:01:16.283Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1045.536 ms) ====== [2023-04-18T22:01:16.283Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2023-04-18T22:01:16.627Z] GC before operation: completed in 76.491 ms, heap usage 259.143 MB -> 69.176 MB. [2023-04-18T22:01:17.819Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1024.719 ms) ====== [2023-04-18T22:01:17.819Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2023-04-18T22:01:17.819Z] GC before operation: completed in 75.166 ms, heap usage 265.414 MB -> 69.349 MB. [2023-04-18T22:01:18.549Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1003.722 ms) ====== [2023-04-18T22:01:18.549Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2023-04-18T22:01:18.549Z] GC before operation: completed in 75.245 ms, heap usage 263.876 MB -> 69.440 MB. [2023-04-18T22:01:19.740Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1069.115 ms) ====== [2023-04-18T22:01:19.740Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2023-04-18T22:01:19.740Z] GC before operation: completed in 76.787 ms, heap usage 265.087 MB -> 69.518 MB. [2023-04-18T22:01:20.931Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1036.302 ms) ====== [2023-04-18T22:01:20.931Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2023-04-18T22:01:20.931Z] GC before operation: completed in 73.774 ms, heap usage 253.353 MB -> 80.464 MB. [2023-04-18T22:01:22.118Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1021.602 ms) ====== [2023-04-18T22:01:22.118Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2023-04-18T22:01:22.118Z] GC before operation: completed in 78.510 ms, heap usage 285.389 MB -> 97.418 MB. [2023-04-18T22:01:23.308Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1042.945 ms) ====== [2023-04-18T22:01:23.308Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2023-04-18T22:01:23.308Z] GC before operation: completed in 76.466 ms, heap usage 474.691 MB -> 70.109 MB. [2023-04-18T22:01:24.495Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1046.530 ms) ====== [2023-04-18T22:01:24.495Z] ----------------------------------- [2023-04-18T22:01:24.495Z] renaissance-dec-tree_0_PASSED [2023-04-18T22:01:24.495Z] ----------------------------------- [2023-04-18T22:01:24.495Z] [2023-04-18T22:01:24.495Z] TEST TEARDOWN: [2023-04-18T22:01:24.495Z] Nothing to be done for teardown. [2023-04-18T22:01:24.495Z] renaissance-dec-tree_0 Finish Time: Tue Apr 18 22:01:24 2023 Epoch Time (ms): 1681855284292