Skip to content

Instantly share code, notes, and snippets.

@gamename

gamename/gist:c54095603bd79d49107fb6b2e84b8381

Created August 17, 2019 13:13
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save gamename/c54095603bd79d49107fb6b2e84b8381 to your computer and use it in GitHub Desktop.
Save gamename/c54095603bd79d49107fb6b2e84b8381 to your computer and use it in GitHub Desktop.
Download ZIP
Ansible Error Log
Raw
gistfile1.txt
2019-08-17 08:51:04,313 p=24794 u=automation | 24794 1566046264.31251: starting run
2019-08-17 08:51:04,552 p=24794 u=automation | 24794 1566046264.55196: Added group all to inventory
2019-08-17 08:51:04,553 p=24794 u=automation | 24794 1566046264.55309: Added group ungrouped to inventory
2019-08-17 08:51:04,553 p=24794 u=automation | 24794 1566046264.55333: Group all now contains ungrouped
2019-08-17 08:51:04,553 p=24794 u=automation | 24794 1566046264.55354: Examining possible inventory source: /home/automation/dtcc_ansible/dtcc_hosts
2019-08-17 08:51:04,555 p=24794 u=automation | 24794 1566046264.55569: trying /usr/lib/python2.7/site-packages/ansible/plugins/cache
2019-08-17 08:51:04,557 p=24794 u=automation | 24794 1566046264.55691: Loading CacheModule 'memory' from /usr/lib/python2.7/site-packages/ansible/plugins/cache/memory.py
2019-08-17 08:51:04,558 p=24794 u=automation | 24794 1566046264.55825: trying /usr/lib/python2.7/site-packages/ansible/plugins/inventory
2019-08-17 08:51:04,560 p=24794 u=automation | 24794 1566046264.56035: Loading InventoryModule 'host_list' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/host_list.py
2019-08-17 08:51:04,562 p=24794 u=automation | 24794 1566046264.56192: Loaded config def from plugin (inventory/script)
2019-08-17 08:51:04,562 p=24794 u=automation | 24794 1566046264.56217: Loading InventoryModule 'script' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/script.py
2019-08-17 08:51:04,562 p=24794 u=automation | 24794 1566046264.56280: Loading InventoryModule 'auto' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/auto.py
2019-08-17 08:51:04,564 p=24794 u=automation | 24794 1566046264.56400: Loaded config def from plugin (inventory/yaml)
2019-08-17 08:51:04,564 p=24794 u=automation | 24794 1566046264.56422: Loading InventoryModule 'yaml' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/yaml.py
2019-08-17 08:51:04,565 p=24794 u=automation | 24794 1566046264.56522: Loading InventoryModule 'ini' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/ini.py
2019-08-17 08:51:04,566 p=24794 u=automation | 24794 1566046264.56612: Loading InventoryModule 'toml' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/toml.py
2019-08-17 08:51:04,566 p=24794 u=automation | 24794 1566046264.56636: Attempting to use plugin host_list (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/host_list.py)
2019-08-17 08:51:04,566 p=24794 u=automation | 24794 1566046264.56658: Attempting to use plugin script (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/script.py)
2019-08-17 08:51:04,566 p=24794 u=automation | 24794 1566046264.56689: Attempting to use plugin auto (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/auto.py)
2019-08-17 08:51:04,567 p=24794 u=automation | 24794 1566046264.56709: Attempting to use plugin yaml (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/yaml.py)
2019-08-17 08:51:04,568 p=24794 u=automation | 24794 1566046264.56855: Loading data from /home/automation/dtcc_ansible/dtcc_hosts
2019-08-17 08:51:04,572 p=24794 u=automation | 24794 1566046264.57194: group all already in inventory
2019-08-17 08:51:04,572 p=24794 u=automation | 24794 1566046264.57218: Added group switches to inventory
2019-08-17 08:51:04,572 p=24794 u=automation | 24794 1566046264.57254: set inventory_file for n95ksw1
2019-08-17 08:51:04,572 p=24794 u=automation | 24794 1566046264.57278: set inventory_dir for n95ksw1
2019-08-17 08:51:04,573 p=24794 u=automation | 24794 1566046264.57298: Added host n95ksw1 to inventory
2019-08-17 08:51:04,573 p=24794 u=automation | 24794 1566046264.57320: Added host n95ksw1 to group switches
2019-08-17 08:51:04,573 p=24794 u=automation | 24794 1566046264.57338: set ansible_host for n95ksw1
2019-08-17 08:51:04,573 p=24794 u=automation | 24794 1566046264.57360: set inventory_file for n95ksw2
2019-08-17 08:51:04,573 p=24794 u=automation | 24794 1566046264.57384: set inventory_dir for n95ksw2
2019-08-17 08:51:04,574 p=24794 u=automation | 24794 1566046264.57407: Added host n95ksw2 to inventory
2019-08-17 08:51:04,574 p=24794 u=automation | 24794 1566046264.57428: Added host n95ksw2 to group switches
2019-08-17 08:51:04,574 p=24794 u=automation | 24794 1566046264.57445: set ansible_host for n95ksw2
2019-08-17 08:51:04,574 p=24794 u=automation | 24794 1566046264.57469: set inventory_file for n7ksw2
2019-08-17 08:51:04,574 p=24794 u=automation | 24794 1566046264.57492: set inventory_dir for n7ksw2
2019-08-17 08:51:04,575 p=24794 u=automation | 24794 1566046264.57509: Added host n7ksw2 to inventory
2019-08-17 08:51:04,575 p=24794 u=automation | 24794 1566046264.57528: Added host n7ksw2 to group switches
2019-08-17 08:51:04,575 p=24794 u=automation | 24794 1566046264.57545: set ansible_host for n7ksw2
2019-08-17 08:51:04,575 p=24794 u=automation | 24794 1566046264.57566: set inventory_file for n7ksw1
2019-08-17 08:51:04,575 p=24794 u=automation | 24794 1566046264.57589: set inventory_dir for n7ksw1
2019-08-17 08:51:04,576 p=24794 u=automation | 24794 1566046264.57606: Added host n7ksw1 to inventory
2019-08-17 08:51:04,576 p=24794 u=automation | 24794 1566046264.57625: Added host n7ksw1 to group switches
2019-08-17 08:51:04,576 p=24794 u=automation | 24794 1566046264.57643: set ansible_host for n7ksw1
2019-08-17 08:51:04,576 p=24794 u=automation | 24794 1566046264.57664: Group all now contains switches
2019-08-17 08:51:04,576 p=24794 u=automation | 24794 1566046264.57684: Added group routers to inventory
2019-08-17 08:51:04,577 p=24794 u=automation | 24794 1566046264.57707: set inventory_file for asr1k01
2019-08-17 08:51:04,577 p=24794 u=automation | 24794 1566046264.57728: set inventory_dir for asr1k01
2019-08-17 08:51:04,577 p=24794 u=automation | 24794 1566046264.57751: Added host asr1k01 to inventory
2019-08-17 08:51:04,577 p=24794 u=automation | 24794 1566046264.57771: Added host asr1k01 to group routers
2019-08-17 08:51:04,577 p=24794 u=automation | 24794 1566046264.57790: set ansible_host for asr1k01
2019-08-17 08:51:04,578 p=24794 u=automation | 24794 1566046264.57813: set inventory_file for isr4k02
2019-08-17 08:51:04,578 p=24794 u=automation | 24794 1566046264.57835: set inventory_dir for isr4k02
2019-08-17 08:51:04,578 p=24794 u=automation | 24794 1566046264.57852: Added host isr4k02 to inventory
2019-08-17 08:51:04,578 p=24794 u=automation | 24794 1566046264.57873: Added host isr4k02 to group routers
2019-08-17 08:51:04,578 p=24794 u=automation | 24794 1566046264.57891: set ansible_host for isr4k02
2019-08-17 08:51:04,579 p=24794 u=automation | 24794 1566046264.57910: set inventory_file for isr4k01
2019-08-17 08:51:04,579 p=24794 u=automation | 24794 1566046264.57931: set inventory_dir for isr4k01
2019-08-17 08:51:04,579 p=24794 u=automation | 24794 1566046264.57947: Added host isr4k01 to inventory
2019-08-17 08:51:04,579 p=24794 u=automation | 24794 1566046264.57966: Added host isr4k01 to group routers
2019-08-17 08:51:04,579 p=24794 u=automation | 24794 1566046264.57984: set ansible_host for isr4k01
2019-08-17 08:51:04,580 p=24794 u=automation | 24794 1566046264.58004: Group all now contains routers
2019-08-17 08:51:04,580 p=24794 u=automation | 24794 1566046264.58021: set ansible_password for all
2019-08-17 08:51:04,580 p=24794 u=automation | 24794 1566046264.58038: set ansible_connection for all
2019-08-17 08:51:04,580 p=24794 u=automation | 24794 1566046264.58054: set ansible_network_os for all
2019-08-17 08:51:04,580 p=24794 u=automation | 24794 1566046264.58070: set ansible_user for all
2019-08-17 08:51:04,580 p=24794 u=automation | 24794 1566046264.58089: Reconcile groups and hosts in inventory.
2019-08-17 08:51:04,581 p=24794 u=automation | 24794 1566046264.58125: Loading data from /home/automation/dtcc_ansible/test_vlans/dtcc/vlan_403.yml
2019-08-17 08:51:04,582 p=24794 u=automation | 24794 1566046264.58250: Loading CacheModule 'memory' from /usr/lib/python2.7/site-packages/ansible/plugins/cache/memory.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:04,612 p=24794 u=automation | 24794 1566046264.61251: Loaded config def from plugin (connection/buildah)
2019-08-17 08:51:04,613 p=24794 u=automation | 24794 1566046264.61297: Loading Connection 'buildah' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/buildah.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,614 p=24794 u=automation | 24794 1566046264.61487: Loaded config def from plugin (connection/chroot)
2019-08-17 08:51:04,615 p=24794 u=automation | 24794 1566046264.61511: Loading Connection 'chroot' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/chroot.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,620 p=24794 u=automation | 24794 1566046264.62010: Loaded config def from plugin (connection/docker)
2019-08-17 08:51:04,620 p=24794 u=automation | 24794 1566046264.62036: Loading Connection 'docker' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/docker.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,621 p=24794 u=automation | 24794 1566046264.62134: Loaded config def from plugin (connection/funcd)
2019-08-17 08:51:04,666 p=24794 u=automation | 24794 1566046264.66679: Loaded config def from plugin (connection/httpapi)
2019-08-17 08:51:04,667 p=24794 u=automation | 24794 1566046264.66716: Loading Connection 'httpapi' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/httpapi.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,668 p=24794 u=automation | 24794 1566046264.66846: Loaded config def from plugin (connection/iocage)
2019-08-17 08:51:04,668 p=24794 u=automation | 24794 1566046264.66871: Loading Connection 'iocage' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/iocage.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,669 p=24794 u=automation | 24794 1566046264.66979: Loaded config def from plugin (connection/jail)
2019-08-17 08:51:04,670 p=24794 u=automation | 24794 1566046264.67003: Loading Connection 'jail' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/jail.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,675 p=24794 u=automation | 24794 1566046264.67489: Loaded config def from plugin (connection/kubectl)
2019-08-17 08:51:04,675 p=24794 u=automation | 24794 1566046264.67517: Loading Connection 'kubectl' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/kubectl.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,676 p=24794 u=automation | 24794 1566046264.67610: Loaded config def from plugin (connection/libvirt_lxc)
2019-08-17 08:51:04,676 p=24794 u=automation | 24794 1566046264.67636: Loading Connection 'libvirt_lxc' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/libvirt_lxc.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,677 p=24794 u=automation | 24794 1566046264.67710: Loading Connection 'local' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/local.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,678 p=24794 u=automation | 24794 1566046264.67835: Loaded config def from plugin (connection/lxc)
2019-08-17 08:51:04,678 p=24794 u=automation | 24794 1566046264.67860: Loading Connection 'lxc' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/lxc.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,679 p=24794 u=automation | 24794 1566046264.67970: Loaded config def from plugin (connection/lxd)
2019-08-17 08:51:04,680 p=24794 u=automation | 24794 1566046264.67995: Loading Connection 'lxd' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/lxd.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,683 p=24794 u=automation | 24794 1566046264.68373: Loaded config def from plugin (connection/napalm)
2019-08-17 08:51:04,684 p=24794 u=automation | 24794 1566046264.68399: Loading Connection 'napalm' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/napalm.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,689 p=24794 u=automation | 24794 1566046264.68968: Loaded config def from plugin (connection/netconf)
2019-08-17 08:51:04,690 p=24794 u=automation | 24794 1566046264.68997: Loading Connection 'netconf' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/netconf.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,696 p=24794 u=automation | 24794 1566046264.69676: Loaded config def from plugin (connection/network_cli)
2019-08-17 08:51:04,697 p=24794 u=automation | 24794 1566046264.69704: Loading Connection 'network_cli' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/network_cli.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,701 p=24794 u=automation | 24794 1566046264.70142: Loaded config def from plugin (connection/oc)
2019-08-17 08:51:04,701 p=24794 u=automation | 24794 1566046264.70167: Loading Connection 'oc' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/oc.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,706 p=24794 u=automation | 24794 1566046264.70625: Loaded config def from plugin (connection/paramiko_ssh)
2019-08-17 08:51:04,706 p=24794 u=automation | 24794 1566046264.70653: Loading Connection 'paramiko_ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/paramiko_ssh.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,709 p=24794 u=automation | 24794 1566046264.70891: Loaded config def from plugin (connection/persistent)
2019-08-17 08:51:04,709 p=24794 u=automation | 24794 1566046264.70915: Loading Connection 'persistent' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/persistent.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,710 p=24794 u=automation | 24794 1566046264.71031: Loaded config def from plugin (connection/podman)
2019-08-17 08:51:04,710 p=24794 u=automation | 24794 1566046264.71054: Loading Connection 'podman' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/podman.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,721 p=24794 u=automation | 24794 1566046264.72098: Loaded config def from plugin (connection/psrp)
2019-08-17 08:51:04,721 p=24794 u=automation | 24794 1566046264.72127: Loading Connection 'psrp' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/psrp.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,722 p=24794 u=automation | 24794 1566046264.72230: Loaded config def from plugin (connection/qubes)
2019-08-17 08:51:04,722 p=24794 u=automation | 24794 1566046264.72253: Loading Connection 'qubes' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/qubes.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,723 p=24794 u=automation | 24794 1566046264.72333: Loading Connection 'saltstack' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/saltstack.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,734 p=24794 u=automation | 24794 1566046264.73422: Loaded config def from plugin (connection/ssh)
2019-08-17 08:51:04,734 p=24794 u=automation | 24794 1566046264.73474: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,739 p=24794 u=automation | 24794 1566046264.73932: Loaded config def from plugin (connection/vmware_tools)
2019-08-17 08:51:04,739 p=24794 u=automation | 24794 1566046264.73959: Loading Connection 'vmware_tools' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/vmware_tools.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,743 p=24794 u=automation | 24794 1566046264.74319: Loaded config def from plugin (connection/winrm)
2019-08-17 08:51:04,743 p=24794 u=automation | 24794 1566046264.74345: Loading Connection 'winrm' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/winrm.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,744 p=24794 u=automation | 24794 1566046264.74439: Loaded config def from plugin (connection/zone)
2019-08-17 08:51:04,744 p=24794 u=automation | 24794 1566046264.74462: Loading Connection 'zone' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/zone.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,749 p=24794 u=automation | 24794 1566046264.74963: trying /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments
2019-08-17 08:51:04,755 p=24794 u=automation | 24794 1566046264.75553: Loading ModuleDocFragment 'shell_windows' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/shell_windows.py
2019-08-17 08:51:04,756 p=24794 u=automation | 24794 1566046264.75685: Loaded config def from plugin (shell/cmd)
2019-08-17 08:51:04,757 p=24794 u=automation | 24794 1566046264.75707: Loading ShellModule 'cmd' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/cmd.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,757 p=24794 u=automation | 24794 1566046264.75790: Loading ModuleDocFragment 'shell_common' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/shell_common.py
2019-08-17 08:51:04,760 p=24794 u=automation | 24794 1566046264.76000: Loaded config def from plugin (shell/csh)
2019-08-17 08:51:04,760 p=24794 u=automation | 24794 1566046264.76022: Loading ShellModule 'csh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/csh.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,761 p=24794 u=automation | 24794 1566046264.76111: Loading ModuleDocFragment 'shell_common' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/shell_common.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:04,763 p=24794 u=automation | 24794 1566046264.76323: Loaded config def from plugin (shell/fish)
2019-08-17 08:51:04,763 p=24794 u=automation | 24794 1566046264.76345: Loading ShellModule 'fish' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/fish.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,764 p=24794 u=automation | 24794 1566046264.76430: Loading ModuleDocFragment 'shell_windows' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/shell_windows.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:04,765 p=24794 u=automation | 24794 1566046264.76550: Loaded config def from plugin (shell/powershell)
2019-08-17 08:51:04,765 p=24794 u=automation | 24794 1566046264.76571: Loading ShellModule 'powershell' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/powershell.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,766 p=24794 u=automation | 24794 1566046264.76646: Loading ModuleDocFragment 'shell_common' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/shell_common.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:04,768 p=24794 u=automation | 24794 1566046264.76848: Loaded config def from plugin (shell/sh)
2019-08-17 08:51:04,768 p=24794 u=automation | 24794 1566046264.76869: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,773 p=24794 u=automation | 24794 1566046264.77304: Loaded config def from plugin (become/doas)
2019-08-17 08:51:04,773 p=24794 u=automation | 24794 1566046264.77327: Loading BecomeModule 'doas' from /usr/lib/python2.7/site-packages/ansible/plugins/become/doas.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,775 p=24794 u=automation | 24794 1566046264.77579: Loaded config def from plugin (become/dzdo)
2019-08-17 08:51:04,776 p=24794 u=automation | 24794 1566046264.77603: Loading BecomeModule 'dzdo' from /usr/lib/python2.7/site-packages/ansible/plugins/become/dzdo.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,777 p=24794 u=automation | 24794 1566046264.77709: Loaded config def from plugin (become/enable)
2019-08-17 08:51:04,777 p=24794 u=automation | 24794 1566046264.77730: Loading BecomeModule 'enable' from /usr/lib/python2.7/site-packages/ansible/plugins/become/enable.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,780 p=24794 u=automation | 24794 1566046264.78019: Loaded config def from plugin (become/ksu)
2019-08-17 08:51:04,780 p=24794 u=automation | 24794 1566046264.78043: Loading BecomeModule 'ksu' from /usr/lib/python2.7/site-packages/ansible/plugins/become/ksu.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,783 p=24794 u=automation | 24794 1566046264.78337: Loaded config def from plugin (become/machinectl)
2019-08-17 08:51:04,783 p=24794 u=automation | 24794 1566046264.78359: Loading BecomeModule 'machinectl' from /usr/lib/python2.7/site-packages/ansible/plugins/become/machinectl.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,786 p=24794 u=automation | 24794 1566046264.78645: Loaded config def from plugin (become/pbrun)
2019-08-17 08:51:04,786 p=24794 u=automation | 24794 1566046264.78667: Loading BecomeModule 'pbrun' from /usr/lib/python2.7/site-packages/ansible/plugins/become/pbrun.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,789 p=24794 u=automation | 24794 1566046264.78965: Loaded config def from plugin (become/pfexec)
2019-08-17 08:51:04,789 p=24794 u=automation | 24794 1566046264.78988: Loading BecomeModule 'pfexec' from /usr/lib/python2.7/site-packages/ansible/plugins/become/pfexec.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,791 p=24794 u=automation | 24794 1566046264.79187: Loaded config def from plugin (become/pmrun)
2019-08-17 08:51:04,792 p=24794 u=automation | 24794 1566046264.79208: Loading BecomeModule 'pmrun' from /usr/lib/python2.7/site-packages/ansible/plugins/become/pmrun.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,794 p=24794 u=automation | 24794 1566046264.79422: Loaded config def from plugin (become/runas)
2019-08-17 08:51:04,794 p=24794 u=automation | 24794 1566046264.79444: Loading BecomeModule 'runas' from /usr/lib/python2.7/site-packages/ansible/plugins/become/runas.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,797 p=24794 u=automation | 24794 1566046264.79702: Loaded config def from plugin (become/sesu)
2019-08-17 08:51:04,797 p=24794 u=automation | 24794 1566046264.79725: Loading BecomeModule 'sesu' from /usr/lib/python2.7/site-packages/ansible/plugins/become/sesu.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,800 p=24794 u=automation | 24794 1566046264.80018: Loaded config def from plugin (become/su)
2019-08-17 08:51:04,800 p=24794 u=automation | 24794 1566046264.80040: Loading BecomeModule 'su' from /usr/lib/python2.7/site-packages/ansible/plugins/become/su.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,803 p=24794 u=automation | 24794 1566046264.80296: Loaded config def from plugin (become/sudo)
2019-08-17 08:51:04,803 p=24794 u=automation | 24794 1566046264.80317: Loading BecomeModule 'sudo' from /usr/lib/python2.7/site-packages/ansible/plugins/become/sudo.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:04,803 p=24794 u=automation | 24794 1566046264.80348: Loading data from /home/automation/dtcc_ansible/create_vlan.yml
2019-08-17 08:51:04,828 p=24794 u=automation | 24794 1566046264.82836: trying /usr/lib/python2.7/site-packages/ansible/plugins/action
2019-08-17 08:51:04,931 p=24794 u=automation | 24794 1566046264.93127: trying /usr/lib/python2.7/site-packages/ansible/modules
2019-08-17 08:51:04,932 p=24794 u=automation | 24794 1566046264.93195: trying /usr/lib/python2.7/site-packages/ansible/modules/net_tools
2019-08-17 08:51:04,933 p=24794 u=automation | 24794 1566046264.93296: trying /usr/lib/python2.7/site-packages/ansible/modules/inventory
2019-08-17 08:51:04,933 p=24794 u=automation | 24794 1566046264.93334: trying /usr/lib/python2.7/site-packages/ansible/modules/source_control
2019-08-17 08:51:04,934 p=24794 u=automation | 24794 1566046264.93457: trying /usr/lib/python2.7/site-packages/ansible/modules/notification
2019-08-17 08:51:04,936 p=24794 u=automation | 24794 1566046264.93634: trying /usr/lib/python2.7/site-packages/ansible/modules/system
2019-08-17 08:51:04,941 p=24794 u=automation | 24794 1566046264.94089: trying /usr/lib/python2.7/site-packages/ansible/modules/web_infrastructure
2019-08-17 08:51:04,942 p=24794 u=automation | 24794 1566046264.94218: trying /usr/lib/python2.7/site-packages/ansible/modules/utilities
2019-08-17 08:51:04,942 p=24794 u=automation | 24794 1566046264.94254: trying /usr/lib/python2.7/site-packages/ansible/modules/storage
2019-08-17 08:51:04,943 p=24794 u=automation | 24794 1566046264.94298: trying /usr/lib/python2.7/site-packages/ansible/modules/commands
2019-08-17 08:51:04,943 p=24794 u=automation | 24794 1566046264.94363: trying /usr/lib/python2.7/site-packages/ansible/modules/identity
2019-08-17 08:51:04,944 p=24794 u=automation | 24794 1566046264.94408: trying /usr/lib/python2.7/site-packages/ansible/modules/packaging
2019-08-17 08:51:04,944 p=24794 u=automation | 24794 1566046264.94442: trying /usr/lib/python2.7/site-packages/ansible/modules/files
2019-08-17 08:51:04,946 p=24794 u=automation | 24794 1566046264.94590: trying /usr/lib/python2.7/site-packages/ansible/modules/crypto
2019-08-17 08:51:04,947 p=24794 u=automation | 24794 1566046264.94692: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management
2019-08-17 08:51:04,947 p=24794 u=automation | 24794 1566046264.94744: trying /usr/lib/python2.7/site-packages/ansible/modules/database
2019-08-17 08:51:04,947 p=24794 u=automation | 24794 1566046264.94788: trying /usr/lib/python2.7/site-packages/ansible/modules/clustering
2019-08-17 08:51:04,948 p=24794 u=automation | 24794 1566046264.94855: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud
2019-08-17 08:51:04,949 p=24794 u=automation | 24794 1566046264.94929: trying /usr/lib/python2.7/site-packages/ansible/modules/messaging
2019-08-17 08:51:04,949 p=24794 u=automation | 24794 1566046264.94961: trying /usr/lib/python2.7/site-packages/ansible/modules/monitoring
2019-08-17 08:51:04,951 p=24794 u=automation | 24794 1566046264.95151: trying /usr/lib/python2.7/site-packages/ansible/modules/network
2019-08-17 08:51:04,952 p=24794 u=automation | 24794 1566046264.95248: trying /usr/lib/python2.7/site-packages/ansible/modules/net_tools/nios
2019-08-17 08:51:04,953 p=24794 u=automation | 24794 1566046264.95365: trying /usr/lib/python2.7/site-packages/ansible/modules/net_tools/netbox
2019-08-17 08:51:04,954 p=24794 u=automation | 24794 1566046264.95426: trying /usr/lib/python2.7/site-packages/ansible/modules/net_tools/infinity
2019-08-17 08:51:04,954 p=24794 u=automation | 24794 1566046264.95463: trying /usr/lib/python2.7/site-packages/ansible/modules/net_tools/ldap
2019-08-17 08:51:04,955 p=24794 u=automation | 24794 1566046264.95513: trying /usr/lib/python2.7/site-packages/ansible/modules/net_tools/basics
2019-08-17 08:51:04,955 p=24794 u=automation | 24794 1566046264.95561: trying /usr/lib/python2.7/site-packages/ansible/modules/net_tools/exoscale
2019-08-17 08:51:04,956 p=24794 u=automation | 24794 1566046264.95606: trying /usr/lib/python2.7/site-packages/ansible/modules/source_control/bitbucket
2019-08-17 08:51:04,956 p=24794 u=automation | 24794 1566046264.95657: trying /usr/lib/python2.7/site-packages/ansible/modules/web_infrastructure/sophos_utm
2019-08-17 08:51:04,957 p=24794 u=automation | 24794 1566046264.95760: trying /usr/lib/python2.7/site-packages/ansible/modules/web_infrastructure/ansible_tower
2019-08-17 08:51:04,959 p=24794 u=automation | 24794 1566046264.95914: trying /usr/lib/python2.7/site-packages/ansible/modules/utilities/logic
2019-08-17 08:51:04,960 p=24794 u=automation | 24794 1566046264.96025: trying /usr/lib/python2.7/site-packages/ansible/modules/utilities/helper
2019-08-17 08:51:04,960 p=24794 u=automation | 24794 1566046264.96065: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/glusterfs
2019-08-17 08:51:04,961 p=24794 u=automation | 24794 1566046264.96111: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/netapp
2019-08-17 08:51:04,967 p=24794 u=automation | 24794 1566046264.96767: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/infinidat
2019-08-17 08:51:04,968 p=24794 u=automation | 24794 1566046264.96833: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/vexata
2019-08-17 08:51:04,968 p=24794 u=automation | 24794 1566046264.96868: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/ibm
2019-08-17 08:51:04,969 p=24794 u=automation | 24794 1566046264.96930: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/emc
2019-08-17 08:51:04,969 p=24794 u=automation | 24794 1566046264.96964: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/hpe3par
2019-08-17 08:51:04,970 p=24794 u=automation | 24794 1566046264.97001: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/purestorage
2019-08-17 08:51:04,971 p=24794 u=automation | 24794 1566046264.97159: trying /usr/lib/python2.7/site-packages/ansible/modules/storage/zfs
2019-08-17 08:51:04,972 p=24794 u=automation | 24794 1566046264.97209: trying /usr/lib/python2.7/site-packages/ansible/modules/identity/ipa
2019-08-17 08:51:04,973 p=24794 u=automation | 24794 1566046264.97312: trying /usr/lib/python2.7/site-packages/ansible/modules/identity/opendj
2019-08-17 08:51:04,973 p=24794 u=automation | 24794 1566046264.97344: trying /usr/lib/python2.7/site-packages/ansible/modules/identity/cyberark
2019-08-17 08:51:04,973 p=24794 u=automation | 24794 1566046264.97384: trying /usr/lib/python2.7/site-packages/ansible/modules/identity/keycloak
2019-08-17 08:51:04,974 p=24794 u=automation | 24794 1566046264.97429: trying /usr/lib/python2.7/site-packages/ansible/modules/packaging/os
2019-08-17 08:51:04,977 p=24794 u=automation | 24794 1566046264.97706: trying /usr/lib/python2.7/site-packages/ansible/modules/packaging/language
2019-08-17 08:51:04,978 p=24794 u=automation | 24794 1566046264.97799: trying /usr/lib/python2.7/site-packages/ansible/modules/crypto/acme
2019-08-17 08:51:04,978 p=24794 u=automation | 24794 1566046264.97868: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/ipmi
2019-08-17 08:51:04,979 p=24794 u=automation | 24794 1566046264.97911: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/lxca
2019-08-17 08:51:04,979 p=24794 u=automation | 24794 1566046264.97950: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/oneview
2019-08-17 08:51:04,980 p=24794 u=automation | 24794 1566046264.98057: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/cobbler
2019-08-17 08:51:04,981 p=24794 u=automation | 24794 1566046264.98097: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/hpilo
2019-08-17 08:51:04,981 p=24794 u=automation | 24794 1566046264.98141: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/dellemc
2019-08-17 08:51:04,981 p=24794 u=automation | 24794 1566046264.98171: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/foreman
2019-08-17 08:51:04,982 p=24794 u=automation | 24794 1566046264.98210: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/manageiq
2019-08-17 08:51:04,982 p=24794 u=automation | 24794 1566046264.98277: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/cpm
2019-08-17 08:51:04,983 p=24794 u=automation | 24794 1566046264.98321: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/intersight
2019-08-17 08:51:04,983 p=24794 u=automation | 24794 1566046264.98359: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/ucs
2019-08-17 08:51:04,984 p=24794 u=automation | 24794 1566046264.98479: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/stacki
2019-08-17 08:51:04,985 p=24794 u=automation | 24794 1566046264.98514: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/redfish
2019-08-17 08:51:04,985 p=24794 u=automation | 24794 1566046264.98573: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/imc
2019-08-17 08:51:04,986 p=24794 u=automation | 24794 1566046264.98607: trying /usr/lib/python2.7/site-packages/ansible/modules/remote_management/dellemc/idrac
2019-08-17 08:51:04,986 p=24794 u=automation | 24794 1566046264.98646: trying /usr/lib/python2.7/site-packages/ansible/modules/database/aerospike
2019-08-17 08:51:04,986 p=24794 u=automation | 24794 1566046264.98679: trying /usr/lib/python2.7/site-packages/ansible/modules/database/vertica
2019-08-17 08:51:04,987 p=24794 u=automation | 24794 1566046264.98732: trying /usr/lib/python2.7/site-packages/ansible/modules/database/mysql
2019-08-17 08:51:04,987 p=24794 u=automation | 24794 1566046264.98780: trying /usr/lib/python2.7/site-packages/ansible/modules/database/postgresql
2019-08-17 08:51:04,989 p=24794 u=automation | 24794 1566046264.98893: trying /usr/lib/python2.7/site-packages/ansible/modules/database/proxysql
2019-08-17 08:51:04,989 p=24794 u=automation | 24794 1566046264.98983: trying /usr/lib/python2.7/site-packages/ansible/modules/database/misc
2019-08-17 08:51:04,990 p=24794 u=automation | 24794 1566046264.99031: trying /usr/lib/python2.7/site-packages/ansible/modules/database/influxdb
2019-08-17 08:51:04,990 p=24794 u=automation | 24794 1566046264.99086: trying /usr/lib/python2.7/site-packages/ansible/modules/database/mongodb
2019-08-17 08:51:04,991 p=24794 u=automation | 24794 1566046264.99133: trying /usr/lib/python2.7/site-packages/ansible/modules/database/mssql
2019-08-17 08:51:04,991 p=24794 u=automation | 24794 1566046264.99167: trying /usr/lib/python2.7/site-packages/ansible/modules/clustering/k8s
2019-08-17 08:51:04,992 p=24794 u=automation | 24794 1566046264.99231: trying /usr/lib/python2.7/site-packages/ansible/modules/clustering/openshift
2019-08-17 08:51:04,992 p=24794 u=automation | 24794 1566046264.99273: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/cloudscale
2019-08-17 08:51:04,993 p=24794 u=automation | 24794 1566046264.99323: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/oneandone
2019-08-17 08:51:04,993 p=24794 u=automation | 24794 1566046264.99380: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/univention
2019-08-17 08:51:04,994 p=24794 u=automation | 24794 1566046264.99434: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/vultr
2019-08-17 08:51:04,996 p=24794 u=automation | 24794 1566046264.99613: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/amazon
2019-08-17 08:51:05,006 p=24794 u=automation | 24794 1566046265.00653: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/scaleway
2019-08-17 08:51:05,007 p=24794 u=automation | 24794 1566046265.00765: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/podman
2019-08-17 08:51:05,008 p=24794 u=automation | 24794 1566046265.00810: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/pubnub
2019-08-17 08:51:05,008 p=24794 u=automation | 24794 1566046265.00844: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/hcloud
2019-08-17 08:51:05,009 p=24794 u=automation | 24794 1566046265.00929: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/atomic
2019-08-17 08:51:05,009 p=24794 u=automation | 24794 1566046265.00974: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/digital_ocean
2019-08-17 08:51:05,011 p=24794 u=automation | 24794 1566046265.01109: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/lxd
2019-08-17 08:51:05,011 p=24794 u=automation | 24794 1566046265.01148: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/xenserver
2019-08-17 08:51:05,012 p=24794 u=automation | 24794 1566046265.01193: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/lxc
2019-08-17 08:51:05,012 p=24794 u=automation | 24794 1566046265.01227: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/softlayer
2019-08-17 08:51:05,012 p=24794 u=automation | 24794 1566046265.01267: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/kubevirt
2019-08-17 08:51:05,013 p=24794 u=automation | 24794 1566046265.01326: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/vmware
2019-08-17 08:51:05,019 p=24794 u=automation | 24794 1566046265.01910: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/dimensiondata
2019-08-17 08:51:05,019 p=24794 u=automation | 24794 1566046265.01952: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/ovirt
2019-08-17 08:51:05,022 p=24794 u=automation | 24794 1566046265.02236: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/azure
2019-08-17 08:51:05,030 p=24794 u=automation | 24794 1566046265.03047: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/openstack
2019-08-17 08:51:05,033 p=24794 u=automation | 24794 1566046265.03346: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/webfaction
2019-08-17 08:51:05,034 p=24794 u=automation | 24794 1566046265.03402: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/oracle
2019-08-17 08:51:05,034 p=24794 u=automation | 24794 1566046265.03439: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/huawei
2019-08-17 08:51:05,034 p=24794 u=automation | 24794 1566046265.03479: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/packet
2019-08-17 08:51:05,035 p=24794 u=automation | 24794 1566046265.03522: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/centurylink
2019-08-17 08:51:05,036 p=24794 u=automation | 24794 1566046265.03626: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/docker
2019-08-17 08:51:05,037 p=24794 u=automation | 24794 1566046265.03772: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/opennebula
2019-08-17 08:51:05,038 p=24794 u=automation | 24794 1566046265.03842: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/alicloud
2019-08-17 08:51:05,039 p=24794 u=automation | 24794 1566046265.03890: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/linode
2019-08-17 08:51:05,039 p=24794 u=automation | 24794 1566046265.03928: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/google
2019-08-17 08:51:05,046 p=24794 u=automation | 24794 1566046265.04635: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/smartos
2019-08-17 08:51:05,047 p=24794 u=automation | 24794 1566046265.04693: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/ovh
2019-08-17 08:51:05,047 p=24794 u=automation | 24794 1566046265.04732: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/misc
2019-08-17 08:51:05,048 p=24794 u=automation | 24794 1566046265.04831: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/spotinst
2019-08-17 08:51:05,048 p=24794 u=automation | 24794 1566046265.04864: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/heroku
2019-08-17 08:51:05,049 p=24794 u=automation | 24794 1566046265.04897: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/profitbricks
2019-08-17 08:51:05,049 p=24794 u=automation | 24794 1566046265.04949: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/rackspace
2019-08-17 08:51:05,051 p=24794 u=automation | 24794 1566046265.05153: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/online
2019-08-17 08:51:05,052 p=24794 u=automation | 24794 1566046265.05198: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/cloudstack
2019-08-17 08:51:05,055 p=24794 u=automation | 24794 1566046265.05503: trying /usr/lib/python2.7/site-packages/ansible/modules/cloud/memset
2019-08-17 08:51:05,055 p=24794 u=automation | 24794 1566046265.05561: trying /usr/lib/python2.7/site-packages/ansible/modules/messaging/rabbitmq
2019-08-17 08:51:05,056 p=24794 u=automation | 24794 1566046265.05642: trying /usr/lib/python2.7/site-packages/ansible/modules/monitoring/zabbix
2019-08-17 08:51:05,057 p=24794 u=automation | 24794 1566046265.05732: trying /usr/lib/python2.7/site-packages/ansible/modules/network/nuage
2019-08-17 08:51:05,057 p=24794 u=automation | 24794 1566046265.05765: trying /usr/lib/python2.7/site-packages/ansible/modules/network/edgeswitch
2019-08-17 08:51:05,058 p=24794 u=automation | 24794 1566046265.05805: trying /usr/lib/python2.7/site-packages/ansible/modules/network/routing
2019-08-17 08:51:05,058 p=24794 u=automation | 24794 1566046265.05838: trying /usr/lib/python2.7/site-packages/ansible/modules/network/dellos9
2019-08-17 08:51:05,058 p=24794 u=automation | 24794 1566046265.05880: trying /usr/lib/python2.7/site-packages/ansible/modules/network/cloudengine
2019-08-17 08:51:05,062 p=24794 u=automation | 24794 1566046265.06251: trying /usr/lib/python2.7/site-packages/ansible/modules/network/voss
2019-08-17 08:51:05,063 p=24794 u=automation | 24794 1566046265.06314: trying /usr/lib/python2.7/site-packages/ansible/modules/network/cloudvision
2019-08-17 08:51:05,063 p=24794 u=automation | 24794 1566046265.06347: trying /usr/lib/python2.7/site-packages/ansible/modules/network/ovs
2019-08-17 08:51:05,064 p=24794 u=automation | 24794 1566046265.06391: trying /usr/lib/python2.7/site-packages/ansible/modules/network/ingate
2019-08-17 08:51:05,064 p=24794 u=automation | 24794 1566046265.06430: trying /usr/lib/python2.7/site-packages/ansible/modules/network/asa
2019-08-17 08:51:05,064 p=24794 u=automation | 24794 1566046265.06477: trying /usr/lib/python2.7/site-packages/ansible/modules/network/dellos6
2019-08-17 08:51:05,065 p=24794 u=automation | 24794 1566046265.06519: trying /usr/lib/python2.7/site-packages/ansible/modules/network/bigswitch
2019-08-17 08:51:05,065 p=24794 u=automation | 24794 1566046265.06566: trying /usr/lib/python2.7/site-packages/ansible/modules/network/nos
2019-08-17 08:51:05,066 p=24794 u=automation | 24794 1566046265.06610: trying /usr/lib/python2.7/site-packages/ansible/modules/network/vyos
2019-08-17 08:51:05,067 p=24794 u=automation | 24794 1566046265.06714: trying /usr/lib/python2.7/site-packages/ansible/modules/network/system
2019-08-17 08:51:05,067 p=24794 u=automation | 24794 1566046265.06769: trying /usr/lib/python2.7/site-packages/ansible/modules/network/exos
2019-08-17 08:51:05,068 p=24794 u=automation | 24794 1566046265.06811: trying /usr/lib/python2.7/site-packages/ansible/modules/network/citrix
2019-08-17 08:51:05,068 p=24794 u=automation | 24794 1566046265.06843: trying /usr/lib/python2.7/site-packages/ansible/modules/network/restconf
2019-08-17 08:51:05,068 p=24794 u=automation | 24794 1566046265.06880: trying /usr/lib/python2.7/site-packages/ansible/modules/network/netact
2019-08-17 08:51:05,069 p=24794 u=automation | 24794 1566046265.06913: trying /usr/lib/python2.7/site-packages/ansible/modules/network/skydive
2019-08-17 08:51:05,069 p=24794 u=automation | 24794 1566046265.06954: trying /usr/lib/python2.7/site-packages/ansible/modules/network/dellos10
2019-08-17 08:51:05,070 p=24794 u=automation | 24794 1566046265.06998: trying /usr/lib/python2.7/site-packages/ansible/modules/network/ios
2019-08-17 08:51:05,071 p=24794 u=automation | 24794 1566046265.07134: trying /usr/lib/python2.7/site-packages/ansible/modules/network/radware
2019-08-17 08:51:05,071 p=24794 u=automation | 24794 1566046265.07189: trying /usr/lib/python2.7/site-packages/ansible/modules/network/files
2019-08-17 08:51:05,072 p=24794 u=automation | 24794 1566046265.07226: trying /usr/lib/python2.7/site-packages/ansible/modules/network/a10
2019-08-17 08:51:05,072 p=24794 u=automation | 24794 1566046265.07275: trying /usr/lib/python2.7/site-packages/ansible/modules/network/slxos
2019-08-17 08:51:05,073 p=24794 u=automation | 24794 1566046265.07346: trying /usr/lib/python2.7/site-packages/ansible/modules/network/interface
2019-08-17 08:51:05,073 p=24794 u=automation | 24794 1566046265.07389: trying /usr/lib/python2.7/site-packages/ansible/modules/network/aruba
2019-08-17 08:51:05,074 p=24794 u=automation | 24794 1566046265.07427: trying /usr/lib/python2.7/site-packages/ansible/modules/network/layer2
2019-08-17 08:51:05,074 p=24794 u=automation | 24794 1566046265.07464: trying /usr/lib/python2.7/site-packages/ansible/modules/network/netvisor
2019-08-17 08:51:05,077 p=24794 u=automation | 24794 1566046265.07698: trying /usr/lib/python2.7/site-packages/ansible/modules/network/eos
2019-08-17 08:51:05,078 p=24794 u=automation | 24794 1566046265.07823: trying /usr/lib/python2.7/site-packages/ansible/modules/network/fortios
2019-08-17 08:51:05,090 p=24794 u=automation | 24794 1566046265.08984: trying /usr/lib/python2.7/site-packages/ansible/modules/network/aos
2019-08-17 08:51:05,091 p=24794 u=automation | 24794 1566046265.09109: trying /usr/lib/python2.7/site-packages/ansible/modules/network/sros
2019-08-17 08:51:05,091 p=24794 u=automation | 24794 1566046265.09159: trying /usr/lib/python2.7/site-packages/ansible/modules/network/checkpoint
2019-08-17 08:51:05,092 p=24794 u=automation | 24794 1566046265.09232: trying /usr/lib/python2.7/site-packages/ansible/modules/network/junos
2019-08-17 08:51:05,093 p=24794 u=automation | 24794 1566046265.09376: trying /usr/lib/python2.7/site-packages/ansible/modules/network/edgeos
2019-08-17 08:51:05,094 p=24794 u=automation | 24794 1566046265.09425: trying /usr/lib/python2.7/site-packages/ansible/modules/network/nso
2019-08-17 08:51:05,094 p=24794 u=automation | 24794 1566046265.09479: trying /usr/lib/python2.7/site-packages/ansible/modules/network/avi
2019-08-17 08:51:05,098 p=24794 u=automation | 24794 1566046265.09847: trying /usr/lib/python2.7/site-packages/ansible/modules/network/opx
2019-08-17 08:51:05,098 p=24794 u=automation | 24794 1566046265.09886: trying /usr/lib/python2.7/site-packages/ansible/modules/network/routeros
2019-08-17 08:51:05,099 p=24794 u=automation | 24794 1566046265.09924: trying /usr/lib/python2.7/site-packages/ansible/modules/network/netscaler
2019-08-17 08:51:05,100 p=24794 u=automation | 24794 1566046265.10020: trying /usr/lib/python2.7/site-packages/ansible/modules/network/fortimanager
2019-08-17 08:51:05,101 p=24794 u=automation | 24794 1566046265.10184: trying /usr/lib/python2.7/site-packages/ansible/modules/network/f5
2019-08-17 08:51:05,110 p=24794 u=automation | 24794 1566046265.10993: trying /usr/lib/python2.7/site-packages/ansible/modules/network/ftd
2019-08-17 08:51:05,110 p=24794 u=automation | 24794 1566046265.11054: trying /usr/lib/python2.7/site-packages/ansible/modules/network/iosxr
2019-08-17 08:51:05,111 p=24794 u=automation | 24794 1566046265.11144: trying /usr/lib/python2.7/site-packages/ansible/modules/network/illumos
2019-08-17 08:51:05,112 p=24794 u=automation | 24794 1566046265.11229: trying /usr/lib/python2.7/site-packages/ansible/modules/network/aireos
2019-08-17 08:51:05,112 p=24794 u=automation | 24794 1566046265.11276: trying /usr/lib/python2.7/site-packages/ansible/modules/network/itential
2019-08-17 08:51:05,113 p=24794 u=automation | 24794 1566046265.11317: trying /usr/lib/python2.7/site-packages/ansible/modules/network/nxos
2019-08-17 08:51:05,117 p=24794 u=automation | 24794 1566046265.11750: trying /usr/lib/python2.7/site-packages/ansible/modules/network/cnos
2019-08-17 08:51:05,119 p=24794 u=automation | 24794 1566046265.11919: trying /usr/lib/python2.7/site-packages/ansible/modules/network/enos
2019-08-17 08:51:05,119 p=24794 u=automation | 24794 1566046265.11965: trying /usr/lib/python2.7/site-packages/ansible/modules/network/cumulus
2019-08-17 08:51:05,120 p=24794 u=automation | 24794 1566046265.12039: trying /usr/lib/python2.7/site-packages/ansible/modules/network/layer3
2019-08-17 08:51:05,121 p=24794 u=automation | 24794 1566046265.12087: trying /usr/lib/python2.7/site-packages/ansible/modules/network/ironware
2019-08-17 08:51:05,121 p=24794 u=automation | 24794 1566046265.12142: trying /usr/lib/python2.7/site-packages/ansible/modules/network/meraki
2019-08-17 08:51:05,122 p=24794 u=automation | 24794 1566046265.12246: trying /usr/lib/python2.7/site-packages/ansible/modules/network/aci
2019-08-17 08:51:05,128 p=24794 u=automation | 24794 1566046265.12862: trying /usr/lib/python2.7/site-packages/ansible/modules/network/ordnance
2019-08-17 08:51:05,129 p=24794 u=automation | 24794 1566046265.12917: trying /usr/lib/python2.7/site-packages/ansible/modules/network/cli
2019-08-17 08:51:05,129 p=24794 u=automation | 24794 1566046265.12959: trying /usr/lib/python2.7/site-packages/ansible/modules/network/panos
2019-08-17 08:51:05,132 p=24794 u=automation | 24794 1566046265.13224: trying /usr/lib/python2.7/site-packages/ansible/modules/network/frr
2019-08-17 08:51:05,133 p=24794 u=automation | 24794 1566046265.13330: trying /usr/lib/python2.7/site-packages/ansible/modules/network/netconf
2019-08-17 08:51:05,133 p=24794 u=automation | 24794 1566046265.13374: trying /usr/lib/python2.7/site-packages/ansible/modules/network/protocol
2019-08-17 08:51:05,134 p=24794 u=automation | 24794 1566046265.13409: trying /usr/lib/python2.7/site-packages/ansible/modules/network/onyx
2019-08-17 08:51:05,135 p=24794 u=automation | 24794 1566046265.13556: trying /usr/lib/python2.7/site-packages/ansible/modules/windows
2019-08-17 08:51:05,146 p=24794 u=automation | 24794 1566046265.14611: trying /usr/lib/python2.7/site-packages/ansible/plugins/callback
2019-08-17 08:51:05,149 p=24794 u=automation | 24794 1566046265.14937: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py
2019-08-17 08:51:05,151 p=24794 u=automation | 24794 1566046265.15129: Loaded config def from plugin (callback/default)
2019-08-17 08:51:05,151 p=24794 u=automation | 24794 1566046265.15150: Loading CallbackModule 'default' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/default.py
2019-08-17 08:51:05,154 p=24794 u=automation | 24794 1566046265.15487: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,156 p=24794 u=automation | 24794 1566046265.15668: Loaded config def from plugin (callback/actionable)
2019-08-17 08:51:05,156 p=24794 u=automation | 24794 1566046265.15691: Loading CallbackModule 'actionable' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/actionable.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,157 p=24794 u=automation | 24794 1566046265.15762: Loading CallbackModule 'aws_resource_actions' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/aws_resource_actions.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,159 p=24794 u=automation | 24794 1566046265.15896: Loaded config def from plugin (callback/cgroup_memory_recap)
2019-08-17 08:51:05,159 p=24794 u=automation | 24794 1566046265.15917: Loading CallbackModule 'cgroup_memory_recap' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/cgroup_memory_recap.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,164 p=24794 u=automation | 24794 1566046265.16396: Loaded config def from plugin (callback/cgroup_perf_recap)
2019-08-17 08:51:05,164 p=24794 u=automation | 24794 1566046265.16427: Loading CallbackModule 'cgroup_perf_recap' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/cgroup_perf_recap.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,165 p=24794 u=automation | 24794 1566046265.16495: Loading CallbackModule 'context_demo' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/context_demo.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,171 p=24794 u=automation | 24794 1566046265.17150: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,173 p=24794 u=automation | 24794 1566046265.17335: Loaded config def from plugin (callback/counter_enabled)
2019-08-17 08:51:05,173 p=24794 u=automation | 24794 1566046265.17356: Loading CallbackModule 'counter_enabled' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/counter_enabled.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,174 p=24794 u=automation | 24794 1566046265.17431: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,176 p=24794 u=automation | 24794 1566046265.17609: Loaded config def from plugin (callback/debug)
2019-08-17 08:51:05,176 p=24794 u=automation | 24794 1566046265.17630: Loading CallbackModule 'debug' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/debug.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,176 p=24794 u=automation | 24794 1566046265.17650: Loading CallbackModule 'default' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/default.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,177 p=24794 u=automation | 24794 1566046265.17747: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,179 p=24794 u=automation | 24794 1566046265.17943: Loaded config def from plugin (callback/dense)
2019-08-17 08:51:05,179 p=24794 u=automation | 24794 1566046265.17967: Loading CallbackModule 'dense' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/dense.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,182 p=24794 u=automation | 24794 1566046265.18201: Loaded config def from plugin (callback/foreman)
2019-08-17 08:51:05,182 p=24794 u=automation | 24794 1566046265.18222: Loading CallbackModule 'foreman' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/foreman.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,183 p=24794 u=automation | 24794 1566046265.18306: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,184 p=24794 u=automation | 24794 1566046265.18486: Loaded config def from plugin (callback/full_skip)
2019-08-17 08:51:05,185 p=24794 u=automation | 24794 1566046265.18507: Loading CallbackModule 'full_skip' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/full_skip.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,188 p=24794 u=automation | 24794 1566046265.18809: Loaded config def from plugin (callback/grafana_annotations)
2019-08-17 08:51:05,188 p=24794 u=automation | 24794 1566046265.18830: Loading CallbackModule 'grafana_annotations' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/grafana_annotations.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,190 p=24794 u=automation | 24794 1566046265.19048: Loaded config def from plugin (callback/hipchat)
2019-08-17 08:51:05,190 p=24794 u=automation | 24794 1566046265.19069: Loading CallbackModule 'hipchat' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/hipchat.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,192 p=24794 u=automation | 24794 1566046265.19213: Loaded config def from plugin (callback/jabber)
2019-08-17 08:51:05,192 p=24794 u=automation | 24794 1566046265.19234: Loading CallbackModule 'jabber' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/jabber.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,193 p=24794 u=automation | 24794 1566046265.19337: Loaded config def from plugin (callback/json)
2019-08-17 08:51:05,193 p=24794 u=automation | 24794 1566046265.19357: Loading CallbackModule 'json' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/json.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,196 p=24794 u=automation | 24794 1566046265.19641: Loaded config def from plugin (callback/junit)
2019-08-17 08:51:05,196 p=24794 u=automation | 24794 1566046265.19666: Loading CallbackModule 'junit' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/junit.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,197 p=24794 u=automation | 24794 1566046265.19738: Loading CallbackModule 'log_plays' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/log_plays.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,199 p=24794 u=automation | 24794 1566046265.19953: Loaded config def from plugin (callback/logdna)
2019-08-17 08:51:05,199 p=24794 u=automation | 24794 1566046265.19974: Loading CallbackModule 'logdna' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/logdna.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,203 p=24794 u=automation | 24794 1566046265.20333: Loaded config def from plugin (callback/logentries)
2019-08-17 08:51:05,203 p=24794 u=automation | 24794 1566046265.20355: Loading CallbackModule 'logentries' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/logentries.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,205 p=24794 u=automation | 24794 1566046265.20490: Loaded config def from plugin (callback/logstash)
2019-08-17 08:51:05,205 p=24794 u=automation | 24794 1566046265.20511: Loading CallbackModule 'logstash' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/logstash.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,208 p=24794 u=automation | 24794 1566046265.20805: Loaded config def from plugin (callback/mail)
2019-08-17 08:51:05,208 p=24794 u=automation | 24794 1566046265.20827: Loading CallbackModule 'mail' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/mail.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,208 p=24794 u=automation | 24794 1566046265.20889: Loading CallbackModule 'minimal' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/minimal.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,211 p=24794 u=automation | 24794 1566046265.21176: Loaded config def from plugin (callback/nrdp)
2019-08-17 08:51:05,212 p=24794 u=automation | 24794 1566046265.21199: Loading CallbackModule 'nrdp' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/nrdp.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,212 p=24794 u=automation | 24794 1566046265.21261: Loading CallbackModule 'null' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/null.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,213 p=24794 u=automation | 24794 1566046265.21324: Loading CallbackModule 'oneline' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/oneline.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,214 p=24794 u=automation | 24794 1566046265.21399: Loading CallbackModule 'osx_say' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/osx_say.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,214 p=24794 u=automation | 24794 1566046265.21468: Loading CallbackModule 'profile_roles' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/profile_roles.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,216 p=24794 u=automation | 24794 1566046265.21606: Loaded config def from plugin (callback/profile_tasks)
2019-08-17 08:51:05,216 p=24794 u=automation | 24794 1566046265.21627: Loading CallbackModule 'profile_tasks' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/profile_tasks.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,217 p=24794 u=automation | 24794 1566046265.21701: Loading CallbackModule 'say' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/say.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,218 p=24794 u=automation | 24794 1566046265.21825: Loaded config def from plugin (callback/selective)
2019-08-17 08:51:05,218 p=24794 u=automation | 24794 1566046265.21846: Loading CallbackModule 'selective' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/selective.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,219 p=24794 u=automation | 24794 1566046265.21929: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,221 p=24794 u=automation | 24794 1566046265.22111: Loaded config def from plugin (callback/skippy)
2019-08-17 08:51:05,221 p=24794 u=automation | 24794 1566046265.22132: Loading CallbackModule 'skippy' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/skippy.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,223 p=24794 u=automation | 24794 1566046265.22320: Loaded config def from plugin (callback/slack)
2019-08-17 08:51:05,223 p=24794 u=automation | 24794 1566046265.22341: Loading CallbackModule 'slack' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/slack.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,224 p=24794 u=automation | 24794 1566046265.22470: Loaded config def from plugin (callback/splunk)
2019-08-17 08:51:05,225 p=24794 u=automation | 24794 1566046265.22492: Loading CallbackModule 'splunk' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/splunk.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,225 p=24794 u=automation | 24794 1566046265.22582: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,227 p=24794 u=automation | 24794 1566046265.22765: Loaded config def from plugin (callback/stderr)
2019-08-17 08:51:05,227 p=24794 u=automation | 24794 1566046265.22787: Loading CallbackModule 'stderr' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/stderr.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,228 p=24794 u=automation | 24794 1566046265.22887: Loaded config def from plugin (callback/sumologic)
2019-08-17 08:51:05,229 p=24794 u=automation | 24794 1566046265.22908: Loading CallbackModule 'sumologic' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/sumologic.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,231 p=24794 u=automation | 24794 1566046265.23158: Loaded config def from plugin (callback/syslog_json)
2019-08-17 08:51:05,231 p=24794 u=automation | 24794 1566046265.23180: Loading CallbackModule 'syslog_json' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/syslog_json.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,232 p=24794 u=automation | 24794 1566046265.23247: Loading CallbackModule 'timer' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/timer.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,233 p=24794 u=automation | 24794 1566046265.23319: Loading CallbackModule 'tree' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/tree.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,234 p=24794 u=automation | 24794 1566046265.23409: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,236 p=24794 u=automation | 24794 1566046265.23603: Loaded config def from plugin (callback/unixy)
2019-08-17 08:51:05,236 p=24794 u=automation | 24794 1566046265.23623: Loading CallbackModule 'unixy' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/unixy.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,237 p=24794 u=automation | 24794 1566046265.23717: Loading ModuleDocFragment 'default_callback' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/default_callback.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,239 p=24794 u=automation | 24794 1566046265.23896: Loaded config def from plugin (callback/yaml)
2019-08-17 08:51:05,239 p=24794 u=automation | 24794 1566046265.23916: Loading CallbackModule 'yaml' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/yaml.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,239 p=24794 u=automation | 24794 1566046265.23941: in VariableManager get_vars()
2019-08-17 08:51:05,240 p=24794 u=automation | 24794 1566046265.24087: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py
2019-08-17 08:51:05,241 p=24794 u=automation | 24794 1566046265.24122: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py
2019-08-17 08:51:05,241 p=24794 u=automation | 24794 1566046265.24159: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py
2019-08-17 08:51:05,262 p=24794 u=automation | 24794 1566046265.26233: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py
2019-08-17 08:51:05,263 p=24794 u=automation | 24794 1566046265.26321: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py
2019-08-17 08:51:05,263 p=24794 u=automation | 24794 1566046265.26375: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py
2019-08-17 08:51:05,264 p=24794 u=automation | 24794 1566046265.26425: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py
2019-08-17 08:51:05,264 p=24794 u=automation | 24794 1566046265.26473: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py
2019-08-17 08:51:05,265 p=24794 u=automation | 24794 1566046265.26540: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py
2019-08-17 08:51:05,265 p=24794 u=automation | 24794 1566046265.26579: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py
2019-08-17 08:51:05,266 p=24794 u=automation | 24794 1566046265.26617: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py
2019-08-17 08:51:05,270 p=24794 u=automation | 24794 1566046265.27042: done with get_vars()
2019-08-17 08:51:05,270 p=24794 u=automation | 24794 1566046265.27074: in VariableManager get_vars()
2019-08-17 08:51:05,271 p=24794 u=automation | 24794 1566046265.27128: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,271 p=24794 u=automation | 24794 1566046265.27148: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,271 p=24794 u=automation | 24794 1566046265.27168: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,272 p=24794 u=automation | 24794 1566046265.27265: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,272 p=24794 u=automation | 24794 1566046265.27287: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,273 p=24794 u=automation | 24794 1566046265.27306: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,273 p=24794 u=automation | 24794 1566046265.27325: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,273 p=24794 u=automation | 24794 1566046265.27344: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,273 p=24794 u=automation | 24794 1566046265.27362: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,273 p=24794 u=automation | 24794 1566046265.27380: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,274 p=24794 u=automation | 24794 1566046265.27402: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,275 p=24794 u=automation | 24794 1566046265.27506: done with get_vars()
2019-08-17 08:51:05,275 p=24794 u=automation | 24794 1566046265.27560: in VariableManager get_vars()
2019-08-17 08:51:05,276 p=24794 u=automation | 24794 1566046265.27612: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,276 p=24794 u=automation | 24794 1566046265.27632: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,276 p=24794 u=automation | 24794 1566046265.27655: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,277 p=24794 u=automation | 24794 1566046265.27742: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,277 p=24794 u=automation | 24794 1566046265.27763: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,277 p=24794 u=automation | 24794 1566046265.27784: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,278 p=24794 u=automation | 24794 1566046265.27802: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,278 p=24794 u=automation | 24794 1566046265.27821: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,278 p=24794 u=automation | 24794 1566046265.27839: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,278 p=24794 u=automation | 24794 1566046265.27857: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,278 p=24794 u=automation | 24794 1566046265.27876: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,279 p=24794 u=automation | 24794 1566046265.27980: done with get_vars()
2019-08-17 08:51:05,280 p=24794 u=automation | PLAY [vlan testing] *********************************************************************************************************************************
2019-08-17 08:51:05,283 p=24794 u=automation | 24794 1566046265.28320: trying /usr/lib/python2.7/site-packages/ansible/plugins/strategy
2019-08-17 08:51:05,284 p=24794 u=automation | 24794 1566046265.28399: Loading StrategyModule 'linear' from /usr/lib/python2.7/site-packages/ansible/plugins/strategy/linear.py
2019-08-17 08:51:05,284 p=24794 u=automation | 24794 1566046265.28452: getting the remaining hosts for this loop
2019-08-17 08:51:05,284 p=24794 u=automation | 24794 1566046265.28474: done getting the remaining hosts for this loop
2019-08-17 08:51:05,285 p=24794 u=automation | 24794 1566046265.28498: building list of next tasks for hosts
2019-08-17 08:51:05,285 p=24794 u=automation | 24794 1566046265.28516: getting the next task for host n95ksw1
2019-08-17 08:51:05,285 p=24794 u=automation | 24794 1566046265.28536: done getting next task for host n95ksw1
2019-08-17 08:51:05,285 p=24794 u=automation | 24794 1566046265.28556: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,285 p=24794 u=automation | 24794 1566046265.28574: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,286 p=24794 u=automation | 24794 1566046265.28595: getting the next task for host n95ksw2
2019-08-17 08:51:05,286 p=24794 u=automation | 24794 1566046265.28615: done getting next task for host n95ksw2
2019-08-17 08:51:05,286 p=24794 u=automation | 24794 1566046265.28635: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,286 p=24794 u=automation | 24794 1566046265.28652: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,286 p=24794 u=automation | 24794 1566046265.28669: getting the next task for host n7ksw2
2019-08-17 08:51:05,287 p=24794 u=automation | 24794 1566046265.28689: done getting next task for host n7ksw2
2019-08-17 08:51:05,287 p=24794 u=automation | 24794 1566046265.28713: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,287 p=24794 u=automation | 24794 1566046265.28731: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,287 p=24794 u=automation | 24794 1566046265.28748: getting the next task for host n7ksw1
2019-08-17 08:51:05,287 p=24794 u=automation | 24794 1566046265.28771: done getting next task for host n7ksw1
2019-08-17 08:51:05,288 p=24794 u=automation | 24794 1566046265.28795: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,288 p=24794 u=automation | 24794 1566046265.28812: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,288 p=24794 u=automation | 24794 1566046265.28831: done building task lists
2019-08-17 08:51:05,288 p=24794 u=automation | 24794 1566046265.28852: counting tasks in each state of execution
2019-08-17 08:51:05,288 p=24794 u=automation | 24794 1566046265.28872: done counting tasks in each state of execution:
num_setups: 0
num_tasks: 4
num_rescue: 0
num_always: 0
2019-08-17 08:51:05,289 p=24794 u=automation | 24794 1566046265.28897: advancing hosts in ITERATING_TASKS
2019-08-17 08:51:05,289 p=24794 u=automation | 24794 1566046265.28913: starting to advance hosts
2019-08-17 08:51:05,289 p=24794 u=automation | 24794 1566046265.28930: getting the next task for host n95ksw1
2019-08-17 08:51:05,289 p=24794 u=automation | 24794 1566046265.28949: done getting next task for host n95ksw1
2019-08-17 08:51:05,289 p=24794 u=automation | 24794 1566046265.28970: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,289 p=24794 u=automation | 24794 1566046265.28988: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,290 p=24794 u=automation | 24794 1566046265.29005: getting the next task for host n95ksw2
2019-08-17 08:51:05,290 p=24794 u=automation | 24794 1566046265.29024: done getting next task for host n95ksw2
2019-08-17 08:51:05,290 p=24794 u=automation | 24794 1566046265.29043: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,290 p=24794 u=automation | 24794 1566046265.29060: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,290 p=24794 u=automation | 24794 1566046265.29078: getting the next task for host n7ksw2
2019-08-17 08:51:05,291 p=24794 u=automation | 24794 1566046265.29098: done getting next task for host n7ksw2
2019-08-17 08:51:05,291 p=24794 u=automation | 24794 1566046265.29116: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,291 p=24794 u=automation | 24794 1566046265.29133: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,291 p=24794 u=automation | 24794 1566046265.29150: getting the next task for host n7ksw1
2019-08-17 08:51:05,291 p=24794 u=automation | 24794 1566046265.29169: done getting next task for host n7ksw1
2019-08-17 08:51:05,291 p=24794 u=automation | 24794 1566046265.29189: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:05,292 p=24794 u=automation | 24794 1566046265.29206: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,292 p=24794 u=automation | 24794 1566046265.29222: done advancing hosts to next task
2019-08-17 08:51:05,292 p=24794 u=automation | 24794 1566046265.29254: done queuing things up, now waiting for results queue to drain
2019-08-17 08:51:05,292 p=24794 u=automation | 24794 1566046265.29272: results queue empty
2019-08-17 08:51:05,292 p=24794 u=automation | 24794 1566046265.29290: checking for any_errors_fatal
2019-08-17 08:51:05,293 p=24794 u=automation | 24794 1566046265.29309: done checking for any_errors_fatal
2019-08-17 08:51:05,293 p=24794 u=automation | 24794 1566046265.29325: checking for max_fail_percentage
2019-08-17 08:51:05,293 p=24794 u=automation | 24794 1566046265.29341: done checking for max_fail_percentage
2019-08-17 08:51:05,293 p=24794 u=automation | 24794 1566046265.29356: checking to see if all hosts have failed and the running result is not ok
2019-08-17 08:51:05,293 p=24794 u=automation | 24794 1566046265.29372: done checking to see if all hosts have failed
2019-08-17 08:51:05,293 p=24794 u=automation | 24794 1566046265.29389: getting the remaining hosts for this loop
2019-08-17 08:51:05,294 p=24794 u=automation | 24794 1566046265.29410: done getting the remaining hosts for this loop
2019-08-17 08:51:05,294 p=24794 u=automation | 24794 1566046265.29431: building list of next tasks for hosts
2019-08-17 08:51:05,294 p=24794 u=automation | 24794 1566046265.29448: getting the next task for host n95ksw1
2019-08-17 08:51:05,294 p=24794 u=automation | 24794 1566046265.29467: done getting next task for host n95ksw1
2019-08-17 08:51:05,294 p=24794 u=automation | 24794 1566046265.29485: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,295 p=24794 u=automation | 24794 1566046265.29502: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,295 p=24794 u=automation | 24794 1566046265.29520: getting the next task for host n95ksw2
2019-08-17 08:51:05,295 p=24794 u=automation | 24794 1566046265.29538: done getting next task for host n95ksw2
2019-08-17 08:51:05,295 p=24794 u=automation | 24794 1566046265.29556: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,295 p=24794 u=automation | 24794 1566046265.29572: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,295 p=24794 u=automation | 24794 1566046265.29591: getting the next task for host n7ksw2
2019-08-17 08:51:05,296 p=24794 u=automation | 24794 1566046265.29610: done getting next task for host n7ksw2
2019-08-17 08:51:05,296 p=24794 u=automation | 24794 1566046265.29633: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,296 p=24794 u=automation | 24794 1566046265.29650: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,296 p=24794 u=automation | 24794 1566046265.29666: getting the next task for host n7ksw1
2019-08-17 08:51:05,296 p=24794 u=automation | 24794 1566046265.29686: done getting next task for host n7ksw1
2019-08-17 08:51:05,297 p=24794 u=automation | 24794 1566046265.29703: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,297 p=24794 u=automation | 24794 1566046265.29720: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,297 p=24794 u=automation | 24794 1566046265.29737: done building task lists
2019-08-17 08:51:05,297 p=24794 u=automation | 24794 1566046265.29752: counting tasks in each state of execution
2019-08-17 08:51:05,297 p=24794 u=automation | 24794 1566046265.29770: done counting tasks in each state of execution:
num_setups: 0
num_tasks: 4
num_rescue: 0
num_always: 0
2019-08-17 08:51:05,297 p=24794 u=automation | 24794 1566046265.29790: advancing hosts in ITERATING_TASKS
2019-08-17 08:51:05,298 p=24794 u=automation | 24794 1566046265.29806: starting to advance hosts
2019-08-17 08:51:05,298 p=24794 u=automation | 24794 1566046265.29822: getting the next task for host n95ksw1
2019-08-17 08:51:05,298 p=24794 u=automation | 24794 1566046265.29840: done getting next task for host n95ksw1
2019-08-17 08:51:05,298 p=24794 u=automation | 24794 1566046265.29862: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,298 p=24794 u=automation | 24794 1566046265.29879: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,299 p=24794 u=automation | 24794 1566046265.29898: getting the next task for host n95ksw2
2019-08-17 08:51:05,299 p=24794 u=automation | 24794 1566046265.29916: done getting next task for host n95ksw2
2019-08-17 08:51:05,299 p=24794 u=automation | 24794 1566046265.29935: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,299 p=24794 u=automation | 24794 1566046265.29952: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,299 p=24794 u=automation | 24794 1566046265.29969: getting the next task for host n7ksw2
2019-08-17 08:51:05,299 p=24794 u=automation | 24794 1566046265.29989: done getting next task for host n7ksw2
2019-08-17 08:51:05,300 p=24794 u=automation | 24794 1566046265.30006: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,300 p=24794 u=automation | 24794 1566046265.30022: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,300 p=24794 u=automation | 24794 1566046265.30039: getting the next task for host n7ksw1
2019-08-17 08:51:05,300 p=24794 u=automation | 24794 1566046265.30057: done getting next task for host n7ksw1
2019-08-17 08:51:05,300 p=24794 u=automation | 24794 1566046265.30076: ^ task is: TASK: Build VLAN
2019-08-17 08:51:05,301 p=24794 u=automation | 24794 1566046265.30094: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:05,301 p=24794 u=automation | 24794 1566046265.30110: done advancing hosts to next task
2019-08-17 08:51:05,301 p=24794 u=automation | 24794 1566046265.30135: getting variables
2019-08-17 08:51:05,301 p=24794 u=automation | 24794 1566046265.30152: in VariableManager get_vars()
2019-08-17 08:51:05,302 p=24794 u=automation | 24794 1566046265.30212: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,302 p=24794 u=automation | 24794 1566046265.30231: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,302 p=24794 u=automation | 24794 1566046265.30250: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,303 p=24794 u=automation | 24794 1566046265.30337: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,303 p=24794 u=automation | 24794 1566046265.30357: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,303 p=24794 u=automation | 24794 1566046265.30375: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,304 p=24794 u=automation | 24794 1566046265.30395: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,304 p=24794 u=automation | 24794 1566046265.30415: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,304 p=24794 u=automation | 24794 1566046265.30433: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,304 p=24794 u=automation | 24794 1566046265.30450: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,304 p=24794 u=automation | 24794 1566046265.30468: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,305 p=24794 u=automation | 24794 1566046265.30588: Calling all_inventory to load vars for n95ksw1
2019-08-17 08:51:05,306 p=24794 u=automation | 24794 1566046265.30611: Calling groups_inventory to load vars for n95ksw1
2019-08-17 08:51:05,306 p=24794 u=automation | 24794 1566046265.30633: Calling all_plugins_inventory to load vars for n95ksw1
2019-08-17 08:51:05,307 p=24794 u=automation | 24794 1566046265.30756: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py
2019-08-17 08:51:05,308 p=24794 u=automation | 24794 1566046265.30794: Calling all_plugins_play to load vars for n95ksw1
2019-08-17 08:51:05,308 p=24794 u=automation | 24794 1566046265.30829: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,308 p=24794 u=automation | 24794 1566046265.30861: Calling groups_plugins_inventory to load vars for n95ksw1
2019-08-17 08:51:05,309 p=24794 u=automation | 24794 1566046265.30899: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,309 p=24794 u=automation | 24794 1566046265.30932: Calling groups_plugins_play to load vars for n95ksw1
2019-08-17 08:51:05,309 p=24794 u=automation | 24794 1566046265.30965: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,310 p=24794 u=automation | 24794 1566046265.31017: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,310 p=24794 u=automation | 24794 1566046265.31063: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,314 p=24794 u=automation | 24794 1566046265.31372: done with get_vars()
2019-08-17 08:51:05,315 p=24794 u=automation | 24794 1566046265.31559: done getting variables
2019-08-17 08:51:05,315 p=24794 u=automation | 24794 1566046265.31585: sending task start callback, copying the task so we can template it temporarily
2019-08-17 08:51:05,316 p=24794 u=automation | 24794 1566046265.31603: done copying, going to template now
2019-08-17 08:51:05,316 p=24794 u=automation | 24794 1566046265.31622: done templating
2019-08-17 08:51:05,316 p=24794 u=automation | 24794 1566046265.31639: here goes the callback...
2019-08-17 08:51:05,316 p=24794 u=automation | TASK [Build VLAN] ***********************************************************************************************************************************
2019-08-17 08:51:05,316 p=24794 u=automation | 24794 1566046265.31678: sending task start callback
2019-08-17 08:51:05,317 p=24794 u=automation | 24794 1566046265.31697: entering _queue_task() for n95ksw1/nxos_vlan
2019-08-17 08:51:05,317 p=24794 u=automation | 24794 1566046265.31715: Creating lock for nxos_vlan
2019-08-17 08:51:05,319 p=24794 u=automation | 24794 1566046265.31911: worker is 1 (out of 4 available)
2019-08-17 08:51:05,320 p=24794 u=automation | 24794 1566046265.32005: exiting _queue_task() for n95ksw1/nxos_vlan
2019-08-17 08:51:05,320 p=24794 u=automation | 24794 1566046265.32055: getting variables
2019-08-17 08:51:05,320 p=24794 u=automation | 24794 1566046265.32077: in VariableManager get_vars()
2019-08-17 08:51:05,322 p=24794 u=automation | 24794 1566046265.32190: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,322 p=24794 u=automation | 24794 1566046265.32213: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,322 p=24794 u=automation | 24794 1566046265.32235: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,323 p=24794 u=automation | 24794 1566046265.32366: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,324 p=24794 u=automation | 24794 1566046265.32392: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,324 p=24794 u=automation | 24794 1566046265.32416: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,324 p=24794 u=automation | 24804 1566046265.32401: running TaskExecutor() for n95ksw1/TASK: Build VLAN
2019-08-17 08:51:05,324 p=24794 u=automation | 24794 1566046265.32438: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,324 p=24794 u=automation | 24794 1566046265.32461: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,324 p=24794 u=automation | 24794 1566046265.32482: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,325 p=24794 u=automation | 24794 1566046265.32505: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,325 p=24794 u=automation | 24804 1566046265.32504: in run() - task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,325 p=24794 u=automation | 24794 1566046265.32524: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,325 p=24794 u=automation | 24804 1566046265.32561: calling self._execute()
2019-08-17 08:51:05,327 p=24794 u=automation | 24794 1566046265.32711: Calling all_inventory to load vars for n95ksw2
2019-08-17 08:51:05,327 p=24794 u=automation | 24794 1566046265.32741: Calling groups_inventory to load vars for n95ksw2
2019-08-17 08:51:05,327 p=24794 u=automation | 24794 1566046265.32767: Calling all_plugins_inventory to load vars for n95ksw2
2019-08-17 08:51:05,328 p=24794 u=automation | 24794 1566046265.32815: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,328 p=24794 u=automation | 24794 1566046265.32854: Calling all_plugins_play to load vars for n95ksw2
2019-08-17 08:51:05,329 p=24794 u=automation | 24794 1566046265.32892: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,329 p=24794 u=automation | 24794 1566046265.32926: Calling groups_plugins_inventory to load vars for n95ksw2
2019-08-17 08:51:05,329 p=24794 u=automation | 24794 1566046265.32965: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,330 p=24794 u=automation | 24794 1566046265.33001: Calling groups_plugins_play to load vars for n95ksw2
2019-08-17 08:51:05,330 p=24794 u=automation | 24794 1566046265.33040: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,331 p=24794 u=automation | 24794 1566046265.33096: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,331 p=24794 u=automation | 24794 1566046265.33147: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,333 p=24794 u=automation | 24794 1566046265.33386: done with get_vars()
2019-08-17 08:51:05,334 p=24794 u=automation | 24794 1566046265.33423: done getting variables
2019-08-17 08:51:05,334 p=24794 u=automation | 24794 1566046265.33449: entering _queue_task() for n95ksw2/nxos_vlan
2019-08-17 08:51:05,336 p=24794 u=automation | 24794 1566046265.33648: worker is 2 (out of 4 available)
2019-08-17 08:51:05,337 p=24794 u=automation | 24794 1566046265.33743: exiting _queue_task() for n95ksw2/nxos_vlan
2019-08-17 08:51:05,338 p=24794 u=automation | 24794 1566046265.33794: getting variables
2019-08-17 08:51:05,338 p=24794 u=automation | 24794 1566046265.33816: in VariableManager get_vars()
2019-08-17 08:51:05,339 p=24794 u=automation | 24794 1566046265.33933: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,339 p=24794 u=automation | 24794 1566046265.33956: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,339 p=24794 u=automation | 24794 1566046265.33976: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,340 p=24794 u=automation | 24794 1566046265.34085: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,340 p=24794 u=automation | 24805 1566046265.34061: running TaskExecutor() for n95ksw2/TASK: Build VLAN
2019-08-17 08:51:05,341 p=24794 u=automation | 24794 1566046265.34107: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,341 p=24794 u=automation | 24794 1566046265.34129: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,341 p=24794 u=automation | 24794 1566046265.34149: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,341 p=24794 u=automation | 24794 1566046265.34170: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,341 p=24794 u=automation | 24805 1566046265.34168: in run() - task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,342 p=24794 u=automation | 24794 1566046265.34194: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,342 p=24794 u=automation | 24794 1566046265.34215: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,342 p=24794 u=automation | 24794 1566046265.34234: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,342 p=24794 u=automation | 24805 1566046265.34238: calling self._execute()
2019-08-17 08:51:05,344 p=24794 u=automation | 24794 1566046265.34442: Calling all_inventory to load vars for n7ksw2
2019-08-17 08:51:05,344 p=24794 u=automation | 24794 1566046265.34472: Calling groups_inventory to load vars for n7ksw2
2019-08-17 08:51:05,345 p=24794 u=automation | 24794 1566046265.34498: Calling all_plugins_inventory to load vars for n7ksw2
2019-08-17 08:51:05,345 p=24794 u=automation | 24794 1566046265.34546: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,345 p=24794 u=automation | 24794 1566046265.34586: Calling all_plugins_play to load vars for n7ksw2
2019-08-17 08:51:05,346 p=24794 u=automation | 24794 1566046265.34623: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,346 p=24794 u=automation | 24794 1566046265.34658: Calling groups_plugins_inventory to load vars for n7ksw2
2019-08-17 08:51:05,347 p=24794 u=automation | 24794 1566046265.34700: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,347 p=24794 u=automation | 24794 1566046265.34734: Calling groups_plugins_play to load vars for n7ksw2
2019-08-17 08:51:05,347 p=24794 u=automation | 24794 1566046265.34771: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,348 p=24794 u=automation | 24794 1566046265.34832: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,348 p=24794 u=automation | 24794 1566046265.34883: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,350 p=24794 u=automation | 24794 1566046265.35013: done with get_vars()
2019-08-17 08:51:05,350 p=24794 u=automation | 24794 1566046265.35050: done getting variables
2019-08-17 08:51:05,350 p=24794 u=automation | 24794 1566046265.35076: entering _queue_task() for n7ksw2/nxos_vlan
2019-08-17 08:51:05,353 p=24794 u=automation | 24794 1566046265.35275: worker is 3 (out of 4 available)
2019-08-17 08:51:05,353 p=24794 u=automation | 24794 1566046265.35367: exiting _queue_task() for n7ksw2/nxos_vlan
2019-08-17 08:51:05,354 p=24794 u=automation | 24794 1566046265.35422: getting variables
2019-08-17 08:51:05,354 p=24794 u=automation | 24794 1566046265.35445: in VariableManager get_vars()
2019-08-17 08:51:05,355 p=24794 u=automation | 24794 1566046265.35557: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,355 p=24794 u=automation | 24794 1566046265.35579: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,356 p=24794 u=automation | 24794 1566046265.35602: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,357 p=24794 u=automation | 24794 1566046265.35739: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,357 p=24794 u=automation | 24794 1566046265.35763: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,357 p=24794 u=automation | 24794 1566046265.35788: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,358 p=24794 u=automation | 24794 1566046265.35807: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,358 p=24794 u=automation | 24794 1566046265.35827: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,358 p=24794 u=automation | 24794 1566046265.35847: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,358 p=24794 u=automation | 24794 1566046265.35867: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,358 p=24794 u=automation | 24794 1566046265.35890: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,360 p=24794 u=automation | 24806 1566046265.36005: running TaskExecutor() for n7ksw2/TASK: Build VLAN
2019-08-17 08:51:05,360 p=24794 u=automation | 24794 1566046265.36085: Calling all_inventory to load vars for n7ksw1
2019-08-17 08:51:05,361 p=24794 u=automation | 24794 1566046265.36113: Calling groups_inventory to load vars for n7ksw1
2019-08-17 08:51:05,361 p=24794 u=automation | 24794 1566046265.36138: Calling all_plugins_inventory to load vars for n7ksw1
2019-08-17 08:51:05,361 p=24794 u=automation | 24806 1566046265.36156: in run() - task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,361 p=24794 u=automation | 24794 1566046265.36185: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,362 p=24794 u=automation | 24794 1566046265.36222: Calling all_plugins_play to load vars for n7ksw1
2019-08-17 08:51:05,362 p=24794 u=automation | 24794 1566046265.36259: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,362 p=24794 u=automation | 24806 1566046265.36252: calling self._execute()
2019-08-17 08:51:05,363 p=24794 u=automation | 24794 1566046265.36293: Calling groups_plugins_inventory to load vars for n7ksw1
2019-08-17 08:51:05,363 p=24794 u=automation | 24794 1566046265.36332: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,363 p=24794 u=automation | 24794 1566046265.36365: Calling groups_plugins_play to load vars for n7ksw1
2019-08-17 08:51:05,364 p=24794 u=automation | 24794 1566046265.36401: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,364 p=24794 u=automation | 24794 1566046265.36456: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,365 p=24794 u=automation | 24794 1566046265.36510: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,366 p=24794 u=automation | 24794 1566046265.36629: done with get_vars()
2019-08-17 08:51:05,366 p=24794 u=automation | 24794 1566046265.36663: done getting variables
2019-08-17 08:51:05,366 p=24794 u=automation | 24794 1566046265.36690: entering _queue_task() for n7ksw1/nxos_vlan
2019-08-17 08:51:05,369 p=24794 u=automation | 24794 1566046265.36899: worker is 4 (out of 4 available)
2019-08-17 08:51:05,370 p=24794 u=automation | 24794 1566046265.36999: exiting _queue_task() for n7ksw1/nxos_vlan
2019-08-17 08:51:05,370 p=24794 u=automation | 24794 1566046265.37028: done queuing things up, now waiting for results queue to drain
2019-08-17 08:51:05,370 p=24794 u=automation | 24794 1566046265.37048: waiting for pending results...
2019-08-17 08:51:05,374 p=24794 u=automation | 24807 1566046265.37453: running TaskExecutor() for n7ksw1/TASK: Build VLAN
2019-08-17 08:51:05,375 p=24794 u=automation | 24807 1566046265.37555: in run() - task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,376 p=24794 u=automation | 24807 1566046265.37642: calling self._execute()
2019-08-17 08:51:05,398 p=24794 u=automation | 24804 1566046265.39837: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,398 p=24794 u=automation | 24804 1566046265.39876: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,399 p=24794 u=automation | 24804 1566046265.39901: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,400 p=24794 u=automation | 24804 1566046265.39996: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,400 p=24794 u=automation | 24804 1566046265.40023: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,400 p=24794 u=automation | 24804 1566046265.40044: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,400 p=24794 u=automation | 24804 1566046265.40065: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,400 p=24794 u=automation | 24804 1566046265.40088: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,401 p=24794 u=automation | 24804 1566046265.40109: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,401 p=24794 u=automation | 24804 1566046265.40129: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,401 p=24794 u=automation | 24804 1566046265.40149: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,408 p=24794 u=automation | 24804 1566046265.40856: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,408 p=24794 u=automation | 24804 1566046265.40885: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,409 p=24794 u=automation | 24804 1566046265.40907: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,409 p=24794 u=automation | 24804 1566046265.40929: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,409 p=24794 u=automation | 24804 1566046265.40950: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,409 p=24794 u=automation | 24804 1566046265.40971: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,410 p=24794 u=automation | 24804 1566046265.40993: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,410 p=24794 u=automation | 24804 1566046265.41014: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,410 p=24794 u=automation | 24804 1566046265.41057: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,410 p=24794 u=automation | 24804 1566046265.41078: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,411 p=24794 u=automation | 24804 1566046265.41101: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,413 p=24794 u=automation | 24804 1566046265.41317: trying /usr/lib/python2.7/site-packages/ansible/plugins/connection
2019-08-17 08:51:05,415 p=24794 u=automation | 24804 1566046265.41496: Loading Connection 'network_cli' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/network_cli.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,415 p=24794 u=automation | 24804 1566046265.41535: trying /usr/lib/python2.7/site-packages/ansible/plugins/shell
2019-08-17 08:51:05,416 p=24794 u=automation | 24804 1566046265.41599: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,416 p=24794 u=automation | 24804 1566046265.41626: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,416 p=24794 u=automation | 24804 1566046265.41655: Loading Connection 'local' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/local.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,416 p=24794 u=automation | 24804 1566046265.41680: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,417 p=24794 u=automation | 24804 1566046265.41707: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,417 p=24794 u=automation | 24804 1566046265.41738: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=True, class_only=True)
2019-08-17 08:51:05,420 p=24794 u=automation | 24804 1566046265.41997: trying /usr/lib/python2.7/site-packages/ansible/plugins/cliconf
2019-08-17 08:51:05,421 p=24794 u=automation | 24805 1566046265.42169: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,422 p=24794 u=automation | 24805 1566046265.42210: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,422 p=24794 u=automation | 24805 1566046265.42238: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,423 p=24794 u=automation | 24805 1566046265.42369: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,424 p=24794 u=automation | 24805 1566046265.42398: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,424 p=24794 u=automation | 24805 1566046265.42426: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,424 p=24794 u=automation | 24805 1566046265.42461: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,425 p=24794 u=automation | 24805 1566046265.42494: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,425 p=24794 u=automation | 24804 1566046265.42502: Loading Cliconf 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/cliconf/nxos.py
2019-08-17 08:51:05,425 p=24794 u=automation | 24805 1566046265.42526: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,425 p=24794 u=automation | 24805 1566046265.42551: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,426 p=24794 u=automation | 24805 1566046265.42580: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,433 p=24794 u=automation | 24805 1566046265.43317: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,433 p=24794 u=automation | 24805 1566046265.43363: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,434 p=24794 u=automation | 24805 1566046265.43393: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,434 p=24794 u=automation | 24805 1566046265.43430: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,434 p=24794 u=automation | 24805 1566046265.43465: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,435 p=24794 u=automation | 24805 1566046265.43497: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,435 p=24794 u=automation | 24805 1566046265.43523: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,435 p=24794 u=automation | 24805 1566046265.43553: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,436 p=24794 u=automation | 24805 1566046265.43611: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,436 p=24794 u=automation | 24805 1566046265.43638: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,436 p=24794 u=automation | 24805 1566046265.43668: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,437 p=24794 u=automation | 24806 1566046265.43780: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,438 p=24794 u=automation | 24806 1566046265.43809: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,438 p=24794 u=automation | 24806 1566046265.43829: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,439 p=24794 u=automation | 24806 1566046265.43923: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,439 p=24794 u=automation | 24805 1566046265.43924: trying /usr/lib/python2.7/site-packages/ansible/plugins/connection
2019-08-17 08:51:05,439 p=24794 u=automation | 24806 1566046265.43946: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,439 p=24794 u=automation | 24806 1566046265.43965: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,439 p=24794 u=automation | 24806 1566046265.43985: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,440 p=24794 u=automation | 24806 1566046265.44005: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,440 p=24794 u=automation | 24806 1566046265.44031: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,440 p=24794 u=automation | 24806 1566046265.44051: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,440 p=24794 u=automation | 24806 1566046265.44069: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,441 p=24794 u=automation | 24805 1566046265.44153: Loading Connection 'network_cli' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/network_cli.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,442 p=24794 u=automation | 24805 1566046265.44200: trying /usr/lib/python2.7/site-packages/ansible/plugins/shell
2019-08-17 08:51:05,442 p=24794 u=automation | 24805 1566046265.44261: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,443 p=24794 u=automation | 24805 1566046265.44292: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,443 p=24794 u=automation | 24805 1566046265.44326: Loading Connection 'local' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/local.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,443 p=24794 u=automation | 24805 1566046265.44354: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,444 p=24794 u=automation | 24805 1566046265.44385: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,444 p=24794 u=automation | 24805 1566046265.44437: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=True, class_only=True)
2019-08-17 08:51:05,445 p=24794 u=automation | 24806 1566046265.44531: when evaluation is False, skipping this task
2019-08-17 08:51:05,445 p=24794 u=automation | 24806 1566046265.44555: _execute() done
2019-08-17 08:51:05,445 p=24794 u=automation | 24806 1566046265.44574: dumping result to json
2019-08-17 08:51:05,446 p=24794 u=automation | 24806 1566046265.44594: done dumping result, returning
2019-08-17 08:51:05,446 p=24794 u=automation | 24806 1566046265.44616: done running TaskExecutor() for n7ksw2/TASK: Build VLAN [0050568d-840f-1d84-2d82-00000000000e]
2019-08-17 08:51:05,446 p=24794 u=automation | 24806 1566046265.44642: sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,446 p=24794 u=automation | 24806 1566046265.44684: done sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,447 p=24794 u=automation | 24806 1566046265.44700: WORKER PROCESS EXITING
2019-08-17 08:51:05,448 p=24794 u=automation | 24805 1566046265.44841: trying /usr/lib/python2.7/site-packages/ansible/plugins/cliconf
2019-08-17 08:51:05,449 p=24794 u=automation | skipping: [n7ksw2]
2019-08-17 08:51:05,452 p=24794 u=automation | 24807 1566046265.45207: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,452 p=24794 u=automation | 24807 1566046265.45235: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,452 p=24794 u=automation | 24807 1566046265.45260: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,453 p=24794 u=automation | 24807 1566046265.45386: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,454 p=24794 u=automation | 24807 1566046265.45412: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,454 p=24794 u=automation | 24807 1566046265.45434: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,454 p=24794 u=automation | 24807 1566046265.45454: Loading FilterModule 'k8s' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/k8s.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,454 p=24794 u=automation | 24807 1566046265.45475: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,455 p=24794 u=automation | 24807 1566046265.45497: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,455 p=24794 u=automation | 24807 1566046265.45518: Loading FilterModule 'urls' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urls.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,455 p=24794 u=automation | 24807 1566046265.45537: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,455 p=24794 u=automation | 24805 1566046265.45527: Loading Cliconf 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/cliconf/nxos.py
2019-08-17 08:51:05,462 p=24794 u=automation | 24807 1566046265.46250: when evaluation is False, skipping this task
2019-08-17 08:51:05,462 p=24794 u=automation | 24807 1566046265.46283: _execute() done
2019-08-17 08:51:05,463 p=24794 u=automation | 24807 1566046265.46303: dumping result to json
2019-08-17 08:51:05,463 p=24794 u=automation | 24807 1566046265.46324: done dumping result, returning
2019-08-17 08:51:05,463 p=24794 u=automation | 24807 1566046265.46346: done running TaskExecutor() for n7ksw1/TASK: Build VLAN [0050568d-840f-1d84-2d82-00000000000e]
2019-08-17 08:51:05,463 p=24794 u=automation | 24807 1566046265.46376: sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,464 p=24794 u=automation | 24807 1566046265.46434: done sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:05,465 p=24794 u=automation | 24807 1566046265.46497: WORKER PROCESS EXITING
2019-08-17 08:51:05,465 p=24794 u=automation | skipping: [n7ksw1]
2019-08-17 08:51:05,871 p=24809 u=automation | 24809 1566046265.87097: trying /usr/lib/python2.7/site-packages/ansible/plugins/connection
2019-08-17 08:51:05,894 p=24811 u=automation | 24811 1566046265.89415: trying /usr/lib/python2.7/site-packages/ansible/plugins/connection
2019-08-17 08:51:05,899 p=24809 u=automation | 24809 1566046265.89976: Loaded config def from plugin (connection/ssh)
2019-08-17 08:51:05,900 p=24809 u=automation | 24809 1566046265.90005: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,914 p=24809 u=automation | 24826 1566046265.91398: Loaded config def from plugin (connection/network_cli)
2019-08-17 08:51:05,914 p=24809 u=automation | 24826 1566046265.91483: Loading Connection 'network_cli' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/network_cli.py
2019-08-17 08:51:05,915 p=24809 u=automation | 24826 1566046265.91577: trying /usr/lib/python2.7/site-packages/ansible/plugins/shell
2019-08-17 08:51:05,920 p=24809 u=automation | 24826 1566046265.92042: trying /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments
2019-08-17 08:51:05,924 p=24811 u=automation | 24811 1566046265.92487: Loaded config def from plugin (connection/ssh)
2019-08-17 08:51:05,925 p=24811 u=automation | 24811 1566046265.92516: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=False, class_only=True)
2019-08-17 08:51:05,926 p=24809 u=automation | 24826 1566046265.92607: Loading ModuleDocFragment 'shell_common' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/shell_common.py
2019-08-17 08:51:05,928 p=24809 u=automation | 24826 1566046265.92833: Loaded config def from plugin (shell/sh)
2019-08-17 08:51:05,928 p=24809 u=automation | 24826 1566046265.92856: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py
2019-08-17 08:51:05,928 p=24809 u=automation | 24826 1566046265.92876: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,929 p=24809 u=automation | 24826 1566046265.92959: Loading Connection 'local' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/local.py
2019-08-17 08:51:05,929 p=24809 u=automation | 24826 1566046265.92984: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,930 p=24809 u=automation | 24826 1566046265.93003: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,930 p=24809 u=automation | 24826 1566046265.93027: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=True, class_only=True)
2019-08-17 08:51:05,934 p=24809 u=automation | 24826 1566046265.93485: trying /usr/lib/python2.7/site-packages/ansible/plugins/cliconf
2019-08-17 08:51:05,938 p=24809 u=automation | 24826 1566046265.93847: Loading Cliconf 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/cliconf/nxos.py
2019-08-17 08:51:05,939 p=24811 u=automation | 24829 1566046265.93877: Loaded config def from plugin (connection/network_cli)
2019-08-17 08:51:05,939 p=24811 u=automation | 24829 1566046265.93968: Loading Connection 'network_cli' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/network_cli.py
2019-08-17 08:51:05,940 p=24811 u=automation | 24829 1566046265.94047: trying /usr/lib/python2.7/site-packages/ansible/plugins/shell
2019-08-17 08:51:05,945 p=24811 u=automation | 24829 1566046265.94529: trying /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments
2019-08-17 08:51:05,945 p=24809 u=automation | 24826 1566046265.94560: Loaded config def from plugin (connection/paramiko_ssh)
2019-08-17 08:51:05,945 p=24809 u=automation | 24826 1566046265.94583: Loading Connection 'paramiko_ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/paramiko_ssh.py
2019-08-17 08:51:05,946 p=24809 u=automation | 24826 1566046265.94614: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,946 p=24809 u=automation | 24826 1566046265.94630: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,950 p=24811 u=automation | 24829 1566046265.95080: Loading ModuleDocFragment 'shell_common' from /usr/lib/python2.7/site-packages/ansible/plugins/doc_fragments/shell_common.py
2019-08-17 08:51:05,952 p=24811 u=automation | 24829 1566046265.95279: Loaded config def from plugin (shell/sh)
2019-08-17 08:51:05,953 p=24811 u=automation | 24829 1566046265.95297: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py
2019-08-17 08:51:05,953 p=24811 u=automation | 24829 1566046265.95317: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,954 p=24811 u=automation | 24829 1566046265.95397: Loading Connection 'local' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/local.py
2019-08-17 08:51:05,954 p=24811 u=automation | 24829 1566046265.95419: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,954 p=24811 u=automation | 24829 1566046265.95435: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,954 p=24811 u=automation | 24829 1566046265.95457: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=True, class_only=True)
2019-08-17 08:51:05,956 p=24826 u=automation | paramiko [172.26.141.227] starting thread (client mode): 0x3391b90L
2019-08-17 08:51:05,956 p=24826 u=automation | paramiko [172.26.141.227] Local version/idstring: SSH-2.0-paramiko_2.1.1
2019-08-17 08:51:05,959 p=24811 u=automation | 24829 1566046265.95948: trying /usr/lib/python2.7/site-packages/ansible/plugins/cliconf
2019-08-17 08:51:05,963 p=24811 u=automation | 24829 1566046265.96318: Loading Cliconf 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/cliconf/nxos.py
2019-08-17 08:51:05,970 p=24811 u=automation | 24829 1566046265.97042: Loaded config def from plugin (connection/paramiko_ssh)
2019-08-17 08:51:05,970 p=24811 u=automation | 24829 1566046265.97064: Loading Connection 'paramiko_ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/paramiko_ssh.py
2019-08-17 08:51:05,970 p=24811 u=automation | 24829 1566046265.97084: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,971 p=24811 u=automation | 24829 1566046265.97100: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2019-08-17 08:51:05,980 p=24829 u=automation | paramiko [172.26.141.228] starting thread (client mode): 0x2233b90L
2019-08-17 08:51:05,980 p=24829 u=automation | paramiko [172.26.141.228] Local version/idstring: SSH-2.0-paramiko_2.1.1
2019-08-17 08:51:06,164 p=24826 u=automation | paramiko [172.26.141.227] Remote version/idstring: SSH-2.0-OpenSSH_7.2 FIPS
2019-08-17 08:51:06,165 p=24826 u=automation | paramiko [172.26.141.227] Connected (version 2.0, client OpenSSH_7.2)
2019-08-17 08:51:06,171 p=24826 u=automation | paramiko [172.26.141.227] kex algos:[u'diffie-hellman-group14-sha1', u'ecdh-sha2-nistp256', u'ecdh-sha2-nistp384'] server key:[u'ssh-rsa'] client encrypt:[u'aes128-ctr', u'aes256-ctr'] server encrypt:[u'aes128-ctr', u'aes256-ctr'] client mac:[u'hmac-sha1'] server mac:[u'hmac-sha1'] client compress:[u'none', u'zlib@openssh.com'] server compress:[u'none', u'zlib@openssh.com'] client lang:[u''] server lang:[u''] kex follows?False
2019-08-17 08:51:06,171 p=24826 u=automation | paramiko [172.26.141.227] Kex agreed: diffie-hellman-group14-sha1
2019-08-17 08:51:06,171 p=24826 u=automation | paramiko [172.26.141.227] Cipher agreed: aes128-ctr
2019-08-17 08:51:06,171 p=24826 u=automation | paramiko [172.26.141.227] MAC agreed: hmac-sha1
2019-08-17 08:51:06,172 p=24826 u=automation | paramiko [172.26.141.227] Compression agreed: none
2019-08-17 08:51:06,183 p=24829 u=automation | paramiko [172.26.141.228] Remote version/idstring: SSH-2.0-OpenSSH_7.2 FIPS
2019-08-17 08:51:06,183 p=24829 u=automation | paramiko [172.26.141.228] Connected (version 2.0, client OpenSSH_7.2)
2019-08-17 08:51:06,190 p=24829 u=automation | paramiko [172.26.141.228] kex algos:[u'curve25519-sha256@libssh.org', u'ecdh-sha2-nistp256', u'ecdh-sha2-nistp384', u'diffie-hellman-group-exchange-sha256', u'diffie-hellman-group14-sha1'] server key:[u'ssh-rsa'] client encrypt:[u'aes128-ctr', u'aes192-ctr', u'aes256-ctr'] server encrypt:[u'aes128-ctr', u'aes192-ctr', u'aes256-ctr'] client mac:[u'hmac-sha1'] server mac:[u'hmac-sha1'] client compress:[u'none', u'zlib@openssh.com'] server compress:[u'none', u'zlib@openssh.com'] client lang:[u''] server lang:[u''] kex follows?False
2019-08-17 08:51:06,190 p=24829 u=automation | paramiko [172.26.141.228] Kex agreed: diffie-hellman-group14-sha1
2019-08-17 08:51:06,190 p=24829 u=automation | paramiko [172.26.141.228] Cipher agreed: aes128-ctr
2019-08-17 08:51:06,190 p=24829 u=automation | paramiko [172.26.141.228] MAC agreed: hmac-sha1
2019-08-17 08:51:06,190 p=24829 u=automation | paramiko [172.26.141.228] Compression agreed: none
2019-08-17 08:51:06,323 p=24826 u=automation | paramiko [172.26.141.227] kex engine KexGroup14 specified hash_algo <built-in function openssl_sha1>
2019-08-17 08:51:06,324 p=24826 u=automation | paramiko [172.26.141.227] Switch to new keys ...
2019-08-17 08:51:06,338 p=24829 u=automation | paramiko [172.26.141.228] kex engine KexGroup14 specified hash_algo <built-in function openssl_sha1>
2019-08-17 08:51:06,339 p=24829 u=automation | paramiko [172.26.141.228] Switch to new keys ...
2019-08-17 08:51:06,442 p=24826 u=automation | paramiko [172.26.141.227] userauth is OK
2019-08-17 08:51:06,456 p=24829 u=automation | paramiko [172.26.141.228] userauth is OK
2019-08-17 08:51:06,466 p=24826 u=automation | paramiko [172.26.141.227] Auth banner: User Access Verification
2019-08-17 08:51:06,467 p=24826 u=automation | paramiko [172.26.141.227] Authentication (password) successful!
2019-08-17 08:51:06,474 p=24826 u=automation | paramiko [172.26.141.227] [chan 0] Max packet in: 32768 bytes
2019-08-17 08:51:06,481 p=24829 u=automation | paramiko [172.26.141.228] Auth banner: User Access Verification
2019-08-17 08:51:06,481 p=24829 u=automation | paramiko [172.26.141.228] Authentication (password) successful!
2019-08-17 08:51:06,482 p=24826 u=automation | paramiko [172.26.141.227] Received global request "hostkeys-00@openssh.com"
2019-08-17 08:51:06,482 p=24826 u=automation | paramiko [172.26.141.227] Rejecting "hostkeys-00@openssh.com" global request from server.
2019-08-17 08:51:06,510 p=24829 u=automation | paramiko [172.26.141.228] [chan 0] Max packet in: 32768 bytes
2019-08-17 08:51:06,517 p=24829 u=automation | paramiko [172.26.141.228] Received global request "hostkeys-00@openssh.com"
2019-08-17 08:51:06,518 p=24829 u=automation | paramiko [172.26.141.228] Rejecting "hostkeys-00@openssh.com" global request from server.
2019-08-17 08:51:06,529 p=24826 u=automation | paramiko [172.26.141.227] [chan 0] Max packet out: 32768 bytes
2019-08-17 08:51:06,529 p=24826 u=automation | paramiko [172.26.141.227] Secsh channel 0 opened.
2019-08-17 08:51:06,546 p=24826 u=automation | paramiko [172.26.141.227] [chan 0] Sesch channel 0 request ok
2019-08-17 08:51:06,553 p=24826 u=automation | paramiko [172.26.141.227] [chan 0] Sesch channel 0 request ok
2019-08-17 08:51:06,559 p=24809 u=automation | 24826 1566046266.55919: trying /usr/lib/python2.7/site-packages/ansible/plugins/terminal
2019-08-17 08:51:06,563 p=24829 u=automation | paramiko [172.26.141.228] [chan 0] Max packet out: 32768 bytes
2019-08-17 08:51:06,563 p=24829 u=automation | paramiko [172.26.141.228] Secsh channel 0 opened.
2019-08-17 08:51:06,564 p=24809 u=automation | 24826 1566046266.56469: Loading TerminalModule 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/terminal/nxos.py
2019-08-17 08:51:06,582 p=24829 u=automation | paramiko [172.26.141.228] [chan 0] Sesch channel 0 request ok
2019-08-17 08:51:06,589 p=24829 u=automation | paramiko [172.26.141.228] [chan 0] Sesch channel 0 request ok
2019-08-17 08:51:06,594 p=24811 u=automation | 24829 1566046266.59489: trying /usr/lib/python2.7/site-packages/ansible/plugins/terminal
2019-08-17 08:51:06,600 p=24811 u=automation | 24829 1566046266.60017: Loading TerminalModule 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/terminal/nxos.py
2019-08-17 08:51:07,213 p=24794 u=automation | network_os is set to nxos
2019-08-17 08:51:07,219 p=24794 u=automation | 24804 1566046267.21928: Loading ActionModule 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/action/nxos.py
2019-08-17 08:51:07,220 p=24794 u=automation | 24804 1566046267.21985: starting attempt loop
2019-08-17 08:51:07,220 p=24794 u=automation | 24804 1566046267.22036: running the handler
2019-08-17 08:51:07,222 p=24794 u=automation | 24804 1566046267.22232: _low_level_execute_command(): starting
2019-08-17 08:51:07,222 p=24794 u=automation | 24804 1566046267.22270: _low_level_execute_command(): executing: /bin/sh -c '( umask 77 && mkdir -p "` echo /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955 `" && echo ansible-tmp-1566046267.22-78072601525955="` echo /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955 `" ) && sleep 0'
2019-08-17 08:51:07,223 p=24794 u=automation | 24804 1566046267.22312: in local.exec_command()
2019-08-17 08:51:07,223 p=24794 u=automation | 24804 1566046267.22346: opening command with Popen()
2019-08-17 08:51:07,228 p=24794 u=automation | 24804 1566046267.22860: done running command with Popen()
2019-08-17 08:51:07,229 p=24794 u=automation | 24804 1566046267.22908: getting output with communicate()
2019-08-17 08:51:07,232 p=24794 u=automation | network_os is set to nxos
2019-08-17 08:51:07,234 p=24794 u=automation | 24804 1566046267.23460: done communicating
2019-08-17 08:51:07,234 p=24794 u=automation | 24804 1566046267.23486: done with local.exec_command()
2019-08-17 08:51:07,235 p=24794 u=automation | 24804 1566046267.23508: _low_level_execute_command() done: rc=0, stdout=ansible-tmp-1566046267.22-78072601525955=/home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955
, stderr=
2019-08-17 08:51:07,235 p=24794 u=automation | 24804 1566046267.23583: ANSIBALLZ: Using lock for nxos_vlan
2019-08-17 08:51:07,236 p=24794 u=automation | 24804 1566046267.23599: ANSIBALLZ: Acquiring lock
2019-08-17 08:51:07,236 p=24794 u=automation | 24804 1566046267.23616: ANSIBALLZ: Lock acquired: 51391632
2019-08-17 08:51:07,236 p=24794 u=automation | 24805 1566046267.23622: Loading ActionModule 'nxos' from /usr/lib/python2.7/site-packages/ansible/plugins/action/nxos.py
2019-08-17 08:51:07,236 p=24794 u=automation | 24804 1566046267.23633: ANSIBALLZ: Creating module
2019-08-17 08:51:07,236 p=24794 u=automation | 24805 1566046267.23650: starting attempt loop
2019-08-17 08:51:07,236 p=24794 u=automation | 24805 1566046267.23666: running the handler
2019-08-17 08:51:07,238 p=24794 u=automation | 24805 1566046267.23796: _low_level_execute_command(): starting
2019-08-17 08:51:07,238 p=24794 u=automation | 24805 1566046267.23823: _low_level_execute_command(): executing: /bin/sh -c '( umask 77 && mkdir -p "` echo /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522 `" && echo ansible-tmp-1566046267.24-52106563401522="` echo /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522 `" ) && sleep 0'
2019-08-17 08:51:07,238 p=24794 u=automation | 24805 1566046267.23852: in local.exec_command()
2019-08-17 08:51:07,238 p=24794 u=automation | 24805 1566046267.23871: opening command with Popen()
2019-08-17 08:51:07,242 p=24794 u=automation | 24805 1566046267.24205: done running command with Popen()
2019-08-17 08:51:07,242 p=24794 u=automation | 24805 1566046267.24240: getting output with communicate()
2019-08-17 08:51:07,247 p=24794 u=automation | 24805 1566046267.24731: done communicating
2019-08-17 08:51:07,247 p=24794 u=automation | 24805 1566046267.24750: done with local.exec_command()
2019-08-17 08:51:07,247 p=24794 u=automation | 24805 1566046267.24773: _low_level_execute_command() done: rc=0, stdout=ansible-tmp-1566046267.24-52106563401522=/home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522
, stderr=
2019-08-17 08:51:07,248 p=24794 u=automation | 24805 1566046267.24841: ANSIBALLZ: Using lock for nxos_vlan
2019-08-17 08:51:07,248 p=24794 u=automation | 24805 1566046267.24858: ANSIBALLZ: Acquiring lock
2019-08-17 08:51:07,580 p=24794 u=automation | 24804 1566046267.58063: ANSIBALLZ: Writing module
2019-08-17 08:51:07,581 p=24794 u=automation | 24804 1566046267.58122: ANSIBALLZ: Renaming module
2019-08-17 08:51:07,581 p=24794 u=automation | 24804 1566046267.58146: ANSIBALLZ: Done creating module
2019-08-17 08:51:07,581 p=24794 u=automation | 24805 1566046267.58172: ANSIBALLZ: Lock acquired: 51391632
2019-08-17 08:51:07,582 p=24794 u=automation | 24805 1566046267.58204: ANSIBALLZ: Reading module after lock
2019-08-17 08:51:07,582 p=24794 u=automation | 24804 1566046267.58234: _low_level_execute_command(): starting
2019-08-17 08:51:07,582 p=24794 u=automation | 24804 1566046267.58255: _low_level_execute_command(): executing: /bin/sh -c 'echo PLATFORM; uname; echo FOUND; command -v '"'"'/usr/bin/python'"'"'; command -v '"'"'python3.7'"'"'; command -v '"'"'python3.6'"'"'; command -v '"'"'python3.5'"'"'; command -v '"'"'python2.7'"'"'; command -v '"'"'python2.6'"'"'; command -v '"'"'/usr/libexec/platform-python'"'"'; command -v '"'"'/usr/bin/python3'"'"'; command -v '"'"'python'"'"'; echo ENDFOUND && sleep 0'
2019-08-17 08:51:07,582 p=24794 u=automation | 24804 1566046267.58272: in local.exec_command()
2019-08-17 08:51:07,582 p=24794 u=automation | 24804 1566046267.58292: opening command with Popen()
2019-08-17 08:51:07,583 p=24794 u=automation | 24805 1566046267.58328: _low_level_execute_command(): starting
2019-08-17 08:51:07,583 p=24794 u=automation | 24805 1566046267.58349: _low_level_execute_command(): executing: /bin/sh -c 'echo PLATFORM; uname; echo FOUND; command -v '"'"'/usr/bin/python'"'"'; command -v '"'"'python3.7'"'"'; command -v '"'"'python3.6'"'"'; command -v '"'"'python3.5'"'"'; command -v '"'"'python2.7'"'"'; command -v '"'"'python2.6'"'"'; command -v '"'"'/usr/libexec/platform-python'"'"'; command -v '"'"'/usr/bin/python3'"'"'; command -v '"'"'python'"'"'; echo ENDFOUND && sleep 0'
2019-08-17 08:51:07,583 p=24794 u=automation | 24805 1566046267.58367: in local.exec_command()
2019-08-17 08:51:07,583 p=24794 u=automation | 24805 1566046267.58388: opening command with Popen()
2019-08-17 08:51:07,586 p=24794 u=automation | 24804 1566046267.58668: done running command with Popen()
2019-08-17 08:51:07,587 p=24794 u=automation | 24804 1566046267.58706: getting output with communicate()
2019-08-17 08:51:07,587 p=24794 u=automation | 24805 1566046267.58741: done running command with Popen()
2019-08-17 08:51:07,587 p=24794 u=automation | 24805 1566046267.58775: getting output with communicate()
2019-08-17 08:51:07,591 p=24794 u=automation | 24804 1566046267.59103: done communicating
2019-08-17 08:51:07,591 p=24794 u=automation | 24804 1566046267.59122: done with local.exec_command()
2019-08-17 08:51:07,591 p=24794 u=automation | 24804 1566046267.59143: _low_level_execute_command() done: rc=0, stdout=PLATFORM
Linux
FOUND
/usr/bin/python
/usr/local/bin/python3.7
/usr/bin/python2.7
/usr/bin/python
ENDFOUND
, stderr=
2019-08-17 08:51:07,591 p=24794 u=automation | 24805 1566046267.59147: done communicating
2019-08-17 08:51:07,591 p=24794 u=automation | 24804 1566046267.59165 [n95ksw1]: found interpreters: [u'/usr/bin/python', u'/usr/local/bin/python3.7', u'/usr/bin/python2.7', u'/usr/bin/python']
2019-08-17 08:51:07,591 p=24794 u=automation | 24805 1566046267.59171: done with local.exec_command()
2019-08-17 08:51:07,592 p=24794 u=automation | 24805 1566046267.59203: _low_level_execute_command() done: rc=0, stdout=PLATFORM
Linux
FOUND
/usr/bin/python
/usr/local/bin/python3.7
/usr/bin/python2.7
/usr/bin/python
ENDFOUND
, stderr=
2019-08-17 08:51:07,592 p=24794 u=automation | 24805 1566046267.59229 [n95ksw2]: found interpreters: [u'/usr/bin/python', u'/usr/local/bin/python3.7', u'/usr/bin/python2.7', u'/usr/bin/python']
2019-08-17 08:51:07,592 p=24794 u=automation | 24804 1566046267.59229: _low_level_execute_command(): starting
2019-08-17 08:51:07,592 p=24794 u=automation | 24804 1566046267.59257: _low_level_execute_command(): executing: /bin/sh -c '/usr/bin/python && sleep 0'
2019-08-17 08:51:07,592 p=24794 u=automation | 24804 1566046267.59275: in local.exec_command()
2019-08-17 08:51:07,592 p=24794 u=automation | 24805 1566046267.59278: _low_level_execute_command(): starting
2019-08-17 08:51:07,593 p=24794 u=automation | 24804 1566046267.59296: opening command with Popen()
2019-08-17 08:51:07,593 p=24794 u=automation | 24805 1566046267.59310: _low_level_execute_command(): executing: /bin/sh -c '/usr/bin/python && sleep 0'
2019-08-17 08:51:07,593 p=24794 u=automation | 24805 1566046267.59340: in local.exec_command()
2019-08-17 08:51:07,593 p=24794 u=automation | 24805 1566046267.59359: opening command with Popen()
2019-08-17 08:51:07,596 p=24794 u=automation | 24804 1566046267.59652: done running command with Popen()
2019-08-17 08:51:07,596 p=24794 u=automation | 24805 1566046267.59670: done running command with Popen()
2019-08-17 08:51:07,596 p=24794 u=automation | 24804 1566046267.59689: getting output with communicate()
2019-08-17 08:51:07,597 p=24794 u=automation | 24805 1566046267.59706: getting output with communicate()
2019-08-17 08:51:07,622 p=24794 u=automation | 24805 1566046267.62237: done communicating
2019-08-17 08:51:07,622 p=24794 u=automation | 24804 1566046267.62245: done communicating
2019-08-17 08:51:07,622 p=24794 u=automation | 24805 1566046267.62267: done with local.exec_command()
2019-08-17 08:51:07,622 p=24794 u=automation | 24804 1566046267.62274: done with local.exec_command()
2019-08-17 08:51:07,623 p=24794 u=automation | 24805 1566046267.62292: _low_level_execute_command() done: rc=0, stdout={"osrelease_content": "NAME=\"Red Hat Enterprise Linux Server\"\nVERSION=\"7.4 (Maipo)\"\nID=\"rhel\"\nID_LIKE=\"fedora\"\nVARIANT=\"Server\"\nVARIANT_ID=\"server\"\nVERSION_ID=\"7.4\"\nPRETTY_NAME=\"Red Hat Enterprise Linux Server 7.4 (Maipo)\"\nANSI_COLOR=\"0;31\"\nCPE_NAME=\"cpe:/o:redhat:enterprise_linux:7.4:GA:server\"\nHOME_URL=\"https://www.redhat.com/\"\nBUG_REPORT_URL=\"https://bugzilla.redhat.com/\"\n\nREDHAT_BUGZILLA_PRODUCT=\"Red Hat Enterprise Linux 7\"\nREDHAT_BUGZILLA_PRODUCT_VERSION=7.4\nREDHAT_SUPPORT_PRODUCT=\"Red Hat Enterprise Linux\"\nREDHAT_SUPPORT_PRODUCT_VERSION=\"7.4\"\n", "platform_dist_result": ["redhat", "7.4", "Maipo"]}
, stderr=
2019-08-17 08:51:07,623 p=24794 u=automation | 24804 1566046267.62298: _low_level_execute_command() done: rc=0, stdout={"osrelease_content": "NAME=\"Red Hat Enterprise Linux Server\"\nVERSION=\"7.4 (Maipo)\"\nID=\"rhel\"\nID_LIKE=\"fedora\"\nVARIANT=\"Server\"\nVARIANT_ID=\"server\"\nVERSION_ID=\"7.4\"\nPRETTY_NAME=\"Red Hat Enterprise Linux Server 7.4 (Maipo)\"\nANSI_COLOR=\"0;31\"\nCPE_NAME=\"cpe:/o:redhat:enterprise_linux:7.4:GA:server\"\nHOME_URL=\"https://www.redhat.com/\"\nBUG_REPORT_URL=\"https://bugzilla.redhat.com/\"\n\nREDHAT_BUGZILLA_PRODUCT=\"Red Hat Enterprise Linux 7\"\nREDHAT_BUGZILLA_PRODUCT_VERSION=7.4\nREDHAT_SUPPORT_PRODUCT=\"Red Hat Enterprise Linux\"\nREDHAT_SUPPORT_PRODUCT_VERSION=\"7.4\"\n", "platform_dist_result": ["redhat", "7.4", "Maipo"]}
, stderr=
2019-08-17 08:51:07,623 p=24794 u=automation | 24805 1566046267.62367: ANSIBALLZ: using cached module: /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansiballz_cache/nxos_vlan-ZIP_DEFLATED
2019-08-17 08:51:07,623 p=24794 u=automation | 24804 1566046267.62370: ANSIBALLZ: using cached module: /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansiballz_cache/nxos_vlan-ZIP_DEFLATED
2019-08-17 08:51:07,625 p=24794 u=automation | 24805 1566046267.62549: transferring module to remote /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522/AnsiballZ_nxos_vlan.py
2019-08-17 08:51:07,625 p=24794 u=automation | 24804 1566046267.62563: transferring module to remote /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955/AnsiballZ_nxos_vlan.py
2019-08-17 08:51:07,626 p=24794 u=automation | 24804 1566046267.62669: done transferring module to remote
2019-08-17 08:51:07,626 p=24794 u=automation | 24805 1566046267.62667: done transferring module to remote
2019-08-17 08:51:07,627 p=24794 u=automation | 24804 1566046267.62704: _low_level_execute_command(): starting
2019-08-17 08:51:07,627 p=24794 u=automation | 24805 1566046267.62704: _low_level_execute_command(): starting
2019-08-17 08:51:07,627 p=24794 u=automation | 24804 1566046267.62723: _low_level_execute_command(): executing: /bin/sh -c 'chmod u+x /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955/ /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955/AnsiballZ_nxos_vlan.py && sleep 0'
2019-08-17 08:51:07,627 p=24794 u=automation | 24805 1566046267.62723: _low_level_execute_command(): executing: /bin/sh -c 'chmod u+x /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522/ /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522/AnsiballZ_nxos_vlan.py && sleep 0'
2019-08-17 08:51:07,627 p=24794 u=automation | 24805 1566046267.62740: in local.exec_command()
2019-08-17 08:51:07,627 p=24794 u=automation | 24804 1566046267.62740: in local.exec_command()
2019-08-17 08:51:07,627 p=24794 u=automation | 24805 1566046267.62759: opening command with Popen()
2019-08-17 08:51:07,627 p=24794 u=automation | 24804 1566046267.62760: opening command with Popen()
2019-08-17 08:51:07,631 p=24794 u=automation | 24804 1566046267.63149: done running command with Popen()
2019-08-17 08:51:07,631 p=24794 u=automation | 24804 1566046267.63190: getting output with communicate()
2019-08-17 08:51:07,631 p=24794 u=automation | 24805 1566046267.63157: done running command with Popen()
2019-08-17 08:51:07,632 p=24794 u=automation | 24805 1566046267.63219: getting output with communicate()
2019-08-17 08:51:07,635 p=24794 u=automation | 24804 1566046267.63542: done communicating
2019-08-17 08:51:07,635 p=24794 u=automation | 24805 1566046267.63541: done communicating
2019-08-17 08:51:07,635 p=24794 u=automation | 24805 1566046267.63561: done with local.exec_command()
2019-08-17 08:51:07,635 p=24794 u=automation | 24804 1566046267.63560: done with local.exec_command()
2019-08-17 08:51:07,635 p=24794 u=automation | 24805 1566046267.63583: _low_level_execute_command() done: rc=0, stdout=, stderr=
2019-08-17 08:51:07,635 p=24794 u=automation | 24804 1566046267.63586: _low_level_execute_command() done: rc=0, stdout=, stderr=
2019-08-17 08:51:07,636 p=24794 u=automation | 24804 1566046267.63604: _low_level_execute_command(): starting
2019-08-17 08:51:07,636 p=24794 u=automation | 24805 1566046267.63601: _low_level_execute_command(): starting
2019-08-17 08:51:07,636 p=24794 u=automation | 24804 1566046267.63628: _low_level_execute_command(): executing: /bin/sh -c '/usr/bin/python /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955/AnsiballZ_nxos_vlan.py && sleep 0'
2019-08-17 08:51:07,636 p=24794 u=automation | 24805 1566046267.63629: _low_level_execute_command(): executing: /bin/sh -c '/usr/bin/python /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522/AnsiballZ_nxos_vlan.py && sleep 0'
2019-08-17 08:51:07,636 p=24794 u=automation | 24804 1566046267.63645: in local.exec_command()
2019-08-17 08:51:07,636 p=24794 u=automation | 24805 1566046267.63648: in local.exec_command()
2019-08-17 08:51:07,636 p=24794 u=automation | 24804 1566046267.63665: opening command with Popen()
2019-08-17 08:51:07,636 p=24794 u=automation | 24805 1566046267.63668: opening command with Popen()
2019-08-17 08:51:07,640 p=24794 u=automation | 24805 1566046267.64034: done running command with Popen()
2019-08-17 08:51:07,640 p=24794 u=automation | 24805 1566046267.64087: getting output with communicate()
2019-08-17 08:51:07,640 p=24794 u=automation | 24804 1566046267.64068: done running command with Popen()
2019-08-17 08:51:07,641 p=24794 u=automation | 24804 1566046267.64126: getting output with communicate()
2019-08-17 08:51:12,095 p=24794 u=automation | 24805 1566046272.09526: done communicating
2019-08-17 08:51:12,095 p=24794 u=automation | 24805 1566046272.09562: done with local.exec_command()
2019-08-17 08:51:12,095 p=24794 u=automation | 24805 1566046272.09589: _low_level_execute_command() done: rc=1, stdout=
{"msg": "switchport\r\r\n ^\r\n% Invalid command at '^' marker.\r\n\rJER-LAB-95KSW2(config-if)# ", "failed": true, "exception": "WARNING: The below traceback may *not* be related to the actual failure.\n File \"/tmp/ansible_nxos_vlan_payload_fEzlvl/ansible_nxos_vlan_payload.zip/ansible/module_utils/network/nxos/nxos.py\", line 187, in load_config\n resp = connection.edit_config(config, replace=replace)\n File \"/tmp/ansible_nxos_vlan_payload_fEzlvl/ansible_nxos_vlan_payload.zip/ansible/module_utils/connection.py\", line 186, in __rpc__\n raise ConnectionError(to_text(msg, errors='surrogate_then_replace'), code=code)\n", "invocation": {"module_args": {"authorize": null, "associated_interfaces": null, "use_ssl": null, "port": null, "vlan_id": 403, "vlan_range": null, "auth_pass": null, "delay": 10, "state": "present", "transport": null, "provider": null, "username": null, "vlan_state": "active", "interfaces": ["Eth2/10"], "host": null, "admin_state": "up", "aggregate": null, "password": null, "ssh_keyfile": null, "name": "tennis_403", "mapped_vni": null, "purge": false, "mode": "ce", "timeout": null, "validate_certs": null}}}
, stderr=
2019-08-17 08:51:12,097 p=24794 u=automation | 24805 1566046272.09782: done with _execute_module (nxos_vlan, {'_ansible_version': '2.8.3', '_ansible_selinux_special_fs': ['fuse', 'nfs', 'vboxsf', 'ramfs', '9p'], '_ansible_no_log': False, u'interfaces': [u'Eth2/10'], '_ansible_remote_tmp': u'~/.ansible/tmp', '_ansible_verbosity': 0, '_ansible_keep_remote_files': False, '_ansible_syslog_facility': u'LOG_USER', '_ansible_socket': u'/home/automation/.ansible/pc/e669080584', u'state': u'present', '_ansible_diff': False, '_ansible_debug': True, '_ansible_shell_executable': u'/bin/sh', '_ansible_check_mode': False, '_ansible_tmpdir': u'/home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522/', '_ansible_string_conversion_action': u'warn', '_ansible_module_name': u'nxos_vlan', u'vlan_id': 403, u'name': u'tennis_403'})
2019-08-17 08:51:12,098 p=24794 u=automation | 24805 1566046272.09838: _low_level_execute_command(): starting
2019-08-17 08:51:12,098 p=24794 u=automation | 24805 1566046272.09869: _low_level_execute_command(): executing: /bin/sh -c 'rm -f -r /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.24-52106563401522/ > /dev/null 2>&1 && sleep 0'
2019-08-17 08:51:12,098 p=24794 u=automation | 24805 1566046272.09889: in local.exec_command()
2019-08-17 08:51:12,099 p=24794 u=automation | 24805 1566046272.09910: opening command with Popen()
2019-08-17 08:51:12,102 p=24794 u=automation | 24805 1566046272.10274: done running command with Popen()
2019-08-17 08:51:12,103 p=24794 u=automation | 24805 1566046272.10311: getting output with communicate()
2019-08-17 08:51:12,107 p=24794 u=automation | 24805 1566046272.10749: done communicating
2019-08-17 08:51:12,107 p=24794 u=automation | 24805 1566046272.10767: done with local.exec_command()
2019-08-17 08:51:12,107 p=24794 u=automation | 24805 1566046272.10789: _low_level_execute_command() done: rc=0, stdout=, stderr=
2019-08-17 08:51:12,108 p=24794 u=automation | 24805 1566046272.10807: handler run complete
2019-08-17 08:51:12,109 p=24794 u=automation | 24805 1566046272.10990: attempt loop complete, returning result
2019-08-17 08:51:12,110 p=24794 u=automation | 24805 1566046272.11010: _execute() done
2019-08-17 08:51:12,110 p=24794 u=automation | 24805 1566046272.11031: dumping result to json
2019-08-17 08:51:12,110 p=24794 u=automation | 24805 1566046272.11053: done dumping result, returning
2019-08-17 08:51:12,110 p=24794 u=automation | 24805 1566046272.11075: done running TaskExecutor() for n95ksw2/TASK: Build VLAN [0050568d-840f-1d84-2d82-00000000000e]
2019-08-17 08:51:12,111 p=24794 u=automation | 24805 1566046272.11110: sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:12,112 p=24794 u=automation | 24805 1566046272.11169: done sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:12,112 p=24794 u=automation | 24805 1566046272.11249: WORKER PROCESS EXITING
2019-08-17 08:51:12,113 p=24794 u=automation | 24794 1566046272.11360: marking n95ksw2 as failed
2019-08-17 08:51:12,113 p=24794 u=automation | 24794 1566046272.11391: marking host n95ksw2 failed, current state: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:12,114 p=24794 u=automation | 24794 1566046272.11411: ^ failed state is now: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_COMPLETE, fail_state=FAILED_TASKS, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:12,114 p=24794 u=automation | 24794 1566046272.11485: getting the next task for host n95ksw2
2019-08-17 08:51:12,115 p=24794 u=automation | 24794 1566046272.11506: host n95ksw2 is done iterating, returning
2019-08-17 08:51:12,115 p=24794 u=automation | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: raise ConnectionError(to_text(msg, errors='surrogate_then_replace'), code=code)
2019-08-17 08:51:12,116 p=24794 u=automation | fatal: [n95ksw2]: FAILED! => {"ansible_facts": {"discovered_interpreter_python": "/usr/bin/python"}, "changed": false, "msg": "switchport\r\r\n ^\r\n% Invalid command at '^' marker.\r\n\rJER-LAB-95KSW2(config-if)# "}
2019-08-17 08:51:12,170 p=24794 u=automation | 24804 1566046272.17044: done communicating
2019-08-17 08:51:12,170 p=24794 u=automation | 24804 1566046272.17079: done with local.exec_command()
2019-08-17 08:51:12,171 p=24794 u=automation | 24804 1566046272.17106: _low_level_execute_command() done: rc=1, stdout=
{"msg": "switchport\r\r\n ^\r\n% Invalid command at '^' marker.\r\n\rJER-LAB-N95KSW1(config-if)# ", "failed": true, "exception": "WARNING: The below traceback may *not* be related to the actual failure.\n File \"/tmp/ansible_nxos_vlan_payload_Sqm7DP/ansible_nxos_vlan_payload.zip/ansible/module_utils/network/nxos/nxos.py\", line 187, in load_config\n resp = connection.edit_config(config, replace=replace)\n File \"/tmp/ansible_nxos_vlan_payload_Sqm7DP/ansible_nxos_vlan_payload.zip/ansible/module_utils/connection.py\", line 186, in __rpc__\n raise ConnectionError(to_text(msg, errors='surrogate_then_replace'), code=code)\n", "invocation": {"module_args": {"authorize": null, "associated_interfaces": null, "use_ssl": null, "port": null, "vlan_id": 403, "vlan_range": null, "auth_pass": null, "delay": 10, "state": "present", "transport": null, "provider": null, "username": null, "vlan_state": "active", "interfaces": ["Eth1/30", "Eth1/31", "Eth1/33"], "host": null, "admin_state": "up", "aggregate": null, "password": null, "ssh_keyfile": null, "name": "tennis_403", "mapped_vni": null, "purge": false, "mode": "ce", "timeout": null, "validate_certs": null}}}
, stderr=
2019-08-17 08:51:12,172 p=24794 u=automation | 24804 1566046272.17287: done with _execute_module (nxos_vlan, {'_ansible_version': '2.8.3', '_ansible_selinux_special_fs': ['fuse', 'nfs', 'vboxsf', 'ramfs', '9p'], '_ansible_no_log': False, u'interfaces': [u'Eth1/30', u'Eth1/31', u'Eth1/33'], '_ansible_remote_tmp': u'~/.ansible/tmp', '_ansible_verbosity': 0, '_ansible_keep_remote_files': False, '_ansible_syslog_facility': u'LOG_USER', '_ansible_socket': u'/home/automation/.ansible/pc/dd8d084669', u'state': u'present', '_ansible_diff': False, '_ansible_debug': True, '_ansible_shell_executable': u'/bin/sh', '_ansible_check_mode': False, '_ansible_tmpdir': u'/home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955/', '_ansible_string_conversion_action': u'warn', '_ansible_module_name': u'nxos_vlan', u'vlan_id': 403, u'name': u'tennis_403'})
2019-08-17 08:51:12,173 p=24794 u=automation | 24804 1566046272.17318: _low_level_execute_command(): starting
2019-08-17 08:51:12,173 p=24794 u=automation | 24804 1566046272.17340: _low_level_execute_command(): executing: /bin/sh -c 'rm -f -r /home/automation/.ansible/tmp/ansible-local-24794UAc5AB/ansible-tmp-1566046267.22-78072601525955/ > /dev/null 2>&1 && sleep 0'
2019-08-17 08:51:12,173 p=24794 u=automation | 24804 1566046272.17357: in local.exec_command()
2019-08-17 08:51:12,173 p=24794 u=automation | 24804 1566046272.17377: opening command with Popen()
2019-08-17 08:51:12,177 p=24794 u=automation | 24804 1566046272.17767: done running command with Popen()
2019-08-17 08:51:12,178 p=24794 u=automation | 24804 1566046272.17812: getting output with communicate()
2019-08-17 08:51:12,181 p=24794 u=automation | 24804 1566046272.18174: done communicating
2019-08-17 08:51:12,182 p=24794 u=automation | 24804 1566046272.18201: done with local.exec_command()
2019-08-17 08:51:12,182 p=24794 u=automation | 24804 1566046272.18228: _low_level_execute_command() done: rc=0, stdout=, stderr=
2019-08-17 08:51:12,182 p=24794 u=automation | 24804 1566046272.18253: handler run complete
2019-08-17 08:51:12,185 p=24794 u=automation | 24804 1566046272.18562: attempt loop complete, returning result
2019-08-17 08:51:12,185 p=24794 u=automation | 24804 1566046272.18588: _execute() done
2019-08-17 08:51:12,186 p=24794 u=automation | 24804 1566046272.18606: dumping result to json
2019-08-17 08:51:12,186 p=24794 u=automation | 24804 1566046272.18630: done dumping result, returning
2019-08-17 08:51:12,186 p=24794 u=automation | 24804 1566046272.18651: done running TaskExecutor() for n95ksw1/TASK: Build VLAN [0050568d-840f-1d84-2d82-00000000000e]
2019-08-17 08:51:12,186 p=24794 u=automation | 24804 1566046272.18678: sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:12,188 p=24794 u=automation | 24804 1566046272.18741: done sending task result for task 0050568d-840f-1d84-2d82-00000000000e
2019-08-17 08:51:12,188 p=24794 u=automation | 24804 1566046272.18833: WORKER PROCESS EXITING
2019-08-17 08:51:12,189 p=24794 u=automation | 24794 1566046272.18940: marking n95ksw1 as failed
2019-08-17 08:51:12,189 p=24794 u=automation | 24794 1566046272.18969: marking host n95ksw1 failed, current state: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:12,190 p=24794 u=automation | 24794 1566046272.19000: ^ failed state is now: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=ITERATING_COMPLETE, fail_state=FAILED_TASKS, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:12,190 p=24794 u=automation | 24794 1566046272.19018: getting the next task for host n95ksw1
2019-08-17 08:51:12,190 p=24794 u=automation | 24794 1566046272.19035: host n95ksw1 is done iterating, returning
2019-08-17 08:51:12,190 p=24794 u=automation | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: raise ConnectionError(to_text(msg, errors='surrogate_then_replace'), code=code)
2019-08-17 08:51:12,191 p=24794 u=automation | fatal: [n95ksw1]: FAILED! => {"ansible_facts": {"discovered_interpreter_python": "/usr/bin/python"}, "changed": false, "msg": "switchport\r\r\n ^\r\n% Invalid command at '^' marker.\r\n\rJER-LAB-N95KSW1(config-if)# "}
2019-08-17 08:51:12,191 p=24794 u=automation | 24794 1566046272.19185: no more pending results, returning what we have
2019-08-17 08:51:12,192 p=24794 u=automation | 24794 1566046272.19207: results queue empty
2019-08-17 08:51:12,192 p=24794 u=automation | 24794 1566046272.19227: checking for any_errors_fatal
2019-08-17 08:51:12,192 p=24794 u=automation | 24794 1566046272.19251: getting the next task for host n95ksw1
2019-08-17 08:51:12,192 p=24794 u=automation | 24794 1566046272.19268: host n95ksw1 is done iterating, returning
2019-08-17 08:51:12,192 p=24794 u=automation | 24794 1566046272.19289: getting the next task for host n95ksw2
2019-08-17 08:51:12,193 p=24794 u=automation | 24794 1566046272.19305: host n95ksw2 is done iterating, returning
2019-08-17 08:51:12,193 p=24794 u=automation | 24794 1566046272.19321: getting the next task for host n7ksw2
2019-08-17 08:51:12,193 p=24794 u=automation | 24794 1566046272.19339: done getting next task for host n7ksw2
2019-08-17 08:51:12,193 p=24794 u=automation | 24794 1566046272.19358: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:12,193 p=24794 u=automation | 24794 1566046272.19373: ^ state is: HOST STATE: block=3, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:12,194 p=24794 u=automation | 24794 1566046272.19395: getting the next task for host n7ksw1
2019-08-17 08:51:12,194 p=24794 u=automation | 24794 1566046272.19412: done getting next task for host n7ksw1
2019-08-17 08:51:12,194 p=24794 u=automation | 24794 1566046272.19432: ^ task is: TASK: meta (flush_handlers)
2019-08-17 08:51:12,194 p=24794 u=automation | 24794 1566046272.19447: ^ state is: HOST STATE: block=3, task=1, rescue=0, always=0, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
2019-08-17 08:51:12,194 p=24794 u=automation | 24794 1566046272.19462: done checking for any_errors_fatal
2019-08-17 08:51:12,194 p=24794 u=automation | 24794 1566046272.19477: checking for max_fail_percentage
2019-08-17 08:51:12,195 p=24794 u=automation | 24794 1566046272.19496: done checking for max_fail_percentage
2019-08-17 08:51:12,195 p=24794 u=automation | 24794 1566046272.19510: checking to see if all hosts have failed and the running result is not ok
2019-08-17 08:51:12,195 p=24794 u=automation | 24794 1566046272.19524: ^ not ok, so returning result now
2019-08-17 08:51:12,195 p=24794 u=automation | NO MORE HOSTS LEFT **********************************************************************************************************************************
2019-08-17 08:51:12,196 p=24826 u=automation | paramiko [172.26.141.227] [chan 0] EOF sent (0)
2019-08-17 08:51:12,246 p=24826 u=automation | paramiko [172.26.141.227] EOF in transport thread
2019-08-17 08:51:12,261 p=24829 u=automation | paramiko [172.26.141.228] [chan 0] EOF sent (0)
2019-08-17 08:51:12,272 p=24829 u=automation | paramiko [172.26.141.228] EOF in transport thread
2019-08-17 08:51:12,278 p=24794 u=automation | PLAY RECAP ******************************************************************************************************************************************
2019-08-17 08:51:12,278 p=24794 u=automation | n7ksw1 : ok=0 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0
2019-08-17 08:51:12,279 p=24794 u=automation | n7ksw2 : ok=0 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0
2019-08-17 08:51:12,279 p=24794 u=automation | n95ksw1 : ok=0 changed=0 unreachable=0 failed=1 skipped=0 rescued=0 ignored=0
2019-08-17 08:51:12,279 p=24794 u=automation | n95ksw2 : ok=0 changed=0 unreachable=0 failed=1 skipped=0 rescued=0 ignored=0
2019-08-17 08:51:12,279 p=24794 u=automation | 24794 1566046272.27972: RUNNING CLEANUP
2019-08-17 08:51:12,361 p=24809 u=automation | shutdown complete
2019-08-17 08:51:12,378 p=24811 u=automation | shutdown complete
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment