2017-09-16 21:19:39,348 [salt.cli.daemons ][WARNING ][2620] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-16 21:19:44,001 [salt.loaded.int.module.cmdmod][ERROR   ][2975] 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-16 21:19:44,002 [salt.loaded.int.module.cmdmod][ERROR   ][2975] stderr: Running scope as unit run-rb26dd78a10444cafaa0b58f3800714ca.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-16 21:19:44,002 [salt.loaded.int.module.cmdmod][ERROR   ][2975] retcode: 100
2017-09-16 21:19:44,029 [salt.minion      ][ERROR   ][2975] A command in 'pkg.install' had a problem: Problem encountered installing package(s). Additional info follows:

changes:
    ----------
errors:
    - Running scope as unit run-rb26dd78a10444cafaa0b58f3800714ca.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-16 21:19:57,233 [salt.utils.parsers][WARNING ][2620] Minion received a SIGTERM. Exiting.
2017-09-16 21:22:21,700 [salt.cli.daemons ][WARNING ][3326] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-16 21:25:14,879 [py.warnings      ][WARNING ][15364] /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-16 21:26:05,480 [salt.state       ][ERROR   ][15364] Module function virtng.init is not available
2017-09-16 21:26:05,521 [salt.state       ][ERROR   ][15364] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-16 21:26:05,565 [salt.state       ][ERROR   ][15364] Module function virtng.init is not available
2017-09-16 21:26:05,606 [salt.state       ][ERROR   ][15364] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-16 21:26:05,649 [salt.state       ][ERROR   ][15364] Module function virtng.init is not available
2017-09-16 21:26:05,691 [salt.state       ][ERROR   ][15364] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-16 21:26:05,734 [salt.state       ][ERROR   ][15364] Module function virtng.init is not available
2017-09-16 21:26:05,777 [salt.state       ][ERROR   ][15364] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-16 21:26:05,822 [salt.state       ][ERROR   ][15364] Module function virtng.init is not available
2017-09-16 21:26:05,863 [salt.state       ][ERROR   ][15364] Module function virt.set_autostart threw an exception. Exception: No virtual machines found.
2017-09-16 21:26:05,876 [salt.loaded.int.module.cmdmod][ERROR   ][15364] 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-16 21:26:08,266 [salt.loaded.int.module.cmdmod][INFO    ][21476] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-16 21:26:08,280 [salt.loaded.int.module.cmdmod][INFO    ][21476] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-16 21:26:08,303 [salt.loaded.int.module.cmdmod][INFO    ][21476] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-09-16 21:26:08,320 [salt.utils.parsers][WARNING ][3326] Minion received a SIGTERM. Exiting.
2017-09-16 21:26:08,755 [salt.cli.daemons ][INFO    ][21549] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-ha.local"
2017-09-16 21:26:09,231 [salt.minion      ][INFO    ][21549] Creating minion process manager
2017-09-16 21:26:09,232 [salt.cli.daemons ][WARNING ][21549] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-16 21:26:09,244 [salt.cli.daemons ][INFO    ][21549] The Salt Minion is starting up
2017-09-16 21:26:09,245 [salt.minion      ][INFO    ][21549] Minion is starting as user 'root'
2017-09-16 21:26:09,246 [salt.utils.event ][INFO    ][21549] Starting pull socket on /var/run/salt/minion/minion_event_fd678c5357_pull.ipc
2017-09-16 21:26:10,072 [salt.loaded.int.module.cmdmod][INFO    ][21549] Executing command ['date', '+%z'] in directory '/root'
2017-09-16 21:26:10,090 [salt.utils.schedule][INFO    ][21549] Updating job settings for scheduled job: __mine_interval
2017-09-16 21:26:10,095 [salt.minion      ][INFO    ][21549] Added mine.update to scheduler
2017-09-16 21:26:10,127 [salt.minion      ][INFO    ][21549] Minion is ready to receive requests!
2017-09-16 21:26:11,129 [salt.utils.schedule][INFO    ][21549] Running scheduled job: __mine_interval
2017-09-16 21:26:55,220 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170916212655218894
2017-09-16 21:26:55,241 [salt.minion      ][INFO    ][21644] Starting a new job with PID 21644
2017-09-16 21:26:57,729 [salt.state       ][INFO    ][21644] Loading fresh modules for state activity
2017-09-16 21:26:57,768 [salt.state       ][ERROR   ][21644] No contents found in top file
2017-09-16 21:26:57,769 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-16 21:26:57,775 [salt.utils.extmods][INFO    ][21644] Syncing beacons for environment 'base'
2017-09-16 21:26:57,775 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_beacons, for base)
2017-09-16 21:26:57,776 [salt.fileclient  ][INFO    ][21644] Caching directory '_beacons/' for environment 'base'
2017-09-16 21:26:57,816 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/modules'
2017-09-16 21:26:57,821 [salt.utils.extmods][INFO    ][21644] Syncing modules for environment 'base'
2017-09-16 21:26:57,821 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_modules, for base)
2017-09-16 21:26:57,822 [salt.fileclient  ][INFO    ][21644] Caching directory '_modules/' for environment 'base'
2017-09-16 21:26:57,879 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-16 21:26:57,892 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-16 21:26:57,908 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-16 21:26:57,922 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-16 21:26:57,935 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-16 21:26:57,948 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-16 21:26:57,962 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-16 21:26:57,977 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-16 21:26:58,012 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-16 21:26:58,027 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-16 21:26:58,040 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-16 21:26:58,054 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-16 21:26:58,070 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-16 21:26:58,083 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-16 21:26:58,098 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-16 21:26:58,113 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-16 21:26:58,131 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-16 21:26:58,143 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-16 21:26:58,170 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-16 21:26:58,184 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-16 21:26:58,201 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-16 21:26:58,215 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-16 21:26:58,229 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-16 21:26:58,243 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-16 21:26:58,256 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-16 21:26:58,271 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-16 21:26:58,284 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-16 21:26:58,305 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-16 21:26:58,318 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-16 21:26:58,330 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-16 21:26:58,342 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-16 21:26:58,357 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-16 21:26:58,363 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-16 21:26:58,363 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-16 21:26:58,364 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-16 21:26:58,364 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-16 21:26:58,365 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-16 21:26:58,365 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-16 21:26:58,366 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-16 21:26:58,366 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-16 21:26:58,367 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-16 21:26:58,367 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-16 21:26:58,367 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-16 21:26:58,368 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-16 21:26:58,368 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-16 21:26:58,369 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-16 21:26:58,369 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-16 21:26:58,370 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-16 21:26:58,370 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-16 21:26:58,371 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-16 21:26:58,371 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-16 21:26:58,371 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-16 21:26:58,372 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-16 21:26:58,372 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-16 21:26:58,373 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-16 21:26:58,373 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-16 21:26:58,373 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-16 21:26:58,374 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-16 21:26:58,374 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-16 21:26:58,375 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-16 21:26:58,375 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-16 21:26:58,376 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-16 21:26:58,376 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-16 21:26:58,376 [salt.utils.extmods][INFO    ][21644] 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-16 21:26:58,377 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-16 21:26:58,377 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-16 21:26:58,380 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/states'
2017-09-16 21:26:58,386 [salt.utils.extmods][INFO    ][21644] Syncing states for environment 'base'
2017-09-16 21:26:58,386 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_states, for base)
2017-09-16 21:26:58,386 [salt.fileclient  ][INFO    ][21644] Caching directory '_states/' for environment 'base'
2017-09-16 21:26:58,442 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-16 21:26:58,458 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-16 21:26:58,474 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-16 21:26:58,488 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-16 21:26:58,503 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-16 21:26:58,521 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-16 21:26:58,533 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-16 21:26:58,547 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-16 21:26:58,559 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-16 21:26:58,573 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-16 21:26:58,586 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-16 21:26:58,600 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-16 21:26:58,614 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-16 21:26:58,630 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-16 21:26:58,642 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-16 21:26:58,655 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-16 21:26:58,668 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-16 21:26:59,034 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-16 21:26:59,048 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-16 21:26:59,062 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-16 21:26:59,075 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-16 21:26:59,087 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-16 21:26:59,099 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-16 21:26:59,121 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-16 21:26:59,134 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-16 21:26:59,146 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-16 21:26:59,158 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-16 21:26:59,174 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-16 21:26:59,186 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-16 21:26:59,192 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-16 21:26:59,193 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-16 21:26:59,193 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-16 21:26:59,194 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-16 21:26:59,194 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-16 21:26:59,195 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-16 21:26:59,195 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-16 21:26:59,196 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-16 21:26:59,196 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-16 21:26:59,197 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-16 21:26:59,197 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-16 21:26:59,198 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-16 21:26:59,198 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-16 21:26:59,198 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-16 21:26:59,199 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-16 21:26:59,199 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-16 21:26:59,200 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-16 21:26:59,200 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-16 21:26:59,201 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-16 21:26:59,201 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-16 21:26:59,202 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-16 21:26:59,202 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-16 21:26:59,202 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-16 21:26:59,203 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-16 21:26:59,203 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-16 21:26:59,204 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-16 21:26:59,204 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-16 21:26:59,205 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-16 21:26:59,205 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-16 21:26:59,205 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-16 21:26:59,207 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-16 21:26:59,212 [salt.utils.extmods][INFO    ][21644] Syncing sdb for environment 'base'
2017-09-16 21:26:59,212 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_sdb, for base)
2017-09-16 21:26:59,213 [salt.fileclient  ][INFO    ][21644] Caching directory '_sdb/' for environment 'base'
2017-09-16 21:26:59,255 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/grains'
2017-09-16 21:26:59,260 [salt.utils.extmods][INFO    ][21644] Syncing grains for environment 'base'
2017-09-16 21:26:59,261 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_grains, for base)
2017-09-16 21:26:59,261 [salt.fileclient  ][INFO    ][21644] Caching directory '_grains/' for environment 'base'
2017-09-16 21:26:59,310 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-16 21:26:59,323 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-16 21:26:59,335 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-16 21:26:59,348 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-16 21:26:59,361 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-16 21:26:59,375 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-16 21:26:59,387 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-16 21:26:59,400 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-16 21:26:59,414 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-16 21:26:59,427 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-16 21:26:59,440 [salt.fileclient  ][INFO    ][21644] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-16 21:26:59,447 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-16 21:26:59,448 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-16 21:26:59,448 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-16 21:26:59,449 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-16 21:26:59,449 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-16 21:26:59,450 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-16 21:26:59,450 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-16 21:26:59,451 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-16 21:26:59,451 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-16 21:26:59,451 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-16 21:26:59,452 [salt.utils.extmods][INFO    ][21644] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-16 21:26:59,453 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-16 21:26:59,458 [salt.utils.extmods][INFO    ][21644] Syncing renderers for environment 'base'
2017-09-16 21:26:59,459 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_renderers, for base)
2017-09-16 21:26:59,459 [salt.fileclient  ][INFO    ][21644] Caching directory '_renderers/' for environment 'base'
2017-09-16 21:26:59,500 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-16 21:26:59,505 [salt.utils.extmods][INFO    ][21644] Syncing returners for environment 'base'
2017-09-16 21:26:59,505 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_returners, for base)
2017-09-16 21:26:59,505 [salt.fileclient  ][INFO    ][21644] Caching directory '_returners/' for environment 'base'
2017-09-16 21:26:59,548 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-16 21:26:59,553 [salt.utils.extmods][INFO    ][21644] Syncing output for environment 'base'
2017-09-16 21:26:59,554 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_output, for base)
2017-09-16 21:26:59,554 [salt.fileclient  ][INFO    ][21644] Caching directory '_output/' for environment 'base'
2017-09-16 21:26:59,597 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-16 21:26:59,602 [salt.utils.extmods][INFO    ][21644] Syncing utils for environment 'base'
2017-09-16 21:26:59,602 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_utils, for base)
2017-09-16 21:26:59,602 [salt.fileclient  ][INFO    ][21644] Caching directory '_utils/' for environment 'base'
2017-09-16 21:26:59,644 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-16 21:26:59,649 [salt.utils.extmods][INFO    ][21644] Syncing log_handlers for environment 'base'
2017-09-16 21:26:59,649 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_log_handlers, for base)
2017-09-16 21:26:59,649 [salt.fileclient  ][INFO    ][21644] Caching directory '_log_handlers/' for environment 'base'
2017-09-16 21:26:59,692 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-16 21:26:59,697 [salt.utils.extmods][INFO    ][21644] Syncing proxy for environment 'base'
2017-09-16 21:26:59,697 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_proxy, for base)
2017-09-16 21:26:59,697 [salt.fileclient  ][INFO    ][21644] Caching directory '_proxy/' for environment 'base'
2017-09-16 21:26:59,741 [salt.utils.extmods][INFO    ][21644] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-16 21:26:59,746 [salt.utils.extmods][INFO    ][21644] Syncing engines for environment 'base'
2017-09-16 21:26:59,747 [salt.utils.extmods][INFO    ][21644] Loading cache from salt://_engines, for base)
2017-09-16 21:26:59,747 [salt.fileclient  ][INFO    ][21644] Caching directory '_engines/' for environment 'base'
2017-09-16 21:26:59,796 [salt.minion      ][INFO    ][21644] Returning information for job: 20170916212655218894
2017-09-16 21:27:03,689 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command state.sls with jid 20170916212703683195
2017-09-16 21:27:03,711 [salt.minion      ][INFO    ][21740] Starting a new job with PID 21740
2017-09-16 21:27:04,342 [salt.state       ][INFO    ][21740] Loading fresh modules for state activity
2017-09-16 21:27:04,385 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-09-16 21:27:04,413 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-09-16 21:27:04,452 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-16 21:27:04,591 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-09-16 21:27:04,605 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-09-16 21:27:04,630 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-16 21:27:04,681 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-16 21:27:04,697 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-16 21:27:04,738 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-16 21:27:04,755 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-16 21:27:04,767 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-16 21:27:04,782 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-16 21:27:04,789 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-16 21:27:04,823 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-16 21:27:04,855 [py.warnings      ][WARNING ][21740] /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-16 21:27:04,855 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'salt-minion --version' in directory '/root'
2017-09-16 21:27:05,239 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-16 21:27:05,263 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-16 21:27:05,280 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-16 21:27:05,296 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-16 21:27:05,371 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-16 21:27:05,420 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-16 21:27:05,511 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-09-16 21:27:05,549 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-16 21:27:05,640 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-16 21:27:05,668 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-16 21:27:05,733 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-16 21:27:05,759 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-16 21:27:05,773 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-16 21:27:05,791 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-16 21:27:05,801 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-16 21:27:05,833 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-16 21:27:05,855 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'salt-minion --version' in directory '/root'
2017-09-16 21:27:06,270 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-16 21:27:06,297 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-16 21:27:06,314 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-16 21:27:06,331 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-16 21:27:06,411 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-16 21:27:06,463 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-16 21:27:06,560 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-09-16 21:27:06,633 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-16 21:27:07,170 [salt.state       ][INFO    ][21740] Running state [salt-minion] at time 21:27:07.169953
2017-09-16 21:27:07,170 [salt.state       ][INFO    ][21740] Executing state pkg.installed for salt-minion
2017-09-16 21:27:07,171 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:27:07,638 [salt.state       ][INFO    ][21740] Package salt-minion is already installed
2017-09-16 21:27:07,638 [salt.state       ][INFO    ][21740] Completed state [salt-minion] at time 21:27:07.637825 duration_in_ms=467.873
2017-09-16 21:27:07,638 [salt.state       ][INFO    ][21740] Running state [python-yaml] at time 21:27:07.638145
2017-09-16 21:27:07,638 [salt.state       ][INFO    ][21740] Executing state pkg.installed for python-yaml
2017-09-16 21:27:07,642 [salt.state       ][INFO    ][21740] Package python-yaml is already installed
2017-09-16 21:27:07,643 [salt.state       ][INFO    ][21740] Completed state [python-yaml] at time 21:27:07.642652 duration_in_ms=4.507
2017-09-16 21:27:07,643 [salt.state       ][INFO    ][21740] Running state [python-psutil] at time 21:27:07.642892
2017-09-16 21:27:07,643 [salt.state       ][INFO    ][21740] Executing state pkg.installed for python-psutil
2017-09-16 21:27:07,647 [salt.state       ][INFO    ][21740] Package python-psutil is already installed
2017-09-16 21:27:07,647 [salt.state       ][INFO    ][21740] Completed state [python-psutil] at time 21:27:07.647263 duration_in_ms=4.371
2017-09-16 21:27:07,648 [salt.state       ][INFO    ][21740] Running state [python-m2crypto] at time 21:27:07.647486
2017-09-16 21:27:07,648 [salt.state       ][INFO    ][21740] Executing state pkg.installed for python-m2crypto
2017-09-16 21:27:07,652 [salt.state       ][INFO    ][21740] Package python-m2crypto is already installed
2017-09-16 21:27:07,652 [salt.state       ][INFO    ][21740] Completed state [python-m2crypto] at time 21:27:07.651702 duration_in_ms=4.216
2017-09-16 21:27:07,654 [salt.state       ][INFO    ][21740] Running state [/etc/salt/minion.d/minion.conf] at time 21:27:07.653712
2017-09-16 21:27:07,654 [salt.state       ][INFO    ][21740] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-09-16 21:27:07,719 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-09-16 21:27:07,809 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-16 21:27:07,879 [salt.fileclient  ][INFO    ][21740] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-16 21:27:07,925 [salt.state       ][INFO    ][21740] File /etc/salt/minion.d/minion.conf is in the correct state
2017-09-16 21:27:07,926 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/minion.d/minion.conf] at time 21:27:07.925605 duration_in_ms=271.893
2017-09-16 21:27:07,926 [salt.state       ][INFO    ][21740] Running state [/etc/salt/minion.d/_virt.conf] at time 21:27:07.926069
2017-09-16 21:27:07,926 [salt.state       ][INFO    ][21740] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-09-16 21:27:07,934 [salt.state       ][INFO    ][21740] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-09-16 21:27:07,934 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/minion.d/_virt.conf] at time 21:27:07.933722 duration_in_ms=7.653
2017-09-16 21:27:07,935 [salt.state       ][INFO    ][21740] Running state [salt-minion] at time 21:27:07.935087
2017-09-16 21:27:07,935 [salt.state       ][INFO    ][21740] Executing state service.running for salt-minion
2017-09-16 21:27:07,936 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-16 21:27:07,969 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-09-16 21:27:07,986 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-16 21:27:08,001 [salt.state       ][INFO    ][21740] The service salt-minion is already running
2017-09-16 21:27:08,002 [salt.state       ][INFO    ][21740] Completed state [salt-minion] at time 21:27:08.001494 duration_in_ms=66.406
2017-09-16 21:27:08,004 [salt.state       ][INFO    ][21740] Running state [/etc/salt/grains.d] at time 21:27:08.004265
2017-09-16 21:27:08,005 [salt.state       ][INFO    ][21740] Executing state file.directory for /etc/salt/grains.d
2017-09-16 21:27:08,006 [salt.state       ][INFO    ][21740] Directory /etc/salt/grains.d is in the correct state
2017-09-16 21:27:08,007 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/grains.d] at time 21:27:08.006518 duration_in_ms=2.252
2017-09-16 21:27:08,008 [salt.state       ][INFO    ][21740] Running state [/etc/salt/grains] at time 21:27:08.007687
2017-09-16 21:27:08,008 [salt.state       ][INFO    ][21740] Executing state file.managed for /etc/salt/grains
2017-09-16 21:27:08,009 [salt.state       ][INFO    ][21740] File /etc/salt/grains exists with proper permissions. No changes made.
2017-09-16 21:27:08,009 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/grains] at time 21:27:08.009281 duration_in_ms=1.593
2017-09-16 21:27:08,010 [salt.state       ][INFO    ][21740] Running state [/etc/salt/grains.d/placeholder] at time 21:27:08.010046
2017-09-16 21:27:08,011 [salt.state       ][INFO    ][21740] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-09-16 21:27:08,011 [salt.state       ][INFO    ][21740] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-09-16 21:27:08,012 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/grains.d/placeholder] at time 21:27:08.011577 duration_in_ms=1.529
2017-09-16 21:27:08,012 [salt.state       ][INFO    ][21740] Running state [/etc/salt/grains.d/sphinx] at time 21:27:08.012354
2017-09-16 21:27:08,013 [salt.state       ][INFO    ][21740] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-09-16 21:27:08,017 [salt.state       ][INFO    ][21740] File /etc/salt/grains.d/sphinx is in the correct state
2017-09-16 21:27:08,018 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/grains.d/sphinx] at time 21:27:08.017756 duration_in_ms=5.401
2017-09-16 21:27:08,019 [salt.state       ][INFO    ][21740] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:27:08.018876
2017-09-16 21:27:08,019 [salt.state       ][INFO    ][21740] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-16 21:27:08,020 [salt.state       ][INFO    ][21740] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-16 21:27:08,020 [salt.state       ][INFO    ][21740] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:27:08.020085 duration_in_ms=1.209
2017-09-16 21:27:08,021 [salt.state       ][INFO    ][21740] Running state [/etc/salt/grains.d/dns_records] at time 21:27:08.020800
2017-09-16 21:27:08,021 [salt.state       ][INFO    ][21740] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-09-16 21:27:08,023 [salt.state       ][INFO    ][21740] File /etc/salt/grains.d/dns_records is in the correct state
2017-09-16 21:27:08,024 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/grains.d/dns_records] at time 21:27:08.023703 duration_in_ms=2.9
2017-09-16 21:27:08,025 [salt.state       ][INFO    ][21740] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:27:08.024844
2017-09-16 21:27:08,025 [salt.state       ][INFO    ][21740] 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-16 21:27:08,026 [salt.state       ][INFO    ][21740] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-16 21:27:08,026 [salt.state       ][INFO    ][21740] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:27:08.026111 duration_in_ms=1.266
2017-09-16 21:27:08,027 [salt.state       ][INFO    ][21740] Running state [/etc/salt/grains.d/salt] at time 21:27:08.026831
2017-09-16 21:27:08,027 [salt.state       ][INFO    ][21740] Executing state file.managed for /etc/salt/grains.d/salt
2017-09-16 21:27:08,029 [salt.state       ][INFO    ][21740] File /etc/salt/grains.d/salt is in the correct state
2017-09-16 21:27:08,030 [salt.state       ][INFO    ][21740] Completed state [/etc/salt/grains.d/salt] at time 21:27:08.029684 duration_in_ms=2.852
2017-09-16 21:27:08,031 [salt.state       ][INFO    ][21740] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:27:08.030712
2017-09-16 21:27:08,031 [salt.state       ][INFO    ][21740] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-16 21:27:08,032 [salt.state       ][INFO    ][21740] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-16 21:27:08,032 [salt.state       ][INFO    ][21740] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:27:08.031844 duration_in_ms=1.131
2017-09-16 21:27:08,035 [salt.state       ][INFO    ][21740] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:27:08.034740
2017-09-16 21:27:08,035 [salt.state       ][INFO    ][21740] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-16 21:27:08,036 [salt.state       ][INFO    ][21740] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-16 21:27:08,036 [salt.state       ][INFO    ][21740] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:27:08.035928 duration_in_ms=1.187
2017-09-16 21:27:08,037 [salt.state       ][INFO    ][21740] Running state [mine.update] at time 21:27:08.036919
2017-09-16 21:27:08,037 [salt.state       ][INFO    ][21740] Executing state module.wait for mine.update
2017-09-16 21:27:08,038 [salt.state       ][INFO    ][21740] No changes made for mine.update
2017-09-16 21:27:08,038 [salt.state       ][INFO    ][21740] Completed state [mine.update] at time 21:27:08.037998 duration_in_ms=1.078
2017-09-16 21:27:08,038 [salt.state       ][INFO    ][21740] Running state [ca-certificates] at time 21:27:08.038360
2017-09-16 21:27:08,039 [salt.state       ][INFO    ][21740] Executing state pkg.installed for ca-certificates
2017-09-16 21:27:08,046 [salt.state       ][INFO    ][21740] Package ca-certificates is already installed
2017-09-16 21:27:08,046 [salt.state       ][INFO    ][21740] Completed state [ca-certificates] at time 21:27:08.046052 duration_in_ms=7.691
2017-09-16 21:27:08,047 [salt.state       ][INFO    ][21740] Running state [update-ca-certificates] at time 21:27:08.047082
2017-09-16 21:27:08,048 [salt.state       ][INFO    ][21740] Executing state cmd.wait for update-ca-certificates
2017-09-16 21:27:08,048 [salt.state       ][INFO    ][21740] No changes made for update-ca-certificates
2017-09-16 21:27:08,048 [salt.state       ][INFO    ][21740] Completed state [update-ca-certificates] at time 21:27:08.048204 duration_in_ms=1.122
2017-09-16 21:27:08,049 [salt.state       ][INFO    ][21740] Running state [libvirt-dev] at time 21:27:08.048586
2017-09-16 21:27:08,049 [salt.state       ][INFO    ][21740] Executing state pkg.installed for libvirt-dev
2017-09-16 21:27:08,055 [salt.state       ][INFO    ][21740] Package libvirt-dev is already installed
2017-09-16 21:27:08,056 [salt.state       ][INFO    ][21740] Completed state [libvirt-dev] at time 21:27:08.055691 duration_in_ms=7.106
2017-09-16 21:27:08,056 [salt.state       ][INFO    ][21740] Running state [pkg-config] at time 21:27:08.056063
2017-09-16 21:27:08,056 [salt.state       ][INFO    ][21740] Executing state pkg.installed for pkg-config
2017-09-16 21:27:08,063 [salt.state       ][INFO    ][21740] Package pkg-config is already installed
2017-09-16 21:27:08,063 [salt.state       ][INFO    ][21740] Completed state [pkg-config] at time 21:27:08.062812 duration_in_ms=6.75
2017-09-16 21:27:08,063 [salt.state       ][INFO    ][21740] Running state [virtng.init] at time 21:27:08.063187
2017-09-16 21:27:08,064 [salt.state       ][INFO    ][21740] Executing state module.run for virtng.init
2017-09-16 21:27:08,160 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command ['git', '--version'] in directory '/root'
2017-09-16 21:27:08,288 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:13,783 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916212713784235
2017-09-16 21:27:13,804 [salt.minion      ][INFO    ][21795] Starting a new job with PID 21795
2017-09-16 21:27:13,822 [salt.minion      ][INFO    ][21795] Returning information for job: 20170916212713784235
2017-09-16 21:27:18,345 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-16 21:27:18,382 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:18,431 [salt.loaded.int.module.cmdmod][ERROR   ][21740] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-16 21:27:18,431 [salt.loaded.int.module.cmdmod][ERROR   ][21740] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-16 21:27:18,432 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/prx01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-09-16 21:27:18,460 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:18,472 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-16 21:27:19,531 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-16 21:27:20,202 [salt.loaded.ext.module.seedng][INFO    ][21740] salt-minion pre-installed on image, configuring as prx01.baremetal-mcp-ocata-ovs-ha.local
2017-09-16 21:27:20,215 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -l' in directory '/root'
2017-09-16 21:27:20,265 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-16 21:27:21,258 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:23,457 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:23,458 [salt.state       ][INFO    ][21740] Completed state [virtng.init] at time 21:27:23.457588 duration_in_ms=15394.399
2017-09-16 21:27:23,458 [salt.state       ][INFO    ][21740] Running state [virt.set_autostart] at time 21:27:23.458291
2017-09-16 21:27:23,459 [salt.state       ][INFO    ][21740] Executing state module.run for virt.set_autostart
2017-09-16 21:27:23,464 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:23,499 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:23,500 [salt.state       ][INFO    ][21740] Completed state [virt.set_autostart] at time 21:27:23.499801 duration_in_ms=41.51
2017-09-16 21:27:23,500 [salt.state       ][INFO    ][21740] Running state [virtng.init] at time 21:27:23.500226
2017-09-16 21:27:23,501 [salt.state       ][INFO    ][21740] Executing state module.run for virtng.init
2017-09-16 21:27:23,505 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:23,977 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916212723971087
2017-09-16 21:27:24,016 [salt.minion      ][INFO    ][22209] Starting a new job with PID 22209
2017-09-16 21:27:24,031 [salt.minion      ][INFO    ][22209] Returning information for job: 20170916212723971087
2017-09-16 21:27:25,975 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-16 21:27:25,990 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:26,002 [salt.loaded.int.module.cmdmod][ERROR   ][21740] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-16 21:27:26,002 [salt.loaded.int.module.cmdmod][ERROR   ][21740] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-16 21:27:26,003 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/mdb01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-09-16 21:27:26,035 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:26,050 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-16 21:27:27,101 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-16 21:27:27,860 [salt.loaded.ext.module.seedng][INFO    ][21740] salt-minion pre-installed on image, configuring as mdb01.baremetal-mcp-ocata-ovs-ha.local
2017-09-16 21:27:27,870 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -l' in directory '/root'
2017-09-16 21:27:28,072 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-16 21:27:31,249 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:33,436 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:33,436 [salt.state       ][INFO    ][21740] Completed state [virtng.init] at time 21:27:33.436155 duration_in_ms=9935.926
2017-09-16 21:27:33,437 [salt.state       ][INFO    ][21740] Running state [virt.set_autostart] at time 21:27:33.436671
2017-09-16 21:27:33,437 [salt.state       ][INFO    ][21740] Executing state module.run for virt.set_autostart
2017-09-16 21:27:33,441 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:33,480 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:33,480 [salt.state       ][INFO    ][21740] Completed state [virt.set_autostart] at time 21:27:33.480279 duration_in_ms=43.606
2017-09-16 21:27:33,481 [salt.state       ][INFO    ][21740] Running state [virtng.init] at time 21:27:33.480768
2017-09-16 21:27:33,481 [salt.state       ][INFO    ][21740] Executing state module.run for virtng.init
2017-09-16 21:27:33,486 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:34,189 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916212734184068
2017-09-16 21:27:34,210 [salt.minion      ][INFO    ][22478] Starting a new job with PID 22478
2017-09-16 21:27:34,228 [salt.minion      ][INFO    ][22478] Returning information for job: 20170916212734184068
2017-09-16 21:27:35,865 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-16 21:27:35,880 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:35,892 [salt.loaded.int.module.cmdmod][ERROR   ][21740] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-16 21:27:35,893 [salt.loaded.int.module.cmdmod][ERROR   ][21740] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-16 21:27:35,893 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/msg01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-09-16 21:27:35,925 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:35,939 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-16 21:27:36,993 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-16 21:27:37,530 [salt.loaded.ext.module.seedng][INFO    ][21740] salt-minion pre-installed on image, configuring as msg01.baremetal-mcp-ocata-ovs-ha.local
2017-09-16 21:27:37,541 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -l' in directory '/root'
2017-09-16 21:27:37,763 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-16 21:27:44,217 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916212744211225
2017-09-16 21:27:44,242 [salt.minion      ][INFO    ][22532] Starting a new job with PID 22532
2017-09-16 21:27:44,270 [salt.minion      ][INFO    ][22532] Returning information for job: 20170916212744211225
2017-09-16 21:27:44,379 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:46,558 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:46,559 [salt.state       ][INFO    ][21740] Completed state [virtng.init] at time 21:27:46.559084 duration_in_ms=13078.312
2017-09-16 21:27:46,560 [salt.state       ][INFO    ][21740] Running state [virt.set_autostart] at time 21:27:46.559627
2017-09-16 21:27:46,560 [salt.state       ][INFO    ][21740] Executing state module.run for virt.set_autostart
2017-09-16 21:27:46,566 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:46,608 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:46,608 [salt.state       ][INFO    ][21740] Completed state [virt.set_autostart] at time 21:27:46.608267 duration_in_ms=48.64
2017-09-16 21:27:46,609 [salt.state       ][INFO    ][21740] Running state [virtng.init] at time 21:27:46.608700
2017-09-16 21:27:46,609 [salt.state       ][INFO    ][21740] Executing state module.run for virtng.init
2017-09-16 21:27:46,613 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:52,715 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-16 21:27:52,733 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:52,746 [salt.loaded.int.module.cmdmod][ERROR   ][21740] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-16 21:27:52,796 [salt.loaded.int.module.cmdmod][ERROR   ][21740] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-16 21:27:52,797 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/dbs01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-09-16 21:27:52,833 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:52,849 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-16 21:27:53,910 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-16 21:27:54,247 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916212754243432
2017-09-16 21:27:54,269 [salt.minion      ][INFO    ][22791] Starting a new job with PID 22791
2017-09-16 21:27:54,288 [salt.minion      ][INFO    ][22791] Returning information for job: 20170916212754243432
2017-09-16 21:27:54,938 [salt.loaded.ext.module.seedng][INFO    ][21740] salt-minion pre-installed on image, configuring as dbs01.baremetal-mcp-ocata-ovs-ha.local
2017-09-16 21:27:54,949 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -l' in directory '/root'
2017-09-16 21:27:55,001 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-16 21:27:56,177 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-16 21:27:58,337 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:58,338 [salt.state       ][INFO    ][21740] Completed state [virtng.init] at time 21:27:58.337461 duration_in_ms=11728.756
2017-09-16 21:27:58,338 [salt.state       ][INFO    ][21740] Running state [virt.set_autostart] at time 21:27:58.338253
2017-09-16 21:27:58,339 [salt.state       ][INFO    ][21740] Executing state module.run for virt.set_autostart
2017-09-16 21:27:58,344 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:27:58,386 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:27:58,386 [salt.state       ][INFO    ][21740] Completed state [virt.set_autostart] at time 21:27:58.386308 duration_in_ms=48.055
2017-09-16 21:27:58,387 [salt.state       ][INFO    ][21740] Running state [virtng.init] at time 21:27:58.386833
2017-09-16 21:27:58,387 [salt.state       ][INFO    ][21740] Executing state module.run for virtng.init
2017-09-16 21:27:58,392 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:28:01,372 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'modprobe nbd max_part=63' in directory '/root'
2017-09-16 21:28:01,388 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:28:01,402 [salt.loaded.int.module.cmdmod][ERROR   ][21740] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-16 21:28:01,402 [salt.loaded.int.module.cmdmod][ERROR   ][21740] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-16 21:28:01,403 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -c /dev/nbd9 /var/lib/libvirt/images/ctl01.baremetal-mcp-ocata-ovs-ha.local/system.qcow2' in directory '/root'
2017-09-16 21:28:01,435 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'sfdisk -d /dev/nbd9' in directory '/root'
2017-09-16 21:28:01,449 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'partprobe /dev/nbd9' in directory '/root'
2017-09-16 21:28:02,493 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -o defaults /dev/nbd9p1 /tmp/nbd/nbd9/nbd9p1 ' in directory '/root'
2017-09-16 21:28:03,216 [salt.loaded.ext.module.seedng][INFO    ][21740] salt-minion pre-installed on image, configuring as ctl01.baremetal-mcp-ocata-ovs-ha.local
2017-09-16 21:28:03,227 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'mount -l' in directory '/root'
2017-09-16 21:28:03,435 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'umount /tmp/nbd/nbd9/nbd9p1' in directory '/root'
2017-09-16 21:28:04,470 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916212804464309
2017-09-16 21:28:04,492 [salt.minion      ][INFO    ][23074] Starting a new job with PID 23074
2017-09-16 21:28:04,511 [salt.minion      ][INFO    ][23074] Returning information for job: 20170916212804464309
2017-09-16 21:28:07,677 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'qemu-nbd -d /dev/nbd9' in directory '/root'
2017-09-16 21:28:09,845 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:28:09,846 [salt.state       ][INFO    ][21740] Completed state [virtng.init] at time 21:28:09.845989 duration_in_ms=11459.153
2017-09-16 21:28:09,847 [salt.state       ][INFO    ][21740] Running state [virt.set_autostart] at time 21:28:09.846681
2017-09-16 21:28:09,847 [salt.state       ][INFO    ][21740] Executing state module.run for virt.set_autostart
2017-09-16 21:28:09,852 [salt.loaded.int.module.cmdmod][INFO    ][21740] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-09-16 21:28:09,892 [salt.state       ][INFO    ][21740] {'ret': True}
2017-09-16 21:28:09,893 [salt.state       ][INFO    ][21740] Completed state [virt.set_autostart] at time 21:28:09.892593 duration_in_ms=45.911
2017-09-16 21:28:09,907 [salt.minion      ][INFO    ][21740] Returning information for job: 20170916212703683195
2017-09-16 21:28:10,817 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command pillar.get with jid 20170916212810810732
2017-09-16 21:28:10,837 [salt.minion      ][INFO    ][23303] Starting a new job with PID 23303
2017-09-16 21:28:10,857 [salt.minion      ][INFO    ][23303] Returning information for job: 20170916212810810732
2017-09-16 21:29:51,302 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170916212951295981
2017-09-16 21:29:51,326 [salt.minion      ][INFO    ][23331] Starting a new job with PID 23331
2017-09-16 21:29:55,342 [salt.state       ][INFO    ][23331] Loading fresh modules for state activity
2017-09-16 21:29:55,484 [salt.state       ][ERROR   ][23331] No contents found in top file
2017-09-16 21:29:55,490 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-16 21:29:55,497 [salt.utils.extmods][INFO    ][23331] Syncing beacons for environment 'base'
2017-09-16 21:29:55,497 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_beacons, for base)
2017-09-16 21:29:55,497 [salt.fileclient  ][INFO    ][23331] Caching directory '_beacons/' for environment 'base'
2017-09-16 21:29:55,575 [salt.utils.extmods][INFO    ][23331] Syncing modules for environment 'base'
2017-09-16 21:29:55,575 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_modules, for base)
2017-09-16 21:29:55,576 [salt.fileclient  ][INFO    ][23331] Caching directory '_modules/' for environment 'base'
2017-09-16 21:29:55,623 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-16 21:29:55,659 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-16 21:29:55,679 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-16 21:29:55,697 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-16 21:29:55,713 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-16 21:29:55,725 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-16 21:29:55,738 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-16 21:29:55,749 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-16 21:29:55,761 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-16 21:29:56,503 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-16 21:29:56,522 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-16 21:29:56,567 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-16 21:29:56,587 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-16 21:29:56,725 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-16 21:29:56,747 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-16 21:29:56,774 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-16 21:29:56,796 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-16 21:29:56,818 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-16 21:29:56,898 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-16 21:29:56,980 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-16 21:29:57,028 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-16 21:29:57,058 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-16 21:29:57,082 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-16 21:29:57,093 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-16 21:29:57,127 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-16 21:29:57,215 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-16 21:29:57,265 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-16 21:29:57,335 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-16 21:29:57,552 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-16 21:29:57,600 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-16 21:29:57,692 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-16 21:29:57,717 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-16 21:29:57,721 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-16 21:29:57,722 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-16 21:29:57,722 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-16 21:29:57,723 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-16 21:29:57,723 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-16 21:29:57,724 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-16 21:29:57,724 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-16 21:29:57,724 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-16 21:29:57,725 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-16 21:29:57,725 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-16 21:29:57,726 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-16 21:29:57,726 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-16 21:29:57,726 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-16 21:29:57,727 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-16 21:29:57,727 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-16 21:29:57,728 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-16 21:29:57,728 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-16 21:29:57,728 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-16 21:29:57,729 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-16 21:29:57,729 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-16 21:29:57,730 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-16 21:29:57,730 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-16 21:29:57,731 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-16 21:29:57,731 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-16 21:29:57,732 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-16 21:29:57,732 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-16 21:29:57,732 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-16 21:29:57,733 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-16 21:29:57,733 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-16 21:29:57,733 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-16 21:29:57,734 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-16 21:29:57,734 [salt.utils.extmods][INFO    ][23331] 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-16 21:29:57,734 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-16 21:29:57,735 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-16 21:29:57,744 [salt.utils.extmods][INFO    ][23331] Syncing states for environment 'base'
2017-09-16 21:29:57,744 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_states, for base)
2017-09-16 21:29:57,745 [salt.fileclient  ][INFO    ][23331] Caching directory '_states/' for environment 'base'
2017-09-16 21:29:57,793 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-16 21:29:57,808 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-16 21:29:57,821 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-16 21:29:57,880 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-16 21:29:57,945 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-16 21:29:58,007 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-16 21:29:58,029 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-16 21:29:58,045 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-16 21:29:58,061 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-16 21:29:58,127 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-16 21:29:58,145 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-16 21:29:58,164 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-16 21:29:58,221 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-16 21:29:58,235 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-16 21:29:58,250 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-16 21:29:58,287 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-16 21:29:58,316 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-16 21:29:58,329 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-16 21:29:58,353 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-16 21:29:58,402 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-16 21:29:58,517 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-16 21:29:58,616 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-16 21:29:58,627 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-16 21:29:58,664 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-16 21:29:58,686 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-16 21:29:58,704 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-16 21:29:58,733 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-16 21:29:58,764 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-16 21:29:58,784 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-16 21:29:58,790 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-16 21:29:58,791 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-16 21:29:58,791 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-16 21:29:58,792 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-16 21:29:58,792 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-16 21:29:58,793 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-16 21:29:58,793 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-16 21:29:58,793 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-16 21:29:58,794 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-16 21:29:58,794 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-16 21:29:58,794 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-16 21:29:58,795 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-16 21:29:58,795 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-16 21:29:58,795 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-16 21:29:58,796 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-16 21:29:58,796 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-16 21:29:58,796 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-16 21:29:58,796 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-16 21:29:58,797 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-16 21:29:58,797 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-16 21:29:58,797 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-16 21:29:58,798 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-16 21:29:58,798 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-16 21:29:58,799 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-16 21:29:58,799 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-16 21:29:58,800 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-16 21:29:58,800 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-16 21:29:58,801 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-16 21:29:58,801 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-16 21:29:58,801 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-16 21:29:58,805 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-16 21:29:58,811 [salt.utils.extmods][INFO    ][23331] Syncing sdb for environment 'base'
2017-09-16 21:29:58,812 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_sdb, for base)
2017-09-16 21:29:58,812 [salt.fileclient  ][INFO    ][23331] Caching directory '_sdb/' for environment 'base'
2017-09-16 21:29:58,886 [salt.utils.extmods][INFO    ][23331] Syncing grains for environment 'base'
2017-09-16 21:29:58,886 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_grains, for base)
2017-09-16 21:29:58,886 [salt.fileclient  ][INFO    ][23331] Caching directory '_grains/' for environment 'base'
2017-09-16 21:29:58,948 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-16 21:29:59,021 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-16 21:29:59,044 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-16 21:29:59,069 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-16 21:29:59,079 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-16 21:29:59,092 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-16 21:29:59,313 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-16 21:29:59,324 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-16 21:29:59,333 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-16 21:29:59,342 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-16 21:29:59,686 [salt.fileclient  ][INFO    ][23331] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-16 21:29:59,693 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-16 21:29:59,694 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-16 21:29:59,694 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-16 21:29:59,695 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-16 21:29:59,695 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-16 21:29:59,696 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-16 21:29:59,696 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-16 21:29:59,697 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-16 21:29:59,697 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-16 21:29:59,698 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-16 21:29:59,698 [salt.utils.extmods][INFO    ][23331] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-16 21:29:59,700 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-16 21:29:59,706 [salt.utils.extmods][INFO    ][23331] Syncing renderers for environment 'base'
2017-09-16 21:29:59,707 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_renderers, for base)
2017-09-16 21:29:59,707 [salt.fileclient  ][INFO    ][23331] Caching directory '_renderers/' for environment 'base'
2017-09-16 21:30:00,191 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-16 21:30:00,196 [salt.utils.extmods][INFO    ][23331] Syncing returners for environment 'base'
2017-09-16 21:30:00,196 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_returners, for base)
2017-09-16 21:30:00,196 [salt.fileclient  ][INFO    ][23331] Caching directory '_returners/' for environment 'base'
2017-09-16 21:30:00,223 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-16 21:30:00,227 [salt.utils.extmods][INFO    ][23331] Syncing output for environment 'base'
2017-09-16 21:30:00,228 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_output, for base)
2017-09-16 21:30:00,228 [salt.fileclient  ][INFO    ][23331] Caching directory '_output/' for environment 'base'
2017-09-16 21:30:00,256 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-16 21:30:00,260 [salt.utils.extmods][INFO    ][23331] Syncing utils for environment 'base'
2017-09-16 21:30:00,260 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_utils, for base)
2017-09-16 21:30:00,261 [salt.fileclient  ][INFO    ][23331] Caching directory '_utils/' for environment 'base'
2017-09-16 21:30:00,284 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-16 21:30:00,287 [salt.utils.extmods][INFO    ][23331] Syncing log_handlers for environment 'base'
2017-09-16 21:30:00,288 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_log_handlers, for base)
2017-09-16 21:30:00,288 [salt.fileclient  ][INFO    ][23331] Caching directory '_log_handlers/' for environment 'base'
2017-09-16 21:30:00,314 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-16 21:30:00,318 [salt.utils.extmods][INFO    ][23331] Syncing proxy for environment 'base'
2017-09-16 21:30:00,318 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_proxy, for base)
2017-09-16 21:30:00,319 [salt.fileclient  ][INFO    ][23331] Caching directory '_proxy/' for environment 'base'
2017-09-16 21:30:00,340 [salt.utils.extmods][INFO    ][23331] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-16 21:30:00,344 [salt.utils.extmods][INFO    ][23331] Syncing engines for environment 'base'
2017-09-16 21:30:00,344 [salt.utils.extmods][INFO    ][23331] Loading cache from salt://_engines, for base)
2017-09-16 21:30:00,344 [salt.fileclient  ][INFO    ][23331] Caching directory '_engines/' for environment 'base'
2017-09-16 21:30:00,373 [salt.minion      ][INFO    ][23331] Returning information for job: 20170916212951295981
2017-09-16 21:35:37,685 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command test.ping with jid 20170916213537673073
2017-09-16 21:35:37,709 [salt.minion      ][INFO    ][23504] Starting a new job with PID 23504
2017-09-16 21:35:37,767 [salt.minion      ][INFO    ][23504] Returning information for job: 20170916213537673073
2017-09-16 21:39:26,105 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command state.sls with jid 20170916213926093301
2017-09-16 21:39:26,127 [salt.minion      ][INFO    ][23581] Starting a new job with PID 23581
2017-09-16 21:39:27,480 [salt.state       ][INFO    ][23581] Loading fresh modules for state activity
2017-09-16 21:39:27,521 [salt.fileclient  ][INFO    ][23581] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-09-16 21:39:27,544 [salt.fileclient  ][INFO    ][23581] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-09-16 21:39:27,578 [salt.fileclient  ][INFO    ][23581] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-16 21:39:27,919 [salt.state       ][INFO    ][23581] Running state [lsof] at time 21:39:27.918805
2017-09-16 21:39:27,919 [salt.state       ][INFO    ][23581] Executing state pkg.installed for lsof
2017-09-16 21:39:27,919 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:39:28,295 [salt.state       ][INFO    ][23581] Package lsof is already installed
2017-09-16 21:39:28,296 [salt.state       ][INFO    ][23581] Completed state [lsof] at time 21:39:28.295555 duration_in_ms=376.749
2017-09-16 21:39:28,296 [salt.state       ][INFO    ][23581] Running state [keepalived] at time 21:39:28.295772
2017-09-16 21:39:28,296 [salt.state       ][INFO    ][23581] Executing state pkg.installed for keepalived
2017-09-16 21:39:28,317 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-16 21:39:30,416 [salt.loaded.int.module.cmdmod][INFO    ][23581] 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-16 21:39:36,148 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916213936134027
2017-09-16 21:39:36,169 [salt.minion      ][INFO    ][24846] Starting a new job with PID 24846
2017-09-16 21:39:36,199 [salt.minion      ][INFO    ][24846] Returning information for job: 20170916213936134027
2017-09-16 21:39:39,489 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:39:39,545 [salt.state       ][INFO    ][23581] 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-16 21:39:39,563 [salt.state       ][INFO    ][23581] Loading fresh modules for state activity
2017-09-16 21:39:39,589 [salt.state       ][INFO    ][23581] Completed state [keepalived] at time 21:39:39.588475 duration_in_ms=11292.702
2017-09-16 21:39:39,592 [salt.state       ][INFO    ][23581] Running state [/etc/keepalived/keepalived.conf] at time 21:39:39.591821
2017-09-16 21:39:39,592 [salt.state       ][INFO    ][23581] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-09-16 21:39:39,621 [salt.fileclient  ][INFO    ][23581] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-09-16 21:39:39,668 [salt.fileclient  ][INFO    ][23581] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-16 21:39:39,678 [salt.state       ][INFO    ][23581] File changed:
New file
2017-09-16 21:39:39,679 [salt.state       ][INFO    ][23581] Completed state [/etc/keepalived/keepalived.conf] at time 21:39:39.678473 duration_in_ms=86.652
2017-09-16 21:39:39,809 [salt.state       ][INFO    ][23581] Running state [keepalived] at time 21:39:39.808934
2017-09-16 21:39:39,809 [salt.state       ][INFO    ][23581] Executing state service.running for keepalived
2017-09-16 21:39:39,812 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-09-16 21:39:39,831 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-16 21:39:39,847 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-16 21:39:39,861 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-16 21:39:39,875 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-09-16 21:39:39,959 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-16 21:39:39,975 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-16 21:39:39,989 [salt.loaded.int.module.cmdmod][INFO    ][23581] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-16 21:39:40,002 [salt.state       ][INFO    ][23581] {'keepalived': True}
2017-09-16 21:39:40,003 [salt.state       ][INFO    ][23581] Completed state [keepalived] at time 21:39:40.002795 duration_in_ms=193.863
2017-09-16 21:39:40,005 [salt.minion      ][INFO    ][23581] Returning information for job: 20170916213926093301
2017-09-16 21:39:54,854 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command pillar.get with jid 20170916213954847957
2017-09-16 21:39:54,879 [salt.minion      ][INFO    ][25122] Starting a new job with PID 25122
2017-09-16 21:39:54,888 [salt.minion      ][INFO    ][25122] Returning information for job: 20170916213954847957
2017-09-16 21:45:26,352 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command state.sls with jid 20170916214526347080
2017-09-16 21:45:26,374 [salt.minion      ][INFO    ][25235] Starting a new job with PID 25235
2017-09-16 21:45:27,928 [salt.state       ][INFO    ][25235] Loading fresh modules for state activity
2017-09-16 21:45:27,970 [salt.fileclient  ][INFO    ][25235] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-16 21:45:28,004 [salt.fileclient  ][INFO    ][25235] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-16 21:45:28,346 [salt.state       ][INFO    ][25235] Running state [attr] at time 21:45:28.345867
2017-09-16 21:45:28,346 [salt.state       ][INFO    ][25235] Executing state pkg.installed for attr
2017-09-16 21:45:28,346 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:45:28,733 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-16 21:45:30,985 [salt.loaded.int.module.cmdmod][INFO    ][25235] 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-16 21:45:34,186 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:45:34,235 [salt.state       ][INFO    ][25235] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-09-16 21:45:34,248 [salt.state       ][INFO    ][25235] Loading fresh modules for state activity
2017-09-16 21:45:34,267 [salt.state       ][INFO    ][25235] Completed state [attr] at time 21:45:34.266967 duration_in_ms=5921.099
2017-09-16 21:45:34,275 [salt.state       ][INFO    ][25235] Running state [glusterfs-server] at time 21:45:34.274521
2017-09-16 21:45:34,275 [salt.state       ][INFO    ][25235] Executing state pkg.installed for glusterfs-server
2017-09-16 21:45:34,560 [salt.loaded.int.module.cmdmod][INFO    ][25235] 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-16 21:45:36,447 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916214536433701
2017-09-16 21:45:36,468 [salt.minion      ][INFO    ][26183] Starting a new job with PID 26183
2017-09-16 21:45:36,488 [salt.minion      ][INFO    ][26183] Returning information for job: 20170916214536433701
2017-09-16 21:45:46,654 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916214546640632
2017-09-16 21:45:46,675 [salt.minion      ][INFO    ][28869] Starting a new job with PID 28869
2017-09-16 21:45:46,694 [salt.minion      ][INFO    ][28869] Returning information for job: 20170916214546640632
2017-09-16 21:45:56,746 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command saltutil.find_job with jid 20170916214556734684
2017-09-16 21:45:56,767 [salt.minion      ][INFO    ][29469] Starting a new job with PID 29469
2017-09-16 21:45:56,785 [salt.minion      ][INFO    ][29469] Returning information for job: 20170916214556734684
2017-09-16 21:45:57,042 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:45:57,098 [salt.state       ][INFO    ][25235] 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-16 21:45:57,118 [salt.state       ][INFO    ][25235] Loading fresh modules for state activity
2017-09-16 21:45:57,150 [salt.state       ][INFO    ][25235] Completed state [glusterfs-server] at time 21:45:57.149466 duration_in_ms=22874.943
2017-09-16 21:45:57,159 [salt.state       ][INFO    ][25235] Running state [bc] at time 21:45:57.159218
2017-09-16 21:45:57,160 [salt.state       ][INFO    ][25235] Executing state pkg.installed for bc
2017-09-16 21:45:57,497 [salt.loaded.int.module.cmdmod][INFO    ][25235] 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-16 21:46:01,003 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:46:01,063 [salt.state       ][INFO    ][25235] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-09-16 21:46:01,079 [salt.state       ][INFO    ][25235] Loading fresh modules for state activity
2017-09-16 21:46:01,102 [salt.state       ][INFO    ][25235] Completed state [bc] at time 21:46:01.102367 duration_in_ms=3943.147
2017-09-16 21:46:01,188 [salt.state       ][INFO    ][25235] Running state [glusterfs-server] at time 21:46:01.188356
2017-09-16 21:46:01,189 [salt.state       ][INFO    ][25235] Executing state service.running for glusterfs-server
2017-09-16 21:46:01,190 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-16 21:46:01,207 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-16 21:46:01,220 [salt.loaded.int.module.cmdmod][INFO    ][25235] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-16 21:46:01,234 [salt.state       ][INFO    ][25235] The service glusterfs-server is already running
2017-09-16 21:46:01,234 [salt.state       ][INFO    ][25235] Completed state [glusterfs-server] at time 21:46:01.234289 duration_in_ms=45.932
2017-09-16 21:46:01,236 [salt.state       ][INFO    ][25235] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:46:01.236015
2017-09-16 21:46:01,236 [salt.state       ][INFO    ][25235] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-16 21:46:01,248 [salt.state       ][INFO    ][25235] File changed:
New file
2017-09-16 21:46:01,248 [salt.state       ][INFO    ][25235] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:46:01.248045 duration_in_ms=12.031
2017-09-16 21:46:01,248 [salt.state       ][INFO    ][25235] Running state [/srv/glusterfs/glance] at time 21:46:01.248279
2017-09-16 21:46:01,248 [salt.state       ][INFO    ][25235] Executing state file.directory for /srv/glusterfs/glance
2017-09-16 21:46:01,273 [salt.state       ][INFO    ][25235] {'/srv/glusterfs/glance': 'New Dir'}
2017-09-16 21:46:01,273 [salt.state       ][INFO    ][25235] Completed state [/srv/glusterfs/glance] at time 21:46:01.273345 duration_in_ms=25.066
2017-09-16 21:46:01,274 [salt.state       ][INFO    ][25235] Running state [/srv/glusterfs/keystone-keys] at time 21:46:01.273624
2017-09-16 21:46:01,274 [salt.state       ][INFO    ][25235] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-16 21:46:01,275 [salt.state       ][INFO    ][25235] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-09-16 21:46:01,275 [salt.state       ][INFO    ][25235] Completed state [/srv/glusterfs/keystone-keys] at time 21:46:01.274810 duration_in_ms=1.186
2017-09-16 21:46:01,275 [salt.state       ][INFO    ][25235] Running state [/srv/glusterfs/nova_instances] at time 21:46:01.275082
2017-09-16 21:46:01,275 [salt.state       ][INFO    ][25235] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-16 21:46:01,276 [salt.state       ][INFO    ][25235] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-09-16 21:46:01,276 [salt.state       ][INFO    ][25235] Completed state [/srv/glusterfs/nova_instances] at time 21:46:01.276186 duration_in_ms=1.104
2017-09-16 21:46:01,276 [salt.state       ][INFO    ][25235] Running state [/srv/glusterfs/keystone-credential-keys] at time 21:46:01.276423
2017-09-16 21:46:01,277 [salt.state       ][INFO    ][25235] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-16 21:46:01,277 [salt.state       ][INFO    ][25235] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-09-16 21:46:01,277 [salt.state       ][INFO    ][25235] Completed state [/srv/glusterfs/keystone-credential-keys] at time 21:46:01.277424 duration_in_ms=1.001
2017-09-16 21:46:01,278 [salt.minion      ][INFO    ][25235] Returning information for job: 20170916214526347080
2017-09-16 21:46:02,443 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command test.ping with jid 20170916214602432976
2017-09-16 21:46:02,465 [salt.minion      ][INFO    ][29908] Starting a new job with PID 29908
2017-09-16 21:46:02,518 [salt.minion      ][INFO    ][29908] Returning information for job: 20170916214602432976
2017-09-16 21:46:49,180 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command state.sls with jid 20170916214649168118
2017-09-16 21:46:49,201 [salt.minion      ][INFO    ][30128] Starting a new job with PID 30128
2017-09-16 21:46:49,636 [salt.state       ][INFO    ][30128] Loading fresh modules for state activity
2017-09-16 21:46:49,674 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-09-16 21:46:49,727 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-16 21:46:49,825 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-16 21:46:49,846 [salt.fileclient  ][INFO    ][30128] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-16 21:46:50,218 [salt.state       ][INFO    ][30128] Running state [attr] at time 21:46:50.218167
2017-09-16 21:46:50,218 [salt.state       ][INFO    ][30128] Executing state pkg.installed for attr
2017-09-16 21:46:50,219 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-16 21:46:50,585 [salt.state       ][INFO    ][30128] Package attr is already installed
2017-09-16 21:46:50,585 [salt.state       ][INFO    ][30128] Completed state [attr] at time 21:46:50.584816 duration_in_ms=366.648
2017-09-16 21:46:50,585 [salt.state       ][INFO    ][30128] Running state [glusterfs-server] at time 21:46:50.585031
2017-09-16 21:46:50,585 [salt.state       ][INFO    ][30128] Executing state pkg.installed for glusterfs-server
2017-09-16 21:46:50,588 [salt.state       ][INFO    ][30128] Package glusterfs-server is already installed
2017-09-16 21:46:50,588 [salt.state       ][INFO    ][30128] Completed state [glusterfs-server] at time 21:46:50.588053 duration_in_ms=3.022
2017-09-16 21:46:50,588 [salt.state       ][INFO    ][30128] Running state [bc] at time 21:46:50.588204
2017-09-16 21:46:50,588 [salt.state       ][INFO    ][30128] Executing state pkg.installed for bc
2017-09-16 21:46:50,591 [salt.state       ][INFO    ][30128] Package bc is already installed
2017-09-16 21:46:50,591 [salt.state       ][INFO    ][30128] Completed state [bc] at time 21:46:50.591103 duration_in_ms=2.899
2017-09-16 21:46:50,592 [salt.state       ][INFO    ][30128] Running state [glusterfs-server] at time 21:46:50.591942
2017-09-16 21:46:50,592 [salt.state       ][INFO    ][30128] Executing state service.running for glusterfs-server
2017-09-16 21:46:50,593 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-16 21:46:50,609 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-09-16 21:46:50,697 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-16 21:46:50,727 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-16 21:46:50,742 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-16 21:46:50,760 [salt.state       ][INFO    ][30128] The service glusterfs-server is already running
2017-09-16 21:46:50,760 [salt.state       ][INFO    ][30128] Completed state [glusterfs-server] at time 21:46:50.760321 duration_in_ms=168.377
2017-09-16 21:46:50,764 [salt.state       ][INFO    ][30128] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:46:50.763587
2017-09-16 21:46:50,764 [salt.state       ][INFO    ][30128] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-16 21:46:50,777 [salt.state       ][INFO    ][30128] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-09-16 21:46:50,778 [salt.state       ][INFO    ][30128] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 21:46:50.777465 duration_in_ms=13.878
2017-09-16 21:46:50,778 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/glance] at time 21:46:50.777880
2017-09-16 21:46:50,778 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/glance
2017-09-16 21:46:50,779 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/glance is in the correct state
2017-09-16 21:46:50,779 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/glance] at time 21:46:50.779377 duration_in_ms=1.496
2017-09-16 21:46:50,780 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/keystone-keys] at time 21:46:50.779746
2017-09-16 21:46:50,780 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-16 21:46:50,781 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-09-16 21:46:50,781 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/keystone-keys] at time 21:46:50.781217 duration_in_ms=1.47
2017-09-16 21:46:50,782 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/nova_instances] at time 21:46:50.781587
2017-09-16 21:46:50,782 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-16 21:46:50,783 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/nova_instances is in the correct state
2017-09-16 21:46:50,783 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/nova_instances] at time 21:46:50.783093 duration_in_ms=1.505
2017-09-16 21:46:50,784 [salt.state       ][INFO    ][30128] Running state [/srv/glusterfs/keystone-credential-keys] at time 21:46:50.783452
2017-09-16 21:46:50,784 [salt.state       ][INFO    ][30128] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-16 21:46:50,785 [salt.state       ][INFO    ][30128] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-09-16 21:46:50,785 [salt.state       ][INFO    ][30128] Completed state [/srv/glusterfs/keystone-credential-keys] at time 21:46:50.784922 duration_in_ms=1.47
2017-09-16 21:46:50,934 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command ['git', '--version'] in directory '/root'
2017-09-16 21:46:51,112 [salt.state       ][INFO    ][30128] Running state [sleep 5] at time 21:46:51.111877
2017-09-16 21:46:51,112 [salt.state       ][INFO    ][30128] Executing state cmd.wait for sleep 5
2017-09-16 21:46:51,113 [salt.state       ][INFO    ][30128] No changes made for sleep 5
2017-09-16 21:46:51,113 [salt.state       ][INFO    ][30128] Completed state [sleep 5] at time 21:46:51.112735 duration_in_ms=0.858
2017-09-16 21:46:51,113 [salt.state       ][INFO    ][30128] Running state [10.167.4.141] at time 21:46:51.113306
2017-09-16 21:46:51,114 [salt.state       ][INFO    ][30128] Executing state glusterfs.peered for 10.167.4.141
2017-09-16 21:46:51,114 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:51,132 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:51,237 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:51,251 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:51,356 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:51,375 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:51,481 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:51,499 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:51,605 [salt.state       ][INFO    ][30128] Peering with localhost is not needed
2017-09-16 21:46:51,605 [salt.state       ][INFO    ][30128] Completed state [10.167.4.141] at time 21:46:51.605064 duration_in_ms=491.756
2017-09-16 21:46:51,611 [salt.state       ][INFO    ][30128] Running state [10.167.4.142] at time 21:46:51.611206
2017-09-16 21:46:51,612 [salt.state       ][INFO    ][30128] Executing state glusterfs.peered for 10.167.4.142
2017-09-16 21:46:51,613 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:51,630 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:51,736 [salt.state       ][INFO    ][30128] Host 10.167.4.142 already peered
2017-09-16 21:46:51,737 [salt.state       ][INFO    ][30128] Completed state [10.167.4.142] at time 21:46:51.736911 duration_in_ms=125.705
2017-09-16 21:46:51,743 [salt.state       ][INFO    ][30128] Running state [10.167.4.143] at time 21:46:51.742919
2017-09-16 21:46:51,743 [salt.state       ][INFO    ][30128] Executing state glusterfs.peered for 10.167.4.143
2017-09-16 21:46:51,744 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:51,761 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:51,865 [salt.state       ][INFO    ][30128] Host 10.167.4.143 already peered
2017-09-16 21:46:51,865 [salt.state       ][INFO    ][30128] Completed state [10.167.4.143] at time 21:46:51.865123 duration_in_ms=122.203
2017-09-16 21:46:51,867 [salt.state       ][INFO    ][30128] Running state [glance] at time 21:46:51.866936
2017-09-16 21:46:51,867 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for glance
2017-09-16 21:46:51,868 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:51,887 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:51,995 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:52,014 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:52,142 [salt.state       ][INFO    ][30128] Volume glance already exists and is started.
2017-09-16 21:46:52,142 [salt.state       ][INFO    ][30128] Completed state [glance] at time 21:46:52.142392 duration_in_ms=275.454
2017-09-16 21:46:52,150 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:52.149894
2017-09-16 21:46:52,150 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-09-16 21:46:52,151 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:52,253 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:52,254 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:52.253898 duration_in_ms=104.004
2017-09-16 21:46:52,255 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 21:46:52.254982
2017-09-16 21:46:52,256 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-09-16 21:46:52,257 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-16 21:46:52,360 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:52,361 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 21:46:52.360454 duration_in_ms=105.471
2017-09-16 21:46:52,362 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 21:46:52.361446
2017-09-16 21:46:52,362 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-09-16 21:46:52,363 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-09-16 21:46:52,463 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:52,463 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 21:46:52.463245 duration_in_ms=101.799
2017-09-16 21:46:52,464 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:52.464371
2017-09-16 21:46:52,465 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-09-16 21:46:52,466 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:52,571 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:52,571 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:52.571256 duration_in_ms=106.885
2017-09-16 21:46:52,572 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 21:46:52.572345
2017-09-16 21:46:52,573 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-09-16 21:46:52,574 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-16 21:46:52,678 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:52,679 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 21:46:52.678914 duration_in_ms=106.568
2017-09-16 21:46:52,681 [salt.state       ][INFO    ][30128] Running state [glance] at time 21:46:52.681412
2017-09-16 21:46:52,682 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for glance
2017-09-16 21:46:52,683 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:52,700 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:52,811 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:52,829 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:52,945 [salt.state       ][INFO    ][30128] Volume glance is already started
2017-09-16 21:46:52,945 [salt.state       ][INFO    ][30128] Completed state [glance] at time 21:46:52.944981 duration_in_ms=263.568
2017-09-16 21:46:52,947 [salt.state       ][INFO    ][30128] Running state [keystone-keys] at time 21:46:52.946821
2017-09-16 21:46:52,947 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for keystone-keys
2017-09-16 21:46:52,948 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:52,965 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:53,067 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:53,084 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:53,199 [salt.state       ][INFO    ][30128] Volume keystone-keys already exists and is started.
2017-09-16 21:46:53,199 [salt.state       ][INFO    ][30128] Completed state [keystone-keys] at time 21:46:53.199138 duration_in_ms=252.315
2017-09-16 21:46:53,207 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 21:46:53.206925
2017-09-16 21:46:53,207 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-09-16 21:46:53,208 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-16 21:46:53,317 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:53,317 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 21:46:53.317119 duration_in_ms=110.192
2017-09-16 21:46:53,318 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:53.318258
2017-09-16 21:46:53,319 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-16 21:46:53,320 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:53,420 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:53,421 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:53.420666 duration_in_ms=102.406
2017-09-16 21:46:53,422 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:53.421771
2017-09-16 21:46:53,422 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-16 21:46:53,423 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:53,532 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:53,533 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:53.532486 duration_in_ms=110.714
2017-09-16 21:46:53,534 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 21:46:53.533570
2017-09-16 21:46:53,534 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-09-16 21:46:53,535 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-16 21:46:53,646 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:53,647 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 21:46:53.646648 duration_in_ms=113.077
2017-09-16 21:46:53,648 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 21:46:53.647719
2017-09-16 21:46:53,648 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-09-16 21:46:53,649 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-16 21:46:53,756 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:53,757 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 21:46:53.756997 duration_in_ms=109.277
2017-09-16 21:46:53,760 [salt.state       ][INFO    ][30128] Running state [keystone-keys] at time 21:46:53.759602
2017-09-16 21:46:53,760 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for keystone-keys
2017-09-16 21:46:53,761 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:53,779 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:53,885 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:53,903 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:54,015 [salt.state       ][INFO    ][30128] Volume keystone-keys is already started
2017-09-16 21:46:54,016 [salt.state       ][INFO    ][30128] Completed state [keystone-keys] at time 21:46:54.015623 duration_in_ms=256.02
2017-09-16 21:46:54,018 [salt.state       ][INFO    ][30128] Running state [nova_instances] at time 21:46:54.017692
2017-09-16 21:46:54,018 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for nova_instances
2017-09-16 21:46:54,019 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:54,037 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:54,148 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:54,166 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:54,274 [salt.state       ][INFO    ][30128] Volume nova_instances already exists and is started.
2017-09-16 21:46:54,275 [salt.state       ][INFO    ][30128] Completed state [nova_instances] at time 21:46:54.274786 duration_in_ms=257.093
2017-09-16 21:46:54,283 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:54.283373
2017-09-16 21:46:54,284 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-09-16 21:46:54,285 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:54,389 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:54,390 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:54.389777 duration_in_ms=106.405
2017-09-16 21:46:54,391 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 21:46:54.390898
2017-09-16 21:46:54,391 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-09-16 21:46:54,392 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-16 21:46:54,499 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:54,500 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 21:46:54.499513 duration_in_ms=108.614
2017-09-16 21:46:54,501 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 21:46:54.500585
2017-09-16 21:46:54,501 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-09-16 21:46:54,502 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-16 21:46:54,604 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:54,604 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 21:46:54.604386 duration_in_ms=103.8
2017-09-16 21:46:54,606 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 21:46:54.605489
2017-09-16 21:46:54,606 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-09-16 21:46:54,607 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-09-16 21:46:54,712 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:54,713 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 21:46:54.712773 duration_in_ms=107.283
2017-09-16 21:46:54,714 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:54.713881
2017-09-16 21:46:54,714 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-09-16 21:46:54,715 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:54,823 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:54,824 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:54.823889 duration_in_ms=110.006
2017-09-16 21:46:54,827 [salt.state       ][INFO    ][30128] Running state [nova_instances] at time 21:46:54.826703
2017-09-16 21:46:54,827 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for nova_instances
2017-09-16 21:46:54,829 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:54,844 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:54,945 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:54,960 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:55,067 [salt.state       ][INFO    ][30128] Volume nova_instances is already started
2017-09-16 21:46:55,067 [salt.state       ][INFO    ][30128] Completed state [nova_instances] at time 21:46:55.067051 duration_in_ms=240.348
2017-09-16 21:46:55,069 [salt.state       ][INFO    ][30128] Running state [keystone-credential-keys] at time 21:46:55.069034
2017-09-16 21:46:55,070 [salt.state       ][INFO    ][30128] Executing state glusterfs.created for keystone-credential-keys
2017-09-16 21:46:55,071 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:55,088 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:55,191 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:55,209 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:55,318 [salt.state       ][INFO    ][30128] Volume keystone-credential-keys already exists and is started.
2017-09-16 21:46:55,318 [salt.state       ][INFO    ][30128] Completed state [keystone-credential-keys] at time 21:46:55.318065 duration_in_ms=249.03
2017-09-16 21:46:55,326 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 21:46:55.326084
2017-09-16 21:46:55,327 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-09-16 21:46:55,328 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-16 21:46:55,432 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:55,432 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 21:46:55.432186 duration_in_ms=106.101
2017-09-16 21:46:55,433 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:55.433221
2017-09-16 21:46:55,434 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-16 21:46:55,435 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:55,537 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:55,538 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 21:46:55.537584 duration_in_ms=104.363
2017-09-16 21:46:55,539 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 21:46:55.538744
2017-09-16 21:46:55,539 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-09-16 21:46:55,540 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-16 21:46:55,648 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:55,648 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 21:46:55.648014 duration_in_ms=109.27
2017-09-16 21:46:55,649 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:55.649023
2017-09-16 21:46:55,650 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-16 21:46:55,651 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-16 21:46:55,751 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:55,751 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 21:46:55.750986 duration_in_ms=101.962
2017-09-16 21:46:55,752 [salt.state       ][INFO    ][30128] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 21:46:55.752050
2017-09-16 21:46:55,753 [salt.state       ][INFO    ][30128] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-09-16 21:46:55,754 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-16 21:46:55,863 [salt.state       ][INFO    ][30128] unless execution succeeded
2017-09-16 21:46:55,877 [salt.state       ][INFO    ][30128] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 21:46:55.876984 duration_in_ms=124.932
2017-09-16 21:46:55,879 [salt.state       ][INFO    ][30128] Running state [keystone-credential-keys] at time 21:46:55.878884
2017-09-16 21:46:55,879 [salt.state       ][INFO    ][30128] Executing state glusterfs.started for keystone-credential-keys
2017-09-16 21:46:55,880 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:55,897 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:56,004 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --version' in directory '/root'
2017-09-16 21:46:56,022 [salt.loaded.int.module.cmdmod][INFO    ][30128] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-16 21:46:56,140 [salt.state       ][INFO    ][30128] Volume keystone-credential-keys is already started
2017-09-16 21:46:56,140 [salt.state       ][INFO    ][30128] Completed state [keystone-credential-keys] at time 21:46:56.140068 duration_in_ms=261.182
2017-09-16 21:46:56,144 [salt.minion      ][INFO    ][30128] Returning information for job: 20170916214649168118
2017-09-16 21:46:57,285 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command test.ping with jid 20170916214657272850
2017-09-16 21:46:57,307 [salt.minion      ][INFO    ][30530] Starting a new job with PID 30530
2017-09-16 21:46:57,358 [salt.minion      ][INFO    ][30530] Returning information for job: 20170916214657272850
2017-09-16 21:46:57,999 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command cmd.run with jid 20170916214657989521
2017-09-16 21:46:58,021 [salt.minion      ][INFO    ][30535] Starting a new job with PID 30535
2017-09-16 21:46:58,029 [salt.loaded.int.module.cmdmod][INFO    ][30535] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-09-16 21:46:58,254 [salt.minion      ][INFO    ][30535] Returning information for job: 20170916214657989521
2017-09-16 22:26:11,128 [salt.utils.schedule][INFO    ][21549] Running scheduled job: __mine_interval
2017-09-16 22:52:41,517 [salt.minion      ][INFO    ][21549] User sudo_ubuntu Executing command cp.push_dir with jid 20170916225241508074
2017-09-16 22:52:41,539 [salt.minion      ][INFO    ][31491] Starting a new job with PID 31491
