[2024-08-01T00:46:53.186Z] Running test renaissance-dec-tree_0 ... [2024-08-01T00:46:53.186Z] =============================================== [2024-08-01T00:46:53.186Z] renaissance-dec-tree_0 Start Time: Thu Aug 1 00:46:52 2024 Epoch Time (ms): 1722473212509 [2024-08-01T00:46:53.186Z] variation: NoOptions [2024-08-01T00:46:53.186Z] JVM_OPTIONS: [2024-08-01T00:46:53.186Z] { \ [2024-08-01T00:46:53.186Z] echo ""; echo "TEST SETUP:"; \ [2024-08-01T00:46:53.186Z] echo "Nothing to be done for setup."; \ [2024-08-01T00:46:53.186Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17224704683843/renaissance-dec-tree_0"; \ [2024-08-01T00:46:53.186Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17224704683843/renaissance-dec-tree_0"; \ [2024-08-01T00:46:53.186Z] echo ""; echo "TESTING:"; \ [2024-08-01T00:46:53.186Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/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_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17224704683843/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-01T00:46:53.186Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17224704683843/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-01T00:46:53.186Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-01T00:46:53.186Z] echo "Nothing to be done for teardown."; \ [2024-08-01T00:46:53.186Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17224704683843/TestTargetResult"; [2024-08-01T00:46:53.186Z] [2024-08-01T00:46:53.186Z] TEST SETUP: [2024-08-01T00:46:53.186Z] Nothing to be done for setup. [2024-08-01T00:46:53.186Z] [2024-08-01T00:46:53.186Z] TESTING: [2024-08-01T00:47:07.415Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-01T00:47:19.792Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-01T00:47:27.071Z] WARNING: An illegal reflective access operation has occurred [2024-08-01T00:47:27.071Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/output_17224704683843/renaissance-dec-tree_0/harness-004655-2603849896630994213/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-01T00:47:27.071Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-01T00:47:27.071Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-01T00:47:27.071Z] WARNING: All illegal access operations will be denied in a future release [2024-08-01T00:47:41.545Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-01T00:47:41.545Z] GC before operation: completed in 447.401 ms, heap usage 64.509 MB -> 33.820 MB. [2024-08-01T00:49:02.476Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (72337.309 ms) ====== [2024-08-01T00:49:02.476Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-01T00:49:02.476Z] GC before operation: completed in 799.961 ms, heap usage 133.183 MB -> 63.666 MB. [2024-08-01T00:49:19.355Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (24236.076 ms) ====== [2024-08-01T00:49:19.355Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-01T00:49:19.355Z] GC before operation: completed in 954.988 ms, heap usage 112.008 MB -> 63.839 MB. [2024-08-01T00:49:42.130Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (20542.004 ms) ====== [2024-08-01T00:49:42.130Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-01T00:49:42.130Z] GC before operation: completed in 750.595 ms, heap usage 278.343 MB -> 64.256 MB. [2024-08-01T00:49:58.949Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (16514.115 ms) ====== [2024-08-01T00:49:58.949Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-01T00:49:58.949Z] GC before operation: completed in 891.446 ms, heap usage 105.253 MB -> 91.797 MB. [2024-08-01T00:50:13.341Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (15104.193 ms) ====== [2024-08-01T00:50:13.341Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-01T00:50:14.156Z] GC before operation: completed in 958.616 ms, heap usage 351.729 MB -> 64.695 MB. [2024-08-01T00:50:29.360Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (14724.522 ms) ====== [2024-08-01T00:50:29.360Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-01T00:50:30.205Z] GC before operation: completed in 932.235 ms, heap usage 266.693 MB -> 65.293 MB. [2024-08-01T00:50:44.728Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (13712.160 ms) ====== [2024-08-01T00:50:44.728Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-01T00:50:44.728Z] GC before operation: completed in 997.118 ms, heap usage 231.068 MB -> 65.085 MB. [2024-08-01T00:50:57.229Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (12734.929 ms) ====== [2024-08-01T00:50:57.229Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-01T00:50:58.939Z] GC before operation: completed in 953.284 ms, heap usage 225.848 MB -> 92.648 MB. [2024-08-01T00:50:58.939Z] 24/08/01 00:50:58 WARN BlockManager: Asked to remove block broadcast_106, which does not exist [2024-08-01T00:51:11.314Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (11553.291 ms) ====== [2024-08-01T00:51:11.314Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-01T00:51:11.314Z] GC before operation: completed in 706.362 ms, heap usage 224.396 MB -> 65.448 MB. [2024-08-01T00:51:21.813Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (11154.874 ms) ====== [2024-08-01T00:51:21.813Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-01T00:51:22.645Z] GC before operation: completed in 830.568 ms, heap usage 147.347 MB -> 65.257 MB. [2024-08-01T00:51:35.717Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (11650.863 ms) ====== [2024-08-01T00:51:35.717Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-01T00:51:35.717Z] GC before operation: completed in 952.214 ms, heap usage 186.228 MB -> 93.112 MB. [2024-08-01T00:51:48.117Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (11984.205 ms) ====== [2024-08-01T00:51:48.117Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-01T00:51:48.117Z] GC before operation: completed in 864.614 ms, heap usage 421.983 MB -> 76.523 MB. [2024-08-01T00:51:58.609Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (10564.802 ms) ====== [2024-08-01T00:51:58.609Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-01T00:51:59.424Z] GC before operation: completed in 758.008 ms, heap usage 229.861 MB -> 65.737 MB. [2024-08-01T00:52:10.012Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (10541.824 ms) ====== [2024-08-01T00:52:10.012Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-01T00:52:10.833Z] GC before operation: completed in 916.649 ms, heap usage 140.775 MB -> 92.749 MB. [2024-08-01T00:52:21.160Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (10017.328 ms) ====== [2024-08-01T00:52:21.160Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-01T00:52:21.984Z] GC before operation: completed in 809.993 ms, heap usage 227.761 MB -> 66.093 MB. [2024-08-01T00:52:32.300Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (10641.924 ms) ====== [2024-08-01T00:52:32.300Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-01T00:52:33.136Z] GC before operation: completed in 879.362 ms, heap usage 132.120 MB -> 65.822 MB. [2024-08-01T00:52:43.531Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (9894.902 ms) ====== [2024-08-01T00:52:43.531Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-01T00:52:44.491Z] GC before operation: completed in 957.292 ms, heap usage 263.997 MB -> 66.355 MB. [2024-08-01T00:52:55.408Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (10874.276 ms) ====== [2024-08-01T00:52:55.408Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-01T00:52:56.261Z] GC before operation: completed in 887.409 ms, heap usage 192.789 MB -> 66.306 MB. [2024-08-01T00:53:06.972Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (10552.132 ms) ====== [2024-08-01T00:53:06.972Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-01T00:53:06.972Z] GC before operation: completed in 800.789 ms, heap usage 106.556 MB -> 66.269 MB. [2024-08-01T00:53:17.436Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (9827.988 ms) ====== [2024-08-01T00:53:17.437Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-01T00:53:18.266Z] GC before operation: completed in 817.711 ms, heap usage 193.257 MB -> 66.508 MB. [2024-08-01T00:53:27.119Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (8988.183 ms) ====== [2024-08-01T00:53:27.119Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-01T00:53:27.932Z] GC before operation: completed in 746.969 ms, heap usage 334.695 MB -> 66.755 MB. [2024-08-01T00:53:36.652Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (9167.901 ms) ====== [2024-08-01T00:53:36.652Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-01T00:53:37.460Z] GC before operation: completed in 752.818 ms, heap usage 127.850 MB -> 66.831 MB. [2024-08-01T00:53:47.768Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (10038.415 ms) ====== [2024-08-01T00:53:47.768Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-01T00:53:48.608Z] GC before operation: completed in 814.701 ms, heap usage 217.121 MB -> 66.945 MB. [2024-08-01T00:53:58.061Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (9544.886 ms) ====== [2024-08-01T00:53:58.061Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-01T00:53:58.874Z] GC before operation: completed in 829.985 ms, heap usage 243.521 MB -> 66.898 MB. [2024-08-01T00:54:09.361Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (9864.436 ms) ====== [2024-08-01T00:54:09.361Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-01T00:54:09.361Z] GC before operation: completed in 797.796 ms, heap usage 237.255 MB -> 67.189 MB. [2024-08-01T00:54:18.279Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (8940.100 ms) ====== [2024-08-01T00:54:18.279Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-01T00:54:19.128Z] GC before operation: completed in 966.784 ms, heap usage 333.050 MB -> 67.143 MB. [2024-08-01T00:54:29.437Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (8922.716 ms) ====== [2024-08-01T00:54:29.437Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-01T00:54:29.437Z] GC before operation: completed in 810.550 ms, heap usage 314.713 MB -> 67.269 MB. [2024-08-01T00:54:39.863Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (9604.055 ms) ====== [2024-08-01T00:54:39.863Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-01T00:54:39.863Z] GC before operation: completed in 844.512 ms, heap usage 221.894 MB -> 67.517 MB. [2024-08-01T00:54:50.266Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (9671.354 ms) ====== [2024-08-01T00:54:50.266Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-01T00:54:50.266Z] GC before operation: completed in 870.791 ms, heap usage 259.275 MB -> 67.470 MB. [2024-08-01T00:55:01.397Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (9550.328 ms) ====== [2024-08-01T00:55:01.397Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-01T00:55:01.397Z] GC before operation: completed in 707.121 ms, heap usage 297.173 MB -> 68.251 MB. [2024-08-01T00:55:10.190Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (9924.257 ms) ====== [2024-08-01T00:55:10.190Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-01T00:55:11.028Z] GC before operation: completed in 885.093 ms, heap usage 171.888 MB -> 67.380 MB. [2024-08-01T00:55:21.633Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (9282.049 ms) ====== [2024-08-01T00:55:21.633Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-01T00:55:21.633Z] GC before operation: completed in 928.519 ms, heap usage 196.220 MB -> 67.761 MB. [2024-08-01T00:55:30.409Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (8713.886 ms) ====== [2024-08-01T00:55:30.409Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-01T00:55:31.234Z] GC before operation: completed in 906.636 ms, heap usage 105.549 MB -> 67.979 MB. [2024-08-01T00:55:39.967Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (8481.349 ms) ====== [2024-08-01T00:55:39.968Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-01T00:55:40.792Z] GC before operation: completed in 849.763 ms, heap usage 333.085 MB -> 67.939 MB. [2024-08-01T00:55:51.395Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (9727.526 ms) ====== [2024-08-01T00:55:51.395Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-01T00:55:51.395Z] GC before operation: completed in 886.911 ms, heap usage 210.995 MB -> 68.037 MB. [2024-08-01T00:56:00.411Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (9238.596 ms) ====== [2024-08-01T00:56:00.411Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-01T00:56:01.237Z] GC before operation: completed in 859.540 ms, heap usage 288.226 MB -> 68.275 MB. [2024-08-01T00:56:10.690Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (9585.847 ms) ====== [2024-08-01T00:56:10.690Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-01T00:56:11.604Z] GC before operation: completed in 850.466 ms, heap usage 305.974 MB -> 68.374 MB. [2024-08-01T00:56:20.476Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (8662.222 ms) ====== [2024-08-01T00:56:20.476Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-01T00:56:21.284Z] GC before operation: completed in 806.947 ms, heap usage 330.738 MB -> 68.323 MB. [2024-08-01T00:56:30.257Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (9366.580 ms) ====== [2024-08-01T00:56:30.257Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-01T00:56:31.076Z] GC before operation: completed in 1043.578 ms, heap usage 302.050 MB -> 68.557 MB. [2024-08-01T00:56:41.426Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (9198.189 ms) ====== [2024-08-01T00:56:42.235Z] ----------------------------------- [2024-08-01T00:56:42.235Z] renaissance-dec-tree_0_PASSED [2024-08-01T00:56:42.235Z] ----------------------------------- [2024-08-01T00:56:42.235Z] [2024-08-01T00:56:42.235Z] TEST TEARDOWN: [2024-08-01T00:56:42.235Z] Nothing to be done for teardown. [2024-08-01T00:56:42.235Z] renaissance-dec-tree_0 Finish Time: Thu Aug 1 00:56:41 2024 Epoch Time (ms): 1722473801449