change: print build execution time

buildspec.yml

@@ -10,29 +10,51 @@ phases:
       # run linters
       - TOX_PARALLEL_NO_SPINNER=1
       - PY_COLORS=0
+      - start_time=`date +%s`
       - tox -e flake8,pylint,twine,black-check --parallel all
+      - ./ci-scripts/displaytime.sh 'flake8,pylint,twine,black-check' $start_time
+
+      - start_time=`date +%s`
       - tox -e sphinx
+      - ./ci-scripts/displaytime.sh 'sphinx' $start_time
 
       # run unit tests
+      - start_time=`date +%s`
       - AWS_ACCESS_KEY_ID= AWS_SECRET_ACCESS_KEY= AWS_SESSION_TOKEN=
         AWS_CONTAINER_CREDENTIALS_RELATIVE_URI= AWS_DEFAULT_REGION=
         tox -e py36,py27 --parallel all -- tests/unit
+      - ./ci-scripts/displaytime.sh 'py36,py27 unit' $start_time
 
       # local mode tests
       - |
         if has-matching-changes "tests/" "src/*.py" "setup.py" "setup.cfg" "buildspec.yml"; then
+          start_time=`date +%s`
           IGNORE_COVERAGE=- tox -e py36 -- tests/integ -m local_mode --durations 50
+          ./ci-scripts/displaytime.sh 'py36 local mode' $start_time
+
+          start_time=`date +%s`
           IGNORE_COVERAGE=- tox -e py27 -- tests/integ -m local_mode --durations 50
+          ./ci-scripts/displaytime.sh 'py27 local mode' $start_time
+
         else
           echo "skipping integration tests"
         fi
 
       # run integration tests
       - |
         if has-matching-changes "tests/" "src/*.py" "setup.py" "setup.cfg" "buildspec.yml"; then
+          start_time=`date +%s`
           python3 -u ci-scripts/queue_build.py
+          ./ci-scripts/displaytime.sh 'build queue' $start_time
+
+          start_time=`date +%s`
           IGNORE_COVERAGE=- tox -e py36 -- tests/integ -m "not local_mode" -n 48  --reruns 3 --reruns-delay 5 --durations 50
+          ./ci-scripts/displaytime.sh 'py36 tests/integ' $start_time
+
+          start_time=`date +%s`
           IGNORE_COVERAGE=- tox -e py27 -- tests/integ -m "not local_mode" -n 48  --reruns 3 --reruns-delay 5 --durations 50
+          ./ci-scripts/displaytime.sh 'py27 tests/integ' $start_time
+
         else
           echo "skipping integration tests"
         fi
@@ -41,7 +63,10 @@ phases:
       - |
         if has-matching-changes "src/*.py" "setup.py" "setup.cfg" "buildspec.yml"; then
           echo "running notebook test"
+          start_time=`date +%s`
           ./tests/scripts/run-notebook-test.sh
+          ./ci-scripts/displaytime.sh 'notebook test' $start_time
+
          else
            echo "skipping notebook test"
          fi

ci-scripts/displaytime.sh

@@ -0,0 +1,29 @@
+#!/usr/bin/env bash
+# Copyright 2019 Amazon.com, Inc. or its affiliates. All Rights Reserved.
+#
+# Licensed under the Apache License, Version 2.0 (the "License"). You
+# may not use this file except in compliance with the License. A copy of
+# the License is located at
+#
+#     http://aws.amazon.com/apache2.0/
+#
+# or in the "license" file accompanying this file. This file is
+# distributed on an "AS IS" BASIS, WITHOUT WARRANTIES OR CONDITIONS OF
+# ANY KIND, either express or implied. See the License for the specific
+# language governing permissions and limitations under the License.
+
+set -euo pipefail
+
+echo =================== $1 execution time ===================
+
+start_time=$2
+end_time=`date +%s`
+total_time=$(expr $end_time - $start_time)
+hours=$((total_time/60/60%24))
+minutes=$((total_time/60%60))
+secs=$((total_time%60))
+
+(( $hours > 0 )) && printf '%d hours ' $hours
+(( $minutes > 0 )) && printf '%d minutes ' $minutes
+(( $hours > 0 || $minutes > 0 )) && printf 'and '
+printf '%d seconds\n\n' $secs