[2024-08-28T20:18:39.886Z] Running test renaissance-dec-tree_0 ... [2024-08-28T20:18:39.886Z] =============================================== [2024-08-28T20:18:39.886Z] renaissance-dec-tree_0 Start Time: Wed Aug 28 20:18:39 2024 Epoch Time (ms): 1724876319133 [2024-08-28T20:18:39.886Z] variation: NoOptions [2024-08-28T20:18:39.886Z] JVM_OPTIONS: [2024-08-28T20:18:39.886Z] { \ [2024-08-28T20:18:39.886Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T20:18:39.886Z] echo "Nothing to be done for setup."; \ [2024-08-28T20:18:39.886Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17248754842896/renaissance-dec-tree_0"; \ [2024-08-28T20:18:39.886Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17248754842896/renaissance-dec-tree_0"; \ [2024-08-28T20:18:39.886Z] echo ""; echo "TESTING:"; \ [2024-08-28T20:18:39.886Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-17.0.13+5/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_17248754842896/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-28T20:18:39.886Z] 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_17248754842896/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T20:18:39.886Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T20:18:39.886Z] echo "Nothing to be done for teardown."; \ [2024-08-28T20:18:39.886Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17248754842896/TestTargetResult"; [2024-08-28T20:18:39.886Z] [2024-08-28T20:18:39.886Z] TEST SETUP: [2024-08-28T20:18:39.886Z] Nothing to be done for setup. [2024-08-28T20:18:39.886Z] [2024-08-28T20:18:39.886Z] TESTING: [2024-08-28T20:18:47.991Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T20:18:54.821Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-28T20:19:04.561Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-28T20:19:04.562Z] GC before operation: completed in 220.756 ms, heap usage 50.251 MB -> 35.295 MB. [2024-08-28T20:19:45.669Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (38878.229 ms) ====== [2024-08-28T20:19:45.669Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-28T20:19:45.669Z] GC before operation: completed in 416.653 ms, heap usage 239.589 MB -> 64.683 MB. [2024-08-28T20:19:55.603Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (11350.102 ms) ====== [2024-08-28T20:19:55.603Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-28T20:19:55.603Z] GC before operation: completed in 425.098 ms, heap usage 121.043 MB -> 65.066 MB. [2024-08-28T20:20:05.403Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (9296.840 ms) ====== [2024-08-28T20:20:05.403Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-28T20:20:05.403Z] GC before operation: completed in 357.176 ms, heap usage 147.260 MB -> 65.426 MB. [2024-08-28T20:20:14.226Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (9045.261 ms) ====== [2024-08-28T20:20:14.226Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-28T20:20:14.226Z] GC before operation: completed in 402.253 ms, heap usage 101.589 MB -> 65.244 MB. [2024-08-28T20:20:24.054Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (8372.356 ms) ====== [2024-08-28T20:20:24.054Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-28T20:20:24.054Z] GC before operation: completed in 379.857 ms, heap usage 413.123 MB -> 65.852 MB. [2024-08-28T20:20:29.605Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (6471.761 ms) ====== [2024-08-28T20:20:29.605Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-28T20:20:30.385Z] GC before operation: completed in 395.014 ms, heap usage 240.514 MB -> 66.186 MB. [2024-08-28T20:20:37.305Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (6704.115 ms) ====== [2024-08-28T20:20:37.305Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-28T20:20:37.305Z] GC before operation: completed in 320.709 ms, heap usage 132.164 MB -> 66.105 MB. [2024-08-28T20:20:42.873Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (6030.031 ms) ====== [2024-08-28T20:20:42.873Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-28T20:20:43.615Z] GC before operation: completed in 323.939 ms, heap usage 194.377 MB -> 66.337 MB. [2024-08-28T20:20:49.078Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5496.572 ms) ====== [2024-08-28T20:20:49.078Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-28T20:20:49.078Z] GC before operation: completed in 378.236 ms, heap usage 344.432 MB -> 66.828 MB. [2024-08-28T20:20:55.812Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (6253.256 ms) ====== [2024-08-28T20:20:55.812Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-28T20:20:55.812Z] GC before operation: completed in 409.034 ms, heap usage 332.538 MB -> 66.530 MB. [2024-08-28T20:21:02.573Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (6236.977 ms) ====== [2024-08-28T20:21:02.573Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-28T20:21:02.573Z] GC before operation: completed in 375.969 ms, heap usage 339.069 MB -> 66.619 MB. [2024-08-28T20:21:10.879Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (7650.048 ms) ====== [2024-08-28T20:21:10.879Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-28T20:21:10.879Z] GC before operation: completed in 355.542 ms, heap usage 316.042 MB -> 94.523 MB. [2024-08-28T20:21:15.283Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (4811.020 ms) ====== [2024-08-28T20:21:15.283Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-28T20:21:16.024Z] GC before operation: completed in 356.848 ms, heap usage 203.214 MB -> 66.680 MB. [2024-08-28T20:21:21.534Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (5701.867 ms) ====== [2024-08-28T20:21:21.534Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-28T20:21:21.534Z] GC before operation: completed in 298.421 ms, heap usage 304.739 MB -> 67.074 MB. [2024-08-28T20:21:27.600Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (5194.993 ms) ====== [2024-08-28T20:21:27.600Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-28T20:21:27.600Z] GC before operation: completed in 299.480 ms, heap usage 345.866 MB -> 67.268 MB. [2024-08-28T20:21:33.099Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (5090.669 ms) ====== [2024-08-28T20:21:33.099Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-28T20:21:33.099Z] GC before operation: completed in 350.206 ms, heap usage 328.942 MB -> 67.506 MB. [2024-08-28T20:21:38.561Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (5461.038 ms) ====== [2024-08-28T20:21:38.561Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-28T20:21:38.561Z] GC before operation: completed in 396.715 ms, heap usage 99.599 MB -> 66.907 MB. [2024-08-28T20:21:44.091Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (5011.446 ms) ====== [2024-08-28T20:21:44.091Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-28T20:21:44.091Z] GC before operation: completed in 367.074 ms, heap usage 148.266 MB -> 95.141 MB. [2024-08-28T20:21:44.091Z] 24/08/28 20:21:43 WARN BlockManager: Asked to remove block rdd_879_1, which does not exist [2024-08-28T20:21:44.091Z] 24/08/28 20:21:43 WARN BlockManager: Asked to remove block rdd_879_0, which does not exist [2024-08-28T20:21:44.091Z] 24/08/28 20:21:43 WARN BlockManager: Asked to remove block rdd_879_2, which does not exist [2024-08-28T20:21:49.564Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (5043.619 ms) ====== [2024-08-28T20:21:49.564Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-28T20:21:49.564Z] GC before operation: completed in 391.159 ms, heap usage 161.047 MB -> 67.558 MB. [2024-08-28T20:21:55.029Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4985.072 ms) ====== [2024-08-28T20:21:55.029Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-28T20:21:55.029Z] GC before operation: completed in 433.061 ms, heap usage 422.288 MB -> 67.960 MB. [2024-08-28T20:22:00.589Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (5763.002 ms) ====== [2024-08-28T20:22:00.589Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-28T20:22:01.340Z] GC before operation: completed in 376.701 ms, heap usage 299.792 MB -> 67.996 MB. [2024-08-28T20:22:05.697Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4673.066 ms) ====== [2024-08-28T20:22:05.697Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-28T20:22:05.697Z] GC before operation: completed in 350.206 ms, heap usage 191.239 MB -> 68.107 MB. [2024-08-28T20:22:11.186Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (4916.320 ms) ====== [2024-08-28T20:22:11.186Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-28T20:22:11.186Z] GC before operation: completed in 420.282 ms, heap usage 200.924 MB -> 68.060 MB. [2024-08-28T20:22:16.647Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (5036.626 ms) ====== [2024-08-28T20:22:16.647Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-28T20:22:16.647Z] GC before operation: completed in 385.501 ms, heap usage 274.077 MB -> 68.304 MB. [2024-08-28T20:22:21.000Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (4370.612 ms) ====== [2024-08-28T20:22:21.000Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-28T20:22:21.757Z] GC before operation: completed in 397.800 ms, heap usage 204.884 MB -> 85.019 MB. [2024-08-28T20:22:26.149Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4501.352 ms) ====== [2024-08-28T20:22:26.149Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-28T20:22:26.149Z] GC before operation: completed in 347.999 ms, heap usage 126.926 MB -> 67.843 MB. [2024-08-28T20:22:31.698Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (4547.726 ms) ====== [2024-08-28T20:22:31.698Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-28T20:22:31.698Z] GC before operation: completed in 332.307 ms, heap usage 490.781 MB -> 68.662 MB. [2024-08-28T20:22:36.689Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4838.209 ms) ====== [2024-08-28T20:22:36.689Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-28T20:22:36.689Z] GC before operation: completed in 348.979 ms, heap usage 190.416 MB -> 68.589 MB. [2024-08-28T20:22:41.066Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (4460.863 ms) ====== [2024-08-28T20:22:41.066Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-28T20:22:41.827Z] GC before operation: completed in 447.504 ms, heap usage 159.771 MB -> 68.604 MB. [2024-08-28T20:22:46.221Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (4695.917 ms) ====== [2024-08-28T20:22:46.221Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-28T20:22:46.221Z] GC before operation: completed in 426.675 ms, heap usage 383.219 MB -> 68.916 MB. [2024-08-28T20:22:51.828Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (4640.487 ms) ====== [2024-08-28T20:22:51.828Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-28T20:22:51.828Z] GC before operation: completed in 404.481 ms, heap usage 119.446 MB -> 68.304 MB. [2024-08-28T20:22:56.200Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (4297.009 ms) ====== [2024-08-28T20:22:56.200Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-28T20:22:56.200Z] GC before operation: completed in 335.401 ms, heap usage 332.468 MB -> 69.164 MB. [2024-08-28T20:23:00.569Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (4409.270 ms) ====== [2024-08-28T20:23:00.569Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-28T20:23:01.318Z] GC before operation: completed in 468.817 ms, heap usage 279.095 MB -> 69.264 MB. [2024-08-28T20:23:05.644Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4134.889 ms) ====== [2024-08-28T20:23:05.644Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-28T20:23:05.644Z] GC before operation: completed in 323.718 ms, heap usage 281.760 MB -> 69.210 MB. [2024-08-28T20:23:10.167Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (4239.499 ms) ====== [2024-08-28T20:23:10.167Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-28T20:23:10.167Z] GC before operation: completed in 346.439 ms, heap usage 119.272 MB -> 68.831 MB. [2024-08-28T20:23:15.729Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (4908.844 ms) ====== [2024-08-28T20:23:15.729Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-28T20:23:15.729Z] GC before operation: completed in 402.681 ms, heap usage 348.749 MB -> 69.461 MB. [2024-08-28T20:23:20.150Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (4481.359 ms) ====== [2024-08-28T20:23:20.150Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-28T20:23:20.150Z] GC before operation: completed in 328.612 ms, heap usage 262.727 MB -> 69.605 MB. [2024-08-28T20:23:24.549Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4559.487 ms) ====== [2024-08-28T20:23:24.549Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-28T20:23:25.290Z] GC before operation: completed in 377.378 ms, heap usage 286.997 MB -> 69.734 MB. [2024-08-28T20:23:30.796Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (5053.793 ms) ====== [2024-08-28T20:23:30.796Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-28T20:23:30.796Z] GC before operation: completed in 336.806 ms, heap usage 185.835 MB -> 69.857 MB. [2024-08-28T20:23:34.709Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4072.581 ms) ====== [2024-08-28T20:23:35.448Z] ----------------------------------- [2024-08-28T20:23:35.448Z] renaissance-dec-tree_0_PASSED [2024-08-28T20:23:35.448Z] ----------------------------------- [2024-08-28T20:23:35.448Z] [2024-08-28T20:23:35.448Z] TEST TEARDOWN: [2024-08-28T20:23:35.448Z] Nothing to be done for teardown. [2024-08-28T20:23:35.448Z] renaissance-dec-tree_0 Finish Time: Wed Aug 28 20:23:35 2024 Epoch Time (ms): 1724876615087