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

unarchive moduled #558

Closed
dbiegunski opened this issue Mar 6, 2019 · 6 comments

Comments

@dbiegunski
Copy link

commented Mar 6, 2019

Python 2.7.5
Ansible 2.3

ansible unarchive module throwing an error

[task 9638] 13:50:03.153553 D ansible_mitogen.mixins: _make_tmp_path(remote_user=None)
[task 9638] 13:50:03.155617 D mitogen: unix.connect(path='/tmp/mitogen_unix_IZrjzU.sock')
[task 9638] 13:50:03.156457 D mitogen: unix.connect(): local ID is 2004, remote is 0
[mux  9585] 13:50:03.156422 D mitogen: mitogen.unix.Listener('/tmp/mitogen_unix_IZrjzU.sock'): accepted mitogen.core.Stream('unix_client.9638')
[mux  9585] 13:50:03.156990 D mitogen: register(Context(2004, None), mitogen.core.Stream('unix_client.9638'))
[task 9638] 13:50:03.157133 D mitogen: register(Context(0, None), mitogen.core.Stream('unix_listener.9585'))
[task 9638] 13:50:03.158982 D mitogen: Context(0, None).call_service_async(u'ansible_mitogen.services.ContextService', u'get', {'stack': [{u'method': u'ssh', u'kwargs': {u'username': None, u'check_host_keys': u'ignore', u'compression': True, u'ssh_debug_level': None, u'hostname': u'10.212.225.84', u'identities_only': False, u'identity_file': None, u'connect_timeout': 30, u'python_path': [u'/usr/bin/python'], u'ssh_path': u'ssh', u'password': None, u'port': None, u'ssh_args': [u'-C', u'-o', u'ControlMaster=auto', u'-o', u'ControlPersist=600s']}}, {u'enable_lru': True, u'method': u'sudo', u'kwargs': {u'username': u'root', u'sudo_path': None, u'python_path': [u'/usr/bin/python'], u'password': None, u'sudo_args': [u'-H', u'-S'], u'connect_timeout': 30}}]})
[task 9638] 13:50:03.159680 D mitogen: Context(0, None).send_async(Message(0, 2004, 2004, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..775))
[task 9638] 13:50:03.161758 D ansible_mitogen.mixins: Temporary directory: u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9'
[task 9638] 13:50:03.162101 D mitogen: CallChain(Context(4, u'ssh.10.212.225.84.sudo.root')).call_no_reply(): posix.mkdir(u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9')
[task 9638] 13:50:03.162662 D ansible_mitogen.mixins: _remote_file_exists(u'/opt/DAS/java/jre1.7.0_51')
[task 9638] 13:50:03.162998 D mitogen: CallChain(Context(4, u'ssh.10.212.225.84.sudo.root')).call_async(): ansible_mitogen.target.file_exists(u'/opt/DAS/java/jre1.7.0_51')
[task 9638] 13:50:03.163480 D mitogen: Context(4, u'ssh.10.212.225.84.sudo.root').send_async(Message(4, 2004, 2004, 101, 1001, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..184))
[mux  9585] 13:50:03.169641 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_one(('pdesjans001.gestion.desjardins.com-9638-7f11d9f13740-5837175208460', u'posix', None, u'mkdir', (u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9',), Kwargs({})))
[mux  9585] 13:50:03.170084 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_calls: Message(4, 2004, 0, 101, 0, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..194) -> None
[mux  9585] 13:50:03.170442 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_one(('pdesjans001.gestion.desjardins.com-9638-7f11d9f13740-5837175208460', u'ansible_mitogen.target', None, u'file_exists', (u'/opt/DAS/java/jre1.7.0_51',), Kwargs({})))
[mux  9585] 13:50:03.170771 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_calls: Message(4, 2004, 0, 101, 1001, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..184) -> False
[task 9638] 13:50:03.171434 D ansible_mitogen.connection: Call took 8 ms: ansible_mitogen.target.file_exists(u'/opt/DAS/java/jre1.7.0_51')
[task 9638] 13:50:03.171875 D ansible_mitogen.mixins: _remote_expand_user(u'/opt/DAS/java', sudoable=True)
[task 9638] 13:50:03.178572 D ansible_mitogen.planner: BinaryPlanner() rejected u'stat'
[task 9638] 13:50:03.178965 D ansible_mitogen.planner: NewStylePlanner() accepted u'stat' (filename u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py')
[task 9638] 13:50:03.179425 D mitogen: Context(0, None).call_service_async(u'ansible_mitogen.services.ModuleDepService', u'scan', {'module_name': u'ansible_module_stat', 'module_path': u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py', 'search_path': ('/usr/lib/python2.7/site-packages/ansible/module_utils',), 'context': Context(4, u'ssh.10.212.225.84.sudo.root'), 'builtin_path': '/usr/lib/python2.7/site-packages/ansible/executor/../module_utils'})
[task 9638] 13:50:03.179863 D mitogen: Context(0, None).send_async(Message(0, 2004, 2004, 110, 1002, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..467))
[task 9638] 13:50:03.181179 D mitogen: Context(0, None).call_service_async(u'mitogen.service.PushFileService', u'propagate_paths_and_modules', {'paths': [u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py'], 'modules': [u'ansible.module_utils._text', u'ansible.module_utils.basic', u'ansible.module_utils.pycompat24', u'ansible.module_utils.six'], 'context': Context(4, u'ssh.10.212.225.84.sudo.root')})
[task 9638] 13:50:03.181680 D mitogen: Context(0, None).send_async(Message(0, 2004, 2004, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..428))
[mux  9585] 13:50:03.182758 D mitogen: PushFileService().propagate_to(Context(4, u'ssh.10.212.225.84.sudo.root'), u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py')
[mux  9585] 13:50:03.183201 D mitogen: Context(2, None).call_service_async(u'mitogen.service.PushFileService', 'forward', {'path': u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py', 'context': Context(4, u'ssh.10.212.225.84.sudo.root')})
[mux  9585] 13:50:03.183745 D mitogen: Context(2, None).send_async(Message(2, 0, 0, 110, 1005, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x07\x00\x00\x00forwa'..250))
[task 9638] 13:50:03.185347 D mitogen: CallChain(Context(4, u'ssh.10.212.225.84.sudo.root')).call_async(): ansible_mitogen.target.run_module(kwargs={u'module_map': {u'builtin': [u'ansible.module_utils._text', u'ansible.module_utils.basic', u'ansible.module_utils.pycompat24', u'ansible.module_utils.six'], u'custom': []}, u'emulate_tty': True, u'good_temp_dir': u'/root/.ansible/tmp', u'module': u'stat', u'cwd': None, u'service_context': Context(0, None), u'extra_env': None, u'is_python': None, u'env': {}, u'path': u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py', u'runner_name': u'NewStyleRunner', u'interpreter_fragment': None, u'json_args': '{"_ansible_version": "2.3.2.0", "follow": true, "_ansible_selinux_special_fs": ["fuse", "nfs", "vboxsf", "ramfs", "9p"], "_ansible_no_log": false, "_ansible_module_name": "stat", "_ansible_verbosity": 3, "_ansible_syslog_facility": "LOG_USER", "_ansible_socket": null, "_ansible_diff": false, "get_checksum": true, "_ansible_debug": false, "checksum_algo": "sha1", "_ansible_check_mode": false, "get_md5": false, "path": "/opt/DAS/java"}'})
[task 9638] 13:50:03.185924 D mitogen: Context(4, u'ssh.10.212.225.84.sudo.root').send_async(Message(4, 2004, 2004, 101, 1004, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..1151))
[mux  9585] 13:50:03.189258 D mitogen.ctx.ssh.10.212.225.84: mitogen: PushFileService().forward(u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py', Context(4, u'sudo.root'))
[mux  9585] 13:50:03.191254 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_one(('pdesjans001.gestion.desjardins.com-9638-7f11d9f13740-5837175208460', u'ansible_mitogen.target', None, u'run_module', (), Kwargs({'kwargs': {u'module_map': {u'builtin': [u'ansible.module_utils._text', u'ansible.module_utils.basic', u'ansible.module_utils.pycompat24', u'ansible.module_utils.six'], u'custom': []}, u'emulate_tty': True, u'good_temp_dir': u'/root/.ansible/tmp', u'module': u'stat', u'interpreter_fragment': None, u'service_context': Context(0, None), u'extra_env': None, u'is_python': None, u'env': {}, u'path': u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py', u'json_args': '{"_ansible_version": "2.3.2.0", "follow": true, "_ansible_selinux_special_fs": ["fuse", "nfs", "vboxsf", "ramfs", "9p"], "_ansible_no_log": false, "_ansible_module_name": "stat", "_ansible_verbosity": 3, "_ansible_syslog_facility": "LOG_USER", "_ansible_socket": null, "_ansible_diff": false, "get_checksum": true, "_ansible_debug": false, "checksum_algo": "sha1", "_ansible_check_mode": false, "get_md5": false, "path": "/opt/DAS/java"}', u'cwd': None, u'runner_name': u'NewStyleRunner'}})))
[mux  9585] 13:50:03.218197 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_calls: Message(4, 2004, 0, 101, 1004, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..1151) -> {u'stdout': u'\n{"invocation": {"module_args": {"checksum_algorithm": "sha1", "get_checksum": true, "path": "/opt/DAS/java", "checksum_algo": "sha1", "follow": true, "get_md5": false, "get_mime": true, "get_attributes": true}}, "stat": {"charset": "binary", "uid": 0, "exists": true, "attr_flags": "", "woth": false, "isreg": false, "device_type": 0, "mtime": 1551896759.3276913, "block_size": 4096, "inode": 4194368, "isgid": false, "size": 6, "executable": true, "isuid": false, "readable": true, "version": "439973676", "pw_name": "root", "gid": 0, "ischr": false, "wusr": true, "writeable": true, "mimetype": "inode/directory", "blocks": 0, "xoth": true, "islnk": false, "nlink": 2, "issock": false, "rgrp": true, "gr_name": "root", "path": "/opt/DAS/java", "xusr": true, "atime": 1551896759.3276913, "isdir": true, "ctime": 1551896759.3286905, "isblk": false, "wgrp": false, "xgrp": true, "dev": 64778, "roth": true, "isfifo": false, "mode": "0755", "rusr": true, "attributes": []}, "changed": false}\n', u'stderr': u'', u'rc': 0}
[task 9638] 13:50:03.219096 D ansible_mitogen.connection: Call took 33 ms: ansible_mitogen.target.run_module(kwargs={u'module_map': {u'builtin': [u'ansible.module_utils._text', u'ansible.module_utils.basic', u'ansible.module_utils.pycompat24', u'ansible.module_utils.six'], u'custom': []}, u'emulate_tty': True, u'good_temp_dir': u'/root/.ansible/tmp', u'module': u'stat', u'cwd': None, u'service_context': Context(0, None), u'extra_env': None, u'is_python': None, u'env': {}, u'path': u'/usr/lib/python2.7/site-packages/ansible/modules/files/stat.py', u'runner_name': u'NewStyleRunner', u'interpreter_fragment': None, u'json_args': '{"_ansible_version": "2.3.2.0", "follow": true, "_ansible_selinux_special_fs": ["fuse", "nfs", "vboxsf", "ramfs", "9p"], "_ansible_no_log": false, "_ansible_module_name": "stat", "_ansible_verbosity": 3, "_ansible_syslog_facility": "LOG_USER", "_ansible_socket": null, "_ansible_diff": false, "get_checksum": true, "_ansible_debug": false, "checksum_algo": "sha1", "_ansible_check_mode": false, "get_md5": false, "path": "/opt/DAS/java"}'})
[task 9638] 13:50:03.219953 D ansible_mitogen.mixins: _remove_tmp_path(u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9')
[task 9638] 13:50:03.220434 D mitogen: CallChain(Context(4, u'ssh.10.212.225.84.sudo.root')).call_no_reply(): ansible_mitogen.target.prune_tree(u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9')
[task 9638] 13:50:03.221381 D mitogen: Context(0, None).call_service_async(u'mitogen.service.FileService', u'register', {'path': u'/mnt-ansible-on-psgmswww001/UNIX/depot/lemss/jre-7u51-linux-x64.gz'})
[task 9638] 13:50:03.222143 D mitogen: Context(0, None).send_async(Message(0, 2004, 2004, 110, 1005, '\x80\x02X\x1b\x00\x00\x00mitogen.service.FileServiceq\x01X\x08\x00\x00\x00registerq'..167))
[mux  9585] 13:50:03.223352 D mitogen: FileService(): registering u'/mnt-ansible-on-psgmswww001/UNIX/depot/lemss/jre-7u51-linux-x64.gz'
[task 9638] 13:50:03.224256 D mitogen: CallChain(Context(4, u'ssh.10.212.225.84.sudo.root')).call_async(): ansible_mitogen.target.transfer_file(in_path=u'/mnt-ansible-on-psgmswww001/UNIX/depot/lemss/jre-7u51-linux-x64.gz', context=Context(0, None), out_path=u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9/source')
[task 9638] 13:50:03.224811 D mitogen: Context(4, u'ssh.10.212.225.84.sudo.root').send_async(Message(4, 2004, 2004, 101, 1006, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..376))
[mux  9585] 13:50:03.226908 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_one(('pdesjans001.gestion.desjardins.com-9638-7f11d9f13740-5837175208460', u'ansible_mitogen.target', None, u'prune_tree', (u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9',), Kwargs({})))
[mux  9585] 13:50:03.227504 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_calls: Message(4, 2004, 0, 101, 0, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..216) -> None
[mux  9585] 13:50:03.229655 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_one(('pdesjans001.gestion.desjardins.com-9638-7f11d9f13740-5837175208460', u'ansible_mitogen.target', None, u'transfer_file', (), Kwargs({'in_path': u'/mnt-ansible-on-psgmswww001/UNIX/depot/lemss/jre-7u51-linux-x64.gz', 'out_path': u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9/source', 'context': Context(0, None)})))
[mux  9585] 13:50:03.232200 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_calls: Message(4, 2004, 0, 101, 1006, '\x80\x02(UBpdesjans001.gestion.desjardins.com-9638-7f11d'..376) -> CallError(u'exceptions.OSError: [Errno 2] No such file or directory: \'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9/.ansible_mitogen_transfer-vjCTKN.tmp\'\n  File "<stdin>", line 3107, in _dispatch_one\n  File "master:/usr/share/ansible_mitogen/target.py", line 198, in transfer_file\n    dir=os.path.dirname(out_path))\n  File "/usr/lib64/python2.7/tempfile.py", line 304, in mkstemp\n    return _mkstemp_inner(dir, prefix, suffix, flags)\n  File "/usr/lib64/python2.7/tempfile.py", line 239, in _mkstemp_inner\n    fd = _os.open(file, flags, 0600)\n',)
[task 9638] 13:50:03.232853 D ansible_mitogen.connection: Call took 8 ms: ansible_mitogen.target.transfer_file(in_path=u'/mnt-ansible-on-psgmswww001/UNIX/depot/lemss/jre-7u51-linux-x64.gz', out_path=u'/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9/source', context=Context(0, None))
[task 9638] 13:50:03.236267 D mitogen: CallChain(Context(4, u'ssh.10.212.225.84.sudo.root')).call_no_reply(): mitogen.core.Dispatcher.forget_chain('pdesjans001.gestion.desjardins.com-9638-7f11d9f13740-5837175208460')
[task 9638] 13:50:03.236813 D mitogen: Context(0, None).call_service_async(u'ansible_mitogen.services.ContextService', u'put', {'context': Context(4, u'ssh.10.212.225.84.sudo.root')})
[task 9638] 13:50:03.237238 D mitogen: Context(0, None).send_async(Message(0, 2004, 2004, 110, 1007, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..178))
[mux  9585] 13:50:03.238172 D ansible_mitogen.services: ContextService().put(Context(4, u'ssh.10.212.225.84.sudo.root'))
[task 9638] 13:50:03.239261 D mitogen: Broker(0x7f11c4052790).shutdown()
[task 9638] 13:50:03.239731 D mitogen: mitogen.core.Stream('unix_listener.9585').on_disconnect()
[task 9638] 13:50:03.240152 D mitogen: Context(0, None).on_disconnect()
[mux  9585] 13:50:03.240181 D mitogen: mitogen.core.Stream('unix_client.9638').on_disconnect()
[task 9638] 13:50:03.240539 D mitogen: Waker(Broker(0x7f11c4052790) rfd=14, wfd=15).on_shutdown()
[mux  9585] 13:50:03.240649 D mitogen: Context(2004, None).on_disconnect()
[task 9638] 13:50:03.240836 D mitogen: Waker(Broker(0x7f11c4052790) rfd=14, wfd=15).on_disconnect()
[task 9638] 13:50:03.241333 D mitogen: Router(Broker(0x7f11c4052790)): stats: 0 module requests in 0 ms, 0 sent (0 ms minify time), 0 negative responses. Sent 0.0 kb total, 0.0 kb avg.
[mux  9585] 13:50:03.243830 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_one((None, u'mitogen.core', u'Dispatcher', u'forget_chain', ('pdesjans001.gestion.desjardins.com-9638-7f11d9f13740-5837175208460',), Kwargs({})))
[mux  9585] 13:50:03.244215 D mitogen.ctx.ssh.10.212.225.84.sudo.root: mitogen: _dispatch_calls: Message(4, 2004, 0, 101, 0, '\x80\x02(NX\x0c\x00\x00\x00mitogen.coreX\n\x00\x00\x00Dispatcherq\x01X\x0c\x00\x00\x00forget_'..160) -> None
The full traceback is:
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ansible/executor/task_executor.py", line 125, in run
    res = self._execute()
  File "/usr/lib/python2.7/site-packages/ansible/executor/task_executor.py", line 522, in _execute
    result = self._handler.run(task_vars=variables)
  File "/usr/share/ansible_mitogen/mixins.py", line 116, in run
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/usr/lib/python2.7/site-packages/ansible/plugins/action/unarchive.py", line 104, in run
    self._transfer_file(source, tmp_src)
  File "/usr/lib/python2.7/site-packages/ansible/plugins/action/__init__.py", line 312, in _transfer_file
    self._connection.put_file(local_path, remote_path)
  File "/usr/share/ansible_mitogen/connection.py", line 999, in put_file
    out_path=out_path
  File "/usr/share/ansible_mitogen/connection.py", line 413, in call
    return self._rethrow(recv)
  File "/usr/share/ansible_mitogen/connection.py", line 399, in _rethrow
    return recv.get().unpickle()
  File "/usr/share/mitogen/core.py", line 835, in unpickle
    raise obj
CallError: exceptions.OSError: [Errno 2] No such file or directory: '/root/.ansible/tmp/ansible_mitogen_action_9bea896ffa5c48b9/.ansible_mitogen_transfer-vjCTKN.tmp'
  File "<stdin>", line 3107, in _dispatch_one
  File "master:/usr/share/ansible_mitogen/target.py", line 198, in transfer_file
    dir=os.path.dirname(out_path))
  File "/usr/lib64/python2.7/tempfile.py", line 304, in mkstemp
    return _mkstemp_inner(dir, prefix, suffix, flags)
  File "/usr/lib64/python2.7/tempfile.py", line 239, in _mkstemp_inner
    fd = _os.open(file, flags, 0600)


fatal: [10.212.225.84]: FAILED! => {
    "failed": true,
    "msg": "Unexpected failure during module execution.",
    "stdout": ""
}

PLAY RECAP ***********************************************************************************************************************************************************************************************************
10.212.225.84              : ok=2    changed=0    unreachable=0    failed=1

[mux  9585] 13:50:03.254062 D mitogen: Broker(0x4168f90).shutdown()
[mux  9585] 13:50:03.255155 D mitogen: Waker(Broker(0x4168f90) rfd=10, wfd=12).on_shutdown()
[mux  9585] 13:50:03.255503 D mitogen: Waker(Broker(0x4168f90) rfd=10, wfd=12).on_disconnect()
[mux  9585] 13:50:03.255947 D mitogen: mitogen.ssh.Stream('ssh.10.212.225.84') closing CALL_FUNCTION channel
[mux  9585] 13:50:03.256314 D mitogen: mitogen.parent.DiagLogStream(fd=83, 'ssh.10.212.225.84').on_shutdown()
[mux  9585] 13:50:03.256660 D mitogen: mitogen.parent.DiagLogStream(fd=83, 'ssh.10.212.225.84').on_disconnect()
[mux  9585] 13:50:03.257177 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-13'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.257762 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-14'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.258216 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-15'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.258676 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-16'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.259114 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-17'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.259600 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-18'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.260038 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-19'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.260505 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-20'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.260946 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-21'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.261533 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-22'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.262009 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-23'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.262498 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-24'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.262940 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-25'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.263552 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-26'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.264068 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-27'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.264609 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-28'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.265055 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-29'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.265535 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-30'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.265995 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-31'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.266444 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-0'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.266909 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-1'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.267469 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-2'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.268045 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-3'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.268538 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-4'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.269111 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-5'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.269600 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-6'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.270016 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-7'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.270562 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-8'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.270772 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-9'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.270863 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-10'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.270956 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-11'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.271042 D mitogen: mitogen.service.Pool(0x416e590, size=32, th='mitogen.service.Pool.416e590.worker-12'): channel or latch closed, exitting: None
[mux  9585] 13:50:03.273172 D mitogen.ctx.ssh.10.212.225.84: mitogen: _on_shutdown_msg(Message(2, 0, 0, 106, 0, ''..0))
[mux  9585] 13:50:03.273535 D mitogen.ctx.ssh.10.212.225.84: mitogen: Broker(0x7fc2ddaaa290).shutdown()
[mux  9585] 13:50:03.273916 D mitogen.ctx.ssh.10.212.225.84: mitogen: Waker(Broker(0x7fc2ddaaa290) rfd=3, wfd=4).on_shutdown()
[mux  9585] 13:50:03.274242 D mitogen.ctx.ssh.10.212.225.84: mitogen: Waker(Broker(0x7fc2ddaaa290) rfd=3, wfd=4).on_disconnect()
[mux  9585] 13:50:03.274581 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.core.Stream('parent').on_shutdown(Broker(0x7fc2ddaaa290))
[mux  9585] 13:50:03.274888 D mitogen.ctx.ssh.10.212.225.84: mitogen: <IoLogger stdout>.on_shutdown()
[mux  9585] 13:50:03.275194 D mitogen.ctx.ssh.10.212.225.84: mitogen: <IoLogger stderr>.on_shutdown()
[mux  9585] 13:50:03.275557 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.fork.Stream('fork.23561') closing CALL_FUNCTION channel
[mux  9585] 13:50:03.276092 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.parent.DiagLogStream(fd=17, 'sudo.root').on_shutdown()
[mux  9585] 13:50:03.276459 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.parent.DiagLogStream(fd=17, 'sudo.root').on_disconnect()
[mux  9585] 13:50:03.276861 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.sudo.Stream('sudo.root') closing CALL_FUNCTION channel
[mux  9585] 13:50:03.277230 D mitogen.ctx.ssh.10.212.225.84: mitogen: <IoLogger stdout>.on_disconnect()
[mux  9585] 13:50:03.277566 D mitogen.ctx.ssh.10.212.225.84: mitogen: <IoLogger stderr>.on_disconnect()
[mux  9585] 13:50:03.277871 D mitogen.ctx.fork.23561: mitogen: _on_shutdown_msg(Message(3, 2, 2, 106, 0, ''..0))
[mux  9585] 13:50:03.278173 D mitogen.ctx.fork.23561: mitogen: Broker(0x7fc2d3bcaf10).shutdown()
[mux  9585] 13:50:03.278509 D mitogen.ctx.fork.23561: mitogen: <IoLogger stderr>.on_shutdown()
[mux  9585] 13:50:03.278809 D mitogen.ctx.fork.23561: mitogen: Waker(Broker(0x7fc2d3bcaf10) rfd=3, wfd=4).on_shutdown()
[mux  9585] 13:50:03.279110 D mitogen.ctx.fork.23561: mitogen: Waker(Broker(0x7fc2d3bcaf10) rfd=3, wfd=4).on_disconnect()
[mux  9585] 13:50:03.279442 D mitogen.ctx.fork.23561: mitogen: mitogen.core.Stream('parent').on_shutdown(Broker(0x7fc2d3bcaf10))
[mux  9585] 13:50:03.279749 D mitogen.ctx.fork.23561: mitogen: <IoLogger stdout>.on_shutdown()
[mux  9585] 13:50:03.280046 D mitogen.ctx.fork.23561: mitogen: <IoLogger stderr>.on_disconnect()
[mux  9585] 13:50:03.280359 D mitogen.ctx.fork.23561: mitogen: <IoLogger stdout>.on_disconnect()
[mux  9585] 13:50:03.280845 D mitogen.ctx.fork.23561: mitogen: _broker_main() force disconnecting <Side of mitogen.core.Stream('parent') fd 5>
[mux  9585] 13:50:03.281252 D mitogen.ctx.fork.23561: mitogen: mitogen.core.Stream('parent').on_disconnect()
[mux  9585] 13:50:03.281600 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.fork.Stream('fork.23561').on_disconnect()
[mux  9585] 13:50:03.281901 D mitogen.ctx.ssh.10.212.225.84: mitogen: RouteMonitor(): mitogen.fork.Stream('fork.23561') is gone; propagating DEL_ROUTE for set([3])
[mux  9585] 13:50:03.282221 D mitogen.ctx.ssh.10.212.225.84: mitogen: Router(Broker(0x7fc2ddaaa290)).del_route(3)
[mux  9585] 13:50:03.282668 D mitogen: RouteMonitor(): firing local disconnect for Context(3, u'fork.23561')
[mux  9585] 13:50:03.282950 D mitogen: RouteMonitor(): deleting route to 3 via mitogen.ssh.Stream('ssh.10.212.225.84')
[mux  9585] 13:50:03.283228 D mitogen: Router(Broker(0x4168f90)).del_route(3)
[mux  9585] 13:50:03.283630 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.fork.Stream('fork.23561'): child process still alive, sending SIGTERM
[mux  9585] 13:50:03.283951 D mitogen.ctx.ssh.10.212.225.84: mitogen: <Side of mitogen.sudo.Stream('sudo.root') fd 18>.read(): disconnected: [Errno 104] Connection reset by peer
[mux  9585] 13:50:03.284322 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.sudo.Stream('sudo.root').on_disconnect()
[mux  9585] 13:50:03.284664 D mitogen.ctx.ssh.10.212.225.84: mitogen: RouteMonitor(): mitogen.sudo.Stream('sudo.root') is gone; propagating DEL_ROUTE for set([1003, 4])
[mux  9585] 13:50:03.284975 D mitogen.ctx.ssh.10.212.225.84: mitogen: Router(Broker(0x7fc2ddaaa290)).del_route(1003)
[mux  9585] 13:50:03.285275 D mitogen: RouteMonitor(): firing local disconnect for Context(1003, u'fork.23574')
[mux  9585] 13:50:03.285586 D mitogen: RouteMonitor(): deleting route to 1003 via mitogen.ssh.Stream('ssh.10.212.225.84')
[mux  9585] 13:50:03.285862 D mitogen: Router(Broker(0x4168f90)).del_route(1003)
[mux  9585] 13:50:03.286166 D mitogen.ctx.ssh.10.212.225.84: mitogen: Router(Broker(0x7fc2ddaaa290)).del_route(4)
[mux  9585] 13:50:03.286503 D mitogen: RouteMonitor(): firing local disconnect for Context(4, u'ssh.10.212.225.84.sudo.root')
[mux  9585] 13:50:03.286782 I ansible_mitogen.services: ContextService(): Forgetting Context(4, u'ssh.10.212.225.84.sudo.root') due to stream disconnect
[mux  9585] 13:50:03.287085 D mitogen: RouteMonitor(): deleting route to 4 via mitogen.ssh.Stream('ssh.10.212.225.84')
[mux  9585] 13:50:03.287456 D mitogen: Router(Broker(0x4168f90)).del_route(4)
[mux  9585] 13:50:03.294781 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.parent.DiagLogStream(fd=17, 'sudo.root').on_disconnect()
[mux  9585] 13:50:03.306066 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.sudo.Stream('sudo.root'): PID 23565 exited due to signal 1 (SIGHUP)
[mux  9585] 13:50:03.306538 D mitogen.ctx.ssh.10.212.225.84: mitogen: _broker_main() force disconnecting <Side of mitogen.core.Stream('parent') fd 5>
[mux  9585] 13:50:03.306856 D mitogen.ctx.ssh.10.212.225.84: mitogen: mitogen.core.Stream('parent').on_disconnect()
[mux  9585] 13:50:03.324810 D mitogen: mitogen.ssh.Stream('ssh.10.212.225.84').on_disconnect()
[mux  9585] 13:50:03.325187 D mitogen: RouteMonitor(): mitogen.ssh.Stream('ssh.10.212.225.84') is gone; propagating DEL_ROUTE for set([2])
[mux  9585] 13:50:03.325509 D mitogen: Router(Broker(0x4168f90)).del_route(2)
[mux  9585] 13:50:03.325801 I ansible_mitogen.services: ContextService(): Forgetting Context(2, u'ssh.10.212.225.84') due to stream disconnect
[mux  9585] 13:50:03.326112 D mitogen: mitogen.parent.DiagLogStream(fd=83, 'ssh.10.212.225.84').on_disconnect()
[mux  9585] 13:50:03.326467 D mitogen: mitogen.ssh.Stream('ssh.10.212.225.84'): PID 9622 exited with return code 0
[mux  9585] 13:50:03.326908 D mitogen: Router(Broker(0x4168f90)): stats: 1 module requests in 43 ms, 17 sent (386 ms minify time), 0 negative responses. Sent 104.2 kb total, 6.1 kb avg.
@fdutheil

This comment has been minimized.

Copy link

commented Apr 3, 2019

[This comment was moved to issue #594]

@sw-elling

This comment has been minimized.

Copy link

commented Jun 20, 2019

Any idea when a patch or new release with a fix will be available?

@migalsp

This comment has been minimized.

Copy link

commented Aug 6, 2019

I've got the same error.
Mitogen: 0.2.7
Ansible: 2.4.1.0
Python: 2.7.5

The full traceback is:
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ansible/executor/task_executor.py", line 125, in run
    res = self._execute()
  File "/usr/lib/python2.7/site-packages/ansible/executor/task_executor.py", line 521, in _execute
    result = self._handler.run(task_vars=variables)
  File "/ansible/mitogen/ansible_mitogen/mixins.py", line 116, in run
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/usr/lib/python2.7/site-packages/ansible/plugins/action/unarchive.py", line 105, in run
    self._transfer_file(source, tmp_src)
  File "/usr/lib/python2.7/site-packages/ansible/plugins/action/__init__.py", line 311, in _transfer_file
    self._connection.put_file(local_path, remote_path)
  File "/ansible/mitogen/ansible_mitogen/connection.py", line 1020, in put_file
    out_path=out_path
  File "/ansible/mitogen/ansible_mitogen/connection.py", line 434, in call
    return self._rethrow(recv)
  File "/ansible/mitogen/ansible_mitogen/connection.py", line 420, in _rethrow
    return recv.get().unpickle()
  File "/ansible/mitogen/mitogen/core.py", line 835, in unpickle
    raise obj
CallError: exceptions.OSError: [Errno 2] No such file or directory: '/tmp/.ansible-Loc/tmp/ansible_mitogen_action_7d95ade0c5d3b305/.ansible_mitogen_transfer-hhdT3C.tmp'
  File "<stdin>", line 3107, in _dispatch_one
  File "master:/ansible/mitogen/ansible_mitogen/target.py", line 198, in transfer_file
    dir=os.path.dirname(out_path))
  File "/usr/lib64/python2.7/tempfile.py", line 304, in mkstemp
    return _mkstemp_inner(dir, prefix, suffix, flags)
  File "/usr/lib64/python2.7/tempfile.py", line 239, in _mkstemp_inner
    fd = _os.open(file, flags, 0600)


fatal: [test]: FAILED! => {
    "failed": true
}

MSG:

Unexpected failure during module execution.

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

Do you have any info, when this problem could be solved?

Thanks in advance

@migalsp

This comment has been minimized.

Copy link

commented Aug 7, 2019

Any idea when a patch or new release with a fix will be available?

With Ansible 2.3 or 2.4 and Python 2.7, you can use Mitogen 0.2.5.
The problem in release 0.2.6 for these versions.

@dw

This comment has been minimized.

Copy link
Owner

commented Aug 11, 2019

The problem is commit 7743e57 due to ticket #554. WIP

dw added a commit that referenced this issue Aug 12, 2019

issue #558, #582: preserve remote tmpdir if caller did not supply one
The undocumented 'tmp' parameter controls whether _execute_module()
would delete anything on 2.3, so mimic that. This means
_execute_remove_stat() calls will not blow away the temp directory,
which broke the unarchive plugin.

dw added a commit that referenced this issue Aug 12, 2019

issue #558, #582: preserve remote tmpdir if caller did not supply one
The undocumented 'tmp' parameter controls whether _execute_module()
would delete anything on 2.3, so mimic that. This means
_execute_remove_stat() calls will not blow away the temp directory,
which broke the unarchive plugin.

dw added a commit that referenced this issue Aug 12, 2019

dw added a commit that referenced this issue Aug 12, 2019

Merge remote-tracking branch 'origin/dmw'
* origin/dmw:
  issue #558: disable test on OSX to cope with boundless mediocrity
  issue #558, #582: preserve remote tmpdir if caller did not supply one
@dw

This comment has been minimized.

Copy link
Owner

commented Aug 12, 2019

This is now 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/

Thanks for reporting this!

@dw dw closed this Aug 12, 2019

dw added a commit that referenced this issue Aug 18, 2019

Merge remote-tracking branch 'origin/v028' into stable
* origin/v028: (383 commits)
  Bump version for release.
  docs: update Changelog for 0.2.8.
  issue #627: add test and tweak Reaper behaviour.
  docs: lots more changelog concision
  docs: changelog concision
  docs: more changelog tweaks
  docs: reorder chapters
  docs: versionless <title>
  docs: update supported Ansible version, mention unsupported features
  docs: changelog fixes/tweaks
  issue #590: update Changelog.
  issue #621: send ADD_ROUTE earlier and add test for early logging.
  issue #590: whoops, import missing test modules
  issue #590: rework ParentEnumerationMethod to recursively handle bad modules
  issue #627: reduce the default pool size in a child to 2.
  tests: add a few extra service tests.
  docs: some more hyperlink joy
  docs: more hyperlinks
  docs: add domainrefs plugin to make link aliases everywhere \o/
  docs: link IS_DEAD in changelog
  docs: tweaks to better explain changelog race
  issue #533: update routing to account for DEL_ROUTE propagation race
  tests: use defer_sync() Rather than defer() + ancient sync_with_broker()
  tests: one case from doas_test was invoking su
  tests: hide memory-mapped files from lsof output
  issue #615: remove meaningless test
  issue #625: ignore SIGINT within MuxProcess
  issue #625: use exec() instead of subprocess in mitogen_ansible_playbook
  issue #615: regression test
  issue #615: update Changelog.
  issue #615: ensure 4GB max_message_size is configured for task workers.
  issue #615: update Changelog.
  issue #615: route a dead message to recipients when no reply is expected
  issue #615: fetch_file() might be called with AnsibleUnicode.
  issue #615: redirect 'fetch' action to 'mitogen_fetch'.
  issue #615: extricate slurp brainwrong from mitogen_fetch
  issue #615: ansible: import Ansible fetch.py action plug-in
  issue #533: include object identity of Stream in repr()
  docs: lots more changelog
  issue #595: add buildah to docs and changelog.
  docs: a few more internals.rst additions
  ci: update to Ansible 2.8.3
  tests: another random string changed in 2.8.3
  tests: fix sudo_flags_failure for Ansible 2.8.3
  ci: fix procps command line format warning
  Whoops, merge together lgtm.yml and .lgtm.yml
  issue #440: log Python version during bootstrap.
  docs: update changelog
  issue #558: disable test on OSX to cope with boundless mediocrity
  issue #558, #582: preserve remote tmpdir if caller did not supply one
  issue #613: must await 'exit' and 'disconnect' in wait=False test
  Import LGTM config to disable some stuff
  Fix up another handful of LGTM errors.
  tests: work around AnsibleModule.run_command() race.
  docs: mention another __main__ safeguard
  docs: tweaks
  formatting error
  docs: make Sphinx install soft fail on Python 2.
  issue #598: allow disabling preempt in terraform
  issue #598: update Changelog.
  issue #605: update Changelog.
  issue #605: ansible: share a sem_t instead of a pthread_mutex_t
  issue #613: add tests for all the weird shutdown methods
  Add mitogen.core.now() and use it everywhere; closes #614.
  docs: move decorator docs into core.py and use autodecorator
  preamble_size: make it work on Python 3.
  docs: upgrade Sphinx to 2.1.2, require Python 3 to build docs.
  docs: fix Sphinx warnings, add LogHandler, more docstrings
  docs: tidy up some Changelog text
  issue #615: fix up FileService tests for new logic
  issue #615: another Py3x fix.
  issue #615: Py3x fix.
  issue #615: update Changelog.
  issue #615: use FileService for target->controll file transfers
  issue #482: another Py3 fix
  ci: try removing exclude: to make Azure jobs work again
  compat: fix Py2.4 SyntaxError
  issue #482: remove 'ssh' from checked processes
  ci: Py3 fix
  issue #279: add one more test for max_message_size
  issue #482: ci: add stray process checks to all jobs
  tests: fix format string error
  core: MitogenProtocol.is_privileged was not set in children
  issue #482: tests: fail DockerMixin tests if stray processes exist
  docs: update Changelog.
  issue #586: update Changelog.
  docs: update Changelog.
  [security] core: undirectional routing wasn't respected in some cases
  docs: tidy up Select.all()
  issue #612: update Changelog.
  master: fix TypeError
  pkgutil: fix Python3 compatibility
  parent: use protocol for getting remote_id
  docs: merge signals.rst into internals.rst
  os_fork: do not attempt to cork the active thread.
  parent: fix get_log_level() for split out loggers.
  issue #547: fix service_test failures.
  issue #547: update Changelog.
  issue #547: core/service: race/deadlock-free service pool init
  docs: update Changelog.
  ...
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
5 participants
You can’t perform that action at this time.