[2024-11-14T12:45:09.206Z] Running test renaissance-dec-tree_0 ... [2024-11-14T12:45:09.206Z] =============================================== [2024-11-14T12:45:09.206Z] renaissance-dec-tree_0 Start Time: Thu Nov 14 12:45:09 2024 Epoch Time (ms): 1731588309011 [2024-11-14T12:45:09.206Z] variation: NoOptions [2024-11-14T12:45:09.206Z] JVM_OPTIONS: [2024-11-14T12:45:09.206Z] { \ [2024-11-14T12:45:09.206Z] echo ""; echo "TEST SETUP:"; \ [2024-11-14T12:45:09.206Z] echo "Nothing to be done for setup."; \ [2024-11-14T12:45:09.206Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17315876662546/renaissance-dec-tree_0"; \ [2024-11-14T12:45:09.206Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17315876662546/renaissance-dec-tree_0"; \ [2024-11-14T12:45:09.206Z] echo ""; echo "TESTING:"; \ [2024-11-14T12:45:09.206Z] "/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_17315876662546/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-11-14T12:45:09.206Z] 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_17315876662546/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-14T12:45:09.206Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-14T12:45:09.206Z] echo "Nothing to be done for teardown."; \ [2024-11-14T12:45:09.206Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17315876662546/TestTargetResult"; [2024-11-14T12:45:09.206Z] [2024-11-14T12:45:09.207Z] TEST SETUP: [2024-11-14T12:45:09.207Z] Nothing to be done for setup. [2024-11-14T12:45:09.207Z] [2024-11-14T12:45:09.207Z] TESTING: [2024-11-14T12:45:14.016Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-14T12:45:16.617Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-14T12:45:22.458Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-14T12:45:22.458Z] GC before operation: completed in 99.759 ms, heap usage 60.703 MB -> 35.040 MB. [2024-11-14T12:45:37.327Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (13914.219 ms) ====== [2024-11-14T12:45:37.327Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-14T12:45:37.327Z] GC before operation: completed in 134.329 ms, heap usage 131.547 MB -> 64.089 MB. [2024-11-14T12:45:40.927Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (4249.984 ms) ====== [2024-11-14T12:45:40.927Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-14T12:45:40.927Z] GC before operation: completed in 184.289 ms, heap usage 192.237 MB -> 64.877 MB. [2024-11-14T12:45:44.549Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3572.775 ms) ====== [2024-11-14T12:45:44.549Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-14T12:45:44.549Z] GC before operation: completed in 131.160 ms, heap usage 240.065 MB -> 65.225 MB. [2024-11-14T12:45:48.149Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3219.264 ms) ====== [2024-11-14T12:45:48.149Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-14T12:45:48.149Z] GC before operation: completed in 174.476 ms, heap usage 146.871 MB -> 65.492 MB. [2024-11-14T12:45:50.785Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3097.428 ms) ====== [2024-11-14T12:45:50.785Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-14T12:45:51.592Z] GC before operation: completed in 162.869 ms, heap usage 274.072 MB -> 65.581 MB. [2024-11-14T12:45:54.191Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2656.686 ms) ====== [2024-11-14T12:45:54.191Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-14T12:45:54.191Z] GC before operation: completed in 176.952 ms, heap usage 331.906 MB -> 93.439 MB. [2024-11-14T12:45:56.797Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2567.607 ms) ====== [2024-11-14T12:45:56.797Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-14T12:45:56.797Z] GC before operation: completed in 190.172 ms, heap usage 332.210 MB -> 66.013 MB. [2024-11-14T12:45:59.384Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2688.534 ms) ====== [2024-11-14T12:45:59.384Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-14T12:45:59.384Z] GC before operation: completed in 163.355 ms, heap usage 305.123 MB -> 66.297 MB. [2024-11-14T12:46:01.982Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2518.084 ms) ====== [2024-11-14T12:46:01.982Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-14T12:46:02.802Z] GC before operation: completed in 173.376 ms, heap usage 305.929 MB -> 66.285 MB. [2024-11-14T12:46:05.413Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2826.992 ms) ====== [2024-11-14T12:46:05.413Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-14T12:46:05.413Z] GC before operation: completed in 144.431 ms, heap usage 132.095 MB -> 66.280 MB. [2024-11-14T12:46:08.008Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2340.146 ms) ====== [2024-11-14T12:46:08.008Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-14T12:46:08.008Z] GC before operation: completed in 136.142 ms, heap usage 373.235 MB -> 66.653 MB. [2024-11-14T12:46:10.608Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2511.891 ms) ====== [2024-11-14T12:46:10.608Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-14T12:46:10.608Z] GC before operation: completed in 143.471 ms, heap usage 278.386 MB -> 66.677 MB. [2024-11-14T12:46:13.189Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2142.463 ms) ====== [2024-11-14T12:46:13.189Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-14T12:46:13.189Z] GC before operation: completed in 167.488 ms, heap usage 283.056 MB -> 66.903 MB. [2024-11-14T12:46:15.773Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2399.065 ms) ====== [2024-11-14T12:46:15.773Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-14T12:46:15.773Z] GC before operation: completed in 200.351 ms, heap usage 272.958 MB -> 66.894 MB. [2024-11-14T12:46:17.435Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2240.819 ms) ====== [2024-11-14T12:46:17.435Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-14T12:46:18.252Z] GC before operation: completed in 221.489 ms, heap usage 372.803 MB -> 94.657 MB. [2024-11-14T12:46:20.844Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2407.873 ms) ====== [2024-11-14T12:46:20.844Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-14T12:46:20.844Z] GC before operation: completed in 175.031 ms, heap usage 132.675 MB -> 66.734 MB. [2024-11-14T12:46:22.521Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2214.126 ms) ====== [2024-11-14T12:46:22.521Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-14T12:46:23.353Z] GC before operation: completed in 210.869 ms, heap usage 459.118 MB -> 95.142 MB. [2024-11-14T12:46:25.041Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2165.054 ms) ====== [2024-11-14T12:46:25.041Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-14T12:46:25.041Z] GC before operation: completed in 144.711 ms, heap usage 297.804 MB -> 67.554 MB. [2024-11-14T12:46:27.659Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2165.391 ms) ====== [2024-11-14T12:46:27.659Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-14T12:46:27.659Z] GC before operation: completed in 175.352 ms, heap usage 281.125 MB -> 67.675 MB. [2024-11-14T12:46:30.263Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2467.303 ms) ====== [2024-11-14T12:46:30.263Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-14T12:46:30.263Z] GC before operation: completed in 179.464 ms, heap usage 159.215 MB -> 72.906 MB. [2024-11-14T12:46:32.516Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2245.739 ms) ====== [2024-11-14T12:46:32.516Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-14T12:46:32.516Z] GC before operation: completed in 206.417 ms, heap usage 468.869 MB -> 67.857 MB. [2024-11-14T12:46:35.119Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2257.975 ms) ====== [2024-11-14T12:46:35.119Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-14T12:46:35.119Z] GC before operation: completed in 180.027 ms, heap usage 275.009 MB -> 67.932 MB. [2024-11-14T12:46:37.733Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2243.820 ms) ====== [2024-11-14T12:46:37.733Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-14T12:46:37.733Z] GC before operation: completed in 248.640 ms, heap usage 141.889 MB -> 67.775 MB. [2024-11-14T12:46:40.326Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2332.996 ms) ====== [2024-11-14T12:46:40.326Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-14T12:46:40.326Z] GC before operation: completed in 230.162 ms, heap usage 131.465 MB -> 67.848 MB. [2024-11-14T12:46:42.007Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2044.176 ms) ====== [2024-11-14T12:46:42.007Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-14T12:46:42.817Z] GC before operation: completed in 187.875 ms, heap usage 131.499 MB -> 67.922 MB. [2024-11-14T12:46:44.486Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2053.694 ms) ====== [2024-11-14T12:46:44.486Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-14T12:46:44.486Z] GC before operation: completed in 172.220 ms, heap usage 473.674 MB -> 68.852 MB. [2024-11-14T12:46:46.164Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1829.092 ms) ====== [2024-11-14T12:46:46.164Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-14T12:46:46.974Z] GC before operation: completed in 193.766 ms, heap usage 106.840 MB -> 95.480 MB. [2024-11-14T12:46:46.974Z] 24/11/14 12:46:46 WARN BlockManager: Asked to remove block broadcast_376, which does not exist [2024-11-14T12:46:48.649Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2138.557 ms) ====== [2024-11-14T12:46:48.649Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-14T12:46:48.649Z] GC before operation: completed in 185.406 ms, heap usage 224.644 MB -> 68.532 MB. [2024-11-14T12:46:51.245Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1987.123 ms) ====== [2024-11-14T12:46:51.245Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-14T12:46:51.245Z] GC before operation: completed in 179.643 ms, heap usage 232.411 MB -> 68.591 MB. [2024-11-14T12:46:52.916Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1923.108 ms) ====== [2024-11-14T12:46:52.916Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-14T12:46:52.916Z] GC before operation: completed in 189.990 ms, heap usage 359.833 MB -> 68.587 MB. [2024-11-14T12:46:55.518Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1951.329 ms) ====== [2024-11-14T12:46:55.518Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-14T12:46:55.518Z] GC before operation: completed in 151.010 ms, heap usage 133.737 MB -> 68.571 MB. [2024-11-14T12:46:57.193Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2031.609 ms) ====== [2024-11-14T12:46:57.193Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-14T12:46:57.193Z] GC before operation: completed in 0.504 ms, heap usage 127.610 MB -> 83.033 MB. [2024-11-14T12:46:59.791Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2048.999 ms) ====== [2024-11-14T12:46:59.791Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-14T12:46:59.791Z] GC before operation: completed in 194.669 ms, heap usage 280.407 MB -> 85.623 MB. [2024-11-14T12:47:01.430Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1954.285 ms) ====== [2024-11-14T12:47:01.430Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-14T12:47:02.192Z] GC before operation: completed in 186.425 ms, heap usage 214.042 MB -> 69.128 MB. [2024-11-14T12:47:04.649Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2363.635 ms) ====== [2024-11-14T12:47:04.649Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-14T12:47:04.649Z] GC before operation: completed in 251.865 ms, heap usage 234.037 MB -> 96.782 MB. [2024-11-14T12:47:07.108Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2379.570 ms) ====== [2024-11-14T12:47:07.108Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-14T12:47:07.108Z] GC before operation: completed in 301.440 ms, heap usage 312.335 MB -> 69.334 MB. [2024-11-14T12:47:09.553Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2240.919 ms) ====== [2024-11-14T12:47:09.553Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-14T12:47:09.553Z] GC before operation: completed in 190.409 ms, heap usage 271.855 MB -> 69.464 MB. [2024-11-14T12:47:11.991Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2052.437 ms) ====== [2024-11-14T12:47:11.991Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-14T12:47:11.991Z] GC before operation: completed in 223.874 ms, heap usage 374.309 MB -> 69.564 MB. [2024-11-14T12:47:13.578Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1905.283 ms) ====== [2024-11-14T12:47:13.578Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-14T12:47:14.413Z] GC before operation: completed in 231.206 ms, heap usage 346.670 MB -> 69.435 MB. [2024-11-14T12:47:15.991Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1837.617 ms) ====== [2024-11-14T12:47:15.991Z] ----------------------------------- [2024-11-14T12:47:15.991Z] renaissance-dec-tree_0_PASSED [2024-11-14T12:47:15.991Z] ----------------------------------- [2024-11-14T12:47:15.991Z] [2024-11-14T12:47:15.991Z] TEST TEARDOWN: [2024-11-14T12:47:15.991Z] Nothing to be done for teardown. [2024-11-14T12:47:15.991Z] renaissance-dec-tree_0 Finish Time: Thu Nov 14 12:47:15 2024 Epoch Time (ms): 1731588435786