[2025-09-24T23:03:21.933Z] Running test renaissance-dec-tree_0 ... [2025-09-24T23:03:21.933Z] =============================================== [2025-09-24T23:03:21.933Z] renaissance-dec-tree_0 Start Time: Wed Sep 24 23:03:21 2025 Epoch Time (ms): 1758755001449 [2025-09-24T23:03:21.933Z] variation: NoOptions [2025-09-24T23:03:21.933Z] JVM_OPTIONS: [2025-09-24T23:03:21.933Z] { \ [2025-09-24T23:03:21.933Z] echo ""; echo "TEST SETUP:"; \ [2025-09-24T23:03:21.933Z] echo "Nothing to be done for setup."; \ [2025-09-24T23:03:21.933Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587544633630/renaissance-dec-tree_0"; \ [2025-09-24T23:03:21.933Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587544633630/renaissance-dec-tree_0"; \ [2025-09-24T23:03:21.933Z] echo ""; echo "TESTING:"; \ [2025-09-24T23:03:21.933Z] "/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_17587544633630/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-09-24T23:03:21.933Z] 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_17587544633630/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-24T23:03:21.933Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-24T23:03:21.933Z] echo "Nothing to be done for teardown."; \ [2025-09-24T23:03:21.933Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587544633630/TestTargetResult"; [2025-09-24T23:03:21.933Z] [2025-09-24T23:03:21.933Z] TEST SETUP: [2025-09-24T23:03:21.933Z] Nothing to be done for setup. [2025-09-24T23:03:21.933Z] [2025-09-24T23:03:21.933Z] TESTING: [2025-09-24T23:03:27.271Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-24T23:03:29.211Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-09-24T23:03:29.211Z] GC before operation: completed in 56.560 ms, heap usage 49.560 MB -> 35.083 MB. [2025-09-24T23:03:38.872Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8741.078 ms) ====== [2025-09-24T23:03:38.872Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-09-24T23:03:38.872Z] GC before operation: completed in 100.279 ms, heap usage 193.559 MB -> 68.871 MB. [2025-09-24T23:03:40.808Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2203.211 ms) ====== [2025-09-24T23:03:40.808Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-09-24T23:03:40.808Z] GC before operation: completed in 83.432 ms, heap usage 106.633 MB -> 69.071 MB. [2025-09-24T23:03:42.744Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1893.701 ms) ====== [2025-09-24T23:03:42.744Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-09-24T23:03:42.744Z] GC before operation: completed in 87.872 ms, heap usage 273.874 MB -> 69.964 MB. [2025-09-24T23:03:44.680Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1805.779 ms) ====== [2025-09-24T23:03:44.680Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-09-24T23:03:44.680Z] GC before operation: completed in 91.511 ms, heap usage 226.343 MB -> 70.104 MB. [2025-09-24T23:03:46.614Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1713.509 ms) ====== [2025-09-24T23:03:46.614Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-09-24T23:03:46.614Z] GC before operation: completed in 92.429 ms, heap usage 209.595 MB -> 70.323 MB. [2025-09-24T23:03:47.593Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1684.330 ms) ====== [2025-09-24T23:03:47.593Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-09-24T23:03:48.535Z] GC before operation: completed in 98.980 ms, heap usage 178.437 MB -> 70.237 MB. [2025-09-24T23:03:49.479Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1671.843 ms) ====== [2025-09-24T23:03:49.479Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-09-24T23:03:49.479Z] GC before operation: completed in 98.162 ms, heap usage 161.205 MB -> 70.570 MB. [2025-09-24T23:03:51.417Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1641.436 ms) ====== [2025-09-24T23:03:51.417Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-09-24T23:03:51.417Z] GC before operation: completed in 99.175 ms, heap usage 416.287 MB -> 71.101 MB. [2025-09-24T23:03:53.352Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1596.097 ms) ====== [2025-09-24T23:03:53.352Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-09-24T23:03:53.352Z] GC before operation: completed in 102.448 ms, heap usage 213.144 MB -> 87.614 MB. [2025-09-24T23:03:55.291Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1590.984 ms) ====== [2025-09-24T23:03:55.291Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-09-24T23:03:55.291Z] GC before operation: completed in 106.039 ms, heap usage 201.702 MB -> 70.955 MB. [2025-09-24T23:03:56.234Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1618.483 ms) ====== [2025-09-24T23:03:56.234Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-09-24T23:03:57.176Z] GC before operation: completed in 110.737 ms, heap usage 149.046 MB -> 70.926 MB. [2025-09-24T23:03:58.118Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1615.299 ms) ====== [2025-09-24T23:03:58.118Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-09-24T23:03:58.118Z] GC before operation: completed in 106.739 ms, heap usage 374.547 MB -> 82.657 MB. [2025-09-24T23:04:00.054Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1567.870 ms) ====== [2025-09-24T23:04:00.054Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-09-24T23:04:00.055Z] GC before operation: completed in 108.746 ms, heap usage 269.432 MB -> 71.521 MB. [2025-09-24T23:04:01.991Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1565.933 ms) ====== [2025-09-24T23:04:01.991Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-09-24T23:04:01.991Z] GC before operation: completed in 102.113 ms, heap usage 146.491 MB -> 82.568 MB. [2025-09-24T23:04:02.933Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1581.790 ms) ====== [2025-09-24T23:04:02.933Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-09-24T23:04:03.875Z] GC before operation: completed in 103.065 ms, heap usage 162.401 MB -> 82.690 MB. [2025-09-24T23:04:03.875Z] 23:04:02.926 WARN [block-manager-storage-async-thread-pool-14] org.apache.spark.storage.BlockManager - Asked to remove block rdd_717_0, which does not exist [2025-09-24T23:04:04.817Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1571.470 ms) ====== [2025-09-24T23:04:04.818Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-09-24T23:04:04.818Z] GC before operation: completed in 104.481 ms, heap usage 152.976 MB -> 71.762 MB. [2025-09-24T23:04:06.756Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1589.257 ms) ====== [2025-09-24T23:04:06.756Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-09-24T23:04:06.756Z] GC before operation: completed in 104.627 ms, heap usage 314.801 MB -> 72.075 MB. [2025-09-24T23:04:08.771Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1574.484 ms) ====== [2025-09-24T23:04:08.771Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-09-24T23:04:08.771Z] GC before operation: completed in 108.883 ms, heap usage 113.479 MB -> 71.676 MB. [2025-09-24T23:04:29.479Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:04:29.729Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:04:49.730Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:04:50.030Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:05:10.031Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:05:10.332Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:05:30.332Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:05:30.633Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:05:50.634Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:05:50.934Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:06:10.935Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:06:11.235Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:06:31.237Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:06:31.537Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:06:51.538Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:06:51.838Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:07:11.840Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:07:12.271Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:07:32.272Z] test-osuosl-centos74-ppc64le-1 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-09-24T23:07:32.572Z] test-osuosl-centos74-ppc64le-1 is back online [2025-09-24T23:07:49.605Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1580.388 ms) ====== [2025-09-24T23:07:49.605Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-09-24T23:07:49.605Z] GC before operation: completed in 110.252 ms, heap usage 257.797 MB -> 83.236 MB. [2025-09-24T23:07:49.605Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1513.815 ms) ====== [2025-09-24T23:07:49.605Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-09-24T23:07:49.605Z] GC before operation: completed in 102.327 ms, heap usage 282.001 MB -> 72.383 MB. [2025-09-24T23:07:49.605Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1552.885 ms) ====== [2025-09-24T23:07:49.605Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 105.043 ms, heap usage 116.140 MB -> 71.958 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1537.520 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 114.292 ms, heap usage 227.348 MB -> 89.364 MB. [2025-09-24T23:07:49.606Z] 23:04:14.599 WARN [block-manager-storage-async-thread-pool-95] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_303, which does not exist [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1578.087 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 112.194 ms, heap usage 163.012 MB -> 72.583 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1507.329 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 122.114 ms, heap usage 330.710 MB -> 84.137 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1526.974 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 116.637 ms, heap usage 263.683 MB -> 89.440 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1545.821 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 112.374 ms, heap usage 176.353 MB -> 72.692 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1536.383 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 119.585 ms, heap usage 320.237 MB -> 84.500 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1500.548 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 106.868 ms, heap usage 251.519 MB -> 73.310 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1535.155 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 117.545 ms, heap usage 414.209 MB -> 73.397 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1506.188 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 131.129 ms, heap usage 408.417 MB -> 73.544 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1494.841 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 134.326 ms, heap usage 400.819 MB -> 73.642 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1498.839 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 128.206 ms, heap usage 399.796 MB -> 73.927 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1529.183 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 128.628 ms, heap usage 402.954 MB -> 73.905 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1509.466 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 144.138 ms, heap usage 387.442 MB -> 85.112 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1548.605 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 117.382 ms, heap usage 234.751 MB -> 74.227 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1467.164 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 124.010 ms, heap usage 402.804 MB -> 74.320 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1496.616 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 124.775 ms, heap usage 391.187 MB -> 74.318 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1494.211 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 134.326 ms, heap usage 390.087 MB -> 85.282 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1469.664 ms) ====== [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-09-24T23:07:49.606Z] GC before operation: completed in 120.800 ms, heap usage 248.663 MB -> 74.659 MB. [2025-09-24T23:07:49.606Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1477.487 ms) ====== [2025-09-24T23:07:49.606Z] ----------------------------------- [2025-09-24T23:07:49.606Z] renaissance-dec-tree_0_PASSED [2025-09-24T23:07:49.606Z] ----------------------------------- [2025-09-24T23:07:49.606Z] [2025-09-24T23:07:49.606Z] TEST TEARDOWN: [2025-09-24T23:07:49.606Z] Nothing to be done for teardown. [2025-09-24T23:07:49.606Z] renaissance-dec-tree_0 Finish Time: Wed Sep 24 23:04:44 2025 Epoch Time (ms): 1758755084186