Unable to Open Shell
97 views
Skip to first unread message
Lukas M
Nov 27, 2017, 3:12:43 PM11/27/17
to Ansible Project
Hello,
I am still new to ansible and have been trying to get a simple playbook together using cisco IOS modules. I am testing with a Cisco Catalyst 3850 switch (running IOS XE 16.6.2) and at this stage am trying to collect the config and safe it as a text file. I am running ansible 2.4.1, python 2.7.5 and this is my playbook, related files and the resulting error:
_____________________________________________________________
backup.yaml
---
- hosts: all
gather_facts: false
connection: local
tasks:
- name: Execute Show run command
ios_command:
commands:
- show run
host: "{{ ansible_host }}"
username: ansible
password: ansible
register: config
- name: Save the output to /etc/ansible/files
copy:
content: "{{ config.stdout[0] }}"
dest: "/etc/ansible/files/SHOW_RUN_{{ inventory_hostname }}.txt"
_____________________________________________________________
ansible.cfg
[defaults]
transport = local
host_key_checking = False
timeout = 5
[paramiko_connection]
host_key_auto_add = True
look_for_keys=False
[persistent_connection]
connect_timeout = 60
command_timeout = 30
_____________________________________________________________
hosts
[switches]
3850-TEST ansible_host=10.0.111.10
_____________________________________________________________
ansible-playbook ansibleScripting/networkingTest/templates/backup.yaml
PLAY [all] **********************************************************************************************************************************************************************************
TASK [Execute Show run command] *************************************************************************************************************************************************************
fatal: [3850-TEST]: FAILED! => {"changed": false, "failed": true, "msg": "unable to open shell. Please see: https://docs.ansible.com/ansible/network_debug_troubleshooting.html#unable-to-open-shell"}
to retry, use: --limit @/home/lukasm/ansibleScripting/networkingTest/templates/backup.retry
PLAY RECAP **********************************************************************************************************************************************************************************
3850-TEST : ok=0 changed=0 unreachable=0 failed=1
_____________________________________________________________
I have tried to find this error and possible solutions for it in different forums but the suggested solutions haven't worked for me yet. Any help would be great as I am out of thoughts on what to do next.
Thanks,
Lukas
Kai Stian Olstad
Nov 28, 2017, 9:25:53 AM11/28/17
to ansible...@googlegroups.com
On Monday, 27 November 2017 21.12.43 CET Lukas M wrote:
> TASK [Execute Show run command]
> *************************************************************************************************************************************************************
> fatal: [3850-TEST]: FAILED! => {"changed": false, "failed": true, "msg":
> "unable to open shell. Please see:
> https://docs.ansible.com/ansible/network_debug_troubleshooting.html#unable-to-open-shell"}
> to retry, use: --limit
> @/home/lukasm/ansibleScripting/networkingTest/templates/backup.retry
>
> PLAY RECAP
> **********************************************************************************************************************************************************************************
> 3850-TEST : ok=0 changed=0 unreachable=0 failed=1
>
> _____________________________________________________________
>
> I have tried to find this error and possible solutions for it in different
> forums but the suggested solutions haven't worked for me yet. Any help
> would be great as I am out of thoughts on what to do next.
Have you read the link and done as it say?
> TASK [Execute Show run command]
> *************************************************************************************************************************************************************
> fatal: [3850-TEST]: FAILED! => {"changed": false, "failed": true, "msg":
> "unable to open shell. Please see:
> https://docs.ansible.com/ansible/network_debug_troubleshooting.html#unable-to-open-shell"}
> to retry, use: --limit
> @/home/lukasm/ansibleScripting/networkingTest/templates/backup.retry
>
> PLAY RECAP
> **********************************************************************************************************************************************************************************
> 3850-TEST : ok=0 changed=0 unreachable=0 failed=1
>
> _____________________________________________________________
>
> I have tried to find this error and possible solutions for it in different
> forums but the suggested solutions haven't worked for me yet. Any help
> would be great as I am out of thoughts on what to do next.
If so, where are the logs for that?
--
Kai Stian Olstad
Lukas M
Nov 28, 2017, 9:57:21 AM11/28/17
to Ansible Project
Yes, I looked through this link and through the log file but the suggested errors and solutions didn't work for me either..
Here is the log file content:
##########################################################################################################
2017-11-28 09:29:37,505 p=24401 u=lukasm | 24401 1511879377.50471: starting run
2017-11-28 09:29:37,635 p=24401 u=lukasm | ansible-playbook 2.4.1.0
config file = /etc/ansible/ansible.cfg
configured module search path = [u'/home/lukasm/.ansible/plugins/modules', u'/usr/share/ansible/plugins/modules']
ansible python module location = /usr/lib/python2.7/site-packages/ansible
executable location = /usr/bin/ansible-playbook
python version = 2.7.5 (default, Aug 4 2017, 00:39:18) [GCC 4.8.5 20150623 (Red Hat 4.8.5-16)]
2017-11-28 09:29:37,635 p=24401 u=lukasm | Using /etc/ansible/ansible.cfg as config file
2017-11-28 09:29:37,635 p=24401 u=lukasm | 24401 1511879377.63537: Added group all to inventory
2017-11-28 09:29:37,635 p=24401 u=lukasm | 24401 1511879377.63552: Added group ungrouped to inventory
2017-11-28 09:29:37,635 p=24401 u=lukasm | 24401 1511879377.63565: Group all now contains ungrouped
2017-11-28 09:29:37,636 p=24401 u=lukasm | 24401 1511879377.63652: Loading CacheModule 'memory' from /usr/lib/python2.7/site-packages/ansible/plugins/cache/memory.py
2017-11-28 09:29:37,636 p=24401 u=lukasm | setting up inventory plugins
2017-11-28 09:29:37,637 p=24401 u=lukasm | 24401 1511879377.63782: Loading InventoryModule 'host_list' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/host_list.py
2017-11-28 09:29:37,638 p=24401 u=lukasm | 24401 1511879377.63808: Loading InventoryModule 'script' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/script.py
2017-11-28 09:29:37,638 p=24401 u=lukasm | 24401 1511879377.63837: Loading InventoryModule 'yaml' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/yaml.py
2017-11-28 09:29:37,638 p=24401 u=lukasm | 24401 1511879377.63871: Loading InventoryModule 'ini' from /usr/lib/python2.7/site-packages/ansible/plugins/inventory/ini.py
2017-11-28 09:29:37,638 p=24401 u=lukasm | 24401 1511879377.63887: Examining possible inventory source: /etc/ansible/hosts
2017-11-28 09:29:37,639 p=24401 u=lukasm | 24401 1511879377.63899: Attempting to use plugin host_list (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/host_list.py)
2017-11-28 09:29:37,639 p=24401 u=lukasm | 24401 1511879377.63911: /etc/ansible/hosts did not meet host_list requirements
2017-11-28 09:29:37,639 p=24401 u=lukasm | 24401 1511879377.63922: Attempting to use plugin script (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/script.py)
2017-11-28 09:29:37,639 p=24401 u=lukasm | 24401 1511879377.63938: /etc/ansible/hosts did not meet script requirements
2017-11-28 09:29:37,639 p=24401 u=lukasm | 24401 1511879377.63950: Attempting to use plugin yaml (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/yaml.py)
2017-11-28 09:29:37,640 p=24401 u=lukasm | 24401 1511879377.64045: Loading data from /etc/ansible/hosts
2017-11-28 09:29:37,642 p=24401 u=lukasm | 24401 1511879377.64277: /etc/ansible/hosts did not meet yaml requirements
2017-11-28 09:29:37,643 p=24401 u=lukasm | 24401 1511879377.64318: Attempting to use plugin ini (/usr/lib/python2.7/site-packages/ansible/plugins/inventory/ini.py)
2017-11-28 09:29:37,647 p=24401 u=lukasm | 24401 1511879377.64778: Added group switches to inventory
2017-11-28 09:29:37,648 p=24401 u=lukasm | 24401 1511879377.64862: set inventory_file for 3850-TEST
2017-11-28 09:29:37,649 p=24401 u=lukasm | 24401 1511879377.64906: set inventory_dir for 3850-TEST
2017-11-28 09:29:37,649 p=24401 u=lukasm | 24401 1511879377.64939: Added host 3850-TEST to inventory
2017-11-28 09:29:37,649 p=24401 u=lukasm | 24401 1511879377.64968: Added host 3850-TEST to group switches
2017-11-28 09:29:37,650 p=24401 u=lukasm | 24401 1511879377.64999: set ansible_host for 3850-TEST
2017-11-28 09:29:37,650 p=24401 u=lukasm | Parsed /etc/ansible/hosts inventory source with ini plugin
2017-11-28 09:29:37,650 p=24401 u=lukasm | 24401 1511879377.65072: Reconcile groups and hosts in inventory.
2017-11-28 09:29:37,651 p=24401 u=lukasm | 24401 1511879377.65108: Group all now contains switches
2017-11-28 09:29:37,651 p=24401 u=lukasm | 24401 1511879377.65145: Group all now contains 3850-TEST
2017-11-28 09:29:37,651 p=24401 u=lukasm | 24401 1511879377.65164: Loading CacheModule 'memory' from /usr/lib/python2.7/site-packages/ansible/plugins/cache/memory.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:37,661 p=24401 u=lukasm | 24401 1511879377.66144: Loading data from /etc/ansible/templates/backup.yaml
2017-11-28 09:29:37,749 p=24401 u=lukasm | 24401 1511879377.74934: Loading CallbackModule 'default' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/default.py
2017-11-28 09:29:37,749 p=24401 u=lukasm | Loading callback plugin default of type stdout, v2.0 from /usr/lib/python2.7/site-packages/ansible/plugins/callback/__init__.pyc
2017-11-28 09:29:37,754 p=24401 u=lukasm | 24401 1511879377.75480: assigned :doc
2017-11-28 09:29:37,756 p=24401 u=lukasm | 24401 1511879377.75619: Loading CallbackModule 'actionable' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/actionable.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,760 p=24401 u=lukasm | 24401 1511879377.76039: assigned :doc
2017-11-28 09:29:37,760 p=24401 u=lukasm | 24401 1511879377.76072: Loading CallbackModule 'context_demo' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/context_demo.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,762 p=24401 u=lukasm | 24401 1511879377.76293: assigned :doc
2017-11-28 09:29:37,763 p=24401 u=lukasm | 24401 1511879377.76323: Loading CallbackModule 'debug' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/debug.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,765 p=24401 u=lukasm | 24401 1511879377.76555: assigned :doc
2017-11-28 09:29:37,765 p=24401 u=lukasm | 24401 1511879377.76573: Loading CallbackModule 'default' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/default.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,788 p=24401 u=lukasm | 24401 1511879377.78805: assigned :doc
2017-11-28 09:29:37,789 p=24401 u=lukasm | 24401 1511879377.78900: Loading CallbackModule 'dense' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/dense.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,794 p=24401 u=lukasm | 24401 1511879377.79429: assigned :doc
2017-11-28 09:29:37,815 p=24401 u=lukasm | 24401 1511879377.81504: Loading CallbackModule 'foreman' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/foreman.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,823 p=24401 u=lukasm | 24401 1511879377.82330: assigned :doc
2017-11-28 09:29:37,823 p=24401 u=lukasm | 24401 1511879377.82356: Loaded config def from plugin (callback/foreman)
2017-11-28 09:29:37,823 p=24401 u=lukasm | 24401 1511879377.82384: Loading CallbackModule 'full_skip' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/full_skip.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,826 p=24401 u=lukasm | 24401 1511879377.82642: assigned :doc
2017-11-28 09:29:37,839 p=24401 u=lukasm | 24401 1511879377.83949: Loading CallbackModule 'hipchat' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/hipchat.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,848 p=24401 u=lukasm | 24401 1511879377.84870: assigned :doc
2017-11-28 09:29:37,848 p=24401 u=lukasm | 24401 1511879377.84892: Loaded config def from plugin (callback/hipchat)
2017-11-28 09:29:37,849 p=24401 u=lukasm | 24401 1511879377.84925: Loading CallbackModule 'jabber' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/jabber.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,856 p=24401 u=lukasm | 24401 1511879377.85638: assigned :doc
2017-11-28 09:29:37,856 p=24401 u=lukasm | 24401 1511879377.85661: Loaded config def from plugin (callback/jabber)
2017-11-28 09:29:37,856 p=24401 u=lukasm | 24401 1511879377.85688: Loading CallbackModule 'json' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/json.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,859 p=24401 u=lukasm | 24401 1511879377.85909: assigned :doc
2017-11-28 09:29:37,859 p=24401 u=lukasm | 24401 1511879377.85960: Loading CallbackModule 'junit' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/junit.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,873 p=24401 u=lukasm | 24401 1511879377.87323: assigned :doc
2017-11-28 09:29:37,873 p=24401 u=lukasm | 24401 1511879377.87357: Loaded config def from plugin (callback/junit)
2017-11-28 09:29:37,873 p=24401 u=lukasm | 24401 1511879377.87387: Loading CallbackModule 'log_plays' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/log_plays.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,876 p=24401 u=lukasm | 24401 1511879377.87666: assigned :doc
2017-11-28 09:29:37,877 p=24401 u=lukasm | 24401 1511879377.87719: Loading CallbackModule 'logentries' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/logentries.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,892 p=24401 u=lukasm | 24401 1511879377.89276: assigned :doc
2017-11-28 09:29:37,892 p=24401 u=lukasm | 24401 1511879377.89289: assigned :plainexamples
2017-11-28 09:29:37,893 p=24401 u=lukasm | 24401 1511879377.89313: Loaded config def from plugin (callback/logentries)
2017-11-28 09:29:37,893 p=24401 u=lukasm | 24401 1511879377.89355: Loading CallbackModule 'logstash' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/logstash.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,899 p=24401 u=lukasm | 24401 1511879377.89977: assigned :doc
2017-11-28 09:29:37,900 p=24401 u=lukasm | 24401 1511879377.89999: Loaded config def from plugin (callback/logstash)
2017-11-28 09:29:37,901 p=24401 u=lukasm | 24401 1511879377.90171: Loading CallbackModule 'mail' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/mail.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,912 p=24401 u=lukasm | 24401 1511879377.91273: assigned :doc
2017-11-28 09:29:37,913 p=24401 u=lukasm | 24401 1511879377.91295: Loaded config def from plugin (callback/mail)
2017-11-28 09:29:37,913 p=24401 u=lukasm | 24401 1511879377.91323: Loading CallbackModule 'minimal' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/minimal.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,915 p=24401 u=lukasm | 24401 1511879377.91549: assigned :doc
2017-11-28 09:29:37,915 p=24401 u=lukasm | 24401 1511879377.91583: Loading CallbackModule 'oneline' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/oneline.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,918 p=24401 u=lukasm | 24401 1511879377.91798: assigned :doc
2017-11-28 09:29:37,918 p=24401 u=lukasm | 24401 1511879377.91839: Loading CallbackModule 'osx_say' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/osx_say.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,922 p=24401 u=lukasm | 24401 1511879377.92217: assigned :doc
2017-11-28 09:29:37,923 p=24401 u=lukasm | 24401 1511879377.92316: Loading CallbackModule 'profile_roles' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/profile_roles.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,929 p=24401 u=lukasm | 24401 1511879377.92940: assigned :doc
2017-11-28 09:29:37,930 p=24401 u=lukasm | 24401 1511879377.93037: Loading CallbackModule 'profile_tasks' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/profile_tasks.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,938 p=24401 u=lukasm | 24401 1511879377.93891: assigned :doc
2017-11-28 09:29:37,939 p=24401 u=lukasm | 24401 1511879377.93904: assigned :plainexamples
2017-11-28 09:29:37,939 p=24401 u=lukasm | 24401 1511879377.93924: Loaded config def from plugin (callback/profile_tasks)
2017-11-28 09:29:37,939 p=24401 u=lukasm | 24401 1511879377.93963: Loading CallbackModule 'selective' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/selective.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,946 p=24401 u=lukasm | 24401 1511879377.94649: assigned :doc
2017-11-28 09:29:37,946 p=24401 u=lukasm | 24401 1511879377.94662: assigned :plainexamples
2017-11-28 09:29:37,946 p=24401 u=lukasm | 24401 1511879377.94689: Loaded config def from plugin (callback/selective)
2017-11-28 09:29:37,947 p=24401 u=lukasm | 24401 1511879377.94717: Loading CallbackModule 'skippy' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/skippy.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,949 p=24401 u=lukasm | 24401 1511879377.94954: assigned :doc
2017-11-28 09:29:37,949 p=24401 u=lukasm | 24401 1511879377.94992: Loading CallbackModule 'slack' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/slack.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,963 p=24401 u=lukasm | 24401 1511879377.96318: assigned :doc
2017-11-28 09:29:37,963 p=24401 u=lukasm | 24401 1511879377.96349: Loaded config def from plugin (callback/slack)
2017-11-28 09:29:37,963 p=24401 u=lukasm | 24401 1511879377.96378: Loading CallbackModule 'stderr' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/stderr.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,966 p=24401 u=lukasm | 24401 1511879377.96670: assigned :doc
2017-11-28 09:29:37,967 p=24401 u=lukasm | 24401 1511879377.96758: Loading CallbackModule 'syslog_json' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/syslog_json.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,982 p=24401 u=lukasm | 24401 1511879377.98246: assigned :doc
2017-11-28 09:29:37,982 p=24401 u=lukasm | 24401 1511879377.98265: Loaded config def from plugin (callback/syslog_json)
2017-11-28 09:29:37,982 p=24401 u=lukasm | 24401 1511879377.98292: Loading CallbackModule 'timer' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/timer.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,985 p=24401 u=lukasm | 24401 1511879377.98496: assigned :doc
2017-11-28 09:29:37,985 p=24401 u=lukasm | 24401 1511879377.98528: Loading CallbackModule 'tree' from /usr/lib/python2.7/site-packages/ansible/plugins/callback/tree.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:37,987 p=24401 u=lukasm | 24401 1511879377.98789: assigned :doc
2017-11-28 09:29:37,988 p=24401 u=lukasm | PLAYBOOK: backup.yaml ***********************************************************************************************************************************************************************
2017-11-28 09:29:37,988 p=24401 u=lukasm | 1 plays in backup.yaml
2017-11-28 09:29:37,988 p=24401 u=lukasm | 24401 1511879377.98835: in VariableManager get_vars()
2017-11-28 09:29:38,004 p=24401 u=lukasm | 24401 1511879378.00408: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py
2017-11-28 09:29:38,014 p=24401 u=lukasm | 24401 1511879378.01477: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py
2017-11-28 09:29:38,015 p=24401 u=lukasm | 24401 1511879378.01509: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py
2017-11-28 09:29:38,015 p=24401 u=lukasm | 24401 1511879378.01541: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py
2017-11-28 09:29:38,016 p=24401 u=lukasm | 24401 1511879378.01604: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py
2017-11-28 09:29:38,016 p=24401 u=lukasm | 24401 1511879378.01630: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py
2017-11-28 09:29:38,017 p=24401 u=lukasm | 24401 1511879378.01708: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py
2017-11-28 09:29:38,017 p=24401 u=lukasm | 24401 1511879378.01732: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py
2017-11-28 09:29:38,018 p=24401 u=lukasm | 24401 1511879378.01807: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py
2017-11-28 09:29:38,022 p=24401 u=lukasm | 24401 1511879378.02241: done with get_vars()
2017-11-28 09:29:38,022 p=24401 u=lukasm | 24401 1511879378.02284: in VariableManager get_vars()
2017-11-28 09:29:38,023 p=24401 u=lukasm | 24401 1511879378.02317: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,023 p=24401 u=lukasm | 24401 1511879378.02329: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,023 p=24401 u=lukasm | 24401 1511879378.02347: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,023 p=24401 u=lukasm | 24401 1511879378.02359: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,023 p=24401 u=lukasm | 24401 1511879378.02370: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,023 p=24401 u=lukasm | 24401 1511879378.02382: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,024 p=24401 u=lukasm | 24401 1511879378.02422: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,024 p=24401 u=lukasm | 24401 1511879378.02437: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,024 p=24401 u=lukasm | 24401 1511879378.02450: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,025 p=24401 u=lukasm | 24401 1511879378.02514: done with get_vars()
2017-11-28 09:29:38,025 p=24401 u=lukasm | PLAY [all] **********************************************************************************************************************************************************************************
2017-11-28 09:29:38,031 p=24401 u=lukasm | 24401 1511879378.03095: Loading StrategyModule 'linear' from /usr/lib/python2.7/site-packages/ansible/plugins/strategy/linear.py
2017-11-28 09:29:38,032 p=24401 u=lukasm | 24401 1511879378.03256: getting the remaining hosts for this loop
2017-11-28 09:29:38,032 p=24401 u=lukasm | 24401 1511879378.03271: done getting the remaining hosts for this loop
2017-11-28 09:29:38,032 p=24401 u=lukasm | 24401 1511879378.03283: building list of next tasks for hosts
2017-11-28 09:29:38,032 p=24401 u=lukasm | 24401 1511879378.03294: getting the next task for host 3850-TEST
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03307: done getting next task for host 3850-TEST
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03318: ^ task is: TASK: meta (flush_handlers)
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03329: ^ 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
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03343: done building task lists
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03353: counting tasks in each state of execution
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03363: done counting tasks in each state of execution:
num_setups: 0
num_tasks: 1
num_rescue: 0
num_always: 0
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03375: advancing hosts in ITERATING_TASKS
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03385: starting to advance hosts
2017-11-28 09:29:38,033 p=24401 u=lukasm | 24401 1511879378.03395: getting the next task for host 3850-TEST
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03407: done getting next task for host 3850-TEST
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03418: ^ task is: TASK: meta (flush_handlers)
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03428: ^ 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
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03440: done advancing hosts to next task
2017-11-28 09:29:38,034 p=24401 u=lukasm | META: ran handlers
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03465: done queuing things up, now waiting for results queue to drain
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03475: results queue empty
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03485: checking for any_errors_fatal
2017-11-28 09:29:38,034 p=24401 u=lukasm | 24401 1511879378.03495: done checking for any_errors_fatal
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03504: checking for max_fail_percentage
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03514: done checking for max_fail_percentage
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03523: checking to see if all hosts have failed and the running result is not ok
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03534: done checking to see if all hosts have failed
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03544: getting the remaining hosts for this loop
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03555: done getting the remaining hosts for this loop
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03566: building list of next tasks for hosts
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03576: getting the next task for host 3850-TEST
2017-11-28 09:29:38,035 p=24401 u=lukasm | 24401 1511879378.03588: done getting next task for host 3850-TEST
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03598: ^ task is: TASK: Execute Show run command
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03609: ^ 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
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03619: done building task lists
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03628: counting tasks in each state of execution
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03640: done counting tasks in each state of execution:
num_setups: 0
num_tasks: 1
num_rescue: 0
num_always: 0
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03650: advancing hosts in ITERATING_TASKS
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03660: starting to advance hosts
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03670: getting the next task for host 3850-TEST
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03681: done getting next task for host 3850-TEST
2017-11-28 09:29:38,036 p=24401 u=lukasm | 24401 1511879378.03692: ^ task is: TASK: Execute Show run command
2017-11-28 09:29:38,037 p=24401 u=lukasm | 24401 1511879378.03702: ^ 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
2017-11-28 09:29:38,037 p=24401 u=lukasm | 24401 1511879378.03713: done advancing hosts to next task
2017-11-28 09:29:38,037 p=24401 u=lukasm | 24401 1511879378.03726: getting variables
2017-11-28 09:29:38,037 p=24401 u=lukasm | 24401 1511879378.03738: in VariableManager get_vars()
2017-11-28 09:29:38,037 p=24401 u=lukasm | 24401 1511879378.03770: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,037 p=24401 u=lukasm | 24401 1511879378.03782: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,037 p=24401 u=lukasm | 24401 1511879378.03794: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,038 p=24401 u=lukasm | 24401 1511879378.03805: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,038 p=24401 u=lukasm | 24401 1511879378.03816: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,038 p=24401 u=lukasm | 24401 1511879378.03827: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,038 p=24401 u=lukasm | 24401 1511879378.03853: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,038 p=24401 u=lukasm | 24401 1511879378.03865: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,038 p=24401 u=lukasm | 24401 1511879378.03876: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,039 p=24401 u=lukasm | 24401 1511879378.03955: Calling all_inventory to load vars for 3850-TEST
2017-11-28 09:29:38,039 p=24401 u=lukasm | 24401 1511879378.03983: Calling groups_inventory to load vars for 3850-TEST
2017-11-28 09:29:38,040 p=24401 u=lukasm | 24401 1511879378.03996: Calling all_plugins_inventory to load vars for 3850-TEST
2017-11-28 09:29:38,040 p=24401 u=lukasm | 24401 1511879378.04071: Loading VarsModule 'host_group_vars' from /usr/lib/python2.7/site-packages/ansible/plugins/vars/host_group_vars.py
2017-11-28 09:29:38,040 p=24401 u=lukasm | 24401 1511879378.04094: Calling all_plugins_play to load vars for 3850-TEST
2017-11-28 09:29:38,041 p=24401 u=lukasm | 24401 1511879378.04116: 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)
2017-11-28 09:29:38,041 p=24401 u=lukasm | 24401 1511879378.04140: Calling groups_plugins_inventory to load vars for 3850-TEST
2017-11-28 09:29:38,041 p=24401 u=lukasm | 24401 1511879378.04164: 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)
2017-11-28 09:29:38,041 p=24401 u=lukasm | 24401 1511879378.04185: Calling groups_plugins_play to load vars for 3850-TEST
2017-11-28 09:29:38,042 p=24401 u=lukasm | 24401 1511879378.04206: 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)
2017-11-28 09:29:38,042 p=24401 u=lukasm | 24401 1511879378.04242: 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)
2017-11-28 09:29:38,042 p=24401 u=lukasm | 24401 1511879378.04262: processing dir /etc/ansible/host_vars
2017-11-28 09:29:38,042 p=24401 u=lukasm | 24401 1511879378.04278: Loading data from /etc/ansible/host_vars/3850-TEST
2017-11-28 09:29:38,045 p=24401 u=lukasm | 24401 1511879378.04571: 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)
2017-11-28 09:29:38,046 p=24401 u=lukasm | 24401 1511879378.04671: done with get_vars()
2017-11-28 09:29:38,047 p=24401 u=lukasm | 24401 1511879378.04723: done getting variables
2017-11-28 09:29:38,047 p=24401 u=lukasm | 24401 1511879378.04759: sending task start callback, copying the task so we can template it temporarily
2017-11-28 09:29:38,048 p=24401 u=lukasm | 24401 1511879378.04791: done copying, going to template now
2017-11-28 09:29:38,048 p=24401 u=lukasm | 24401 1511879378.04822: done templating
2017-11-28 09:29:38,048 p=24401 u=lukasm | 24401 1511879378.04850: here goes the callback...
2017-11-28 09:29:38,048 p=24401 u=lukasm | TASK [Execute Show run command] *************************************************************************************************************************************************************
2017-11-28 09:29:38,049 p=24401 u=lukasm | task path: /etc/ansible/templates/backup.yaml:7
2017-11-28 09:29:38,049 p=24401 u=lukasm | 24401 1511879378.04949: sending task start callback
2017-11-28 09:29:38,049 p=24401 u=lukasm | 24401 1511879378.04980: entering _queue_task() for 3850-TEST/ios_command
2017-11-28 09:29:38,050 p=24401 u=lukasm | 24401 1511879378.05009: Creating lock for ios_command
2017-11-28 09:29:38,052 p=24401 u=lukasm | 24401 1511879378.05194: worker is 1 (out of 1 available)
2017-11-28 09:29:38,052 p=24401 u=lukasm | 24401 1511879378.05260: exiting _queue_task() for 3850-TEST/ios_command
2017-11-28 09:29:38,052 p=24401 u=lukasm | 24401 1511879378.05279: done queuing things up, now waiting for results queue to drain
2017-11-28 09:29:38,052 p=24401 u=lukasm | 24401 1511879378.05291: waiting for pending results...
2017-11-28 09:29:38,057 p=24401 u=lukasm | 24413 1511879378.05742: running TaskExecutor() for 3850-TEST/TASK: Execute Show run command
2017-11-28 09:29:38,059 p=24401 u=lukasm | 24413 1511879378.05887: in run() - task 0050569e-a860-2e0a-50e5-000000000008
2017-11-28 09:29:38,060 p=24401 u=lukasm | 24413 1511879378.06036: calling self._execute()
2017-11-28 09:29:38,063 p=24401 u=lukasm | 24413 1511879378.06350: Loading FilterModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/core.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,063 p=24401 u=lukasm | 24413 1511879378.06367: Loading FilterModule 'ipaddr' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/ipaddr.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,063 p=24401 u=lukasm | 24413 1511879378.06381: Loading FilterModule 'json_query' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/json_query.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,063 p=24401 u=lukasm | 24413 1511879378.06393: Loading FilterModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/mathstuff.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,064 p=24401 u=lukasm | 24413 1511879378.06406: Loading FilterModule 'network' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/network.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,064 p=24401 u=lukasm | 24413 1511879378.06419: Loading FilterModule 'urlsplit' from /usr/lib/python2.7/site-packages/ansible/plugins/filter/urlsplit.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,064 p=24401 u=lukasm | 24413 1511879378.06463: Loading TestModule 'core' from /usr/lib/python2.7/site-packages/ansible/plugins/test/core.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,064 p=24401 u=lukasm | 24413 1511879378.06477: Loading TestModule 'files' from /usr/lib/python2.7/site-packages/ansible/plugins/test/files.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,064 p=24401 u=lukasm | 24413 1511879378.06491: Loading TestModule 'mathstuff' from /usr/lib/python2.7/site-packages/ansible/plugins/test/mathstuff.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,069 p=24401 u=lukasm | 24413 1511879378.06975: Loading Connection 'local' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/local.py
2017-11-28 09:29:38,070 p=24401 u=lukasm | 24413 1511879378.07047: Loading ShellModule 'csh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/csh.py
2017-11-28 09:29:38,071 p=24401 u=lukasm | 24413 1511879378.07094: Loading ShellModule 'fish' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/fish.py
2017-11-28 09:29:38,071 p=24401 u=lukasm | 24413 1511879378.07110: Loading ShellModule 'powershell' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/powershell.py
2017-11-28 09:29:38,071 p=24401 u=lukasm | 24413 1511879378.07123: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py
2017-11-28 09:29:38,071 p=24401 u=lukasm | 24413 1511879378.07154: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,077 p=24401 u=lukasm | 24413 1511879378.07701: assigned :doc
2017-11-28 09:29:38,078 p=24401 u=lukasm | 24413 1511879378.07814: Loading ActionModule 'ios' from /usr/lib/python2.7/site-packages/ansible/plugins/action/ios.py
2017-11-28 09:29:38,078 p=24401 u=lukasm | 24413 1511879378.07831: starting attempt loop
2017-11-28 09:29:38,078 p=24401 u=lukasm | 24413 1511879378.07875: running the handler
2017-11-28 09:29:38,081 p=24401 u=lukasm | 24413 1511879378.08154: Loading Connection 'persistent' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/persistent.py
2017-11-28 09:29:38,082 p=24401 u=lukasm | 24413 1511879378.08212: Loading ShellModule 'csh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/csh.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,082 p=24401 u=lukasm | 24413 1511879378.08247: Loading ShellModule 'fish' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/fish.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,082 p=24401 u=lukasm | 24413 1511879378.08278: Loading ShellModule 'powershell' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/powershell.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,083 p=24401 u=lukasm | 24413 1511879378.08299: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,083 p=24401 u=lukasm | 24413 1511879378.08338: Loading ShellModule 'sh' from /usr/lib/python2.7/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
2017-11-28 09:29:38,088 p=24401 u=lukasm | 24413 1511879378.08798: assigned :doc
2017-11-28 09:29:38,733 p=24414 u=lukasm | 24414 1511879378.73374: Loading Connection 'ssh' from /usr/lib/python2.7/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=False, class_only=True)
2017-11-28 09:29:38,777 p=24414 u=lukasm | 24414 1511879378.77775: assigned :doc
2017-11-28 09:29:38,778 p=24414 u=lukasm | 24414 1511879378.77828: Loaded config def from plugin (connection/ssh)
2017-11-28 09:29:38,781 p=24414 u=lukasm | creating new control socket for host 10.0.111.10:22 as user ansible
2017-11-28 09:29:38,782 p=24414 u=lukasm | control socket path is /home/lukasm/.ansible/pc/f236feb178
2017-11-28 09:29:38,782 p=24414 u=lukasm | current working directory is /etc/ansible/templates
2017-11-28 09:29:38,782 p=24414 u=lukasm | using connection plugin network_cli
2017-11-28 09:29:38,834 p=24414 u=lukasm | failed to create control socket for host 10.0.111.10
2017-11-28 09:29:38,835 p=24414 u=lukasm | Traceback (most recent call last):
File "/usr/bin/ansible-connection", line 315, in main
server = Server(socket_path, pc)
File "/usr/bin/ansible-connection", line 111, in __init__
self.connection = connection_loader.get(play_context.connection, play_context, sys.stdin)
File "/usr/lib/python2.7/site-packages/ansible/plugins/loader.py", line 363, in get
self._module_cache[path] = self._load_module_source(name, path)
File "/usr/lib/python2.7/site-packages/ansible/plugins/loader.py", line 339, in _load_module_source
module = imp.load_source(full_name, path, module_file)
File "/usr/lib/python2.7/site-packages/ansible/plugins/connection/network_cli.py", line 61, in <module>
from ansible.plugins.connection.paramiko_ssh import Connection as _Connection
File "/usr/lib/python2.7/site-packages/ansible/plugins/connection/paramiko_ssh.py", line 94, in <module>
import paramiko
File "/usr/lib/python2.7/site-packages/paramiko/__init__.py", line 22, in <module>
from paramiko.transport import SecurityOptions, Transport
File "/usr/lib/python2.7/site-packages/paramiko/transport.py", line 38, in <module>
from paramiko.auth_handler import AuthHandler
File "/usr/lib/python2.7/site-packages/paramiko/auth_handler.py", line 48, in <module>
from paramiko.ssh_gss import GSSAuth, GSS_EXCEPTIONS
File "/usr/lib/python2.7/site-packages/paramiko/ssh_gss.py", line 54, in <module>
GSS_EXCEPTIONS = (gssapi.GSSException,)
AttributeError: 'module' object has no attribute 'GSSException'
2017-11-28 09:30:08,961 p=24401 u=lukasm | 24413 1511879408.96180: handler run complete
2017-11-28 09:30:08,962 p=24401 u=lukasm | 24413 1511879408.96221: attempt loop complete, returning result
2017-11-28 09:30:08,962 p=24401 u=lukasm | 24413 1511879408.96236: _execute() done
2017-11-28 09:30:08,962 p=24401 u=lukasm | 24413 1511879408.96248: dumping result to json
2017-11-28 09:30:08,962 p=24401 u=lukasm | 24413 1511879408.96260: done dumping result, returning
2017-11-28 09:30:08,962 p=24401 u=lukasm | 24413 1511879408.96277: done running TaskExecutor() for 3850-TEST/TASK: Execute Show run command [0050569e-a860-2e0a-50e5-000000000008]
2017-11-28 09:30:08,963 p=24401 u=lukasm | 24413 1511879408.96297: sending task result for task 0050569e-a860-2e0a-50e5-000000000008
2017-11-28 09:30:08,964 p=24401 u=lukasm | 24413 1511879408.96340: done sending task result for task 0050569e-a860-2e0a-50e5-000000000008
2017-11-28 09:30:08,964 p=24401 u=lukasm | 24413 1511879408.96417: WORKER PROCESS EXITING
2017-11-28 09:30:08,965 p=24401 u=lukasm | 24401 1511879408.96548: marking 3850-TEST as failed
2017-11-28 09:30:08,965 p=24401 u=lukasm | 24401 1511879408.96572: marking host 3850-TEST 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
2017-11-28 09:30:08,965 p=24401 u=lukasm | 24401 1511879408.96588: ^ 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
2017-11-28 09:30:08,966 p=24401 u=lukasm | 24401 1511879408.96602: getting the next task for host 3850-TEST
2017-11-28 09:30:08,966 p=24401 u=lukasm | 24401 1511879408.96615: host 3850-TEST is done iterating, returning
2017-11-28 09:30:08,966 p=24401 u=lukasm | fatal: [3850-TEST]: FAILED! => {
"changed": false,
"failed": true,
"msg": "unable to open shell. Please see: https://docs.ansible.com/ansible/network_debug_troubleshooting.html#unable-to-open-shell"
}
2017-11-28 09:30:08,966 p=24401 u=lukasm | 24401 1511879408.96672: no more pending results, returning what we have
2017-11-28 09:30:08,967 p=24401 u=lukasm | 24401 1511879408.96705: results queue empty
2017-11-28 09:30:08,967 p=24401 u=lukasm | 24401 1511879408.96738: checking for any_errors_fatal
2017-11-28 09:30:08,967 p=24401 u=lukasm | 24401 1511879408.96765: done checking for any_errors_fatal
2017-11-28 09:30:08,968 p=24401 u=lukasm | 24401 1511879408.96798: checking for max_fail_percentage
2017-11-28 09:30:08,968 p=24401 u=lukasm | 24401 1511879408.96827: done checking for max_fail_percentage
2017-11-28 09:30:08,968 p=24401 u=lukasm | 24401 1511879408.96853: checking to see if all hosts have failed and the running result is not ok
2017-11-28 09:30:08,968 p=24401 u=lukasm | 24401 1511879408.96881: done checking to see if all hosts have failed
2017-11-28 09:30:08,969 p=24401 u=lukasm | 24401 1511879408.96908: getting the remaining hosts for this loop
2017-11-28 09:30:08,969 p=24401 u=lukasm | 24401 1511879408.96942: done getting the remaining hosts for this loop
2017-11-28 09:30:08,969 p=24401 u=lukasm | 24401 1511879408.96977: building list of next tasks for hosts
2017-11-28 09:30:08,970 p=24401 u=lukasm | 24401 1511879408.97001: getting the next task for host 3850-TEST
2017-11-28 09:30:08,970 p=24401 u=lukasm | 24401 1511879408.97031: host 3850-TEST is done iterating, returning
2017-11-28 09:30:08,970 p=24401 u=lukasm | 24401 1511879408.97057: done building task lists
2017-11-28 09:30:08,970 p=24401 u=lukasm | 24401 1511879408.97086: counting tasks in each state of execution
2017-11-28 09:30:08,971 p=24401 u=lukasm | 24401 1511879408.97109: done counting tasks in each state of execution:
num_setups: 0
num_tasks: 0
num_rescue: 0
num_always: 0
2017-11-28 09:30:08,971 p=24401 u=lukasm | 24401 1511879408.97141: all hosts are done, so returning None's for all hosts
2017-11-28 09:30:08,971 p=24401 u=lukasm | 24401 1511879408.97170: done queuing things up, now waiting for results queue to drain
2017-11-28 09:30:08,972 p=24401 u=lukasm | 24401 1511879408.97199: results queue empty
2017-11-28 09:30:08,972 p=24401 u=lukasm | 24401 1511879408.97226: checking for any_errors_fatal
2017-11-28 09:30:08,972 p=24401 u=lukasm | 24401 1511879408.97253: done checking for any_errors_fatal
2017-11-28 09:30:08,972 p=24401 u=lukasm | 24401 1511879408.97280: checking for max_fail_percentage
2017-11-28 09:30:08,973 p=24401 u=lukasm | 24401 1511879408.97307: done checking for max_fail_percentage
2017-11-28 09:30:08,973 p=24401 u=lukasm | 24401 1511879408.97340: checking to see if all hosts have failed and the running result is not ok
2017-11-28 09:30:08,973 p=24401 u=lukasm | 24401 1511879408.97368: done checking to see if all hosts have failed
2017-11-28 09:30:08,973 p=24401 u=lukasm | 24401 1511879408.97392: getting the next task for host 3850-TEST
2017-11-28 09:30:08,974 p=24401 u=lukasm | 24401 1511879408.97403: host 3850-TEST is done iterating, returning
2017-11-28 09:30:08,974 p=24401 u=lukasm | 24401 1511879408.97414: running handlers
2017-11-28 09:30:08,975 p=24401 u=lukasm | [WARNING]: Could not create retry file '/etc/ansible/templates/backup.retry'. [Errno 13] Permission denied: u'/etc/ansible/templates/backup.retry'
2017-11-28 09:30:08,975 p=24401 u=lukasm | PLAY RECAP **********************************************************************************************************************************************************************************
2017-11-28 09:30:08,976 p=24401 u=lukasm | 3850-TEST : ok=0 changed=0 unreachable=0 failed=1
2017-11-28 09:30:08,976 p=24401 u=lukasm | 24401 1511879408.97618: RUNNING CLEANUP
##########################################################################################################
Peter Sprygada
Nov 28, 2017, 10:06:30 AM11/28/17
to ansible...@googlegroups.com
That looks like a paramiko issue. Could you open an issue at github.com/ansible/ansible and provide this output along with the output from `pip freeze`? From there we can further troubleshoot the bug.
--
You received this message because you are subscribed to the Google Groups "Ansible Project" group.
To unsubscribe from this group and stop receiving emails from it, send an email to ansible-proje...@googlegroups.com.
To post to this group, send email to ansible...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/ansible-project/fa6b8d52-e84c-4ec3-9b93-33e7cec035a7%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
Lukas M
Nov 28, 2017, 10:51:35 AM11/28/17
to Ansible Project
I just opened an issue on github for this as "Cisco Switch Unable to Open Shell". #33342
Thank you!
...
Reply all
Reply to author
Forward
0 new messages