[2025-05-08T07:46:39.449Z] Running test renaissance-dec-tree_0 ... [2025-05-08T07:46:39.449Z] =============================================== [2025-05-08T07:46:39.449Z] renaissance-dec-tree_0 Start Time: Thu May 8 07:46:38 2025 Epoch Time (ms): 1746690398574 [2025-05-08T07:46:39.449Z] variation: NoOptions [2025-05-08T07:46:39.449Z] JVM_OPTIONS: [2025-05-08T07:46:39.449Z] { \ [2025-05-08T07:46:39.449Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T07:46:39.449Z] echo "Nothing to be done for setup."; \ [2025-05-08T07:46:39.449Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-dec-tree_0"; \ [2025-05-08T07:46:39.449Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-dec-tree_0"; \ [2025-05-08T07:46:39.449Z] echo ""; echo "TESTING:"; \ [2025-05-08T07:46:39.449Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-05-08T07:46:39.449Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T07:46:39.449Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T07:46:39.449Z] echo "Nothing to be done for teardown."; \ [2025-05-08T07:46:39.449Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1746689949764/TestTargetResult"; [2025-05-08T07:46:39.449Z] [2025-05-08T07:46:39.449Z] TEST SETUP: [2025-05-08T07:46:39.449Z] Nothing to be done for setup. [2025-05-08T07:46:39.449Z] [2025-05-08T07:46:39.449Z] TESTING: [2025-05-08T07:46:44.822Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-08T07:46:46.774Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-05-08T07:46:46.774Z] GC before operation: completed in 41.452 ms, heap usage 62.921 MB -> 34.960 MB. [2025-05-08T07:46:55.528Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8933.101 ms) ====== [2025-05-08T07:46:55.528Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-05-08T07:46:55.528Z] GC before operation: completed in 88.984 ms, heap usage 205.672 MB -> 68.607 MB. [2025-05-08T07:46:57.541Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2137.031 ms) ====== [2025-05-08T07:46:57.541Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-05-08T07:46:57.541Z] GC before operation: completed in 105.888 ms, heap usage 318.583 MB -> 69.281 MB. [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1852.288 ms) ====== [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-05-08T07:47:10.788Z] GC before operation: completed in 76.123 ms, heap usage 159.504 MB -> 69.364 MB. [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1761.460 ms) ====== [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-05-08T07:47:10.788Z] GC before operation: completed in 82.544 ms, heap usage 178.772 MB -> 69.531 MB. [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1712.357 ms) ====== [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-05-08T07:47:10.788Z] GC before operation: completed in 85.506 ms, heap usage 196.182 MB -> 69.914 MB. [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1786.608 ms) ====== [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-05-08T07:47:10.788Z] GC before operation: completed in 84.999 ms, heap usage 176.960 MB -> 70.127 MB. [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1699.599 ms) ====== [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-05-08T07:47:10.788Z] GC before operation: completed in 86.541 ms, heap usage 177.810 MB -> 70.197 MB. [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1628.263 ms) ====== [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-05-08T07:47:10.788Z] GC before operation: completed in 88.672 ms, heap usage 176.066 MB -> 70.486 MB. [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1622.839 ms) ====== [2025-05-08T07:47:10.788Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-05-08T07:47:10.788Z] GC before operation: completed in 101.034 ms, heap usage 111.423 MB -> 70.306 MB. [2025-05-08T07:47:12.834Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1722.107 ms) ====== [2025-05-08T07:47:12.834Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-05-08T07:47:12.834Z] GC before operation: completed in 97.348 ms, heap usage 294.581 MB -> 70.911 MB. [2025-05-08T07:47:13.795Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1654.219 ms) ====== [2025-05-08T07:47:13.795Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-05-08T07:47:13.795Z] GC before operation: completed in 123.283 ms, heap usage 176.499 MB -> 87.424 MB. [2025-05-08T07:47:15.782Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1668.856 ms) ====== [2025-05-08T07:47:15.782Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-05-08T07:47:15.782Z] GC before operation: completed in 120.044 ms, heap usage 117.787 MB -> 71.379 MB. [2025-05-08T07:47:18.994Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1710.979 ms) ====== [2025-05-08T07:47:18.994Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-05-08T07:47:18.994Z] GC before operation: completed in 124.526 ms, heap usage 118.247 MB -> 81.943 MB. [2025-05-08T07:47:19.952Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1678.804 ms) ====== [2025-05-08T07:47:19.952Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-05-08T07:47:19.952Z] GC before operation: completed in 113.302 ms, heap usage 511.330 MB -> 71.814 MB. [2025-05-08T07:47:20.926Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1630.606 ms) ====== [2025-05-08T07:47:20.926Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-05-08T07:47:20.926Z] GC before operation: completed in 108.061 ms, heap usage 181.178 MB -> 71.350 MB. [2025-05-08T07:47:29.925Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1621.231 ms) ====== [2025-05-08T07:47:29.925Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-05-08T07:47:29.925Z] GC before operation: completed in 109.684 ms, heap usage 417.691 MB -> 71.839 MB. [2025-05-08T07:47:31.335Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1608.174 ms) ====== [2025-05-08T07:47:31.335Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-05-08T07:47:31.335Z] GC before operation: completed in 109.184 ms, heap usage 412.452 MB -> 71.807 MB. [2025-05-08T07:47:31.335Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1594.587 ms) ====== [2025-05-08T07:47:31.335Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-05-08T07:47:31.336Z] GC before operation: completed in 119.538 ms, heap usage 178.635 MB -> 71.592 MB. [2025-05-08T07:47:31.336Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1605.463 ms) ====== [2025-05-08T07:47:31.336Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-05-08T07:47:31.336Z] GC before operation: completed in 112.577 ms, heap usage 389.879 MB -> 72.125 MB. [2025-05-08T07:47:31.336Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1625.624 ms) ====== [2025-05-08T07:47:31.336Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-05-08T07:47:31.336Z] GC before operation: completed in 98.820 ms, heap usage 97.041 MB -> 71.792 MB. [2025-05-08T07:47:31.336Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1640.966 ms) ====== [2025-05-08T07:47:31.336Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-05-08T07:47:31.336Z] GC before operation: completed in 106.161 ms, heap usage 121.648 MB -> 71.741 MB. [2025-05-08T07:47:33.291Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1616.384 ms) ====== [2025-05-08T07:47:33.291Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-05-08T07:47:33.291Z] GC before operation: completed in 110.977 ms, heap usage 152.736 MB -> 77.538 MB. [2025-05-08T07:47:33.291Z] 07:47:32.842 WARN [block-manager-storage-async-thread-pool-12] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1053_2, which does not exist [2025-05-08T07:47:35.255Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1615.355 ms) ====== [2025-05-08T07:47:35.255Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-05-08T07:47:35.255Z] GC before operation: completed in 112.274 ms, heap usage 277.672 MB -> 77.988 MB. [2025-05-08T07:47:37.207Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1624.720 ms) ====== [2025-05-08T07:47:37.207Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-05-08T07:47:37.207Z] GC before operation: completed in 102.655 ms, heap usage 358.277 MB -> 78.092 MB. [2025-05-08T07:47:38.158Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1568.123 ms) ====== [2025-05-08T07:47:38.158Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-05-08T07:47:38.158Z] GC before operation: completed in 94.086 ms, heap usage 392.375 MB -> 72.659 MB. [2025-05-08T07:47:40.113Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1553.662 ms) ====== [2025-05-08T07:47:40.113Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-05-08T07:47:40.113Z] GC before operation: completed in 100.361 ms, heap usage 164.281 MB -> 83.391 MB. [2025-05-08T07:47:42.065Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1565.877 ms) ====== [2025-05-08T07:47:42.065Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-05-08T07:47:42.065Z] GC before operation: completed in 117.185 ms, heap usage 229.901 MB -> 84.266 MB. [2025-05-08T07:47:43.017Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1573.847 ms) ====== [2025-05-08T07:47:43.017Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-05-08T07:47:43.968Z] GC before operation: completed in 113.654 ms, heap usage 264.268 MB -> 84.032 MB. [2025-05-08T07:47:44.922Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1580.733 ms) ====== [2025-05-08T07:47:44.922Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-05-08T07:47:44.922Z] GC before operation: completed in 105.977 ms, heap usage 263.588 MB -> 95.386 MB. [2025-05-08T07:47:46.878Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1552.344 ms) ====== [2025-05-08T07:47:46.878Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-05-08T07:47:46.878Z] GC before operation: completed in 109.589 ms, heap usage 146.226 MB -> 73.050 MB. [2025-05-08T07:47:48.833Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1562.562 ms) ====== [2025-05-08T07:47:48.833Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-05-08T07:47:48.833Z] GC before operation: completed in 113.183 ms, heap usage 125.293 MB -> 72.909 MB. [2025-05-08T07:47:49.787Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1566.737 ms) ====== [2025-05-08T07:47:49.787Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-05-08T07:47:49.787Z] GC before operation: completed in 112.574 ms, heap usage 274.149 MB -> 73.847 MB. [2025-05-08T07:47:51.741Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1535.252 ms) ====== [2025-05-08T07:47:51.741Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-05-08T07:47:51.741Z] GC before operation: completed in 104.685 ms, heap usage 291.438 MB -> 73.604 MB. [2025-05-08T07:47:53.695Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1548.844 ms) ====== [2025-05-08T07:47:53.695Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-05-08T07:47:53.695Z] GC before operation: completed in 106.056 ms, heap usage 414.466 MB -> 79.074 MB. [2025-05-08T07:47:54.646Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1553.325 ms) ====== [2025-05-08T07:47:54.646Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-05-08T07:47:55.598Z] GC before operation: completed in 106.069 ms, heap usage 378.780 MB -> 73.925 MB. [2025-05-08T07:47:56.556Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1563.842 ms) ====== [2025-05-08T07:47:56.556Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-05-08T07:47:56.556Z] GC before operation: completed in 114.291 ms, heap usage 123.621 MB -> 73.409 MB. [2025-05-08T07:47:58.511Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1566.077 ms) ====== [2025-05-08T07:47:58.511Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-05-08T07:47:58.511Z] GC before operation: completed in 114.313 ms, heap usage 268.553 MB -> 73.982 MB. [2025-05-08T07:48:00.470Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1543.123 ms) ====== [2025-05-08T07:48:00.470Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-05-08T07:48:00.470Z] GC before operation: completed in 109.829 ms, heap usage 278.231 MB -> 90.845 MB. [2025-05-08T07:48:01.425Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1543.594 ms) ====== [2025-05-08T07:48:01.425Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-05-08T07:48:01.425Z] GC before operation: completed in 101.821 ms, heap usage 141.411 MB -> 73.616 MB. [2025-05-08T07:48:03.378Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1555.711 ms) ====== [2025-05-08T07:48:03.378Z] ----------------------------------- [2025-05-08T07:48:03.378Z] renaissance-dec-tree_0_PASSED [2025-05-08T07:48:03.378Z] ----------------------------------- [2025-05-08T07:48:03.378Z] [2025-05-08T07:48:03.378Z] TEST TEARDOWN: [2025-05-08T07:48:03.378Z] Nothing to be done for teardown. [2025-05-08T07:48:03.378Z] renaissance-dec-tree_0 Finish Time: Thu May 8 07:48:03 2025 Epoch Time (ms): 1746690483115