[2025-01-10T19:27:56.731Z] Running test renaissance-dec-tree_0 ... [2025-01-10T19:27:56.731Z] =============================================== [2025-01-10T19:27:56.731Z] renaissance-dec-tree_0 Start Time: Fri Jan 10 19:27:55 2025 Epoch Time (ms): 1736537275773 [2025-01-10T19:27:56.731Z] variation: NoOptions [2025-01-10T19:27:56.731Z] JVM_OPTIONS: [2025-01-10T19:27:56.731Z] { \ [2025-01-10T19:27:56.731Z] echo ""; echo "TEST SETUP:"; \ [2025-01-10T19:27:56.731Z] echo "Nothing to be done for setup."; \ [2025-01-10T19:27:56.731Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17365368469914/renaissance-dec-tree_0"; \ [2025-01-10T19:27:56.731Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17365368469914/renaissance-dec-tree_0"; \ [2025-01-10T19:27:56.731Z] echo ""; echo "TESTING:"; \ [2025-01-10T19:27:56.731Z] "/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_17365368469914/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-01-10T19:27:56.731Z] 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_17365368469914/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-10T19:27:56.731Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-10T19:27:56.731Z] echo "Nothing to be done for teardown."; \ [2025-01-10T19:27:56.731Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17365368469914/TestTargetResult"; [2025-01-10T19:27:56.731Z] [2025-01-10T19:27:56.731Z] TEST SETUP: [2025-01-10T19:27:56.731Z] Nothing to be done for setup. [2025-01-10T19:27:56.731Z] [2025-01-10T19:27:56.731Z] TESTING: [2025-01-10T19:27:59.747Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-10T19:28:00.697Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-01-10T19:28:03.720Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-01-10T19:28:03.720Z] GC before operation: completed in 55.230 ms, heap usage 57.904 MB -> 35.379 MB. [2025-01-10T19:28:10.417Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6708.549 ms) ====== [2025-01-10T19:28:10.417Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-01-10T19:28:10.417Z] GC before operation: completed in 72.762 ms, heap usage 165.406 MB -> 64.622 MB. [2025-01-10T19:28:12.370Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2082.458 ms) ====== [2025-01-10T19:28:12.370Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-01-10T19:28:12.370Z] GC before operation: completed in 80.153 ms, heap usage 352.831 MB -> 92.769 MB. [2025-01-10T19:28:12.370Z] 25/01/10 19:28:12 WARN BlockManager: Asked to remove block broadcast_26, which does not exist [2025-01-10T19:28:12.370Z] 25/01/10 19:28:12 WARN BlockManager: Asked to remove block broadcast_26_piece0, which does not exist [2025-01-10T19:28:14.325Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1697.865 ms) ====== [2025-01-10T19:28:14.325Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-01-10T19:28:14.325Z] GC before operation: completed in 68.666 ms, heap usage 254.663 MB -> 65.601 MB. [2025-01-10T19:28:16.278Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1587.046 ms) ====== [2025-01-10T19:28:16.278Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-01-10T19:28:16.278Z] GC before operation: completed in 79.161 ms, heap usage 287.847 MB -> 66.026 MB. [2025-01-10T19:28:17.230Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1517.066 ms) ====== [2025-01-10T19:28:17.230Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-01-10T19:28:17.230Z] GC before operation: completed in 79.647 ms, heap usage 342.362 MB -> 65.924 MB. [2025-01-10T19:28:19.196Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1530.480 ms) ====== [2025-01-10T19:28:19.196Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-01-10T19:28:19.196Z] GC before operation: completed in 78.838 ms, heap usage 133.884 MB -> 66.190 MB. [2025-01-10T19:28:21.152Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1515.391 ms) ====== [2025-01-10T19:28:21.152Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-01-10T19:28:21.152Z] GC before operation: completed in 81.567 ms, heap usage 281.958 MB -> 66.341 MB. [2025-01-10T19:28:22.103Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1427.228 ms) ====== [2025-01-10T19:28:22.103Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-01-10T19:28:22.103Z] GC before operation: completed in 83.222 ms, heap usage 331.764 MB -> 77.530 MB. [2025-01-10T19:28:24.057Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1459.962 ms) ====== [2025-01-10T19:28:24.057Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-01-10T19:28:24.057Z] GC before operation: completed in 84.861 ms, heap usage 249.633 MB -> 66.773 MB. [2025-01-10T19:28:25.008Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1427.650 ms) ====== [2025-01-10T19:28:25.008Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-01-10T19:28:25.008Z] GC before operation: completed in 84.104 ms, heap usage 327.181 MB -> 66.842 MB. [2025-01-10T19:28:26.966Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1404.728 ms) ====== [2025-01-10T19:28:26.966Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-01-10T19:28:26.966Z] GC before operation: completed in 92.318 ms, heap usage 311.797 MB -> 67.060 MB. [2025-01-10T19:28:28.983Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1408.339 ms) ====== [2025-01-10T19:28:28.983Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-01-10T19:28:28.983Z] GC before operation: completed in 93.873 ms, heap usage 307.628 MB -> 67.089 MB. [2025-01-10T19:28:29.935Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1446.119 ms) ====== [2025-01-10T19:28:29.935Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-01-10T19:28:29.935Z] GC before operation: completed in 86.219 ms, heap usage 324.747 MB -> 67.270 MB. [2025-01-10T19:28:30.885Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1393.192 ms) ====== [2025-01-10T19:28:30.885Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-01-10T19:28:31.836Z] GC before operation: completed in 93.129 ms, heap usage 322.841 MB -> 78.542 MB. [2025-01-10T19:28:32.786Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1364.604 ms) ====== [2025-01-10T19:28:32.786Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-01-10T19:28:32.786Z] GC before operation: completed in 80.393 ms, heap usage 197.957 MB -> 67.723 MB. [2025-01-10T19:28:34.739Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1359.749 ms) ====== [2025-01-10T19:28:34.739Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-01-10T19:28:34.739Z] GC before operation: completed in 83.129 ms, heap usage 319.056 MB -> 67.455 MB. [2025-01-10T19:28:35.691Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1374.296 ms) ====== [2025-01-10T19:28:35.691Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-01-10T19:28:35.691Z] GC before operation: completed in 78.449 ms, heap usage 322.913 MB -> 67.725 MB. [2025-01-10T19:28:37.647Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1405.054 ms) ====== [2025-01-10T19:28:37.647Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-01-10T19:28:37.647Z] GC before operation: completed in 86.757 ms, heap usage 300.901 MB -> 67.815 MB. [2025-01-10T19:28:38.598Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1374.685 ms) ====== [2025-01-10T19:28:38.598Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-01-10T19:28:38.598Z] GC before operation: completed in 83.920 ms, heap usage 302.410 MB -> 73.569 MB. [2025-01-10T19:28:40.550Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1360.312 ms) ====== [2025-01-10T19:28:40.550Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-01-10T19:28:40.550Z] GC before operation: completed in 91.384 ms, heap usage 216.761 MB -> 79.254 MB. [2025-01-10T19:28:41.501Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1384.602 ms) ====== [2025-01-10T19:28:41.501Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-01-10T19:28:41.501Z] GC before operation: completed in 84.642 ms, heap usage 148.997 MB -> 68.101 MB. [2025-01-10T19:28:43.459Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1361.441 ms) ====== [2025-01-10T19:28:43.459Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-01-10T19:28:43.459Z] GC before operation: completed in 92.759 ms, heap usage 264.006 MB -> 84.970 MB. [2025-01-10T19:28:43.459Z] 25/01/10 19:28:42 WARN BlockManager: Asked to remove block broadcast_302, which does not exist [2025-01-10T19:28:43.459Z] 25/01/10 19:28:42 WARN BlockManager: Asked to remove block broadcast_302_piece0, which does not exist [2025-01-10T19:28:44.412Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1363.511 ms) ====== [2025-01-10T19:28:44.412Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-01-10T19:28:44.412Z] GC before operation: completed in 100.830 ms, heap usage 417.089 MB -> 79.304 MB. [2025-01-10T19:28:46.363Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1328.017 ms) ====== [2025-01-10T19:28:46.363Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-01-10T19:28:46.363Z] GC before operation: completed in 93.629 ms, heap usage 230.054 MB -> 73.899 MB. [2025-01-10T19:28:46.363Z] 25/01/10 19:28:45 WARN BlockManager: Asked to remove block broadcast_330_piece0, which does not exist [2025-01-10T19:28:47.314Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1363.554 ms) ====== [2025-01-10T19:28:47.314Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-01-10T19:28:47.314Z] GC before operation: completed in 92.824 ms, heap usage 228.224 MB -> 79.214 MB. [2025-01-10T19:28:49.267Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1363.605 ms) ====== [2025-01-10T19:28:49.267Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-01-10T19:28:49.267Z] GC before operation: completed in 86.155 ms, heap usage 132.878 MB -> 67.875 MB. [2025-01-10T19:28:50.221Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1354.733 ms) ====== [2025-01-10T19:28:50.221Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-01-10T19:28:50.221Z] GC before operation: completed in 90.676 ms, heap usage 150.852 MB -> 68.290 MB. [2025-01-10T19:28:52.173Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1367.018 ms) ====== [2025-01-10T19:28:52.173Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-01-10T19:28:52.173Z] GC before operation: completed in 97.412 ms, heap usage 232.129 MB -> 79.744 MB. [2025-01-10T19:28:53.124Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1344.278 ms) ====== [2025-01-10T19:28:53.124Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-01-10T19:28:53.124Z] GC before operation: completed in 94.871 ms, heap usage 156.644 MB -> 68.685 MB. [2025-01-10T19:28:55.074Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1347.977 ms) ====== [2025-01-10T19:28:55.074Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-01-10T19:28:55.074Z] GC before operation: completed in 88.107 ms, heap usage 238.497 MB -> 68.894 MB. [2025-01-10T19:28:56.029Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1350.625 ms) ====== [2025-01-10T19:28:56.029Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-01-10T19:28:56.029Z] GC before operation: completed in 90.329 ms, heap usage 301.426 MB -> 80.321 MB. [2025-01-10T19:28:57.983Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1353.148 ms) ====== [2025-01-10T19:28:57.983Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-01-10T19:28:57.983Z] GC before operation: completed in 89.086 ms, heap usage 160.323 MB -> 68.956 MB. [2025-01-10T19:28:58.936Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1343.930 ms) ====== [2025-01-10T19:28:58.936Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-01-10T19:28:58.936Z] GC before operation: completed in 87.161 ms, heap usage 225.877 MB -> 69.171 MB. [2025-01-10T19:28:59.889Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1325.740 ms) ====== [2025-01-10T19:28:59.889Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-01-10T19:29:00.841Z] GC before operation: completed in 89.642 ms, heap usage 292.694 MB -> 69.437 MB. [2025-01-10T19:29:01.792Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1319.022 ms) ====== [2025-01-10T19:29:01.792Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-01-10T19:29:01.792Z] GC before operation: completed in 0.442 ms, heap usage 296.533 MB -> 296.533 MB. [2025-01-10T19:29:02.744Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1278.018 ms) ====== [2025-01-10T19:29:02.744Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-01-10T19:29:02.744Z] GC before operation: completed in 96.572 ms, heap usage 248.953 MB -> 86.233 MB. [2025-01-10T19:29:04.700Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1316.128 ms) ====== [2025-01-10T19:29:04.701Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-01-10T19:29:04.701Z] GC before operation: completed in 90.892 ms, heap usage 274.528 MB -> 69.618 MB. [2025-01-10T19:29:05.756Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1307.341 ms) ====== [2025-01-10T19:29:05.756Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-01-10T19:29:05.756Z] GC before operation: completed in 89.873 ms, heap usage 289.742 MB -> 69.883 MB. [2025-01-10T19:29:07.711Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1327.967 ms) ====== [2025-01-10T19:29:07.711Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-01-10T19:29:07.711Z] GC before operation: completed in 90.825 ms, heap usage 264.407 MB -> 69.857 MB. [2025-01-10T19:29:08.663Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1318.027 ms) ====== [2025-01-10T19:29:08.663Z] ----------------------------------- [2025-01-10T19:29:08.663Z] renaissance-dec-tree_0_PASSED [2025-01-10T19:29:08.663Z] ----------------------------------- [2025-01-10T19:29:08.663Z] [2025-01-10T19:29:08.663Z] TEST TEARDOWN: [2025-01-10T19:29:08.663Z] Nothing to be done for teardown. [2025-01-10T19:29:08.663Z] renaissance-dec-tree_0 Finish Time: Fri Jan 10 19:29:08 2025 Epoch Time (ms): 1736537348336