search

saltstack / salt

Software to automate the management and configuration of any infrastructure or application at scale. Install Salt from the Salt package repositories here:
https://docs.saltproject.io/salt/install-guide/en/latest/
Apache License 2.0
14.18k stars 5.48k forks source link

execution module cp.cache_file fails with error 'invalid arguments to setopt' #49275

Closed dgengtek closed 6 years ago

dgengtek commented 6 years ago

Description of Issue/Question

cp.cache_file fails on CentOS 7 python2 installation with Tornado 4.2.1 with the error "Passed invalid arguments to cp.cache_file: invalid arguments to setopt" when running the execution module from the shell. Anything which obviously is using cp.cache_file to download something is failing.

cp.cache_file works on CentOS 7 python34 installation with Tornado 4.4.2

Setup

CentOS 7 with salt python2 from repo https://repo.saltstack.com/yum/redhat/salt-repo-latest-2.el7.noarch.rpm

Steps to Reproduce Issue

$ salt "<CentOS 7 minion with python2>" cp.cache_file <any external url>
Passed invalid arguments to cp.cache_file: invalid arguments to setopt
...
<output of documention for cp.cache_file>
...

Versions Report

CentOS 7 with python2 which fails

Salt Version:
           Salt: 2018.3.2

Dependency Versions:
           cffi: 1.6.0
       cherrypy: unknown
       dateutil: Not Installed
      docker-py: Not Installed
          gitdb: Not Installed
      gitpython: Not Installed
          ioflo: Not Installed
         Jinja2: 2.7.2
        libgit2: 0.26.3
        libnacl: Not Installed
       M2Crypto: 0.28.2
           Mako: Not Installed
   msgpack-pure: Not Installed
 msgpack-python: 0.5.6
   mysql-python: Not Installed
      pycparser: 2.14
       pycrypto: 2.6.1
   pycryptodome: Not Installed
         pygit2: 0.26.4
         Python: 2.7.5 (default, Jul 13 2018, 13:06:57)
   python-gnupg: Not Installed
         PyYAML: 3.11
          PyZMQ: 15.3.0
           RAET: Not Installed
          smmap: Not Installed
        timelib: Not Installed
        Tornado: 4.2.1
            ZMQ: 4.1.4

System Versions:
           dist: centos 7.5.1804 Core
         locale: UTF-8
        machine: x86_64
        release: 4.17.13-arch1-1-ARCH
         system: Linux
        version: CentOS Linux 7.5.1804 Core

Centos 7 with python34 which works

    Salt Version:
               Salt: 2018.3.2

    Dependency Versions:
               cffi: Not Installed
           cherrypy: Not Installed
           dateutil: Not Installed
          docker-py: Not Installed
              gitdb: Not Installed
          gitpython: Not Installed
              ioflo: Not Installed
             Jinja2: 2.8
            libgit2: Not Installed
            libnacl: Not Installed
           M2Crypto: Not Installed
               Mako: Not Installed
       msgpack-pure: Not Installed
     msgpack-python: 0.4.6
       mysql-python: Not Installed
          pycparser: Not Installed
           pycrypto: 2.6.1
       pycryptodome: Not Installed
             pygit2: Not Installed
             Python: 3.4.8 (default, Jul 25 2018, 22:08:49)
       python-gnupg: Not Installed
             PyYAML: 3.11
              PyZMQ: 15.3.0
               RAET: Not Installed
              smmap: Not Installed
            timelib: Not Installed
            Tornado: 4.4.2
                ZMQ: 4.1.4

    System Versions:
               dist: centos 7.5.1804 Core
             locale: UTF-8
            machine: x86_64
            release: 3.18.25-19.el7.x86_64
             system: Linux
            version: CentOS Linux 7.5.1804 Core
gtmanfred commented 6 years ago 
[root@salt ~]# salt --versions-report
Salt Version:
           Salt: 2018.3.2

Dependency Versions:
           cffi: Not Installed
       cherrypy: Not Installed
       dateutil: Not Installed
      docker-py: Not Installed
          gitdb: Not Installed
      gitpython: Not Installed
          ioflo: Not Installed
         Jinja2: 2.7.2
        libgit2: Not Installed
        libnacl: Not Installed
       M2Crypto: Not Installed
           Mako: Not Installed
   msgpack-pure: Not Installed
 msgpack-python: 0.5.6
   mysql-python: Not Installed
      pycparser: Not Installed
       pycrypto: 2.6.1
   pycryptodome: Not Installed
         pygit2: Not Installed
         Python: 2.7.5 (default, Jul 13 2018, 13:06:57)
   python-gnupg: Not Installed
         PyYAML: 3.11
          PyZMQ: 15.3.0
           RAET: Not Installed
          smmap: Not Installed
        timelib: Not Installed
        Tornado: 4.2.1
            ZMQ: 4.1.4

System Versions:
           dist: centos 7.5.1804 Core
         locale: UTF-8
        machine: x86_64
        release: 3.10.0-862.11.6.el7.x86_64
         system: Linux
        version: CentOS Linux 7.5.1804 Core

[root@salt ~]# salt \* test.ping
salt.c.gtmanfred-1263.internal:
    True
[root@salt ~]# salt \* cp.cache_file https://raw.githubusercontent.com/saltstack/salt/develop/README.rst
salt.c.gtmanfred-1263.internal:
    /var/cache/salt/minion/extrn_files/base/raw.githubusercontent.com/saltstack/salt/develop/README.rst
[root@salt ~]# head -n10 /var/cache/salt/minion/extrn_files/base/raw.githubusercontent.com/saltstack/salt/develop/README.rst
==================
What is SaltStack?
==================

SaltStack makes software for complex systems management at scale.
SaltStack is the company that created and maintains the Salt Open
project and develops and sells SaltStack Enterprise software, services
and support. Easy enough to get running in minutes, scalable enough to
manage tens of thousands of servers, and fast enough to communicate with
them in *seconds*.

I am unable to replicate this issue, can you provide me with more information?

dgengtek commented 6 years ago

I should have probably mentioned that I am behind a squid http proxy.

I just now tried with a new host install and getting the same error behind a http proxy. I can not see any traffic to the proxy with tcpdump so I think it is an issue with the minion proxy settings?

These are the only settings in /etc/salt/minion I used

proxy_host: 'proxy.<domain>'
proxy_port: 3128

This is what I used to run the command:

[root@borg-formula-kitchen-centos-7-62de453c7788c80a ~]# salt-call  -c /tmp/kitchen/etc/salt/ --local cp.cache_file https://github.com/borgbackup/borg/releases/download/1.1.7/borg-linux64
Passed invalid arguments: invalid arguments to setopt.
Usage:

    Used to cache a single file on the Minion

    Returns the location of the new cached file on the Minion

    source_hash
        If ``name`` is an http(s) or ftp URL and the file exists in the
        minion's file cache, this option can be passed to keep the minion from
        re-downloading the file if the cached copy matches the specified hash.

        .. versionadded:: 2018.3.0

    CLI Example:

    .. code-block:: bash

        salt '*' cp.cache_file salt://path/to/file

    There are two ways of defining the fileserver environment (a.k.a.
    ``saltenv``) from which to cache the file. One is to use the ``saltenv``
    parameter, and the other is to use a querystring syntax in the ``salt://``
    URL. The below two examples are equivalent:

    .. code-block:: bash

        salt '*' cp.cache_file salt://foo/bar.conf saltenv=config
        salt '*' cp.cache_file salt://foo/bar.conf?saltenv=config

    If the path being cached is a ``salt://`` URI, and the path does not exist,
    then ``False`` will be returned.

    .. note::
        It may be necessary to quote the URL when using the querystring method,
        depending on the shell being used to run the command.

This is the versions report of the clean host which fails:

Salt Version:
           Salt: 2018.3.2

Dependency Versions:
           cffi: Not Installed
       cherrypy: Not Installed
       dateutil: Not Installed
      docker-py: Not Installed
          gitdb: Not Installed
      gitpython: Not Installed
          ioflo: Not Installed
         Jinja2: 2.7.2
        libgit2: Not Installed
        libnacl: Not Installed
       M2Crypto: Not Installed
           Mako: Not Installed
   msgpack-pure: Not Installed
 msgpack-python: 0.4.6
   mysql-python: Not Installed
      pycparser: Not Installed
       pycrypto: 2.6.1
   pycryptodome: Not Installed
         pygit2: Not Installed
         Python: 2.7.5 (default, Apr 11 2018, 07:36:10)
   python-gnupg: Not Installed
         PyYAML: 3.11
          PyZMQ: 15.3.0
           RAET: Not Installed
          smmap: Not Installed
        timelib: Not Installed
        Tornado: 4.2.1
            ZMQ: 4.1.4

System Versions:
           dist: centos 7.5.1804 Core
         locale: UTF-8
        machine: x86_64
        release: 4.17.13-arch1-1-ARCH
         system: Linux
        version: CentOS Linux 7.5.1804 Core
dgengtek commented 6 years ago

This is the formula is used for testing https://github.com/dgengtek/testing-formula/tree/issue/49275

These are the kitchen logs with the error:

I, [2018-08-23T16:56:55.110262 #4754]  INFO -- testing-formula-kitchen-centos-7: -----> Creating <testing-formula-kitchen-centos-7>...
I, [2018-08-23T16:56:55.110459 #4754]  INFO -- testing-formula-kitchen-centos-7: Container name: testing-formula-kitchen-centos-7-dc26c64a4071c187
I, [2018-08-23T16:57:22.957245 #4754]  INFO -- testing-formula-kitchen-centos-7: Waiting for an IP address...
I, [2018-08-23T16:57:29.797291 #4754]  INFO -- testing-formula-kitchen-centos-7: Finished creating <testing-formula-kitchen-centos-7> (0m34.69s).
I, [2018-08-23T16:57:29.797410 #4754]  INFO -- testing-formula-kitchen-centos-7: -----> Converging <testing-formula-kitchen-centos-7>...
I, [2018-08-23T16:57:29.797900 #4754]  INFO -- testing-formula-kitchen-centos-7: Preparing files for transfer
I, [2018-08-23T16:57:29.797953 #4754]  INFO -- testing-formula-kitchen-centos-7: Preparing salt-minion
I, [2018-08-23T16:57:29.799010 #4754]  INFO -- testing-formula-kitchen-centos-7: Preparing pillars into /srv/pillar
I, [2018-08-23T16:57:29.799639 #4754]  INFO -- testing-formula-kitchen-centos-7: Preparing grains into /etc/salt/grains
I, [2018-08-23T16:57:29.799831 #4754]  INFO -- testing-formula-kitchen-centos-7: Preparing formula: testing from /mnt/hdd/bigX/salt/formulas/testing-formula
I, [2018-08-23T16:57:29.801293 #4754]  INFO -- testing-formula-kitchen-centos-7: Preparing state_top
I, [2018-08-23T16:57:29.801753 #4754]  INFO -- testing-formula-kitchen-centos-7: Preparing scripts into /etc/salt/scripts
I, [2018-08-23T16:57:30.160669 #4754]  INFO -- testing-formula-kitchen-centos-7: -----> Installing yum repo for salt 2018.3.2
I, [2018-08-23T16:57:31.294686 #4754]  INFO -- testing-formula-kitchen-centos-7: [saltstack-repo]
I, [2018-08-23T16:57:31.294759 #4754]  INFO -- testing-formula-kitchen-centos-7: name=SaltStack repo for RHEL/CentOS 
I, [2018-08-23T16:57:31.294783 #4754]  INFO -- testing-formula-kitchen-centos-7: baseurl=https://repo.saltstack.com/yum/redhat/$releasever/$basearch/archive/2018.3.2
I, [2018-08-23T16:57:31.294805 #4754]  INFO -- testing-formula-kitchen-centos-7: enabled=1
I, [2018-08-23T16:57:31.294872 #4754]  INFO -- testing-formula-kitchen-centos-7: gpgcheck=1
I, [2018-08-23T16:57:31.294898 #4754]  INFO -- testing-formula-kitchen-centos-7: gpgkey=https://repo.saltstack.com/yum/redhat/$releasever/$basearch/archive/2018.3.2/SALTSTACK-GPG-KEY.pub
I, [2018-08-23T16:57:31.468671 #4754]  INFO -- testing-formula-kitchen-centos-7: Failed to set locale, defaulting to C
I, [2018-08-23T16:57:31.479843 #4754]  INFO -- testing-formula-kitchen-centos-7: Loaded plugins: fastestmirror
I, [2018-08-23T16:57:31.540799 #4754]  INFO -- testing-formula-kitchen-centos-7: Cleaning repos: base extras saltstack-repo updates
I, [2018-08-23T16:57:31.541764 #4754]  INFO -- testing-formula-kitchen-centos-7: 6 metadata files removed
I, [2018-08-23T16:57:31.562105 #4754]  INFO -- testing-formula-kitchen-centos-7: -----> Installing salt-minion (2018.3.2)
I, [2018-08-23T16:57:31.681363 #4754]  INFO -- testing-formula-kitchen-centos-7: Failed to set locale, defaulting to C
I, [2018-08-23T16:57:31.690881 #4754]  INFO -- testing-formula-kitchen-centos-7: Loaded plugins: fastestmirror
I, [2018-08-23T16:57:31.742543 #4754]  INFO -- testing-formula-kitchen-centos-7: Determining fastest mirrors
I, [2018-08-23T16:57:38.256571 #4754]  INFO -- testing-formula-kitchen-centos-7:  * base: centos.alpha-labs.net
I, [2018-08-23T16:57:38.257391 #4754]  INFO -- testing-formula-kitchen-centos-7:  * extras: centos.alpha-labs.net
I, [2018-08-23T16:57:38.257976 #4754]  INFO -- testing-formula-kitchen-centos-7:  * updates: centos.mirrors.as250.net
I, [2018-08-23T16:57:41.819395 #4754]  INFO -- testing-formula-kitchen-centos-7: No package salt-common available.
I, [2018-08-23T16:57:41.923262 #4754]  INFO -- testing-formula-kitchen-centos-7: Resolving Dependencies
I, [2018-08-23T16:57:41.924801 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Running transaction check
I, [2018-08-23T16:57:41.924882 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package salt-master.noarch 0:2018.3.2-1.el7 will be installed
I, [2018-08-23T16:57:41.927410 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: salt = 2018.3.2-1.el7 for package: salt-master-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.095428 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: systemd-python for package: salt-master-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.098545 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package salt-minion.noarch 0:2018.3.2-1.el7 will be installed
I, [2018-08-23T16:57:42.098810 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Running transaction check
I, [2018-08-23T16:57:42.099723 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package salt.noarch 0:2018.3.2-1.el7 will be installed
I, [2018-08-23T16:57:42.103131 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-tornado < 5.0 for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.105726 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-tornado >= 4.2.1 for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.106571 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-requests >= 1.0.0 for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.108764 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-msgpack >= 0.4 for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.110136 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-futures >= 2.0 for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.110950 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-crypto >= 2.6.1 for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.114450 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: yum-utils for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.117390 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: which for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.119546 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-zmq for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.120374 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-six for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.122272 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-psutil for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.123456 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-markupsafe for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.125860 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-jinja2 for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.127912 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: pciutils for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.130302 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: PyYAML for package: salt-2018.3.2-1.el7.noarch
I, [2018-08-23T16:57:42.132429 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package systemd-python.x86_64 0:219-57.el7_5.1 will be installed
I, [2018-08-23T16:57:42.145964 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: systemd-libs = 219-57.el7_5.1 for package: systemd-python-219-57.el7_5.1.x86_64
I, [2018-08-23T16:57:42.152637 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: systemd = 219-57.el7_5.1 for package: systemd-python-219-57.el7_5.1.x86_64
I, [2018-08-23T16:57:42.155847 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Running transaction check
I, [2018-08-23T16:57:42.156128 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package PyYAML.x86_64 0:3.11-1.el7 will be installed
I, [2018-08-23T16:57:42.157061 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libyaml-0.so.2()(64bit) for package: PyYAML-3.11-1.el7.x86_64
I, [2018-08-23T16:57:42.160924 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package pciutils.x86_64 0:3.5.1-3.el7 will be installed
I, [2018-08-23T16:57:42.162832 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: pciutils-libs = 3.5.1-3.el7 for package: pciutils-3.5.1-3.el7.x86_64
I, [2018-08-23T16:57:42.166527 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libpci.so.3(LIBPCI_3.5)(64bit) for package: pciutils-3.5.1-3.el7.x86_64
I, [2018-08-23T16:57:42.167300 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libpci.so.3(LIBPCI_3.3)(64bit) for package: pciutils-3.5.1-3.el7.x86_64
I, [2018-08-23T16:57:42.167357 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libpci.so.3(LIBPCI_3.1)(64bit) for package: pciutils-3.5.1-3.el7.x86_64
I, [2018-08-23T16:57:42.167385 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libpci.so.3(LIBPCI_3.0)(64bit) for package: pciutils-3.5.1-3.el7.x86_64
I, [2018-08-23T16:57:42.167408 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: hwdata for package: pciutils-3.5.1-3.el7.x86_64
I, [2018-08-23T16:57:42.168151 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libpci.so.3()(64bit) for package: pciutils-3.5.1-3.el7.x86_64
I, [2018-08-23T16:57:42.168687 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-futures.noarch 0:3.0.3-1.el7 will be installed
I, [2018-08-23T16:57:42.168867 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-jinja2.noarch 0:2.7.2-2.el7 will be installed
I, [2018-08-23T16:57:42.169171 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-babel >= 0.8 for package: python-jinja2-2.7.2-2.el7.noarch
I, [2018-08-23T16:57:42.171207 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-markupsafe.x86_64 0:0.11-10.el7 will be installed
I, [2018-08-23T16:57:42.171448 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-msgpack.x86_64 0:0.4.6-1.el7 will be installed
I, [2018-08-23T16:57:42.172624 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-psutil.x86_64 0:2.2.1-1.el7 will be installed
I, [2018-08-23T16:57:42.173490 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-requests.noarch 0:2.6.0-1.el7_1 will be installed
I, [2018-08-23T16:57:42.173938 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-urllib3 >= 1.10.2-1 for package: python-requests-2.6.0-1.el7_1.noarch
I, [2018-08-23T16:57:42.176567 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-chardet >= 2.2.1-1 for package: python-requests-2.6.0-1.el7_1.noarch
I, [2018-08-23T16:57:42.178982 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-six.noarch 0:1.9.0-2.el7 will be installed
I, [2018-08-23T16:57:42.179277 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-tornado.x86_64 0:4.2.1-3.el7 will be installed
I, [2018-08-23T16:57:42.180654 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-backports-ssl_match_hostname for package: python-tornado-4.2.1-3.el7.x86_64
I, [2018-08-23T16:57:42.181704 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-zmq.x86_64 0:15.3.0-2.el7 will be installed
I, [2018-08-23T16:57:42.181965 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libzmq.so.5()(64bit) for package: python-zmq-15.3.0-2.el7.x86_64
I, [2018-08-23T16:57:42.183083 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python2-crypto.x86_64 0:2.6.1-15.el7 will be installed
I, [2018-08-23T16:57:42.184097 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libtomcrypt.so.0()(64bit) for package: python2-crypto-2.6.1-15.el7.x86_64
I, [2018-08-23T16:57:42.186409 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package systemd.x86_64 0:219-57.el7 will be updated
I, [2018-08-23T16:57:42.194963 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package systemd.x86_64 0:219-57.el7_5.1 will be an update
I, [2018-08-23T16:57:42.212637 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package systemd-libs.x86_64 0:219-57.el7 will be updated
I, [2018-08-23T16:57:42.214631 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package systemd-libs.x86_64 0:219-57.el7_5.1 will be an update
I, [2018-08-23T16:57:42.215721 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package which.x86_64 0:2.20-7.el7 will be installed
I, [2018-08-23T16:57:42.216354 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package yum-utils.noarch 0:1.1.31-46.el7_5 will be installed
I, [2018-08-23T16:57:42.217334 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-kitchen for package: yum-utils-1.1.31-46.el7_5.noarch
I, [2018-08-23T16:57:42.219385 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libxml2-python for package: yum-utils-1.1.31-46.el7_5.noarch
I, [2018-08-23T16:57:42.221072 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Running transaction check
I, [2018-08-23T16:57:42.221277 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package hwdata.x86_64 0:0.252-8.8.el7 will be installed
I, [2018-08-23T16:57:42.221786 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package libtomcrypt.x86_64 0:1.17-26.el7 will be installed
I, [2018-08-23T16:57:42.223036 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libtommath >= 0.42.0 for package: libtomcrypt-1.17-26.el7.x86_64
I, [2018-08-23T16:57:42.224294 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libtommath.so.0()(64bit) for package: libtomcrypt-1.17-26.el7.x86_64
I, [2018-08-23T16:57:42.224531 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package libxml2-python.x86_64 0:2.9.1-6.el7_2.3 will be installed
I, [2018-08-23T16:57:42.231728 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package libyaml.x86_64 0:0.1.4-11.el7_0 will be installed
I, [2018-08-23T16:57:42.231825 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package pciutils-libs.x86_64 0:3.5.1-3.el7 will be installed
I, [2018-08-23T16:57:42.232440 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-babel.noarch 0:0.9.6-8.el7 will be installed
I, [2018-08-23T16:57:42.232510 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-backports-ssl_match_hostname.noarch 0:3.5.0.1-1.el7 will be installed
I, [2018-08-23T16:57:42.232538 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-ipaddress for package: python-backports-ssl_match_hostname-3.5.0.1-1.el7.noarch
I, [2018-08-23T16:57:42.233544 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: python-backports for package: python-backports-ssl_match_hostname-3.5.0.1-1.el7.noarch
I, [2018-08-23T16:57:42.234659 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-chardet.noarch 0:2.2.1-1.el7_1 will be installed
I, [2018-08-23T16:57:42.234921 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-kitchen.noarch 0:1.1.1-5.el7 will be installed
I, [2018-08-23T16:57:42.235160 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-urllib3.noarch 0:1.10.2-5.el7 will be installed
I, [2018-08-23T16:57:42.235525 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package zeromq.x86_64 0:4.1.4-7.el7 will be installed
I, [2018-08-23T16:57:42.238023 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libsodium.so.23()(64bit) for package: zeromq-4.1.4-7.el7.x86_64
I, [2018-08-23T16:57:42.239448 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Processing Dependency: libpgm-5.2.so.0()(64bit) for package: zeromq-4.1.4-7.el7.x86_64
I, [2018-08-23T16:57:42.240896 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Running transaction check
I, [2018-08-23T16:57:42.241494 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package libsodium.x86_64 0:1.0.16-1.el7 will be installed
I, [2018-08-23T16:57:42.241812 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package libtommath.x86_64 0:0.42.0-6.el7 will be installed
I, [2018-08-23T16:57:42.241866 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package openpgm.x86_64 0:5.2.122-2.el7 will be installed
I, [2018-08-23T16:57:42.242970 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-backports.x86_64 0:1.0-8.el7 will be installed
I, [2018-08-23T16:57:42.243034 #4754]  INFO -- testing-formula-kitchen-centos-7: ---> Package python-ipaddress.noarch 0:1.0.16-2.el7 will be installed
I, [2018-08-23T16:57:42.263369 #4754]  INFO -- testing-formula-kitchen-centos-7: --> Finished Dependency Resolution
I, [2018-08-23T16:57:42.281125 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:57:42.281199 #4754]  INFO -- testing-formula-kitchen-centos-7: Dependencies Resolved
I, [2018-08-23T16:57:42.290083 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:57:42.290164 #4754]  INFO -- testing-formula-kitchen-centos-7: ================================================================================
I, [2018-08-23T16:57:42.290201 #4754]  INFO -- testing-formula-kitchen-centos-7:  Package                            Arch   Version         Repository      Size
I, [2018-08-23T16:57:42.290228 #4754]  INFO -- testing-formula-kitchen-centos-7: ================================================================================
I, [2018-08-23T16:57:42.290252 #4754]  INFO -- testing-formula-kitchen-centos-7: Installing:
I, [2018-08-23T16:57:42.290277 #4754]  INFO -- testing-formula-kitchen-centos-7:  salt-master                        noarch 2018.3.2-1.el7  saltstack-repo 2.3 M
I, [2018-08-23T16:57:42.290300 #4754]  INFO -- testing-formula-kitchen-centos-7:  salt-minion                        noarch 2018.3.2-1.el7  saltstack-repo  36 k
I, [2018-08-23T16:57:42.290324 #4754]  INFO -- testing-formula-kitchen-centos-7: Installing for dependencies:
I, [2018-08-23T16:57:42.290346 #4754]  INFO -- testing-formula-kitchen-centos-7:  PyYAML                             x86_64 3.11-1.el7      saltstack-repo 160 k
I, [2018-08-23T16:57:42.290371 #4754]  INFO -- testing-formula-kitchen-centos-7:  hwdata                             x86_64 0.252-8.8.el7   base           2.3 M
I, [2018-08-23T16:57:42.290396 #4754]  INFO -- testing-formula-kitchen-centos-7:  libsodium                          x86_64 1.0.16-1.el7    saltstack-repo 140 k
I, [2018-08-23T16:57:42.290417 #4754]  INFO -- testing-formula-kitchen-centos-7:  libtomcrypt                        x86_64 1.17-26.el7     extras         224 k
I, [2018-08-23T16:57:42.290438 #4754]  INFO -- testing-formula-kitchen-centos-7:  libtommath                         x86_64 0.42.0-6.el7    extras          36 k
I, [2018-08-23T16:57:42.290495 #4754]  INFO -- testing-formula-kitchen-centos-7:  libxml2-python                     x86_64 2.9.1-6.el7_2.3 base           247 k
I, [2018-08-23T16:57:42.290528 #4754]  INFO -- testing-formula-kitchen-centos-7:  libyaml                            x86_64 0.1.4-11.el7_0  base            55 k
I, [2018-08-23T16:57:42.290553 #4754]  INFO -- testing-formula-kitchen-centos-7:  openpgm                            x86_64 5.2.122-2.el7   saltstack-repo 172 k
I, [2018-08-23T16:57:42.290606 #4754]  INFO -- testing-formula-kitchen-centos-7:  pciutils                           x86_64 3.5.1-3.el7     base            93 k
I, [2018-08-23T16:57:42.290638 #4754]  INFO -- testing-formula-kitchen-centos-7:  pciutils-libs                      x86_64 3.5.1-3.el7     base            46 k
I, [2018-08-23T16:57:42.290662 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-babel                       noarch 0.9.6-8.el7     base           1.4 M
I, [2018-08-23T16:57:42.290687 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-backports                   x86_64 1.0-8.el7       base           5.8 k
I, [2018-08-23T16:57:42.290707 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-backports-ssl_match_hostname
I, [2018-08-23T16:57:42.290760 #4754]  INFO -- testing-formula-kitchen-centos-7:                              noarch 3.5.0.1-1.el7   base            13 k
I, [2018-08-23T16:57:42.290787 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-chardet                     noarch 2.2.1-1.el7_1   base           227 k
I, [2018-08-23T16:57:42.290807 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-futures                     noarch 3.0.3-1.el7     saltstack-repo  26 k
I, [2018-08-23T16:57:42.290830 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-ipaddress                   noarch 1.0.16-2.el7    base            34 k
I, [2018-08-23T16:57:42.290850 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-jinja2                      noarch 2.7.2-2.el7     base           515 k
I, [2018-08-23T16:57:42.290874 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-kitchen                     noarch 1.1.1-5.el7     base           267 k
I, [2018-08-23T16:57:42.290894 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-markupsafe                  x86_64 0.11-10.el7     base            25 k
I, [2018-08-23T16:57:42.290916 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-msgpack                     x86_64 0.4.6-1.el7     saltstack-repo  73 k
I, [2018-08-23T16:57:42.290936 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-psutil                      x86_64 2.2.1-1.el7     saltstack-repo 114 k
I, [2018-08-23T16:57:42.290959 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-requests                    noarch 2.6.0-1.el7_1   base            94 k
I, [2018-08-23T16:57:42.290979 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-six                         noarch 1.9.0-2.el7     base            29 k
I, [2018-08-23T16:57:42.291010 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-tornado                     x86_64 4.2.1-3.el7     base           640 k
I, [2018-08-23T16:57:42.291031 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-urllib3                     noarch 1.10.2-5.el7    base           102 k
I, [2018-08-23T16:57:42.291078 #4754]  INFO -- testing-formula-kitchen-centos-7:  python-zmq                         x86_64 15.3.0-2.el7    saltstack-repo 520 k
I, [2018-08-23T16:57:42.291103 #4754]  INFO -- testing-formula-kitchen-centos-7:  python2-crypto                     x86_64 2.6.1-15.el7    extras         477 k
I, [2018-08-23T16:57:42.291123 #4754]  INFO -- testing-formula-kitchen-centos-7:  salt                               noarch 2018.3.2-1.el7  saltstack-repo 8.9 M
I, [2018-08-23T16:57:42.291143 #4754]  INFO -- testing-formula-kitchen-centos-7:  systemd-python                     x86_64 219-57.el7_5.1  updates        128 k
I, [2018-08-23T16:57:42.291166 #4754]  INFO -- testing-formula-kitchen-centos-7:  which                              x86_64 2.20-7.el7      base            41 k
I, [2018-08-23T16:57:42.291186 #4754]  INFO -- testing-formula-kitchen-centos-7:  yum-utils                          noarch 1.1.31-46.el7_5 updates        120 k
I, [2018-08-23T16:57:42.291206 #4754]  INFO -- testing-formula-kitchen-centos-7:  zeromq                             x86_64 4.1.4-7.el7     saltstack-repo 556 k
I, [2018-08-23T16:57:42.291225 #4754]  INFO -- testing-formula-kitchen-centos-7: Updating for dependencies:
I, [2018-08-23T16:57:42.291245 #4754]  INFO -- testing-formula-kitchen-centos-7:  systemd                            x86_64 219-57.el7_5.1  updates        5.0 M
I, [2018-08-23T16:57:42.291264 #4754]  INFO -- testing-formula-kitchen-centos-7:  systemd-libs                       x86_64 219-57.el7_5.1  updates        402 k
I, [2018-08-23T16:57:42.291283 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:57:42.291301 #4754]  INFO -- testing-formula-kitchen-centos-7: Transaction Summary
I, [2018-08-23T16:57:42.291319 #4754]  INFO -- testing-formula-kitchen-centos-7: ================================================================================
I, [2018-08-23T16:57:42.291344 #4754]  INFO -- testing-formula-kitchen-centos-7: Install  2 Packages (+32 Dependent packages)
I, [2018-08-23T16:57:42.291370 #4754]  INFO -- testing-formula-kitchen-centos-7: Upgrade             (  2 Dependent packages)
I, [2018-08-23T16:57:42.291389 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:57:42.292181 #4754]  INFO -- testing-formula-kitchen-centos-7: Total download size: 25 M
I, [2018-08-23T16:57:42.292213 #4754]  INFO -- testing-formula-kitchen-centos-7: Downloading packages:
I, [2018-08-23T16:57:42.293961 #4754]  INFO -- testing-formula-kitchen-centos-7: Delta RPMs disabled because /usr/bin/applydeltarpm not installed.
I, [2018-08-23T16:57:45.921445 #4754]  INFO -- testing-formula-kitchen-centos-7: http://mirror.alpix.eu/centos/7.5.1804/os/x86_64/Packages/python-tornado-4.2.1-3.el7.x86_64.rpm: [Errno 14] HTTP Error 503 - Service Unavailable
I, [2018-08-23T16:57:45.927495 #4754]  INFO -- testing-formula-kitchen-centos-7: Trying other mirror.
I, [2018-08-23T16:57:57.600557 #4754]  INFO -- testing-formula-kitchen-centos-7: --------------------------------------------------------------------------------
I, [2018-08-23T16:57:57.600770 #4754]  INFO -- testing-formula-kitchen-centos-7: Total                                              1.7 MB/s |  25 MB  00:15     
I, [2018-08-23T16:57:57.686166 #4754]  INFO -- testing-formula-kitchen-centos-7: Running transaction check
I, [2018-08-23T16:57:57.731981 #4754]  INFO -- testing-formula-kitchen-centos-7: Running transaction test
I, [2018-08-23T16:57:57.833744 #4754]  INFO -- testing-formula-kitchen-centos-7: Transaction test succeeded
I, [2018-08-23T16:57:57.834365 #4754]  INFO -- testing-formula-kitchen-centos-7: Running transaction
I, [2018-08-23T16:57:58.535792 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-chardet-2.2.1-1.el7_1.noarch                         1/38 
I, [2018-08-23T16:57:58.821347 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-ipaddress-1.0.16-2.el7.noarch                        2/38 
I, [2018-08-23T16:58:01.899663 #4754]  INFO -- testing-formula-kitchen-centos-7:   Updating   : systemd-libs-219-57.el7_5.1.x86_64                          3/38 
I, [2018-08-23T16:58:03.479357 #4754]  INFO -- testing-formula-kitchen-centos-7:   Updating   : systemd-219-57.el7_5.1.x86_64                               4/38 
I, [2018-08-23T16:58:03.788401 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : systemd-python-219-57.el7_5.1.x86_64                        5/38 
I, [2018-08-23T16:58:04.062514 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-markupsafe-0.11-10.el7.x86_64                        6/38 
I, [2018-08-23T16:58:04.609598 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-six-1.9.0-2.el7.noarch                               7/38 
I, [2018-08-23T16:58:05.815020 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : hwdata-0.252-8.8.el7.x86_64                                 8/38 
I, [2018-08-23T16:58:06.970167 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-kitchen-1.1.1-5.el7.noarch                           9/38 
I, [2018-08-23T16:58:07.665100 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : libsodium-1.0.16-1.el7.x86_64                              10/38 
I, [2018-08-23T16:58:08.319117 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : libyaml-0.1.4-11.el7_0.x86_64                              11/38 
I, [2018-08-23T16:58:08.570184 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : PyYAML-3.11-1.el7.x86_64                                   12/38 
I, [2018-08-23T16:58:09.258223 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : libtommath-0.42.0-6.el7.x86_64                             13/38 
I, [2018-08-23T16:58:10.052317 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : libtomcrypt-1.17-26.el7.x86_64                             14/38 
I, [2018-08-23T16:58:10.408150 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python2-crypto-2.6.1-15.el7.x86_64                         15/38 
I, [2018-08-23T16:58:10.705365 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-backports-1.0-8.el7.x86_64                          16/38 
I, [2018-08-23T16:58:11.021457 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-backports-ssl_match_hostname-3.5.0.1-1.el7.noarch   17/38 
I, [2018-08-23T16:58:11.367351 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-urllib3-1.10.2-5.el7.noarch                         18/38 
I, [2018-08-23T16:58:11.764913 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-requests-2.6.0-1.el7_1.noarch                       19/38 
I, [2018-08-23T16:58:12.109364 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-tornado-4.2.1-3.el7.x86_64                          20/38 
I, [2018-08-23T16:58:12.451344 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-msgpack-0.4.6-1.el7.x86_64                          21/38 
I, [2018-08-23T16:58:12.808090 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : libxml2-python-2.9.1-6.el7_2.3.x86_64                      22/38 
I, [2018-08-23T16:58:13.368890 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : yum-utils-1.1.31-46.el7_5.noarch                           23/38 
I, [2018-08-23T16:58:13.824829 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-babel-0.9.6-8.el7.noarch                            24/38 
I, [2018-08-23T16:58:14.181168 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-jinja2-2.7.2-2.el7.noarch                           25/38 
I, [2018-08-23T16:58:14.863570 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : openpgm-5.2.122-2.el7.x86_64                               26/38 
I, [2018-08-23T16:58:15.543798 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : zeromq-4.1.4-7.el7.x86_64                                  27/38 
I, [2018-08-23T16:58:15.926242 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-zmq-15.3.0-2.el7.x86_64                             28/38 
I, [2018-08-23T16:58:16.257424 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-psutil-2.2.1-1.el7.x86_64                           29/38 
I, [2018-08-23T16:58:16.877204 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : which-2.20-7.el7.x86_64                                    30/38 
I, [2018-08-23T16:58:17.517345 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : pciutils-libs-3.5.1-3.el7.x86_64                           31/38 
I, [2018-08-23T16:58:17.864179 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : pciutils-3.5.1-3.el7.x86_64                                32/38 
I, [2018-08-23T16:58:19.552606 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : python-futures-3.0.3-1.el7.noarch                          33/38 
I, [2018-08-23T16:58:20.020657 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : salt-2018.3.2-1.el7.noarch                                 34/38 
I, [2018-08-23T16:58:20.702844 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : salt-master-2018.3.2-1.el7.noarch                          35/38 
I, [2018-08-23T16:58:23.274515 #4754]  INFO -- testing-formula-kitchen-centos-7:   Installing : salt-minion-2018.3.2-1.el7.noarch                          36/38 
I, [2018-08-23T16:58:23.964992 #4754]  INFO -- testing-formula-kitchen-centos-7:   Cleanup    : systemd-219-57.el7.x86_64                                  37/38 
I, [2018-08-23T16:58:25.034870 #4754]  INFO -- testing-formula-kitchen-centos-7:   Cleanup    : systemd-libs-219-57.el7.x86_64                             38/38 
I, [2018-08-23T16:58:25.193183 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-jinja2-2.7.2-2.el7.noarch                            1/38 
I, [2018-08-23T16:58:25.329864 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-backports-ssl_match_hostname-3.5.0.1-1.el7.noarch    2/38 
I, [2018-08-23T16:58:25.442731 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : systemd-219-57.el7_5.1.x86_64                               3/38 
I, [2018-08-23T16:58:25.576308 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-futures-3.0.3-1.el7.noarch                           4/38 
I, [2018-08-23T16:58:25.731314 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : pciutils-libs-3.5.1-3.el7.x86_64                            5/38 
I, [2018-08-23T16:58:25.866272 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : zeromq-4.1.4-7.el7.x86_64                                   6/38 
I, [2018-08-23T16:58:25.984872 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : yum-utils-1.1.31-46.el7_5.noarch                            7/38 
I, [2018-08-23T16:58:26.129011 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : which-2.20-7.el7.x86_64                                     8/38 
I, [2018-08-23T16:58:26.264791 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-psutil-2.2.1-1.el7.x86_64                            9/38 
I, [2018-08-23T16:58:26.386481 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : openpgm-5.2.122-2.el7.x86_64                               10/38 
I, [2018-08-23T16:58:26.529305 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : salt-master-2018.3.2-1.el7.noarch                          11/38 
I, [2018-08-23T16:58:26.681909 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : PyYAML-3.11-1.el7.x86_64                                   12/38 
I, [2018-08-23T16:58:26.801064 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-babel-0.9.6-8.el7.noarch                            13/38 
I, [2018-08-23T16:58:26.929992 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-six-1.9.0-2.el7.noarch                              14/38 
I, [2018-08-23T16:58:27.082228 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-urllib3-1.10.2-5.el7.noarch                         15/38 
I, [2018-08-23T16:58:27.209521 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-tornado-4.2.1-3.el7.x86_64                          16/38 
I, [2018-08-23T16:58:27.331265 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-markupsafe-0.11-10.el7.x86_64                       17/38 
I, [2018-08-23T16:58:27.473632 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : systemd-libs-219-57.el7_5.1.x86_64                         18/38 
I, [2018-08-23T16:58:27.609833 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : libxml2-python-2.9.1-6.el7_2.3.x86_64                      19/38 
I, [2018-08-23T16:58:27.722620 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-msgpack-0.4.6-1.el7.x86_64                          20/38 
I, [2018-08-23T16:58:27.857212 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-backports-1.0-8.el7.x86_64                          21/38 
I, [2018-08-23T16:58:28.010000 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : salt-minion-2018.3.2-1.el7.noarch                          22/38 
I, [2018-08-23T16:58:28.122180 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : libtommath-0.42.0-6.el7.x86_64                             23/38 
I, [2018-08-23T16:58:28.259768 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : salt-2018.3.2-1.el7.noarch                                 24/38 
I, [2018-08-23T16:58:28.395669 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : hwdata-0.252-8.8.el7.x86_64                                25/38 
I, [2018-08-23T16:58:28.506372 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : libyaml-0.1.4-11.el7_0.x86_64                              26/38 
I, [2018-08-23T16:58:28.642155 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : libsodium-1.0.16-1.el7.x86_64                              27/38 
I, [2018-08-23T16:58:28.796444 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python2-crypto-2.6.1-15.el7.x86_64                         28/38 
I, [2018-08-23T16:58:28.915266 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-requests-2.6.0-1.el7_1.noarch                       29/38 
I, [2018-08-23T16:58:29.025900 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-zmq-15.3.0-2.el7.x86_64                             30/38 
I, [2018-08-23T16:58:29.169897 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-ipaddress-1.0.16-2.el7.noarch                       31/38 
I, [2018-08-23T16:58:29.290162 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-chardet-2.2.1-1.el7_1.noarch                        32/38 
I, [2018-08-23T16:58:29.409414 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : python-kitchen-1.1.1-5.el7.noarch                          33/38 
I, [2018-08-23T16:58:29.562612 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : systemd-python-219-57.el7_5.1.x86_64                       34/38 
I, [2018-08-23T16:58:29.716306 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : pciutils-3.5.1-3.el7.x86_64                                35/38 
I, [2018-08-23T16:58:29.844185 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : libtomcrypt-1.17-26.el7.x86_64                             36/38 
I, [2018-08-23T16:58:29.845614 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : systemd-219-57.el7.x86_64                                  37/38 
I, [2018-08-23T16:58:30.154510 #4754]  INFO -- testing-formula-kitchen-centos-7:   Verifying  : systemd-libs-219-57.el7.x86_64                             38/38 
I, [2018-08-23T16:58:30.154629 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:30.154673 #4754]  INFO -- testing-formula-kitchen-centos-7: Installed:
I, [2018-08-23T16:58:30.154714 #4754]  INFO -- testing-formula-kitchen-centos-7:   salt-master.noarch 0:2018.3.2-1.el7    salt-minion.noarch 0:2018.3.2-1.el7   
I, [2018-08-23T16:58:30.154761 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:30.154805 #4754]  INFO -- testing-formula-kitchen-centos-7: Dependency Installed:
I, [2018-08-23T16:58:30.154845 #4754]  INFO -- testing-formula-kitchen-centos-7:   PyYAML.x86_64 0:3.11-1.el7                                                    
I, [2018-08-23T16:58:30.154886 #4754]  INFO -- testing-formula-kitchen-centos-7:   hwdata.x86_64 0:0.252-8.8.el7                                                 
I, [2018-08-23T16:58:30.154926 #4754]  INFO -- testing-formula-kitchen-centos-7:   libsodium.x86_64 0:1.0.16-1.el7                                               
I, [2018-08-23T16:58:30.154977 #4754]  INFO -- testing-formula-kitchen-centos-7:   libtomcrypt.x86_64 0:1.17-26.el7                                              
I, [2018-08-23T16:58:30.155018 #4754]  INFO -- testing-formula-kitchen-centos-7:   libtommath.x86_64 0:0.42.0-6.el7                                              
I, [2018-08-23T16:58:30.155065 #4754]  INFO -- testing-formula-kitchen-centos-7:   libxml2-python.x86_64 0:2.9.1-6.el7_2.3                                       
I, [2018-08-23T16:58:30.155112 #4754]  INFO -- testing-formula-kitchen-centos-7:   libyaml.x86_64 0:0.1.4-11.el7_0                                               
I, [2018-08-23T16:58:30.155160 #4754]  INFO -- testing-formula-kitchen-centos-7:   openpgm.x86_64 0:5.2.122-2.el7                                                
I, [2018-08-23T16:58:30.155201 #4754]  INFO -- testing-formula-kitchen-centos-7:   pciutils.x86_64 0:3.5.1-3.el7                                                 
I, [2018-08-23T16:58:30.155247 #4754]  INFO -- testing-formula-kitchen-centos-7:   pciutils-libs.x86_64 0:3.5.1-3.el7                                            
I, [2018-08-23T16:58:30.155287 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-babel.noarch 0:0.9.6-8.el7                                             
I, [2018-08-23T16:58:30.155338 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-backports.x86_64 0:1.0-8.el7                                           
I, [2018-08-23T16:58:30.155404 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-backports-ssl_match_hostname.noarch 0:3.5.0.1-1.el7                    
I, [2018-08-23T16:58:30.155446 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-chardet.noarch 0:2.2.1-1.el7_1                                         
I, [2018-08-23T16:58:30.155488 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-futures.noarch 0:3.0.3-1.el7                                           
I, [2018-08-23T16:58:30.155545 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-ipaddress.noarch 0:1.0.16-2.el7                                        
I, [2018-08-23T16:58:30.155569 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-jinja2.noarch 0:2.7.2-2.el7                                            
I, [2018-08-23T16:58:30.155618 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-kitchen.noarch 0:1.1.1-5.el7                                           
I, [2018-08-23T16:58:30.155661 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-markupsafe.x86_64 0:0.11-10.el7                                        
I, [2018-08-23T16:58:30.155708 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-msgpack.x86_64 0:0.4.6-1.el7                                           
I, [2018-08-23T16:58:30.155744 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-psutil.x86_64 0:2.2.1-1.el7                                            
I, [2018-08-23T16:58:30.155787 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-requests.noarch 0:2.6.0-1.el7_1                                        
I, [2018-08-23T16:58:30.155844 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-six.noarch 0:1.9.0-2.el7                                               
I, [2018-08-23T16:58:30.155872 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-tornado.x86_64 0:4.2.1-3.el7                                           
I, [2018-08-23T16:58:30.155914 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-urllib3.noarch 0:1.10.2-5.el7                                          
I, [2018-08-23T16:58:30.155948 #4754]  INFO -- testing-formula-kitchen-centos-7:   python-zmq.x86_64 0:15.3.0-2.el7                                              
I, [2018-08-23T16:58:30.155984 #4754]  INFO -- testing-formula-kitchen-centos-7:   python2-crypto.x86_64 0:2.6.1-15.el7                                          
I, [2018-08-23T16:58:30.156030 #4754]  INFO -- testing-formula-kitchen-centos-7:   salt.noarch 0:2018.3.2-1.el7                                                  
I, [2018-08-23T16:58:30.156061 #4754]  INFO -- testing-formula-kitchen-centos-7:   systemd-python.x86_64 0:219-57.el7_5.1                                        
I, [2018-08-23T16:58:30.156109 #4754]  INFO -- testing-formula-kitchen-centos-7:   which.x86_64 0:2.20-7.el7                                                     
I, [2018-08-23T16:58:30.156190 #4754]  INFO -- testing-formula-kitchen-centos-7:   yum-utils.noarch 0:1.1.31-46.el7_5                                            
I, [2018-08-23T16:58:30.156234 #4754]  INFO -- testing-formula-kitchen-centos-7:   zeromq.x86_64 0:4.1.4-7.el7                                                   
I, [2018-08-23T16:58:30.156270 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:30.156305 #4754]  INFO -- testing-formula-kitchen-centos-7: Dependency Updated:
I, [2018-08-23T16:58:30.156340 #4754]  INFO -- testing-formula-kitchen-centos-7:   systemd.x86_64 0:219-57.el7_5.1      systemd-libs.x86_64 0:219-57.el7_5.1     
I, [2018-08-23T16:58:30.156378 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:30.159686 #4754]  INFO -- testing-formula-kitchen-centos-7: Complete!
I, [2018-08-23T16:58:30.762120 #4754]  INFO -- testing-formula-kitchen-centos-7: You asked for 2018.3.2 and you have 2018.3.2 installed, sweet!
I, [2018-08-23T16:58:31.044858 #4754]  INFO -- testing-formula-kitchen-centos-7: Transferring files to <testing-formula-kitchen-centos-7>
I, [2018-08-23T16:58:32.215189 #4754]  INFO -- testing-formula-kitchen-centos-7: Install External Dependencies
I, [2018-08-23T16:58:32.230373 #4754]  INFO -- testing-formula-kitchen-centos-7: Content of /tmp/kitchen//srv/salt :
I, [2018-08-23T16:58:32.231488 #4754]  INFO -- testing-formula-kitchen-centos-7: total 24
I, [2018-08-23T16:58:32.231564 #4754]  INFO -- testing-formula-kitchen-centos-7: drwxr-xr-x 5 root    kitchen 4096 Aug 23 14:58 .
I, [2018-08-23T16:58:32.231609 #4754]  INFO -- testing-formula-kitchen-centos-7: drwxr-xr-x 4 kitchen kitchen 4096 Aug 23 14:58 ..
I, [2018-08-23T16:58:32.231649 #4754]  INFO -- testing-formula-kitchen-centos-7: drwxr-xr-x 2 root    kitchen 4096 Aug 23 14:58 _modules
I, [2018-08-23T16:58:32.231687 #4754]  INFO -- testing-formula-kitchen-centos-7: drwxr-xr-x 2 root    kitchen 4096 Aug 23 14:58 _states
I, [2018-08-23T16:58:32.231724 #4754]  INFO -- testing-formula-kitchen-centos-7: drwxr-xr-x 3 root    kitchen 4096 Aug 23 14:58 testing
I, [2018-08-23T16:58:32.231762 #4754]  INFO -- testing-formula-kitchen-centos-7: -rw-r--r-- 1 root    kitchen   38 Aug 23 14:58 top.sls
I, [2018-08-23T16:58:40.961249 #4754]  INFO -- testing-formula-kitchen-centos-7: [ERROR   ] Rendering exception occurred
I, [2018-08-23T16:58:40.961331 #4754]  INFO -- testing-formula-kitchen-centos-7: Traceback (most recent call last):
I, [2018-08-23T16:58:40.961356 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 170, in render_tmpl
I, [2018-08-23T16:58:40.961381 #4754]  INFO -- testing-formula-kitchen-centos-7:     output = render_str(tmplstr, context, tmplpath)
I, [2018-08-23T16:58:40.961403 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 438, in render_jinja_tmpl
I, [2018-08-23T16:58:40.961423 #4754]  INFO -- testing-formula-kitchen-centos-7:     trace=tracestr)
I, [2018-08-23T16:58:40.961446 #4754]  INFO -- testing-formula-kitchen-centos-7: SaltRenderError: Jinja error: invalid arguments to setopt
I, [2018-08-23T16:58:40.961466 #4754]  INFO -- testing-formula-kitchen-centos-7: Traceback (most recent call last):
I, [2018-08-23T16:58:40.961488 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 389, in render_jinja_tmpl
I, [2018-08-23T16:58:40.961509 #4754]  INFO -- testing-formula-kitchen-centos-7:     output = template.render(**decoded_context)
I, [2018-08-23T16:58:40.961529 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 969, in render
I, [2018-08-23T16:58:40.961549 #4754]  INFO -- testing-formula-kitchen-centos-7:     return self.environment.handle_exception(exc_info, True)
I, [2018-08-23T16:58:40.961570 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 742, in handle_exception
I, [2018-08-23T16:58:40.961594 #4754]  INFO -- testing-formula-kitchen-centos-7:     reraise(exc_type, exc_value, tb)
I, [2018-08-23T16:58:40.961614 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "<template>", line 4, in top-level template code
I, [2018-08-23T16:58:40.961635 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/modules/cp.py", line 500, in cache_file
I, [2018-08-23T16:58:40.961659 #4754]  INFO -- testing-formula-kitchen-centos-7:     result = _client().cache_file(path, saltenv, source_hash=source_hash)
I, [2018-08-23T16:58:40.961679 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/fileclient.py", line 193, in cache_file
I, [2018-08-23T16:58:40.961703 #4754]  INFO -- testing-formula-kitchen-centos-7:     path, '', True, saltenv, cachedir=cachedir, source_hash=source_hash)
I, [2018-08-23T16:58:40.961724 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/fileclient.py", line 734, in get_url
I, [2018-08-23T16:58:40.961743 #4754]  INFO -- testing-formula-kitchen-centos-7:     **get_kwargs
I, [2018-08-23T16:58:40.961777 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/http.py", line 542, in query
I, [2018-08-23T16:58:40.961798 #4754]  INFO -- testing-formula-kitchen-centos-7:     **req_kwargs
I, [2018-08-23T16:58:40.961817 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/httpclient.py", line 102, in fetch
I, [2018-08-23T16:58:40.961836 #4754]  INFO -- testing-formula-kitchen-centos-7:     self._async_client.fetch, request, **kwargs))
I, [2018-08-23T16:58:40.961860 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/ioloop.py", line 444, in run_sync
I, [2018-08-23T16:58:40.961879 #4754]  INFO -- testing-formula-kitchen-centos-7:     return future_cell[0].result()
I, [2018-08-23T16:58:40.961898 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/concurrent.py", line 214, in result
I, [2018-08-23T16:58:40.961917 #4754]  INFO -- testing-formula-kitchen-centos-7:     raise_exc_info(self._exc_info)
I, [2018-08-23T16:58:40.961936 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "<string>", line 3, in raise_exc_info
I, [2018-08-23T16:58:40.961955 #4754]  INFO -- testing-formula-kitchen-centos-7: TypeError: invalid arguments to setopt
I, [2018-08-23T16:58:40.961977 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.961997 #4754]  INFO -- testing-formula-kitchen-centos-7: ; line 4
I, [2018-08-23T16:58:40.962015 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.962033 #4754]  INFO -- testing-formula-kitchen-centos-7: ---
I, [2018-08-23T16:58:40.962051 #4754]  INFO -- testing-formula-kitchen-centos-7: # vim: ft=sls
I, [2018-08-23T16:58:40.962070 #4754]  INFO -- testing-formula-kitchen-centos-7: {% from "testing/map.sls" import testing with context %}
I, [2018-08-23T16:58:40.962093 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.962112 #4754]  INFO -- testing-formula-kitchen-centos-7: {{ salt.cp.cache_file(testing.download_url) }}    <======================
I, [2018-08-23T16:58:40.962131 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.962149 #4754]  INFO -- testing-formula-kitchen-centos-7: Traceback (most recent call last):
I, [2018-08-23T16:58:40.962168 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 389, in render_jinja_tmpl
I, [2018-08-23T16:58:40.962188 #4754]  INFO -- testing-formula-kitchen-centos-7:     output = template.render(**decoded_context)
I, [2018-08-23T16:58:40.962210 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 969, in render
I, [2018-08-23T16:58:40.962229 #4754]  INFO -- testing-formula-kitchen-centos-7: [...]
I, [2018-08-23T16:58:40.962247 #4754]  INFO -- testing-formula-kitchen-centos-7: ---
I, [2018-08-23T16:58:40.962986 #4754]  INFO -- testing-formula-kitchen-centos-7: [CRITICAL] Rendering SLS 'base:testing.download' failed: Jinja error: invalid arguments to setopt
I, [2018-08-23T16:58:40.963019 #4754]  INFO -- testing-formula-kitchen-centos-7: Traceback (most recent call last):
I, [2018-08-23T16:58:40.963045 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 389, in render_jinja_tmpl
I, [2018-08-23T16:58:40.963065 #4754]  INFO -- testing-formula-kitchen-centos-7:     output = template.render(**decoded_context)
I, [2018-08-23T16:58:40.963085 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 969, in render
I, [2018-08-23T16:58:40.963104 #4754]  INFO -- testing-formula-kitchen-centos-7:     return self.environment.handle_exception(exc_info, True)
I, [2018-08-23T16:58:40.963124 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 742, in handle_exception
I, [2018-08-23T16:58:40.963153 #4754]  INFO -- testing-formula-kitchen-centos-7:     reraise(exc_type, exc_value, tb)
I, [2018-08-23T16:58:40.963174 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "<template>", line 4, in top-level template code
I, [2018-08-23T16:58:40.963195 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/modules/cp.py", line 500, in cache_file
I, [2018-08-23T16:58:40.963215 #4754]  INFO -- testing-formula-kitchen-centos-7:     result = _client().cache_file(path, saltenv, source_hash=source_hash)
I, [2018-08-23T16:58:40.963234 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/fileclient.py", line 193, in cache_file
I, [2018-08-23T16:58:40.963252 #4754]  INFO -- testing-formula-kitchen-centos-7:     path, '', True, saltenv, cachedir=cachedir, source_hash=source_hash)
I, [2018-08-23T16:58:40.963275 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/fileclient.py", line 734, in get_url
I, [2018-08-23T16:58:40.963295 #4754]  INFO -- testing-formula-kitchen-centos-7:     **get_kwargs
I, [2018-08-23T16:58:40.963313 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/http.py", line 542, in query
I, [2018-08-23T16:58:40.963338 #4754]  INFO -- testing-formula-kitchen-centos-7:     **req_kwargs
I, [2018-08-23T16:58:40.963358 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/httpclient.py", line 102, in fetch
I, [2018-08-23T16:58:40.963377 #4754]  INFO -- testing-formula-kitchen-centos-7:     self._async_client.fetch, request, **kwargs))
I, [2018-08-23T16:58:40.963399 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/ioloop.py", line 444, in run_sync
I, [2018-08-23T16:58:40.963419 #4754]  INFO -- testing-formula-kitchen-centos-7:     return future_cell[0].result()
I, [2018-08-23T16:58:40.963438 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/concurrent.py", line 214, in result
I, [2018-08-23T16:58:40.963457 #4754]  INFO -- testing-formula-kitchen-centos-7:     raise_exc_info(self._exc_info)
I, [2018-08-23T16:58:40.963476 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "<string>", line 3, in raise_exc_info
I, [2018-08-23T16:58:40.963498 #4754]  INFO -- testing-formula-kitchen-centos-7: TypeError: invalid arguments to setopt
I, [2018-08-23T16:58:40.963517 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.963536 #4754]  INFO -- testing-formula-kitchen-centos-7: ; line 4
I, [2018-08-23T16:58:40.963554 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.963571 #4754]  INFO -- testing-formula-kitchen-centos-7: ---
I, [2018-08-23T16:58:40.963593 #4754]  INFO -- testing-formula-kitchen-centos-7: # vim: ft=sls
I, [2018-08-23T16:58:40.963612 #4754]  INFO -- testing-formula-kitchen-centos-7: {% from "testing/map.sls" import testing with context %}
I, [2018-08-23T16:58:40.963631 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.963649 #4754]  INFO -- testing-formula-kitchen-centos-7: {{ salt.cp.cache_file(testing.download_url) }}    <======================
I, [2018-08-23T16:58:40.963667 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.963689 #4754]  INFO -- testing-formula-kitchen-centos-7: Traceback (most recent call last):
I, [2018-08-23T16:58:40.963708 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 389, in render_jinja_tmpl
I, [2018-08-23T16:58:40.963746 #4754]  INFO -- testing-formula-kitchen-centos-7:     output = template.render(**decoded_context)
I, [2018-08-23T16:58:40.963767 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 969, in render
I, [2018-08-23T16:58:40.963791 #4754]  INFO -- testing-formula-kitchen-centos-7: [...]
I, [2018-08-23T16:58:40.963810 #4754]  INFO -- testing-formula-kitchen-centos-7: ---
I, [2018-08-23T16:58:40.964357 #4754]  INFO -- testing-formula-kitchen-centos-7: local:
I, [2018-08-23T16:58:40.964385 #4754]  INFO -- testing-formula-kitchen-centos-7:     Data failed to compile:
I, [2018-08-23T16:58:40.964405 #4754]  INFO -- testing-formula-kitchen-centos-7: ----------
I, [2018-08-23T16:58:40.964427 #4754]  INFO -- testing-formula-kitchen-centos-7:     Rendering SLS 'base:testing.download' failed: Jinja error: invalid arguments to setopt
I, [2018-08-23T16:58:40.964447 #4754]  INFO -- testing-formula-kitchen-centos-7: Traceback (most recent call last):
I, [2018-08-23T16:58:40.964467 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 389, in render_jinja_tmpl
I, [2018-08-23T16:58:40.964491 #4754]  INFO -- testing-formula-kitchen-centos-7:     output = template.render(**decoded_context)
I, [2018-08-23T16:58:40.964511 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 969, in render
I, [2018-08-23T16:58:40.964532 #4754]  INFO -- testing-formula-kitchen-centos-7:     return self.environment.handle_exception(exc_info, True)
I, [2018-08-23T16:58:40.964551 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 742, in handle_exception
I, [2018-08-23T16:58:40.964573 #4754]  INFO -- testing-formula-kitchen-centos-7:     reraise(exc_type, exc_value, tb)
I, [2018-08-23T16:58:40.964592 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "<template>", line 4, in top-level template code
I, [2018-08-23T16:58:40.964611 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/modules/cp.py", line 500, in cache_file
I, [2018-08-23T16:58:40.964630 #4754]  INFO -- testing-formula-kitchen-centos-7:     result = _client().cache_file(path, saltenv, source_hash=source_hash)
I, [2018-08-23T16:58:40.964653 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/fileclient.py", line 193, in cache_file
I, [2018-08-23T16:58:40.964673 #4754]  INFO -- testing-formula-kitchen-centos-7:     path, '', True, saltenv, cachedir=cachedir, source_hash=source_hash)
I, [2018-08-23T16:58:40.964692 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/fileclient.py", line 734, in get_url
I, [2018-08-23T16:58:40.964715 #4754]  INFO -- testing-formula-kitchen-centos-7:     **get_kwargs
I, [2018-08-23T16:58:40.964734 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/http.py", line 542, in query
I, [2018-08-23T16:58:40.964756 #4754]  INFO -- testing-formula-kitchen-centos-7:     **req_kwargs
I, [2018-08-23T16:58:40.964775 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/httpclient.py", line 102, in fetch
I, [2018-08-23T16:58:40.964794 #4754]  INFO -- testing-formula-kitchen-centos-7:     self._async_client.fetch, request, **kwargs))
I, [2018-08-23T16:58:40.964817 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/ioloop.py", line 444, in run_sync
I, [2018-08-23T16:58:40.964837 #4754]  INFO -- testing-formula-kitchen-centos-7:     return future_cell[0].result()
I, [2018-08-23T16:58:40.964856 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib64/python2.7/site-packages/tornado/concurrent.py", line 214, in result
I, [2018-08-23T16:58:40.964875 #4754]  INFO -- testing-formula-kitchen-centos-7:     raise_exc_info(self._exc_info)
I, [2018-08-23T16:58:40.964894 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "<string>", line 3, in raise_exc_info
I, [2018-08-23T16:58:40.964916 #4754]  INFO -- testing-formula-kitchen-centos-7: TypeError: invalid arguments to setopt
I, [2018-08-23T16:58:40.964935 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.964960 #4754]  INFO -- testing-formula-kitchen-centos-7: ; line 4
I, [2018-08-23T16:58:40.964979 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.964997 #4754]  INFO -- testing-formula-kitchen-centos-7: ---
I, [2018-08-23T16:58:40.965045 #4754]  INFO -- testing-formula-kitchen-centos-7: # vim: ft=sls
I, [2018-08-23T16:58:40.965067 #4754]  INFO -- testing-formula-kitchen-centos-7: {% from "testing/map.sls" import testing with context %}
I, [2018-08-23T16:58:40.965087 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.965105 #4754]  INFO -- testing-formula-kitchen-centos-7: {{ salt.cp.cache_file(testing.download_url) }}    <======================
I, [2018-08-23T16:58:40.965124 #4754]  INFO -- testing-formula-kitchen-centos-7: 
I, [2018-08-23T16:58:40.965142 #4754]  INFO -- testing-formula-kitchen-centos-7: Traceback (most recent call last):
I, [2018-08-23T16:58:40.965160 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/salt/utils/templates.py", line 389, in render_jinja_tmpl
I, [2018-08-23T16:58:40.965179 #4754]  INFO -- testing-formula-kitchen-centos-7:     output = template.render(**decoded_context)
I, [2018-08-23T16:58:40.965198 #4754]  INFO -- testing-formula-kitchen-centos-7:   File "/usr/lib/python2.7/site-packages/jinja2/environment.py", line 969, in render
I, [2018-08-23T16:58:40.965216 #4754]  INFO -- testing-formula-kitchen-centos-7: [...]
I, [2018-08-23T16:58:40.965234 #4754]  INFO -- testing-formula-kitchen-centos-7: ---
E, [2018-08-23T16:58:41.025239 #4754] ERROR -- testing-formula-kitchen-centos-7: Converge failed on instance <testing-formula-kitchen-centos-7>.
E, [2018-08-23T16:58:41.025336 #4754] ERROR -- testing-formula-kitchen-centos-7: ------Exception-------
E, [2018-08-23T16:58:41.025357 #4754] ERROR -- testing-formula-kitchen-centos-7: Class: Kitchen::ActionFailed
E, [2018-08-23T16:58:41.025372 #4754] ERROR -- testing-formula-kitchen-centos-7: Message: The command lxc exec testing-formula-kitchen-centos-7-dc26c64a4071c187 -- su root -c bash\ -c\ sudo\\\ -E\\\ chmod\\\ \\\+x\\\ /tmp/kitchen/\\\*.sh\\\;sudo\\\ -E\\\ /tmp/kitchen/dependencies.sh\\\;sudo\\\ -E\\\ salt-call\\\ --state-output\\\=changes\\\ --config-dir\\\=/tmp/kitchen/etc/salt\\\ state.highstate\\\ --log-level\\\=error\\\ --retcode-passthrough failed with exit code: 1
E, [2018-08-23T16:58:41.025388 #4754] ERROR -- testing-formula-kitchen-centos-7: ----------------------
E, [2018-08-23T16:58:41.025402 #4754] ERROR -- testing-formula-kitchen-centos-7: ------Backtrace-------
E, [2018-08-23T16:58:41.025417 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/provisioner/base.rb:91:in `rescue in call'
E, [2018-08-23T16:58:41.025432 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/provisioner/base.rb:67:in `call'
E, [2018-08-23T16:58:41.025448 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:393:in `block in converge_action'
E, [2018-08-23T16:58:41.025463 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:536:in `synchronize_or_call'
E, [2018-08-23T16:58:41.025478 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:498:in `block in action'
E, [2018-08-23T16:58:41.025493 #4754] ERROR -- testing-formula-kitchen-centos-7: /usr/lib/ruby/2.5.0/benchmark.rb:293:in `measure'
E, [2018-08-23T16:58:41.025508 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:497:in `action'
E, [2018-08-23T16:58:41.025527 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:389:in `converge_action'
E, [2018-08-23T16:58:41.025543 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:368:in `block in transition_to'
E, [2018-08-23T16:58:41.025568 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:367:in `each'
E, [2018-08-23T16:58:41.025584 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:367:in `transition_to'
E, [2018-08-23T16:58:41.025600 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:135:in `converge'
E, [2018-08-23T16:58:41.025615 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/command.rb:197:in `public_send'
E, [2018-08-23T16:58:41.025630 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/command.rb:197:in `run_action_in_thread'
E, [2018-08-23T16:58:41.025645 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/command.rb:169:in `block (2 levels) in run_action'
E, [2018-08-23T16:58:41.025661 #4754] ERROR -- testing-formula-kitchen-centos-7: ----End Backtrace-----
E, [2018-08-23T16:58:41.025675 #4754] ERROR -- testing-formula-kitchen-centos-7: ---Nested Exception---
E, [2018-08-23T16:58:41.025689 #4754] ERROR -- testing-formula-kitchen-centos-7: Class: Kitchen::Transport::TransportFailed
E, [2018-08-23T16:58:41.025704 #4754] ERROR -- testing-formula-kitchen-centos-7: Message: The command lxc exec testing-formula-kitchen-centos-7-dc26c64a4071c187 -- su root -c bash\ -c\ sudo\\\ -E\\\ chmod\\\ \\\+x\\\ /tmp/kitchen/\\\*.sh\\\;sudo\\\ -E\\\ /tmp/kitchen/dependencies.sh\\\;sudo\\\ -E\\\ salt-call\\\ --state-output\\\=changes\\\ --config-dir\\\=/tmp/kitchen/etc/salt\\\ state.highstate\\\ --log-level\\\=error\\\ --retcode-passthrough failed with exit code: 1
E, [2018-08-23T16:58:41.025719 #4754] ERROR -- testing-formula-kitchen-centos-7: ----------------------
E, [2018-08-23T16:58:41.025736 #4754] ERROR -- testing-formula-kitchen-centos-7: ------Backtrace-------
E, [2018-08-23T16:58:41.025751 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/provisioner/base.rb:91:in `rescue in call'
E, [2018-08-23T16:58:41.025766 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/provisioner/base.rb:67:in `call'
E, [2018-08-23T16:58:41.025780 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:393:in `block in converge_action'
E, [2018-08-23T16:58:41.025795 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:536:in `synchronize_or_call'
E, [2018-08-23T16:58:41.025850 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:498:in `block in action'
E, [2018-08-23T16:58:41.025912 #4754] ERROR -- testing-formula-kitchen-centos-7: /usr/lib/ruby/2.5.0/benchmark.rb:293:in `measure'
E, [2018-08-23T16:58:41.025949 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:497:in `action'
E, [2018-08-23T16:58:41.025970 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:389:in `converge_action'
E, [2018-08-23T16:58:41.025987 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:368:in `block in transition_to'
E, [2018-08-23T16:58:41.026006 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:367:in `each'
E, [2018-08-23T16:58:41.026022 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:367:in `transition_to'
E, [2018-08-23T16:58:41.026043 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/instance.rb:135:in `converge'
E, [2018-08-23T16:58:41.026060 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/command.rb:197:in `public_send'
E, [2018-08-23T16:58:41.026074 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/command.rb:197:in `run_action_in_thread'
E, [2018-08-23T16:58:41.026089 #4754] ERROR -- testing-formula-kitchen-centos-7: /home/gd/.gem/ruby/2.5.0/gems/test-kitchen-1.21.2/lib/kitchen/command.rb:169:in `block (2 levels) in run_action'
E, [2018-08-23T16:58:41.026104 #4754] ERROR -- testing-formula-kitchen-centos-7: ----End Backtrace-----
gtmanfred commented 6 years ago

Yes, that would have been helpful to know.

gtmanfred commented 6 years ago

I have replicated this issue with the following docker compose setup.

https://github.com/gtmanfred/salt-docker-demo/tree/issue49275

Thanks for reporting. Daniel

rallytime commented 6 years ago

@terminalmage Can you fix this for 2018.3.4?

terminalmage commented 6 years ago

Fixed in https://github.com/saltstack/salt/pull/50080

dgengtek commented 6 years ago

The patch works. Thank you!