[2024-08-28T21:25:59.712Z] Running test renaissance-dec-tree_0 ... [2024-08-28T21:25:59.712Z] =============================================== [2024-08-28T21:25:59.712Z] renaissance-dec-tree_0 Start Time: Wed Aug 28 21:25:58 2024 Epoch Time (ms): 1724880358911 [2024-08-28T21:25:59.712Z] variation: NoOptions [2024-08-28T21:25:59.712Z] JVM_OPTIONS: [2024-08-28T21:25:59.712Z] { \ [2024-08-28T21:25:59.712Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T21:25:59.712Z] echo "Nothing to be done for setup."; \ [2024-08-28T21:25:59.712Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17248787458810/renaissance-dec-tree_0"; \ [2024-08-28T21:25:59.712Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17248787458810/renaissance-dec-tree_0"; \ [2024-08-28T21:25:59.712Z] echo ""; echo "TESTING:"; \ [2024-08-28T21:25:59.712Z] "/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_17248787458810/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-28T21:25:59.713Z] 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_17248787458810/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T21:25:59.713Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T21:25:59.713Z] echo "Nothing to be done for teardown."; \ [2024-08-28T21:25:59.713Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17248787458810/TestTargetResult"; [2024-08-28T21:25:59.713Z] [2024-08-28T21:25:59.713Z] TEST SETUP: [2024-08-28T21:25:59.713Z] Nothing to be done for setup. [2024-08-28T21:25:59.713Z] [2024-08-28T21:25:59.713Z] TESTING: [2024-08-28T21:26:05.439Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T21:26:11.220Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-28T21:26:21.303Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-28T21:26:21.303Z] GC before operation: completed in 122.850 ms, heap usage 45.640 MB -> 35.096 MB. [2024-08-28T21:26:47.472Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (25742.945 ms) ====== [2024-08-28T21:26:47.472Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-28T21:26:47.472Z] GC before operation: completed in 369.031 ms, heap usage 115.086 MB -> 64.233 MB. [2024-08-28T21:26:57.726Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (9247.786 ms) ====== [2024-08-28T21:26:57.726Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-28T21:26:57.726Z] GC before operation: completed in 312.394 ms, heap usage 195.581 MB -> 64.897 MB. [2024-08-28T21:27:03.510Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6974.426 ms) ====== [2024-08-28T21:27:03.510Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-28T21:27:04.302Z] GC before operation: completed in 297.364 ms, heap usage 109.961 MB -> 92.289 MB. [2024-08-28T21:27:04.302Z] 24/08/28 21:27:03 WARN BlockManager: Asked to remove block broadcast_36, which does not exist [2024-08-28T21:27:10.108Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (5604.349 ms) ====== [2024-08-28T21:27:10.108Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-28T21:27:10.108Z] GC before operation: completed in 286.438 ms, heap usage 380.821 MB -> 65.468 MB. [2024-08-28T21:27:14.706Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (4995.515 ms) ====== [2024-08-28T21:27:14.706Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-28T21:27:15.502Z] GC before operation: completed in 258.968 ms, heap usage 263.805 MB -> 65.632 MB. [2024-08-28T21:27:22.618Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (6797.390 ms) ====== [2024-08-28T21:27:22.618Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-28T21:27:22.618Z] GC before operation: completed in 282.174 ms, heap usage 203.590 MB -> 65.835 MB. [2024-08-28T21:27:28.406Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5557.100 ms) ====== [2024-08-28T21:27:28.406Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-28T21:27:28.406Z] GC before operation: completed in 373.911 ms, heap usage 132.906 MB -> 65.914 MB. [2024-08-28T21:27:34.191Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (6064.235 ms) ====== [2024-08-28T21:27:34.191Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-28T21:27:35.004Z] GC before operation: completed in 374.928 ms, heap usage 141.512 MB -> 66.114 MB. [2024-08-28T21:27:40.779Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5529.789 ms) ====== [2024-08-28T21:27:40.779Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-28T21:27:40.779Z] GC before operation: completed in 370.741 ms, heap usage 291.753 MB -> 66.317 MB. [2024-08-28T21:27:46.536Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (5755.657 ms) ====== [2024-08-28T21:27:46.536Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-28T21:27:46.536Z] GC before operation: completed in 367.124 ms, heap usage 229.698 MB -> 66.593 MB. [2024-08-28T21:27:53.603Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (5927.472 ms) ====== [2024-08-28T21:27:53.603Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-28T21:27:53.603Z] GC before operation: completed in 362.006 ms, heap usage 222.208 MB -> 66.744 MB. [2024-08-28T21:27:58.191Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (5280.436 ms) ====== [2024-08-28T21:27:58.191Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-28T21:27:58.986Z] GC before operation: completed in 358.792 ms, heap usage 329.296 MB -> 66.865 MB. [2024-08-28T21:28:03.592Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4817.534 ms) ====== [2024-08-28T21:28:03.592Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-28T21:28:03.592Z] GC before operation: completed in 319.613 ms, heap usage 292.343 MB -> 66.780 MB. [2024-08-28T21:28:08.187Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4674.000 ms) ====== [2024-08-28T21:28:08.188Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-28T21:28:08.984Z] GC before operation: completed in 266.803 ms, heap usage 343.812 MB -> 67.069 MB. [2024-08-28T21:28:12.510Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4021.353 ms) ====== [2024-08-28T21:28:12.510Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-28T21:28:13.328Z] GC before operation: completed in 332.514 ms, heap usage 290.378 MB -> 67.180 MB. [2024-08-28T21:28:17.918Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (4581.856 ms) ====== [2024-08-28T21:28:17.918Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-28T21:28:17.918Z] GC before operation: completed in 291.335 ms, heap usage 302.695 MB -> 67.228 MB. [2024-08-28T21:28:22.524Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4266.851 ms) ====== [2024-08-28T21:28:22.524Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-28T21:28:22.524Z] GC before operation: completed in 325.348 ms, heap usage 302.755 MB -> 67.517 MB. [2024-08-28T21:28:27.106Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4232.665 ms) ====== [2024-08-28T21:28:27.106Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-28T21:28:27.106Z] GC before operation: completed in 357.613 ms, heap usage 271.918 MB -> 67.579 MB. [2024-08-28T21:28:31.745Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4637.153 ms) ====== [2024-08-28T21:28:31.745Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-28T21:28:32.548Z] GC before operation: completed in 294.170 ms, heap usage 309.352 MB -> 67.724 MB. [2024-08-28T21:28:37.144Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4553.963 ms) ====== [2024-08-28T21:28:37.144Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-28T21:28:37.144Z] GC before operation: completed in 389.306 ms, heap usage 202.600 MB -> 67.611 MB. [2024-08-28T21:28:41.712Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (4407.037 ms) ====== [2024-08-28T21:28:41.712Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-28T21:28:41.712Z] GC before operation: completed in 343.888 ms, heap usage 232.592 MB -> 67.889 MB. [2024-08-28T21:28:46.298Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4559.494 ms) ====== [2024-08-28T21:28:46.298Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-28T21:28:47.088Z] GC before operation: completed in 404.431 ms, heap usage 213.986 MB -> 67.968 MB. [2024-08-28T21:28:50.601Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3903.714 ms) ====== [2024-08-28T21:28:50.601Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-28T21:28:50.601Z] GC before operation: completed in 249.145 ms, heap usage 252.528 MB -> 68.105 MB. [2024-08-28T21:28:55.196Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3608.846 ms) ====== [2024-08-28T21:28:55.196Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-28T21:28:55.196Z] GC before operation: completed in 260.997 ms, heap usage 321.746 MB -> 68.142 MB. [2024-08-28T21:28:59.824Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4315.146 ms) ====== [2024-08-28T21:28:59.824Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-28T21:28:59.824Z] GC before operation: completed in 428.282 ms, heap usage 298.643 MB -> 68.245 MB. [2024-08-28T21:29:03.320Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3697.481 ms) ====== [2024-08-28T21:29:03.320Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-28T21:29:03.320Z] GC before operation: completed in 253.790 ms, heap usage 298.900 MB -> 68.347 MB. [2024-08-28T21:29:06.832Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2861.026 ms) ====== [2024-08-28T21:29:06.832Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-28T21:29:06.832Z] GC before operation: completed in 317.428 ms, heap usage 287.138 MB -> 68.497 MB. [2024-08-28T21:29:10.464Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3612.037 ms) ====== [2024-08-28T21:29:10.464Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-28T21:29:10.464Z] GC before operation: completed in 311.866 ms, heap usage 293.627 MB -> 68.433 MB. [2024-08-28T21:29:15.073Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3784.439 ms) ====== [2024-08-28T21:29:15.073Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-28T21:29:15.073Z] GC before operation: completed in 275.423 ms, heap usage 303.616 MB -> 68.725 MB. [2024-08-28T21:29:18.680Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3802.991 ms) ====== [2024-08-28T21:29:18.680Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-28T21:29:18.680Z] GC before operation: completed in 278.432 ms, heap usage 284.990 MB -> 68.739 MB. [2024-08-28T21:29:23.263Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4262.093 ms) ====== [2024-08-28T21:29:23.263Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-28T21:29:23.263Z] GC before operation: completed in 329.601 ms, heap usage 145.800 MB -> 68.389 MB. [2024-08-28T21:29:27.841Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4072.076 ms) ====== [2024-08-28T21:29:27.841Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-28T21:29:27.841Z] GC before operation: completed in 257.052 ms, heap usage 135.174 MB -> 68.692 MB. [2024-08-28T21:29:31.401Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3134.277 ms) ====== [2024-08-28T21:29:31.401Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-28T21:29:31.401Z] GC before operation: completed in 241.103 ms, heap usage 191.937 MB -> 69.077 MB. [2024-08-28T21:29:33.951Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2898.320 ms) ====== [2024-08-28T21:29:33.951Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-28T21:29:33.951Z] GC before operation: completed in 301.863 ms, heap usage 257.472 MB -> 69.081 MB. [2024-08-28T21:29:37.458Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2660.267 ms) ====== [2024-08-28T21:29:37.458Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-28T21:29:37.458Z] GC before operation: completed in 274.185 ms, heap usage 104.368 MB -> 68.758 MB. [2024-08-28T21:29:40.968Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3266.358 ms) ====== [2024-08-28T21:29:40.968Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-28T21:29:40.968Z] GC before operation: completed in 269.989 ms, heap usage 480.184 MB -> 69.541 MB. [2024-08-28T21:29:43.493Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2752.495 ms) ====== [2024-08-28T21:29:43.493Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-28T21:29:43.493Z] GC before operation: completed in 294.262 ms, heap usage 272.676 MB -> 69.474 MB. [2024-08-28T21:29:47.035Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3091.733 ms) ====== [2024-08-28T21:29:47.035Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-28T21:29:47.035Z] GC before operation: completed in 235.084 ms, heap usage 285.196 MB -> 69.452 MB. [2024-08-28T21:29:50.568Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2857.990 ms) ====== [2024-08-28T21:29:50.568Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-28T21:29:50.568Z] GC before operation: completed in 310.661 ms, heap usage 292.153 MB -> 69.940 MB. [2024-08-28T21:29:53.116Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3173.399 ms) ====== [2024-08-28T21:29:53.903Z] ----------------------------------- [2024-08-28T21:29:53.903Z] renaissance-dec-tree_0_PASSED [2024-08-28T21:29:53.903Z] ----------------------------------- [2024-08-28T21:29:53.903Z] [2024-08-28T21:29:53.903Z] TEST TEARDOWN: [2024-08-28T21:29:53.903Z] Nothing to be done for teardown. [2024-08-28T21:29:53.903Z] renaissance-dec-tree_0 Finish Time: Wed Aug 28 21:29:53 2024 Epoch Time (ms): 1724880593490