Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

ansible.eos.eos_config "defaults: True" option takes a very long time #441

Open
michaeljaboyd opened this issue Aug 30, 2023 · 3 comments
Open
Assignees
Labels
needs_info This issue requires further information. Please answer any outstanding questions.

Comments

@michaeljaboyd
Copy link

SUMMARY

The follow code takes up to 6 minutes to run. whereas running the commands manually "term len 0" followed by "show run all" completes in seconds.

- name: take backup of config
      arista.eos.eos_config:
        defaults: True
        backup: True
        backup_options:
          dir_path: "/tmp/"
      register: running_config_backup
      vars:
        ansible_command_timeout: 600
ISSUE TYPE

Slow command output. This makes the playbook very slow

COMPONENT NAME

arista.eos.eos_config

ANSIBLE VERSION
ansible [core 2.13.10]
  config file = /home/user/git/network/ansible.cfg
  configured module search path = ['/home/user/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
  ansible python module location = /home/user/git/network/lib64/python3.8/site-packages/ansible
  ansible collection location = /home/user/.ansible/collections:/usr/share/ansible/collections
  executable location = ./bin/ansible
  python version = 3.8.13 (default, Aug 16 2022, 12:16:29) [GCC 9.3.1 20200408 (Red Hat 9.3.1-2)]
  jinja version = 3.1.2
  libyaml = True
  
COLLECTION VERSION
N/A installed from pip
CONFIGURATION
OS / ENVIRONMENT

Centos 7
7260cx3

STEPS TO REPRODUCE

in hosts file

[all:vars]
ansible_connection=ansible.netcommon.network_cli
ansible_become=yes
ansible_become_method=enable
ansible_network_os=arista.eos.eos

in playbook

      arista.eos.eos_config:
        defaults: True
        backup: True
        backup_options:
          dir_path: "/tmp/"
      register: running_config_backup
      vars:
        ansible_command_timeout: 600```

##### EXPECTED RESULTS
the command to complete in a similar time to the command line interface


##### ACTUAL RESULTS
It takes 6 minutes for the module to complete. 2 minutes on a 7280TR

@rohitthakur2590 rohitthakur2590 self-assigned this Sep 6, 2023
@rohitthakur2590
Copy link
Contributor

@michaeljaboyd I have tried running the same task with the latest releases, and this is not reproducible at our end,
could you share detailed debug logs if this issue still persists at your end?

you can enable debug logs as shown below:

export ANSIBLE_LOG_PATH=ansible.log export ANSIBLE_PERSISTENT_LOG_MESSAGE=TRUE export ANSIBLE_DEBUG=TRUE

@rohitthakur2590 rohitthakur2590 added the needs_info This issue requires further information. Please answer any outstanding questions. label Oct 9, 2023
@michaeljaboyd
Copy link
Author

Thank you for looking into this issue. Sure Please find the output requested bellow:-

Playbook

---

- name: configure hosts playbook
  hosts: spines,leafs
  gather_facts: yes
  vars:
    "interface_descriptions": {}
  pre_tasks:

  tasks:

    - name: take backup of config for quick and accurate config compairision (using show run all)
      arista.eos.eos_config:
        defaults: True
        backup: True
        backup_options:
          dir_path: "/tmp/ansible_network_config_cache/"
      register: running_config_backup
      changed_when: False
      vars:
        ansible_command_timeout: 600

Ansible.log

2023-10-13 13:00:36,805 p=28343 u=user1 n=ansible | PLAY [configure hosts playbook] ************************************************
2023-10-13 13:00:36,847 p=28343 u=user1 n=ansible | TASK [Gathering Facts] *********************************************************
2023-10-13 13:00:37,496 p=28350 u=user1 n=ansible | network_os is set to arista.eos.eos
2023-10-13 13:00:40,840 p=28343 u=user1 n=ansible | ok: [spine-site-a-1]
2023-10-13 13:00:40,851 p=28343 u=user1 n=ansible | TASK [take backup of config for quick and accurate config compairision (using show run all)] ***
2023-10-13 13:06:51,570 p=28343 u=user1 n=ansible | ok: [spine-site-a-1]
2023-10-13 13:06:51,582 p=28343 u=user1 n=ansible | PLAY RECAP *********************************************************************
2023-10-13 13:06:51,582 p=28343 u=user1 n=ansible | spine-site-a-1              : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0   
2023-10-13 13:06:51,682 p=28357 u=user1 n=ansible | shutdown complete

Stdio

 28343 1697198436.71492: starting run
 28343 1697198436.72021: Added group all to inventory
 28343 1697198436.72032: Added group ungrouped to inventory
 28343 1697198436.72040: Group all now contains ungrouped
 28343 1697198436.72049: Examining possible inventory source: /home/user1/git/network/hosts
 28343 1697198436.72235: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/cache
 28343 1697198436.72290: Loading CacheModule 'memory' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/cache/memory.py
 28343 1697198436.72312: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory
 28343 1697198436.72365: Loading InventoryModule 'host_list' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/host_list.py
 28343 1697198436.72436: Loaded config def from plugin (inventory/script)
 28343 1697198436.72441: Loading InventoryModule 'script' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/script.py
 28343 1697198436.72478: Loading InventoryModule 'auto' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/auto.py
 28343 1697198436.72544: Loaded config def from plugin (inventory/yaml)
 28343 1697198436.72550: Loading InventoryModule 'yaml' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/yaml.py
 28343 1697198436.72619: Loading InventoryModule 'ini' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/ini.py
 28343 1697198436.72671: Loading InventoryModule 'toml' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/toml.py
 28343 1697198436.72676: Attempting to use plugin host_list (/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/host_list.py)
 28343 1697198436.72681: Attempting to use plugin script (/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/script.py)
 28343 1697198436.72689: Attempting to use plugin auto (/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/auto.py)
 28343 1697198436.72693: Attempting to use plugin yaml (/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/yaml.py)
 28343 1697198436.72733: Loading data from /home/user1/git/network/hosts
 28343 1697198436.72784: /home/user1/git/network/hosts was not parsable by yaml
 28343 1697198436.72814: Attempting to use plugin ini (/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/inventory/ini.py)
 28343 1697198436.72893: set ansible_connection for all
 28343 1697198436.72899: set ansible_become for all
 28343 1697198436.72905: set ansible_become_method for all
 28343 1697198436.72911: set ansible_network_os for all
 28343 1697198436.72915: Added group spines to inventory
 28343 1697198436.72920: Added group spine_sa to inventory
 28343 1697198436.72926: Group spines now contains spine_sa
 28343 1697198436.72937: set inventory_file for spine-site-a-1
 28343 1697198436.72943: set inventory_dir for spine-site-a-1
 28343 1697198436.72946: Added host spine-site-a-1 to inventory
 28343 1697198436.72950: Added host spine-site-a-1 to group spine_sa
 28343 1697198436.72958: set inventory_file for spine-site-a-2
 28343 1697198436.72964: set inventory_dir for spine-site-a-2
 28343 1697198436.72967: Added host spine-site-a-2 to inventory
 28343 1697198436.72971: Added host spine-site-a-2 to group spine_sa
 28343 1697198436.72975: Added group spine_sb to inventory
 28343 1697198436.72979: Group spines now contains spine_sb
 28343 1697198436.72987: set inventory_file for spine-site-b-1
 28343 1697198436.72993: set inventory_dir for spine-site-b-1
 28343 1697198436.72996: Added host spine-site-b-1 to inventory
 28343 1697198436.73000: Added host spine-site-b-1 to group spine_sb
 28343 1697198436.73008: set inventory_file for spine-site-b-2
 28343 1697198436.73013: set inventory_dir for spine-site-b-2
 28343 1697198436.73016: Added host spine-site-b-2 to inventory
 28343 1697198436.73020: Added host spine-site-b-2 to group spine_sb
 28343 1697198436.73024: Added group leafs to inventory
 28343 1697198436.73029: Added group leaf_sa to inventory
 28343 1697198436.73033: Group leafs now contains leaf_sa
 28343 1697198436.73041: set inventory_file for leaf-site-a-1
 28343 1697198436.73046: set inventory_dir for leaf-site-a-1
 28343 1697198436.73049: Added host leaf-site-a-1 to inventory
 28343 1697198436.73053: Added host leaf-site-a-1 to group leaf_sa
 28343 1697198436.73061: set inventory_file for leaf-site-a-2
 28343 1697198436.73066: set inventory_dir for leaf-site-a-2
 28343 1697198436.73069: Added host leaf-site-a-2 to inventory
 28343 1697198436.73073: Added host leaf-site-a-2 to group leaf_sa
 28343 1697198436.73077: Added group leaf_sb to inventory
 28343 1697198436.73081: Group leafs now contains leaf_sb
 28343 1697198436.73085: Reconcile groups and hosts in inventory.
 28343 1697198436.73092: Group all now contains spines
 28343 1697198436.73098: Group all now contains leafs
 28343 1697198436.73278: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments
 28343 1697198436.73335: Loading ModuleDocFragment 'vars_plugin_staging' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/vars_plugin_staging.py
 28343 1697198436.73367: Loaded config def from plugin (vars/host_group_vars)
 28343 1697198436.73371: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py
 28343 1697198436.73403: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73431: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73459: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73487: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73514: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73541: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73568: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73593: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73618: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73642: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73667: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73692: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73716: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.73750: Loading CacheModule 'memory' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/cache/memory.py (found_in_cache=True, class_only=False)
 28343 1697198436.74692: Loading ModuleDocFragment 'connection_pipelining' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/connection_pipelining.py
 28343 1697198436.74744: Loaded config def from plugin (connection/local)
 28343 1697198436.74756: Loading Connection 'local' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/local.py (found_in_cache=False, class_only=True)
 28343 1697198436.75243: Loaded config def from plugin (connection/paramiko_ssh)
 28343 1697198436.75257: Loading Connection 'paramiko_ssh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/paramiko_ssh.py (found_in_cache=False, class_only=True)
 28343 1697198436.76065: Loading ModuleDocFragment 'connection_pipelining' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/connection_pipelining.py (found_in_cache=True, class_only=False)
 28343 1697198436.76106: Loaded config def from plugin (connection/psrp)
 28343 1697198436.76112: Loading Connection 'psrp' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/psrp.py (found_in_cache=False, class_only=True)
 28343 1697198436.76762: Loading ModuleDocFragment 'connection_pipelining' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/connection_pipelining.py (found_in_cache=True, class_only=False)
 28343 1697198436.76801: Loaded config def from plugin (connection/ssh)
 28343 1697198436.76806: Loading Connection 'ssh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=False, class_only=True)
 28343 1697198436.77084: Loading ModuleDocFragment 'connection_pipelining' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/connection_pipelining.py (found_in_cache=True, class_only=False)
 28343 1697198436.77122: Loaded config def from plugin (connection/winrm)
 28343 1697198436.77129: Loading Connection 'winrm' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/winrm.py (found_in_cache=False, class_only=True)
 28343 1697198436.77223: Loading ModuleDocFragment 'shell_windows' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/shell_windows.py
 28343 1697198436.77285: Loaded config def from plugin (shell/cmd)
 28343 1697198436.77292: Loading ShellModule 'cmd' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/cmd.py (found_in_cache=False, class_only=True)
 28343 1697198436.77315: Loading ModuleDocFragment 'shell_windows' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/shell_windows.py (found_in_cache=True, class_only=False)
 28343 1697198436.77376: Loaded config def from plugin (shell/powershell)
 28343 1697198436.77380: Loading ShellModule 'powershell' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/powershell.py (found_in_cache=False, class_only=True)
 28343 1697198436.77427: Loading ModuleDocFragment 'shell_common' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/shell_common.py
 28343 1697198436.77577: Loaded config def from plugin (shell/sh)
 28343 1697198436.77581: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=False, class_only=True)
 28343 1697198436.77764: Loaded config def from plugin (become/runas)
 28343 1697198436.77770: Loading BecomeModule 'runas' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become/runas.py (found_in_cache=False, class_only=True)
 28343 1697198436.77966: Loaded config def from plugin (become/su)
 28343 1697198436.77977: Loading BecomeModule 'su' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become/su.py (found_in_cache=False, class_only=True)
 28343 1697198436.78172: Loaded config def from plugin (become/sudo)
 28343 1697198436.78185: Loading BecomeModule 'sudo' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become/sudo.py (found_in_cache=False, class_only=True)
 28343 1697198436.78238: Loading data from /home/user1/git/network/github.yml
 28343 1697198436.79055: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/callback
 28343 1697198436.79149: Loading ModuleDocFragment 'default_callback' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/default_callback.py
 28343 1697198436.79314: Loading ModuleDocFragment 'result_format_callback' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/result_format_callback.py
 28343 1697198436.79370: Loaded config def from plugin (callback/default)
 28343 1697198436.79376: Loading CallbackModule 'default' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/callback/default.py
 28343 1697198436.79431: Loading CallbackModule 'default' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/callback/default.py (found_in_cache=True, class_only=True)
 28343 1697198436.80161: Loaded config def from plugin (callback/junit)
 28343 1697198436.80169: Loading CallbackModule 'junit' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/callback/junit.py (found_in_cache=False, class_only=True)
 28343 1697198436.80219: Loading ModuleDocFragment 'result_format_callback' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/doc_fragments/result_format_callback.py (found_in_cache=True, class_only=False)
 28343 1697198436.80280: Loaded config def from plugin (callback/minimal)
 28343 1697198436.80287: Loading CallbackModule 'minimal' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/callback/minimal.py (found_in_cache=False, class_only=True)
 28343 1697198436.80324: Loading CallbackModule 'oneline' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/callback/oneline.py (found_in_cache=False, class_only=True)
 28343 1697198436.80380: Loaded config def from plugin (callback/tree)
 28343 1697198436.80386: Loading CallbackModule 'tree' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/callback/tree.py (found_in_cache=False, class_only=True)
 28343 1697198436.80401: in VariableManager get_vars()
 28343 1697198436.80451: done with get_vars()
 28343 1697198436.80462: in VariableManager get_vars()
 28343 1697198436.80477: done with get_vars()
 28343 1697198436.80486: variable 'omit' from source: magic vars
 28343 1697198436.80510: in VariableManager get_vars()
 28343 1697198436.80526: done with get_vars()
 28343 1697198436.80544: variable 'omit' from source: magic vars

PLAY [configure hosts playbook] ************************************************
 28343 1697198436.83535: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/strategy
 28343 1697198436.83580: Loading StrategyModule 'linear' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/strategy/linear.py
 28343 1697198436.83617: getting the remaining hosts for this loop
 28343 1697198436.83623: done getting the remaining hosts for this loop
 28343 1697198436.83632: building list of next tasks for hosts
 28343 1697198436.83639: getting the next task for host spine-site-a-1
 28343 1697198436.83645: done getting next task for host spine-site-a-1
 28343 1697198436.83651:  ^ task is: TASK: Gathering Facts
 28343 1697198436.83658:  ^ state is: HOST STATE: block=0, task=0, rescue=0, always=0, run_state=IteratingStates.SETUP, fail_state=FailedStates.NONE, pending_setup=True, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
 28343 1697198436.83662: done building task lists
 28343 1697198436.83666: counting tasks in each state of execution
 28343 1697198436.83673: done counting tasks in each state of execution:
	num_setups: 1
	num_tasks: 0
	num_rescue: 0
	num_always: 0
 28343 1697198436.83677: advancing hosts in SETUP
 28343 1697198436.83681: starting to advance hosts
 28343 1697198436.83685: done advancing hosts to next task
 28343 1697198436.83691: getting variables
 28343 1697198436.83697: in VariableManager get_vars()
 28343 1697198436.83712: Calling all_inventory to load vars for spine-site-a-1
 28343 1697198436.83719: Calling groups_inventory to load vars for spine-site-a-1
 28343 1697198436.83726: Calling all_plugins_inventory to load vars for spine-site-a-1
 28343 1697198436.83749: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.83775: Calling all_plugins_play to load vars for spine-site-a-1
 28343 1697198436.83794: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.83814: Calling groups_plugins_inventory to load vars for spine-site-a-1
 28343 1697198436.83835: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.83862: Calling groups_plugins_play to load vars for spine-site-a-1
 28343 1697198436.83881: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.83925: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.83944: 	processing dir /home/user1/git/network/host_vars
 28343 1697198436.83957: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198436.84366: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198436.84388: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198436.84462: done with get_vars()
 28343 1697198436.84473: done getting variables
 28343 1697198436.84584: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/action
 28343 1697198436.84667: Loading ActionModule 'gather_facts' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/action/gather_facts.py (found_in_cache=False, class_only=True)
 28343 1697198436.84675: sending task start callback, copying the task so we can template it temporarily
 28343 1697198436.84680: done copying, going to template now
 28343 1697198436.84685: done templating
 28343 1697198436.84689: here goes the callback...

TASK [Gathering Facts] *********************************************************
 28343 1697198436.84712: sending task start callback
 28343 1697198436.84717: entering _queue_task() for spine-site-a-1/gather_facts
 28343 1697198436.84722: Creating lock for gather_facts
 28343 1697198436.84891: worker is 1 (out of 1 available)
 28343 1697198436.84919: exiting _queue_task() for spine-site-a-1/gather_facts
 28343 1697198436.84969: done queuing things up, now waiting for results queue to drain
 28343 1697198436.84975: waiting for pending results...
 28350 1697198436.85006: running TaskExecutor() for spine-site-a-1/TASK: Gathering Facts
 28350 1697198436.85050: in run() - task 90b11c8e-6458-4667-9bd6-000000000011
 28350 1697198436.85067: variable 'ansible_search_path' from source: unknown
 28350 1697198436.85091: calling self._execute()
 28350 1697198436.85129: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.85136: variable 'ansible_network_os' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.85142: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.85148: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.85157: variable 'omit' from source: magic vars
 28350 1697198436.85263: variable 'omit' from source: magic vars
 28350 1697198436.85288: variable 'omit' from source: magic vars
 28350 1697198436.85329: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.85335: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.87688: Loading ModuleDocFragment 'ansible_collections.ansible.netcommon.plugins.doc_fragments.connection_persistent' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/doc_fragments/connection_persistent.py
 28350 1697198436.87790: Loaded config def from plugin (connection/ansible_collections.ansible.netcommon.plugins.connection.network_cli)
 28350 1697198436.87797: Loading Connection 'ansible_collections.ansible.netcommon.plugins.connection.network_cli' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/connection/network_cli.py
 28350 1697198436.87815: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell
 28350 1697198436.87832: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28350 1697198436.87843: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28350 1697198436.87857: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection
 28350 1697198436.87879: Loading Connection 'local' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/local.py (found_in_cache=True, class_only=False)
 28350 1697198436.87887: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28350 1697198436.87898: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28350 1697198436.87927: Loading Connection 'ssh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=True, class_only=True)
 28350 1697198436.88261: Loading TerminalModule 'ansible_collections.arista.eos.plugins.terminal.eos' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/arista/eos/plugins/terminal/eos.py
 28350 1697198436.90709: Loaded config def from plugin (cliconf/ansible_collections.arista.eos.plugins.cliconf.eos)
 28350 1697198436.90717: Loading Cliconf 'ansible_collections.arista.eos.plugins.cliconf.eos' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/arista/eos/plugins/cliconf/eos.py
 28350 1697198436.90724: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.90727: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.90732: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.90738: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.90750: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become
 28350 1697198436.90764: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become/__pycache__
 28350 1697198436.90879: Loaded config def from plugin (become/ansible_collections.ansible.netcommon.plugins.become.enable)
 28350 1697198436.90884: Loading BecomeModule 'ansible_collections.ansible.netcommon.plugins.become.enable' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/become/enable.py (searched paths: /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become/__pycache__:/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become)
 28350 1697198436.90895: variable 'inventory_hostname' from source: host vars for 'spine-site-a-1'
 28350 1697198436.90899: variable 'ansible_network_os' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.90903: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.90906: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.91352: Loaded config def from plugin (connection/ansible_collections.ansible.netcommon.plugins.connection.libssh)
 28350 1697198436.91363: Loading Connection 'ansible_collections.ansible.netcommon.plugins.connection.libssh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/connection/libssh.py
 28350 1697198436.91379: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28350 1697198436.91391: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28350 1697198436.91525: variable 'inventory_hostname' from source: host vars for 'spine-site-a-1'
 28350 1697198436.91584: Set connection var ansible_port to 22
 28350 1697198436.91594: Set connection var ansible_pipelining to False
 28350 1697198436.91603: Set connection var ansible_host to spine-site-a-1
 28350 1697198436.91612: Set connection var ansible_timeout to 10
 28350 1697198436.91621: Set connection var ansible_shell_type to sh
 28350 1697198436.91629: Set connection var ansible_shell_executable to /bin/sh
 28350 1697198436.91640: Set connection var ansible_module_compression to ZIP_DEFLATED
 28350 1697198436.91664: variable 'ansible_shell_executable' from source: unknown
 28350 1697198436.91670: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.91676: variable 'ansible_module_compression' from source: unknown
 28350 1697198436.91682: variable 'ansible_shell_type' from source: unknown
 28350 1697198436.91688: variable 'ansible_shell_executable' from source: unknown
 28350 1697198436.91699: variable 'ansible_host' from source: unknown
 28350 1697198436.91706: variable 'ansible_port' from source: unknown
 28350 1697198436.91712: variable 'ansible_pipelining' from source: unknown
 28350 1697198436.91722: variable 'ansible_timeout' from source: unknown
 28350 1697198436.91729: variable 'ansible_network_os' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.91733: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28350 1697198436.91736: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28350 1697198437.49745: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/modules
 28350 1697198437.49917: Loading ActionModule 'gather_facts' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/action/gather_facts.py (found_in_cache=True, class_only=False)
 28350 1697198437.49930: variable 'omit' from source: magic vars
 28350 1697198437.49937: starting attempt loop
 28350 1697198437.49941: running the handler
 28350 1697198437.49960: variable 'ansible_facts' from source: unknown
 28350 1697198437.49964: variable 'ansible_network_os' from source: group vars, precedence entry 'all_inventory'
 28350 1697198437.50035: _low_level_execute_command(): starting
 28350 1697198437.50043: _low_level_execute_command(): executing: /bin/sh -c '( umask 77 && mkdir -p "` echo /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj `"&& mkdir "` echo /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342 `" && echo ansible-tmp-1697198437.500292-28350-97884362885342="` echo /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342 `" ) && sleep 0'
 28350 1697198437.50051: in local.exec_command()
 28350 1697198437.50086: opening command with Popen()
 28350 1697198437.50281: done running command with Popen()
 28350 1697198437.50292: getting output with communicate()
 28350 1697198437.50940: done communicating
 28350 1697198437.50947: done with local.exec_command()
 28350 1697198437.50955: _low_level_execute_command() done: rc=0, stdout=ansible-tmp-1697198437.500292-28350-97884362885342=/home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342
, stderr=
 28350 1697198437.51074: ANSIBALLZ: Using generic lock for arista.eos.eos_facts
 28350 1697198437.51078: ANSIBALLZ: Acquiring lock
 28350 1697198437.51083: ANSIBALLZ: Lock acquired: 139893745942736
 28350 1697198437.51088: ANSIBALLZ: Creating module
 28350 1697198437.79672: ANSIBALLZ: Writing module into payload
 28350 1697198437.79813: ANSIBALLZ: Writing module
 28350 1697198437.79841: ANSIBALLZ: Renaming module
 28350 1697198437.79847: ANSIBALLZ: Done creating module
 28350 1697198437.79879: variable 'ansible_playbook_python' from source: magic vars
 28350 1697198437.79943: transferring module to remote /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342/AnsiballZ_eos_facts.py
 28350 1697198437.80031: done transferring module to remote
 28350 1697198437.80044: _low_level_execute_command(): starting
 28350 1697198437.80051: _low_level_execute_command(): executing: /bin/sh -c 'chmod u+x /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342/ /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342/AnsiballZ_eos_facts.py && sleep 0'
 28350 1697198437.80056: in local.exec_command()
 28350 1697198437.80061: opening command with Popen()
 28350 1697198437.80286: done running command with Popen()
 28350 1697198437.80297: getting output with communicate()
 28350 1697198437.80721: done communicating
 28350 1697198437.80727: done with local.exec_command()
 28350 1697198437.80735: _low_level_execute_command() done: rc=0, stdout=, stderr=
 28350 1697198437.80740: _low_level_execute_command(): starting
 28350 1697198437.80758: _low_level_execute_command(): executing: /bin/sh -c '/home/user1/git/network/bin/python /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342/AnsiballZ_eos_facts.py && sleep 0'
 28350 1697198437.80763: in local.exec_command()
 28350 1697198437.80770: opening command with Popen()
 28350 1697198437.80983: done running command with Popen()
 28350 1697198437.80996: getting output with communicate()
 28350 1697198440.82813: done communicating
 28350 1697198440.82822: done with local.exec_command()
 28350 1697198440.82833: _low_level_execute_command() done: rc=0, stdout=
{"ansible_facts": {"ansible_network_resources": {}, "ansible_net_gather_network_resources": [], "ansible_net_gather_subset": ["default"], "ansible_net_serialnum": "JPA2322P1MR", "ansible_net_hostname": "spine-site-a-1", "ansible_net_fqdn": "spine-site-a-1", "ansible_net_system": "eos", "ansible_net_model": "DCS-7260CX3-64-R", "ansible_net_image": "flash:/EOS-4.29.4M.swi", "ansible_net_version": "4.29.4M", "ansible_net_api": "cliconf", "ansible_net_python_version": "3.8.13"}, "warnings": [], "invocation": {"module_args": {"gather_subset": ["min"], "available_network_resources": false, "gather_network_resources": null, "provider": null}}}
, stderr=
 28350 1697198440.82895: done with _execute_module (arista.eos.eos_facts, {'_ansible_check_mode': True, '_ansible_no_log': False, '_ansible_debug': True, '_ansible_diff': True, '_ansible_verbosity': 0, '_ansible_version': '2.13.10', '_ansible_module_name': 'arista.eos.eos_facts', '_ansible_syslog_facility': 'LOG_USER', '_ansible_selinux_special_fs': ['fuse', 'nfs', 'vboxsf', 'ramfs', '9p', 'vfat'], '_ansible_string_conversion_action': 'warn', '_ansible_socket': '/home/user1/.ansible/pc/0153088484', '_ansible_shell_executable': '/bin/sh', '_ansible_keep_remote_files': False, '_ansible_tmpdir': '/home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342/', '_ansible_remote_tmp': '~/.ansible/tmp'})
 28350 1697198440.82920: _low_level_execute_command(): starting
 28350 1697198440.82935: _low_level_execute_command(): executing: /bin/sh -c 'rm -f -r /home/user1/.ansible/tmp/ansible-local-28343g2fp36nj/ansible-tmp-1697198437.500292-28350-97884362885342/ > /dev/null 2>&1 && sleep 0'
 28350 1697198440.82941: in local.exec_command()
 28350 1697198440.82951: opening command with Popen()
 28350 1697198440.83188: done running command with Popen()
 28350 1697198440.83203: getting output with communicate()
 28350 1697198440.83635: done communicating
 28350 1697198440.83643: done with local.exec_command()
 28350 1697198440.83652: _low_level_execute_command() done: rc=0, stdout=, stderr=
 28350 1697198440.83663: handler run complete
 28350 1697198440.83680: variable 'ansible_facts' from source: unknown
 28350 1697198440.83759: variable 'ansible_facts' from source: unknown
 28350 1697198440.83798: attempt loop complete, returning result
 28350 1697198440.83802: _execute() done
 28350 1697198440.83805: dumping result to json
 28350 1697198440.83809: done dumping result, returning
 28350 1697198440.83817: done running TaskExecutor() for spine-site-a-1/TASK: Gathering Facts [90b11c8e-6458-4667-9bd6-000000000011]
 28350 1697198440.83821: sending task result for task 90b11c8e-6458-4667-9bd6-000000000011
 28350 1697198440.83864: done sending task result for task 90b11c8e-6458-4667-9bd6-000000000011
 28350 1697198440.83896: WORKER PROCESS EXITING
ok: [spine-site-a-1]
 28343 1697198440.84020: no more pending results, returning what we have
 28343 1697198440.84027: results queue empty
 28343 1697198440.84031: checking for any_errors_fatal
 28343 1697198440.84036: done checking for any_errors_fatal
 28343 1697198440.84040: checking for max_fail_percentage
 28343 1697198440.84045: done checking for max_fail_percentage
 28343 1697198440.84049: checking to see if all hosts have failed and the running result is not ok
 28343 1697198440.84053: done checking to see if all hosts have failed
 28343 1697198440.84057: getting the remaining hosts for this loop
 28343 1697198440.84062: done getting the remaining hosts for this loop
 28343 1697198440.84070: building list of next tasks for hosts
 28343 1697198440.84075: getting the next task for host spine-site-a-1
 28343 1697198440.84082: done getting next task for host spine-site-a-1
 28343 1697198440.84087:  ^ task is: TASK: meta (flush_handlers)
 28343 1697198440.84094:  ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, run_state=IteratingStates.TASKS, fail_state=FailedStates.NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
 28343 1697198440.84098: done building task lists
 28343 1697198440.84102: counting tasks in each state of execution
 28343 1697198440.84108: done counting tasks in each state of execution:
	num_setups: 0
	num_tasks: 1
	num_rescue: 0
	num_always: 0
 28343 1697198440.84112: advancing hosts in TASKS
 28343 1697198440.84116: starting to advance hosts
 28343 1697198440.84121: done advancing hosts to next task
 28343 1697198440.84126: getting variables
 28343 1697198440.84130: in VariableManager get_vars()
 28343 1697198440.84145: Calling all_inventory to load vars for spine-site-a-1
 28343 1697198440.84152: Calling groups_inventory to load vars for spine-site-a-1
 28343 1697198440.84158: Calling all_plugins_inventory to load vars for spine-site-a-1
 28343 1697198440.84188: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84219: Calling all_plugins_play to load vars for spine-site-a-1
 28343 1697198440.84237: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84259: Calling groups_plugins_inventory to load vars for spine-site-a-1
 28343 1697198440.84280: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84308: Calling groups_plugins_play to load vars for spine-site-a-1
 28343 1697198440.84327: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84370: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84394: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198440.84415: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84436: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198440.84493: done with get_vars()
 28343 1697198440.84504: done getting variables
 28343 1697198440.84515: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/action/__pycache__
 28343 1697198440.84595: done queuing things up, now waiting for results queue to drain
 28343 1697198440.84601: results queue empty
 28343 1697198440.84605: checking for any_errors_fatal
 28343 1697198440.84611: done checking for any_errors_fatal
 28343 1697198440.84615: checking for max_fail_percentage
 28343 1697198440.84620: done checking for max_fail_percentage
 28343 1697198440.84624: checking to see if all hosts have failed and the running result is not ok
 28343 1697198440.84628: done checking to see if all hosts have failed
 28343 1697198440.84632: getting the remaining hosts for this loop
 28343 1697198440.84636: done getting the remaining hosts for this loop
 28343 1697198440.84644: building list of next tasks for hosts
 28343 1697198440.84648: getting the next task for host spine-site-a-1
 28343 1697198440.84654: done getting next task for host spine-site-a-1
 28343 1697198440.84658:  ^ task is: TASK: take backup of config for quick and accurate config compairision (using show run all)
 28343 1697198440.84664:  ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, run_state=IteratingStates.TASKS, fail_state=FailedStates.NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
 28343 1697198440.84668: done building task lists
 28343 1697198440.84672: counting tasks in each state of execution
 28343 1697198440.84677: done counting tasks in each state of execution:
	num_setups: 0
	num_tasks: 1
	num_rescue: 0
	num_always: 0
 28343 1697198440.84689: advancing hosts in TASKS
 28343 1697198440.84695: starting to advance hosts
 28343 1697198440.84701: done advancing hosts to next task
 28343 1697198440.84706: getting variables
 28343 1697198440.84709: in VariableManager get_vars()
 28343 1697198440.84720: Calling all_inventory to load vars for spine-site-a-1
 28343 1697198440.84725: Calling groups_inventory to load vars for spine-site-a-1
 28343 1697198440.84731: Calling all_plugins_inventory to load vars for spine-site-a-1
 28343 1697198440.84760: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84799: Calling all_plugins_play to load vars for spine-site-a-1
 28343 1697198440.84818: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84838: Calling groups_plugins_inventory to load vars for spine-site-a-1
 28343 1697198440.84856: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84880: Calling groups_plugins_play to load vars for spine-site-a-1
 28343 1697198440.84899: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84940: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84961: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198440.84979: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198440.84999: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198440.85048: done with get_vars()
 28343 1697198440.85061: done getting variables
 28343 1697198440.85085: sending task start callback, copying the task so we can template it temporarily
 28343 1697198440.85089: done copying, going to template now
 28343 1697198440.85093: done templating
 28343 1697198440.85096: here goes the callback...

TASK [take backup of config for quick and accurate config compairision (using show run all)] ***
 28343 1697198440.85118: sending task start callback
 28343 1697198440.85124: entering _queue_task() for spine-site-a-1/arista.eos.eos_config
 28343 1697198440.85128: Creating lock for arista.eos.eos_config
 28343 1697198440.85316: worker is 1 (out of 1 available)
 28343 1697198440.85350: exiting _queue_task() for spine-site-a-1/arista.eos.eos_config
 28343 1697198440.85407: done queuing things up, now waiting for results queue to drain
 28343 1697198440.85414: waiting for pending results...
 28383 1697198440.85455: running TaskExecutor() for spine-site-a-1/TASK: take backup of config for quick and accurate config compairision (using show run all)
 28383 1697198440.85526: in run() - task 90b11c8e-6458-4667-9bd6-00000000000d
 28383 1697198440.85548: variable 'ansible_search_path' from source: unknown
 28383 1697198440.85577: calling self._execute()
 28383 1697198440.85636: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.85646: variable 'ansible_network_os' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.85653: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.85659: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.85669: variable 'omit' from source: magic vars
 28383 1697198440.85745: variable 'omit' from source: magic vars
 28383 1697198440.85769: variable 'omit' from source: magic vars
 28383 1697198440.85817: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.85823: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.87932: Loading ModuleDocFragment 'ansible_collections.ansible.netcommon.plugins.doc_fragments.connection_persistent' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/doc_fragments/connection_persistent.py
 28383 1697198440.88031: Loaded config def from plugin (connection/ansible_collections.ansible.netcommon.plugins.connection.network_cli)
 28383 1697198440.88038: Loading Connection 'ansible_collections.ansible.netcommon.plugins.connection.network_cli' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/connection/network_cli.py
 28383 1697198440.88053: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell
 28383 1697198440.88077: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28383 1697198440.88086: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28383 1697198440.88101: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection
 28383 1697198440.88123: Loading Connection 'local' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/local.py (found_in_cache=True, class_only=False)
 28383 1697198440.88131: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28383 1697198440.88139: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28383 1697198440.88167: Loading Connection 'ssh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/connection/ssh.py (found_in_cache=True, class_only=True)
 28383 1697198440.88496: Loading TerminalModule 'ansible_collections.arista.eos.plugins.terminal.eos' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/arista/eos/plugins/terminal/eos.py
 28383 1697198440.91221: Loaded config def from plugin (cliconf/ansible_collections.arista.eos.plugins.cliconf.eos)
 28383 1697198440.91233: Loading Cliconf 'ansible_collections.arista.eos.plugins.cliconf.eos' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/arista/eos/plugins/cliconf/eos.py
 28383 1697198440.91241: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.91248: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.91251: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.91256: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.91271: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become
 28383 1697198440.91291: trying /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become/__pycache__
 28383 1697198440.91496: Loaded config def from plugin (become/ansible_collections.ansible.netcommon.plugins.become.enable)
 28383 1697198440.91508: Loading BecomeModule 'ansible_collections.ansible.netcommon.plugins.become.enable' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/become/enable.py (searched paths: /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become/__pycache__:/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/become)
 28383 1697198440.91528: variable 'inventory_hostname' from source: host vars for 'spine-site-a-1'
 28383 1697198440.91534: variable 'ansible_network_os' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.91540: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.91546: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.91552: variable 'ansible_command_timeout' from source: task vars
 28383 1697198440.92068: Loaded config def from plugin (connection/ansible_collections.ansible.netcommon.plugins.connection.libssh)
 28383 1697198440.92078: Loading Connection 'ansible_collections.ansible.netcommon.plugins.connection.libssh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/ansible/netcommon/plugins/connection/libssh.py
 28383 1697198440.92091: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28383 1697198440.92099: Loading ShellModule 'sh' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/shell/sh.py (found_in_cache=True, class_only=False)
 28383 1697198440.92188: variable 'inventory_hostname' from source: host vars for 'spine-site-a-1'
 28383 1697198440.92232: Set connection var ansible_port to 22
 28383 1697198440.92240: Set connection var ansible_pipelining to False
 28383 1697198440.92248: Set connection var ansible_host to spine-site-a-1
 28383 1697198440.92254: Set connection var ansible_timeout to 10
 28383 1697198440.92260: Set connection var ansible_shell_type to sh
 28383 1697198440.92266: Set connection var ansible_shell_executable to /bin/sh
 28383 1697198440.92274: Set connection var ansible_module_compression to ZIP_DEFLATED
 28383 1697198440.92289: variable 'ansible_shell_executable' from source: unknown
 28383 1697198440.92294: variable 'ansible_connection' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.92299: variable 'ansible_module_compression' from source: unknown
 28383 1697198440.92303: variable 'ansible_shell_type' from source: unknown
 28383 1697198440.92307: variable 'ansible_shell_executable' from source: unknown
 28383 1697198440.92312: variable 'ansible_host' from source: unknown
 28383 1697198440.92317: variable 'ansible_port' from source: unknown
 28383 1697198440.92322: variable 'ansible_pipelining' from source: unknown
 28383 1697198440.92327: variable 'ansible_timeout' from source: unknown
 28383 1697198440.92331: variable 'ansible_network_os' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.92336: variable 'ansible_become' from source: group vars, precedence entry 'all_inventory'
 28383 1697198440.92341: variable 'ansible_become_method' from source: group vars, precedence entry 'all_inventory'
 28383 1697198441.39251: Loading ActionModule 'ansible_collections.arista.eos.plugins.action.eos' from /home/user1/git/network/lib64/python3.8/site-packages/ansible_collections/arista/eos/plugins/action/eos.py (searched paths: /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/action/__pycache__:/home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/action)
 28383 1697198441.39271: variable 'omit' from source: magic vars
 28383 1697198441.39277: starting attempt loop
 28383 1697198441.39282: running the handler
 28383 1697198441.39291: variable 'ansible_host' from source: unknown
 28383 1697198811.56495: variable 'inventory_hostname' from source: host vars for 'spine-site-a-1'
 28383 1697198811.56808: handler run complete
 28383 1697198811.56843: Evaluated conditional (False): False
 28383 1697198811.56851: attempt loop complete, returning result
 28383 1697198811.56856: _execute() done
 28383 1697198811.56861: dumping result to json
 28383 1697198811.56867: done dumping result, returning
 28383 1697198811.56875: done running TaskExecutor() for spine-site-a-1/TASK: take backup of config for quick and accurate config compairision (using show run all) [90b11c8e-6458-4667-9bd6-00000000000d]
 28383 1697198811.56881: sending task result for task 90b11c8e-6458-4667-9bd6-00000000000d
 28383 1697198811.56921: done sending task result for task 90b11c8e-6458-4667-9bd6-00000000000d
 28383 1697198811.56929: WORKER PROCESS EXITING
ok: [spine-site-a-1]
 28343 1697198811.57075: no more pending results, returning what we have
 28343 1697198811.57083: results queue empty
 28343 1697198811.57089: checking for any_errors_fatal
 28343 1697198811.57098: done checking for any_errors_fatal
 28343 1697198811.57103: checking for max_fail_percentage
 28343 1697198811.57112: done checking for max_fail_percentage
 28343 1697198811.57118: checking to see if all hosts have failed and the running result is not ok
 28343 1697198811.57125: done checking to see if all hosts have failed
 28343 1697198811.57131: getting the remaining hosts for this loop
 28343 1697198811.57137: done getting the remaining hosts for this loop
 28343 1697198811.57147: building list of next tasks for hosts
 28343 1697198811.57152: getting the next task for host spine-site-a-1
 28343 1697198811.57162: done getting next task for host spine-site-a-1
 28343 1697198811.57167:  ^ task is: TASK: meta (flush_handlers)
 28343 1697198811.57175:  ^ state is: HOST STATE: block=3, task=1, rescue=0, always=0, run_state=IteratingStates.TASKS, fail_state=FailedStates.NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
 28343 1697198811.57179: done building task lists
 28343 1697198811.57184: counting tasks in each state of execution
 28343 1697198811.57192: done counting tasks in each state of execution:
	num_setups: 0
	num_tasks: 1
	num_rescue: 0
	num_always: 0
 28343 1697198811.57197: advancing hosts in TASKS
 28343 1697198811.57203: starting to advance hosts
 28343 1697198811.57209: done advancing hosts to next task
 28343 1697198811.57214: getting variables
 28343 1697198811.57217: in VariableManager get_vars()
 28343 1697198811.57232: Calling all_inventory to load vars for spine-site-a-1
 28343 1697198811.57238: Calling groups_inventory to load vars for spine-site-a-1
 28343 1697198811.57243: Calling all_plugins_inventory to load vars for spine-site-a-1
 28343 1697198811.57273: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57302: Calling all_plugins_play to load vars for spine-site-a-1
 28343 1697198811.57321: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57343: Calling groups_plugins_inventory to load vars for spine-site-a-1
 28343 1697198811.57362: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57389: Calling groups_plugins_play to load vars for spine-site-a-1
 28343 1697198811.57411: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57454: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57479: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198811.57501: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57521: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198811.57574: done with get_vars()
 28343 1697198811.57585: done getting variables
 28343 1697198811.57614: done queuing things up, now waiting for results queue to drain
 28343 1697198811.57618: results queue empty
 28343 1697198811.57621: checking for any_errors_fatal
 28343 1697198811.57626: done checking for any_errors_fatal
 28343 1697198811.57629: checking for max_fail_percentage
 28343 1697198811.57631: done checking for max_fail_percentage
 28343 1697198811.57634: checking to see if all hosts have failed and the running result is not ok
 28343 1697198811.57637: done checking to see if all hosts have failed
 28343 1697198811.57639: getting the remaining hosts for this loop
 28343 1697198811.57642: done getting the remaining hosts for this loop
 28343 1697198811.57649: building list of next tasks for hosts
 28343 1697198811.57652: getting the next task for host spine-site-a-1
 28343 1697198811.57657: done getting next task for host spine-site-a-1
 28343 1697198811.57661:  ^ task is: TASK: meta (flush_handlers)
 28343 1697198811.57664:  ^ state is: HOST STATE: block=4, task=1, rescue=0, always=0, run_state=IteratingStates.TASKS, fail_state=FailedStates.NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
 28343 1697198811.57667: done building task lists
 28343 1697198811.57670: counting tasks in each state of execution
 28343 1697198811.57674: done counting tasks in each state of execution:
	num_setups: 0
	num_tasks: 1
	num_rescue: 0
	num_always: 0
 28343 1697198811.57677: advancing hosts in TASKS
 28343 1697198811.57679: starting to advance hosts
 28343 1697198811.57682: done advancing hosts to next task
 28343 1697198811.57685: getting variables
 28343 1697198811.57689: in VariableManager get_vars()
 28343 1697198811.57699: Calling all_inventory to load vars for spine-site-a-1
 28343 1697198811.57704: Calling groups_inventory to load vars for spine-site-a-1
 28343 1697198811.57709: Calling all_plugins_inventory to load vars for spine-site-a-1
 28343 1697198811.57734: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57757: Calling all_plugins_play to load vars for spine-site-a-1
 28343 1697198811.57774: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57794: Calling groups_plugins_inventory to load vars for spine-site-a-1
 28343 1697198811.57812: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57835: Calling groups_plugins_play to load vars for spine-site-a-1
 28343 1697198811.57852: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57892: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57910: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198811.57929: Loading VarsModule 'host_group_vars' from /home/user1/git/network/lib64/python3.8/site-packages/ansible/plugins/vars/host_group_vars.py (found_in_cache=True, class_only=False)
 28343 1697198811.57951: Loading data from /home/user1/git/network/host_vars/spine-site-a-1
 28343 1697198811.57998: done with get_vars()
 28343 1697198811.58006: done getting variables
 28343 1697198811.58028: done queuing things up, now waiting for results queue to drain
 28343 1697198811.58032: results queue empty
 28343 1697198811.58035: checking for any_errors_fatal
 28343 1697198811.58039: done checking for any_errors_fatal
 28343 1697198811.58042: checking for max_fail_percentage
 28343 1697198811.58045: done checking for max_fail_percentage
 28343 1697198811.58047: checking to see if all hosts have failed and the running result is not ok
 28343 1697198811.58050: done checking to see if all hosts have failed
 28343 1697198811.58053: getting the remaining hosts for this loop
 28343 1697198811.58056: done getting the remaining hosts for this loop
 28343 1697198811.58062: building list of next tasks for hosts
 28343 1697198811.58065: getting the next task for host spine-site-a-1
 28343 1697198811.58069: done getting next task for host spine-site-a-1
 28343 1697198811.58072:  ^ task is: None
 28343 1697198811.58075:  ^ state is: HOST STATE: block=5, task=0, rescue=0, always=0, run_state=IteratingStates.COMPLETE, fail_state=FailedStates.NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
 28343 1697198811.58078: done building task lists
 28343 1697198811.58081: counting tasks in each state of execution
 28343 1697198811.58084: done counting tasks in each state of execution:
	num_setups: 0
	num_tasks: 0
	num_rescue: 0
	num_always: 0
 28343 1697198811.58087: all hosts are done, so returning None's for all hosts
 28343 1697198811.58090: done queuing things up, now waiting for results queue to drain
 28343 1697198811.58094: results queue empty
 28343 1697198811.58096: checking for any_errors_fatal
 28343 1697198811.58099: done checking for any_errors_fatal
 28343 1697198811.58102: checking for max_fail_percentage
 28343 1697198811.58104: done checking for max_fail_percentage
 28343 1697198811.58107: checking to see if all hosts have failed and the running result is not ok
 28343 1697198811.58110: done checking to see if all hosts have failed
 28343 1697198811.58113: getting the next task for host spine-site-a-1
 28343 1697198811.58117: done getting next task for host spine-site-a-1
 28343 1697198811.58120:  ^ task is: None
 28343 1697198811.58123:  ^ state is: HOST STATE: block=5, task=0, rescue=0, always=0, run_state=IteratingStates.COMPLETE, fail_state=FailedStates.NONE, pending_setup=False, tasks child state? (None), rescue child state? (None), always child state? (None), did rescue? False, did start at task? False
 28343 1697198811.58127: running handlers

PLAY RECAP *********************************************************************
spine-site-a-1              : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0   

 28343 1697198811.58264: RUNNING CLEANUP

Ran with command
./bin/ansible-playbook github.yml --diff --limit="spine-site-a-1" --check | tee /tmp/ansible_github.out

@michaeljaboyd
Copy link
Author

@rohitthakur2590 I have since tested this against vEOS. vEOS runs the module in a second, with exactly the same playbook. Only when the module runs against real equipment will the slowdown be apparent.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
needs_info This issue requires further information. Please answer any outstanding questions.
Projects
None yet
Development

No branches or pull requests

2 participants