2017-09-15 08:47:25,487 [salt.cli.daemons ][WARNING ][2540] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-15 08:47:29,588 [salt.loaded.int.module.cmdmod][ERROR   ][2883] Command '['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'bridge-utils']' failed with return code: 100
2017-09-15 08:47:29,589 [salt.loaded.int.module.cmdmod][ERROR   ][2883] stderr: Running scope as unit run-r876f87e3346243a8a9ffc19d05b98bd6.scope.
E: Could not get lock /var/lib/dpkg/lock - open (11: Resource temporarily unavailable)
E: Unable to lock the administration directory (/var/lib/dpkg/), is another process using it?
2017-09-15 08:47:29,589 [salt.loaded.int.module.cmdmod][ERROR   ][2883] retcode: 100
2017-09-15 08:47:29,664 [salt.minion      ][ERROR   ][2883] A command in 'pkg.install' had a problem: Problem encountered installing package(s). Additional info follows:

changes:
    ----------
    man:
        ----------
        new:
        old:
            1
    man-browser:
        ----------
        new:
        old:
            1
    man-db:
        ----------
        new:
        old:
            2.7.5-1
    ntp:
        ----------
        new:
        old:
            1:4.2.8p4+dfsg-3ubuntu5.6
    readahead:
        ----------
        new:
        old:
            1
    systemd:
        ----------
        new:
        old:
            229-4ubuntu19
    systemd-services:
        ----------
        new:
        old:
            1
    ureadahead:
        ----------
        new:
        old:
            0.100.0-19
errors:
    - Running scope as unit run-r876f87e3346243a8a9ffc19d05b98bd6.scope.
      E: Could not get lock /var/lib/dpkg/lock - open (11: Resource temporarily unavailable)
      E: Unable to lock the administration directory (/var/lib/dpkg/), is another process using it?
2017-09-15 08:47:40,606 [salt.utils.parsers][WARNING ][2540] Minion received a SIGTERM. Exiting.
2017-09-15 08:49:52,594 [salt.cli.daemons ][WARNING ][3202] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-15 08:55:25,168 [py.warnings      ][WARNING ][16320] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-09-15 08:56:09,543 [salt.state       ][ERROR   ][16320] Module function virtng.init is not available
2017-09-15 08:56:09,562 [salt.state       ][ERROR   ][16320] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-15 08:56:09,580 [salt.state       ][ERROR   ][16320] Module function virtng.init is not available
2017-09-15 08:56:09,598 [salt.state       ][ERROR   ][16320] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-15 08:56:09,616 [salt.state       ][ERROR   ][16320] Module function virtng.init is not available
2017-09-15 08:56:09,633 [salt.state       ][ERROR   ][16320] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-15 08:56:09,651 [salt.state       ][ERROR   ][16320] Module function virtng.init is not available
2017-09-15 08:56:09,668 [salt.state       ][ERROR   ][16320] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-15 08:56:09,674 [salt.loaded.int.module.cmdmod][ERROR   ][16320] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2017-09-15 08:56:11,326 [salt.loaded.int.module.cmdmod][INFO    ][22468] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-15 08:56:11,334 [salt.loaded.int.module.cmdmod][INFO    ][22468] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-15 08:56:11,345 [salt.loaded.int.module.cmdmod][INFO    ][22468] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-09-15 08:56:11,354 [salt.utils.parsers][WARNING ][3202] Minion received a SIGTERM. Exiting.
2017-09-15 08:56:11,615 [salt.cli.daemons ][INFO    ][22541] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local"
2017-09-15 08:56:11,879 [salt.minion      ][INFO    ][22541] Creating minion process manager
2017-09-15 08:56:11,879 [salt.cli.daemons ][WARNING ][22541] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-15 08:56:11,880 [salt.cli.daemons ][INFO    ][22541] The Salt Minion is starting up
2017-09-15 08:56:11,880 [salt.minion      ][INFO    ][22541] Minion is starting as user 'root'
2017-09-15 08:56:11,880 [salt.utils.event ][INFO    ][22541] Starting pull socket on /var/run/salt/minion/minion_event_4fcafbd0f5_pull.ipc
2017-09-15 08:56:12,481 [salt.loaded.int.module.cmdmod][INFO    ][22541] Executing command ['date', '+%z'] in directory '/root'
2017-09-15 08:56:12,489 [salt.utils.schedule][INFO    ][22541] Updating job settings for scheduled job: __mine_interval
2017-09-15 08:56:12,492 [salt.minion      ][INFO    ][22541] Added mine.update to scheduler
2017-09-15 08:56:12,526 [salt.minion      ][INFO    ][22541] Minion is ready to receive requests!
2017-09-15 08:56:13,527 [salt.utils.schedule][INFO    ][22541] Running scheduled job: __mine_interval
2017-09-15 08:58:49,184 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170915085849179505
2017-09-15 08:58:49,200 [salt.minion      ][INFO    ][22638] Starting a new job with PID 22638
2017-09-15 08:58:50,646 [salt.state       ][INFO    ][22638] Loading fresh modules for state activity
2017-09-15 08:58:50,671 [salt.state       ][ERROR   ][22638] No contents found in top file
2017-09-15 08:58:50,672 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-15 08:58:50,675 [salt.utils.extmods][INFO    ][22638] Syncing beacons for environment 'base'
2017-09-15 08:58:50,675 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_beacons, for base)
2017-09-15 08:58:50,676 [salt.fileclient  ][INFO    ][22638] Caching directory '_beacons/' for environment 'base'
2017-09-15 08:58:50,719 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/modules'
2017-09-15 08:58:50,722 [salt.utils.extmods][INFO    ][22638] Syncing modules for environment 'base'
2017-09-15 08:58:50,722 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_modules, for base)
2017-09-15 08:58:50,722 [salt.fileclient  ][INFO    ][22638] Caching directory '_modules/' for environment 'base'
2017-09-15 08:58:50,760 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-15 08:58:50,768 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-15 08:58:50,777 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-15 08:58:50,785 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-15 08:58:50,793 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-15 08:58:50,801 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-15 08:58:50,813 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-15 08:58:50,826 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-15 08:58:50,838 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-15 08:58:50,849 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-15 08:58:51,110 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-15 08:58:51,122 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-15 08:58:51,134 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-15 08:58:51,144 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-15 08:58:51,154 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-15 08:58:51,167 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-15 08:58:51,185 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-15 08:58:51,196 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-15 08:58:51,207 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-15 08:58:51,221 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-15 08:58:51,236 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-15 08:58:51,246 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-15 08:58:51,260 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-15 08:58:51,273 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-15 08:58:51,287 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-15 08:58:51,298 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-15 08:58:51,309 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-15 08:58:51,326 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-15 08:58:51,335 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-15 08:58:51,344 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-15 08:58:51,353 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-15 08:58:51,365 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-15 08:58:51,368 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-15 08:58:51,368 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-15 08:58:51,368 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-15 08:58:51,368 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-15 08:58:51,369 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-15 08:58:51,369 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-15 08:58:51,369 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-15 08:58:51,370 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-15 08:58:51,370 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-15 08:58:51,370 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-15 08:58:51,370 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-15 08:58:51,371 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-15 08:58:51,371 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-15 08:58:51,371 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-15 08:58:51,371 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-15 08:58:51,372 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-15 08:58:51,372 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-15 08:58:51,372 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-15 08:58:51,372 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-15 08:58:51,373 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-15 08:58:51,373 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-15 08:58:51,373 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-15 08:58:51,373 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-15 08:58:51,374 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-15 08:58:51,374 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-15 08:58:51,374 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-15 08:58:51,375 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-15 08:58:51,375 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-15 08:58:51,375 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-15 08:58:51,375 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-15 08:58:51,376 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-15 08:58:51,376 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-09-15 08:58:51,376 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-15 08:58:51,376 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-15 08:58:51,378 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/states'
2017-09-15 08:58:51,380 [salt.utils.extmods][INFO    ][22638] Syncing states for environment 'base'
2017-09-15 08:58:51,380 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_states, for base)
2017-09-15 08:58:51,381 [salt.fileclient  ][INFO    ][22638] Caching directory '_states/' for environment 'base'
2017-09-15 08:58:51,428 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-15 08:58:51,439 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-15 08:58:51,449 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-15 08:58:51,459 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-15 08:58:51,471 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-15 08:58:51,483 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-15 08:58:51,494 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-15 08:58:51,503 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-15 08:58:51,516 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-15 08:58:51,528 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-15 08:58:51,540 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-15 08:58:51,553 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-15 08:58:51,564 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-15 08:58:51,576 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-15 08:58:51,587 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-15 08:58:51,599 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-15 08:58:51,611 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-15 08:58:51,626 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-15 08:58:51,639 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-15 08:58:51,650 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-15 08:58:51,662 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-15 08:58:51,678 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-15 08:58:51,687 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-15 08:58:51,707 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-15 08:58:51,717 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-15 08:58:52,084 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-15 08:58:52,097 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-15 08:58:52,111 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-15 08:58:52,123 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-15 08:58:52,125 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-15 08:58:52,126 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-15 08:58:52,126 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-15 08:58:52,126 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-15 08:58:52,127 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-15 08:58:52,127 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-15 08:58:52,127 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-15 08:58:52,127 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-15 08:58:52,128 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-15 08:58:52,128 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-15 08:58:52,128 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-15 08:58:52,128 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-15 08:58:52,129 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-15 08:58:52,129 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-15 08:58:52,129 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-15 08:58:52,129 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-15 08:58:52,130 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-15 08:58:52,130 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-15 08:58:52,130 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-15 08:58:52,130 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-15 08:58:52,131 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-15 08:58:52,131 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-15 08:58:52,131 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-15 08:58:52,131 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-15 08:58:52,132 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-15 08:58:52,132 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-15 08:58:52,132 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-15 08:58:52,132 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-15 08:58:52,133 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-15 08:58:52,133 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-15 08:58:52,134 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-15 08:58:52,136 [salt.utils.extmods][INFO    ][22638] Syncing sdb for environment 'base'
2017-09-15 08:58:52,137 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_sdb, for base)
2017-09-15 08:58:52,137 [salt.fileclient  ][INFO    ][22638] Caching directory '_sdb/' for environment 'base'
2017-09-15 08:58:52,169 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/grains'
2017-09-15 08:58:52,171 [salt.utils.extmods][INFO    ][22638] Syncing grains for environment 'base'
2017-09-15 08:58:52,171 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_grains, for base)
2017-09-15 08:58:52,171 [salt.fileclient  ][INFO    ][22638] Caching directory '_grains/' for environment 'base'
2017-09-15 08:58:52,208 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-15 08:58:52,221 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-15 08:58:52,235 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-15 08:58:52,246 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-15 08:58:52,256 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-15 08:58:52,268 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-15 08:58:52,278 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-15 08:58:52,288 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-15 08:58:52,298 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-15 08:58:52,308 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-15 08:58:52,320 [salt.fileclient  ][INFO    ][22638] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-15 08:58:52,323 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-15 08:58:52,323 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-15 08:58:52,323 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-15 08:58:52,324 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-15 08:58:52,324 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-15 08:58:52,324 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-15 08:58:52,324 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-15 08:58:52,325 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-15 08:58:52,325 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-15 08:58:52,325 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-15 08:58:52,325 [salt.utils.extmods][INFO    ][22638] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-15 08:58:52,326 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-15 08:58:52,329 [salt.utils.extmods][INFO    ][22638] Syncing renderers for environment 'base'
2017-09-15 08:58:52,329 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_renderers, for base)
2017-09-15 08:58:52,329 [salt.fileclient  ][INFO    ][22638] Caching directory '_renderers/' for environment 'base'
2017-09-15 08:58:52,356 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-15 08:58:52,359 [salt.utils.extmods][INFO    ][22638] Syncing returners for environment 'base'
2017-09-15 08:58:52,359 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_returners, for base)
2017-09-15 08:58:52,359 [salt.fileclient  ][INFO    ][22638] Caching directory '_returners/' for environment 'base'
2017-09-15 08:58:52,380 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-15 08:58:52,383 [salt.utils.extmods][INFO    ][22638] Syncing output for environment 'base'
2017-09-15 08:58:52,383 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_output, for base)
2017-09-15 08:58:52,383 [salt.fileclient  ][INFO    ][22638] Caching directory '_output/' for environment 'base'
2017-09-15 08:58:52,406 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-15 08:58:52,408 [salt.utils.extmods][INFO    ][22638] Syncing utils for environment 'base'
2017-09-15 08:58:52,409 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_utils, for base)
2017-09-15 08:58:52,409 [salt.fileclient  ][INFO    ][22638] Caching directory '_utils/' for environment 'base'
2017-09-15 08:58:52,435 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-15 08:58:52,437 [salt.utils.extmods][INFO    ][22638] Syncing log_handlers for environment 'base'
2017-09-15 08:58:52,438 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_log_handlers, for base)
2017-09-15 08:58:52,438 [salt.fileclient  ][INFO    ][22638] Caching directory '_log_handlers/' for environment 'base'
2017-09-15 08:58:52,460 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-15 08:58:52,462 [salt.utils.extmods][INFO    ][22638] Syncing proxy for environment 'base'
2017-09-15 08:58:52,462 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_proxy, for base)
2017-09-15 08:58:52,462 [salt.fileclient  ][INFO    ][22638] Caching directory '_proxy/' for environment 'base'
2017-09-15 08:58:52,485 [salt.utils.extmods][INFO    ][22638] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-15 08:58:52,487 [salt.utils.extmods][INFO    ][22638] Syncing engines for environment 'base'
2017-09-15 08:58:52,487 [salt.utils.extmods][INFO    ][22638] Loading cache from salt://_engines, for base)
2017-09-15 08:58:52,487 [salt.fileclient  ][INFO    ][22638] Caching directory '_engines/' for environment 'base'
2017-09-15 08:58:52,512 [salt.minion      ][INFO    ][22638] Returning information for job: 20170915085849179505
2017-09-15 08:58:54,078 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command state.sls with jid 20170915085853697983
2017-09-15 08:58:54,091 [salt.minion      ][INFO    ][22735] Starting a new job with PID 22735
2017-09-15 08:58:54,478 [salt.state       ][INFO    ][22735] Loading fresh modules for state activity
2017-09-15 08:58:54,502 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-09-15 08:58:54,521 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-09-15 08:58:54,597 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 08:58:54,659 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-09-15 08:58:54,673 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-09-15 08:58:54,698 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 08:58:54,748 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-15 08:58:54,767 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-15 08:58:54,809 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-15 08:58:54,825 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-15 08:58:54,840 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 08:58:54,855 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-15 08:58:54,862 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-15 08:58:54,884 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-15 08:58:54,898 [py.warnings      ][WARNING ][22735] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-09-15 08:58:54,898 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'salt-minion --version' in directory '/root'
2017-09-15 08:58:55,145 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-15 08:58:55,164 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-15 08:58:55,176 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-15 08:58:55,188 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-15 08:58:55,235 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-15 08:58:55,272 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-15 08:58:55,327 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-09-15 08:58:55,346 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 08:58:55,395 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-15 08:58:55,412 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-15 08:58:55,450 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-15 08:58:55,467 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-15 08:58:55,481 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 08:58:55,775 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-15 08:58:55,795 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-15 08:58:55,819 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-15 08:58:55,833 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'salt-minion --version' in directory '/root'
2017-09-15 08:58:56,092 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-15 08:58:56,112 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-15 08:58:56,125 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-15 08:58:56,138 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-15 08:58:56,185 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-15 08:58:56,223 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-15 08:58:56,284 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-09-15 08:58:56,340 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 08:58:56,699 [salt.state       ][INFO    ][22735] Running state [salt-minion] at time 08:58:56.698763
2017-09-15 08:58:56,699 [salt.state       ][INFO    ][22735] Executing state pkg.installed for salt-minion
2017-09-15 08:58:56,700 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 08:58:56,953 [salt.state       ][INFO    ][22735] Package salt-minion is already installed
2017-09-15 08:58:56,953 [salt.state       ][INFO    ][22735] Completed state [salt-minion] at time 08:58:56.953456 duration_in_ms=254.693
2017-09-15 08:58:56,954 [salt.state       ][INFO    ][22735] Running state [python-yaml] at time 08:58:56.953697
2017-09-15 08:58:56,954 [salt.state       ][INFO    ][22735] Executing state pkg.installed for python-yaml
2017-09-15 08:58:56,956 [salt.state       ][INFO    ][22735] Package python-yaml is already installed
2017-09-15 08:58:56,957 [salt.state       ][INFO    ][22735] Completed state [python-yaml] at time 08:58:56.956554 duration_in_ms=2.857
2017-09-15 08:58:56,957 [salt.state       ][INFO    ][22735] Running state [python-psutil] at time 08:58:56.956697
2017-09-15 08:58:56,957 [salt.state       ][INFO    ][22735] Executing state pkg.installed for python-psutil
2017-09-15 08:58:56,959 [salt.state       ][INFO    ][22735] Package python-psutil is already installed
2017-09-15 08:58:56,959 [salt.state       ][INFO    ][22735] Completed state [python-psutil] at time 08:58:56.959450 duration_in_ms=2.753
2017-09-15 08:58:56,960 [salt.state       ][INFO    ][22735] Running state [python-m2crypto] at time 08:58:56.959580
2017-09-15 08:58:56,960 [salt.state       ][INFO    ][22735] Executing state pkg.installed for python-m2crypto
2017-09-15 08:58:56,962 [salt.state       ][INFO    ][22735] Package python-m2crypto is already installed
2017-09-15 08:58:56,962 [salt.state       ][INFO    ][22735] Completed state [python-m2crypto] at time 08:58:56.962308 duration_in_ms=2.728
2017-09-15 08:58:56,964 [salt.state       ][INFO    ][22735] Running state [/etc/salt/minion.d/minion.conf] at time 08:58:56.963711
2017-09-15 08:58:56,964 [salt.state       ][INFO    ][22735] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-09-15 08:58:56,985 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-09-15 08:58:57,029 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-15 08:58:57,069 [salt.fileclient  ][INFO    ][22735] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-15 08:58:57,099 [salt.state       ][INFO    ][22735] File /etc/salt/minion.d/minion.conf is in the correct state
2017-09-15 08:58:57,099 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/minion.d/minion.conf] at time 08:58:57.098652 duration_in_ms=134.941
2017-09-15 08:58:57,099 [salt.state       ][INFO    ][22735] Running state [/etc/salt/minion.d/_virt.conf] at time 08:58:57.098921
2017-09-15 08:58:57,099 [salt.state       ][INFO    ][22735] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-09-15 08:58:57,104 [salt.state       ][INFO    ][22735] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-09-15 08:58:57,104 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/minion.d/_virt.conf] at time 08:58:57.103783 duration_in_ms=4.862
2017-09-15 08:58:57,105 [salt.state       ][INFO    ][22735] Running state [salt-minion] at time 08:58:57.104749
2017-09-15 08:58:57,105 [salt.state       ][INFO    ][22735] Executing state service.running for salt-minion
2017-09-15 08:58:57,105 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-15 08:58:57,122 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-09-15 08:58:57,130 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-15 08:58:57,138 [salt.state       ][INFO    ][22735] The service salt-minion is already running
2017-09-15 08:58:57,138 [salt.state       ][INFO    ][22735] Completed state [salt-minion] at time 08:58:57.137970 duration_in_ms=33.219
2017-09-15 08:58:57,139 [salt.state       ][INFO    ][22735] Running state [/etc/salt/grains.d] at time 08:58:57.139240
2017-09-15 08:58:57,140 [salt.state       ][INFO    ][22735] Executing state file.directory for /etc/salt/grains.d
2017-09-15 08:58:57,140 [salt.state       ][INFO    ][22735] Directory /etc/salt/grains.d is in the correct state
2017-09-15 08:58:57,141 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/grains.d] at time 08:58:57.140523 duration_in_ms=1.283
2017-09-15 08:58:57,141 [salt.state       ][INFO    ][22735] Running state [/etc/salt/grains] at time 08:58:57.141119
2017-09-15 08:58:57,141 [salt.state       ][INFO    ][22735] Executing state file.managed for /etc/salt/grains
2017-09-15 08:58:57,142 [salt.state       ][INFO    ][22735] File /etc/salt/grains exists with proper permissions. No changes made.
2017-09-15 08:58:57,142 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/grains] at time 08:58:57.142082 duration_in_ms=0.962
2017-09-15 08:58:57,143 [salt.state       ][INFO    ][22735] Running state [/etc/salt/grains.d/placeholder] at time 08:58:57.142493
2017-09-15 08:58:57,143 [salt.state       ][INFO    ][22735] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-09-15 08:58:57,143 [salt.state       ][INFO    ][22735] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-09-15 08:58:57,143 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/grains.d/placeholder] at time 08:58:57.143419 duration_in_ms=0.926
2017-09-15 08:58:57,144 [salt.state       ][INFO    ][22735] Running state [/etc/salt/grains.d/sphinx] at time 08:58:57.143805
2017-09-15 08:58:57,144 [salt.state       ][INFO    ][22735] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-09-15 08:58:57,153 [salt.state       ][INFO    ][22735] File /etc/salt/grains.d/sphinx is in the correct state
2017-09-15 08:58:57,153 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/grains.d/sphinx] at time 08:58:57.152715 duration_in_ms=8.91
2017-09-15 08:58:57,153 [salt.state       ][INFO    ][22735] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:58:57.153306
2017-09-15 08:58:57,154 [salt.state       ][INFO    ][22735] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-15 08:58:57,154 [salt.state       ][INFO    ][22735] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-15 08:58:57,154 [salt.state       ][INFO    ][22735] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:58:57.154087 duration_in_ms=0.78
2017-09-15 08:58:57,155 [salt.state       ][INFO    ][22735] Running state [/etc/salt/grains.d/dns_records] at time 08:58:57.154485
2017-09-15 08:58:57,155 [salt.state       ][INFO    ][22735] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-09-15 08:58:57,159 [salt.state       ][INFO    ][22735] File /etc/salt/grains.d/dns_records is in the correct state
2017-09-15 08:58:57,159 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/grains.d/dns_records] at time 08:58:57.158818 duration_in_ms=4.333
2017-09-15 08:58:57,159 [salt.state       ][INFO    ][22735] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:58:57.159400
2017-09-15 08:58:57,160 [salt.state       ][INFO    ][22735] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-15 08:58:57,160 [salt.state       ][INFO    ][22735] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-15 08:58:57,160 [salt.state       ][INFO    ][22735] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:58:57.160176 duration_in_ms=0.775
2017-09-15 08:58:57,161 [salt.state       ][INFO    ][22735] Running state [/etc/salt/grains.d/salt] at time 08:58:57.160561
2017-09-15 08:58:57,161 [salt.state       ][INFO    ][22735] Executing state file.managed for /etc/salt/grains.d/salt
2017-09-15 08:58:57,165 [salt.state       ][INFO    ][22735] File /etc/salt/grains.d/salt is in the correct state
2017-09-15 08:58:57,165 [salt.state       ][INFO    ][22735] Completed state [/etc/salt/grains.d/salt] at time 08:58:57.164843 duration_in_ms=4.282
2017-09-15 08:58:57,165 [salt.state       ][INFO    ][22735] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:58:57.165420
2017-09-15 08:58:57,166 [salt.state       ][INFO    ][22735] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-15 08:58:57,166 [salt.state       ][INFO    ][22735] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-15 08:58:57,166 [salt.state       ][INFO    ][22735] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:58:57.166193 duration_in_ms=0.772
2017-09-15 08:58:57,168 [salt.state       ][INFO    ][22735] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:58:57.167532
2017-09-15 08:58:57,168 [salt.state       ][INFO    ][22735] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-15 08:58:57,168 [salt.state       ][INFO    ][22735] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-15 08:58:57,168 [salt.state       ][INFO    ][22735] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:58:57.168310 duration_in_ms=0.777
2017-09-15 08:58:57,169 [salt.state       ][INFO    ][22735] Running state [mine.update] at time 08:58:57.168844
2017-09-15 08:58:57,169 [salt.state       ][INFO    ][22735] Executing state module.wait for mine.update
2017-09-15 08:58:57,169 [salt.state       ][INFO    ][22735] No changes made for mine.update
2017-09-15 08:58:57,170 [salt.state       ][INFO    ][22735] Completed state [mine.update] at time 08:58:57.169579 duration_in_ms=0.734
2017-09-15 08:58:57,170 [salt.state       ][INFO    ][22735] Running state [ca-certificates] at time 08:58:57.169830
2017-09-15 08:58:57,170 [salt.state       ][INFO    ][22735] Executing state pkg.installed for ca-certificates
2017-09-15 08:58:57,173 [salt.state       ][INFO    ][22735] Package ca-certificates is already installed
2017-09-15 08:58:57,173 [salt.state       ][INFO    ][22735] Completed state [ca-certificates] at time 08:58:57.173359 duration_in_ms=3.529
2017-09-15 08:58:57,174 [salt.state       ][INFO    ][22735] Running state [update-ca-certificates] at time 08:58:57.173910
2017-09-15 08:58:57,174 [salt.state       ][INFO    ][22735] Executing state cmd.wait for update-ca-certificates
2017-09-15 08:58:57,174 [salt.state       ][INFO    ][22735] No changes made for update-ca-certificates
2017-09-15 08:58:57,175 [salt.state       ][INFO    ][22735] Completed state [update-ca-certificates] at time 08:58:57.174675 duration_in_ms=0.764
2017-09-15 08:58:57,175 [salt.state       ][INFO    ][22735] Running state [libvirt-dev] at time 08:58:57.174924
2017-09-15 08:58:57,175 [salt.state       ][INFO    ][22735] Executing state pkg.installed for libvirt-dev
2017-09-15 08:58:57,178 [salt.state       ][INFO    ][22735] Package libvirt-dev is already installed
2017-09-15 08:58:57,178 [salt.state       ][INFO    ][22735] Completed state [libvirt-dev] at time 08:58:57.178050 duration_in_ms=3.126
2017-09-15 08:58:57,178 [salt.state       ][INFO    ][22735] Running state [pkg-config] at time 08:58:57.178309
2017-09-15 08:58:57,179 [salt.state       ][INFO    ][22735] Executing state pkg.installed for pkg-config
2017-09-15 08:58:57,181 [salt.state       ][INFO    ][22735] Package pkg-config is already installed
2017-09-15 08:58:57,181 [salt.state       ][INFO    ][22735] Completed state [pkg-config] at time 08:58:57.181430 duration_in_ms=3.121
2017-09-15 08:58:57,182 [salt.state       ][INFO    ][22735] Running state [virtng.init] at time 08:58:57.181679
2017-09-15 08:58:57,182 [salt.state       ][INFO    ][22735] Executing state module.run for virtng.init
2017-09-15 08:58:57,231 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command ['git', '--version'] in directory '/root'
2017-09-15 08:58:57,323 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 08:59:03,756 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915085903744924
2017-09-15 08:59:03,771 [salt.minion      ][INFO    ][22790] Starting a new job with PID 22790
2017-09-15 08:59:03,781 [salt.minion      ][INFO    ][22790] Returning information for job: 20170915085903744924
2017-09-15 08:59:13,825 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915085913813642
2017-09-15 08:59:13,840 [salt.minion      ][INFO    ][22795] Starting a new job with PID 22795
2017-09-15 08:59:13,850 [salt.minion      ][INFO    ][22795] Returning information for job: 20170915085913813642
2017-09-15 08:59:23,875 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915085923865174
2017-09-15 08:59:23,891 [salt.minion      ][INFO    ][22810] Starting a new job with PID 22810
2017-09-15 08:59:23,901 [salt.minion      ][INFO    ][22810] Returning information for job: 20170915085923865174
2017-09-15 08:59:33,919 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915085933906026
2017-09-15 08:59:33,935 [salt.minion      ][INFO    ][22815] Starting a new job with PID 22815
2017-09-15 08:59:33,944 [salt.minion      ][INFO    ][22815] Returning information for job: 20170915085933906026
2017-09-15 08:59:44,150 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915085944139167
2017-09-15 08:59:44,167 [salt.minion      ][INFO    ][22820] Starting a new job with PID 22820
2017-09-15 08:59:44,176 [salt.minion      ][INFO    ][22820] Returning information for job: 20170915085944139167
2017-09-15 08:59:54,297 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915085954285711
2017-09-15 08:59:54,312 [salt.minion      ][INFO    ][22825] Starting a new job with PID 22825
2017-09-15 08:59:54,321 [salt.minion      ][INFO    ][22825] Returning information for job: 20170915085954285711
2017-09-15 09:00:04,370 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090004358594
2017-09-15 09:00:04,387 [salt.minion      ][INFO    ][22830] Starting a new job with PID 22830
2017-09-15 09:00:04,397 [salt.minion      ][INFO    ][22830] Returning information for job: 20170915090004358594
2017-09-15 09:00:14,566 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090014555527
2017-09-15 09:00:14,581 [salt.minion      ][INFO    ][22835] Starting a new job with PID 22835
2017-09-15 09:00:14,590 [salt.minion      ][INFO    ][22835] Returning information for job: 20170915090014555527
2017-09-15 09:00:24,764 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090024752785
2017-09-15 09:00:24,780 [salt.minion      ][INFO    ][22840] Starting a new job with PID 22840
2017-09-15 09:00:24,789 [salt.minion      ][INFO    ][22840] Returning information for job: 20170915090024752785
2017-09-15 09:00:34,962 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090034950691
2017-09-15 09:00:34,978 [salt.minion      ][INFO    ][22845] Starting a new job with PID 22845
2017-09-15 09:00:34,987 [salt.minion      ][INFO    ][22845] Returning information for job: 20170915090034950691
2017-09-15 09:00:45,166 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090045154270
2017-09-15 09:00:45,181 [salt.minion      ][INFO    ][22850] Starting a new job with PID 22850
2017-09-15 09:00:45,191 [salt.minion      ][INFO    ][22850] Returning information for job: 20170915090045154270
2017-09-15 09:00:55,370 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090055358784
2017-09-15 09:00:55,385 [salt.minion      ][INFO    ][22855] Starting a new job with PID 22855
2017-09-15 09:00:55,395 [salt.minion      ][INFO    ][22855] Returning information for job: 20170915090055358784
2017-09-15 09:01:05,578 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090105566446
2017-09-15 09:01:05,593 [salt.minion      ][INFO    ][22860] Starting a new job with PID 22860
2017-09-15 09:01:05,602 [salt.minion      ][INFO    ][22860] Returning information for job: 20170915090105566446
2017-09-15 09:01:15,792 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090115781541
2017-09-15 09:01:15,808 [salt.minion      ][INFO    ][22865] Starting a new job with PID 22865
2017-09-15 09:01:15,817 [salt.minion      ][INFO    ][22865] Returning information for job: 20170915090115781541
2017-09-15 09:01:26,013 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090126003551
2017-09-15 09:01:26,029 [salt.minion      ][INFO    ][22870] Starting a new job with PID 22870
2017-09-15 09:01:26,038 [salt.minion      ][INFO    ][22870] Returning information for job: 20170915090126003551
2017-09-15 09:01:36,224 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090136213007
2017-09-15 09:01:36,239 [salt.minion      ][INFO    ][22875] Starting a new job with PID 22875
2017-09-15 09:01:36,248 [salt.minion      ][INFO    ][22875] Returning information for job: 20170915090136213007
2017-09-15 09:01:46,440 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090146429538
2017-09-15 09:01:46,456 [salt.minion      ][INFO    ][22880] Starting a new job with PID 22880
2017-09-15 09:01:46,465 [salt.minion      ][INFO    ][22880] Returning information for job: 20170915090146429538
2017-09-15 09:01:56,661 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090156652087
2017-09-15 09:01:56,677 [salt.minion      ][INFO    ][22885] Starting a new job with PID 22885
2017-09-15 09:01:56,686 [salt.minion      ][INFO    ][22885] Returning information for job: 20170915090156652087
2017-09-15 09:02:06,882 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090206871026
2017-09-15 09:02:06,897 [salt.minion      ][INFO    ][22890] Starting a new job with PID 22890
2017-09-15 09:02:06,906 [salt.minion      ][INFO    ][22890] Returning information for job: 20170915090206871026
2017-09-15 09:02:17,111 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090217100598
2017-09-15 09:02:17,127 [salt.minion      ][INFO    ][22895] Starting a new job with PID 22895
2017-09-15 09:02:17,136 [salt.minion      ][INFO    ][22895] Returning information for job: 20170915090217100598
2017-09-15 09:02:27,133 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090227121986
2017-09-15 09:02:27,149 [salt.minion      ][INFO    ][22900] Starting a new job with PID 22900
2017-09-15 09:02:27,159 [salt.minion      ][INFO    ][22900] Returning information for job: 20170915090227121986
2017-09-15 09:02:37,159 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090237147104
2017-09-15 09:02:37,174 [salt.minion      ][INFO    ][22905] Starting a new job with PID 22905
2017-09-15 09:02:37,183 [salt.minion      ][INFO    ][22905] Returning information for job: 20170915090237147104
2017-09-15 09:02:47,185 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090247174982
2017-09-15 09:02:47,201 [salt.minion      ][INFO    ][22910] Starting a new job with PID 22910
2017-09-15 09:02:47,210 [salt.minion      ][INFO    ][22910] Returning information for job: 20170915090247174982
2017-09-15 09:02:57,211 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090257198236
2017-09-15 09:02:57,227 [salt.minion      ][INFO    ][22916] Starting a new job with PID 22916
2017-09-15 09:02:57,236 [salt.minion      ][INFO    ][22916] Returning information for job: 20170915090257198236
2017-09-15 09:03:07,253 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090307230555
2017-09-15 09:03:07,268 [salt.minion      ][INFO    ][22921] Starting a new job with PID 22921
2017-09-15 09:03:07,277 [salt.minion      ][INFO    ][22921] Returning information for job: 20170915090307230555
2017-09-15 09:03:17,282 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090317271082
2017-09-15 09:03:17,297 [salt.minion      ][INFO    ][22926] Starting a new job with PID 22926
2017-09-15 09:03:17,306 [salt.minion      ][INFO    ][22926] Returning information for job: 20170915090317271082
2017-09-15 09:03:27,316 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090327306082
2017-09-15 09:03:27,331 [salt.minion      ][INFO    ][22931] Starting a new job with PID 22931
2017-09-15 09:03:27,341 [salt.minion      ][INFO    ][22931] Returning information for job: 20170915090327306082
2017-09-15 09:03:37,355 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090337343315
2017-09-15 09:03:37,371 [salt.minion      ][INFO    ][22936] Starting a new job with PID 22936
2017-09-15 09:03:37,380 [salt.minion      ][INFO    ][22936] Returning information for job: 20170915090337343315
2017-09-15 09:03:47,401 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090347389331
2017-09-15 09:03:47,415 [salt.minion      ][INFO    ][22941] Starting a new job with PID 22941
2017-09-15 09:03:47,425 [salt.minion      ][INFO    ][22941] Returning information for job: 20170915090347389331
2017-09-15 09:03:57,449 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090357437547
2017-09-15 09:03:57,463 [salt.minion      ][INFO    ][22946] Starting a new job with PID 22946
2017-09-15 09:03:57,472 [salt.minion      ][INFO    ][22946] Returning information for job: 20170915090357437547
2017-09-15 09:04:07,507 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090407497687
2017-09-15 09:04:07,522 [salt.minion      ][INFO    ][22961] Starting a new job with PID 22961
2017-09-15 09:04:07,532 [salt.minion      ][INFO    ][22961] Returning information for job: 20170915090407497687
2017-09-15 09:04:17,572 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090417561126
2017-09-15 09:04:17,587 [salt.minion      ][INFO    ][22966] Starting a new job with PID 22966
2017-09-15 09:04:17,596 [salt.minion      ][INFO    ][22966] Returning information for job: 20170915090417561126
2017-09-15 09:04:27,636 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090427625932
2017-09-15 09:04:27,651 [salt.minion      ][INFO    ][22971] Starting a new job with PID 22971
2017-09-15 09:04:27,660 [salt.minion      ][INFO    ][22971] Returning information for job: 20170915090427625932
2017-09-15 09:04:37,704 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090437692713
2017-09-15 09:04:37,720 [salt.minion      ][INFO    ][22976] Starting a new job with PID 22976
2017-09-15 09:04:37,730 [salt.minion      ][INFO    ][22976] Returning information for job: 20170915090437692713
2017-09-15 09:04:47,785 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090447774089
2017-09-15 09:04:47,800 [salt.minion      ][INFO    ][22990] Starting a new job with PID 22990
2017-09-15 09:04:47,809 [salt.minion      ][INFO    ][22990] Returning information for job: 20170915090447774089
2017-09-15 09:04:57,855 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090457845023
2017-09-15 09:04:57,872 [salt.minion      ][INFO    ][22997] Starting a new job with PID 22997
2017-09-15 09:04:57,880 [salt.minion      ][INFO    ][22997] Returning information for job: 20170915090457845023
2017-09-15 09:05:07,934 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090507923601
2017-09-15 09:05:07,949 [salt.minion      ][INFO    ][23002] Starting a new job with PID 23002
2017-09-15 09:05:07,958 [salt.minion      ][INFO    ][23002] Returning information for job: 20170915090507923601
2017-09-15 09:05:18,019 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090518006430
2017-09-15 09:05:18,033 [salt.minion      ][INFO    ][23007] Starting a new job with PID 23007
2017-09-15 09:05:18,042 [salt.minion      ][INFO    ][23007] Returning information for job: 20170915090518006430
2017-09-15 09:05:28,117 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090528101179
2017-09-15 09:05:28,133 [salt.minion      ][INFO    ][23012] Starting a new job with PID 23012
2017-09-15 09:05:28,142 [salt.minion      ][INFO    ][23012] Returning information for job: 20170915090528101179
2017-09-15 09:05:38,211 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090538200164
2017-09-15 09:05:38,226 [salt.minion      ][INFO    ][23017] Starting a new job with PID 23017
2017-09-15 09:05:38,236 [salt.minion      ][INFO    ][23017] Returning information for job: 20170915090538200164
2017-09-15 09:05:48,318 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090548308411
2017-09-15 09:05:48,334 [salt.minion      ][INFO    ][23022] Starting a new job with PID 23022
2017-09-15 09:05:48,343 [salt.minion      ][INFO    ][23022] Returning information for job: 20170915090548308411
2017-09-15 09:05:58,441 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090558419052
2017-09-15 09:05:58,457 [salt.minion      ][INFO    ][23027] Starting a new job with PID 23027
2017-09-15 09:05:58,466 [salt.minion      ][INFO    ][23027] Returning information for job: 20170915090558419052
2017-09-15 09:06:08,566 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090608551022
2017-09-15 09:06:08,581 [salt.minion      ][INFO    ][23032] Starting a new job with PID 23032
2017-09-15 09:06:08,589 [salt.minion      ][INFO    ][23032] Returning information for job: 20170915090608551022
2017-09-15 09:06:18,680 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090618669968
2017-09-15 09:06:18,695 [salt.minion      ][INFO    ][23037] Starting a new job with PID 23037
2017-09-15 09:06:18,705 [salt.minion      ][INFO    ][23037] Returning information for job: 20170915090618669968
2017-09-15 09:06:28,803 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090628791735
2017-09-15 09:06:28,819 [salt.minion      ][INFO    ][23042] Starting a new job with PID 23042
2017-09-15 09:06:28,829 [salt.minion      ][INFO    ][23042] Returning information for job: 20170915090628791735
2017-09-15 09:06:38,928 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090638917012
2017-09-15 09:06:38,944 [salt.minion      ][INFO    ][23047] Starting a new job with PID 23047
2017-09-15 09:06:38,953 [salt.minion      ][INFO    ][23047] Returning information for job: 20170915090638917012
2017-09-15 09:06:49,057 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090649046128
2017-09-15 09:06:49,073 [salt.minion      ][INFO    ][23052] Starting a new job with PID 23052
2017-09-15 09:06:49,081 [salt.minion      ][INFO    ][23052] Returning information for job: 20170915090649046128
2017-09-15 09:06:59,190 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090659179571
2017-09-15 09:06:59,206 [salt.minion      ][INFO    ][23057] Starting a new job with PID 23057
2017-09-15 09:06:59,217 [salt.minion      ][INFO    ][23057] Returning information for job: 20170915090659179571
2017-09-15 09:07:09,346 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090709334131
2017-09-15 09:07:09,361 [salt.minion      ][INFO    ][23062] Starting a new job with PID 23062
2017-09-15 09:07:09,371 [salt.minion      ][INFO    ][23062] Returning information for job: 20170915090709334131
2017-09-15 09:07:19,504 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090719491269
2017-09-15 09:07:19,518 [salt.minion      ][INFO    ][23067] Starting a new job with PID 23067
2017-09-15 09:07:19,527 [salt.minion      ][INFO    ][23067] Returning information for job: 20170915090719491269
2017-09-15 09:07:29,663 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090729650383
2017-09-15 09:07:29,678 [salt.minion      ][INFO    ][23072] Starting a new job with PID 23072
2017-09-15 09:07:29,688 [salt.minion      ][INFO    ][23072] Returning information for job: 20170915090729650383
2017-09-15 09:07:39,830 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090739817679
2017-09-15 09:07:39,845 [salt.minion      ][INFO    ][23077] Starting a new job with PID 23077
2017-09-15 09:07:39,855 [salt.minion      ][INFO    ][23077] Returning information for job: 20170915090739817679
2017-09-15 09:07:50,011 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090749999801
2017-09-15 09:07:50,025 [salt.minion      ][INFO    ][23082] Starting a new job with PID 23082
2017-09-15 09:07:50,034 [salt.minion      ][INFO    ][23082] Returning information for job: 20170915090749999801
2017-09-15 09:08:00,196 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090800185784
2017-09-15 09:08:00,212 [salt.minion      ][INFO    ][23087] Starting a new job with PID 23087
2017-09-15 09:08:00,221 [salt.minion      ][INFO    ][23087] Returning information for job: 20170915090800185784
2017-09-15 09:08:10,390 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090810377320
2017-09-15 09:08:10,406 [salt.minion      ][INFO    ][23093] Starting a new job with PID 23093
2017-09-15 09:08:10,415 [salt.minion      ][INFO    ][23093] Returning information for job: 20170915090810377320
2017-09-15 09:08:20,582 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090820571012
2017-09-15 09:08:20,597 [salt.minion      ][INFO    ][23098] Starting a new job with PID 23098
2017-09-15 09:08:20,616 [salt.minion      ][INFO    ][23098] Returning information for job: 20170915090820571012
2017-09-15 09:08:30,792 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090830778646
2017-09-15 09:08:30,808 [salt.minion      ][INFO    ][23103] Starting a new job with PID 23103
2017-09-15 09:08:30,817 [salt.minion      ][INFO    ][23103] Returning information for job: 20170915090830778646
2017-09-15 09:08:40,990 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090840979093
2017-09-15 09:08:41,006 [salt.minion      ][INFO    ][23108] Starting a new job with PID 23108
2017-09-15 09:08:41,015 [salt.minion      ][INFO    ][23108] Returning information for job: 20170915090840979093
2017-09-15 09:08:48,494 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-15 09:08:48,518 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:08:48,567 [salt.loaded.int.module.cmdmod][ERROR   ][22735] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 09:08:48,567 [salt.loaded.int.module.cmdmod][ERROR   ][22735] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 09:08:48,567 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local/system.qcow2' in directory '/root'
2017-09-15 09:08:48,585 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:08:48,593 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-15 09:08:49,647 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-15 09:08:50,185 [salt.loaded.ext.module.seedng][INFO    ][22735] salt-minion pre-installed on image, configuring as mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local
2017-09-15 09:08:50,190 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -l' in directory '/root'
2017-09-15 09:08:50,245 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-15 09:08:51,046 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-15 09:08:51,213 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090851201136
2017-09-15 09:08:51,228 [salt.minion      ][INFO    ][23288] Starting a new job with PID 23288
2017-09-15 09:08:51,237 [salt.minion      ][INFO    ][23288] Returning information for job: 20170915090851201136
2017-09-15 09:08:52,486 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:08:52,487 [salt.state       ][INFO    ][22735] Completed state [virtng.init] at time 09:08:52.486799 duration_in_ms=595305.119
2017-09-15 09:08:52,487 [salt.state       ][INFO    ][22735] Running state [virt.set_autostart] at time 09:08:52.487099
2017-09-15 09:08:52,487 [salt.state       ][INFO    ][22735] Executing state module.run for virt.set_autostart
2017-09-15 09:08:52,490 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 09:08:52,512 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:08:52,512 [salt.state       ][INFO    ][22735] Completed state [virt.set_autostart] at time 09:08:52.511728 duration_in_ms=24.629
2017-09-15 09:08:52,512 [salt.state       ][INFO    ][22735] Running state [virtng.init] at time 09:08:52.511909
2017-09-15 09:08:52,512 [salt.state       ][INFO    ][22735] Executing state module.run for virtng.init
2017-09-15 09:08:52,514 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 09:08:55,482 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-15 09:08:55,493 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:08:55,502 [salt.loaded.int.module.cmdmod][ERROR   ][22735] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 09:08:55,503 [salt.loaded.int.module.cmdmod][ERROR   ][22735] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 09:08:55,503 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local/system.qcow2' in directory '/root'
2017-09-15 09:08:55,520 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:08:55,530 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-15 09:08:56,566 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-15 09:08:57,205 [salt.loaded.ext.module.seedng][INFO    ][22735] salt-minion pre-installed on image, configuring as dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local
2017-09-15 09:08:57,210 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -l' in directory '/root'
2017-09-15 09:08:57,370 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-15 09:08:59,714 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-15 09:09:01,120 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:09:01,121 [salt.state       ][INFO    ][22735] Completed state [virtng.init] at time 09:09:01.120695 duration_in_ms=8608.784
2017-09-15 09:09:01,121 [salt.state       ][INFO    ][22735] Running state [virt.set_autostart] at time 09:09:01.120956
2017-09-15 09:09:01,121 [salt.state       ][INFO    ][22735] Executing state module.run for virt.set_autostart
2017-09-15 09:09:01,123 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 09:09:01,144 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:09:01,144 [salt.state       ][INFO    ][22735] Completed state [virt.set_autostart] at time 09:09:01.143841 duration_in_ms=22.884
2017-09-15 09:09:01,144 [salt.state       ][INFO    ][22735] Running state [virtng.init] at time 09:09:01.144015
2017-09-15 09:09:01,144 [salt.state       ][INFO    ][22735] Executing state module.run for virtng.init
2017-09-15 09:09:01,146 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 09:09:01,442 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090901432034
2017-09-15 09:09:01,479 [salt.minion      ][INFO    ][23800] Starting a new job with PID 23800
2017-09-15 09:09:01,487 [salt.minion      ][INFO    ][23800] Returning information for job: 20170915090901432034
2017-09-15 09:09:05,057 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-15 09:09:05,068 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:09:05,077 [salt.loaded.int.module.cmdmod][ERROR   ][22735] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 09:09:05,077 [salt.loaded.int.module.cmdmod][ERROR   ][22735] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 09:09:05,078 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local/system.qcow2' in directory '/root'
2017-09-15 09:09:05,095 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:09:05,106 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-15 09:09:06,151 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-15 09:09:06,605 [salt.loaded.ext.module.seedng][INFO    ][22735] salt-minion pre-installed on image, configuring as ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local
2017-09-15 09:09:06,609 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -l' in directory '/root'
2017-09-15 09:09:06,711 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-15 09:09:09,597 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-15 09:09:10,948 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:09:10,952 [salt.state       ][INFO    ][22735] Completed state [virtng.init] at time 09:09:10.952304 duration_in_ms=9808.285
2017-09-15 09:09:10,953 [salt.state       ][INFO    ][22735] Running state [virt.set_autostart] at time 09:09:10.952603
2017-09-15 09:09:10,953 [salt.state       ][INFO    ][22735] Executing state module.run for virt.set_autostart
2017-09-15 09:09:10,955 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 09:09:10,977 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:09:10,977 [salt.state       ][INFO    ][22735] Completed state [virt.set_autostart] at time 09:09:10.977416 duration_in_ms=24.812
2017-09-15 09:09:10,978 [salt.state       ][INFO    ][22735] Running state [virtng.init] at time 09:09:10.977596
2017-09-15 09:09:10,978 [salt.state       ][INFO    ][22735] Executing state module.run for virtng.init
2017-09-15 09:09:10,980 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 09:09:11,471 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915090911461127
2017-09-15 09:09:11,483 [salt.minion      ][INFO    ][24069] Starting a new job with PID 24069
2017-09-15 09:09:11,491 [salt.minion      ][INFO    ][24069] Returning information for job: 20170915090911461127
2017-09-15 09:09:13,937 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-15 09:09:13,948 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:09:13,956 [salt.loaded.int.module.cmdmod][ERROR   ][22735] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-15 09:09:13,957 [salt.loaded.int.module.cmdmod][ERROR   ][22735] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-15 09:09:13,957 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local/system.qcow2' in directory '/root'
2017-09-15 09:09:13,974 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-15 09:09:13,984 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-15 09:09:15,019 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-15 09:09:15,530 [salt.loaded.ext.module.seedng][INFO    ][22735] salt-minion pre-installed on image, configuring as msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local
2017-09-15 09:09:15,534 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'mount -l' in directory '/root'
2017-09-15 09:09:15,762 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-15 09:09:18,947 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-15 09:09:20,311 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:09:20,311 [salt.state       ][INFO    ][22735] Completed state [virtng.init] at time 09:09:20.311395 duration_in_ms=9333.796
2017-09-15 09:09:20,312 [salt.state       ][INFO    ][22735] Running state [virt.set_autostart] at time 09:09:20.311649
2017-09-15 09:09:20,312 [salt.state       ][INFO    ][22735] Executing state module.run for virt.set_autostart
2017-09-15 09:09:20,314 [salt.loaded.int.module.cmdmod][INFO    ][22735] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-09-15 09:09:20,336 [salt.state       ][INFO    ][22735] {'ret': True}
2017-09-15 09:09:20,336 [salt.state       ][INFO    ][22735] Completed state [virt.set_autostart] at time 09:09:20.336025 duration_in_ms=24.374
2017-09-15 09:09:20,338 [salt.minion      ][INFO    ][22735] Returning information for job: 20170915085853697983
2017-09-15 09:10:37,782 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170915091037770686
2017-09-15 09:10:37,800 [salt.minion      ][INFO    ][24363] Starting a new job with PID 24363
2017-09-15 09:10:41,881 [salt.state       ][INFO    ][24363] Loading fresh modules for state activity
2017-09-15 09:10:41,945 [salt.state       ][ERROR   ][24363] No contents found in top file
2017-09-15 09:10:41,946 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-15 09:10:41,949 [salt.utils.extmods][INFO    ][24363] Syncing beacons for environment 'base'
2017-09-15 09:10:41,949 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_beacons, for base)
2017-09-15 09:10:41,949 [salt.fileclient  ][INFO    ][24363] Caching directory '_beacons/' for environment 'base'
2017-09-15 09:10:41,989 [salt.utils.extmods][INFO    ][24363] Syncing modules for environment 'base'
2017-09-15 09:10:41,990 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_modules, for base)
2017-09-15 09:10:41,990 [salt.fileclient  ][INFO    ][24363] Caching directory '_modules/' for environment 'base'
2017-09-15 09:10:42,035 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-15 09:10:42,042 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-15 09:10:42,074 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-15 09:10:42,083 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-15 09:10:42,096 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-15 09:10:42,113 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-15 09:10:42,125 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-15 09:10:42,132 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-15 09:10:42,140 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-15 09:10:42,148 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-15 09:10:42,212 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-15 09:10:42,238 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-15 09:10:42,399 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-15 09:10:42,419 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-15 09:10:42,440 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-15 09:10:42,489 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-15 09:10:42,532 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-15 09:10:42,546 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-15 09:10:42,574 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-15 09:10:42,599 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-15 09:10:42,670 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-15 09:10:42,691 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-15 09:10:42,728 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-15 09:10:42,757 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-15 09:10:42,765 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-15 09:10:42,798 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-15 09:10:42,825 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-15 09:10:42,837 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-15 09:10:42,855 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-15 09:10:42,869 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-15 09:10:42,897 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-15 09:10:42,912 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-15 09:10:42,915 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-15 09:10:42,915 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-15 09:10:42,915 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-15 09:10:42,916 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-15 09:10:42,916 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-15 09:10:42,916 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-15 09:10:42,916 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-15 09:10:42,916 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-15 09:10:42,917 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-15 09:10:42,917 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-15 09:10:42,917 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-15 09:10:42,917 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-15 09:10:42,918 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-15 09:10:42,918 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-15 09:10:42,918 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-15 09:10:42,918 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-15 09:10:42,918 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-15 09:10:42,919 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-15 09:10:42,919 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-15 09:10:42,919 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-15 09:10:42,919 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-15 09:10:42,920 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-15 09:10:42,920 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-15 09:10:42,920 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-15 09:10:42,920 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-15 09:10:42,921 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-15 09:10:42,921 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-15 09:10:42,921 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-15 09:10:42,921 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-15 09:10:42,921 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-15 09:10:42,922 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-15 09:10:42,922 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-09-15 09:10:42,922 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-15 09:10:42,922 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-15 09:10:42,927 [salt.utils.extmods][INFO    ][24363] Syncing states for environment 'base'
2017-09-15 09:10:42,927 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_states, for base)
2017-09-15 09:10:42,927 [salt.fileclient  ][INFO    ][24363] Caching directory '_states/' for environment 'base'
2017-09-15 09:10:43,025 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-15 09:10:43,050 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-15 09:10:43,069 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-15 09:10:43,095 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-15 09:10:43,114 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-15 09:10:43,125 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-15 09:10:43,135 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-15 09:10:43,164 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-15 09:10:43,201 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-15 09:10:43,212 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-15 09:10:43,230 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-15 09:10:43,378 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-15 09:10:43,401 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-15 09:10:43,445 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-15 09:10:43,493 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-15 09:10:43,538 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-15 09:10:43,582 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-15 09:10:43,627 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-15 09:10:43,649 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-15 09:10:43,663 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-15 09:10:43,701 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-15 09:10:43,729 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-15 09:10:43,760 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-15 09:10:43,815 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-15 09:10:43,834 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-15 09:10:43,882 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-15 09:10:43,930 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-15 09:10:43,978 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-15 09:10:44,004 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-15 09:10:44,007 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-15 09:10:44,008 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-15 09:10:44,008 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-15 09:10:44,008 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-15 09:10:44,009 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-15 09:10:44,009 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-15 09:10:44,009 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-15 09:10:44,009 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-15 09:10:44,009 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-15 09:10:44,010 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-15 09:10:44,010 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-15 09:10:44,010 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-15 09:10:44,010 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-15 09:10:44,010 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-15 09:10:44,011 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-15 09:10:44,011 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-15 09:10:44,011 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-15 09:10:44,011 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-15 09:10:44,011 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-15 09:10:44,012 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-15 09:10:44,012 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-15 09:10:44,012 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-15 09:10:44,013 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-15 09:10:44,013 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-15 09:10:44,013 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-15 09:10:44,014 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-15 09:10:44,014 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-15 09:10:44,014 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-15 09:10:44,014 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-15 09:10:44,015 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-15 09:10:44,017 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-15 09:10:44,020 [salt.utils.extmods][INFO    ][24363] Syncing sdb for environment 'base'
2017-09-15 09:10:44,020 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_sdb, for base)
2017-09-15 09:10:44,020 [salt.fileclient  ][INFO    ][24363] Caching directory '_sdb/' for environment 'base'
2017-09-15 09:10:44,150 [salt.utils.extmods][INFO    ][24363] Syncing grains for environment 'base'
2017-09-15 09:10:44,150 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_grains, for base)
2017-09-15 09:10:44,151 [salt.fileclient  ][INFO    ][24363] Caching directory '_grains/' for environment 'base'
2017-09-15 09:10:44,187 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-15 09:10:44,244 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-15 09:10:44,260 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-15 09:10:44,275 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-15 09:10:44,300 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-15 09:10:44,325 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-15 09:10:44,338 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-15 09:10:44,375 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-15 09:10:44,385 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-15 09:10:44,399 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-15 09:10:44,409 [salt.fileclient  ][INFO    ][24363] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-15 09:10:44,412 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-15 09:10:44,413 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-15 09:10:44,413 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-15 09:10:44,413 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-15 09:10:44,413 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-15 09:10:44,413 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-15 09:10:44,414 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-15 09:10:44,414 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-15 09:10:44,414 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-15 09:10:44,414 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-15 09:10:44,414 [salt.utils.extmods][INFO    ][24363] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-15 09:10:44,416 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-15 09:10:44,418 [salt.utils.extmods][INFO    ][24363] Syncing renderers for environment 'base'
2017-09-15 09:10:44,418 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_renderers, for base)
2017-09-15 09:10:44,418 [salt.fileclient  ][INFO    ][24363] Caching directory '_renderers/' for environment 'base'
2017-09-15 09:10:44,451 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-15 09:10:44,454 [salt.utils.extmods][INFO    ][24363] Syncing returners for environment 'base'
2017-09-15 09:10:44,454 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_returners, for base)
2017-09-15 09:10:44,454 [salt.fileclient  ][INFO    ][24363] Caching directory '_returners/' for environment 'base'
2017-09-15 09:10:44,577 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-15 09:10:44,579 [salt.utils.extmods][INFO    ][24363] Syncing output for environment 'base'
2017-09-15 09:10:44,579 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_output, for base)
2017-09-15 09:10:44,579 [salt.fileclient  ][INFO    ][24363] Caching directory '_output/' for environment 'base'
2017-09-15 09:10:44,662 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-15 09:10:44,664 [salt.utils.extmods][INFO    ][24363] Syncing utils for environment 'base'
2017-09-15 09:10:44,664 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_utils, for base)
2017-09-15 09:10:44,664 [salt.fileclient  ][INFO    ][24363] Caching directory '_utils/' for environment 'base'
2017-09-15 09:10:44,743 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-15 09:10:44,745 [salt.utils.extmods][INFO    ][24363] Syncing log_handlers for environment 'base'
2017-09-15 09:10:44,746 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_log_handlers, for base)
2017-09-15 09:10:44,746 [salt.fileclient  ][INFO    ][24363] Caching directory '_log_handlers/' for environment 'base'
2017-09-15 09:10:44,791 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-15 09:10:44,793 [salt.utils.extmods][INFO    ][24363] Syncing proxy for environment 'base'
2017-09-15 09:10:44,793 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_proxy, for base)
2017-09-15 09:10:44,794 [salt.fileclient  ][INFO    ][24363] Caching directory '_proxy/' for environment 'base'
2017-09-15 09:10:44,822 [salt.utils.extmods][INFO    ][24363] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-15 09:10:44,824 [salt.utils.extmods][INFO    ][24363] Syncing engines for environment 'base'
2017-09-15 09:10:44,824 [salt.utils.extmods][INFO    ][24363] Loading cache from salt://_engines, for base)
2017-09-15 09:10:44,824 [salt.fileclient  ][INFO    ][24363] Caching directory '_engines/' for environment 'base'
2017-09-15 09:10:44,869 [salt.minion      ][INFO    ][24363] Returning information for job: 20170915091037770686
2017-09-15 09:20:13,289 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command test.ping with jid 20170915092013278957
2017-09-15 09:20:13,305 [salt.minion      ][INFO    ][24575] Starting a new job with PID 24575
2017-09-15 09:20:13,329 [salt.minion      ][INFO    ][24575] Returning information for job: 20170915092013278957
2017-09-15 09:23:14,485 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command state.sls with jid 20170915092314475800
2017-09-15 09:23:14,502 [salt.minion      ][INFO    ][24626] Starting a new job with PID 24626
2017-09-15 09:23:14,879 [salt.state       ][INFO    ][24626] Loading fresh modules for state activity
2017-09-15 09:23:14,908 [salt.fileclient  ][INFO    ][24626] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-09-15 09:23:14,924 [salt.fileclient  ][INFO    ][24626] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-09-15 09:23:14,947 [salt.fileclient  ][INFO    ][24626] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 09:23:15,234 [salt.state       ][INFO    ][24626] Running state [lsof] at time 09:23:15.234386
2017-09-15 09:23:15,235 [salt.state       ][INFO    ][24626] Executing state pkg.installed for lsof
2017-09-15 09:23:15,235 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 09:23:15,482 [salt.state       ][INFO    ][24626] Package lsof is already installed
2017-09-15 09:23:15,483 [salt.state       ][INFO    ][24626] Completed state [lsof] at time 09:23:15.482684 duration_in_ms=248.296
2017-09-15 09:23:15,483 [salt.state       ][INFO    ][24626] Running state [keepalived] at time 09:23:15.482879
2017-09-15 09:23:15,483 [salt.state       ][INFO    ][24626] Executing state pkg.installed for keepalived
2017-09-15 09:23:15,497 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-15 09:23:17,571 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2017-09-15 09:23:24,530 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915092324519281
2017-09-15 09:23:24,545 [salt.minion      ][INFO    ][25297] Starting a new job with PID 25297
2017-09-15 09:23:24,561 [salt.minion      ][INFO    ][25297] Returning information for job: 20170915092324519281
2017-09-15 09:23:28,820 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 09:23:28,843 [salt.state       ][INFO    ][24626] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'keepalived' changed from 'absent' to '1:1.2.19-1ubuntu0.2'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libsensors4' changed from 'absent' to '1:3.4.0-2'

2017-09-15 09:23:28,851 [salt.state       ][INFO    ][24626] Loading fresh modules for state activity
2017-09-15 09:23:28,863 [salt.state       ][INFO    ][24626] Completed state [keepalived] at time 09:23:28.862949 duration_in_ms=13380.069
2017-09-15 09:23:28,866 [salt.state       ][INFO    ][24626] Running state [/etc/keepalived/keepalived.conf] at time 09:23:28.865546
2017-09-15 09:23:28,866 [salt.state       ][INFO    ][24626] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-09-15 09:23:28,888 [salt.fileclient  ][INFO    ][24626] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-09-15 09:23:28,917 [salt.fileclient  ][INFO    ][24626] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-15 09:23:28,923 [salt.state       ][INFO    ][24626] File changed:
New file
2017-09-15 09:23:28,923 [salt.state       ][INFO    ][24626] Completed state [/etc/keepalived/keepalived.conf] at time 09:23:28.922792 duration_in_ms=57.245
2017-09-15 09:23:28,978 [salt.state       ][INFO    ][24626] Running state [keepalived] at time 09:23:28.978078
2017-09-15 09:23:28,978 [salt.state       ][INFO    ][24626] Executing state service.running for keepalived
2017-09-15 09:23:28,980 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-09-15 09:23:28,988 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-15 09:23:28,995 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 09:23:29,002 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 09:23:29,010 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-09-15 09:23:29,064 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-15 09:23:29,071 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 09:23:29,078 [salt.loaded.int.module.cmdmod][INFO    ][24626] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-15 09:23:29,083 [salt.state       ][INFO    ][24626] {'keepalived': True}
2017-09-15 09:23:29,084 [salt.state       ][INFO    ][24626] Completed state [keepalived] at time 09:23:29.083695 duration_in_ms=105.616
2017-09-15 09:23:29,085 [salt.minion      ][INFO    ][24626] Returning information for job: 20170915092314475800
2017-09-15 09:24:12,402 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command pillar.get with jid 20170915092412397304
2017-09-15 09:24:12,423 [salt.minion      ][INFO    ][26219] Starting a new job with PID 26219
2017-09-15 09:24:12,428 [salt.minion      ][INFO    ][26219] Returning information for job: 20170915092412397304
2017-09-15 09:29:29,816 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command state.sls with jid 20170915092929810305
2017-09-15 09:29:29,833 [salt.minion      ][INFO    ][26304] Starting a new job with PID 26304
2017-09-15 09:29:32,229 [salt.state       ][INFO    ][26304] Loading fresh modules for state activity
2017-09-15 09:29:32,256 [salt.fileclient  ][INFO    ][26304] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-15 09:29:32,275 [salt.fileclient  ][INFO    ][26304] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-15 09:29:32,539 [salt.state       ][INFO    ][26304] Running state [attr] at time 09:29:32.538497
2017-09-15 09:29:32,539 [salt.state       ][INFO    ][26304] Executing state pkg.installed for attr
2017-09-15 09:29:32,539 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 09:29:32,849 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-15 09:29:34,841 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'attr'] in directory '/root'
2017-09-15 09:29:37,141 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 09:29:37,165 [salt.state       ][INFO    ][26304] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-09-15 09:29:37,173 [salt.state       ][INFO    ][26304] Loading fresh modules for state activity
2017-09-15 09:29:37,185 [salt.state       ][INFO    ][26304] Completed state [attr] at time 09:29:37.184568 duration_in_ms=4646.071
2017-09-15 09:29:37,189 [salt.state       ][INFO    ][26304] Running state [glusterfs-server] at time 09:29:37.189224
2017-09-15 09:29:37,189 [salt.state       ][INFO    ][26304] Executing state pkg.installed for glusterfs-server
2017-09-15 09:29:37,383 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'glusterfs-server'] in directory '/root'
2017-09-15 09:29:39,907 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915092939894978
2017-09-15 09:29:39,924 [salt.minion      ][INFO    ][27314] Starting a new job with PID 27314
2017-09-15 09:29:39,934 [salt.minion      ][INFO    ][27314] Returning information for job: 20170915092939894978
2017-09-15 09:29:50,112 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915092950100257
2017-09-15 09:29:50,127 [salt.minion      ][INFO    ][30110] Starting a new job with PID 30110
2017-09-15 09:29:50,137 [salt.minion      ][INFO    ][30110] Returning information for job: 20170915092950100257
2017-09-15 09:29:55,351 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 09:29:55,375 [salt.state       ][INFO    ][26304] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'glusterfs-common' changed from 'absent' to '3.7.6-1ubuntu1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'portmap' changed from 'absent' to '1'
'libattr1-dev' changed from 'absent' to '1:2.4.47-2'
'attr-dev' changed from 'absent' to '1'
'acl-dev' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.7.6-1ubuntu1'
'glusterfs-client' changed from 'absent' to '3.7.6-1ubuntu1'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.1'
'nfs-client' changed from 'absent' to '1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'libacl1-dev' changed from 'absent' to '2.2.52-3'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'

2017-09-15 09:29:55,382 [salt.state       ][INFO    ][26304] Loading fresh modules for state activity
2017-09-15 09:29:55,394 [salt.state       ][INFO    ][26304] Completed state [glusterfs-server] at time 09:29:55.393609 duration_in_ms=18204.385
2017-09-15 09:29:55,398 [salt.state       ][INFO    ][26304] Running state [bc] at time 09:29:55.397967
2017-09-15 09:29:55,398 [salt.state       ][INFO    ][26304] Executing state pkg.installed for bc
2017-09-15 09:29:55,632 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'bc'] in directory '/root'
2017-09-15 09:29:58,396 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 09:29:58,422 [salt.state       ][INFO    ][26304] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-09-15 09:29:58,432 [salt.state       ][INFO    ][26304] Loading fresh modules for state activity
2017-09-15 09:29:58,446 [salt.state       ][INFO    ][26304] Completed state [bc] at time 09:29:58.445760 duration_in_ms=3047.793
2017-09-15 09:29:58,508 [salt.state       ][INFO    ][26304] Running state [glusterfs-server] at time 09:29:58.507952
2017-09-15 09:29:58,508 [salt.state       ][INFO    ][26304] Executing state service.running for glusterfs-server
2017-09-15 09:29:58,509 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-15 09:29:58,519 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-15 09:29:58,526 [salt.loaded.int.module.cmdmod][INFO    ][26304] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-15 09:29:58,534 [salt.state       ][INFO    ][26304] The service glusterfs-server is already running
2017-09-15 09:29:58,534 [salt.state       ][INFO    ][26304] Completed state [glusterfs-server] at time 09:29:58.534130 duration_in_ms=26.178
2017-09-15 09:29:58,536 [salt.state       ][INFO    ][26304] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:29:58.535945
2017-09-15 09:29:58,536 [salt.state       ][INFO    ][26304] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-15 09:29:58,550 [salt.state       ][INFO    ][26304] File changed:
New file
2017-09-15 09:29:58,551 [salt.state       ][INFO    ][26304] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:29:58.550547 duration_in_ms=14.603
2017-09-15 09:29:58,551 [salt.state       ][INFO    ][26304] Running state [/srv/glusterfs/glance] at time 09:29:58.550695
2017-09-15 09:29:58,551 [salt.state       ][INFO    ][26304] Executing state file.directory for /srv/glusterfs/glance
2017-09-15 09:29:58,561 [salt.state       ][INFO    ][26304] {'/srv/glusterfs/glance': 'New Dir'}
2017-09-15 09:29:58,561 [salt.state       ][INFO    ][26304] Completed state [/srv/glusterfs/glance] at time 09:29:58.560722 duration_in_ms=10.025
2017-09-15 09:29:58,561 [salt.state       ][INFO    ][26304] Running state [/srv/glusterfs/keystone-keys] at time 09:29:58.560947
2017-09-15 09:29:58,561 [salt.state       ][INFO    ][26304] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-15 09:29:58,562 [salt.state       ][INFO    ][26304] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-09-15 09:29:58,562 [salt.state       ][INFO    ][26304] Completed state [/srv/glusterfs/keystone-keys] at time 09:29:58.561894 duration_in_ms=0.947
2017-09-15 09:29:58,562 [salt.state       ][INFO    ][26304] Running state [/srv/glusterfs/nova_instances] at time 09:29:58.562112
2017-09-15 09:29:58,562 [salt.state       ][INFO    ][26304] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-15 09:29:58,563 [salt.state       ][INFO    ][26304] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-09-15 09:29:58,563 [salt.state       ][INFO    ][26304] Completed state [/srv/glusterfs/nova_instances] at time 09:29:58.563002 duration_in_ms=0.89
2017-09-15 09:29:58,563 [salt.state       ][INFO    ][26304] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:29:58.563139
2017-09-15 09:29:58,563 [salt.state       ][INFO    ][26304] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-15 09:29:58,564 [salt.state       ][INFO    ][26304] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-09-15 09:29:58,564 [salt.state       ][INFO    ][26304] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:29:58.563776 duration_in_ms=0.636
2017-09-15 09:29:58,565 [salt.minion      ][INFO    ][26304] Returning information for job: 20170915092929810305
2017-09-15 09:29:59,735 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command test.ping with jid 20170915092959730447
2017-09-15 09:29:59,751 [salt.minion      ][INFO    ][31026] Starting a new job with PID 31026
2017-09-15 09:29:59,777 [salt.minion      ][INFO    ][31026] Returning information for job: 20170915092959730447
2017-09-15 09:29:59,916 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command state.sls with jid 20170915092959904314
2017-09-15 09:29:59,928 [salt.minion      ][INFO    ][31031] Starting a new job with PID 31031
2017-09-15 09:30:01,339 [salt.state       ][INFO    ][31031] Loading fresh modules for state activity
2017-09-15 09:30:01,361 [salt.fileclient  ][INFO    ][31031] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-09-15 09:30:01,391 [salt.fileclient  ][INFO    ][31031] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-15 09:30:01,451 [salt.fileclient  ][INFO    ][31031] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-15 09:30:01,470 [salt.fileclient  ][INFO    ][31031] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-15 09:30:01,738 [salt.state       ][INFO    ][31031] Running state [attr] at time 09:30:01.738361
2017-09-15 09:30:01,739 [salt.state       ][INFO    ][31031] Executing state pkg.installed for attr
2017-09-15 09:30:01,739 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-15 09:30:02,022 [salt.state       ][INFO    ][31031] Package attr is already installed
2017-09-15 09:30:02,022 [salt.state       ][INFO    ][31031] Completed state [attr] at time 09:30:02.022039 duration_in_ms=283.678
2017-09-15 09:30:02,022 [salt.state       ][INFO    ][31031] Running state [glusterfs-server] at time 09:30:02.022321
2017-09-15 09:30:02,023 [salt.state       ][INFO    ][31031] Executing state pkg.installed for glusterfs-server
2017-09-15 09:30:02,025 [salt.state       ][INFO    ][31031] Package glusterfs-server is already installed
2017-09-15 09:30:02,025 [salt.state       ][INFO    ][31031] Completed state [glusterfs-server] at time 09:30:02.025298 duration_in_ms=2.977
2017-09-15 09:30:02,026 [salt.state       ][INFO    ][31031] Running state [bc] at time 09:30:02.025500
2017-09-15 09:30:02,026 [salt.state       ][INFO    ][31031] Executing state pkg.installed for bc
2017-09-15 09:30:02,028 [salt.state       ][INFO    ][31031] Package bc is already installed
2017-09-15 09:30:02,028 [salt.state       ][INFO    ][31031] Completed state [bc] at time 09:30:02.028413 duration_in_ms=2.912
2017-09-15 09:30:02,029 [salt.state       ][INFO    ][31031] Running state [glusterfs-server] at time 09:30:02.029248
2017-09-15 09:30:02,029 [salt.state       ][INFO    ][31031] Executing state service.running for glusterfs-server
2017-09-15 09:30:02,030 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-15 09:30:02,040 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-09-15 09:30:02,080 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-15 09:30:02,095 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-15 09:30:02,102 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-15 09:30:02,111 [salt.state       ][INFO    ][31031] The service glusterfs-server is already running
2017-09-15 09:30:02,111 [salt.state       ][INFO    ][31031] Completed state [glusterfs-server] at time 09:30:02.111318 duration_in_ms=82.07
2017-09-15 09:30:02,113 [salt.state       ][INFO    ][31031] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:30:02.112666
2017-09-15 09:30:02,113 [salt.state       ][INFO    ][31031] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-15 09:30:02,117 [salt.state       ][INFO    ][31031] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-09-15 09:30:02,117 [salt.state       ][INFO    ][31031] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:30:02.117426 duration_in_ms=4.76
2017-09-15 09:30:02,118 [salt.state       ][INFO    ][31031] Running state [/srv/glusterfs/glance] at time 09:30:02.117573
2017-09-15 09:30:02,118 [salt.state       ][INFO    ][31031] Executing state file.directory for /srv/glusterfs/glance
2017-09-15 09:30:02,118 [salt.state       ][INFO    ][31031] Directory /srv/glusterfs/glance is in the correct state
2017-09-15 09:30:02,118 [salt.state       ][INFO    ][31031] Completed state [/srv/glusterfs/glance] at time 09:30:02.118172 duration_in_ms=0.599
2017-09-15 09:30:02,118 [salt.state       ][INFO    ][31031] Running state [/srv/glusterfs/keystone-keys] at time 09:30:02.118323
2017-09-15 09:30:02,118 [salt.state       ][INFO    ][31031] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-15 09:30:02,119 [salt.state       ][INFO    ][31031] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-09-15 09:30:02,119 [salt.state       ][INFO    ][31031] Completed state [/srv/glusterfs/keystone-keys] at time 09:30:02.118866 duration_in_ms=0.544
2017-09-15 09:30:02,119 [salt.state       ][INFO    ][31031] Running state [/srv/glusterfs/nova_instances] at time 09:30:02.119002
2017-09-15 09:30:02,119 [salt.state       ][INFO    ][31031] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-15 09:30:02,119 [salt.state       ][INFO    ][31031] Directory /srv/glusterfs/nova_instances is in the correct state
2017-09-15 09:30:02,120 [salt.state       ][INFO    ][31031] Completed state [/srv/glusterfs/nova_instances] at time 09:30:02.119553 duration_in_ms=0.551
2017-09-15 09:30:02,120 [salt.state       ][INFO    ][31031] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:30:02.119691
2017-09-15 09:30:02,120 [salt.state       ][INFO    ][31031] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-15 09:30:02,120 [salt.state       ][INFO    ][31031] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-09-15 09:30:02,120 [salt.state       ][INFO    ][31031] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:30:02.120253 duration_in_ms=0.563
2017-09-15 09:30:02,168 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command ['git', '--version'] in directory '/root'
2017-09-15 09:30:02,252 [salt.state       ][INFO    ][31031] Running state [sleep 5] at time 09:30:02.252278
2017-09-15 09:30:02,253 [salt.state       ][INFO    ][31031] Executing state cmd.wait for sleep 5
2017-09-15 09:30:02,253 [salt.state       ][INFO    ][31031] No changes made for sleep 5
2017-09-15 09:30:02,253 [salt.state       ][INFO    ][31031] Completed state [sleep 5] at time 09:30:02.252827 duration_in_ms=0.55
2017-09-15 09:30:02,253 [salt.state       ][INFO    ][31031] Running state [10.167.4.141] at time 09:30:02.253204
2017-09-15 09:30:02,253 [salt.state       ][INFO    ][31031] Executing state glusterfs.peered for 10.167.4.141
2017-09-15 09:30:02,254 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:02,264 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:02,317 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:02,327 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:02,702 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:02,712 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:02,773 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:02,783 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:02,833 [salt.state       ][INFO    ][31031] {'new': {'10.167.4.141': []}, 'old': None}
2017-09-15 09:30:02,833 [salt.state       ][INFO    ][31031] Completed state [10.167.4.141] at time 09:30:02.833330 duration_in_ms=580.125
2017-09-15 09:30:02,836 [salt.state       ][INFO    ][31031] Running state [10.167.4.142] at time 09:30:02.835777
2017-09-15 09:30:02,836 [salt.state       ][INFO    ][31031] Executing state glusterfs.peered for 10.167.4.142
2017-09-15 09:30:02,837 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:02,846 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,039 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,049 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,514 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,525 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,584 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,593 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,645 [salt.state       ][INFO    ][31031] {'new': {'10.167.4.141': [], '10.167.4.142': []}, 'old': {'10.167.4.141': []}}
2017-09-15 09:30:03,646 [salt.state       ][INFO    ][31031] Completed state [10.167.4.142] at time 09:30:03.645663 duration_in_ms=809.885
2017-09-15 09:30:03,648 [salt.state       ][INFO    ][31031] Running state [10.167.4.143] at time 09:30:03.647948
2017-09-15 09:30:03,648 [salt.state       ][INFO    ][31031] Executing state glusterfs.peered for 10.167.4.143
2017-09-15 09:30:03,649 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,658 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,714 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,723 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,851 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,861 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,912 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,923 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:03,972 [salt.state       ][INFO    ][31031] Peering with localhost is not needed
2017-09-15 09:30:03,972 [salt.state       ][INFO    ][31031] Completed state [10.167.4.143] at time 09:30:03.972436 duration_in_ms=324.486
2017-09-15 09:30:03,973 [salt.state       ][INFO    ][31031] Running state [glance] at time 09:30:03.973220
2017-09-15 09:30:03,973 [salt.state       ][INFO    ][31031] Executing state glusterfs.created for glance
2017-09-15 09:30:03,974 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:03,984 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:04,032 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:04,042 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:04,920 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:04,932 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:05,933 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:05,946 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:05,998 [salt.state       ][INFO    ][31031] {'new': ['glance'], 'old': []}
2017-09-15 09:30:05,999 [salt.state       ][INFO    ][31031] Completed state [glance] at time 09:30:05.998716 duration_in_ms=2025.495
2017-09-15 09:30:06,001 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:06.001460
2017-09-15 09:30:06,002 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 09:30:06,002 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:06,056 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:06,806 [salt.state       ][INFO    ][31031] {'pid': 31256, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:06,807 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:06.806620 duration_in_ms=805.159
2017-09-15 09:30:06,807 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:30:06.807177
2017-09-15 09:30:06,807 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-09-15 09:30:06,808 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 09:30:06,865 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-09-15 09:30:07,477 [salt.state       ][INFO    ][31031] {'pid': 31274, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:07,478 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:30:07.477601 duration_in_ms=670.422
2017-09-15 09:30:07,478 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:30:07.478227
2017-09-15 09:30:07,479 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-09-15 09:30:07,479 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 09:30:07,535 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-09-15 09:30:09,960 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915093009949783
2017-09-15 09:30:09,977 [salt.minion      ][INFO    ][31301] Starting a new job with PID 31301
2017-09-15 09:30:09,987 [salt.minion      ][INFO    ][31301] Returning information for job: 20170915093009949783
2017-09-15 09:30:10,211 [salt.state       ][INFO    ][31031] {'pid': 31291, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:10,211 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:30:10.211153 duration_in_ms=2732.925
2017-09-15 09:30:10,212 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:10.211719
2017-09-15 09:30:10,212 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 09:30:10,213 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:10,269 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:10,938 [salt.state       ][INFO    ][31031] {'pid': 31316, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:10,938 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:10.938028 duration_in_ms=726.308
2017-09-15 09:30:10,939 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:30:10.938618
2017-09-15 09:30:10,939 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-09-15 09:30:10,939 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 09:30:10,997 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-15 09:30:11,621 [salt.state       ][INFO    ][31031] {'pid': 31333, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:11,621 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:30:11.620956 duration_in_ms=682.338
2017-09-15 09:30:11,622 [salt.state       ][INFO    ][31031] Running state [glance] at time 09:30:11.621980
2017-09-15 09:30:11,622 [salt.state       ][INFO    ][31031] Executing state glusterfs.started for glance
2017-09-15 09:30:11,623 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:11,636 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:11,692 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:11,702 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:11,759 [salt.state       ][INFO    ][31031] Volume glance is already started
2017-09-15 09:30:11,760 [salt.state       ][INFO    ][31031] Completed state [glance] at time 09:30:11.759572 duration_in_ms=137.579
2017-09-15 09:30:11,760 [salt.state       ][INFO    ][31031] Running state [keystone-keys] at time 09:30:11.760430
2017-09-15 09:30:11,761 [salt.state       ][INFO    ][31031] Executing state glusterfs.created for keystone-keys
2017-09-15 09:30:11,761 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:11,771 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:11,822 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:11,830 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:12,576 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:12,588 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:15,452 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:15,464 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:15,518 [salt.state       ][INFO    ][31031] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-09-15 09:30:15,520 [salt.state       ][INFO    ][31031] Completed state [keystone-keys] at time 09:30:15.519851 duration_in_ms=3759.418
2017-09-15 09:30:15,523 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:30:15.523381
2017-09-15 09:30:15,524 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-09-15 09:30:15,524 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 09:30:15,578 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-09-15 09:30:16,178 [salt.state       ][INFO    ][31031] {'pid': 31444, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:16,179 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:30:16.178784 duration_in_ms=655.402
2017-09-15 09:30:16,179 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:16.179331
2017-09-15 09:30:16,180 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 09:30:16,180 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:16,239 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:17,233 [salt.state       ][INFO    ][31031] {'pid': 31461, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:17,234 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:17.233571 duration_in_ms=1054.238
2017-09-15 09:30:17,234 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:17.234135
2017-09-15 09:30:17,235 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 09:30:17,235 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:17,291 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:17,975 [salt.state       ][INFO    ][31031] {'pid': 31479, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:17,976 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:17.975465 duration_in_ms=741.33
2017-09-15 09:30:17,976 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:30:17.976072
2017-09-15 09:30:17,976 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-09-15 09:30:17,977 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 09:30:18,035 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-09-15 09:30:20,102 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915093020090269
2017-09-15 09:30:20,118 [salt.minion      ][INFO    ][31510] Starting a new job with PID 31510
2017-09-15 09:30:20,129 [salt.minion      ][INFO    ][31510] Returning information for job: 20170915093020090269
2017-09-15 09:30:20,706 [salt.state       ][INFO    ][31031] {'pid': 31500, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:20,706 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:30:20.705959 duration_in_ms=2729.887
2017-09-15 09:30:20,707 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:30:20.706521
2017-09-15 09:30:20,707 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-09-15 09:30:20,707 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 09:30:20,766 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-15 09:30:21,352 [salt.state       ][INFO    ][31031] {'pid': 31522, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:21,353 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:30:21.352579 duration_in_ms=646.057
2017-09-15 09:30:21,354 [salt.state       ][INFO    ][31031] Running state [keystone-keys] at time 09:30:21.353614
2017-09-15 09:30:21,354 [salt.state       ][INFO    ][31031] Executing state glusterfs.started for keystone-keys
2017-09-15 09:30:21,355 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:21,366 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:21,422 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:21,432 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:21,493 [salt.state       ][INFO    ][31031] Volume keystone-keys is already started
2017-09-15 09:30:21,493 [salt.state       ][INFO    ][31031] Completed state [keystone-keys] at time 09:30:21.493088 duration_in_ms=139.474
2017-09-15 09:30:21,494 [salt.state       ][INFO    ][31031] Running state [nova_instances] at time 09:30:21.493980
2017-09-15 09:30:21,494 [salt.state       ][INFO    ][31031] Executing state glusterfs.created for nova_instances
2017-09-15 09:30:21,495 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:21,505 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:21,557 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:21,567 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:22,290 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:22,301 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:25,140 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:25,152 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:25,206 [salt.state       ][INFO    ][31031] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-09-15 09:30:25,207 [salt.state       ][INFO    ][31031] Completed state [nova_instances] at time 09:30:25.206642 duration_in_ms=3712.662
2017-09-15 09:30:25,210 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:25.210081
2017-09-15 09:30:25,210 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 09:30:25,211 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:25,264 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:25,926 [salt.state       ][INFO    ][31031] {'pid': 31634, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:25,926 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:25.926127 duration_in_ms=716.045
2017-09-15 09:30:25,927 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:30:25.926712
2017-09-15 09:30:25,927 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-09-15 09:30:25,928 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 09:30:25,986 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-15 09:30:26,651 [salt.state       ][INFO    ][31031] {'pid': 31652, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:26,651 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:30:26.651049 duration_in_ms=724.337
2017-09-15 09:30:26,652 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:30:26.651629
2017-09-15 09:30:26,652 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-09-15 09:30:26,652 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 09:30:26,711 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'True'' in directory '/root'
2017-09-15 09:30:27,424 [salt.state       ][INFO    ][31031] {'pid': 31669, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:27,425 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:30:27.424487 duration_in_ms=772.856
2017-09-15 09:30:27,425 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:30:27.425089
2017-09-15 09:30:27,425 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-09-15 09:30:27,426 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 09:30:27,484 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'True'' in directory '/root'
2017-09-15 09:30:30,245 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915093030233704
2017-09-15 09:30:30,260 [salt.minion      ][INFO    ][31701] Starting a new job with PID 31701
2017-09-15 09:30:30,270 [salt.minion      ][INFO    ][31701] Returning information for job: 20170915093030233704
2017-09-15 09:30:30,304 [salt.state       ][INFO    ][31031] {'pid': 31687, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:30,304 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:30:30.304189 duration_in_ms=2879.1
2017-09-15 09:30:30,305 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:30.304723
2017-09-15 09:30:30,305 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 09:30:30,306 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:30,359 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:30,989 [salt.state       ][INFO    ][31031] {'pid': 31713, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:30,989 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:30.988877 duration_in_ms=684.153
2017-09-15 09:30:30,990 [salt.state       ][INFO    ][31031] Running state [nova_instances] at time 09:30:30.989922
2017-09-15 09:30:30,990 [salt.state       ][INFO    ][31031] Executing state glusterfs.started for nova_instances
2017-09-15 09:30:30,991 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:31,002 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:31,056 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:31,066 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:31,126 [salt.state       ][INFO    ][31031] Volume nova_instances is already started
2017-09-15 09:30:31,126 [salt.state       ][INFO    ][31031] Completed state [nova_instances] at time 09:30:31.125927 duration_in_ms=136.004
2017-09-15 09:30:31,127 [salt.state       ][INFO    ][31031] Running state [keystone-credential-keys] at time 09:30:31.126852
2017-09-15 09:30:31,127 [salt.state       ][INFO    ][31031] Executing state glusterfs.created for keystone-credential-keys
2017-09-15 09:30:31,128 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:31,138 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:31,191 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:31,200 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:31,925 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:31,937 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:34,923 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:34,935 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:34,989 [salt.state       ][INFO    ][31031] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-09-15 09:30:34,990 [salt.state       ][INFO    ][31031] Completed state [keystone-credential-keys] at time 09:30:34.989651 duration_in_ms=3862.798
2017-09-15 09:30:34,993 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:30:34.993189
2017-09-15 09:30:34,994 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-09-15 09:30:34,994 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-15 09:30:35,048 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-15 09:30:35,697 [salt.state       ][INFO    ][31031] {'pid': 31829, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:35,697 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:30:35.697013 duration_in_ms=703.824
2017-09-15 09:30:35,698 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:35.697660
2017-09-15 09:30:35,698 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-15 09:30:35,699 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:35,753 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:36,336 [salt.state       ][INFO    ][31031] {'pid': 31846, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:36,337 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:30:36.336723 duration_in_ms=639.063
2017-09-15 09:30:36,337 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:30:36.337323
2017-09-15 09:30:36,338 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-09-15 09:30:36,338 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-15 09:30:36,393 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-09-15 09:30:39,084 [salt.state       ][INFO    ][31031] {'pid': 31864, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:39,085 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:30:39.084476 duration_in_ms=2747.153
2017-09-15 09:30:39,085 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:39.085046
2017-09-15 09:30:39,085 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-15 09:30:39,086 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-15 09:30:39,143 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-15 09:30:39,835 [salt.state       ][INFO    ][31031] {'pid': 31883, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:39,835 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:30:39.835027 duration_in_ms=749.979
2017-09-15 09:30:39,836 [salt.state       ][INFO    ][31031] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:30:39.835602
2017-09-15 09:30:39,836 [salt.state       ][INFO    ][31031] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-09-15 09:30:39,836 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-15 09:30:39,892 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-09-15 09:30:40,384 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command saltutil.find_job with jid 20170915093040373044
2017-09-15 09:30:40,398 [salt.minion      ][INFO    ][31910] Starting a new job with PID 31910
2017-09-15 09:30:40,409 [salt.minion      ][INFO    ][31910] Returning information for job: 20170915093040373044
2017-09-15 09:30:40,498 [salt.state       ][INFO    ][31031] {'pid': 31900, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-15 09:30:40,499 [salt.state       ][INFO    ][31031] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:30:40.498801 duration_in_ms=663.198
2017-09-15 09:30:40,500 [salt.state       ][INFO    ][31031] Running state [keystone-credential-keys] at time 09:30:40.499867
2017-09-15 09:30:40,500 [salt.state       ][INFO    ][31031] Executing state glusterfs.started for keystone-credential-keys
2017-09-15 09:30:40,501 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:40,511 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:40,565 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --version' in directory '/root'
2017-09-15 09:30:40,575 [salt.loaded.int.module.cmdmod][INFO    ][31031] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-15 09:30:40,631 [salt.state       ][INFO    ][31031] Volume keystone-credential-keys is already started
2017-09-15 09:30:40,631 [salt.state       ][INFO    ][31031] Completed state [keystone-credential-keys] at time 09:30:40.630965 duration_in_ms=131.097
2017-09-15 09:30:40,633 [salt.minion      ][INFO    ][31031] Returning information for job: 20170915092959904314
2017-09-15 09:30:50,528 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command test.ping with jid 20170915093050519061
2017-09-15 09:30:50,544 [salt.minion      ][INFO    ][31937] Starting a new job with PID 31937
2017-09-15 09:30:50,569 [salt.minion      ][INFO    ][31937] Returning information for job: 20170915093050519061
2017-09-15 09:30:50,709 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command cmd.run with jid 20170915093050690913
2017-09-15 09:30:50,723 [salt.minion      ][INFO    ][31942] Starting a new job with PID 31942
2017-09-15 09:30:50,727 [salt.loaded.int.module.cmdmod][INFO    ][31942] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-09-15 09:30:50,835 [salt.minion      ][INFO    ][31942] Returning information for job: 20170915093050690913
2017-09-15 09:56:13,527 [salt.utils.schedule][INFO    ][22541] Running scheduled job: __mine_interval
2017-09-15 10:32:52,554 [salt.minion      ][INFO    ][22541] User sudo_ubuntu Executing command cp.push_dir with jid 20170915103252544032
2017-09-15 10:32:52,572 [salt.minion      ][INFO    ][32654] Starting a new job with PID 32654
