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

SSH auth failure in specific case #436

Closed
trim777 opened this issue Dec 7, 2018 · 10 comments
Closed

SSH auth failure in specific case #436

trim777 opened this issue Dec 7, 2018 · 10 comments

Comments

@trim777
Copy link

@trim777 trim777 commented Dec 7, 2018

I found similar issues, but due to different environment and steps to reproduce, I decided to create another issue.
I have the following code in role:

---

- name: add_msp_repos_url_undefined_consul | {{ item.key }} | Collect and set the msp-scm git hash commit
  delegate_to: 127.0.0.1
  become: no
  command: git rev-parse HEAD
  register: _git_version
  changed_when: false
  when:
    - deployment_mode != 'production'

- name: add_msp_repos_url_undefined_consul | {{ item.key }} | Register facts in Consul
  delegate_to: "{{ hostvars[groups.consul[0]].ansible_ssh_host }}"
  consul_kv:
    key: "{{ _consul_key.key }}/{{ item.key }}"
    value: "{{ _consul_key.value }}"
  loop_control:
    loop_var: _consul_key
  with_dict:
    msp_git_versions: "{{ _git_version.stdout | default('') }}"
    msp_versions: "{{ item.value.version }}"
    msp_builds: "{{ item.value.build if item.value.build else __max_build }}"

Many steps before this one and many roles running without errors. But at step "Register facts in Consul" I'm getting "SSH authentication "

This is output with -vvvv option:

log.txt

On ansible-controller and on targets running CentOS Linux release 7.5.1804 (Core), ansible and python are:

ansible-playbook 2.7.2
  config file = /root/msp-scm/ansible.cfg
  configured module search path = ['/root/msp-scm/library', '/root/msp-scm/submodules/kubespray/library']
  ansible python module location = /root/msp-scm/env/lib/python3.6/site-packages/ansible
  executable location = /root/msp-scm/env/bin/ansible-playbook
  python version = 3.6.6 (default, Aug 13 2018, 18:24:23) [GCC 4.8.5 20150623 (Red Hat 4.8.5-28)]

Ansible-config dump is:

(env) [root@localhost msp-scm]# ansible-config dump --only-changed
ANSIBLE_PIPELINING(/root/msp-scm/ansible.cfg) = True
ANSIBLE_SSH_ARGS(/root/msp-scm/ansible.cfg) = -q -o "ControlMaster=auto" -o "ControlPersist=3600s"
ANSIBLE_SSH_CONTROL_PATH(/root/msp-scm/ansible.cfg) = %(directory)s/%%h-%%r
ANSIBLE_SSH_RETRIES(/root/msp-scm/ansible.cfg) = 5
CACHE_PLUGIN_TIMEOUT(/root/msp-scm/ansible.cfg) = 7200
DEFAULT_BECOME(/root/msp-scm/ansible.cfg) = True
DEFAULT_BECOME_METHOD(/root/msp-scm/ansible.cfg) = sudo
DEFAULT_BECOME_USER(/root/msp-scm/ansible.cfg) = root
DEFAULT_CALLBACK_PLUGIN_PATH(/root/msp-scm/ansible.cfg) = ['/root/msp-scm/plugins/callback']
DEFAULT_CALLBACK_WHITELIST(/root/msp-scm/ansible.cfg) = ['profile_tasks', 'eventlogging', 'sentry']
DEFAULT_FILTER_PLUGIN_PATH(/root/msp-scm/ansible.cfg) = ['/root/msp-scm/filter_plugins']
DEFAULT_FORKS(/root/msp-scm/ansible.cfg) = 10
DEFAULT_HASH_BEHAVIOUR(/root/msp-scm/ansible.cfg) = merge
DEFAULT_MANAGED_STR(/root/msp-scm/ansible.cfg) = Ansible managed
DEFAULT_MODULE_LANG(/root/msp-scm/ansible.cfg) = en_US.UTF-8
DEFAULT_MODULE_PATH(/root/msp-scm/ansible.cfg) = ['/root/msp-scm/library', '/root/msp-scm/submodules/kubespray/library']
DEFAULT_ROLES_PATH(/root/msp-scm/ansible.cfg) = ['/root/msp-scm/roles', '/root/msp-scm/playbooks/percona/roles', '/root/msp-scm/submodules/kubespray/roles']
DEFAULT_SCP_IF_SSH(/root/msp-scm/ansible.cfg) = True
DEFAULT_SFTP_BATCH_MODE(/root/msp-scm/ansible.cfg) = True
DEFAULT_STRATEGY(/root/msp-scm/ansible.cfg) = mitogen_linear
DEFAULT_STRATEGY_PLUGIN_PATH(/root/msp-scm/ansible.cfg) = ['/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/plugins/strategy']
DEFAULT_TIMEOUT(/root/msp-scm/ansible.cfg) = 30
DEPRECATION_WARNINGS(/root/msp-scm/ansible.cfg) = False
HOST_KEY_CHECKING(/root/msp-scm/ansible.cfg) = False
RETRY_FILES_ENABLED(/root/msp-scm/ansible.cfg) = False

Please, help with this issue, if it's possible.

@trim777
Copy link
Author

@trim777 trim777 commented Dec 7, 2018

Also I tried to disable ssh_pipelining and comment all ssh_args, but it didn't help.
May be this is related to ssh banner before login somehow.
This is the same run but without -q option in ssh_args:

log.txt

@dw
Copy link
Member

@dw dw commented Dec 8, 2018

Can you please rerun with '-e mitogen_ssh_debug_level=3', and don't bother with -vvvv output, it is far too noisy, just '-vvv' is fine. Sorry, debugging is a bit vebose at present.

Would it be possible to also include the mitogen_ssh_debug_level=.. + -vvv output for a task that succeeded?

I bet the difference is somewhere in the SSH command line, or if not there, somehow the process environment is varying compared to a regular Ansible run.

Thanks for reporting this!

@trim777
Copy link
Author

@trim777 trim777 commented Dec 8, 2018

Thank you very much for your help, I really appreciate it!
I tried to launch with these options

ansible-playbook -e 'mitogen_ssh_debug_level=3' -vvv -i inventories.local/my_filled_inventory -u username playbook-msp.yml --timeout 120 -e '@builds/7.8.2/deploy-builds.yml' -e '{"dc_id": 60000, "dc_name": "dc_random", "deployment_mode": "local"}' 2>&1 | tee mitogen.verbose.log

but I'm getting error:

TASK [Gathering Facts] *******************************************************************************************************************************************************************************************************************
task path: /root/msp-scm/playbook-msp.yml:44
Saturday 08 December 2018  15:28:47 +0000 (0:00:00.092)       0:00:26.729 ***** 
[pid 6057] 15:28:47.177886 D mitogen: unix.connect(path='/tmp/mitogen_unix_pcop8sfe')
[pid 6057] 15:28:47.179050 D mitogen: unix.connect(): local ID is 2126, remote is 0
[pid 2038] 15:28:47.182158 D mitogen: mitogen.ssh.Stream('default').connect()
ERROR! [pid 2038] 15:28:47.183087 E mitogen: mitogen.service.Pool(0x7f99aa147668, size=16, th='mitogen.service.Pool.7f99aa147668.worker-15'): while invoking 'get' of 'ansible_mitogen.services.ContextService'
Traceback (most recent call last):
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 519, in _on_service_call
    return invoker.invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 253, in invoke
    response = self._invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 239, in _invoke
    ret = method(**kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 423, in get
    reraise(*result)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 73, in reraise
    raise value
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 381, in _wait_or_start
    response = self._connect(key, spec, via=via)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 330, in _connect
    context = method(via=via, unidirectional=True, **spec['kwargs'])
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1672, in ssh
    return self.connect(u'ssh', **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1621, in connect
    return self._connect(klass, name=name, **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1604, in _connect
    stream.connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 239, in connect
    super(Stream, self).connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1119, in connect
    self.pid, fd, extra_fd = self.start_child()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1109, in start_child
    args = self.get_boot_command()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 199, in get_boot_command
    bits += ['-' + ('v' * min(3, self.ssh_debug_level))]
TypeError: '<' not supported between instances of 'str' and 'int'
[pid 6057] 15:28:47.213061 D mitogen: mitogen.core.Stream('unix_listener.2038').on_disconnect()
[pid 6060] 15:28:47.213063 D mitogen: unix.connect(path='/tmp/mitogen_unix_pcop8sfe')
[pid 6057] 15:28:47.213886 D mitogen: Waker(Broker(0x7f99a8afc080) rfd=31, wfd=32).on_disconnect()
[pid 2038] 15:28:47.214105 D mitogen: mitogen.core.Stream('unix_client.6057').on_disconnect()
[pid 6060] 15:28:47.215250 D mitogen: unix.connect(): local ID is 2128, remote is 0
[pid 2038] 15:28:47.218757 D mitogen: mitogen.ssh.Stream('default').connect()
ERROR! [pid 2038] 15:28:47.219049 E mitogen: mitogen.service.Pool(0x7f99aa147668, size=16, th='mitogen.service.Pool.7f99aa147668.worker-12'): while invoking 'get' of 'ansible_mitogen.services.ContextService'
Traceback (most recent call last):
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 519, in _on_service_call
    return invoker.invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 253, in invoke
    response = self._invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 239, in _invoke
    ret = method(**kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 423, in get
    reraise(*result)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 73, in reraise
    raise value
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 381, in _wait_or_start
    response = self._connect(key, spec, via=via)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 330, in _connect
    context = method(via=via, unidirectional=True, **spec['kwargs'])
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1672, in ssh
    return self.connect(u'ssh', **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1621, in connect
    return self._connect(klass, name=name, **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1604, in _connect
    stream.connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 239, in connect
    super(Stream, self).connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1119, in connect
    self.pid, fd, extra_fd = self.start_child()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1109, in start_child
    args = self.get_boot_command()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 199, in get_boot_command
    bits += ['-' + ('v' * min(3, self.ssh_debug_level))]
TypeError: '<' not supported between instances of 'str' and 'int'
The full traceback is:
Traceback (most recent call last):
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/executor/task_executor.py", line 139, in run
    res = self._execute()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/executor/task_executor.py", line 603, in _execute
    result = self._handler.run(task_vars=variables)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 116, in run
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/plugins/action/normal.py", line 46, in run
    result = merge_hash(result, self._execute_module(task_vars=task_vars, wrap_async=wrap_async))
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 332, in _execute_module
    self._temp_file_gibberish(module_args, wrap_async)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 311, in _temp_file_gibberish
    self._connection.get_good_temp_dir()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 694, in get_good_temp_dir
    self._connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 728, in _connect
    self._connect_stack(stack)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 676, in _connect_stack
    stack=mitogen.utils.cast(list(stack)),
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/core.py", line 1261, in call_service
    return recv.get().unpickle()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/core.py", line 607, in unpickle
    raise obj
mitogen.core.CallError: builtins.TypeError: '<' not supported between instances of 'str' and 'int'
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 519, in _on_service_call
    return invoker.invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 253, in invoke
    response = self._invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 239, in _invoke
    ret = method(**kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 423, in get
    reraise(*result)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 73, in reraise
    raise value
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 381, in _wait_or_start
    response = self._connect(key, spec, via=via)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 330, in _connect
    context = method(via=via, unidirectional=True, **spec['kwargs'])
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1672, in ssh
    return self.connect(u'ssh', **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1621, in connect
    return self._connect(klass, name=name, **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1604, in _connect
    stream.connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 239, in connect
    super(Stream, self).connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1119, in connect
    self.pid, fd, extra_fd = self.start_child()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1109, in start_child
    args = self.get_boot_command()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 199, in get_boot_command
    bits += ['-' + ('v' * min(3, self.ssh_debug_level))]

fatal: [timur-consul-60092.acronis]: FAILED! => {
    "msg": "Unexpected failure during module execution.",
    "stdout": ""
}
[pid 6060] 15:28:47.237018 D mitogen: mitogen.core.Stream('unix_listener.2038').on_disconnect()
[pid 6060] 15:28:47.237676 D mitogen: Waker(Broker(0x7f99a985f8d0) rfd=33, wfd=34).on_disconnect()
[pid 2038] 15:28:47.237748 D mitogen: mitogen.core.Stream('unix_client.6060').on_disconnect()
[pid 6065] 15:28:47.249733 D mitogen: unix.connect(path='/tmp/mitogen_unix_pcop8sfe')
[pid 6065] 15:28:47.250709 D mitogen: unix.connect(): local ID is 2130, remote is 0
[pid 2038] 15:28:47.252800 D mitogen: mitogen.ssh.Stream('default').connect()
ERROR! [pid 2038] 15:28:47.253092 E mitogen: mitogen.service.Pool(0x7f99aa147668, size=16, th='mitogen.service.Pool.7f99aa147668.worker-14'): while invoking 'get' of 'ansible_mitogen.services.ContextService'
Traceback (most recent call last):
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 519, in _on_service_call
    return invoker.invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 253, in invoke
    response = self._invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 239, in _invoke
    ret = method(**kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 423, in get
    reraise(*result)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 73, in reraise
    raise value
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 381, in _wait_or_start
    response = self._connect(key, spec, via=via)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 330, in _connect
    context = method(via=via, unidirectional=True, **spec['kwargs'])
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1672, in ssh
    return self.connect(u'ssh', **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1621, in connect
    return self._connect(klass, name=name, **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1604, in _connect
    stream.connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 239, in connect
    super(Stream, self).connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1119, in connect
    self.pid, fd, extra_fd = self.start_child()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1109, in start_child
    args = self.get_boot_command()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 199, in get_boot_command
    bits += ['-' + ('v' * min(3, self.ssh_debug_level))]
TypeError: '<' not supported between instances of 'str' and 'int'
[pid 6065] 15:28:47.267327 D mitogen: mitogen.core.Stream('unix_listener.2038').on_disconnect()
[pid 2038] 15:28:47.268281 D mitogen: mitogen.core.Stream('unix_client.6065').on_disconnect()
[pid 6065] 15:28:47.268206 D mitogen: Waker(Broker(0x7f99a8b15e10) rfd=35, wfd=36).on_disconnect()
The full traceback is:
Traceback (most recent call last):
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/executor/task_executor.py", line 139, in run
    res = self._execute()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/executor/task_executor.py", line 603, in _execute
    result = self._handler.run(task_vars=variables)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 116, in run
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/plugins/action/normal.py", line 46, in run
    result = merge_hash(result, self._execute_module(task_vars=task_vars, wrap_async=wrap_async))
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 332, in _execute_module
    self._temp_file_gibberish(module_args, wrap_async)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 311, in _temp_file_gibberish
    self._connection.get_good_temp_dir()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 694, in get_good_temp_dir
    self._connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 728, in _connect
    self._connect_stack(stack)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 676, in _connect_stack
    stack=mitogen.utils.cast(list(stack)),
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/core.py", line 1261, in call_service
    return recv.get().unpickle()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/core.py", line 607, in unpickle
    raise obj
mitogen.core.CallError: builtins.TypeError: '<' not supported between instances of 'str' and 'int'
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 519, in _on_service_call
    return invoker.invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 253, in invoke
    response = self._invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 239, in _invoke
    ret = method(**kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 423, in get
    reraise(*result)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 73, in reraise
    raise value
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 381, in _wait_or_start
    response = self._connect(key, spec, via=via)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 330, in _connect
    context = method(via=via, unidirectional=True, **spec['kwargs'])
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1672, in ssh
    return self.connect(u'ssh', **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1621, in connect
    return self._connect(klass, name=name, **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1604, in _connect
    stream.connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 239, in connect
    super(Stream, self).connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1119, in connect
    self.pid, fd, extra_fd = self.start_child()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1109, in start_child
    args = self.get_boot_command()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 199, in get_boot_command
    bits += ['-' + ('v' * min(3, self.ssh_debug_level))]

fatal: [timur-consul-60093.acronis]: FAILED! => {
    "msg": "Unexpected failure during module execution.",
    "stdout": ""
}
The full traceback is:
Traceback (most recent call last):
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/executor/task_executor.py", line 139, in run
    res = self._execute()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/executor/task_executor.py", line 603, in _execute
    result = self._handler.run(task_vars=variables)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 116, in run
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible/plugins/action/normal.py", line 46, in run
    result = merge_hash(result, self._execute_module(task_vars=task_vars, wrap_async=wrap_async))
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 332, in _execute_module
    self._temp_file_gibberish(module_args, wrap_async)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/mixins.py", line 311, in _temp_file_gibberish
    self._connection.get_good_temp_dir()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 694, in get_good_temp_dir
    self._connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 728, in _connect
    self._connect_stack(stack)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/connection.py", line 676, in _connect_stack
    stack=mitogen.utils.cast(list(stack)),
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/core.py", line 1261, in call_service
    return recv.get().unpickle()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/core.py", line 607, in unpickle
    raise obj
mitogen.core.CallError: builtins.TypeError: '<' not supported between instances of 'str' and 'int'
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 519, in _on_service_call
    return invoker.invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 253, in invoke
    response = self._invoke(method_name, kwargs, msg)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/service.py", line 239, in _invoke
    ret = method(**kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 423, in get
    reraise(*result)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 73, in reraise
    raise value
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 381, in _wait_or_start
    response = self._connect(key, spec, via=via)
  File "/root/msp-scm/env/lib/python3.6/site-packages/ansible_mitogen/services.py", line 330, in _connect
    context = method(via=via, unidirectional=True, **spec['kwargs'])
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1672, in ssh
    return self.connect(u'ssh', **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1621, in connect
    return self._connect(klass, name=name, **kwargs)
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1604, in _connect
    stream.connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 239, in connect
    super(Stream, self).connect()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1119, in connect
    self.pid, fd, extra_fd = self.start_child()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/parent.py", line 1109, in start_child
    args = self.get_boot_command()
  File "/root/msp-scm/env/lib/python3.6/site-packages/mitogen/ssh.py", line 199, in get_boot_command
    bits += ['-' + ('v' * min(3, self.ssh_debug_level))]

fatal: [timur-consul-60094.acronis]: FAILED! => {
    "msg": "Unexpected failure during module execution.",
    "stdout": ""
}

I guess, something wrong with command line option 'mitogen_ssh_debug_level'.

dw added a commit that referenced this issue Dec 8, 2018
It can be a string when specified on the command line.
@dw
Copy link
Member

@dw dw commented Dec 8, 2018

Whoops, it is because there was no code to convert from string to integer. You can use -e '{"mitogen_ssh_debug_level": 3}', or simply pull the current master and it is fixed.

@trim777
Copy link
Author

@trim777 trim777 commented Dec 8, 2018

Thank you!
I see issue with authentication:

Saturday 08 December 2018  17:14:06 +0000 (0:00:00.132)       0:00:12.904 *****
[pid 6972] 17:14:06.833673 D mitogen: unix.connect(path='/tmp/mitogen_unix_km7hlw22')
[pid 6972] 17:14:06.834658 D mitogen: unix.connect(): local ID is 4026, remote is 0
[pid 6715] 17:14:06.836972 D mitogen: mitogen.ssh.Stream('default').connect()
[pid 6715] 17:14:06.864303 D mitogen: create_child() child 6975 fd 66, parent 6715, cmd: ssh -vvv -o "Compression yes" -o "ServerAliveInterval 15" -o "ServerAliveCountMax 3" -o "BatchMode yes" -o "StrictHostKeyChecking no" -o "UserKnownHostsFile /dev/null" -o "GlobalKnownHostsFile /dev/null" -o ControlMaster=auto -o ControlPersist=3600s 10.250.192.184 python -c "'import codecs,os,sys;_=codecs.decode;exec(_(_(\"eNqFkVFLwzAUhZ/XX9G3m2DY0okKhYCyB/FBhCLuQYekTeqCXRLSbHX+eu86Ye188O1+3JN7DicFWwrXTr3xmtAksG5Apk4Rahc+Cc2TCc5q6+eEs4xzeuKCDSngNjty1bhWk2IIYQjLIXQIaNju0b6REV03qRApKBk6YyGVVvVL/aWrbZRlo/v1bNuGWWnszO/j2lnAnJMz2YXoH+50aI2zr/nlqrfVdmcCMtwV9y8cVmL87KhBbMh4wcZ4AWRjovvQNg/OxdvGVbJZuzZO+0m5jTQ2v77JrijQBG92wURNMgaPD89PnPM3C5ilcgorp8lCvJND6cp5bbFqCCXQadBSkWx+OMLg23i8VHtx0i0ZdCUc/qH2vwaLfj52e6bu/lP/TZmNUv4AyKqyog==\".encode(),\"base64\"),\"zip\"))'"
[pid 6715] 17:14:06.865478 D mitogen: mitogen.ssh.Stream('local.6975').connect(): child process stdin/stdout=66
[pid 6715] 17:14:06.869151 D mitogen: mitogen.ssh.Stream('local.6975'): received b'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\n'
[pid 6715] 17:14:06.869449 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Reading configuration data /root/.ssh/config'
[pid 6715] 17:14:06.869661 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Reading configuration data /etc/ssh/ssh_config'
[pid 6715] 17:14:06.869901 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: /etc/ssh/ssh_config line 58: Applying options for *'
[pid 6715] 17:14:06.870084 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: resolving "10.250.192.184" port 22'
[pid 6715] 17:14:06.870301 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: ssh_connect_direct: needpriv 0'
[pid 6715] 17:14:06.870476 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Connecting to 10.250.192.184 [10.250.192.184] port 22.'
[pid 6715] 17:14:06.870665 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Connection established.'
[pid 6715] 17:14:06.870844 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: permanently_set_uid: 0/0'
[pid 6715] 17:14:06.871026 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_rsa type 1'
[pid 6715] 17:14:06.871187 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.871377 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_rsa-cert type -1'
[pid 6715] 17:14:06.871558 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.871727 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_dsa type -1'
[pid 6715] 17:14:06.871892 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.872057 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_dsa-cert type -1'
[pid 6715] 17:14:06.872233 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.872397 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_ecdsa type -1'
[pid 6715] 17:14:06.872574 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.872736 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_ecdsa-cert type -1'
[pid 6715] 17:14:06.872895 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.873053 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_ed25519 type -1'
[pid 6715] 17:14:06.873224 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.873386 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: identity file /root/.ssh/id_ed25519-cert type -1'
[pid 6715] 17:14:06.873560 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Enabling compatibility mode for protocol 2.0'
[pid 6715] 17:14:06.873711 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Local version string SSH-2.0-OpenSSH_7.4'
[pid 6715] 17:14:06.877286 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Remote protocol version 2.0, remote software version OpenSSH_7.4'
[pid 6715] 17:14:06.877519 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: match: OpenSSH_7.4 pat OpenSSH* compat 0x04000000'
[pid 6715] 17:14:06.877694 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: fd 3 setting O_NONBLOCK'
[pid 6715] 17:14:06.877886 D mitogen: mitogen.ssh.Stream('local.6975'): b"debug1: Authenticating to 10.250.192.184:22 as 'root'"
[pid 6715] 17:14:06.878088 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:06.878332 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:06.878534 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: send packet: type 20'
[pid 6715] 17:14:06.878708 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: SSH2_MSG_KEXINIT sent'
[pid 6715] 17:14:06.878872 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 20'
[pid 6715] 17:14:06.879045 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: SSH2_MSG_KEXINIT received'
[pid 6715] 17:14:06.879253 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: local client KEXINIT proposal'
[pid 6715] 17:14:06.879444 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1,ext-info-c'
[pid 6715] 17:14:06.879633 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: host key algorithms: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa,ssh-dss'
[pid 6715] 17:14:06.879806 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc'
[pid 6715] 17:14:06.879983 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc'
[pid 6715] 17:14:06.880183 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.880394 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.880583 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: compression ctos: zlib@openssh.com,zlib,none'
[pid 6715] 17:14:06.880752 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: compression stoc: zlib@openssh.com,zlib,none'
[pid 6715] 17:14:06.880915 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: languages ctos:'
[pid 6715] 17:14:06.881077 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: languages stoc:'
[pid 6715] 17:14:06.881258 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: first_kex_follows 0'
[pid 6715] 17:14:06.881423 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: reserved 0'
[pid 6715] 17:14:06.881602 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: peer server KEXINIT proposal'
[pid 6715] 17:14:06.881765 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1'
[pid 6715] 17:14:06.881936 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519'
[pid 6715] 17:14:06.882100 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc'
[pid 6715] 17:14:06.882276 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc'
[pid 6715] 17:14:06.882441 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.882620 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.882783 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: compression ctos: none,zlib@openssh.com'
[pid 6715] 17:14:06.882956 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: compression stoc: none,zlib@openssh.com'
[pid 6715] 17:14:06.883108 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: languages ctos:'
[pid 6715] 17:14:06.883276 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: languages stoc:'
[pid 6715] 17:14:06.883451 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: first_kex_follows 0'
[pid 6715] 17:14:06.883626 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: reserved 0'
[pid 6715] 17:14:06.883813 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: kex: algorithm: curve25519-sha256'
[pid 6715] 17:14:06.883971 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: kex: host key algorithm: ecdsa-sha2-nistp256'
[pid 6715] 17:14:06.884129 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com'
[pid 6715] 17:14:06.884319 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com'
[pid 6715] 17:14:06.884503 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: kex: curve25519-sha256 need=64 dh_need=64'
[pid 6715] 17:14:06.884664 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: kex: curve25519-sha256 need=64 dh_need=64'
[pid 6715] 17:14:06.884870 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: send packet: type 30'
[pid 6715] 17:14:06.885052 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: expecting SSH2_MSG_KEX_ECDH_REPLY'
[pid 6715] 17:14:06.886658 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 31'
[pid 6715] 17:14:06.886919 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Server host key: ecdsa-sha2-nistp256 SHA256:meT3YAIeZ8bVKB7iyXSs77Srt7Y7IOL5vYzMNtd3uu8'
[pid 6715] 17:14:06.887117 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:06.887311 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:06.887480 D mitogen: mitogen.ssh.Stream('local.6975'): received b"Warning: Permanently added '10.250.192.184' (ECDSA) to the list of known hosts.\r\n"
[pid 6715] 17:14:06.889733 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: send packet: type 21'
[pid 6715] 17:14:06.889949 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: set_newkeys: mode 1'
[pid 6715] 17:14:06.890152 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: rekey after 134217728 blocks'
[pid 6715] 17:14:06.890375 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: SSH2_MSG_NEWKEYS sent'
[pid 6715] 17:14:06.890572 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: expecting SSH2_MSG_NEWKEYS'
[pid 6715] 17:14:06.890782 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 21'
[pid 6715] 17:14:06.890951 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: SSH2_MSG_NEWKEYS received'
[pid 6715] 17:14:06.891109 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: set_newkeys: mode 0'
[pid 6715] 17:14:06.891298 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: rekey after 134217728 blocks'
[pid 6715] 17:14:06.891479 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: key: /root/.ssh/id_rsa (0x559534cf36f0), agent'
[pid 6715] 17:14:06.891674 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: key: /root/.ssh/id_dsa ((nil))'
[pid 6715] 17:14:06.891849 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: key: /root/.ssh/id_ecdsa ((nil))'
[pid 6715] 17:14:06.892022 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: key: /root/.ssh/id_ed25519 ((nil))'
[pid 6715] 17:14:06.892196 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: send packet: type 5'
[pid 6715] 17:14:06.892387 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 7'
[pid 6715] 17:14:06.892572 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: SSH2_MSG_EXT_INFO received'
[pid 6715] 17:14:06.892731 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: kex_input_ext_info: server-sig-algs=<rsa-sha2-256,rsa-sha2-512>'
[pid 6715] 17:14:06.929959 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 6'
[pid 6715] 17:14:06.930309 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: service_accept: ssh-userauth'
[pid 6715] 17:14:06.930539 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: SSH2_MSG_SERVICE_ACCEPT received'
[pid 6715] 17:14:06.930718 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: send packet: type 50'
[pid 6715] 17:14:06.932575 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 53'
[pid 6715] 17:14:06.932788 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: input_userauth_banner'
[pid 6715] 17:14:06.933002 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      This system is for the use of authorised users only. Individuals using this\n'
[pid 6715] 17:14:06.933188 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      computer system without authority, or in excess of their authority, are\n'
[pid 6715] 17:14:06.933418 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      subject to having all of their activities on this system monitored and\n'
[pid 6715] 17:14:06.933637 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      recorded by system personnel. In the course of monitoring individuals\n'
[pid 6715] 17:14:06.933829 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      improperly using this system, or in the course of system maintenance, the\n'
[pid 6715] 17:14:06.934010 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      activities of authorised users may also be monitored. Anyone using this\n'
[pid 6715] 17:14:06.934187 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      system expressly consents to such monitoring and is advised that if such\n'
[pid 6715] 17:14:06.934393 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      monitoring reveals possible criminal activity, system personnel may provide\n'
[pid 6715] 17:14:06.934597 D mitogen: mitogen.ssh.Stream('local.6975'): received b'      the evidence of such monitoring to law enforcement officials.\n'
[pid 6715] 17:14:06.934777 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 51'
[pid 6715] 17:14:06.934933 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Authentications that can continue: publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:14:06.935085 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: start over, passed a different list publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:14:06.935254 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: preferred gssapi-keyex,gssapi-with-mic,publickey'
[pid 6715] 17:14:06.935441 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: authmethod_lookup gssapi-keyex'
[pid 6715] 17:14:06.935647 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: remaining preferred: gssapi-with-mic,publickey'
[pid 6715] 17:14:06.935820 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: authmethod_is_enabled gssapi-keyex'
[pid 6715] 17:14:06.935981 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Next authentication method: gssapi-keyex'
[pid 6715] 17:14:06.936137 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: No valid Key exchange context'
[pid 6715] 17:14:06.936308 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:14:06.936471 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: authmethod_lookup gssapi-with-mic'
[pid 6715] 17:14:06.936656 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: remaining preferred: publickey'
[pid 6715] 17:14:06.936839 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: authmethod_is_enabled gssapi-with-mic'
[pid 6715] 17:14:06.937012 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Next authentication method: gssapi-with-mic'
[pid 6715] 17:14:06.937256 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Unspecified GSS failure.  Minor code may provide more information'
[pid 6715] 17:14:06.937448 D mitogen: mitogen.ssh.Stream('local.6975'): received b'No Kerberos credentials available (default cache: KEYRING:persistent:0)\n'
[pid 6715] 17:14:06.937666 D mitogen: mitogen.ssh.Stream('local.6975'): received b'\r\n'
[pid 6715] 17:14:06.937852 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Unspecified GSS failure.  Minor code may provide more information'
[pid 6715] 17:14:06.938022 D mitogen: mitogen.ssh.Stream('local.6975'): received b'No Kerberos credentials available (default cache: KEYRING:persistent:0)\n'
[pid 6715] 17:14:06.938197 D mitogen: mitogen.ssh.Stream('local.6975'): received b'\r\n'
[pid 6715] 17:14:06.938389 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:14:06.938567 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: authmethod_lookup publickey'
[pid 6715] 17:14:06.938735 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: remaining preferred:'
[pid 6715] 17:14:06.938900 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: authmethod_is_enabled publickey'
[pid 6715] 17:14:06.939062 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Next authentication method: publickey'
[pid 6715] 17:14:06.939238 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Offering RSA public key: /root/.ssh/id_rsa'
[pid 6715] 17:14:06.939407 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: send_pubkey_test'
[pid 6715] 17:14:06.939589 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: send packet: type 50'
[pid 6715] 17:14:06.939742 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: we sent a publickey packet, wait for reply'
[pid 6715] 17:14:06.939918 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: receive packet: type 51'
[pid 6715] 17:14:06.940063 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Authentications that can continue: publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:14:06.940226 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Trying private key: /root/.ssh/id_dsa'
[pid 6715] 17:14:06.940400 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: no such identity: /root/.ssh/id_dsa: No such file or directory'
[pid 6715] 17:14:06.940580 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Trying private key: /root/.ssh/id_ecdsa'
[pid 6715] 17:14:06.940761 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: no such identity: /root/.ssh/id_ecdsa: No such file or directory'
[pid 6715] 17:14:06.940924 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: Trying private key: /root/.ssh/id_ed25519'
[pid 6715] 17:14:06.941079 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug3: no such identity: /root/.ssh/id_ed25519: No such file or directory'
[pid 6715] 17:14:06.941252 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:14:06.941419 D mitogen: mitogen.ssh.Stream('local.6975'): b'debug1: No more authentication methods to try.'
[pid 6715] 17:14:06.941603 D mitogen: mitogen.ssh.Stream('local.6975'): received b'Permission denied (publickey,gssapi-keyex,gssapi-with-mic).\r\n'
[pid 6715] 17:14:06.942009 D mitogen: mitogen.ssh.Stream('local.6975'): PID 6975 exited with return code 255
failed: [timur-crs-60017.host] (item={'key': 'msp_git_versions', 'value': 'e7be96de720a535dcd8e70003cadffb7b60a80ae'}) => {
    "_consul_key": {
        "key": "msp_git_versions",
        "value": "e7be96de720a535dcd8e70003cadffb7b60a80ae"
    },
    "msg": "SSH authentication is incorrect",
    "unreachable": true
}
[pid 6972] 17:14:06.961690 D mitogen: mitogen.core.Stream('unix_listener.6715').on_disconnect()
[pid 6972] 17:14:06.962245 D mitogen: Waker(Broker(0x7f7f445247b8) rfd=11, wfd=12).on_disconnect()
[pid 6715] 17:14:06.962328 D mitogen: mitogen.core.Stream('unix_client.6972').on_disconnect()
[pid 6972] 17:14:06.963327 D mitogen: unix.connect(path='/tmp/mitogen_unix_km7hlw22')
[pid 6972] 17:14:06.963784 D mitogen: unix.connect(): local ID is 4028, remote is 0
[pid 6715] 17:14:06.965365 D mitogen: mitogen.ssh.Stream('default').connect()
[pid 6715] 17:14:06.980954 D mitogen: create_child() child 6979 fd 68, parent 6715, cmd: ssh -vvv -o "Compression yes" -o "ServerAliveInterval 15" -o "ServerAliveCountMax 3" -o "BatchMode yes" -o "StrictHostKeyChecking no" -o "UserKnownHostsFile /dev/null" -o "GlobalKnownHostsFile /dev/null" -o ControlMaster=auto -o ControlPersist=3600s 10.250.192.184 python -c "'import codecs,os,sys;_=codecs.decode;exec(_(_(\"eNqFkc1qwzAQhM/xU/gmiYhESn8CBkFLDqWHUjClOaShyJbciDqSkJW46dN34xRip4fe9mNnNcMop0vhmok3XmOSBNr2yFQpQOXCJyZZMoJZ7fwMM8oZI2fOaZ8CbPmJy9o1Gud9CH1Y9qEFAMPmAPa1jOC6TYVIkZKhNRal0qpuqb90uYuyqHW3nu6aMC2MnfpD3DiLIOfoQjYW3eFeh8Y4u8qu1p2ttnsTgNF9/vDK0FoMz04awBoPF3SIY4S3JroPbbPgXLyrXSnrjWvipJuU20pjs9s5vyGIJPBmG0zUmFP09PjyzBh7swiylE5B5SRZiHd8LF05ry1UjUKByCRoqTCfzfk1oejbeHip8uKsW1LUFuj4D5X/NVh086nbC3X7n/pvSj5I+QPIJLKh\".encode(),\"base64\"),\"zip\"))'"
[pid 6715] 17:14:06.981790 D mitogen: mitogen.ssh.Stream('local.6979').connect(): child process stdin/stdout=68
[pid 6715] 17:14:06.985989 D mitogen: mitogen.ssh.Stream('local.6979'): received b'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\n'
[pid 6715] 17:14:06.986232 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Reading configuration data /root/.ssh/config'
[pid 6715] 17:14:06.986411 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Reading configuration data /etc/ssh/ssh_config'
[pid 6715] 17:14:06.986537 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: /etc/ssh/ssh_config line 58: Applying options for *'
[pid 6715] 17:14:06.986638 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: resolving "10.250.192.184" port 22'
[pid 6715] 17:14:06.986739 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: ssh_connect_direct: needpriv 0'
[pid 6715] 17:14:06.986829 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Connecting to 10.250.192.184 [10.250.192.184] port 22.'
[pid 6715] 17:14:06.986916 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Connection established.'
[pid 6715] 17:14:06.987029 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: permanently_set_uid: 0/0'
[pid 6715] 17:14:06.987122 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_rsa type 1'
[pid 6715] 17:14:06.987219 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.987314 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_rsa-cert type -1'
[pid 6715] 17:14:06.987401 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.987497 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_dsa type -1'
[pid 6715] 17:14:06.987587 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.987672 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_dsa-cert type -1'
[pid 6715] 17:14:06.987774 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.987856 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_ecdsa type -1'
[pid 6715] 17:14:06.987940 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.988023 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_ecdsa-cert type -1'
[pid 6715] 17:14:06.988106 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.988189 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_ed25519 type -1'
[pid 6715] 17:14:06.988335 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:06.988429 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: identity file /root/.ssh/id_ed25519-cert type -1'
[pid 6715] 17:14:06.988528 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Enabling compatibility mode for protocol 2.0'
[pid 6715] 17:14:06.988615 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Local version string SSH-2.0-OpenSSH_7.4'
[pid 6715] 17:14:06.994319 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Remote protocol version 2.0, remote software version OpenSSH_7.4'
[pid 6715] 17:14:06.994440 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: match: OpenSSH_7.4 pat OpenSSH* compat 0x04000000'
[pid 6715] 17:14:06.994580 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: fd 3 setting O_NONBLOCK'
[pid 6715] 17:14:06.994675 D mitogen: mitogen.ssh.Stream('local.6979'): b"debug1: Authenticating to 10.250.192.184:22 as 'root'"
[pid 6715] 17:14:06.994792 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:06.994895 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:06.994997 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: send packet: type 20'
[pid 6715] 17:14:06.995093 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: SSH2_MSG_KEXINIT sent'
[pid 6715] 17:14:06.995318 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 20'
[pid 6715] 17:14:06.995434 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: SSH2_MSG_KEXINIT received'
[pid 6715] 17:14:06.995556 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: local client KEXINIT proposal'
[pid 6715] 17:14:06.995650 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1,ext-info-c'
[pid 6715] 17:14:06.995749 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: host key algorithms: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa,ssh-dss'
[pid 6715] 17:14:06.995880 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc'
[pid 6715] 17:14:06.995973 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc'
[pid 6715] 17:14:06.996056 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.996140 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.996243 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: compression ctos: zlib@openssh.com,zlib,none'
[pid 6715] 17:14:06.996339 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: compression stoc: zlib@openssh.com,zlib,none'
[pid 6715] 17:14:06.996439 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: languages ctos:'
[pid 6715] 17:14:06.996553 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: languages stoc:'
[pid 6715] 17:14:06.996657 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: first_kex_follows 0'
[pid 6715] 17:14:06.996749 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: reserved 0'
[pid 6715] 17:14:06.996847 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: peer server KEXINIT proposal'
[pid 6715] 17:14:06.996943 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1'
[pid 6715] 17:14:06.997046 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519'
[pid 6715] 17:14:06.997148 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc'
[pid 6715] 17:14:06.997249 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc'
[pid 6715] 17:14:06.997340 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.997429 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:14:06.997529 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: compression ctos: none,zlib@openssh.com'
[pid 6715] 17:14:06.997618 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: compression stoc: none,zlib@openssh.com'
[pid 6715] 17:14:06.997704 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: languages ctos:'
[pid 6715] 17:14:06.997789 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: languages stoc:'
[pid 6715] 17:14:06.997874 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: first_kex_follows 0'
[pid 6715] 17:14:06.997960 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: reserved 0'
[pid 6715] 17:14:06.998045 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: kex: algorithm: curve25519-sha256'
[pid 6715] 17:14:06.998132 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: kex: host key algorithm: ecdsa-sha2-nistp256'
[pid 6715] 17:14:06.998228 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com'
[pid 6715] 17:14:06.998320 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com'
[pid 6715] 17:14:06.998406 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: kex: curve25519-sha256 need=64 dh_need=64'
[pid 6715] 17:14:06.998513 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: kex: curve25519-sha256 need=64 dh_need=64'
[pid 6715] 17:14:06.998644 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: send packet: type 30'
[pid 6715] 17:14:06.998734 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: expecting SSH2_MSG_KEX_ECDH_REPLY'
[pid 6715] 17:14:07.003972 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 31'
[pid 6715] 17:14:07.004169 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Server host key: ecdsa-sha2-nistp256 SHA256:meT3YAIeZ8bVKB7iyXSs77Srt7Y7IOL5vYzMNtd3uu8'
[pid 6715] 17:14:07.004302 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:07.004405 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:14:07.004553 D mitogen: mitogen.ssh.Stream('local.6979'): received b"Warning: Permanently added '10.250.192.184' (ECDSA) to the list of known hosts.\r\n"
[pid 6715] 17:14:07.007005 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: send packet: type 21'
[pid 6715] 17:14:07.007122 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: set_newkeys: mode 1'
[pid 6715] 17:14:07.007231 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: rekey after 134217728 blocks'
[pid 6715] 17:14:07.007328 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: SSH2_MSG_NEWKEYS sent'
[pid 6715] 17:14:07.007419 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: expecting SSH2_MSG_NEWKEYS'
[pid 6715] 17:14:07.007547 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 21'
[pid 6715] 17:14:07.007644 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: SSH2_MSG_NEWKEYS received'
[pid 6715] 17:14:07.007732 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: set_newkeys: mode 0'
[pid 6715] 17:14:07.007819 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: rekey after 134217728 blocks'
[pid 6715] 17:14:07.007915 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: key: /root/.ssh/id_rsa (0x5639e2e566f0), agent'
[pid 6715] 17:14:07.008002 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: key: /root/.ssh/id_dsa ((nil))'
[pid 6715] 17:14:07.008088 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: key: /root/.ssh/id_ecdsa ((nil))'
[pid 6715] 17:14:07.008174 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: key: /root/.ssh/id_ed25519 ((nil))'
[pid 6715] 17:14:07.008274 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: send packet: type 5'
[pid 6715] 17:14:07.008363 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 7'
[pid 6715] 17:14:07.008450 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: SSH2_MSG_EXT_INFO received'
[pid 6715] 17:14:07.008551 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: kex_input_ext_info: server-sig-algs=<rsa-sha2-256,rsa-sha2-512>'
[pid 6715] 17:14:07.046914 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 6'
[pid 6715] 17:14:07.047193 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: service_accept: ssh-userauth'
[pid 6715] 17:14:07.047333 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: SSH2_MSG_SERVICE_ACCEPT received'
[pid 6715] 17:14:07.047440 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: send packet: type 50'
[pid 6715] 17:14:07.049031 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 53'
[pid 6715] 17:14:07.049143 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: input_userauth_banner'
[pid 6715] 17:14:07.049280 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      This system is for the use of authorised users only. Individuals using this\n'
[pid 6715] 17:14:07.049395 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      computer system without authority, or in excess of their authority, are\n'
[pid 6715] 17:14:07.049525 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      subject to having all of their activities on this system monitored and\n'
[pid 6715] 17:14:07.049632 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      recorded by system personnel. In the course of monitoring individuals\n'
[pid 6715] 17:14:07.049727 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      improperly using this system, or in the course of system maintenance, the\n'
[pid 6715] 17:14:07.049829 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      activities of authorised users may also be monitored. Anyone using this\n'
[pid 6715] 17:14:07.049929 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      system expressly consents to such monitoring and is advised that if such\n'
[pid 6715] 17:14:07.050022 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      monitoring reveals possible criminal activity, system personnel may provide\n'
[pid 6715] 17:14:07.050113 D mitogen: mitogen.ssh.Stream('local.6979'): received b'      the evidence of such monitoring to law enforcement officials.\n'
[pid 6715] 17:14:07.050230 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 51'
[pid 6715] 17:14:07.050325 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Authentications that can continue: publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:14:07.050414 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: start over, passed a different list publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:14:07.050513 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: preferred gssapi-keyex,gssapi-with-mic,publickey'
[pid 6715] 17:14:07.050609 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: authmethod_lookup gssapi-keyex'
[pid 6715] 17:14:07.050698 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: remaining preferred: gssapi-with-mic,publickey'
[pid 6715] 17:14:07.050785 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: authmethod_is_enabled gssapi-keyex'
[pid 6715] 17:14:07.050871 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Next authentication method: gssapi-keyex'
[pid 6715] 17:14:07.050956 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: No valid Key exchange context'
[pid 6715] 17:14:07.051043 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:14:07.051177 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: authmethod_lookup gssapi-with-mic'
[pid 6715] 17:14:07.051308 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: remaining preferred: publickey'
[pid 6715] 17:14:07.051403 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: authmethod_is_enabled gssapi-with-mic'
[pid 6715] 17:14:07.051509 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Next authentication method: gssapi-with-mic'
[pid 6715] 17:14:07.051635 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Unspecified GSS failure.  Minor code may provide more information'
[pid 6715] 17:14:07.051728 D mitogen: mitogen.ssh.Stream('local.6979'): received b'No Kerberos credentials available (default cache: KEYRING:persistent:0)\n'
[pid 6715] 17:14:07.051834 D mitogen: mitogen.ssh.Stream('local.6979'): received b'\r\n'
[pid 6715] 17:14:07.051931 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Unspecified GSS failure.  Minor code may provide more information'
[pid 6715] 17:14:07.052019 D mitogen: mitogen.ssh.Stream('local.6979'): received b'No Kerberos credentials available (default cache: KEYRING:persistent:0)\n'
[pid 6715] 17:14:07.052119 D mitogen: mitogen.ssh.Stream('local.6979'): received b'\r\n'
[pid 6715] 17:14:07.052247 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:14:07.052348 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: authmethod_lookup publickey'
[pid 6715] 17:14:07.052446 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: remaining preferred:'
[pid 6715] 17:14:07.052555 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: authmethod_is_enabled publickey'
[pid 6715] 17:14:07.052654 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Next authentication method: publickey'
[pid 6715] 17:14:07.052757 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Offering RSA public key: /root/.ssh/id_rsa'
[pid 6715] 17:14:07.052844 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: send_pubkey_test'
[pid 6715] 17:14:07.052932 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: send packet: type 50'
[pid 6715] 17:14:07.053018 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: we sent a publickey packet, wait for reply'
[pid 6715] 17:14:07.053104 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: receive packet: type 51'
[pid 6715] 17:14:07.053191 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Authentications that can continue: publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:14:07.053293 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Trying private key: /root/.ssh/id_dsa'
[pid 6715] 17:14:07.053380 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: no such identity: /root/.ssh/id_dsa: No such file or directory'
[pid 6715] 17:14:07.053467 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Trying private key: /root/.ssh/id_ecdsa'
[pid 6715] 17:14:07.053568 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: no such identity: /root/.ssh/id_ecdsa: No such file or directory'
[pid 6715] 17:14:07.053655 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: Trying private key: /root/.ssh/id_ed25519'
[pid 6715] 17:14:07.053740 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug3: no such identity: /root/.ssh/id_ed25519: No such file or directory'
[pid 6715] 17:14:07.053825 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:14:07.053910 D mitogen: mitogen.ssh.Stream('local.6979'): b'debug1: No more authentication methods to try.'
[pid 6715] 17:14:07.053994 D mitogen: mitogen.ssh.Stream('local.6979'): received b'Permission denied (publickey,gssapi-keyex,gssapi-with-mic).\r\n'
[pid 6715] 17:14:07.054226 D mitogen: mitogen.ssh.Stream('local.6979'): PID 6979 exited with return code 255
failed: [timur-crs-60017.host] (item={'key': 'msp_versions', 'value': '1.2'}) => {
    "_consul_key": {
        "key": "msp_versions",
        "value": "1.2"
    },
    "msg": "SSH authentication is incorrect",
    "unreachable": true
}
[pid 6972] 17:14:07.072063 D mitogen: mitogen.core.Stream('unix_listener.6715').on_disconnect()
[pid 6972] 17:14:07.072385 D mitogen: Waker(Broker(0x7f7f446596d8) rfd=11, wfd=12).on_disconnect()
[pid 6715] 17:14:07.072499 D mitogen: mitogen.core.Stream('unix_client.6972').on_disconnect()
[pid 6972] 17:14:07.073164 D mitogen: unix.connect(path='/tmp/mitogen_unix_km7hlw22')
[pid 6972] 17:14:07.073631 D mitogen: unix.connect(): local ID is 4030, remote is 0
[pid 6715] 17:14:07.075311 D mitogen: mitogen.ssh.Stream('default').connect()
[pid 6715] 17:14:07.090804 D mitogen: create_child() child 6981 fd 69, parent 6715, cmd: ssh -vvv -o "Compression yes" -o "ServerAliveInterval 15" -o "ServerAliveCountMax 3" -o "BatchMode yes" -o "StrictHostKeyChecking no" -o "UserKnownHostsFile /dev/null" -o "GlobalKnownHostsFile /dev/null" -o ControlMaster=auto -o ControlPersist=3600s 10.250.192.184 python -c "'import codecs,os,sys;_=codecs.decode;exec(_(_(\"eNqFkVFLwzAUhZ/XX9G3JCxsyYYOCgFlD+KDCEXcgxuSNqkLdklIs9X5673rhLXzwbf7cc/NOZzkdCVcM/HGa0ySQNsemSoFqFz4xCRLRjCrvZ9hRjlj5MI57VOALT9zWbtG47wPoQ+rPrQAYNgcwb6WEVx3qRApUjK0xqJUWtUt9Zcu91EWte7W030TpoWxU3+MW2cR5BxdycaiOzzo0Bhn37L5prPV9mACMLrPH14Z2ojh2VkDWOPhgg5xjPDORPehbRaci3e1K2W9dU2cdJNyO2lsdrvgNwSRBN5sg4kac4qeHl+eGWNriyBL6RRUTpKleMen0pXz2kLVKBSITIKWCvPZgs8JRd/Gw0uVFxfdiqK2QKd/qPyvwbKbz91eqdv/1H9T8kHKH8eesqA=\".encode(),\"base64\"),\"zip\"))'"
[pid 6715] 17:14:07.091650 D mitogen: mitogen.ssh.Stream('local.6981').connect(): child process stdin/stdout=69
[pid 6715] 17:14:07.096108 D mitogen: mitogen.ssh.Stream('local.6981'): received b'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\n'
[pid 6715] 17:14:07.096304 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Reading configuration data /root/.ssh/config'
[pid 6715] 17:14:07.096507 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Reading configuration data /etc/ssh/ssh_config'
[pid 6715] 17:14:07.096619 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: /etc/ssh/ssh_config line 58: Applying options for *'
[pid 6715] 17:14:07.096716 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug2: resolving "10.250.192.184" port 22'
[pid 6715] 17:14:07.096809 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug2: ssh_connect_direct: needpriv 0'
[pid 6715] 17:14:07.096898 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Connecting to 10.250.192.184 [10.250.192.184] port 22.'
[pid 6715] 17:14:07.097012 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Connection established.'
[pid 6715] 17:14:07.097107 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: permanently_set_uid: 0/0'
[pid 6715] 17:14:07.097196 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_rsa type 1'
[pid 6715] 17:14:07.097302 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:07.097390 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_rsa-cert type -1'
[pid 6715] 17:14:07.097476 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:07.097578 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_dsa type -1'
[pid 6715] 17:14:07.097664 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:07.097750 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_dsa-cert type -1'
[pid 6715] 17:14:07.097836 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:07.097922 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_ecdsa type -1'
[pid 6715] 17:14:07.098008 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:07.098093 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_ecdsa-cert type -1'
[pid 6715] 17:14:07.098198 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:07.098343 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_ed25519 type -1'
[pid 6715] 17:14:07.098465 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:14:07.098616 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: identity file /root/.ssh/id_ed25519-cert type -1'
[pid 6715] 17:14:07.098741 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Enabling compatibility mode for protocol 2.0'
[pid 6715] 17:14:07.098861 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Local version string SSH-2.0-OpenSSH_7.4'
[pid 6715] 17:14:07.104046 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Remote protocol version 2.0, remote software version OpenSSH_7.4'
...
...
and so on
...
[pid 6715] 17:14:07.163528 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Trying private key: /root/.ssh/id_ecdsa'
[pid 6715] 17:14:07.163610 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug3: no such identity: /root/.ssh/id_ecdsa: No such file or directory'
[pid 6715] 17:14:07.163689 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: Trying private key: /root/.ssh/id_ed25519'
[pid 6715] 17:14:07.163778 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug3: no such identity: /root/.ssh/id_ed25519: No such file or directory'
[pid 6715] 17:14:07.163867 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:14:07.163955 D mitogen: mitogen.ssh.Stream('local.6981'): b'debug1: No more authentication methods to try.'
[pid 6715] 17:14:07.164044 D mitogen: mitogen.ssh.Stream('local.6981'): received b'Permission denied (publickey,gssapi-keyex,gssapi-with-mic).\r\n'
[pid 6715] 17:14:07.164294 D mitogen: mitogen.ssh.Stream('local.6981'): PID 6981 exited with return code 255
[pid 6972] 17:14:07.167944 D mitogen: mitogen.core.Stream('unix_listener.6715').on_disconnect()
[pid 6972] 17:14:07.168246 D mitogen: Waker(Broker(0x7f7f44572160) rfd=11, wfd=12).on_disconnect()
[pid 6715] 17:14:07.168288 D mitogen: mitogen.core.Stream('unix_client.6972').on_disconnect()
failed: [timur-crs-60017.host] (item={'key': 'msp_builds', 'value': '57'}) => {
    "_consul_key": {
        "key": "msp_builds",
        "value": "57"
    },
    "msg": "SSH authentication is incorrect",
    "unreachable": true
}
fatal: [timur-crs-60017.host]: UNREACHABLE! => {
    "changed": false,
    "msg": "All items completed",
    "results": [
        {
            "_ansible_ignore_errors": null,
            "_ansible_item_label": {
                "key": "msp_git_versions",
                "value": "e7be96de720a535dcd8e70003cadffb7b60a80ae"
            },
            "_ansible_item_result": true,
            "_consul_key": {
                "key": "msp_git_versions",
                "value": "e7be96de720a535dcd8e70003cadffb7b60a80ae"
            },
            "msg": "SSH authentication is incorrect",
            "unreachable": true
        },
        {
            "_ansible_ignore_errors": null,
            "_ansible_item_label": {
                "key": "msp_versions",
                "value": "1.2"
            },
            "_ansible_item_result": true,
            "_consul_key": {
                "key": "msp_versions",
                "value": "1.2"
            },
            "msg": "SSH authentication is incorrect",
            "unreachable": true
        },
        {
            "_ansible_ignore_errors": null,
            "_ansible_item_label": {
                "key": "msp_builds",
                "value": "57"
            },
            "_ansible_item_result": true,
            "_consul_key": {
                "key": "msp_builds",
                "value": "57"
            },
            "msg": "SSH authentication is incorrect",
            "unreachable": true
        }
    ]
}

I'm using ssh-key auth mechanism with ssh-agent on ansible-controller.
I used this command:

ansible-playbook  -e '{"mitogen_ssh_debug_level": 3}' -vvv -i inventories.local/my_filled_inventory -u mylogin -l crs playbook-msp.yml --timeout 120 -e '@builds/7.8.2/deploy-builds.yml' -e '{"dc_id": 60000, "dc_name": "dc_random", "deployment_mode": "local"}' 2>&1 | tee mitogen.verbose2.log
@dw
Copy link
Member

@dw dw commented Dec 8, 2018

I think the problem may be with delegate_to. Please confirm the tasks that are working do not use delegate_to, and the tasks that are broken all use delegate_to.

Finally, would it be possible to return -vvv + mitogen_ssh_debug_level=3 output for one of the earlier tasks that succeed? It probably appears much earlier in the run -- the only reason it appears for your task here is because it is not reusing an existing connection, because there is a configuration difference due to a bug.

Just search for "cmd: ssh" in the full log, you will find all the new SSH invocations :)

Sorry for the inconvenience, and thanks again for your help

@trim777
Copy link
Author

@trim777 trim777 commented Dec 8, 2018

Hello! You are absolutely right, because issue happens on first mention of delegate_to option.
All tasks before succeeded, this is the first "cmd: ssh" from the log:

TASK [Gathering Facts] *****************************************************************************************************************************************************************************************************************************************************************************************************************
task path: /root/msp-scm/playbook-msp.yml:50
Saturday 08 December 2018  17:13:56 +0000 (0:00:00.087)       0:00:02.757 *****
[pid 6831] 17:13:56.665314 D mitogen: unix.connect(path='/tmp/mitogen_unix_km7hlw22')
[pid 6831] 17:13:56.666354 D mitogen: unix.connect(): local ID is 2005, remote is 0
[pid 6715] 17:13:56.669596 D mitogen: mitogen.ssh.Stream('default').connect()
[pid 6715] 17:13:56.699574 D mitogen: create_child() child 6834 fd 62, parent 6715, cmd: ssh -vvv -l mylogin -o "Compression yes" -o "ServerAliveInterval 15" -o "ServerAliveCountMax 3" -o "BatchMode yes" -o "StrictHostKeyChecking no" -o "UserKnownHostsFile /dev/null" -o "GlobalKnownHostsFile /dev/null" -o ControlMaster=auto -o ControlPersist=3600s 10.250.192.104 /usr/bin/python -c "'import codecs,os,sys;_=codecs.decode;exec(_(_(\"eNqFkVFLwzAUhZ/XX9G3JCxsyYYOCgFlD+KDCEXcgxuSNqkLdklIs9X5673rhLXzwbf7cc/NOZzkdCVcM/HGa0ySQNsemSoFqFz4xCRLRjCrvZ9hRjlj5MI57VOALT9zWbtG47wPoQ+rPrQAYNgcwb6WEVx3qRApUjK0xqJUWtUt9Zcu91EWte7W030TpoWxU3+MW2cR5BxdycaiOzzo0Bhn37L5prPV9mACMLrPH14Z2ojh2VkDWOPhgg5xjPDORPehbRaci3e1K2W9dU2cdJNyO2lsdrvgNwSRBN5sg4kac4qeHl+eGWNriyBL6RRUTpKleMen0pXz2kLVKBSITIKWCvPZgs8JRd/Gw0uVFxfdiqK2QKd/qPyvwbKbz91eqdv/1H9T8kHKH8eesqA=\".encode(),\"base64\"),\"zip\"))'"
[pid 6715] 17:13:56.700752 D mitogen: mitogen.ssh.Stream('local.6834').connect(): child process stdin/stdout=62
[pid 6715] 17:13:56.704969 D mitogen: mitogen.ssh.Stream('local.6834'): received b'OpenSSH_7.4p1, OpenSSL 1.0.2k-fips  26 Jan 2017\r\n'
[pid 6715] 17:13:56.705320 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Reading configuration data /root/.ssh/config'
[pid 6715] 17:13:56.705523 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Reading configuration data /etc/ssh/ssh_config'
[pid 6715] 17:13:56.705740 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: /etc/ssh/ssh_config line 58: Applying options for *'
[pid 6715] 17:13:56.705899 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: resolving "10.250.192.104" port 22'
[pid 6715] 17:13:56.706047 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: ssh_connect_direct: needpriv 0'
[pid 6715] 17:13:56.706188 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Connecting to 10.250.192.104 [10.250.192.104] port 22.'
[pid 6715] 17:13:56.706353 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Connection established.'
[pid 6715] 17:13:56.706511 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: permanently_set_uid: 0/0'
[pid 6715] 17:13:56.706652 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_rsa type 1'
[pid 6715] 17:13:56.706785 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:13:56.706915 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_rsa-cert type -1'
[pid 6715] 17:13:56.707045 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:13:56.707197 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_dsa type -1'
[pid 6715] 17:13:56.707391 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:13:56.707550 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_dsa-cert type -1'
[pid 6715] 17:13:56.707695 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:13:56.707853 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_ecdsa type -1'
[pid 6715] 17:13:56.708006 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:13:56.708141 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_ecdsa-cert type -1'
[pid 6715] 17:13:56.708306 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:13:56.708455 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_ed25519 type -1'
[pid 6715] 17:13:56.708618 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: key_load_public: No such file or directory'
[pid 6715] 17:13:56.708762 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: identity file /root/.ssh/id_ed25519-cert type -1'
[pid 6715] 17:13:56.708905 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Enabling compatibility mode for protocol 2.0'
[pid 6715] 17:13:56.709094 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Local version string SSH-2.0-OpenSSH_7.4'
[pid 6715] 17:13:56.713560 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Remote protocol version 2.0, remote software version OpenSSH_7.4'
[pid 6715] 17:13:56.713910 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: match: OpenSSH_7.4 pat OpenSSH* compat 0x04000000'
[pid 6715] 17:13:56.714097 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: fd 3 setting O_NONBLOCK'
[pid 6715] 17:13:56.714292 D mitogen: mitogen.ssh.Stream('local.6834'): b"debug1: Authenticating to 10.250.192.104:22 as 'mylogin'"
[pid 6715] 17:13:56.714450 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:13:56.714677 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:13:56.714855 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 20'
[pid 6715] 17:13:56.715034 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: SSH2_MSG_KEXINIT sent'
[pid 6715] 17:13:56.715207 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 20'
[pid 6715] 17:13:56.715387 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: SSH2_MSG_KEXINIT received'
[pid 6715] 17:13:56.715545 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: local client KEXINIT proposal'
[pid 6715] 17:13:56.715696 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1,ext-info-c'
[pid 6715] 17:13:56.715845 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: host key algorithms: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa,ssh-dss'
[pid 6715] 17:13:56.715990 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc'
[pid 6715] 17:13:56.716133 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc'
[pid 6715] 17:13:56.716290 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:13:56.716435 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:13:56.716594 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: compression ctos: zlib@openssh.com,zlib,none'
[pid 6715] 17:13:56.716732 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: compression stoc: zlib@openssh.com,zlib,none'
[pid 6715] 17:13:56.716868 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: languages ctos:'
[pid 6715] 17:13:56.717004 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: languages stoc:'
[pid 6715] 17:13:56.717141 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: first_kex_follows 0'
[pid 6715] 17:13:56.717344 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: reserved 0'
[pid 6715] 17:13:56.717510 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: peer server KEXINIT proposal'
[pid 6715] 17:13:56.717654 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1'
[pid 6715] 17:13:56.717799 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519'
[pid 6715] 17:13:56.717932 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc'
[pid 6715] 17:13:56.718064 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc'
[pid 6715] 17:13:56.718197 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:13:56.718365 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1'
[pid 6715] 17:13:56.718512 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: compression ctos: none,zlib@openssh.com'
[pid 6715] 17:13:56.718647 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: compression stoc: none,zlib@openssh.com'
[pid 6715] 17:13:56.718778 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: languages ctos:'
[pid 6715] 17:13:56.718908 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: languages stoc:'
[pid 6715] 17:13:56.719036 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: first_kex_follows 0'
[pid 6715] 17:13:56.719193 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: reserved 0'
[pid 6715] 17:13:56.719350 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: kex: algorithm: curve25519-sha256'
[pid 6715] 17:13:56.719477 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: kex: host key algorithm: ecdsa-sha2-nistp256'
[pid 6715] 17:13:56.719641 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com'
[pid 6715] 17:13:56.719784 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com'
[pid 6715] 17:13:56.719909 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: kex: curve25519-sha256 need=64 dh_need=64'
[pid 6715] 17:13:56.720032 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: kex: curve25519-sha256 need=64 dh_need=64'
[pid 6715] 17:13:56.720245 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 30'
[pid 6715] 17:13:56.720399 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: expecting SSH2_MSG_KEX_ECDH_REPLY'
[pid 6715] 17:13:56.724698 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 31'
[pid 6715] 17:13:56.725036 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Server host key: ecdsa-sha2-nistp256 SHA256:sCxKtTgeUfHhzzJCbHPGBAgcWWnLAQNgOIoaiUsY6ps'
[pid 6715] 17:13:56.725202 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:13:56.725377 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: hostkeys_foreach: reading file "/dev/null"'
[pid 6715] 17:13:56.725549 D mitogen: mitogen.ssh.Stream('local.6834'): received b"Warning: Permanently added '10.250.192.104' (ECDSA) to the list of known hosts.\r\n"
[pid 6715] 17:13:56.727968 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 21'
[pid 6715] 17:13:56.728171 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: set_newkeys: mode 1'
[pid 6715] 17:13:56.728382 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: rekey after 134217728 blocks'
[pid 6715] 17:13:56.728544 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: SSH2_MSG_NEWKEYS sent'
[pid 6715] 17:13:56.728686 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: expecting SSH2_MSG_NEWKEYS'
[pid 6715] 17:13:56.728885 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 21'
[pid 6715] 17:13:56.729012 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: SSH2_MSG_NEWKEYS received'
[pid 6715] 17:13:56.729149 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: set_newkeys: mode 0'
[pid 6715] 17:13:56.729328 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: rekey after 134217728 blocks'
[pid 6715] 17:13:56.729513 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: key: /root/.ssh/id_rsa (0x55d53a747730), agent'
[pid 6715] 17:13:56.729649 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: key: /root/.ssh/id_dsa ((nil))'
[pid 6715] 17:13:56.729780 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: key: /root/.ssh/id_ecdsa ((nil))'
[pid 6715] 17:13:56.729923 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: key: /root/.ssh/id_ed25519 ((nil))'
[pid 6715] 17:13:56.730049 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 5'
[pid 6715] 17:13:56.730174 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 7'
[pid 6715] 17:13:56.730336 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: SSH2_MSG_EXT_INFO received'
[pid 6715] 17:13:56.730468 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: kex_input_ext_info: server-sig-algs=<rsa-sha2-256,rsa-sha2-512>'
[pid 6715] 17:13:56.768049 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 6'
[pid 6715] 17:13:56.768408 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: service_accept: ssh-userauth'
[pid 6715] 17:13:56.768597 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: SSH2_MSG_SERVICE_ACCEPT received'
[pid 6715] 17:13:56.768745 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 50'
[pid 6715] 17:13:56.770224 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 53'
[pid 6715] 17:13:56.770413 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: input_userauth_banner'
[pid 6715] 17:13:56.770620 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      This system is for the use of authorised users only. Individuals using this\n'
[pid 6715] 17:13:56.770792 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      computer system without authority, or in excess of their authority, are\n'
[pid 6715] 17:13:56.770971 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      subject to having all of their activities on this system monitored and\n'
[pid 6715] 17:13:56.771108 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      recorded by system personnel. In the course of monitoring individuals\n'
[pid 6715] 17:13:56.771293 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      improperly using this system, or in the course of system maintenance, the\n'
[pid 6715] 17:13:56.771475 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      activities of authorised users may also be monitored. Anyone using this\n'
[pid 6715] 17:13:56.771662 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      system expressly consents to such monitoring and is advised that if such\n'
[pid 6715] 17:13:56.771817 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      monitoring reveals possible criminal activity, system personnel may provide\n'
[pid 6715] 17:13:56.771970 D mitogen: mitogen.ssh.Stream('local.6834'): received b'      the evidence of such monitoring to law enforcement officials.\n'
[pid 6715] 17:13:56.772126 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 51'
[pid 6715] 17:13:56.772283 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Authentications that can continue: publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:13:56.772427 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: start over, passed a different list publickey,gssapi-keyex,gssapi-with-mic'
[pid 6715] 17:13:56.772584 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: preferred gssapi-keyex,gssapi-with-mic,publickey'
[pid 6715] 17:13:56.772723 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: authmethod_lookup gssapi-keyex'
[pid 6715] 17:13:56.772860 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: remaining preferred: gssapi-with-mic,publickey'
[pid 6715] 17:13:56.772997 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: authmethod_is_enabled gssapi-keyex'
[pid 6715] 17:13:56.773135 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Next authentication method: gssapi-keyex'
[pid 6715] 17:13:56.773286 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: No valid Key exchange context'
[pid 6715] 17:13:56.773426 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:13:56.773580 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: authmethod_lookup gssapi-with-mic'
[pid 6715] 17:13:56.773721 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: remaining preferred: publickey'
[pid 6715] 17:13:56.773854 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: authmethod_is_enabled gssapi-with-mic'
[pid 6715] 17:13:56.773988 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Next authentication method: gssapi-with-mic'
[pid 6715] 17:13:56.774171 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Unspecified GSS failure.  Minor code may provide more information'
[pid 6715] 17:13:56.774331 D mitogen: mitogen.ssh.Stream('local.6834'): received b'No Kerberos credentials available (default cache: KEYRING:persistent:0)\n'
[pid 6715] 17:13:56.774494 D mitogen: mitogen.ssh.Stream('local.6834'): received b'\r\n'
[pid 6715] 17:13:56.774647 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Unspecified GSS failure.  Minor code may provide more information'
[pid 6715] 17:13:56.774788 D mitogen: mitogen.ssh.Stream('local.6834'): received b'No Kerberos credentials available (default cache: KEYRING:persistent:0)\n'
[pid 6715] 17:13:56.774935 D mitogen: mitogen.ssh.Stream('local.6834'): received b'\r\n'
[pid 6715] 17:13:56.775080 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: we did not send a packet, disable method'
[pid 6715] 17:13:56.775236 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: authmethod_lookup publickey'
[pid 6715] 17:13:56.775393 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: remaining preferred:'
[pid 6715] 17:13:56.775548 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: authmethod_is_enabled publickey'
[pid 6715] 17:13:56.775702 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Next authentication method: publickey'
[pid 6715] 17:13:56.775849 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Offering RSA public key: /root/.ssh/id_rsa'
[pid 6715] 17:13:56.775994 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send_pubkey_test'
[pid 6715] 17:13:56.776138 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 50'
[pid 6715] 17:13:56.776299 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: we sent a publickey packet, wait for reply'
[pid 6715] 17:13:56.776446 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 60'
[pid 6715] 17:13:56.776605 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Server accepts key: pkalg rsa-sha2-512 blen 277'
[pid 6715] 17:13:56.776754 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: input_userauth_pk_ok: fp SHA256:/oxEMgVDTPPCR8dvNDpVe5oRpuYD7UgqtbK3bAb3ONs'
[pid 6715] 17:13:56.776898 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: sign_and_send_pubkey: RSA SHA256:/oxEMgVDTPPCR8dvNDpVe5oRpuYD7UgqtbK3bAb3ONs'
[pid 6715] 17:13:56.777034 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 50'
[pid 6715] 17:13:56.777234 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 52'
[pid 6715] 17:13:56.777378 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Enabling compression at level 6.'
[pid 6715] 17:13:56.777526 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Authentication succeeded (publickey).'
[pid 6715] 17:13:56.777663 D mitogen: mitogen.ssh.Stream('local.6834'): received b'Authenticated to 10.250.192.104 ([10.250.192.104]:22).\r\n'
[pid 6715] 17:13:56.777802 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: fd 5 setting O_NONBLOCK'
[pid 6715] 17:13:56.777932 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: fd 6 is O_NONBLOCK'
[pid 6715] 17:13:56.778101 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: fd 7 setting O_NONBLOCK'
[pid 6715] 17:13:56.778254 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: channel 0: new [client-session]'
[pid 6715] 17:13:56.778398 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: ssh_session2_open: channel_new: 0'
[pid 6715] 17:13:56.778558 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: channel 0: send open'
[pid 6715] 17:13:56.778690 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 90'
[pid 6715] 17:13:56.778845 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Entering interactive session.'
[pid 6715] 17:13:56.779001 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: pledge: network'
[pid 6715] 17:13:56.781837 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 80'
[pid 6715] 17:13:56.782073 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: client_input_global_request: rtype hostkeys-00@openssh.com want_reply 0'
[pid 6715] 17:13:56.821635 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 91'
[pid 6715] 17:13:56.821956 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: callback start'
[pid 6715] 17:13:56.822121 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: fd 3 setting TCP_NODELAY'
[pid 6715] 17:13:56.822292 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: ssh_packet_set_tos: set IP_TOS 0x08'
[pid 6715] 17:13:56.822446 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: client_session2_setup: id 0'
[pid 6715] 17:13:56.822611 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Sending environment.'
[pid 6715] 17:13:56.822759 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env SSH_AGENT_PID'
[pid 6715] 17:13:56.822909 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env XDG_SESSION_ID'
[pid 6715] 17:13:56.823061 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env HOSTNAME'
[pid 6715] 17:13:56.823199 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env TERM'
[pid 6715] 17:13:56.823368 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env SHELL'
[pid 6715] 17:13:56.823528 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env HISTSIZE'
[pid 6715] 17:13:56.823678 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env SSH_CLIENT'
[pid 6715] 17:13:56.823823 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env SSH_TTY'
[pid 6715] 17:13:56.823968 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env USER'
[pid 6715] 17:13:56.824111 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env LS_COLORS'
[pid 6715] 17:13:56.824267 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env TERMCAP'
[pid 6715] 17:13:56.824414 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env SSH_AUTH_SOCK'
[pid 6715] 17:13:56.824571 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env VIRTUAL_ENV'
[pid 6715] 17:13:56.824713 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env MAIL'
[pid 6715] 17:13:56.824853 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env PATH'
[pid 6715] 17:13:56.824989 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env STY'
[pid 6715] 17:13:56.825126 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env PWD'
[pid 6715] 17:13:56.825274 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Sending env LANG = en_US.UTF-8'
[pid 6715] 17:13:56.825409 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: channel 0: request env confirm 0'
[pid 6715] 17:13:56.825554 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 98'
[pid 6715] 17:13:56.825744 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env PS1'
[pid 6715] 17:13:56.825928 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env HISTCONTROL'
[pid 6715] 17:13:56.826051 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env SHLVL'
[pid 6715] 17:13:56.826200 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env HOME'
[pid 6715] 17:13:56.826380 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env LOGNAME'
[pid 6715] 17:13:56.826557 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env WINDOW'
[pid 6715] 17:13:56.826692 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env SSH_CONNECTION'
[pid 6715] 17:13:56.826838 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env LESSOPEN'
[pid 6715] 17:13:56.827010 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env XDG_RUNTIME_DIR'
[pid 6715] 17:13:56.827165 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: Ignored env _'
[pid 6715] 17:13:56.827287 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug1: Sending command: /usr/bin/python -c \'import codecs,os,sys;_=codecs.decode;exec(_(_("eNqFkVFLwzAUhZ/XX9G3JCxsyYYOCgFlD+KDCEXcgxuSNqkLdklIs9X5673rhLXzwbf7cc/NOZzkdCVcM/HGa0ySQNsemSoFqFz4xCRLRjCrvZ9hRjlj5MI57VOALT9zWbtG47wPoQ+rPrQAYNgcwb6WEVx3qRApUjK0xqJUWtUt9Zcu91EWte7W030TpoWxU3+MW2cR5BxdycaiOzzo0Bhn37L5prPV9mACMLrPH14Z2ojh2VkDWOPhgg5xjPDORPehbRaci3e1K2W9dU2cdJNyO2lsdrvgNwSRBN5sg4kac4qeHl+eGWNriyBL6RRUTpKleMen0pXz2kLVKBSITIKWCvPZgs8JRd/Gw0uVFxfdiqK2QKd/qPyvwbKbz91eqdv/1H9T8kHKH8eesqA=".encode(),"base64"),"zip"))\''
[pid 6715] 17:13:56.827435 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: channel 0: request exec confirm 1'
[pid 6715] 17:13:56.827597 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: send packet: type 98'
[pid 6715] 17:13:56.827729 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: callback done'
[pid 6715] 17:13:56.827942 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: channel 0: open confirm rwindow 0 rmax 32768'
[pid 6715] 17:13:56.828108 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: channel 0: rcvd adjust 2097152'
[pid 6715] 17:13:56.828263 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug3: receive packet: type 99'
[pid 6715] 17:13:56.828420 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: channel_input_status_confirm: type 99 id 0'
[pid 6715] 17:13:56.828571 D mitogen: mitogen.ssh.Stream('local.6834'): b'debug2: exec request accepted on channel 0'
[pid 6715] 17:13:56.846416 D mitogen: mitogen.ssh.Stream('local.6834'): received b'MITO000\n'
[pid 6715] 17:13:56.846902 D mitogen: mitogen.ssh.Stream('local.6834')._ec0_received()
[pid 6715] 17:13:56.855579 D mitogen: CallChain(Context(2006, 'ssh.10.250.192.104')).call_async(): ansible_mitogen.target.init_child(log_level=10, candidate_temp_dirs=['/tmp/.ansible-$(whoami)', '/var/tmp', '/tmp'])
[pid 6715] 17:13:56.856396 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.release')
[pid 6715] 17:13:56.856730 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible')
[pid 6715] 17:13:56.856961 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils')
[pid 6715] 17:13:56.857291 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.config')
[pid 6715] 17:13:56.857495 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.config.data')
[pid 6715] 17:13:56.857666 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.config.manager')
[pid 6715] 17:13:56.857917 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.constants')
[pid 6715] 17:13:56.858137 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.errors')
[pid 6715] 17:13:56.858364 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.errors.yaml_strings')
[pid 6715] 17:13:56.858555 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils._text')
[pid 6715] 17:13:56.858745 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.common')
[pid 6715] 17:13:56.858912 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.common._collections_compat')
[pid 6715] 17:13:56.859058 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.common.file')
[pid 6715] 17:13:56.859260 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.common.process')
[pid 6715] 17:13:56.859446 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.parsing')
[pid 6715] 17:13:56.859611 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.parsing.convert_bool')
[pid 6715] 17:13:56.859760 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.pycompat24')
[pid 6715] 17:13:56.859914 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.six')
[pid 6715] 17:13:56.860124 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.parsing')
[pid 6715] 17:13:56.860355 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.parsing.quoting')
[pid 6715] 17:13:56.860640 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.utils')
[pid 6715] 17:13:56.860864 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.utils.color')
[pid 6715] 17:13:56.861053 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.utils.display')
[pid 6715] 17:13:56.861276 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.utils.path')
[pid 6715] 17:13:56.861464 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.utils.py3compat')
[pid 6715] 17:13:56.861660 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.basic')
[pid 6715] 17:13:56.862242 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.json_utils')
[pid 6715] 17:13:56.862569 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible_mitogen')
[pid 6715] 17:13:56.862797 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible_mitogen.target')
[pid 6715] 17:13:56.863068 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'mitogen.fork')
[pid 6715] 17:13:56.863280 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'mitogen.parent')
[pid 6715] 17:13:56.863607 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'mitogen.select')
[pid 6715] 17:13:56.863791 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'mitogen.service')
[pid 6715] 17:13:56.864025 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible_mitogen.runner')
[pid 6715] 17:13:56.876797 D mitogen: mitogen.parent.DiagLogStream(fd=67, 'ssh.10.250.192.104'):  'debug2: channel 0: rcvd adjust 78249'
[pid 6715] 17:13:56.888293 D mitogen: mitogen.parent.DiagLogStream(fd=67, 'ssh.10.250.192.104'):  'debug2: channel 0: rcvd adjust 65536'
[pid 6715] 17:13:56.897692 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'encodings.ascii' is submodule of a package we did not load
[pid 6715] 17:13:56.898000 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json.decoder' is submodule of a package we did not load
[pid 6715] 17:13:56.898332 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json.re' is submodule of a package we did not load
[pid 6715] 17:13:56.900752 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json.sys' is submodule of a package we did not load
[pid 6715] 17:13:56.901301 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json.struct' is submodule of a package we did not load
[pid 6715] 17:13:56.902293 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json.json' is submodule of a package we did not load
[pid 6715] 17:13:56.903476 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json.scanner' is submodule of a package we did not load
[pid 6715] 17:13:56.905247 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json._json' is submodule of a package we did not load
[pid 6715] 17:13:56.906538 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'encodings.hex_codec' is submodule of a package we did not load
[pid 6715] 17:13:56.906831 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'encodings.binascii' is submodule of a package we did not load
[pid 6715] 17:13:56.907570 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'json.encoder' is submodule of a package we did not load
[pid 6715] 17:13:56.909841 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.logging'
[pid 6715] 17:13:56.910063 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.os'
[pid 6715] 17:13:56.910208 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.random'
[pid 6715] 17:13:56.910348 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.sys'
[pid 6715] 17:13:56.910464 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.threading'
[pid 6715] 17:13:56.910597 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.traceback'
[pid 6715] 17:13:56.910705 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.mitogen'
[pid 6715] 17:13:56.920904 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.codecs'
[pid 6715] 17:13:56.921182 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.errno'
[pid 6715] 17:13:56.921382 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.fcntl'
[pid 6715] 17:13:56.921573 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.getpass'
[pid 6715] 17:13:56.922934 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.inspect'
[pid 6715] 17:13:56.935793 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.signal'
[pid 6715] 17:13:56.936383 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.socket'
[pid 6715] 17:13:56.936549 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.subprocess'
[pid 6715] 17:13:56.936682 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.termios'
[pid 6715] 17:13:56.936801 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.textwrap'
[pid 6715] 17:13:56.937503 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.time'
[pid 6715] 17:13:56.937694 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.zlib'
[pid 6715] 17:13:56.937828 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.cStringIO'
[pid 6715] 17:13:56.937947 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.functools'
[pid 6715] 17:13:56.938058 D mitogen: ModuleResponder(Router(Broker(0x7f7f450915f8)))._on_get_module(b'mitogen.compat')
[pid 6715] 17:13:56.938207 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'mitogen.compat')
[pid 6715] 17:13:56.939097 D mitogen: ModuleResponder(Router(Broker(0x7f7f450915f8)))._on_get_module(b'mitogen.compat.functools')
[pid 6715] 17:13:56.939332 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'mitogen.compat.functools')
[pid 6715] 17:13:56.943547 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.compat.threading'
[pid 6715] 17:13:56.949504 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.grp'
[pid 6715] 17:13:56.949753 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.pprint'
[pid 6715] 17:13:56.949961 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.pwd'
[pid 6715] 17:13:56.950130 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.stat'
[pid 6715] 17:13:56.959418 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'ansible.module_utils.json'
[pid 6715] 17:13:56.960588 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'ctypes.os' is submodule of a package we did not load
[pid 6715] 17:13:56.960976 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'ctypes.sys' is submodule of a package we did not load
[pid 6715] 17:13:56.961168 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'ctypes._ctypes' is submodule of a package we did not load
[pid 6715] 17:13:56.961345 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'ctypes.struct' is submodule of a package we did not load
[pid 6715] 17:13:56.961525 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'ctypes.ctypes' is submodule of a package we did not load
[pid 6715] 17:13:56.961664 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'ctypes._endian' is submodule of a package we did not load
[pid 6715] 17:13:56.989673 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'selinux.sys' is submodule of a package we did not load
[pid 6715] 17:13:56.990407 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'selinux.os' is submodule of a package we did not load
[pid 6715] 17:13:56.990609 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'selinux.imp' is submodule of a package we did not load
[pid 6715] 17:13:57.000284 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'selinux.shutil' is submodule of a package we did not load
[pid 6715] 17:13:57.000829 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'selinux.errno' is submodule of a package we did not load
[pid 6715] 17:13:57.001445 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'selinux.stat' is submodule of a package we did not load
[pid 6715] 17:13:57.001854 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'ansible.module_utils.codecs'
[pid 6715] 17:13:57.003311 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'ansible.module_utils.ansible'
[pid 6715] 17:13:57.003804 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'ansible.module_utils.sys'
[pid 6715] 17:13:57.003991 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'ansible.module_utils.ast'
[pid 6715] 17:13:57.005155 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'ansible.module_utils.six.moves'
[pid 6715] 17:13:57.005403 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'ansible.module_utils.parsing.ansible'
[pid 6715] 17:13:57.005623 D mitogen.ctx.ssh.10.250.192.104: ansible_mitogen.runner: EnvironmentFileWatcher(u'/home/mylogin/.pam_environment') installed; existing keys: []
[pid 6715] 17:13:57.005821 D mitogen.ctx.ssh.10.250.192.104: ansible_mitogen.runner: EnvironmentFileWatcher(u'/etc/environment') installed; existing keys: []
[pid 6715] 17:13:57.006032 D mitogen.ctx.ssh.10.250.192.104: mitogen: replaced Poller(0x7f2644fef510) with EpollPoller(0x7f2638543d90) (new: 4 readers, 0 writers; old: 4 readers, 0 writers)
[pid 6715] 17:13:57.006162 D mitogen.ctx.ssh.10.250.192.104: mitogen: Router(Broker(0x7f2644fef3d0)).upgrade()
[pid 6715] 17:13:57.006311 D mitogen: IdAllocator(Router(Broker(0x7f7f450915f8))): allocating [2007..3007)
[pid 6715] 17:13:57.006439 D mitogen: IdAllocator(Router(Broker(0x7f7f450915f8))): allocating [2007..3007) to Context(2006, 'ssh.10.250.192.104')
[pid 6715] 17:13:57.008516 D mitogen.ctx.ssh.10.250.192.104: mitogen: mitogen.fork.Stream(u'default').connect()
[pid 6715] 17:13:57.009794 D mitogen.ctx.ssh.10.250.192.104: mitogen: mitogen.fork.Stream(u'fork.6600').connect(): child process stdin/stdout=16
[pid 6715] 17:13:57.009950 D mitogen: Adding route to 2007 via mitogen.ssh.Stream('ssh.10.250.192.104')
[pid 6715] 17:13:57.010082 D mitogen: Router(Broker(0x7f7f450915f8)).add_route(2007, mitogen.ssh.Stream('ssh.10.250.192.104'))
[pid 6715] 17:13:57.010418 D mitogen.ctx.ssh.10.250.192.104: ansible_mitogen.target: Selected temp directory: u'/tmp/.ansible-$(whoami)' (from [u'/tmp/.ansible-$(whoami)', u'/var/tmp', u'/tmp', '/tmp', '/var/tmp', '/usr/tmp', '/home/mylogin'])
[pid 6715] 17:13:57.010699 D mitogen.ctx.fork.6600: mitogen: register(Context(2006, 'parent'), mitogen.core.Stream('parent'))
[pid 6715] 17:13:57.010857 D mitogen.ctx.fork.6600: mitogen: Connected to Context(2006, 'parent'); my ID is 2007, PID is 6600
[pid 6715] 17:13:57.011018 D mitogen.ctx.fork.6600: mitogen: Recovered sys.executable: '/usr/bin/python'
[pid 6715] 17:13:57.012633 D mitogen: CallChain(Context(2006, 'ssh.10.250.192.104')).call_async(): mitogen.parent._proxy_connect(name=None, method_name='sudo', kwargs=Kwargs({'unidirectional': True, 'username': 'root', 'password': None, 'python_path': ['/usr/bin/python'], 'sudo_path': None, 'connect_timeout': 120, 'sudo_args': ['-H', '-S', '-n'], 'debug': False, 'profiling': False}))
[pid 6715] 17:13:57.014256 D mitogen: ModuleResponder(Router(Broker(0x7f7f450915f8)))._on_get_module(b'mitogen.sudo')
[pid 6715] 17:13:57.027113 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'mitogen.sudo')
[pid 6715] 17:13:57.032406 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): master doesn't know 'mitogen.optparse'
[pid 6715] 17:13:57.034436 D mitogen.ctx.ssh.10.250.192.104: mitogen: mitogen.sudo.Stream(u'default').connect()
[pid 6715] 17:13:57.043876 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'encodings.base64_codec' is submodule of a package we did not load
[pid 6715] 17:13:57.044251 D mitogen.ctx.ssh.10.250.192.104: mitogen: Importer(): 'encodings.base64' is submodule of a package we did not load
[pid 6715] 17:13:57.044584 D mitogen.ctx.ssh.10.250.192.104: mitogen.sudo: sudo command line: ['sudo', '-u', u'root', '-H', u'/usr/bin/python', '-c', u'import codecs,os,sys;_=codecs.decode;exec(_(_("eNqFkVFLwzAUhZ/XX9G3JCzb0qmbFgLKHsQHEYrYBx3SNqkGuyQk7er2673rhLXzwbf7cc/NOZwkNOXGT62yEpPA0bZHqgwBSuO+MImDEcyisXPMaMQYOXFC++RgGx25qIyXOOmD60PahxYADP0O7KusBtdNyHmIROZapVGYadEt5bcsmjrLK9mtZ413s1zpmd3Vn0YjyDk6k415d7iVziujX+OLdWcr9VY5YHSX3L8wtObDs6MGsMLDBR3iGOGNqs2H1HFt9sLI7W2tNo2bFM5PFoxFy2lWgI/y8eLq+oYgEsDDrVO1xBFFjw/PT4yxN40gUGEE9E6CFX/Hh+aFsVJD38jliEydzASO5stLRijaKwsvlZafdClFbY4On1HaX4NVNx8LPlO3/6n/powGKX8AqjqzZA==".encode(),"base64"),"zip"))']
[pid 6715] 17:13:57.048250 D mitogen.ctx.ssh.10.250.192.104: mitogen: hybrid_tty_create_child() pid=6602 stdio=18, tty=17, cmd: sudo -u root -H /usr/bin/python -c "import codecs,os,sys;_=codecs.decode;exec(_(_(\"eNqFkVFLwzAUhZ/XX9G3JCzb0qmbFgLKHsQHEYrYBx3SNqkGuyQk7er2673rhLXzwbf7cc/NOZwkNOXGT62yEpPA0bZHqgwBSuO+MImDEcyisXPMaMQYOXFC++RgGx25qIyXOOmD60PahxYADP0O7KusBtdNyHmIROZapVGYadEt5bcsmjrLK9mtZ413s1zpmd3Vn0YjyDk6k415d7iVziujX+OLdWcr9VY5YHSX3L8wtObDs6MGsMLDBR3iGOGNqs2H1HFt9sLI7W2tNo2bFM5PFoxFy2lWgI/y8eLq+oYgEsDDrVO1xBFFjw/PT4yxN40gUGEE9E6CFX/Hh+aFsVJD38jliEydzASO5stLRijaKwsvlZafdClFbY4On1HaX4NVNx8LPlO3/6n/powGKX8AqjqzZA==\".encode(),\"base64\"),\"zip\"))"
[pid 6715] 17:13:57.048581 D mitogen.ctx.ssh.10.250.192.104: mitogen: mitogen.sudo.Stream(u'local.6602').connect(): child process stdin/stdout=18
[pid 6715] 17:13:57.067733 D mitogen.ctx.ssh.10.250.192.104: mitogen.sudo: mitogen.sudo.Stream(u'local.6602'): received 'MITO000\n'
[pid 6715] 17:13:57.068019 D mitogen.ctx.ssh.10.250.192.104: mitogen: mitogen.sudo.Stream(u'local.6602')._ec0_received()
[pid 6715] 17:13:57.073326 D mitogen: Adding route to 2008 via mitogen.ssh.Stream('ssh.10.250.192.104')
[pid 6715] 17:13:57.073608 D mitogen: Router(Broker(0x7f7f450915f8)).add_route(2008, mitogen.ssh.Stream('ssh.10.250.192.104'))
[pid 6715] 17:13:57.074063 D mitogen: CallChain(Context(2008, 'ssh.10.250.192.104.sudo.root')).call_async(): ansible_mitogen.target.init_child(log_level=10, candidate_temp_dirs=['/tmp/.ansible-$(whoami)', '/var/tmp', '/tmp'])
[pid 6715] 17:13:57.076059 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_forward_module() sending u'ansible' to 2008 via 2008
[pid 6715] 17:13:57.078031 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_forward_module() sending u'ansible.module_utils' to 2008 via 2008
[pid 6715] 17:13:57.078215 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_forward_module() sending u'ansible.module_utils.basic' to 2008 via 2008
[pid 6715] 17:13:57.078371 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_forward_module() sending u'ansible.module_utils.json_utils' to 2008 via 2008
[pid 6715] 17:13:57.078516 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_forward_module() sending u'ansible_mitogen' to 2008 via 2008
[pid 6715] 17:13:57.078637 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_forward_module() sending u'ansible_mitogen.runner' to 2008 via 2008
[pid 6715] 17:13:57.078743 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._send_module_and_related(u'ansible_mitogen.runner'): absent: u'mitogen'
[pid 6715] 17:13:57.113801 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'encodings.ascii' is submodule of a package we did not load
[pid 6715] 17:13:57.118569 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json.decoder' is submodule of a package we did not load
[pid 6715] 17:13:57.118857 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json.re' is submodule of a package we did not load
[pid 6715] 17:13:57.121415 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json.sys' is submodule of a package we did not load
[pid 6715] 17:13:57.122278 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json.struct' is submodule of a package we did not load
[pid 6715] 17:13:57.122980 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json.json' is submodule of a package we did not load
[pid 6715] 17:13:57.124574 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json.scanner' is submodule of a package we did not load
[pid 6715] 17:13:57.125754 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json._json' is submodule of a package we did not load
[pid 6715] 17:13:57.125940 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'encodings.hex_codec' is submodule of a package we did not load
[pid 6715] 17:13:57.148470 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'encodings.binascii' is submodule of a package we did not load
[pid 6715] 17:13:57.148751 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'json.encoder' is submodule of a package we did not load
[pid 6715] 17:13:57.148903 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.logging'
[pid 6715] 17:13:57.149056 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.os'
[pid 6715] 17:13:57.149437 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.random'
[pid 6715] 17:13:57.149642 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.sys'
[pid 6715] 17:13:57.151927 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.threading'
[pid 6715] 17:13:57.152110 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.traceback'
[pid 6715] 17:13:57.152247 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.mitogen'
[pid 6715] 17:13:57.152450 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.codecs'
[pid 6715] 17:13:57.152683 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.errno'
[pid 6715] 17:13:57.152865 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.fcntl'
[pid 6715] 17:13:57.153061 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.getpass'
[pid 6715] 17:13:57.153293 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.inspect'
[pid 6715] 17:13:57.174123 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.signal'
[pid 6715] 17:13:57.174467 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.socket'
[pid 6715] 17:13:57.177348 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.subprocess'
[pid 6715] 17:13:57.177598 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.termios'
[pid 6715] 17:13:57.177734 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.textwrap'
[pid 6715] 17:13:57.178015 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.time'
[pid 6715] 17:13:57.178140 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.zlib'
[pid 6715] 17:13:57.178289 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.cStringIO'
[pid 6715] 17:13:57.178406 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.functools'
[pid 6715] 17:13:57.178530 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_get_module(Message(2006, 2008, 2008, 100, 0, 'mitogen.compat'..14))
[pid 6715] 17:13:57.178639 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_get_module(): sending u'mitogen.compat'
[pid 6715] 17:13:57.178741 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._send_module_and_related(u'mitogen.compat'): absent: u'mitogen'
[pid 6715] 17:13:57.178867 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_get_module(Message(2006, 2008, 2008, 100, 0, 'mitogen.compat.functools'..24))
[pid 6715] 17:13:57.178970 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_get_module(): sending u'mitogen.compat.functools'
[pid 6715] 17:13:57.179068 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._send_module_and_related(u'mitogen.compat.functools'): absent: u'mitogen'
[pid 6715] 17:13:57.182738 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.compat.threading'
[pid 6715] 17:13:57.191035 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.grp'
[pid 6715] 17:13:57.191330 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.pprint'
[pid 6715] 17:13:57.191512 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.pwd'
[pid 6715] 17:13:57.194253 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'mitogen.stat'
[pid 6715] 17:13:57.195687 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.json'
[pid 6715] 17:13:57.205581 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'ctypes.os' is submodule of a package we did not load
[pid 6715] 17:13:57.206627 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'ctypes.sys' is submodule of a package we did not load
[pid 6715] 17:13:57.207191 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'ctypes._ctypes' is submodule of a package we did not load
[pid 6715] 17:13:57.207506 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'ctypes.struct' is submodule of a package we did not load
[pid 6715] 17:13:57.207653 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'ctypes.ctypes' is submodule of a package we did not load
[pid 6715] 17:13:57.207766 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'ctypes._endian' is submodule of a package we did not load
[pid 6715] 17:13:57.236251 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'selinux.sys' is submodule of a package we did not load
[pid 6715] 17:13:57.236632 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'selinux.os' is submodule of a package we did not load
[pid 6715] 17:13:57.236980 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'selinux.imp' is submodule of a package we did not load
[pid 6715] 17:13:57.237327 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'selinux.shutil' is submodule of a package we did not load
[pid 6715] 17:13:57.238341 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'selinux.errno' is submodule of a package we did not load
[pid 6715] 17:13:57.239351 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'selinux.stat' is submodule of a package we did not load
[pid 6715] 17:13:57.239562 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.codecs'
[pid 6715] 17:13:57.245811 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.ansible'
[pid 6715] 17:13:57.246243 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.sys'
[pid 6715] 17:13:57.247028 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.ast'
[pid 6715] 17:13:57.251554 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.six.moves'
[pid 6715] 17:13:57.251799 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.parsing.ansible'
[pid 6715] 17:13:57.251920 D mitogen.ctx.ssh.10.250.192.104.sudo.root: ansible_mitogen.runner: EnvironmentFileWatcher(u'/root/.pam_environment') installed; existing keys: []
[pid 6715] 17:13:57.252030 D mitogen.ctx.ssh.10.250.192.104.sudo.root: ansible_mitogen.runner: EnvironmentFileWatcher(u'/etc/environment') installed; existing keys: []
[pid 6715] 17:13:57.252148 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: replaced Poller(0x7ff13f300410) with EpollPoller(0x7ff133aa0910) (new: 4 readers, 1 writers; old: 4 readers, 1 writers)
[pid 6715] 17:13:57.252331 D mitogen: IdAllocator(Router(Broker(0x7f7f450915f8))): allocating [3007..4007)
[pid 6715] 17:13:57.252453 D mitogen: IdAllocator(Router(Broker(0x7f7f450915f8))): allocating [3007..4007) to Context(2008, 'ssh.10.250.192.104.sudo.root')
[pid 6715] 17:13:57.255644 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: mitogen.fork.Stream(u'default').connect()
[pid 6715] 17:13:57.258580 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: mitogen.fork.Stream(u'fork.6606').connect(): child process stdin/stdout=16
[pid 6715] 17:13:57.258825 D mitogen.ctx.ssh.10.250.192.104: mitogen: Adding route to 3007 via mitogen.sudo.Stream(u'sudo.root')
[pid 6715] 17:13:57.258961 D mitogen.ctx.ssh.10.250.192.104: mitogen: Router(Broker(0x7f2644fef3d0)).add_route(3007, mitogen.sudo.Stream(u'sudo.root'))
[pid 6715] 17:13:57.259083 D mitogen: Adding route to 3007 via mitogen.ssh.Stream('ssh.10.250.192.104')
[pid 6715] 17:13:57.259180 D mitogen: Router(Broker(0x7f7f450915f8)).add_route(3007, mitogen.ssh.Stream('ssh.10.250.192.104'))
[pid 6715] 17:13:57.259321 D mitogen.ctx.ssh.10.250.192.104.sudo.root: ansible_mitogen.target: Selected temp directory: u'/tmp/.ansible-$(whoami)' (from [u'/tmp/.ansible-$(whoami)', u'/var/tmp', u'/tmp', '/tmp', '/var/tmp', '/usr/tmp', '/home/mylogin'])
[pid 6715] 17:13:57.259649 D mitogen.ctx.fork.6606: mitogen: register(Context(2008, 'parent'), mitogen.core.Stream('parent'))
[pid 6715] 17:13:57.259798 D mitogen.ctx.fork.6606: mitogen: Connected to Context(2008, 'parent'); my ID is 3007, PID is 6606
[pid 6715] 17:13:57.259951 D mitogen.ctx.fork.6606: mitogen: Recovered sys.executable: '/usr/bin/python'
[pid 6831] 17:13:57.261588 D ansible_mitogen.planner: BinaryPlanner() rejected 'setup'
[pid 6831] 17:13:57.261902 D ansible_mitogen.planner: NewStylePlanner() accepted 'setup' (filename '/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py')
[pid 6715] 17:13:57.263919 D mitogen: PushFileService().propagate_to(Context(2008, 'ssh.10.250.192.104.sudo.root'), '/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py')
[pid 6715] 17:13:57.265172 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.ansible_collector')
[pid 6715] 17:13:57.265425 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.collector')
[pid 6715] 17:13:57.265665 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.compat')
[pid 6715] 17:13:57.265862 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.default_collectors')
[pid 6715] 17:13:57.266036 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware')
[pid 6715] 17:13:57.266213 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.aix')
[pid 6715] 17:13:57.266411 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.base')
[pid 6715] 17:13:57.266597 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.darwin')
[pid 6715] 17:13:57.266799 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.dragonfly')
[pid 6715] 17:13:57.266978 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.freebsd')
[pid 6715] 17:13:57.267151 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.hpux')
[pid 6715] 17:13:57.267350 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.hurd')
[pid 6715] 17:13:57.267524 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.linux')
[pid 6715] 17:13:57.267739 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.netbsd')
[pid 6715] 17:13:57.267941 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.openbsd')
[pid 6715] 17:13:57.268108 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.hardware.sunos')
[pid 6715] 17:13:57.268330 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.namespace')
[pid 6715] 17:13:57.268533 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network')
[pid 6715] 17:13:57.268693 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.aix')
[pid 6715] 17:13:57.268889 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.base')
[pid 6715] 17:13:57.269043 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.darwin')
[pid 6715] 17:13:57.269193 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.dragonfly')
[pid 6715] 17:13:57.269377 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.freebsd')
[pid 6715] 17:13:57.269544 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.generic_bsd')
[pid 6715] 17:13:57.269716 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.hpux')
[pid 6715] 17:13:57.269871 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.hurd')
[pid 6715] 17:13:57.270098 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.iscsi')
[pid 6715] 17:13:57.270314 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.linux')
[pid 6715] 17:13:57.270511 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.netbsd')
[pid 6715] 17:13:57.270675 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.openbsd')
[pid 6715] 17:13:57.270842 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.network.sunos')
[pid 6715] 17:13:57.271002 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.other')
[pid 6715] 17:13:57.271132 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.other.facter')
[pid 6715] 17:13:57.271343 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.other.ohai')
[pid 6715] 17:13:57.271543 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.sysctl')
[pid 6715] 17:13:57.271695 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system')
[pid 6715] 17:13:57.271852 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.apparmor')
[pid 6715] 17:13:57.271991 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.caps')
[pid 6715] 17:13:57.272129 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.chroot')
[pid 6715] 17:13:57.272295 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.cmdline')
[pid 6715] 17:13:57.272461 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.date_time')
[pid 6715] 17:13:57.272627 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.distribution')
[pid 6715] 17:13:57.272804 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.dns')
[pid 6715] 17:13:57.272951 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.env')
[pid 6715] 17:13:57.273092 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.fips')
[pid 6715] 17:13:57.273257 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.local')
[pid 6715] 17:13:57.273415 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.lsb')
[pid 6715] 17:13:57.273577 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.pkg_mgr')
[pid 6715] 17:13:57.273732 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.platform')
[pid 6715] 17:13:57.273890 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.python')
[pid 6715] 17:13:57.274029 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.selinux')
[pid 6715] 17:13:57.274182 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.service_mgr')
[pid 6715] 17:13:57.274385 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.ssh_pub_keys')
[pid 6715] 17:13:57.274559 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.system.user')
[pid 6715] 17:13:57.274703 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.timeout')
[pid 6715] 17:13:57.274847 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.utils')
[pid 6715] 17:13:57.275002 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual')
[pid 6715] 17:13:57.275151 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.base')
[pid 6715] 17:13:57.275341 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.dragonfly')
[pid 6715] 17:13:57.275515 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.freebsd')
[pid 6715] 17:13:57.275674 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.hpux')
[pid 6715] 17:13:57.275819 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.linux')
[pid 6715] 17:13:57.275975 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.netbsd')
[pid 6715] 17:13:57.276129 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.openbsd')
[pid 6715] 17:13:57.276296 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.sunos')
[pid 6715] 17:13:57.276459 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts.virtual.sysctl')
[pid 6715] 17:13:57.276637 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.10.250.192.104'), 'ansible.module_utils.facts')
[pid 6831] 17:13:57.296908 D mitogen: CallChain(Context(2008, 'ssh.10.250.192.104.sudo.root')).call_async(): ansible_mitogen.target.run_module(kwargs={'runner_name': 'NewStyleRunner', 'module': 'setup', 'path': '/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py', 'json_args': '{"gather_subset": ["all"], "gather_timeout": 10, "_ansible_check_mode": false, "_ansible_no_log": false, "_ansible_debug": false, "_ansible_diff": false, "_ansible_verbosity": 3, "_ansible_version": "2.7.2", "_ansible_module_name": "setup", "_ansible_syslog_facility": "LOG_USER", "_ansible_selinux_special_fs": ["fuse", "nfs", "vboxsf", "ramfs", "9p"], "_ansible_socket": null, "_ansible_shell_executable": "/bin/sh", "_ansible_keep_remote_files": false, "_ansible_tmpdir": null, "_ansible_remote_tmp": "/tmp/.ansible-$(whoami)"}', 'env': {}, 'interpreter_fragment': None, 'is_python': None, 'module_map': {'builtin': ['ansible.module_utils._text', 'ansible.module_utils.basic', 'ansible.module_utils.common', 'ansible.module_utils.common._collections_compat', 'ansible.module_utils.common.file', 'ansible.module_utils.common.process', 'ansible.module_utils.facts', 'ansible.module_utils.facts.ansible_collector', 'ansible.module_utils.facts.collector', 'ansible.module_utils.facts.compat', 'ansible.module_utils.facts.default_collectors', 'ansible.module_utils.facts.hardware', 'ansible.module_utils.facts.hardware.aix', 'ansible.module_utils.facts.hardware.base', 'ansible.module_utils.facts.hardware.darwin', 'ansible.module_utils.facts.hardware.dragonfly', 'ansible.module_utils.facts.hardware.freebsd', 'ansible.module_utils.facts.hardware.hpux', 'ansible.module_utils.facts.hardware.hurd', 'ansible.module_utils.facts.hardware.linux', 'ansible.module_utils.facts.hardware.netbsd', 'ansible.module_utils.facts.hardware.openbsd', 'ansible.module_utils.facts.hardware.sunos', 'ansible.module_utils.facts.namespace', 'ansible.module_utils.facts.network', 'ansible.module_utils.facts.network.aix', 'ansible.module_utils.facts.network.base', 'ansible.module_utils.facts.network.darwin', 'ansible.module_utils.facts.network.dragonfly', 'ansible.module_utils.facts.network.freebsd', 'ansible.module_utils.facts.network.generic_bsd', 'ansible.module_utils.facts.network.hpux', 'ansible.module_utils.facts.network.hurd', 'ansible.module_utils.facts.network.iscsi', 'ansible.module_utils.facts.network.linux', 'ansible.module_utils.facts.network.netbsd', 'ansible.module_utils.facts.network.openbsd', 'ansible.module_utils.facts.network.sunos', 'ansible.module_utils.facts.other', 'ansible.module_utils.facts.other.facter', 'ansible.module_utils.facts.other.ohai', 'ansible.module_utils.facts.sysctl', 'ansible.module_utils.facts.system', 'ansible.module_utils.facts.system.apparmor', 'ansible.module_utils.facts.system.caps', 'ansible.module_utils.facts.system.chroot', 'ansible.module_utils.facts.system.cmdline', 'ansible.module_utils.facts.system.date_time', 'ansible.module_utils.facts.system.distribution', 'ansible.module_utils.facts.system.dns', 'ansible.module_utils.facts.system.env', 'ansible.module_utils.facts.system.fips', 'ansible.module_utils.facts.system.local', 'ansible.module_utils.facts.system.lsb', 'ansible.module_utils.facts.system.pkg_mgr', 'ansible.module_utils.facts.system.platform', 'ansible.module_utils.facts.system.python', 'ansible.module_utils.facts.system.selinux', 'ansible.module_utils.facts.system.service_mgr', 'ansible.module_utils.facts.system.ssh_pub_keys', 'ansible.module_utils.facts.system.user', 'ansible.module_utils.facts.timeout', 'ansible.module_utils.facts.utils', 'ansible.module_utils.facts.virtual', 'ansible.module_utils.facts.virtual.base', 'ansible.module_utils.facts.virtual.dragonfly', 'ansible.module_utils.facts.virtual.freebsd', 'ansible.module_utils.facts.virtual.hpux', 'ansible.module_utils.facts.virtual.linux', 'ansible.module_utils.facts.virtual.netbsd', 'ansible.module_utils.facts.virtual.openbsd', 'ansible.module_utils.facts.virtual.sunos', 'ansible.module_utils.facts.virtual.sysctl', 'ansible.module_utils.parsing', 'ansible.module_utils.parsing.convert_bool', 'ansible.module_utils.pycompat24', 'ansible.module_utils.six'], 'custom': []}, 'good_temp_dir': '/tmp/.ansible-$(whoami)', 'cwd': None, 'extra_env': None, 'emulate_tty': True, 'service_context': Context(0, None)})
[pid 6715] 17:13:57.298201 D mitogen: mitogen.parent.DiagLogStream(fd=67, 'ssh.10.250.192.104'):  'debug2: channel 0: rcvd adjust 100344'
[pid 6715] 17:13:57.302787 D mitogen: mitogen.parent.DiagLogStream(fd=67, 'ssh.10.250.192.104'):  'debug2: channel 0: rcvd adjust 114688'
[pid 6715] 17:13:57.306301 D mitogen: mitogen.parent.DiagLogStream(fd=67, 'ssh.10.250.192.104'):  'debug2: channel 0: rcvd adjust 114688'
[pid 6715] 17:13:57.309908 D mitogen: mitogen.parent.DiagLogStream(fd=67, 'ssh.10.250.192.104'):  'debug2: channel 0: rcvd adjust 114688'
[pid 6715] 17:13:57.312461 D mitogen.ctx.ssh.10.250.192.104: mitogen: mitogen.service.Pool(0x7f26338e6710, size=16, th='Thread-1'): initialized
[pid 6715] 17:13:57.325199 D mitogen.ctx.ssh.10.250.192.104: mitogen: ModuleForwarder(Router(Broker(0x7f2644fef3d0)))._on_forward_module() sending u'ansible.module_utils.facts' to 2008 via 2008
[pid 6715] 17:13:57.325500 D mitogen.ctx.ssh.10.250.192.104: mitogen: PushFileService().store_and_forward(u'/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py', [blob: 7876 bytes], Context(2008, u'ssh.10.250.192.104.sudo.root'))
[pid 6715] 17:13:57.335993 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: mitogen.service.Pool(0x7ff131d9c710, size=16, th='Thread-1'): initialized
[pid 6715] 17:13:57.337449 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: PushFileService().store_and_forward(u'/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py', [blob: 7876 bytes], Context(2008, u'ssh.10.250.192.104.sudo.root'))
[pid 6715] 17:13:57.337682 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: PushFileService().get(u'/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py') waiting for uncached file to arrive
[pid 6715] 17:13:57.343207 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: PushFileService().get(u'/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py') -> [blob: 7876 bytes]
[pid 6715] 17:13:57.350317 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.common.'
[pid 6715] 17:13:57.351010 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.os'
[pid 6715] 17:13:57.371567 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'distutils.version' is submodule of a package we did not load
[pid 6715] 17:13:57.373450 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'distutils.string' is submodule of a package we did not load
[pid 6715] 17:13:57.374828 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'distutils.re' is submodule of a package we did not load
[pid 6715] 17:13:57.381410 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'distutils.types' is submodule of a package we did not load
[pid 6715] 17:13:57.382165 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.re'
[pid 6715] 17:13:57.402869 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.'
[pid 6715] 17:13:57.403192 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.hardware.'
[pid 6715] 17:13:57.403430 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.network.'
[pid 6715] 17:13:57.403684 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.other.'
[pid 6715] 17:13:57.403886 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.system.'
[pid 6715] 17:13:57.404102 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.facts.virtual.'
[pid 6715] 17:13:57.404332 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.parsing.'
[pid 6715] 17:13:57.404546 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): master doesn't know 'ansible.module_utils.six.'
[pid 6715] 17:13:57.412962 D mitogen.ctx.ssh.10.250.192.104.sudo.root: mitogen: Importer(): 'encodings.string_escape' is submodule of a package we did not load
[pid 6831] 17:13:57.497597 D ansible_mitogen.connection: Call took 200 ms: ansible_mitogen.target.run_module(kwargs={'runner_name': 'NewStyleRunner', 'module': 'setup', 'path': '/root/msp-scm/env/lib/python3.6/site-packages/ansible/modules/system/setup.py', 'json_args': '{"gather_subset": ["all"], "gather_timeout": 10, "_ansible_check_mode": false, "_ansible_no_log": false, "_ansible_debug": false, "_ansible_diff": false, "_ansible_verbosity": 3, "_ansible_version": "2.7.2", "_ansible_module_name": "setup", "_ansible_syslog_facility": "LOG_USER", "_ansible_selinux_special_fs": ["fuse", "nfs", "vboxsf", "ramfs", "9p"], "_ansible_socket": null, "_ansible_shell_executable": "/bin/sh", "_ansible_keep_remote_files": false, "_ansible_tmpdir": null, "_ansible_remote_tmp": "/tmp/.ansible-$(whoami)"}', 'env': {}, 'interpreter_fragment': None, 'is_python': None, 'module_map': {'builtin': ['ansible.module_utils._text', 'ansible.module_utils.basic', 'ansible.module_utils.common', 'ansible.module_utils.common._collections_compat', 'ansible.module_utils.common.file', 'ansible.module_utils.common.process', 'ansible.module_utils.facts', 'ansible.module_utils.facts.ansible_collector', 'ansible.module_utils.facts.collector', 'ansible.module_utils.facts.compat', 'ansible.module_utils.facts.default_collectors', 'ansible.module_utils.facts.hardware', 'ansible.module_utils.facts.hardware.aix', 'ansible.module_utils.facts.hardware.base', 'ansible.module_utils.facts.hardware.darwin', 'ansible.module_utils.facts.hardware.dragonfly', 'ansible.module_utils.facts.hardware.freebsd', 'ansible.module_utils.facts.hardware.hpux', 'ansible.module_utils.facts.hardware.hurd', 'ansible.module_utils.facts.hardware.linux', 'ansible.module_utils.facts.hardware.netbsd', 'ansible.module_utils.facts.hardware.openbsd', 'ansible.module_utils.facts.hardware.sunos', 'ansible.module_utils.facts.namespace', 'ansible.module_utils.facts.network', 'ansible.module_utils.facts.network.aix', 'ansible.module_utils.facts.network.base', 'ansible.module_utils.facts.network.darwin', 'ansible.module_utils.facts.network.dragonfly', 'ansible.module_utils.facts.network.freebsd', 'ansible.module_utils.facts.network.generic_bsd', 'ansible.module_utils.facts.network.hpux', 'ansible.module_utils.facts.network.hurd', 'ansible.module_utils.facts.network.iscsi', 'ansible.module_utils.facts.network.linux', 'ansible.module_utils.facts.network.netbsd', 'ansible.module_utils.facts.network.openbsd', 'ansible.module_utils.facts.network.sunos', 'ansible.module_utils.facts.other', 'ansible.module_utils.facts.other.facter', 'ansible.module_utils.facts.other.ohai', 'ansible.module_utils.facts.sysctl', 'ansible.module_utils.facts.system', 'ansible.module_utils.facts.system.apparmor', 'ansible.module_utils.facts.system.caps', 'ansible.module_utils.facts.system.chroot', 'ansible.module_utils.facts.system.cmdline', 'ansible.module_utils.facts.system.date_time', 'ansible.module_utils.facts.system.distribution', 'ansible.module_utils.facts.system.dns', 'ansible.module_utils.facts.system.env', 'ansible.module_utils.facts.system.fips', 'ansible.module_utils.facts.system.local', 'ansible.module_utils.facts.system.lsb', 'ansible.module_utils.facts.system.pkg_mgr', 'ansible.module_utils.facts.system.platform', 'ansible.module_utils.facts.system.python', 'ansible.module_utils.facts.system.selinux', 'ansible.module_utils.facts.system.service_mgr', 'ansible.module_utils.facts.system.ssh_pub_keys', 'ansible.module_utils.facts.system.user', 'ansible.module_utils.facts.timeout', 'ansible.module_utils.facts.utils', 'ansible.module_utils.facts.virtual', 'ansible.module_utils.facts.virtual.base', 'ansible.module_utils.facts.virtual.dragonfly', 'ansible.module_utils.facts.virtual.freebsd', 'ansible.module_utils.facts.virtual.hpux', 'ansible.module_utils.facts.virtual.linux', 'ansible.module_utils.facts.virtual.netbsd', 'ansible.module_utils.facts.virtual.openbsd', 'ansible.module_utils.facts.virtual.sunos', 'ansible.module_utils.facts.virtual.sysctl', 'ansible.module_utils.parsing', 'ansible.module_utils.parsing.convert_bool', 'ansible.module_utils.pycompat24', 'ansible.module_utils.six'], 'custom': []}, 'good_temp_dir': '/tmp/.ansible-$(whoami)', 'cwd': None, 'extra_env': None, 'emulate_tty': True, 'service_context': Context(0, None)})
[pid 6831] 17:13:57.504101 D ansible_mitogen.mixins: _remove_tmp_path(None)
[pid 6831] 17:13:57.509259 D mitogen: CallChain(Context(2008, 'ssh.10.250.192.104.sudo.root')).call_no_reply(): mitogen.core.Dispatcher.forget_chain('localhost.localdomain-6831-7f7f5800a740-57c85db6adb59')
[pid 6715] 17:13:57.510442 D ansible_mitogen.services: ContextService().put(Context(2008, 'ssh.10.250.192.104.sudo.root'))
[pid 6831] 17:13:57.511386 D mitogen: mitogen.core.Stream('unix_listener.6715').on_disconnect()
[pid 6715] 17:13:57.511994 D mitogen: mitogen.core.Stream('unix_client.6831').on_disconnect()
[pid 6831] 17:13:57.511963 D mitogen: Waker(Broker(0x7f7f4444bb70) rfd=13, wfd=14).on_disconnect()
ok: [timur-crs-60017.hostname]
@trim777
Copy link
Author

@trim777 trim777 commented Dec 14, 2018

Hi!
Can I help somehow to solve this issue ?
Or may be I should try master branch ? If yes, I need just copy content of the folder mitogen (from git) inside locally installed mitogen's folder, right?

@dw
Copy link
Member

@dw dw commented Jan 20, 2019

You said you are authenticating with SSH agent, but in the log, it does not look like ssh is using agent authentication. SSH tries to find all common key filenames and fails, that is why authentication fails. Does the same run succeed under vanilla Ansible?

It is possible that Mitogen is somehow not preserving the SSH_AUTH_SOCK environment variable, which SSH depends on. Is there anything special about your configuration? For example, are you using an Ansible plug-in to manage SSH agent

dw added a commit that referenced this issue Jan 20, 2019
* origin/dmw:
  issue #415: replace default Poller with select.poll()
  issue #415: add IPC latency bench.
  issue #408: fix test fallout.
  issue #408: update Changelog; closes #408.
  issue #408: 2.4 compat: replace iter_read with explicit generator
  issue #408: 2.4 compat: remove ternary if use in master.py.
  issue #408: use compatible method to get thread ID.
  issue #408: fix mitogen.compat.tokenize 2.4 compatibility.
  issue #436: decode debug log lines on Python 3.
  issue #461: Ansible 2.3 placeholder modules for action plug-ins.
  issue #461: Ansible 2.3-compatible _get_candidate_temp_dirs().
  issue #461: Ansible 2.3 did not have _load_name.
@dw
Copy link
Member

@dw dw commented Jan 22, 2019

Hello,

Mitogen 0.2.3 was released with a major bug in delegate_to: handling. Your original task includes "delegate_to:", therefore it is almost certainly a manifestation of this bug. Please pull latest master - the fix will be on master in around 10 minutes. Sorry for the huge delay getting around to looking at this.


This should now be fixed on the master branch and will make it into the next release. To be updated when a new release is made, subscribe to https://networkgenomics.com/mail/mitogen-announce/

If you are still experiencing the problem on the current master branch, please do not hesitate to reopen this issue.

Thanks for reporting this!

@dw dw closed this Jan 22, 2019
dw added a commit that referenced this issue Jan 22, 2019
dw added a commit that referenced this issue Jan 22, 2019
* origin/dmw:
  docs: Changelog typo.
  docs: s/doffers/differs/
  issue #434: add to Changelog.
  issue #401: add to Changelog.
  issue #359: add to Changelog.
  issue #396: add to Changelog.
  docs: add thanks entry
  issue #436: add to Changelog.
  issue #465: add to Changelog.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Linked pull requests

Successfully merging a pull request may close this issue.

None yet
2 participants