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

2.7/Py3.6 controller -> BSD Py2.7 target exceptions #594

Open
dw opened this issue Jun 4, 2019 · 1 comment
Open

2.7/Py3.6 controller -> BSD Py2.7 target exceptions #594

dw opened this issue Jun 4, 2019 · 1 comment

Comments

@dw
Copy link
Member

dw commented Jun 4, 2019

@fdutheil I've opened this so I don't lose track of it. Looks like you're having very different problems to #558, or in addition to it.


Hi, some issues here with the unarchive module too. Depending on the value of the "src" parameter, it shows different things (even if the main exception seems to be the same: ImportError: loader for posixpath cannot handle os.path).
Funny thing, newer ansible archive module is fine.

I use today's master version of mitogen, ansible 2.7.8 and python 3.6.5 on Linux control host. Remote FreeBSD hosts have python 2.7.15.

First simple playbook, with an URL as src:

- name: test mitogen and unarchive
  hosts: jail   
  gather_facts: no
  strategy: mitogen_linear
  tasks:
    - name: Install Victory responsive style
      unarchive:
        src: https://fluxbb.org/resources/styles/victory-responsive-fluxbb-style/releases/1.0.2/victory-responsive-fluxbb-style_v1.0.2.zip
        remote_src: True
        dest: "/tmp"
        creates: "/tmp/Victory"

Shows:

An exception occurred during task execution. To see the full traceback, use -vvv. The error was:     os.rmdir(path)
fatal: [test]: FAILED! => {"msg": "Unexpected failure during module execution.", "stdout": ""}

Whole output:

TASK [Install Victory responsive style] *******************************************************************************************************************************
task path: /home/florent/Documents/Personnel/EGGK/ansible/test.yml:7
[pid 26070] 18:05:02.607107 D ansible_mitogen.mixins: _make_tmp_path(remote_user=None)
[pid 26070] 18:05:02.607564 D ansible_mitogen.mixins: _remote_expand_user('~/.ansible/tmp', sudoable=False)
[pid 26070] 18:05:02.608367 D mitogen: unix.connect(path='/tmp/mitogen_unix_u7u9caqo')
[pid 26070] 18:05:02.609293 D mitogen: unix.connect(): local ID is 1, remote is 0
[pid 26051] 18:05:02.646269 D mitogen: mitogen.ssh.Stream('default').connect()
[pid 26051] 18:05:02.852802 D mitogen: hybrid_tty_create_child() pid=26073 stdio=60, tty=14, cmd: ssh -o "LogLevel ERROR" -l igor -p 2222 -o "IdentitiesOnly yes" -i /home/florent/.ssh/id_ed25519_igor -o "Compression yes" -o "ServerAliveInterval 15" -o "ServerAliveCountMax 3" -o "StrictHostKeyChecking yes" -o ControlMaster=auto -o ControlPersist=120s jail_host /usr/local/bin/python -c "'import codecs,os,sys;_=codecs.decode;exec(_(_(\"eNqFkM1Lw0AQxc/NX5Hb7NKl3cQPMBBQehAPIgSxBy2Sj0ldTHeXzbax/vVOU6FJPXibH/Nm3uNlYpmadmaVRcYDJ7oBqTokqI37ZDwJJjRXWxszKSIp+YkzMSRH2+jIZWNaZNkQ3BCWQ+gIyLDdk32Te3LdhGkaQpW7TmkIc131S/zCcuvzosF+Pd+2bl4oPbd7/2E0UM7JmWya9oc7dK0y+jW5WPW2qHfKEcNddv8iYZWOz44awoaNF2KMU2Ab5c0adVI3xqH2t2W+Ru8xia/lVcSBB/Spc8ojiwQ8Pjw/SSnfNFCC0lRUNA8W6Ts7VF0Zi5oKBlcAnznMKxbFl/ENF/CtLH2qbXrSLQV0BRzar+2vwaKfj42eqbv/1H9TRqOUP9gjrmg=\".encode(),\"base64\"),\"zip\"))'"
[pid 26051] 18:05:02.853901 D mitogen: mitogen.ssh.Stream('local.26073').connect(): child process stdin/stdout=60
[pid 26051] 18:05:04.713104 D mitogen: mitogen.ssh.Stream('local.26073'): received b'MITO000\n'
[pid 26051] 18:05:04.714486 D mitogen: mitogen.ssh.Stream('local.26073')._ec0_received()
[pid 26051] 18:05:04.799972 D mitogen: Context(2, 'ssh.jail_host:2222').call_async(): ansible_mitogen.target.init_child(log_level=10)
[pid 26051] 18:05:04.820536 D mitogen: _build_tuple('/usr/lib64/python3.6/site-packages/ansible/__init__.py', 'ansible') -> ['cli', 'compat', 'config', 'constants', 'errors', 'executor', 'galaxy', 'inventory', 'module_utils', 'modules', 'parsing', 'playbook', 'plugins', 'release', 'template', 'utils', 'vars']
[pid 26051] 18:05:04.830137 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible.release')
[pid 26051] 18:05:04.831366 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible')
[pid 26051] 18:05:04.843271 D mitogen: _build_tuple('/usr/lib64/python3.6/site-packages/ansible/module_utils/__init__.py', 'ansible.module_utils') -> ['_text', 'acme', 'ansible_release', 'ansible_tower', 'api', 'aws', 'azure_rm_common', 'azure_rm_common_rest', 'basic', 'cloud', 'cloudscale', 'cloudstack', 'common', 'compat', 'connection', 'crypto', 'database', 'digital_ocean', 'dimensiondata', 'docker_common', 'ec2', 'exoscale', 'f5_utils', 'facts', 'firewalld', 'gcdns', 'gce', 'gcp', 'gcp_utils', 'gitlab', 'heroku', 'ibm_sa_utils', 'infinibox', 'influxdb', 'ipa', 'ismount', 'json_utils', 'k8s', 'keycloak', 'known_hosts', 'ldap', 'lxd', 'manageiq', 'memset', 'mysql', 'net_tools', 'netapp', 'netapp_elementsw_module', 'netapp_module', 'network', 'oneandone', 'oneview', 'online', 'opennebula', 'openstack', 'ovirt', 'parsing', 'postgres', 'powershell', 'pure', 'pycompat24', 'rax', 'redfish_utils', 'redhat', 'remote_management', 'scaleway', 'service', 'six', 'splitter', 'storage', 'univention_umc', 'urls', 'vca', 'vmware', 'vmware_rest_client', 'vultr', 'yumdnf']
[pid 26051] 18:05:04.843938 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible.module_utils')
[pid 26051] 18:05:04.891689 D mitogen: _get_module_via_sys_modules('syslog') -> <module 'syslog' from '/usr/lib64/python3.6/lib-dynload/syslog.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:04.891968 D mitogen: get_module_source('syslog'): cannot find source
[pid 26051] 18:05:04.928324 D mitogen: _get_module_via_sys_modules('grp') -> <module 'grp' from '/usr/lib64/python3.6/lib-dynload/grp.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:04.928576 D mitogen: get_module_source('grp'): cannot find source
[pid 26051] 18:05:04.953873 D mitogen: _get_module_via_sys_modules('select') -> <module 'select' from '/usr/lib64/python3.6/lib-dynload/select.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:04.954205 D mitogen: get_module_source('select'): cannot find source
[pid 26051] 18:05:04.967513 D mitogen: ModuleFinder()._get_module_via_pkgutil('__main__'): Error while finding loader for '__main__' (<class 'ValueError'>: __main__.__spec__ is None)
[pid 26051] 18:05:04.967709 D mitogen: _get_module_via_sys_modules('__main__') -> <module '__main__' from '/usr/lib/python-exec/python3.6/ansible-playbook'>
[pid 26051] 18:05:04.982459 D mitogen: _get_module_via_sys_modules('zlib') -> <module 'zlib' from '/usr/lib64/python3.6/lib-dynload/zlib.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:04.982798 D mitogen: get_module_source('zlib'): cannot find source
[pid 26051] 18:05:04.997274 D mitogen: While importing 'ansible.module_utils.basic'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 26051] 18:05:05.010355 D mitogen: _get_module_via_sys_modules('_json') -> <module '_json' from '/usr/lib64/python3.6/lib-dynload/_json.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:05.010737 D mitogen: get_module_source('_json'): cannot find source
[pid 26051] 18:05:05.041312 D mitogen: _get_module_via_sys_modules('_heapq') -> <module '_heapq' from '/usr/lib64/python3.6/lib-dynload/_heapq.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:05.041607 D mitogen: get_module_source('_heapq'): cannot find source
[pid 26051] 18:05:05.041915 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible.module_utils.json_utils')
[pid 26051] 18:05:05.042938 D mitogen: _build_tuple('/home/florent/homeext/GIT/mitogen/ansible_mitogen/__init__.py', 'ansible_mitogen') -> ['connection', 'loaders', 'logging', 'mixins', 'module_finder', 'parsing', 'planner', 'plugins', 'process', 'runner', 'services', 'strategy', 'target']
[pid 26051] 18:05:05.043231 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible_mitogen')
[pid 26051] 18:05:05.084234 D mitogen: While importing 'ansible_mitogen.runner'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
AttributeError: type object 'FrozenImporter' has no attribute 'get_filename'

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 26051] 18:05:05.108730 D mitogen: _get_module_via_sys_modules('fcntl') -> <module 'fcntl' from '/usr/lib64/python3.6/lib-dynload/fcntl.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:05.108996 D mitogen: get_module_source('fcntl'): cannot find source
[pid 26051] 18:05:05.130955 D mitogen: While importing 'ansible_mitogen.target'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 26051] 18:05:05.132551 D mitogen: _build_tuple('/home/florent/homeext/GIT/mitogen/mitogen/__init__.py', 'mitogen') -> ['compat', 'core', 'debug', 'doas', 'docker', 'fakessh', 'fork', 'jail', 'lxc', 'lxd', 'master', 'minify', 'parent', 'select', 'service', 'setns', 'ssh', 'su', 'sudo', 'unix', 'utils']
[pid 26051] 18:05:05.133457 D mitogen: _get_module_via_sys_modules('_random') -> <module '_random' from '/usr/lib64/python3.6/lib-dynload/_random.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:05.133720 D mitogen: get_module_source('_random'): cannot find source
[pid 26051] 18:05:05.134627 D mitogen: _get_module_via_sys_modules('math') -> <module 'math' from '/usr/lib64/python3.6/lib-dynload/math.cpython-36m-x86_64-linux-gnu.so'>
[pid 26051] 18:05:05.134898 D mitogen: get_module_source('math'): cannot find source
[pid 26051] 18:05:05.135175 D mitogen: While importing 'mitogen.fork'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 26051] 18:05:05.145652 D mitogen: While importing 'mitogen.service'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 26051] 18:05:05.206946 D mitogen.ctx.ssh.jail_host:2222: mitogen: Importer(): 'encodings.utf_8' is submodule of a package we did not load

[WARNING]: 
[pid 26051] 18:05:05.207375 W mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-0'): call error: Message(0, 1, 0, 110, 1000, b"\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x00X\x03"..757): exceptions.ImportError: Master does not have u'ansible_mitogen.target'
  File "<stdin>", line 2051, in _dispatch_calls
  File "<stdin>", line 2035, in _dispatch_one
  File "<stdin>", line 368, in import_module
  File "<stdin>", line 793, in load_module

[pid 26070] 18:05:05.210769 D mitogen: mitogen.core.Stream('unix_listener.26051').on_disconnect()
[pid 26070] 18:05:05.211491 D mitogen: Waker(Broker(0x7f003d986da0) rfd=11, wfd=12).on_disconnect()
[pid 26051] 18:05:05.211501 D mitogen: mitogen.core.Stream('unix_client.26070').on_disconnect()
The full traceback is:
Traceback (most recent call last):
  File "/usr/lib64/python3.6/site-packages/ansible/executor/task_executor.py", line 140, in run
    res = self._execute()
  File "/usr/lib64/python3.6/site-packages/ansible/executor/task_executor.py", line 612, in _execute
    result = self._handler.run(task_vars=variables)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 115, in run
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/usr/lib64/python3.6/site-packages/ansible/plugins/action/unarchive.py", line 38, in run
    result = super(ActionModule, self).run(tmp, task_vars)
  File "/usr/lib64/python3.6/site-packages/ansible/plugins/action/__init__.py", line 110, in run
    self._make_tmp_path()
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 206, in _make_tmp_path
    base_dir=self._get_remote_tmp(),
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 191, in _get_remote_tmp
    return self._remote_expand_user(s, sudoable=False)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 301, in _remote_expand_user
    return os.path.join(self._connection.homedir, path[2:])
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/connection.py", line 496, in homedir
    self._connect()
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/connection.py", line 634, in _connect
    self._connect_stack(stack)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/connection.py", line 601, in _connect_stack
    stack=mitogen.utils.cast(list(stack)),
  File "/home/florent/homeext/GIT/mitogen/mitogen/core.py", line 1128, in call_service
    return recv.get().unpickle()
  File "/home/florent/homeext/GIT/mitogen/mitogen/core.py", line 488, in unpickle
    raise obj
mitogen.core.CallError: exceptions.ImportError: Master does not have u'ansible_mitogen.target'
  File "<stdin>", line 2051, in _dispatch_calls
  File "<stdin>", line 2035, in _dispatch_one
  File "<stdin>", line 368, in import_module
  File "<stdin>", line 793, in load_module


fatal: [jail]: FAILED! => {
    "msg": "Unexpected failure during module execution.",
    "stdout": ""
}
	to retry, use: --limit @/home/florent/.ansible/retry/jail.retry

PLAY RECAP ************************************************************************************************************************************************************
jail                       : ok=0    changed=0    unreachable=0    failed=1   

[pid 26051] 18:05:05.224260 D mitogen: Waker(Broker(0x7f003d95f550) rfd=7, wfd=9).on_disconnect()
[pid 26051] 18:05:05.225158 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-1'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.225479 D mitogen: <mitogen.unix.Listener object at 0x7f003d95f940>.on_disconnect()
[pid 26051] 18:05:05.226368 D mitogen: mitogen.parent.TtyLogStream('ssh.jail_host:2222').on_disconnect()
[pid 26051] 18:05:05.227614 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-2'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.228693 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-3'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.228882 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222') closing CALL_FUNCTION channel
[pid 26051] 18:05:05.233352 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-5'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.234968 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-6'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.242424 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-7'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.243048 D mitogen: mitogen.parent.TtyLogStream('ssh.jail_host:2222').on_disconnect()
[pid 26051] 18:05:05.243452 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-8'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.249385 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-10'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.250293 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222'): child process exit status was 0
[pid 26051] 18:05:05.252026 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-4'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.260380 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222').on_disconnect()
[pid 26051] 18:05:05.260588 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-11'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.274406 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-12'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.283806 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-9'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.286165 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-13'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.286260 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222') is gone; propagating DEL_ROUTE for {2}
[pid 26051] 18:05:05.292554 I mitogen: mitogen.service.Pool(0x7f003d95fc50, size=16, th='mitogen.service.Pool.7f003d95fc50.worker-14'): channel or latch closed, exitting: None
[pid 26051] 18:05:05.304238 D mitogen: Router(Broker(0x7f003d95f550)).del_route(2)

Second simple playbook with a "local" file on the remote machine:

- name: test mitogen and unarchive
  hosts: jail   
  gather_facts: no
  strategy: mitogen_linear
  tasks:
    - name: Install Victory responsive style
      unarchive:
        src: /root/victory-responsive-fluxbb-style_v1.0.2.zip
        remote_src: True
        dest: "/tmp"
        creates: "/tmp/Victory"

Shows:
fatal: [test]: FAILED! => {"changed": false, "cmd": "-T -s /root/victory-responsive-fluxbb-style_v1.0.2.zip", "msg": "[Errno 2] No such file or directory", "rc": 2}

Whole output:

TASK [Install Victory responsive style] *******************************************************************************************************************************
task path: /home/florent/Documents/Personnel/EGGK/ansible/test.yml:7
[pid 30569] 18:43:20.801085 D ansible_mitogen.mixins: _make_tmp_path(remote_user=None)
[pid 30569] 18:43:20.801513 D ansible_mitogen.mixins: _remote_expand_user('~/.ansible/tmp', sudoable=False)
[pid 30569] 18:43:20.802370 D mitogen: unix.connect(path='/tmp/mitogen_unix_85otzpbg')
[pid 30569] 18:43:20.803328 D mitogen: unix.connect(): local ID is 1, remote is 0
[pid 30550] 18:43:20.839781 D mitogen: mitogen.ssh.Stream('default').connect()
[pid 30550] 18:43:21.054789 D mitogen: hybrid_tty_create_child() pid=30577 stdio=60, tty=14, cmd: ssh -o "LogLevel ERROR" -l igor -p 2222 -o "IdentitiesOnly yes" -i /home/florent/.ssh/id_ed25519_igor -o "Compression yes" -o "ServerAliveInterval 15" -o "ServerAliveCountMax 3" -o "StrictHostKeyChecking yes" -o ControlMaster=auto -o ControlPersist=120s jail_host /usr/local/bin/python -c "'import codecs,os,sys;_=codecs.decode;exec(_(_(\"eNqFkE9rwkAQxc/mU+Q2u7joRuuhgUCLh9JDKYRSD62U/JnYpXF32aym9tN3jIKJPfQ2P+bNvMdLxSoxzcQqi4wHTrQ9UlVIUBn3xXgcjGgud3bGpIik5BdORZ8cbaMTF7VpkKV9cH1Y9aElIMPmQPZ15sl1GyZJCGXmWqUhzHTZLfEbi53P8hq79XTXuGmu9NQe/KfRQDlHV7Jx0h3u0TXK6Ld4vu5sUe+VI4b79OFVwjoZnp00hDUbLsQQx8C2ypsN6riqjUPt74psg95jPJeLheTAA/rUOuWRRQKeHl+epZTvGihBYUoqmgfL5IMdqy6NRU0Fg8uBTxxmJYtmN7NbLuBHWfpU2eSiWwloczi2X9mzwbKbT41eqdv/1H9TRoOUv9dUrmc=\".encode(),\"base64\"),\"zip\"))'"
[pid 30550] 18:43:21.055955 D mitogen: mitogen.ssh.Stream('local.30577').connect(): child process stdin/stdout=60
[pid 30550] 18:43:23.054918 D mitogen: mitogen.ssh.Stream('local.30577'): received b'MITO000\n'
[pid 30550] 18:43:23.056284 D mitogen: mitogen.ssh.Stream('local.30577')._ec0_received()
[pid 30550] 18:43:23.122663 D mitogen: Context(2, 'ssh.jail_host:2222').call_async(): ansible_mitogen.target.init_child(log_level=10)
[pid 30550] 18:43:23.132448 D mitogen: _build_tuple('/usr/lib64/python3.6/site-packages/ansible/__init__.py', 'ansible') -> ['cli', 'compat', 'config', 'constants', 'errors', 'executor', 'galaxy', 'inventory', 'module_utils', 'modules', 'parsing', 'playbook', 'plugins', 'release', 'template', 'utils', 'vars']
[pid 30550] 18:43:23.137204 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible.release')
[pid 30550] 18:43:23.138383 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible')
[pid 30550] 18:43:23.151467 D mitogen: _build_tuple('/usr/lib64/python3.6/site-packages/ansible/module_utils/__init__.py', 'ansible.module_utils') -> ['_text', 'acme', 'ansible_release', 'ansible_tower', 'api', 'aws', 'azure_rm_common', 'azure_rm_common_rest', 'basic', 'cloud', 'cloudscale', 'cloudstack', 'common', 'compat', 'connection', 'crypto', 'database', 'digital_ocean', 'dimensiondata', 'docker_common', 'ec2', 'exoscale', 'f5_utils', 'facts', 'firewalld', 'gcdns', 'gce', 'gcp', 'gcp_utils', 'gitlab', 'heroku', 'ibm_sa_utils', 'infinibox', 'influxdb', 'ipa', 'ismount', 'json_utils', 'k8s', 'keycloak', 'known_hosts', 'ldap', 'lxd', 'manageiq', 'memset', 'mysql', 'net_tools', 'netapp', 'netapp_elementsw_module', 'netapp_module', 'network', 'oneandone', 'oneview', 'online', 'opennebula', 'openstack', 'ovirt', 'parsing', 'postgres', 'powershell', 'pure', 'pycompat24', 'rax', 'redfish_utils', 'redhat', 'remote_management', 'scaleway', 'service', 'six', 'splitter', 'storage', 'univention_umc', 'urls', 'vca', 'vmware', 'vmware_rest_client', 'vultr', 'yumdnf']
[pid 30550] 18:43:23.152255 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible.module_utils')
[pid 30550] 18:43:23.207955 D mitogen: ModuleFinder()._get_module_via_pkgutil('__main__'): Error while finding loader for '__main__' (<class 'ValueError'>: __main__.__spec__ is None)
[pid 30550] 18:43:23.208146 D mitogen: _get_module_via_sys_modules('__main__') -> <module '__main__' from '/usr/lib/python-exec/python3.6/ansible-playbook'>
[pid 30550] 18:43:23.227573 D mitogen: _get_module_via_sys_modules('syslog') -> <module 'syslog' from '/usr/lib64/python3.6/lib-dynload/syslog.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.227904 D mitogen: get_module_source('syslog'): cannot find source
[pid 30550] 18:43:23.262134 D mitogen: _get_module_via_sys_modules('select') -> <module 'select' from '/usr/lib64/python3.6/lib-dynload/select.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.262429 D mitogen: get_module_source('select'): cannot find source
[pid 30550] 18:43:23.282053 D mitogen: _get_module_via_sys_modules('grp') -> <module 'grp' from '/usr/lib64/python3.6/lib-dynload/grp.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.282401 D mitogen: get_module_source('grp'): cannot find source
[pid 30550] 18:43:23.293630 D mitogen: _get_module_via_sys_modules('_posixsubprocess') -> <module '_posixsubprocess' from '/usr/lib64/python3.6/lib-dynload/_posixsubprocess.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.293998 D mitogen: get_module_source('_posixsubprocess'): cannot find source
[pid 30550] 18:43:23.340703 D mitogen: _get_module_via_sys_modules('fcntl') -> <module 'fcntl' from '/usr/lib64/python3.6/lib-dynload/fcntl.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.341088 D mitogen: get_module_source('fcntl'): cannot find source
[pid 30550] 18:43:23.351068 D mitogen: _get_module_via_sys_modules('zlib') -> <module 'zlib' from '/usr/lib64/python3.6/lib-dynload/zlib.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.351775 D mitogen: get_module_source('zlib'): cannot find source
[pid 30550] 18:43:23.369439 D mitogen: _get_module_via_sys_modules('math') -> <module 'math' from '/usr/lib64/python3.6/lib-dynload/math.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.370405 D mitogen: get_module_source('math'): cannot find source
[pid 30550] 18:43:23.371027 D mitogen: _get_module_via_sys_modules('_datetime') -> <module '_datetime' from '/usr/lib64/python3.6/lib-dynload/_datetime.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.371421 D mitogen: get_module_source('_datetime'): cannot find source
[pid 30550] 18:43:23.418808 D mitogen: While importing 'ansible.module_utils.basic'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 30550] 18:43:23.427452 D mitogen: _get_module_via_sys_modules('_json') -> <module '_json' from '/usr/lib64/python3.6/lib-dynload/_json.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.427768 D mitogen: get_module_source('_json'): cannot find source
[pid 30550] 18:43:23.431434 D mitogen: _get_module_via_sys_modules('_heapq') -> <module '_heapq' from '/usr/lib64/python3.6/lib-dynload/_heapq.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.431836 D mitogen: get_module_source('_heapq'): cannot find source
[pid 30550] 18:43:23.432398 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible.module_utils.json_utils')
[pid 30550] 18:43:23.433957 D mitogen: _build_tuple('/home/florent/homeext/GIT/mitogen/ansible_mitogen/__init__.py', 'ansible_mitogen') -> ['connection', 'loaders', 'logging', 'mixins', 'module_finder', 'parsing', 'planner', 'plugins', 'process', 'runner', 'services', 'strategy', 'target']
[pid 30550] 18:43:23.434368 D mitogen: _send_load_module(mitogen.ssh.Stream('ssh.jail_host:2222'), 'ansible_mitogen')
[pid 30550] 18:43:23.494873 D mitogen: While importing 'ansible_mitogen.runner'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 30550] 18:43:23.565606 D mitogen: _get_module_via_sys_modules('termios') -> <module 'termios' from '/usr/lib64/python3.6/lib-dynload/termios.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.566139 D mitogen: get_module_source('termios'): cannot find source
[pid 30550] 18:43:23.575393 D mitogen: While importing 'ansible_mitogen.target'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 30550] 18:43:23.577464 D mitogen: _build_tuple('/home/florent/homeext/GIT/mitogen/mitogen/__init__.py', 'mitogen') -> ['compat', 'core', 'debug', 'doas', 'docker', 'fakessh', 'fork', 'jail', 'lxc', 'lxd', 'master', 'minify', 'parent', 'select', 'service', 'setns', 'ssh', 'su', 'sudo', 'unix', 'utils']
[pid 30550] 18:43:23.578655 D mitogen: _get_module_via_sys_modules('_random') -> <module '_random' from '/usr/lib64/python3.6/lib-dynload/_random.cpython-36m-x86_64-linux-gnu.so'>
[pid 30550] 18:43:23.579207 D mitogen: get_module_source('_random'): cannot find source
[pid 30550] 18:43:23.581176 D mitogen: While importing 'mitogen.fork'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 30550] 18:43:23.587565 D mitogen: While importing 'mitogen.service'
Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 350, in _get_module_via_pkgutil
    path = self._py_filename(loader.get_filename(fullname))
  File "<frozen importlib._bootstrap_external>", line 398, in _check_name_wrapper
ImportError: loader for posixpath cannot handle os.path

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 629, in _send_module_and_related
    tup = self._build_tuple(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 592, in _build_tuple
    for name in self._finder.find_related(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 521, in find_related
    names = self.find_related_imports(name)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 472, in find_related_imports
    modpath, src, _ = self.get_module_source(fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 423, in get_module_source
    tup = method(self, fullname)
  File "/home/florent/homeext/GIT/mitogen/mitogen/master.py", line 363, in _get_module_via_pkgutil
    self, fullname, loader)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1294, in debug
    self._log(DEBUG, msg, args, **kwargs)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1442, in _log
    self.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1452, in handle
    self.callHandlers(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 1514, in callHandlers
    hdlr.handle(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 863, in handle
    self.emit(record)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/logging.py", line 68, in emit
    s = '[pid %d] %s' % (os.getpid(), self.format(record))
  File "/usr/lib64/python3.6/logging/__init__.py", line 838, in format
    return fmt.format(record)
  File "/usr/lib64/python3.6/logging/__init__.py", line 575, in format
    record.message = record.getMessage()
  File "/usr/lib64/python3.6/logging/__init__.py", line 338, in getMessage
    msg = msg % self.args
TypeError: not enough arguments for format string
[pid 30550] 18:43:23.629215 D mitogen.ctx.ssh.jail_host:2222: mitogen: Importer(): 'encodings.utf_8' is submodule of a package we did not load

[WARNING]: 
[pid 30550] 18:43:23.629836 W mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-0'): call error: Message(0, 1, 0, 110, 1000, b"\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x00X\x03"..757): exceptions.ImportError: Master does not have u'ansible_mitogen.target'
  File "<stdin>", line 2051, in _dispatch_calls
  File "<stdin>", line 2035, in _dispatch_one
  File "<stdin>", line 368, in import_module
  File "<stdin>", line 793, in load_module

[pid 30569] 18:43:23.634114 D mitogen: mitogen.core.Stream('unix_listener.30550').on_disconnect()
[pid 30550] 18:43:23.635384 D mitogen: mitogen.core.Stream('unix_client.30569').on_disconnect()
[pid 30569] 18:43:23.635409 D mitogen: Waker(Broker(0x7f9c6769ada0) rfd=11, wfd=12).on_disconnect()
The full traceback is:
Traceback (most recent call last):
  File "/usr/lib64/python3.6/site-packages/ansible/executor/task_executor.py", line 140, in run
    res = self._execute()
  File "/usr/lib64/python3.6/site-packages/ansible/executor/task_executor.py", line 612, in _execute
    result = self._handler.run(task_vars=variables)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 115, in run
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/usr/lib64/python3.6/site-packages/ansible/plugins/action/unarchive.py", line 38, in run
    result = super(ActionModule, self).run(tmp, task_vars)
  File "/usr/lib64/python3.6/site-packages/ansible/plugins/action/__init__.py", line 110, in run
    self._make_tmp_path()
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 206, in _make_tmp_path
    base_dir=self._get_remote_tmp(),
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 191, in _get_remote_tmp
    return self._remote_expand_user(s, sudoable=False)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/mixins.py", line 301, in _remote_expand_user
    return os.path.join(self._connection.homedir, path[2:])
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/connection.py", line 496, in homedir
    self._connect()
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/connection.py", line 634, in _connect
    self._connect_stack(stack)
  File "/home/florent/homeext/GIT/mitogen/ansible_mitogen/connection.py", line 601, in _connect_stack
    stack=mitogen.utils.cast(list(stack)),
  File "/home/florent/homeext/GIT/mitogen/mitogen/core.py", line 1128, in call_service
    return recv.get().unpickle()
  File "/home/florent/homeext/GIT/mitogen/mitogen/core.py", line 488, in unpickle
    raise obj
mitogen.core.CallError: exceptions.ImportError: Master does not have u'ansible_mitogen.target'
  File "<stdin>", line 2051, in _dispatch_calls
  File "<stdin>", line 2035, in _dispatch_one
  File "<stdin>", line 368, in import_module
  File "<stdin>", line 793, in load_module


fatal: [jail]: FAILED! => {
    "msg": "Unexpected failure during module execution.",
    "stdout": ""
}
	to retry, use: --limit @/home/florent/.ansible/retry/jail.retry

PLAY RECAP ************************************************************************************************************************************************************
jail                       : ok=0    changed=0    unreachable=0    failed=1   

[pid 30550] 18:43:23.651701 D mitogen: Waker(Broker(0x7f9c67673588) rfd=7, wfd=9).on_disconnect()
[pid 30550] 18:43:23.653392 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-7'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.653808 D mitogen: <mitogen.unix.Listener object at 0x7f9c67673978>.on_disconnect()
[pid 30550] 18:43:23.654761 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-2'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.654958 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-3'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.655086 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-5'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.656460 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-6'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.657828 D mitogen: mitogen.parent.TtyLogStream('ssh.jail_host:2222').on_disconnect()
[pid 30550] 18:43:23.660159 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-8'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.672795 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-1'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.673091 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222') closing CALL_FUNCTION channel
[pid 30550] 18:43:23.687922 D mitogen: mitogen.parent.TtyLogStream('ssh.jail_host:2222').on_disconnect()
[pid 30550] 18:43:23.698131 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-11'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.702152 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-12'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.703427 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222'): child process exit status was 0
[pid 30550] 18:43:23.706019 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222').on_disconnect()
[pid 30550] 18:43:23.706298 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-15'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.711281 D mitogen: mitogen.ssh.Stream('ssh.jail_host:2222') is gone; propagating DEL_ROUTE for {2}
[pid 30550] 18:43:23.715575 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-9'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.715919 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-10'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.716927 I mitogen: mitogen.service.Pool(0x7f9c67673c88, size=16, th='mitogen.service.Pool.7f9c67673c88.worker-13'): channel or latch closed, exitting: None
[pid 30550] 18:43:23.717058 D mitogen: Router(Broker(0x7f9c67673588)).del_route(2)

I know it's a lot of text output, sorry for that. Tell me if I can do some more useful tests.

Regards,

Originally posted by @fdutheil in #558 (comment)

@dw
Copy link
Member Author

dw commented Aug 18, 2019

Hi @fdutheil, can you please confirm if you are still experiencing this crash on latest master? I tried at length to reproduce it last week, to no avail.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant