[2025-02-12T21:48:09.504Z] Running test renaissance-dec-tree_0 ... [2025-02-12T21:48:09.504Z] =============================================== [2025-02-12T21:48:09.504Z] renaissance-dec-tree_0 Start Time: Wed Feb 12 15:48:09 2025 Epoch Time (ms): 1739396889037 [2025-02-12T21:48:09.504Z] variation: NoOptions [2025-02-12T21:48:09.504Z] JVM_OPTIONS: [2025-02-12T21:48:09.504Z] { \ [2025-02-12T21:48:09.504Z] echo ""; echo "TEST SETUP:"; \ [2025-02-12T21:48:09.504Z] echo "Nothing to be done for setup."; \ [2025-02-12T21:48:09.504Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17393966366363/renaissance-dec-tree_0"; \ [2025-02-12T21:48:09.504Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17393966366363/renaissance-dec-tree_0"; \ [2025-02-12T21:48:09.504Z] echo ""; echo "TESTING:"; \ [2025-02-12T21:48:09.504Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17393966366363/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-12T21:48:09.504Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17393966366363/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-12T21:48:09.504Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-12T21:48:09.504Z] echo "Nothing to be done for teardown."; \ [2025-02-12T21:48:09.504Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17393966366363/TestTargetResult"; [2025-02-12T21:48:09.504Z] [2025-02-12T21:48:09.504Z] TEST SETUP: [2025-02-12T21:48:09.504Z] Nothing to be done for setup. [2025-02-12T21:48:09.504Z] [2025-02-12T21:48:09.504Z] TESTING: [2025-02-12T21:48:11.942Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-12T21:48:13.510Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2025-02-12T21:48:15.950Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-12T21:48:15.950Z] GC before operation: completed in 51.437 ms, heap usage 100.707 MB -> 35.766 MB. [2025-02-12T21:48:22.793Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6736.309 ms) ====== [2025-02-12T21:48:22.793Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-12T21:48:22.793Z] GC before operation: completed in 91.343 ms, heap usage 288.851 MB -> 65.231 MB. [2025-02-12T21:48:25.318Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1896.895 ms) ====== [2025-02-12T21:48:25.318Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-12T21:48:25.318Z] GC before operation: completed in 86.728 ms, heap usage 278.883 MB -> 65.606 MB. [2025-02-12T21:48:26.891Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1531.290 ms) ====== [2025-02-12T21:48:26.891Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-12T21:48:26.891Z] GC before operation: completed in 86.200 ms, heap usage 307.759 MB -> 77.175 MB. [2025-02-12T21:48:27.648Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1291.544 ms) ====== [2025-02-12T21:48:27.648Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-12T21:48:28.408Z] GC before operation: completed in 74.259 ms, heap usage 115.155 MB -> 83.076 MB. [2025-02-12T21:48:29.981Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1496.995 ms) ====== [2025-02-12T21:48:29.981Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-12T21:48:29.981Z] GC before operation: completed in 80.201 ms, heap usage 243.808 MB -> 83.122 MB. [2025-02-12T21:48:31.546Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1473.849 ms) ====== [2025-02-12T21:48:31.546Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-12T21:48:31.546Z] GC before operation: completed in 78.845 ms, heap usage 422.769 MB -> 83.317 MB. [2025-02-12T21:48:32.306Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1245.674 ms) ====== [2025-02-12T21:48:32.306Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-12T21:48:32.306Z] GC before operation: completed in 82.771 ms, heap usage 385.720 MB -> 66.930 MB. [2025-02-12T21:48:33.872Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1385.615 ms) ====== [2025-02-12T21:48:33.872Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-12T21:48:33.872Z] GC before operation: completed in 87.419 ms, heap usage 260.184 MB -> 66.933 MB. [2025-02-12T21:48:35.447Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1233.989 ms) ====== [2025-02-12T21:48:35.447Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-12T21:48:35.447Z] GC before operation: completed in 120.753 ms, heap usage 262.785 MB -> 83.855 MB. [2025-02-12T21:48:37.015Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1307.330 ms) ====== [2025-02-12T21:48:37.015Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-12T21:48:37.016Z] GC before operation: completed in 85.476 ms, heap usage 378.939 MB -> 83.906 MB. [2025-02-12T21:48:37.773Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1370.165 ms) ====== [2025-02-12T21:48:37.773Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-12T21:48:38.559Z] GC before operation: completed in 92.201 ms, heap usage 366.020 MB -> 84.089 MB. [2025-02-12T21:48:39.316Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1203.836 ms) ====== [2025-02-12T21:48:39.316Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-12T21:48:39.316Z] GC before operation: completed in 80.553 ms, heap usage 334.251 MB -> 83.998 MB. [2025-02-12T21:48:40.880Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1254.150 ms) ====== [2025-02-12T21:48:40.880Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-12T21:48:40.880Z] GC before operation: completed in 80.016 ms, heap usage 351.358 MB -> 73.169 MB. [2025-02-12T21:48:42.442Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1412.186 ms) ====== [2025-02-12T21:48:42.442Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-12T21:48:42.442Z] GC before operation: completed in 93.037 ms, heap usage 165.065 MB -> 78.474 MB. [2025-02-12T21:48:44.010Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1375.104 ms) ====== [2025-02-12T21:48:44.010Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-12T21:48:44.010Z] GC before operation: completed in 92.659 ms, heap usage 401.599 MB -> 95.365 MB. [2025-02-12T21:48:45.575Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1342.428 ms) ====== [2025-02-12T21:48:45.575Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-12T21:48:45.575Z] GC before operation: completed in 91.120 ms, heap usage 243.390 MB -> 68.094 MB. [2025-02-12T21:48:46.335Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1271.181 ms) ====== [2025-02-12T21:48:46.335Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-12T21:48:46.335Z] GC before operation: completed in 97.492 ms, heap usage 270.067 MB -> 68.063 MB. [2025-02-12T21:48:47.899Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1307.728 ms) ====== [2025-02-12T21:48:47.899Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-12T21:48:47.899Z] GC before operation: completed in 84.766 ms, heap usage 264.619 MB -> 84.886 MB. [2025-02-12T21:48:49.464Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1414.315 ms) ====== [2025-02-12T21:48:49.464Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-12T21:48:49.464Z] GC before operation: completed in 80.279 ms, heap usage 357.480 MB -> 68.427 MB. [2025-02-12T21:48:51.028Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1268.371 ms) ====== [2025-02-12T21:48:51.028Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-12T21:48:51.028Z] GC before operation: completed in 91.621 ms, heap usage 226.300 MB -> 68.505 MB. [2025-02-12T21:48:51.790Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1180.132 ms) ====== [2025-02-12T21:48:51.790Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-12T21:48:51.790Z] GC before operation: completed in 85.331 ms, heap usage 263.832 MB -> 85.216 MB. [2025-02-12T21:48:53.363Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1299.415 ms) ====== [2025-02-12T21:48:53.363Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-12T21:48:53.363Z] GC before operation: completed in 100.087 ms, heap usage 360.503 MB -> 68.901 MB. [2025-02-12T21:48:54.936Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1559.564 ms) ====== [2025-02-12T21:48:54.936Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-12T21:48:54.936Z] GC before operation: completed in 92.883 ms, heap usage 242.306 MB -> 79.908 MB. [2025-02-12T21:48:56.503Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1618.983 ms) ====== [2025-02-12T21:48:56.503Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-12T21:48:57.397Z] GC before operation: completed in 102.598 ms, heap usage 426.206 MB -> 85.247 MB. [2025-02-12T21:48:58.159Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1262.009 ms) ====== [2025-02-12T21:48:58.159Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-12T21:48:58.159Z] GC before operation: completed in 95.787 ms, heap usage 341.899 MB -> 85.506 MB. [2025-02-12T21:48:59.822Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1549.949 ms) ====== [2025-02-12T21:48:59.822Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-12T21:48:59.822Z] GC before operation: completed in 99.696 ms, heap usage 318.027 MB -> 74.548 MB. [2025-02-12T21:49:01.384Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1177.986 ms) ====== [2025-02-12T21:49:01.384Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-12T21:49:01.384Z] GC before operation: completed in 93.610 ms, heap usage 155.791 MB -> 79.822 MB. [2025-02-12T21:49:02.947Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1335.676 ms) ====== [2025-02-12T21:49:02.947Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-12T21:49:02.947Z] GC before operation: completed in 102.787 ms, heap usage 345.138 MB -> 85.887 MB. [2025-02-12T21:49:03.716Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1224.961 ms) ====== [2025-02-12T21:49:03.716Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-12T21:49:03.716Z] GC before operation: completed in 116.580 ms, heap usage 340.218 MB -> 91.287 MB. [2025-02-12T21:49:05.408Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1364.042 ms) ====== [2025-02-12T21:49:05.408Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-12T21:49:05.408Z] GC before operation: completed in 99.267 ms, heap usage 264.368 MB -> 80.689 MB. [2025-02-12T21:49:06.994Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1189.665 ms) ====== [2025-02-12T21:49:06.994Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-12T21:49:06.994Z] GC before operation: completed in 108.964 ms, heap usage 435.764 MB -> 91.915 MB. [2025-02-12T21:49:07.750Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1364.013 ms) ====== [2025-02-12T21:49:07.750Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-12T21:49:08.507Z] GC before operation: completed in 111.188 ms, heap usage 282.416 MB -> 91.920 MB. [2025-02-12T21:49:08.507Z] 25/02/12 15:49:08 WARN BlockManager: Asked to remove block rdd_1565_1, which does not exist [2025-02-12T21:49:09.263Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1195.367 ms) ====== [2025-02-12T21:49:09.263Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-12T21:49:09.263Z] GC before operation: completed in 97.772 ms, heap usage 293.362 MB -> 80.841 MB. [2025-02-12T21:49:10.829Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1371.551 ms) ====== [2025-02-12T21:49:10.829Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-12T21:49:10.829Z] GC before operation: completed in 101.976 ms, heap usage 410.605 MB -> 75.467 MB. [2025-02-12T21:49:12.410Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1154.681 ms) ====== [2025-02-12T21:49:12.410Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-12T21:49:12.410Z] GC before operation: completed in 98.733 ms, heap usage 272.345 MB -> 80.986 MB. [2025-02-12T21:49:13.973Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1403.186 ms) ====== [2025-02-12T21:49:13.973Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-12T21:49:13.973Z] GC before operation: completed in 107.953 ms, heap usage 438.145 MB -> 86.742 MB. [2025-02-12T21:49:15.539Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1638.788 ms) ====== [2025-02-12T21:49:15.539Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-12T21:49:15.539Z] GC before operation: completed in 107.905 ms, heap usage 319.856 MB -> 97.788 MB. [2025-02-12T21:49:17.104Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1325.615 ms) ====== [2025-02-12T21:49:17.104Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-12T21:49:17.104Z] GC before operation: completed in 90.474 ms, heap usage 258.346 MB -> 70.230 MB. [2025-02-12T21:49:17.861Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1134.759 ms) ====== [2025-02-12T21:49:17.861Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-12T21:49:17.861Z] GC before operation: completed in 103.219 ms, heap usage 344.796 MB -> 97.950 MB. [2025-02-12T21:49:17.861Z] 25/02/12 15:49:17 WARN BlockManager: Asked to remove block broadcast_540, which does not exist [2025-02-12T21:49:19.426Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1383.586 ms) ====== [2025-02-12T21:49:20.187Z] ----------------------------------- [2025-02-12T21:49:20.187Z] renaissance-dec-tree_0_PASSED [2025-02-12T21:49:20.187Z] ----------------------------------- [2025-02-12T21:49:20.187Z] [2025-02-12T21:49:20.187Z] TEST TEARDOWN: [2025-02-12T21:49:20.187Z] Nothing to be done for teardown. [2025-02-12T21:49:20.187Z] renaissance-dec-tree_0 Finish Time: Wed Feb 12 15:49:19 2025 Epoch Time (ms): 1739396959832