[2024-08-15T16:25:31.959Z] Running test renaissance-dec-tree_0 ... [2024-08-15T16:25:31.959Z] =============================================== [2024-08-15T16:25:31.959Z] renaissance-dec-tree_0 Start Time: Thu Aug 15 16:25:31 2024 Epoch Time (ms): 1723739131720 [2024-08-15T16:25:31.959Z] variation: NoOptions [2024-08-15T16:25:31.959Z] JVM_OPTIONS: [2024-08-15T16:25:31.959Z] { \ [2024-08-15T16:25:31.959Z] echo ""; echo "TEST SETUP:"; \ [2024-08-15T16:25:31.959Z] echo "Nothing to be done for setup."; \ [2024-08-15T16:25:31.959Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17237384002399/renaissance-dec-tree_0"; \ [2024-08-15T16:25:31.959Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17237384002399/renaissance-dec-tree_0"; \ [2024-08-15T16:25:31.959Z] echo ""; echo "TESTING:"; \ [2024-08-15T16:25:31.959Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_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_ppc64_aix_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17237384002399/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-15T16:25:31.959Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17237384002399/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-15T16:25:31.959Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-15T16:25:31.959Z] echo "Nothing to be done for teardown."; \ [2024-08-15T16:25:31.959Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17237384002399/TestTargetResult"; [2024-08-15T16:25:31.959Z] [2024-08-15T16:25:31.959Z] TEST SETUP: [2024-08-15T16:25:31.959Z] Nothing to be done for setup. [2024-08-15T16:25:31.959Z] [2024-08-15T16:25:31.959Z] TESTING: [2024-08-15T16:25:51.276Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-15T16:25:53.052Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 16) threads. [2024-08-15T16:25:55.807Z] WARNING: An illegal reflective access operation has occurred [2024-08-15T16:25:55.807Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/output_17237384002399/renaissance-dec-tree_0/harness-162533-9752228902008070902/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-15T16:25:55.807Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-15T16:25:55.807Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-15T16:25:55.807Z] WARNING: All illegal access operations will be denied in a future release [2024-08-15T16:25:59.528Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-15T16:25:59.528Z] GC before operation: completed in 72.698 ms, heap usage 61.338 MB -> 34.371 MB. [2024-08-15T16:26:07.563Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8490.550 ms) ====== [2024-08-15T16:26:07.563Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-15T16:26:07.563Z] GC before operation: completed in 97.018 ms, heap usage 235.272 MB -> 64.454 MB. [2024-08-15T16:26:09.369Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2271.943 ms) ====== [2024-08-15T16:26:09.369Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-15T16:26:09.369Z] GC before operation: completed in 102.374 ms, heap usage 114.283 MB -> 64.081 MB. [2024-08-15T16:26:11.610Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1976.128 ms) ====== [2024-08-15T16:26:11.610Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-15T16:26:11.610Z] GC before operation: completed in 98.554 ms, heap usage 282.572 MB -> 64.899 MB. [2024-08-15T16:26:15.128Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1915.750 ms) ====== [2024-08-15T16:26:15.128Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-15T16:26:15.128Z] GC before operation: completed in 101.689 ms, heap usage 248.632 MB -> 76.237 MB. [2024-08-15T16:26:16.397Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1781.753 ms) ====== [2024-08-15T16:26:16.398Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-15T16:26:16.398Z] GC before operation: completed in 109.954 ms, heap usage 274.829 MB -> 65.484 MB. [2024-08-15T16:26:17.599Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1788.973 ms) ====== [2024-08-15T16:26:17.599Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-15T16:26:17.599Z] GC before operation: completed in 107.704 ms, heap usage 235.270 MB -> 65.676 MB. [2024-08-15T16:26:19.688Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1802.065 ms) ====== [2024-08-15T16:26:19.688Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-15T16:26:19.688Z] GC before operation: completed in 115.592 ms, heap usage 116.544 MB -> 65.182 MB. [2024-08-15T16:26:22.730Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1701.964 ms) ====== [2024-08-15T16:26:22.730Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-15T16:26:22.730Z] GC before operation: completed in 115.066 ms, heap usage 165.278 MB -> 65.583 MB. [2024-08-15T16:26:22.730Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1714.559 ms) ====== [2024-08-15T16:26:22.730Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-15T16:26:24.540Z] GC before operation: completed in 116.977 ms, heap usage 211.128 MB -> 65.966 MB. [2024-08-15T16:26:24.540Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1731.502 ms) ====== [2024-08-15T16:26:24.540Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-15T16:26:25.789Z] GC before operation: completed in 156.268 ms, heap usage 206.331 MB -> 66.249 MB. [2024-08-15T16:26:26.675Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1728.505 ms) ====== [2024-08-15T16:26:26.675Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-15T16:26:26.675Z] GC before operation: completed in 155.689 ms, heap usage 338.080 MB -> 66.344 MB. [2024-08-15T16:26:28.453Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1737.752 ms) ====== [2024-08-15T16:26:28.453Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-15T16:26:28.453Z] GC before operation: completed in 130.658 ms, heap usage 132.877 MB -> 66.012 MB. [2024-08-15T16:26:30.245Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1724.019 ms) ====== [2024-08-15T16:26:30.245Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-15T16:26:31.109Z] GC before operation: completed in 143.906 ms, heap usage 249.980 MB -> 66.829 MB. [2024-08-15T16:26:32.896Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1715.168 ms) ====== [2024-08-15T16:26:32.896Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-15T16:26:32.897Z] GC before operation: completed in 154.226 ms, heap usage 205.218 MB -> 66.541 MB. [2024-08-15T16:26:34.693Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1678.970 ms) ====== [2024-08-15T16:26:34.693Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-15T16:26:34.693Z] GC before operation: completed in 137.651 ms, heap usage 204.444 MB -> 66.791 MB. [2024-08-15T16:26:36.010Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1667.095 ms) ====== [2024-08-15T16:26:36.010Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-15T16:26:36.010Z] GC before operation: completed in 169.002 ms, heap usage 221.929 MB -> 66.962 MB. [2024-08-15T16:26:38.131Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1677.192 ms) ====== [2024-08-15T16:26:38.131Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-15T16:26:38.131Z] GC before operation: completed in 183.567 ms, heap usage 230.081 MB -> 67.045 MB. [2024-08-15T16:26:39.392Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1659.673 ms) ====== [2024-08-15T16:26:39.392Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-15T16:26:40.268Z] GC before operation: completed in 161.111 ms, heap usage 204.854 MB -> 67.143 MB. [2024-08-15T16:26:42.059Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1667.214 ms) ====== [2024-08-15T16:26:42.059Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-15T16:26:42.059Z] GC before operation: completed in 173.368 ms, heap usage 191.358 MB -> 67.247 MB. [2024-08-15T16:26:43.858Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1746.287 ms) ====== [2024-08-15T16:26:43.858Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-15T16:26:43.858Z] GC before operation: completed in 168.986 ms, heap usage 143.107 MB -> 66.911 MB. [2024-08-15T16:26:45.671Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1617.135 ms) ====== [2024-08-15T16:26:45.671Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-15T16:26:45.671Z] GC before operation: completed in 176.010 ms, heap usage 333.019 MB -> 67.307 MB. [2024-08-15T16:26:47.363Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1721.830 ms) ====== [2024-08-15T16:26:47.363Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-15T16:26:47.363Z] GC before operation: completed in 152.716 ms, heap usage 102.791 MB -> 66.838 MB. [2024-08-15T16:26:48.605Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1629.830 ms) ====== [2024-08-15T16:26:48.605Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-15T16:26:49.488Z] GC before operation: completed in 183.067 ms, heap usage 196.937 MB -> 67.895 MB. [2024-08-15T16:26:50.823Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1671.257 ms) ====== [2024-08-15T16:26:50.823Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-15T16:26:50.823Z] GC before operation: completed in 179.589 ms, heap usage 133.999 MB -> 67.305 MB. [2024-08-15T16:26:52.614Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1690.890 ms) ====== [2024-08-15T16:26:52.614Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-15T16:26:52.614Z] GC before operation: completed in 174.064 ms, heap usage 101.510 MB -> 67.280 MB. [2024-08-15T16:26:54.407Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1728.677 ms) ====== [2024-08-15T16:26:54.407Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-15T16:26:54.407Z] GC before operation: completed in 182.093 ms, heap usage 213.873 MB -> 67.970 MB. [2024-08-15T16:26:56.198Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1803.288 ms) ====== [2024-08-15T16:26:56.198Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-15T16:26:58.540Z] GC before operation: completed in 190.400 ms, heap usage 266.984 MB -> 68.022 MB. [2024-08-15T16:26:58.540Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1678.218 ms) ====== [2024-08-15T16:26:58.540Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-15T16:26:58.540Z] GC before operation: completed in 175.077 ms, heap usage 238.389 MB -> 67.983 MB. [2024-08-15T16:27:00.605Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1632.080 ms) ====== [2024-08-15T16:27:00.605Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-15T16:27:00.605Z] GC before operation: completed in 181.909 ms, heap usage 185.711 MB -> 79.119 MB. [2024-08-15T16:27:01.802Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1643.625 ms) ====== [2024-08-15T16:27:01.802Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-15T16:27:03.009Z] GC before operation: completed in 176.523 ms, heap usage 143.429 MB -> 68.034 MB. [2024-08-15T16:27:08.477Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1706.700 ms) ====== [2024-08-15T16:27:08.477Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-15T16:27:08.477Z] GC before operation: completed in 181.653 ms, heap usage 268.381 MB -> 79.349 MB. [2024-08-15T16:27:09.760Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1665.862 ms) ====== [2024-08-15T16:27:09.760Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-15T16:27:09.760Z] GC before operation: completed in 189.514 ms, heap usage 173.972 MB -> 68.250 MB. [2024-08-15T16:27:09.760Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1656.314 ms) ====== [2024-08-15T16:27:09.760Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-15T16:27:09.760Z] GC before operation: completed in 192.310 ms, heap usage 169.439 MB -> 68.331 MB. [2024-08-15T16:27:09.760Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1683.331 ms) ====== [2024-08-15T16:27:09.760Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-15T16:27:09.760Z] GC before operation: completed in 179.806 ms, heap usage 220.602 MB -> 68.697 MB. [2024-08-15T16:27:11.913Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1683.514 ms) ====== [2024-08-15T16:27:11.913Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-15T16:27:11.913Z] GC before operation: completed in 183.714 ms, heap usage 159.511 MB -> 68.402 MB. [2024-08-15T16:27:13.721Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1642.262 ms) ====== [2024-08-15T16:27:13.721Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-15T16:27:13.721Z] GC before operation: completed in 172.377 ms, heap usage 107.706 MB -> 68.365 MB. [2024-08-15T16:27:15.264Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1654.220 ms) ====== [2024-08-15T16:27:15.264Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-15T16:27:15.264Z] GC before operation: completed in 155.818 ms, heap usage 100.000 MB -> 68.468 MB. [2024-08-15T16:27:17.334Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1618.683 ms) ====== [2024-08-15T16:27:17.334Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-15T16:27:17.334Z] GC before operation: completed in 197.141 ms, heap usage 245.572 MB -> 68.963 MB. [2024-08-15T16:27:19.130Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1627.630 ms) ====== [2024-08-15T16:27:19.130Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-15T16:27:19.131Z] GC before operation: completed in 202.513 ms, heap usage 203.326 MB -> 69.055 MB. [2024-08-15T16:27:20.932Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1711.611 ms) ====== [2024-08-15T16:27:20.932Z] ----------------------------------- [2024-08-15T16:27:20.932Z] renaissance-dec-tree_0_PASSED [2024-08-15T16:27:20.932Z] ----------------------------------- [2024-08-15T16:27:20.932Z] [2024-08-15T16:27:20.932Z] TEST TEARDOWN: [2024-08-15T16:27:20.932Z] Nothing to be done for teardown. [2024-08-15T16:27:20.932Z] renaissance-dec-tree_0 Finish Time: Thu Aug 15 16:27:20 2024 Epoch Time (ms): 1723739240416