[2025-02-26T22:35:27.669Z] Running test renaissance-dec-tree_0 ... [2025-02-26T22:35:27.669Z] =============================================== [2025-02-26T22:35:27.669Z] renaissance-dec-tree_0 Start Time: Wed Feb 26 22:35:26 2025 Epoch Time (ms): 1740609326835 [2025-02-26T22:35:27.669Z] variation: NoOptions [2025-02-26T22:35:27.669Z] JVM_OPTIONS: [2025-02-26T22:35:27.669Z] { \ [2025-02-26T22:35:27.669Z] echo ""; echo "TEST SETUP:"; \ [2025-02-26T22:35:27.669Z] echo "Nothing to be done for setup."; \ [2025-02-26T22:35:27.669Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17406088324162/renaissance-dec-tree_0"; \ [2025-02-26T22:35:27.669Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17406088324162/renaissance-dec-tree_0"; \ [2025-02-26T22:35:27.669Z] echo ""; echo "TESTING:"; \ [2025-02-26T22:35:27.669Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_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_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17406088324162/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-26T22:35:27.669Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17406088324162/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-26T22:35:27.669Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-26T22:35:27.669Z] echo "Nothing to be done for teardown."; \ [2025-02-26T22:35:27.669Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17406088324162/TestTargetResult"; [2025-02-26T22:35:27.669Z] [2025-02-26T22:35:27.669Z] TEST SETUP: [2025-02-26T22:35:27.669Z] Nothing to be done for setup. [2025-02-26T22:35:27.669Z] [2025-02-26T22:35:27.669Z] TESTING: [2025-02-26T22:35:30.689Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-26T22:35:32.646Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-26T22:35:33.599Z] WARNING: An illegal reflective access operation has occurred [2025-02-26T22:35:33.599Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17406088324162/renaissance-dec-tree_0/harness-223527-4401728687737876714/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-26T22:35:33.599Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-26T22:35:33.599Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-26T22:35:33.599Z] WARNING: All illegal access operations will be denied in a future release [2025-02-26T22:35:36.655Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-26T22:35:36.655Z] GC before operation: completed in 52.785 ms, heap usage 53.300 MB -> 33.751 MB. [2025-02-26T22:35:44.795Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8289.907 ms) ====== [2025-02-26T22:35:44.795Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-26T22:35:44.795Z] GC before operation: completed in 111.116 ms, heap usage 148.751 MB -> 63.733 MB. [2025-02-26T22:35:46.761Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2313.117 ms) ====== [2025-02-26T22:35:46.761Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-26T22:35:46.761Z] GC before operation: completed in 105.887 ms, heap usage 130.224 MB -> 63.977 MB. [2025-02-26T22:35:48.708Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1956.209 ms) ====== [2025-02-26T22:35:48.708Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-26T22:35:48.708Z] GC before operation: completed in 96.198 ms, heap usage 253.227 MB -> 75.541 MB. [2025-02-26T22:35:50.680Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1932.068 ms) ====== [2025-02-26T22:35:50.680Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-26T22:35:51.631Z] GC before operation: completed in 96.353 ms, heap usage 265.244 MB -> 64.498 MB. [2025-02-26T22:35:52.580Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1830.932 ms) ====== [2025-02-26T22:35:52.580Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-26T22:35:53.530Z] GC before operation: completed in 90.036 ms, heap usage 165.838 MB -> 64.581 MB. [2025-02-26T22:35:54.483Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1768.690 ms) ====== [2025-02-26T22:35:54.483Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-26T22:35:55.439Z] GC before operation: completed in 92.597 ms, heap usage 241.134 MB -> 64.908 MB. [2025-02-26T22:35:57.373Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1785.911 ms) ====== [2025-02-26T22:35:57.373Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-26T22:35:57.373Z] GC before operation: completed in 95.052 ms, heap usage 115.685 MB -> 64.829 MB. [2025-02-26T22:35:58.345Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1756.807 ms) ====== [2025-02-26T22:35:58.345Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-26T22:35:58.345Z] GC before operation: completed in 95.902 ms, heap usage 205.997 MB -> 65.235 MB. [2025-02-26T22:36:00.302Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1785.437 ms) ====== [2025-02-26T22:36:00.302Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-26T22:36:00.302Z] GC before operation: completed in 93.006 ms, heap usage 113.401 MB -> 65.207 MB. [2025-02-26T22:36:02.253Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1837.265 ms) ====== [2025-02-26T22:36:02.253Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-26T22:36:02.253Z] GC before operation: completed in 126.384 ms, heap usage 277.222 MB -> 81.748 MB. [2025-02-26T22:36:04.213Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1766.841 ms) ====== [2025-02-26T22:36:04.213Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-26T22:36:04.213Z] GC before operation: completed in 120.919 ms, heap usage 240.344 MB -> 65.706 MB. [2025-02-26T22:36:06.170Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1750.789 ms) ====== [2025-02-26T22:36:06.170Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-26T22:36:06.170Z] GC before operation: completed in 122.759 ms, heap usage 130.534 MB -> 65.380 MB. [2025-02-26T22:36:08.126Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1750.353 ms) ====== [2025-02-26T22:36:08.126Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-26T22:36:08.126Z] GC before operation: completed in 122.908 ms, heap usage 246.532 MB -> 65.780 MB. [2025-02-26T22:36:10.134Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1740.808 ms) ====== [2025-02-26T22:36:10.134Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-26T22:36:10.134Z] GC before operation: completed in 121.272 ms, heap usage 172.748 MB -> 65.628 MB. [2025-02-26T22:36:12.107Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1768.523 ms) ====== [2025-02-26T22:36:12.107Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-26T22:36:12.107Z] GC before operation: completed in 117.939 ms, heap usage 178.621 MB -> 65.986 MB. [2025-02-26T22:36:14.065Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1751.971 ms) ====== [2025-02-26T22:36:14.065Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-26T22:36:14.065Z] GC before operation: completed in 133.664 ms, heap usage 196.917 MB -> 66.304 MB. [2025-02-26T22:36:16.022Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1689.802 ms) ====== [2025-02-26T22:36:16.022Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-26T22:36:16.022Z] GC before operation: completed in 113.965 ms, heap usage 206.118 MB -> 66.406 MB. [2025-02-26T22:36:16.988Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1680.923 ms) ====== [2025-02-26T22:36:16.988Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-26T22:36:16.988Z] GC before operation: completed in 135.350 ms, heap usage 239.175 MB -> 83.082 MB. [2025-02-26T22:36:18.946Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1703.063 ms) ====== [2025-02-26T22:36:18.946Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-26T22:36:18.946Z] GC before operation: completed in 121.772 ms, heap usage 239.868 MB -> 66.505 MB. [2025-02-26T22:36:20.907Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1709.679 ms) ====== [2025-02-26T22:36:20.907Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-26T22:36:20.907Z] GC before operation: completed in 136.978 ms, heap usage 101.513 MB -> 66.457 MB. [2025-02-26T22:36:22.970Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1663.115 ms) ====== [2025-02-26T22:36:22.970Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-26T22:36:22.970Z] GC before operation: completed in 120.232 ms, heap usage 172.936 MB -> 66.420 MB. [2025-02-26T22:36:24.928Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1720.340 ms) ====== [2025-02-26T22:36:24.928Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-26T22:36:24.928Z] GC before operation: completed in 117.544 ms, heap usage 108.799 MB -> 66.501 MB. [2025-02-26T22:36:26.884Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1665.031 ms) ====== [2025-02-26T22:36:26.884Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-26T22:36:26.884Z] GC before operation: completed in 116.123 ms, heap usage 100.342 MB -> 66.545 MB. [2025-02-26T22:36:27.833Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1610.259 ms) ====== [2025-02-26T22:36:27.833Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-26T22:36:27.833Z] GC before operation: completed in 113.260 ms, heap usage 160.614 MB -> 66.719 MB. [2025-02-26T22:36:29.790Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1602.803 ms) ====== [2025-02-26T22:36:29.790Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-26T22:36:29.790Z] GC before operation: completed in 114.195 ms, heap usage 174.282 MB -> 77.864 MB. [2025-02-26T22:36:31.750Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1671.032 ms) ====== [2025-02-26T22:36:31.750Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-26T22:36:31.750Z] GC before operation: completed in 109.012 ms, heap usage 236.521 MB -> 78.248 MB. [2025-02-26T22:36:33.751Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1602.525 ms) ====== [2025-02-26T22:36:33.751Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-26T22:36:33.751Z] GC before operation: completed in 119.984 ms, heap usage 194.601 MB -> 67.278 MB. [2025-02-26T22:36:34.706Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1613.209 ms) ====== [2025-02-26T22:36:34.706Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-26T22:36:35.654Z] GC before operation: completed in 111.974 ms, heap usage 171.331 MB -> 72.918 MB. [2025-02-26T22:36:36.624Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1600.530 ms) ====== [2025-02-26T22:36:36.624Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-26T22:36:36.624Z] GC before operation: completed in 111.064 ms, heap usage 113.350 MB -> 67.333 MB. [2025-02-26T22:36:38.593Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1600.169 ms) ====== [2025-02-26T22:36:38.593Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-26T22:36:38.593Z] GC before operation: completed in 113.055 ms, heap usage 215.609 MB -> 78.613 MB. [2025-02-26T22:36:40.563Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1574.431 ms) ====== [2025-02-26T22:36:40.563Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-26T22:36:40.563Z] GC before operation: completed in 117.494 ms, heap usage 117.530 MB -> 67.382 MB. [2025-02-26T22:36:41.516Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1592.586 ms) ====== [2025-02-26T22:36:41.516Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-26T22:36:42.469Z] GC before operation: completed in 113.348 ms, heap usage 170.925 MB -> 67.632 MB. [2025-02-26T22:36:42.469Z] 25/02/26 22:36:41 WARN BlockManager: Asked to remove block rdd_1565_2, which does not exist [2025-02-26T22:36:43.418Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1600.361 ms) ====== [2025-02-26T22:36:43.418Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-26T22:36:43.418Z] GC before operation: completed in 113.742 ms, heap usage 161.651 MB -> 78.620 MB. [2025-02-26T22:36:45.428Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1609.789 ms) ====== [2025-02-26T22:36:45.428Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-26T22:36:45.428Z] GC before operation: completed in 154.308 ms, heap usage 221.458 MB -> 84.445 MB. [2025-02-26T22:36:47.379Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1628.457 ms) ====== [2025-02-26T22:36:47.380Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-26T22:36:47.380Z] GC before operation: completed in 138.646 ms, heap usage 118.640 MB -> 67.656 MB. [2025-02-26T22:36:49.327Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1657.285 ms) ====== [2025-02-26T22:36:49.327Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-26T22:36:49.327Z] GC before operation: completed in 135.379 ms, heap usage 273.279 MB -> 68.168 MB. [2025-02-26T22:36:51.278Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1681.392 ms) ====== [2025-02-26T22:36:51.278Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-26T22:36:51.278Z] GC before operation: completed in 145.331 ms, heap usage 162.678 MB -> 67.989 MB. [2025-02-26T22:36:52.226Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1664.366 ms) ====== [2025-02-26T22:36:52.226Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-26T22:36:53.174Z] GC before operation: completed in 141.303 ms, heap usage 211.639 MB -> 68.502 MB. [2025-02-26T22:36:54.126Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1664.547 ms) ====== [2025-02-26T22:36:54.126Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-26T22:36:54.126Z] GC before operation: completed in 141.635 ms, heap usage 200.442 MB -> 79.747 MB. [2025-02-26T22:36:56.085Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1678.015 ms) ====== [2025-02-26T22:36:56.085Z] ----------------------------------- [2025-02-26T22:36:56.085Z] renaissance-dec-tree_0_PASSED [2025-02-26T22:36:56.085Z] ----------------------------------- [2025-02-26T22:36:56.085Z] [2025-02-26T22:36:56.085Z] TEST TEARDOWN: [2025-02-26T22:36:56.085Z] Nothing to be done for teardown. [2025-02-26T22:36:56.085Z] renaissance-dec-tree_0 Finish Time: Wed Feb 26 22:36:55 2025 Epoch Time (ms): 1740609415904