Display playbook execution time

Following up on a discussion during a call with @rmarshall to optionally display task execution times from the playbook for debugging/analysis.

References

• https://docs.ansible.com/ansible/latest/reference_appendices/config.html#default-callback-whitelist
• http://techadminblog.com/get-execution-time-of-each-task-ansible

Testing

./run-orchestration -t

Specific tasks times

Format: <Task start time stamp> (<time elapsed by previous task>) (<current elapsed execution time for playbook>)

PLAY [app:&primary_geo_site] ************************************************************************************************

TASK [Gathering Facts] ******************************************************************************************************
Friday 10 July 2020  16:22:07 +0000 (0:00:00.064)       0:11:33.784 ***********
ok: [mnielsen-gitlab-application-1]

TASK [generate secondary map fact] ******************************************************************************************
Friday 10 July 2020  16:22:09 +0000 (0:00:01.894)       0:11:35.679 ***********

TASK [create secondary looping fact] ****************************************************************************************
Friday 10 July 2020  16:22:09 +0000 (0:00:00.027)       0:11:35.706 ***********
skipping: [mnielsen-gitlab-application-1]

TASK [join secondary clusters to primary cluster] ***************************************************************************
Friday 10 July 2020  16:22:09 +0000 (0:00:00.027)       0:11:35.734 ***********
skipping: [mnielsen-gitlab-application-1]

Play recap summary times

PLAY RECAP ******************************************************************************************************************
mnielsen-gitlab-application-1 : ok=34   changed=16   unreachable=0    failed=0    skipped=22   rescued=0    ignored=0
mnielsen-gitlab-consul-1   : ok=27   changed=11   unreachable=0    failed=0    skipped=18   rescued=0    ignored=0
mnielsen-gitlab-consul-2   : ok=27   changed=11   unreachable=0    failed=0    skipped=18   rescued=0    ignored=0
mnielsen-gitlab-consul-3   : ok=27   changed=11   unreachable=0    failed=0    skipped=18   rescued=0    ignored=0
mnielsen-gitlab-database-1 : ok=33   changed=12   unreachable=0    failed=0    skipped=17   rescued=0    ignored=0
mnielsen-gitlab-database-2 : ok=34   changed=13   unreachable=0    failed=0    skipped=16   rescued=0    ignored=0
mnielsen-gitlab-database-3 : ok=34   changed=13   unreachable=0    failed=0    skipped=16   rescued=0    ignored=0

Friday 10 July 2020  16:22:09 +0000 (0:00:00.021)       0:11:35.755 ***********
===============================================================================
packages : Install the package ------------------------------------------------------------------------------------- 170.02s
common : reconfigure gitlab ----------------------------------------------------------------------------------------- 77.19s
configure : apply migrations ---------------------------------------------------------------------------------------- 51.79s
configure : Register with the expected master ----------------------------------------------------------------------- 50.03s
common : reconfigure gitlab ----------------------------------------------------------------------------------------- 45.74s
packages : Add the package cloud apt repository --------------------------------------------------------------------- 25.00s
common : reconfigure gitlab ----------------------------------------------------------------------------------------- 24.37s
packages : Remove sshguard package ---------------------------------------------------------------------------------- 11.93s
common : restart consul ---------------------------------------------------------------------------------------------- 8.11s
Gathering Facts ------------------------------------------------------------------------------------------------------ 5.39s
Gathering Facts ------------------------------------------------------------------------------------------------------ 5.17s
packages : Fetch the package cloud script ---------------------------------------------------------------------------- 4.39s
common : start gitlab services --------------------------------------------------------------------------------------- 3.23s
Gathering Facts ------------------------------------------------------------------------------------------------------ 3.13s
common : start gitlab services --------------------------------------------------------------------------------------- 2.71s
configure : Create .pgpass file -------------------------------------------------------------------------------------- 2.71s
Gathering Facts ------------------------------------------------------------------------------------------------------ 2.52s
common : Create GitLab Configuration Fragments Directory ------------------------------------------------------------- 2.38s
common : Create common partial configuration ------------------------------------------------------------------------- 2.35s
common : Create common partial configuration ------------------------------------------------------------------------- 2.31s