[2024-08-22T01:15:38.494Z] Running test renaissance-dec-tree_0 ... [2024-08-22T01:15:38.495Z] =============================================== [2024-08-22T01:15:38.495Z] renaissance-dec-tree_0 Start Time: Wed Aug 21 20:15:37 2024 Epoch Time (ms): 1724289337931 [2024-08-22T01:15:38.495Z] variation: NoOptions [2024-08-22T01:15:38.495Z] JVM_OPTIONS: [2024-08-22T01:15:38.495Z] { \ [2024-08-22T01:15:38.495Z] echo ""; echo "TEST SETUP:"; \ [2024-08-22T01:15:38.495Z] echo "Nothing to be done for setup."; \ [2024-08-22T01:15:38.495Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17242890937648/renaissance-dec-tree_0"; \ [2024-08-22T01:15:38.495Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17242890937648/renaissance-dec-tree_0"; \ [2024-08-22T01:15:38.495Z] echo ""; echo "TESTING:"; \ [2024-08-22T01:15:38.495Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-17.0.13+4/bin/..//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_17242890937648/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-22T01:15:38.495Z] 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_17242890937648/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-22T01:15:38.495Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-22T01:15:38.495Z] echo "Nothing to be done for teardown."; \ [2024-08-22T01:15:38.495Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17242890937648/TestTargetResult"; [2024-08-22T01:15:38.495Z] [2024-08-22T01:15:38.495Z] TEST SETUP: [2024-08-22T01:15:38.495Z] Nothing to be done for setup. [2024-08-22T01:15:38.495Z] [2024-08-22T01:15:38.495Z] TESTING: [2024-08-22T01:15:40.668Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-22T01:15:42.838Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2024-08-22T01:15:45.860Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-22T01:15:45.860Z] GC before operation: completed in 88.571 ms, heap usage 49.600 MB -> 35.590 MB. [2024-08-22T01:15:53.380Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7180.765 ms) ====== [2024-08-22T01:15:53.380Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-22T01:15:53.380Z] GC before operation: completed in 78.649 ms, heap usage 257.930 MB -> 65.097 MB. [2024-08-22T01:15:54.782Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (1963.642 ms) ====== [2024-08-22T01:15:54.782Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-22T01:15:55.456Z] GC before operation: completed in 75.592 ms, heap usage 163.148 MB -> 76.202 MB. [2024-08-22T01:15:56.848Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1929.070 ms) ====== [2024-08-22T01:15:56.848Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-22T01:15:56.848Z] GC before operation: completed in 95.279 ms, heap usage 329.575 MB -> 82.231 MB. [2024-08-22T01:15:59.021Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1702.827 ms) ====== [2024-08-22T01:15:59.021Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-22T01:15:59.021Z] GC before operation: completed in 82.532 ms, heap usage 389.673 MB -> 77.079 MB. [2024-08-22T01:16:00.422Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1470.944 ms) ====== [2024-08-22T01:16:00.422Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-22T01:16:00.422Z] GC before operation: completed in 78.625 ms, heap usage 192.387 MB -> 88.226 MB. [2024-08-22T01:16:01.816Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1530.974 ms) ====== [2024-08-22T01:16:01.817Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-22T01:16:01.817Z] GC before operation: completed in 82.736 ms, heap usage 334.991 MB -> 83.136 MB. [2024-08-22T01:16:03.205Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1382.402 ms) ====== [2024-08-22T01:16:03.205Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-22T01:16:03.875Z] GC before operation: completed in 94.416 ms, heap usage 354.232 MB -> 77.893 MB. [2024-08-22T01:16:05.266Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1410.250 ms) ====== [2024-08-22T01:16:05.266Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-22T01:16:05.266Z] GC before operation: completed in 109.876 ms, heap usage 414.932 MB -> 88.705 MB. [2024-08-22T01:16:05.266Z] 24/08/21 20:16:04 WARN BlockManager: Asked to remove block broadcast_106, which does not exist [2024-08-22T01:16:06.663Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1549.387 ms) ====== [2024-08-22T01:16:06.663Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-22T01:16:06.663Z] GC before operation: completed in 93.520 ms, heap usage 284.034 MB -> 77.683 MB. [2024-08-22T01:16:08.064Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1494.724 ms) ====== [2024-08-22T01:16:08.064Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-22T01:16:08.064Z] GC before operation: completed in 93.486 ms, heap usage 120.422 MB -> 77.813 MB. [2024-08-22T01:16:10.244Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1633.658 ms) ====== [2024-08-22T01:16:10.244Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-22T01:16:10.244Z] GC before operation: completed in 97.397 ms, heap usage 291.059 MB -> 77.910 MB. [2024-08-22T01:16:11.640Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1375.984 ms) ====== [2024-08-22T01:16:11.640Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-22T01:16:11.640Z] GC before operation: completed in 88.836 ms, heap usage 143.674 MB -> 78.003 MB. [2024-08-22T01:16:13.035Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1691.264 ms) ====== [2024-08-22T01:16:13.035Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-22T01:16:13.035Z] GC before operation: completed in 118.811 ms, heap usage 353.507 MB -> 94.854 MB. [2024-08-22T01:16:15.216Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1510.186 ms) ====== [2024-08-22T01:16:15.216Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-22T01:16:15.216Z] GC before operation: completed in 95.543 ms, heap usage 252.700 MB -> 78.651 MB. [2024-08-22T01:16:16.564Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1259.435 ms) ====== [2024-08-22T01:16:16.565Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-22T01:16:16.565Z] GC before operation: completed in 88.732 ms, heap usage 289.900 MB -> 95.344 MB. [2024-08-22T01:16:17.954Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1298.052 ms) ====== [2024-08-22T01:16:17.954Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-22T01:16:17.954Z] GC before operation: completed in 94.906 ms, heap usage 258.271 MB -> 67.850 MB. [2024-08-22T01:16:19.350Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1309.057 ms) ====== [2024-08-22T01:16:19.350Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-22T01:16:19.350Z] GC before operation: completed in 95.789 ms, heap usage 243.150 MB -> 67.834 MB. [2024-08-22T01:16:20.762Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1491.517 ms) ====== [2024-08-22T01:16:20.762Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-22T01:16:20.762Z] GC before operation: completed in 111.648 ms, heap usage 239.765 MB -> 95.765 MB. [2024-08-22T01:16:22.153Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1440.105 ms) ====== [2024-08-22T01:16:22.153Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-22T01:16:22.153Z] GC before operation: completed in 97.800 ms, heap usage 268.743 MB -> 68.198 MB. [2024-08-22T01:16:23.546Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1431.416 ms) ====== [2024-08-22T01:16:23.546Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-22T01:16:23.546Z] GC before operation: completed in 103.401 ms, heap usage 274.859 MB -> 95.834 MB. [2024-08-22T01:16:23.546Z] 24/08/21 20:16:23 WARN BlockManager: Asked to remove block broadcast_274, which does not exist [2024-08-22T01:16:24.936Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1280.006 ms) ====== [2024-08-22T01:16:24.936Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-22T01:16:24.936Z] GC before operation: completed in 116.491 ms, heap usage 278.931 MB -> 95.918 MB. [2024-08-22T01:16:27.118Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1488.022 ms) ====== [2024-08-22T01:16:27.118Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-22T01:16:27.118Z] GC before operation: completed in 101.942 ms, heap usage 268.584 MB -> 96.180 MB. [2024-08-22T01:16:28.510Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1478.680 ms) ====== [2024-08-22T01:16:28.510Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-22T01:16:28.510Z] GC before operation: completed in 108.003 ms, heap usage 280.937 MB -> 79.486 MB. [2024-08-22T01:16:29.902Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1573.333 ms) ====== [2024-08-22T01:16:29.902Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-22T01:16:29.902Z] GC before operation: completed in 124.751 ms, heap usage 443.414 MB -> 85.456 MB. [2024-08-22T01:16:31.292Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1346.779 ms) ====== [2024-08-22T01:16:31.292Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-22T01:16:31.292Z] GC before operation: completed in 113.090 ms, heap usage 335.313 MB -> 85.394 MB. [2024-08-22T01:16:32.687Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1308.024 ms) ====== [2024-08-22T01:16:32.687Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-22T01:16:32.687Z] GC before operation: completed in 105.012 ms, heap usage 322.147 MB -> 68.897 MB. [2024-08-22T01:16:34.078Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1333.649 ms) ====== [2024-08-22T01:16:34.078Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-22T01:16:34.078Z] GC before operation: completed in 108.317 ms, heap usage 231.319 MB -> 85.583 MB. [2024-08-22T01:16:35.588Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1400.547 ms) ====== [2024-08-22T01:16:35.588Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-22T01:16:35.588Z] GC before operation: completed in 126.324 ms, heap usage 364.925 MB -> 96.586 MB. [2024-08-22T01:16:37.840Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1445.639 ms) ====== [2024-08-22T01:16:37.840Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-22T01:16:37.840Z] GC before operation: completed in 116.910 ms, heap usage 232.659 MB -> 91.254 MB. [2024-08-22T01:16:39.233Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1525.462 ms) ====== [2024-08-22T01:16:39.233Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-22T01:16:39.233Z] GC before operation: completed in 111.433 ms, heap usage 299.208 MB -> 96.804 MB. [2024-08-22T01:16:39.233Z] 24/08/21 20:16:38 WARN BlockManager: Asked to remove block rdd_1467_1, which does not exist [2024-08-22T01:16:40.620Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1454.519 ms) ====== [2024-08-22T01:16:40.620Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-22T01:16:40.620Z] GC before operation: completed in 114.770 ms, heap usage 236.719 MB -> 91.509 MB. [2024-08-22T01:16:42.029Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1415.303 ms) ====== [2024-08-22T01:16:42.029Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-22T01:16:42.029Z] GC before operation: completed in 107.912 ms, heap usage 318.882 MB -> 91.394 MB. [2024-08-22T01:16:43.418Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1340.834 ms) ====== [2024-08-22T01:16:43.418Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-22T01:16:43.418Z] GC before operation: completed in 108.528 ms, heap usage 294.403 MB -> 80.536 MB. [2024-08-22T01:16:44.814Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1310.041 ms) ====== [2024-08-22T01:16:44.814Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-22T01:16:44.814Z] GC before operation: completed in 115.810 ms, heap usage 407.899 MB -> 86.299 MB. [2024-08-22T01:16:46.208Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1260.400 ms) ====== [2024-08-22T01:16:46.208Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-22T01:16:46.208Z] GC before operation: completed in 131.149 ms, heap usage 317.211 MB -> 91.809 MB. [2024-08-22T01:16:47.606Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1237.274 ms) ====== [2024-08-22T01:16:47.606Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-22T01:16:47.606Z] GC before operation: completed in 103.891 ms, heap usage 264.469 MB -> 86.249 MB. [2024-08-22T01:16:49.784Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1542.399 ms) ====== [2024-08-22T01:16:49.784Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-22T01:16:49.784Z] GC before operation: completed in 136.774 ms, heap usage 352.179 MB -> 97.425 MB. [2024-08-22T01:16:49.784Z] 24/08/21 20:16:49 WARN BlockManager: Asked to remove block broadcast_512_piece0, which does not exist [2024-08-22T01:16:51.201Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1347.854 ms) ====== [2024-08-22T01:16:51.201Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-22T01:16:51.201Z] GC before operation: completed in 122.849 ms, heap usage 227.616 MB -> 75.525 MB. [2024-08-22T01:16:52.590Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1542.756 ms) ====== [2024-08-22T01:16:52.590Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-22T01:16:52.590Z] GC before operation: completed in 117.550 ms, heap usage 441.469 MB -> 97.841 MB. [2024-08-22T01:16:53.983Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1535.938 ms) ====== [2024-08-22T01:16:54.655Z] ----------------------------------- [2024-08-22T01:16:54.655Z] renaissance-dec-tree_0_PASSED [2024-08-22T01:16:54.655Z] ----------------------------------- [2024-08-22T01:16:54.655Z] [2024-08-22T01:16:54.655Z] TEST TEARDOWN: [2024-08-22T01:16:54.655Z] Nothing to be done for teardown. [2024-08-22T01:16:54.655Z] renaissance-dec-tree_0 Finish Time: Wed Aug 21 20:16:54 2024 Epoch Time (ms): 1724289414286