2017-10-27 22:01:14,078 [salt.cli.daemons ][WARNING ][2549] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-27 22:01:28,516 [salt.state       ][ERROR   ][3758] No contents found in top file
2017-10-27 22:03:05,092 [salt.utils.parsers][WARNING ][2549] Minion received a SIGTERM. Exiting.
2017-10-27 22:05:17,334 [salt.cli.daemons ][WARNING ][3206] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-27 22:07:06,904 [salt.transport.ipc][ERROR   ][3206] Exception occurred while handling stream: [Errno 0] Success
2017-10-27 22:08:17,512 [salt.loaded.int.module.cmdmod][ERROR   ][11025] Command 'virsh net-destroy default 2>&1 1>/dev/null; virsh net-undefine default' failed with return code: 1
2017-10-27 22:08:17,512 [salt.loaded.int.module.cmdmod][ERROR   ][11025] stderr: error: failed to get network 'default'
error: Network not found: no network with matching name 'default'
2017-10-27 22:08:17,512 [salt.loaded.int.module.cmdmod][ERROR   ][11025] retcode: 1
2017-10-27 22:08:17,513 [salt.state       ][ERROR   ][11025] {'pid': 15904, 'retcode': 1, 'stderr': "error: failed to get network 'default'\nerror: Network not found: no network with matching name 'default'", 'stdout': ''}
2017-10-27 22:08:35,309 [py.warnings      ][WARNING ][15981] /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-10-27 22:09:27,830 [salt.loaded.int.module.cmdmod][ERROR   ][15981] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:27,830 [salt.loaded.int.module.cmdmod][ERROR   ][15981] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:09:34,370 [salt.loaded.int.module.cmdmod][ERROR   ][15981] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:34,370 [salt.loaded.int.module.cmdmod][ERROR   ][15981] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:09:42,685 [salt.loaded.int.module.cmdmod][ERROR   ][15981] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:42,686 [salt.loaded.int.module.cmdmod][ERROR   ][15981] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:09:53,805 [salt.loaded.int.module.cmdmod][ERROR   ][15981] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:53,806 [salt.loaded.int.module.cmdmod][ERROR   ][15981] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:09:59,278 [salt.loaded.int.module.cmdmod][ERROR   ][15981] 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-10-27 22:10:01,389 [salt.loaded.int.module.cmdmod][INFO    ][23278] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-27 22:10:01,395 [salt.loaded.int.module.cmdmod][INFO    ][23278] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-27 22:10:01,404 [salt.loaded.int.module.cmdmod][INFO    ][23278] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-10-27 22:10:01,411 [salt.utils.parsers][WARNING ][3206] Minion received a SIGTERM. Exiting.
2017-10-27 22:10:01,681 [salt.cli.daemons ][INFO    ][23366] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-odl-ha.local"
2017-10-27 22:10:02,204 [salt.minion      ][INFO    ][23366] Creating minion process manager
2017-10-27 22:10:02,205 [salt.cli.daemons ][WARNING ][23366] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-27 22:10:02,205 [salt.cli.daemons ][INFO    ][23366] The Salt Minion is starting up
2017-10-27 22:10:02,205 [salt.minion      ][INFO    ][23366] Minion is starting as user 'root'
2017-10-27 22:10:02,205 [salt.utils.event ][INFO    ][23366] Starting pull socket on /var/run/salt/minion/minion_event_0607a05f39_pull.ipc
2017-10-27 22:10:03,101 [salt.loaded.int.module.cmdmod][INFO    ][23366] Executing command ['date', '+%z'] in directory '/root'
2017-10-27 22:10:03,109 [salt.utils.schedule][INFO    ][23366] Updating job settings for scheduled job: __mine_interval
2017-10-27 22:10:03,111 [salt.minion      ][INFO    ][23366] Added mine.update to scheduler
2017-10-27 22:10:03,250 [salt.minion      ][INFO    ][23366] Minion is ready to receive requests!
2017-10-27 22:10:04,251 [salt.utils.schedule][INFO    ][23366] Running scheduled job: __mine_interval
2017-10-27 22:10:23,397 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171027221023398257
2017-10-27 22:10:23,411 [salt.minion      ][INFO    ][23496] Starting a new job with PID 23496
2017-10-27 22:10:25,975 [salt.state       ][INFO    ][23496] Loading fresh modules for state activity
2017-10-27 22:10:25,997 [salt.state       ][ERROR   ][23496] No contents found in top file
2017-10-27 22:10:26,011 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-10-27 22:10:26,014 [salt.utils.extmods][INFO    ][23496] Syncing beacons for environment 'base'
2017-10-27 22:10:26,014 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_beacons, for base)
2017-10-27 22:10:26,014 [salt.fileclient  ][INFO    ][23496] Caching directory '_beacons/' for environment 'base'
2017-10-27 22:10:26,044 [salt.utils.extmods][INFO    ][23496] Syncing modules for environment 'base'
2017-10-27 22:10:26,052 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_modules, for base)
2017-10-27 22:10:26,052 [salt.fileclient  ][INFO    ][23496] Caching directory '_modules/' for environment 'base'
2017-10-27 22:10:26,098 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-10-27 22:10:26,203 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-10-27 22:10:26,263 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-10-27 22:10:26,355 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-10-27 22:10:26,451 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-10-27 22:10:26,557 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-10-27 22:10:26,680 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-10-27 22:10:26,774 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-10-27 22:10:26,867 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-10-27 22:10:26,983 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-10-27 22:10:27,009 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-10-27 22:10:27,022 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-10-27 22:10:27,098 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-10-27 22:10:27,146 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-10-27 22:10:27,159 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-10-27 22:10:27,175 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-10-27 22:10:27,198 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-10-27 22:10:27,272 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-10-27 22:10:27,282 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-10-27 22:10:27,309 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-10-27 22:10:27,368 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-10-27 22:10:27,441 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-10-27 22:10:27,502 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-10-27 22:10:27,542 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-10-27 22:10:27,585 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-10-27 22:10:27,651 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-10-27 22:10:27,688 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-10-27 22:10:27,748 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-10-27 22:10:27,778 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-10-27 22:10:27,814 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-10-27 22:10:27,829 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-10-27 22:10:27,902 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-10-27 22:10:27,905 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-10-27 22:10:27,905 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-10-27 22:10:28,009 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-10-27 22:10:28,015 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-10-27 22:10:28,039 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-10-27 22:10:28,063 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-10-27 22:10:28,106 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-10-27 22:10:28,161 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-10-27 22:10:28,199 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-10-27 22:10:28,285 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-10-27 22:10:28,342 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-10-27 22:10:28,398 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-10-27 22:10:28,468 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-10-27 22:10:28,488 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-10-27 22:10:28,522 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-10-27 22:10:28,522 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-10-27 22:10:28,522 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-10-27 22:10:28,523 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-10-27 22:10:28,523 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-10-27 22:10:28,529 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-10-27 22:10:28,585 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-10-27 22:10:28,585 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-10-27 22:10:28,594 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-10-27 22:10:28,594 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-10-27 22:10:28,595 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-10-27 22:10:28,605 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-10-27 22:10:28,611 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-10-27 22:10:28,612 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-10-27 22:10:28,612 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-10-27 22:10:28,613 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-10-27 22:10:28,613 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-10-27 22:10:28,613 [salt.utils.extmods][INFO    ][23496] 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-10-27 22:10:28,614 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-10-27 22:10:28,614 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-10-27 22:10:28,624 [salt.utils.extmods][INFO    ][23496] Syncing states for environment 'base'
2017-10-27 22:10:28,624 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_states, for base)
2017-10-27 22:10:28,625 [salt.fileclient  ][INFO    ][23496] Caching directory '_states/' for environment 'base'
2017-10-27 22:10:28,719 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-10-27 22:10:28,773 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-10-27 22:10:28,797 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-10-27 22:10:28,906 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-10-27 22:10:28,953 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-10-27 22:10:28,965 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-10-27 22:10:28,976 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-10-27 22:10:29,086 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-10-27 22:10:29,181 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-10-27 22:10:29,224 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-10-27 22:10:29,314 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-10-27 22:10:29,400 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-10-27 22:10:29,558 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-10-27 22:10:29,691 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-10-27 22:10:29,787 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-10-27 22:10:29,928 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-10-27 22:10:30,022 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-10-27 22:10:30,047 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-10-27 22:10:30,070 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-10-27 22:10:30,155 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-10-27 22:10:30,327 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-10-27 22:10:30,385 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-10-27 22:10:30,477 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-10-27 22:10:30,657 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-10-27 22:10:30,717 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-10-27 22:10:30,732 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-10-27 22:10:30,869 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-10-27 22:10:30,991 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-10-27 22:10:31,122 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-10-27 22:10:31,125 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-10-27 22:10:31,125 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-10-27 22:10:31,125 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-10-27 22:10:31,125 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-10-27 22:10:31,126 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-10-27 22:10:31,126 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-10-27 22:10:31,126 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-10-27 22:10:31,126 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-10-27 22:10:31,127 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-10-27 22:10:31,127 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-10-27 22:10:31,127 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-10-27 22:10:31,128 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-10-27 22:10:31,128 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-10-27 22:10:31,128 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-10-27 22:10:31,128 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-10-27 22:10:31,129 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-10-27 22:10:31,129 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-10-27 22:10:31,129 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-10-27 22:10:31,129 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-10-27 22:10:31,130 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-10-27 22:10:31,130 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-10-27 22:10:31,130 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-10-27 22:10:31,130 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-10-27 22:10:31,131 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-10-27 22:10:31,131 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-10-27 22:10:31,131 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-10-27 22:10:31,132 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-10-27 22:10:31,132 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-10-27 22:10:31,132 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-10-27 22:10:31,132 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-10-27 22:10:31,134 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-10-27 22:10:31,137 [salt.utils.extmods][INFO    ][23496] Syncing sdb for environment 'base'
2017-10-27 22:10:31,137 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_sdb, for base)
2017-10-27 22:10:31,137 [salt.fileclient  ][INFO    ][23496] Caching directory '_sdb/' for environment 'base'
2017-10-27 22:10:31,194 [salt.utils.extmods][INFO    ][23496] Syncing grains for environment 'base'
2017-10-27 22:10:31,195 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_grains, for base)
2017-10-27 22:10:31,195 [salt.fileclient  ][INFO    ][23496] Caching directory '_grains/' for environment 'base'
2017-10-27 22:10:31,299 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-10-27 22:10:31,423 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-10-27 22:10:31,533 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-10-27 22:10:31,617 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-10-27 22:10:31,790 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-10-27 22:10:31,844 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-10-27 22:10:32,005 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-10-27 22:10:32,078 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-10-27 22:10:32,168 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-10-27 22:10:32,306 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-10-27 22:10:32,623 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-10-27 22:10:32,683 [salt.fileclient  ][INFO    ][23496] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-10-27 22:10:32,686 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-10-27 22:10:32,742 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-10-27 22:10:32,753 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-10-27 22:10:32,753 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-10-27 22:10:32,754 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-10-27 22:10:32,754 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-10-27 22:10:32,754 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-10-27 22:10:32,765 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-10-27 22:10:32,766 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-10-27 22:10:32,766 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-10-27 22:10:32,766 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-10-27 22:10:32,767 [salt.utils.extmods][INFO    ][23496] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-10-27 22:10:32,769 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-10-27 22:10:32,771 [salt.utils.extmods][INFO    ][23496] Syncing renderers for environment 'base'
2017-10-27 22:10:32,771 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_renderers, for base)
2017-10-27 22:10:32,771 [salt.fileclient  ][INFO    ][23496] Caching directory '_renderers/' for environment 'base'
2017-10-27 22:10:32,799 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-10-27 22:10:32,802 [salt.utils.extmods][INFO    ][23496] Syncing returners for environment 'base'
2017-10-27 22:10:32,802 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_returners, for base)
2017-10-27 22:10:32,802 [salt.fileclient  ][INFO    ][23496] Caching directory '_returners/' for environment 'base'
2017-10-27 22:10:32,837 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-10-27 22:10:32,839 [salt.utils.extmods][INFO    ][23496] Syncing output for environment 'base'
2017-10-27 22:10:32,840 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_output, for base)
2017-10-27 22:10:32,840 [salt.fileclient  ][INFO    ][23496] Caching directory '_output/' for environment 'base'
2017-10-27 22:10:32,868 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-10-27 22:10:32,870 [salt.utils.extmods][INFO    ][23496] Syncing utils for environment 'base'
2017-10-27 22:10:32,870 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_utils, for base)
2017-10-27 22:10:32,870 [salt.fileclient  ][INFO    ][23496] Caching directory '_utils/' for environment 'base'
2017-10-27 22:10:32,902 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-10-27 22:10:32,905 [salt.utils.extmods][INFO    ][23496] Syncing log_handlers for environment 'base'
2017-10-27 22:10:32,905 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_log_handlers, for base)
2017-10-27 22:10:32,905 [salt.fileclient  ][INFO    ][23496] Caching directory '_log_handlers/' for environment 'base'
2017-10-27 22:10:32,937 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-10-27 22:10:32,939 [salt.utils.extmods][INFO    ][23496] Syncing proxy for environment 'base'
2017-10-27 22:10:32,939 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_proxy, for base)
2017-10-27 22:10:32,939 [salt.fileclient  ][INFO    ][23496] Caching directory '_proxy/' for environment 'base'
2017-10-27 22:10:32,969 [salt.utils.extmods][INFO    ][23496] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-10-27 22:10:32,972 [salt.utils.extmods][INFO    ][23496] Syncing engines for environment 'base'
2017-10-27 22:10:32,972 [salt.utils.extmods][INFO    ][23496] Loading cache from salt://_engines, for base)
2017-10-27 22:10:32,972 [salt.fileclient  ][INFO    ][23496] Caching directory '_engines/' for environment 'base'
2017-10-27 22:10:33,004 [salt.minion      ][INFO    ][23496] Returning information for job: 20171027221023398257
2017-10-27 22:10:35,370 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027221035372952
2017-10-27 22:10:35,386 [salt.minion      ][INFO    ][23624] Starting a new job with PID 23624
2017-10-27 22:10:35,856 [salt.state       ][INFO    ][23624] Loading fresh modules for state activity
2017-10-27 22:10:35,882 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-10-27 22:10:35,901 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-10-27 22:10:36,033 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:10:36,099 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-10-27 22:10:36,168 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-10-27 22:10:36,191 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:10:36,253 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-27 22:10:36,310 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:10:36,422 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-27 22:10:36,441 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-27 22:10:36,454 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:10:36,469 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-27 22:10:36,475 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-27 22:10:36,591 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-27 22:10:36,606 [py.warnings      ][WARNING ][23624] /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-10-27 22:10:36,607 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'salt-minion --version' in directory '/root'
2017-10-27 22:10:36,850 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-27 22:10:36,869 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-27 22:10:36,881 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-27 22:10:36,893 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-27 22:10:36,937 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-27 22:10:36,973 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-27 22:10:37,118 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-10-27 22:10:37,138 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:10:37,189 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-27 22:10:37,207 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:10:37,249 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-27 22:10:37,266 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-27 22:10:37,278 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:10:37,293 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-27 22:10:37,299 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-27 22:10:37,317 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-27 22:10:37,331 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'salt-minion --version' in directory '/root'
2017-10-27 22:10:37,607 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-27 22:10:37,739 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-27 22:10:37,750 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-27 22:10:37,762 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-27 22:10:37,806 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-27 22:10:37,839 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-27 22:10:37,901 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-10-27 22:10:37,981 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:10:38,409 [salt.state       ][INFO    ][23624] Running state [salt-minion] at time 22:10:38.409407
2017-10-27 22:10:38,410 [salt.state       ][INFO    ][23624] Executing state pkg.installed for salt-minion
2017-10-27 22:10:38,410 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:10:38,694 [salt.state       ][INFO    ][23624] Package salt-minion is already installed
2017-10-27 22:10:38,780 [salt.state       ][INFO    ][23624] Completed state [salt-minion] at time 22:10:38.779780 duration_in_ms=370.372
2017-10-27 22:10:38,780 [salt.state       ][INFO    ][23624] Running state [salt_minion_dependency_packages] at time 22:10:38.780127
2017-10-27 22:10:38,780 [salt.state       ][INFO    ][23624] Executing state pkg.installed for salt_minion_dependency_packages
2017-10-27 22:10:38,783 [salt.state       ][INFO    ][23624] All specified packages are already installed
2017-10-27 22:10:38,783 [salt.state       ][INFO    ][23624] Completed state [salt_minion_dependency_packages] at time 22:10:38.783241 duration_in_ms=3.114
2017-10-27 22:10:38,785 [salt.state       ][INFO    ][23624] Running state [/etc/salt/minion.d/minion.conf] at time 22:10:38.784721
2017-10-27 22:10:38,785 [salt.state       ][INFO    ][23624] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-10-27 22:10:38,803 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-10-27 22:10:38,847 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:10:38,889 [salt.fileclient  ][INFO    ][23624] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:10:38,937 [salt.state       ][INFO    ][23624] File /etc/salt/minion.d/minion.conf is in the correct state
2017-10-27 22:10:38,937 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/minion.d/minion.conf] at time 22:10:38.936684 duration_in_ms=151.962
2017-10-27 22:10:38,937 [salt.state       ][INFO    ][23624] Running state [/etc/salt/minion.d/_virt.conf] at time 22:10:38.936995
2017-10-27 22:10:38,937 [salt.state       ][INFO    ][23624] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-10-27 22:10:39,108 [salt.state       ][INFO    ][23624] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-10-27 22:10:39,108 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/minion.d/_virt.conf] at time 22:10:39.107791 duration_in_ms=170.796
2017-10-27 22:10:39,109 [salt.state       ][INFO    ][23624] Running state [salt-minion] at time 22:10:39.109309
2017-10-27 22:10:39,110 [salt.state       ][INFO    ][23624] Executing state service.running for salt-minion
2017-10-27 22:10:39,110 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-27 22:10:39,124 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-10-27 22:10:39,132 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-27 22:10:39,138 [salt.state       ][INFO    ][23624] The service salt-minion is already running
2017-10-27 22:10:39,138 [salt.state       ][INFO    ][23624] Completed state [salt-minion] at time 22:10:39.137810 duration_in_ms=28.5
2017-10-27 22:10:39,139 [salt.state       ][INFO    ][23624] Running state [/etc/salt/grains.d] at time 22:10:39.139119
2017-10-27 22:10:39,139 [salt.state       ][INFO    ][23624] Executing state file.directory for /etc/salt/grains.d
2017-10-27 22:10:39,140 [salt.state       ][INFO    ][23624] Directory /etc/salt/grains.d is in the correct state
2017-10-27 22:10:39,140 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/grains.d] at time 22:10:39.140016 duration_in_ms=0.897
2017-10-27 22:10:39,140 [salt.state       ][INFO    ][23624] Running state [/etc/salt/grains] at time 22:10:39.140397
2017-10-27 22:10:39,141 [salt.state       ][INFO    ][23624] Executing state file.managed for /etc/salt/grains
2017-10-27 22:10:39,141 [salt.state       ][INFO    ][23624] File /etc/salt/grains exists with proper permissions. No changes made.
2017-10-27 22:10:39,141 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/grains] at time 22:10:39.140980 duration_in_ms=0.583
2017-10-27 22:10:39,141 [salt.state       ][INFO    ][23624] Running state [/etc/salt/grains.d/placeholder] at time 22:10:39.141236
2017-10-27 22:10:39,141 [salt.state       ][INFO    ][23624] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-10-27 22:10:39,142 [salt.state       ][INFO    ][23624] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-10-27 22:10:39,142 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/grains.d/placeholder] at time 22:10:39.141783 duration_in_ms=0.547
2017-10-27 22:10:39,142 [salt.state       ][INFO    ][23624] Running state [/etc/salt/grains.d/sphinx] at time 22:10:39.142029
2017-10-27 22:10:39,142 [salt.state       ][INFO    ][23624] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-10-27 22:10:39,326 [salt.state       ][INFO    ][23624] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.142
               - 127.0.0.1
               - 192.168.11.252
-              - 192.168.122.1
         system:
           name: System
           param:

2017-10-27 22:10:39,327 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/grains.d/sphinx] at time 22:10:39.326516 duration_in_ms=184.487
2017-10-27 22:10:39,327 [salt.state       ][INFO    ][23624] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.327135
2017-10-27 22:10:39,327 [salt.state       ][INFO    ][23624] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:10:39,328 [salt.state       ][INFO    ][23624] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:10:39,328 [salt.state       ][INFO    ][23624] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.327711 duration_in_ms=0.576
2017-10-27 22:10:39,328 [salt.state       ][INFO    ][23624] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.327841
2017-10-27 22:10:39,328 [salt.state       ][INFO    ][23624] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:10:39,329 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-10-27 22:10:39,407 [salt.state       ][INFO    ][23624] {'pid': 23672, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-27 22:10:39,407 [salt.state       ][INFO    ][23624] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.407090 duration_in_ms=79.247
2017-10-27 22:10:39,408 [salt.state       ][INFO    ][23624] Running state [/etc/salt/grains.d/dns_records] at time 22:10:39.407507
2017-10-27 22:10:39,408 [salt.state       ][INFO    ][23624] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-10-27 22:10:39,560 [salt.state       ][INFO    ][23624] File /etc/salt/grains.d/dns_records is in the correct state
2017-10-27 22:10:39,560 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/grains.d/dns_records] at time 22:10:39.560378 duration_in_ms=152.871
2017-10-27 22:10:39,561 [salt.state       ][INFO    ][23624] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.561314
2017-10-27 22:10:39,562 [salt.state       ][INFO    ][23624] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:10:39,562 [salt.state       ][INFO    ][23624] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:10:39,562 [salt.state       ][INFO    ][23624] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.562044 duration_in_ms=0.73
2017-10-27 22:10:39,562 [salt.state       ][INFO    ][23624] Running state [/etc/salt/grains.d/salt] at time 22:10:39.562443
2017-10-27 22:10:39,563 [salt.state       ][INFO    ][23624] Executing state file.managed for /etc/salt/grains.d/salt
2017-10-27 22:10:39,739 [salt.state       ][INFO    ][23624] File /etc/salt/grains.d/salt is in the correct state
2017-10-27 22:10:39,740 [salt.state       ][INFO    ][23624] Completed state [/etc/salt/grains.d/salt] at time 22:10:39.739456 duration_in_ms=177.012
2017-10-27 22:10:39,740 [salt.state       ][INFO    ][23624] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.740079
2017-10-27 22:10:39,740 [salt.state       ][INFO    ][23624] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:10:39,740 [salt.state       ][INFO    ][23624] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:10:39,741 [salt.state       ][INFO    ][23624] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:10:39.740607 duration_in_ms=0.528
2017-10-27 22:10:39,742 [salt.state       ][INFO    ][23624] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:10:39.741743
2017-10-27 22:10:39,742 [salt.state       ][INFO    ][23624] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:10:39,742 [salt.state       ][INFO    ][23624] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:10:39,742 [salt.state       ][INFO    ][23624] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:10:39.742223 duration_in_ms=0.48
2017-10-27 22:10:39,742 [salt.state       ][INFO    ][23624] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:10:39.742347
2017-10-27 22:10:39,743 [salt.state       ][INFO    ][23624] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:10:39,744 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-10-27 22:10:39,751 [salt.state       ][INFO    ][23624] {'pid': 23674, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-27 22:10:39,752 [salt.state       ][INFO    ][23624] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:10:39.751685 duration_in_ms=9.336
2017-10-27 22:10:39,752 [salt.state       ][INFO    ][23624] Running state [mine.update] at time 22:10:39.752350
2017-10-27 22:10:39,753 [salt.state       ][INFO    ][23624] Executing state module.wait for mine.update
2017-10-27 22:10:39,753 [salt.state       ][INFO    ][23624] No changes made for mine.update
2017-10-27 22:10:39,753 [salt.state       ][INFO    ][23624] Completed state [mine.update] at time 22:10:39.752977 duration_in_ms=0.627
2017-10-27 22:10:39,753 [salt.state       ][INFO    ][23624] Running state [mine.update] at time 22:10:39.753130
2017-10-27 22:10:39,753 [salt.state       ][INFO    ][23624] Executing state module.mod_watch for mine.update
2017-10-27 22:10:40,272 [salt.state       ][INFO    ][23624] {'ret': True}
2017-10-27 22:10:40,272 [salt.state       ][INFO    ][23624] Completed state [mine.update] at time 22:10:40.272254 duration_in_ms=519.123
2017-10-27 22:10:40,273 [salt.state       ][INFO    ][23624] Running state [ca-certificates] at time 22:10:40.272538
2017-10-27 22:10:40,273 [salt.state       ][INFO    ][23624] Executing state pkg.installed for ca-certificates
2017-10-27 22:10:40,276 [salt.state       ][INFO    ][23624] Package ca-certificates is already installed
2017-10-27 22:10:40,276 [salt.state       ][INFO    ][23624] Completed state [ca-certificates] at time 22:10:40.276340 duration_in_ms=3.802
2017-10-27 22:10:40,277 [salt.state       ][INFO    ][23624] Running state [update-ca-certificates] at time 22:10:40.276959
2017-10-27 22:10:40,277 [salt.state       ][INFO    ][23624] Executing state cmd.wait for update-ca-certificates
2017-10-27 22:10:40,277 [salt.state       ][INFO    ][23624] No changes made for update-ca-certificates
2017-10-27 22:10:40,278 [salt.state       ][INFO    ][23624] Completed state [update-ca-certificates] at time 22:10:40.277583 duration_in_ms=0.624
2017-10-27 22:10:40,278 [salt.state       ][INFO    ][23624] Running state [libvirt-dev] at time 22:10:40.277774
2017-10-27 22:10:40,278 [salt.state       ][INFO    ][23624] Executing state pkg.installed for libvirt-dev
2017-10-27 22:10:40,281 [salt.state       ][INFO    ][23624] Package libvirt-dev is already installed
2017-10-27 22:10:40,281 [salt.state       ][INFO    ][23624] Completed state [libvirt-dev] at time 22:10:40.280836 duration_in_ms=3.062
2017-10-27 22:10:40,281 [salt.state       ][INFO    ][23624] Running state [pkg-config] at time 22:10:40.281027
2017-10-27 22:10:40,281 [salt.state       ][INFO    ][23624] Executing state pkg.installed for pkg-config
2017-10-27 22:10:40,284 [salt.state       ][INFO    ][23624] Package pkg-config is already installed
2017-10-27 22:10:40,284 [salt.state       ][INFO    ][23624] Completed state [pkg-config] at time 22:10:40.284086 duration_in_ms=3.06
2017-10-27 22:10:40,284 [salt.state       ][INFO    ][23624] Running state [virtng.init] at time 22:10:40.284284
2017-10-27 22:10:40,284 [salt.state       ][INFO    ][23624] Executing state module.run for virtng.init
2017-10-27 22:10:40,582 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command ['git', '--version'] in directory '/root'
2017-10-27 22:10:40,695 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,715 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,715 [salt.state       ][INFO    ][23624] Completed state [virtng.init] at time 22:10:40.715181 duration_in_ms=430.896
2017-10-27 22:10:40,716 [salt.state       ][INFO    ][23624] Running state [virt.set_autostart] at time 22:10:40.715542
2017-10-27 22:10:40,716 [salt.state       ][INFO    ][23624] Executing state module.run for virt.set_autostart
2017-10-27 22:10:40,719 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,737 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,737 [salt.state       ][INFO    ][23624] Completed state [virt.set_autostart] at time 22:10:40.736829 duration_in_ms=21.286
2017-10-27 22:10:40,737 [salt.state       ][INFO    ][23624] Running state [virtng.init] at time 22:10:40.737068
2017-10-27 22:10:40,737 [salt.state       ][INFO    ][23624] Executing state module.run for virtng.init
2017-10-27 22:10:40,739 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,753 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,753 [salt.state       ][INFO    ][23624] Completed state [virtng.init] at time 22:10:40.753089 duration_in_ms=16.02
2017-10-27 22:10:40,753 [salt.state       ][INFO    ][23624] Running state [virt.set_autostart] at time 22:10:40.753304
2017-10-27 22:10:40,754 [salt.state       ][INFO    ][23624] Executing state module.run for virt.set_autostart
2017-10-27 22:10:40,755 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,771 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,771 [salt.state       ][INFO    ][23624] Completed state [virt.set_autostart] at time 22:10:40.770894 duration_in_ms=17.589
2017-10-27 22:10:40,771 [salt.state       ][INFO    ][23624] Running state [virtng.init] at time 22:10:40.771116
2017-10-27 22:10:40,771 [salt.state       ][INFO    ][23624] Executing state module.run for virtng.init
2017-10-27 22:10:40,773 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,788 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,832 [salt.state       ][INFO    ][23624] Completed state [virtng.init] at time 22:10:40.832085 duration_in_ms=60.969
2017-10-27 22:10:40,832 [salt.state       ][INFO    ][23624] Running state [virt.set_autostart] at time 22:10:40.832434
2017-10-27 22:10:40,833 [salt.state       ][INFO    ][23624] Executing state module.run for virt.set_autostart
2017-10-27 22:10:40,835 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,852 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,852 [salt.state       ][INFO    ][23624] Completed state [virt.set_autostart] at time 22:10:40.852129 duration_in_ms=19.694
2017-10-27 22:10:40,852 [salt.state       ][INFO    ][23624] Running state [virtng.init] at time 22:10:40.852351
2017-10-27 22:10:40,853 [salt.state       ][INFO    ][23624] Executing state module.run for virtng.init
2017-10-27 22:10:40,854 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,868 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,869 [salt.state       ][INFO    ][23624] Completed state [virtng.init] at time 22:10:40.868472 duration_in_ms=16.12
2017-10-27 22:10:40,869 [salt.state       ][INFO    ][23624] Running state [virt.set_autostart] at time 22:10:40.868690
2017-10-27 22:10:40,869 [salt.state       ][INFO    ][23624] Executing state module.run for virt.set_autostart
2017-10-27 22:10:40,871 [salt.loaded.int.module.cmdmod][INFO    ][23624] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:10:40,884 [salt.state       ][INFO    ][23624] unless execution succeeded
2017-10-27 22:10:40,884 [salt.state       ][INFO    ][23624] Completed state [virt.set_autostart] at time 22:10:40.884322 duration_in_ms=15.632
2017-10-27 22:10:40,887 [salt.minion      ][INFO    ][23624] Returning information for job: 20171027221035372952
2017-10-27 22:11:06,511 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027221106498839
2017-10-27 22:11:06,526 [salt.minion      ][INFO    ][23726] Starting a new job with PID 23726
2017-10-27 22:11:09,004 [salt.state       ][INFO    ][23726] Loading fresh modules for state activity
2017-10-27 22:11:09,031 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-10-27 22:11:09,052 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-10-27 22:11:09,119 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:09,183 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-10-27 22:11:09,197 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-10-27 22:11:09,221 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:09,273 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-27 22:11:09,290 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:11:09,335 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-27 22:11:09,356 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-27 22:11:09,370 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:11:09,395 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-27 22:11:09,402 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-27 22:11:09,424 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-27 22:11:09,439 [py.warnings      ][WARNING ][23726] /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-10-27 22:11:09,440 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'salt-minion --version' in directory '/root'
2017-10-27 22:11:09,677 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-27 22:11:09,695 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-27 22:11:09,706 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-27 22:11:09,718 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-27 22:11:09,762 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-27 22:11:09,797 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-27 22:11:09,852 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-10-27 22:11:09,871 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:09,921 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-27 22:11:09,939 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:11:09,980 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-27 22:11:09,996 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-27 22:11:10,009 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:11:10,024 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-27 22:11:10,030 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-27 22:11:10,051 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-27 22:11:10,067 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'salt-minion --version' in directory '/root'
2017-10-27 22:11:10,305 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-27 22:11:10,323 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-27 22:11:10,335 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-27 22:11:10,347 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-27 22:11:10,392 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-27 22:11:10,428 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-27 22:11:10,488 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-10-27 22:11:10,542 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:10,836 [salt.state       ][INFO    ][23726] Running state [salt-minion] at time 22:11:10.835768
2017-10-27 22:11:10,836 [salt.state       ][INFO    ][23726] Executing state pkg.installed for salt-minion
2017-10-27 22:11:10,837 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:11:11,106 [salt.state       ][INFO    ][23726] Package salt-minion is already installed
2017-10-27 22:11:11,106 [salt.state       ][INFO    ][23726] Completed state [salt-minion] at time 22:11:11.106177 duration_in_ms=270.409
2017-10-27 22:11:11,106 [salt.state       ][INFO    ][23726] Running state [salt_minion_dependency_packages] at time 22:11:11.106401
2017-10-27 22:11:11,107 [salt.state       ][INFO    ][23726] Executing state pkg.installed for salt_minion_dependency_packages
2017-10-27 22:11:11,109 [salt.state       ][INFO    ][23726] All specified packages are already installed
2017-10-27 22:11:11,109 [salt.state       ][INFO    ][23726] Completed state [salt_minion_dependency_packages] at time 22:11:11.109314 duration_in_ms=2.913
2017-10-27 22:11:11,111 [salt.state       ][INFO    ][23726] Running state [/etc/salt/minion.d/minion.conf] at time 22:11:11.110794
2017-10-27 22:11:11,111 [salt.state       ][INFO    ][23726] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-10-27 22:11:11,131 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-10-27 22:11:11,175 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:11,217 [salt.fileclient  ][INFO    ][23726] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:11:11,249 [salt.state       ][INFO    ][23726] File /etc/salt/minion.d/minion.conf is in the correct state
2017-10-27 22:11:11,249 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/minion.d/minion.conf] at time 22:11:11.249154 duration_in_ms=138.36
2017-10-27 22:11:11,249 [salt.state       ][INFO    ][23726] Running state [/etc/salt/minion.d/_virt.conf] at time 22:11:11.249422
2017-10-27 22:11:11,250 [salt.state       ][INFO    ][23726] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-10-27 22:11:11,255 [salt.state       ][INFO    ][23726] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-10-27 22:11:11,255 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/minion.d/_virt.conf] at time 22:11:11.254703 duration_in_ms=5.28
2017-10-27 22:11:11,256 [salt.state       ][INFO    ][23726] Running state [salt-minion] at time 22:11:11.256069
2017-10-27 22:11:11,256 [salt.state       ][INFO    ][23726] Executing state service.running for salt-minion
2017-10-27 22:11:11,257 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-27 22:11:11,272 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-10-27 22:11:11,279 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-27 22:11:11,284 [salt.state       ][INFO    ][23726] The service salt-minion is already running
2017-10-27 22:11:11,284 [salt.state       ][INFO    ][23726] Completed state [salt-minion] at time 22:11:11.284183 duration_in_ms=28.114
2017-10-27 22:11:11,285 [salt.state       ][INFO    ][23726] Running state [/etc/salt/grains.d] at time 22:11:11.285326
2017-10-27 22:11:11,286 [salt.state       ][INFO    ][23726] Executing state file.directory for /etc/salt/grains.d
2017-10-27 22:11:11,286 [salt.state       ][INFO    ][23726] Directory /etc/salt/grains.d is in the correct state
2017-10-27 22:11:11,286 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/grains.d] at time 22:11:11.286122 duration_in_ms=0.796
2017-10-27 22:11:11,287 [salt.state       ][INFO    ][23726] Running state [/etc/salt/grains] at time 22:11:11.286514
2017-10-27 22:11:11,287 [salt.state       ][INFO    ][23726] Executing state file.managed for /etc/salt/grains
2017-10-27 22:11:11,287 [salt.state       ][INFO    ][23726] File /etc/salt/grains exists with proper permissions. No changes made.
2017-10-27 22:11:11,287 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/grains] at time 22:11:11.287102 duration_in_ms=0.587
2017-10-27 22:11:11,287 [salt.state       ][INFO    ][23726] Running state [/etc/salt/grains.d/placeholder] at time 22:11:11.287358
2017-10-27 22:11:11,288 [salt.state       ][INFO    ][23726] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-10-27 22:11:11,288 [salt.state       ][INFO    ][23726] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-10-27 22:11:11,288 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/grains.d/placeholder] at time 22:11:11.287887 duration_in_ms=0.529
2017-10-27 22:11:11,288 [salt.state       ][INFO    ][23726] Running state [/etc/salt/grains.d/sphinx] at time 22:11:11.288130
2017-10-27 22:11:11,288 [salt.state       ][INFO    ][23726] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-10-27 22:11:11,291 [salt.state       ][INFO    ][23726] File /etc/salt/grains.d/sphinx is in the correct state
2017-10-27 22:11:11,291 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/grains.d/sphinx] at time 22:11:11.290627 duration_in_ms=2.496
2017-10-27 22:11:11,291 [salt.state       ][INFO    ][23726] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.291125
2017-10-27 22:11:11,291 [salt.state       ][INFO    ][23726] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,291 [salt.state       ][INFO    ][23726] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,292 [salt.state       ][INFO    ][23726] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.291589 duration_in_ms=0.464
2017-10-27 22:11:11,292 [salt.state       ][INFO    ][23726] Running state [/etc/salt/grains.d/dns_records] at time 22:11:11.291830
2017-10-27 22:11:11,292 [salt.state       ][INFO    ][23726] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-10-27 22:11:11,297 [salt.state       ][INFO    ][23726] File /etc/salt/grains.d/dns_records is in the correct state
2017-10-27 22:11:11,297 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/grains.d/dns_records] at time 22:11:11.296832 duration_in_ms=5.001
2017-10-27 22:11:11,297 [salt.state       ][INFO    ][23726] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.297342
2017-10-27 22:11:11,298 [salt.state       ][INFO    ][23726] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,298 [salt.state       ][INFO    ][23726] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,298 [salt.state       ][INFO    ][23726] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.297797 duration_in_ms=0.454
2017-10-27 22:11:11,298 [salt.state       ][INFO    ][23726] Running state [/etc/salt/grains.d/salt] at time 22:11:11.298037
2017-10-27 22:11:11,298 [salt.state       ][INFO    ][23726] Executing state file.managed for /etc/salt/grains.d/salt
2017-10-27 22:11:11,303 [salt.state       ][INFO    ][23726] File /etc/salt/grains.d/salt is in the correct state
2017-10-27 22:11:11,303 [salt.state       ][INFO    ][23726] Completed state [/etc/salt/grains.d/salt] at time 22:11:11.302900 duration_in_ms=4.863
2017-10-27 22:11:11,303 [salt.state       ][INFO    ][23726] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.303411
2017-10-27 22:11:11,304 [salt.state       ][INFO    ][23726] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,304 [salt.state       ][INFO    ][23726] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,304 [salt.state       ][INFO    ][23726] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.303884 duration_in_ms=0.474
2017-10-27 22:11:11,305 [salt.state       ][INFO    ][23726] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:11:11.305045
2017-10-27 22:11:11,305 [salt.state       ][INFO    ][23726] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:11:11,305 [salt.state       ][INFO    ][23726] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:11:11,306 [salt.state       ][INFO    ][23726] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:11:11.305525 duration_in_ms=0.48
2017-10-27 22:11:11,306 [salt.state       ][INFO    ][23726] Running state [mine.update] at time 22:11:11.305919
2017-10-27 22:11:11,306 [salt.state       ][INFO    ][23726] Executing state module.wait for mine.update
2017-10-27 22:11:11,306 [salt.state       ][INFO    ][23726] No changes made for mine.update
2017-10-27 22:11:11,306 [salt.state       ][INFO    ][23726] Completed state [mine.update] at time 22:11:11.306364 duration_in_ms=0.446
2017-10-27 22:11:11,307 [salt.state       ][INFO    ][23726] Running state [ca-certificates] at time 22:11:11.306507
2017-10-27 22:11:11,307 [salt.state       ][INFO    ][23726] Executing state pkg.installed for ca-certificates
2017-10-27 22:11:11,310 [salt.state       ][INFO    ][23726] Package ca-certificates is already installed
2017-10-27 22:11:11,310 [salt.state       ][INFO    ][23726] Completed state [ca-certificates] at time 22:11:11.309664 duration_in_ms=3.158
2017-10-27 22:11:11,310 [salt.state       ][INFO    ][23726] Running state [update-ca-certificates] at time 22:11:11.310061
2017-10-27 22:11:11,310 [salt.state       ][INFO    ][23726] Executing state cmd.wait for update-ca-certificates
2017-10-27 22:11:11,310 [salt.state       ][INFO    ][23726] No changes made for update-ca-certificates
2017-10-27 22:11:11,311 [salt.state       ][INFO    ][23726] Completed state [update-ca-certificates] at time 22:11:11.310523 duration_in_ms=0.462
2017-10-27 22:11:11,311 [salt.state       ][INFO    ][23726] Running state [libvirt-dev] at time 22:11:11.310667
2017-10-27 22:11:11,311 [salt.state       ][INFO    ][23726] Executing state pkg.installed for libvirt-dev
2017-10-27 22:11:11,313 [salt.state       ][INFO    ][23726] Package libvirt-dev is already installed
2017-10-27 22:11:11,314 [salt.state       ][INFO    ][23726] Completed state [libvirt-dev] at time 22:11:11.313577 duration_in_ms=2.91
2017-10-27 22:11:11,314 [salt.state       ][INFO    ][23726] Running state [pkg-config] at time 22:11:11.313722
2017-10-27 22:11:11,314 [salt.state       ][INFO    ][23726] Executing state pkg.installed for pkg-config
2017-10-27 22:11:11,316 [salt.state       ][INFO    ][23726] Package pkg-config is already installed
2017-10-27 22:11:11,317 [salt.state       ][INFO    ][23726] Completed state [pkg-config] at time 22:11:11.316493 duration_in_ms=2.77
2017-10-27 22:11:11,317 [salt.state       ][INFO    ][23726] Running state [virtng.init] at time 22:11:11.316649
2017-10-27 22:11:11,317 [salt.state       ][INFO    ][23726] Executing state module.run for virtng.init
2017-10-27 22:11:11,347 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command ['git', '--version'] in directory '/root'
2017-10-27 22:11:11,451 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,470 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,470 [salt.state       ][INFO    ][23726] Completed state [virtng.init] at time 22:11:11.469805 duration_in_ms=153.156
2017-10-27 22:11:11,470 [salt.state       ][INFO    ][23726] Running state [virt.set_autostart] at time 22:11:11.470033
2017-10-27 22:11:11,470 [salt.state       ][INFO    ][23726] Executing state module.run for virt.set_autostart
2017-10-27 22:11:11,472 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,489 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,489 [salt.state       ][INFO    ][23726] Completed state [virt.set_autostart] at time 22:11:11.488867 duration_in_ms=18.833
2017-10-27 22:11:11,489 [salt.state       ][INFO    ][23726] Running state [virtng.init] at time 22:11:11.489101
2017-10-27 22:11:11,489 [salt.state       ][INFO    ][23726] Executing state module.run for virtng.init
2017-10-27 22:11:11,491 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,506 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,507 [salt.state       ][INFO    ][23726] Completed state [virtng.init] at time 22:11:11.506593 duration_in_ms=17.492
2017-10-27 22:11:11,507 [salt.state       ][INFO    ][23726] Running state [virt.set_autostart] at time 22:11:11.506805
2017-10-27 22:11:11,507 [salt.state       ][INFO    ][23726] Executing state module.run for virt.set_autostart
2017-10-27 22:11:11,509 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,525 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,525 [salt.state       ][INFO    ][23726] Completed state [virt.set_autostart] at time 22:11:11.524803 duration_in_ms=17.998
2017-10-27 22:11:11,525 [salt.state       ][INFO    ][23726] Running state [virtng.init] at time 22:11:11.525023
2017-10-27 22:11:11,525 [salt.state       ][INFO    ][23726] Executing state module.run for virtng.init
2017-10-27 22:11:11,527 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,540 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,541 [salt.state       ][INFO    ][23726] Completed state [virtng.init] at time 22:11:11.540592 duration_in_ms=15.569
2017-10-27 22:11:11,541 [salt.state       ][INFO    ][23726] Running state [virt.set_autostart] at time 22:11:11.540795
2017-10-27 22:11:11,541 [salt.state       ][INFO    ][23726] Executing state module.run for virt.set_autostart
2017-10-27 22:11:11,543 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,555 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,556 [salt.state       ][INFO    ][23726] Completed state [virt.set_autostart] at time 22:11:11.555467 duration_in_ms=14.672
2017-10-27 22:11:11,556 [salt.state       ][INFO    ][23726] Running state [virtng.init] at time 22:11:11.555680
2017-10-27 22:11:11,556 [salt.state       ][INFO    ][23726] Executing state module.run for virtng.init
2017-10-27 22:11:11,558 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,572 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,572 [salt.state       ][INFO    ][23726] Completed state [virtng.init] at time 22:11:11.572176 duration_in_ms=16.495
2017-10-27 22:11:11,572 [salt.state       ][INFO    ][23726] Running state [virt.set_autostart] at time 22:11:11.572379
2017-10-27 22:11:11,573 [salt.state       ][INFO    ][23726] Executing state module.run for virt.set_autostart
2017-10-27 22:11:11,574 [salt.loaded.int.module.cmdmod][INFO    ][23726] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:11,588 [salt.state       ][INFO    ][23726] unless execution succeeded
2017-10-27 22:11:11,588 [salt.state       ][INFO    ][23726] Completed state [virt.set_autostart] at time 22:11:11.588461 duration_in_ms=16.081
2017-10-27 22:11:11,591 [salt.minion      ][INFO    ][23726] Returning information for job: 20171027221106498839
2017-10-27 22:11:58,480 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171027221158485288
2017-10-27 22:11:58,497 [salt.minion      ][INFO    ][23824] Starting a new job with PID 23824
2017-10-27 22:12:01,770 [salt.state       ][INFO    ][23824] Loading fresh modules for state activity
2017-10-27 22:12:01,789 [salt.state       ][ERROR   ][23824] No contents found in top file
2017-10-27 22:12:01,789 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-10-27 22:12:01,792 [salt.utils.extmods][INFO    ][23824] Syncing beacons for environment 'base'
2017-10-27 22:12:01,792 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_beacons, for base)
2017-10-27 22:12:01,792 [salt.fileclient  ][INFO    ][23824] Caching directory '_beacons/' for environment 'base'
2017-10-27 22:12:01,812 [salt.utils.extmods][INFO    ][23824] Syncing modules for environment 'base'
2017-10-27 22:12:01,812 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_modules, for base)
2017-10-27 22:12:01,812 [salt.fileclient  ][INFO    ][23824] Caching directory '_modules/' for environment 'base'
2017-10-27 22:12:02,108 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-10-27 22:12:02,115 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-10-27 22:12:02,136 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-10-27 22:12:02,145 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-10-27 22:12:02,259 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-10-27 22:12:02,273 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-10-27 22:12:02,291 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-10-27 22:12:02,318 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-10-27 22:12:02,357 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-10-27 22:12:02,371 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-10-27 22:12:02,380 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-10-27 22:12:02,400 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-10-27 22:12:02,424 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-10-27 22:12:02,532 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-10-27 22:12:02,549 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-10-27 22:12:02,579 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-10-27 22:12:02,617 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-10-27 22:12:02,648 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-10-27 22:12:02,662 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-10-27 22:12:02,669 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-10-27 22:12:02,724 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-10-27 22:12:02,760 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-10-27 22:12:02,784 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-10-27 22:12:02,832 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-10-27 22:12:02,874 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-10-27 22:12:02,907 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-10-27 22:12:02,918 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-10-27 22:12:02,943 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-10-27 22:12:02,975 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-10-27 22:12:03,035 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-10-27 22:12:03,075 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-10-27 22:12:03,108 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-10-27 22:12:03,111 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-10-27 22:12:03,111 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-10-27 22:12:03,111 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-10-27 22:12:03,111 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-10-27 22:12:03,112 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-10-27 22:12:03,112 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-10-27 22:12:03,112 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-10-27 22:12:03,112 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-10-27 22:12:03,113 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-10-27 22:12:03,113 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-10-27 22:12:03,113 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-10-27 22:12:03,113 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-10-27 22:12:03,114 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-10-27 22:12:03,114 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-10-27 22:12:03,114 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-10-27 22:12:03,114 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-10-27 22:12:03,114 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-10-27 22:12:03,115 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-10-27 22:12:03,115 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-10-27 22:12:03,115 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-10-27 22:12:03,115 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-10-27 22:12:03,116 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-10-27 22:12:03,116 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-10-27 22:12:03,116 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-10-27 22:12:03,117 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-10-27 22:12:03,117 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-10-27 22:12:03,117 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-10-27 22:12:03,117 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-10-27 22:12:03,118 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-10-27 22:12:03,118 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-10-27 22:12:03,118 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-10-27 22:12:03,118 [salt.utils.extmods][INFO    ][23824] 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-10-27 22:12:03,118 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-10-27 22:12:03,119 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-10-27 22:12:03,123 [salt.utils.extmods][INFO    ][23824] Syncing states for environment 'base'
2017-10-27 22:12:03,123 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_states, for base)
2017-10-27 22:12:03,123 [salt.fileclient  ][INFO    ][23824] Caching directory '_states/' for environment 'base'
2017-10-27 22:12:03,163 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-10-27 22:12:03,189 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-10-27 22:12:03,204 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-10-27 22:12:03,222 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-10-27 22:12:03,239 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-10-27 22:12:03,277 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-10-27 22:12:03,306 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-10-27 22:12:03,329 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-10-27 22:12:03,344 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-10-27 22:12:03,389 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-10-27 22:12:03,398 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-10-27 22:12:03,415 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-10-27 22:12:03,461 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-10-27 22:12:03,476 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-10-27 22:12:03,497 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-10-27 22:12:03,505 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-10-27 22:12:03,578 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-10-27 22:12:03,588 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-10-27 22:12:03,674 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-10-27 22:12:03,684 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-10-27 22:12:03,695 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-10-27 22:12:03,762 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-10-27 22:12:03,775 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-10-27 22:12:03,863 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-10-27 22:12:03,875 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-10-27 22:12:03,906 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-10-27 22:12:03,933 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-10-27 22:12:03,978 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-10-27 22:12:04,008 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-10-27 22:12:04,011 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-10-27 22:12:04,012 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-10-27 22:12:04,012 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-10-27 22:12:04,012 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-10-27 22:12:04,012 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-10-27 22:12:04,013 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-10-27 22:12:04,013 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-10-27 22:12:04,013 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-10-27 22:12:04,013 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-10-27 22:12:04,014 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-10-27 22:12:04,014 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-10-27 22:12:04,014 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-10-27 22:12:04,014 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-10-27 22:12:04,015 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-10-27 22:12:04,015 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-10-27 22:12:04,015 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-10-27 22:12:04,015 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-10-27 22:12:04,016 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-10-27 22:12:04,016 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-10-27 22:12:04,016 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-10-27 22:12:04,016 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-10-27 22:12:04,016 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-10-27 22:12:04,017 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-10-27 22:12:04,017 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-10-27 22:12:04,017 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-10-27 22:12:04,017 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-10-27 22:12:04,017 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-10-27 22:12:04,018 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-10-27 22:12:04,018 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-10-27 22:12:04,018 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-10-27 22:12:04,021 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-10-27 22:12:04,024 [salt.utils.extmods][INFO    ][23824] Syncing sdb for environment 'base'
2017-10-27 22:12:04,024 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_sdb, for base)
2017-10-27 22:12:04,024 [salt.fileclient  ][INFO    ][23824] Caching directory '_sdb/' for environment 'base'
2017-10-27 22:12:04,054 [salt.utils.extmods][INFO    ][23824] Syncing grains for environment 'base'
2017-10-27 22:12:04,054 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_grains, for base)
2017-10-27 22:12:04,054 [salt.fileclient  ][INFO    ][23824] Caching directory '_grains/' for environment 'base'
2017-10-27 22:12:04,089 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-10-27 22:12:04,119 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-10-27 22:12:04,140 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-10-27 22:12:04,155 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-10-27 22:12:04,166 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-10-27 22:12:04,176 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-10-27 22:12:04,187 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-10-27 22:12:04,205 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-10-27 22:12:04,214 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-10-27 22:12:04,227 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-10-27 22:12:04,236 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-10-27 22:12:04,243 [salt.fileclient  ][INFO    ][23824] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-10-27 22:12:04,246 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-10-27 22:12:04,246 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-10-27 22:12:04,246 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-10-27 22:12:04,246 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-10-27 22:12:04,247 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-10-27 22:12:04,247 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-10-27 22:12:04,247 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-10-27 22:12:04,247 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-10-27 22:12:04,247 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-10-27 22:12:04,248 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-10-27 22:12:04,248 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-10-27 22:12:04,248 [salt.utils.extmods][INFO    ][23824] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-10-27 22:12:04,249 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-10-27 22:12:04,252 [salt.utils.extmods][INFO    ][23824] Syncing renderers for environment 'base'
2017-10-27 22:12:04,252 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_renderers, for base)
2017-10-27 22:12:04,252 [salt.fileclient  ][INFO    ][23824] Caching directory '_renderers/' for environment 'base'
2017-10-27 22:12:04,271 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-10-27 22:12:04,274 [salt.utils.extmods][INFO    ][23824] Syncing returners for environment 'base'
2017-10-27 22:12:04,274 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_returners, for base)
2017-10-27 22:12:04,274 [salt.fileclient  ][INFO    ][23824] Caching directory '_returners/' for environment 'base'
2017-10-27 22:12:04,298 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-10-27 22:12:04,300 [salt.utils.extmods][INFO    ][23824] Syncing output for environment 'base'
2017-10-27 22:12:04,301 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_output, for base)
2017-10-27 22:12:04,301 [salt.fileclient  ][INFO    ][23824] Caching directory '_output/' for environment 'base'
2017-10-27 22:12:04,321 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-10-27 22:12:04,324 [salt.utils.extmods][INFO    ][23824] Syncing utils for environment 'base'
2017-10-27 22:12:04,324 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_utils, for base)
2017-10-27 22:12:04,324 [salt.fileclient  ][INFO    ][23824] Caching directory '_utils/' for environment 'base'
2017-10-27 22:12:04,341 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-10-27 22:12:04,344 [salt.utils.extmods][INFO    ][23824] Syncing log_handlers for environment 'base'
2017-10-27 22:12:04,344 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_log_handlers, for base)
2017-10-27 22:12:04,344 [salt.fileclient  ][INFO    ][23824] Caching directory '_log_handlers/' for environment 'base'
2017-10-27 22:12:04,370 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-10-27 22:12:04,372 [salt.utils.extmods][INFO    ][23824] Syncing proxy for environment 'base'
2017-10-27 22:12:04,372 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_proxy, for base)
2017-10-27 22:12:04,372 [salt.fileclient  ][INFO    ][23824] Caching directory '_proxy/' for environment 'base'
2017-10-27 22:12:04,399 [salt.utils.extmods][INFO    ][23824] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-10-27 22:12:04,401 [salt.utils.extmods][INFO    ][23824] Syncing engines for environment 'base'
2017-10-27 22:12:04,401 [salt.utils.extmods][INFO    ][23824] Loading cache from salt://_engines, for base)
2017-10-27 22:12:04,401 [salt.fileclient  ][INFO    ][23824] Caching directory '_engines/' for environment 'base'
2017-10-27 22:12:04,440 [salt.minion      ][INFO    ][23824] Returning information for job: 20171027221158485288
2017-10-27 22:12:04,931 [salt.transport.ipc][ERROR   ][23366] Exception occurred while handling stream: [Errno 0] Success
2017-10-27 22:41:42,052 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command test.ping with jid 20171027224142042223
2017-10-27 22:41:42,070 [salt.minion      ][INFO    ][24417] Starting a new job with PID 24417
2017-10-27 22:41:42,095 [salt.minion      ][INFO    ][24417] Returning information for job: 20171027224142042223
2017-10-27 22:43:59,616 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027224359603213
2017-10-27 22:43:59,631 [salt.minion      ][INFO    ][24449] Starting a new job with PID 24449
2017-10-27 22:44:01,077 [salt.state       ][INFO    ][24449] Loading fresh modules for state activity
2017-10-27 22:44:01,103 [salt.fileclient  ][INFO    ][24449] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-27 22:44:01,121 [salt.fileclient  ][INFO    ][24449] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-27 22:44:01,145 [salt.fileclient  ][INFO    ][24449] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:44:01,441 [salt.state       ][INFO    ][24449] Running state [lsof] at time 22:44:01.440628
2017-10-27 22:44:01,441 [salt.state       ][INFO    ][24449] Executing state pkg.installed for lsof
2017-10-27 22:44:01,441 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:44:01,714 [salt.state       ][INFO    ][24449] Package lsof is already installed
2017-10-27 22:44:01,714 [salt.state       ][INFO    ][24449] Completed state [lsof] at time 22:44:01.714355 duration_in_ms=273.727
2017-10-27 22:44:01,715 [salt.state       ][INFO    ][24449] Running state [keepalived] at time 22:44:01.714549
2017-10-27 22:44:01,715 [salt.state       ][INFO    ][24449] Executing state pkg.installed for keepalived
2017-10-27 22:44:01,730 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-27 22:44:03,645 [salt.loaded.int.module.cmdmod][INFO    ][24449] 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-10-27 22:44:09,660 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027224409646352
2017-10-27 22:44:09,675 [salt.minion      ][INFO    ][25810] Starting a new job with PID 25810
2017-10-27 22:44:09,684 [salt.minion      ][INFO    ][25810] Returning information for job: 20171027224409646352
2017-10-27 22:44:11,385 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:44:11,408 [salt.state       ][INFO    ][24449] 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-10-27 22:44:11,416 [salt.state       ][INFO    ][24449] Loading fresh modules for state activity
2017-10-27 22:44:11,427 [salt.state       ][INFO    ][24449] Completed state [keepalived] at time 22:44:11.427091 duration_in_ms=9712.542
2017-10-27 22:44:11,429 [salt.state       ][INFO    ][24449] Running state [/etc/keepalived/keepalived.conf] at time 22:44:11.428730
2017-10-27 22:44:11,429 [salt.state       ][INFO    ][24449] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-27 22:44:11,455 [salt.fileclient  ][INFO    ][24449] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-27 22:44:11,486 [salt.fileclient  ][INFO    ][24449] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:44:11,491 [salt.state       ][INFO    ][24449] File changed:
New file
2017-10-27 22:44:11,492 [salt.state       ][INFO    ][24449] Completed state [/etc/keepalived/keepalived.conf] at time 22:44:11.491503 duration_in_ms=62.773
2017-10-27 22:44:11,545 [salt.state       ][INFO    ][24449] Running state [keepalived] at time 22:44:11.544515
2017-10-27 22:44:11,545 [salt.state       ][INFO    ][24449] Executing state service.running for keepalived
2017-10-27 22:44:11,546 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-27 22:44:11,555 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-27 22:44:11,561 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:44:11,568 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:44:11,576 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-10-27 22:44:11,639 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-27 22:44:11,647 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:44:11,653 [salt.loaded.int.module.cmdmod][INFO    ][24449] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:44:11,659 [salt.state       ][INFO    ][24449] {'keepalived': True}
2017-10-27 22:44:11,660 [salt.state       ][INFO    ][24449] Completed state [keepalived] at time 22:44:11.659609 duration_in_ms=115.094
2017-10-27 22:44:11,660 [salt.minion      ][INFO    ][24449] Returning information for job: 20171027224359603213
2017-10-27 22:45:35,356 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command pillar.get with jid 20171027224535346310
2017-10-27 22:45:35,374 [salt.minion      ][INFO    ][26077] Starting a new job with PID 26077
2017-10-27 22:45:35,378 [salt.minion      ][INFO    ][26077] Returning information for job: 20171027224535346310
2017-10-27 22:51:50,171 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027225150159896
2017-10-27 22:51:50,187 [salt.minion      ][INFO    ][26163] Starting a new job with PID 26163
2017-10-27 22:51:51,559 [salt.state       ][INFO    ][26163] Loading fresh modules for state activity
2017-10-27 22:51:51,580 [salt.fileclient  ][INFO    ][26163] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 22:51:51,605 [salt.fileclient  ][INFO    ][26163] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 22:51:51,886 [salt.state       ][INFO    ][26163] Running state [attr] at time 22:51:51.886383
2017-10-27 22:51:51,887 [salt.state       ][INFO    ][26163] Executing state pkg.installed for attr
2017-10-27 22:51:51,887 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:51:52,165 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-27 22:51:55,545 [salt.loaded.int.module.cmdmod][INFO    ][26163] 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-10-27 22:51:57,836 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:51:57,860 [salt.state       ][INFO    ][26163] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-10-27 22:51:57,868 [salt.state       ][INFO    ][26163] Loading fresh modules for state activity
2017-10-27 22:51:57,879 [salt.state       ][INFO    ][26163] Completed state [attr] at time 22:51:57.879442 duration_in_ms=5993.058
2017-10-27 22:51:57,885 [salt.state       ][INFO    ][26163] Running state [glusterfs-server] at time 22:51:57.884906
2017-10-27 22:51:57,885 [salt.state       ][INFO    ][26163] Executing state pkg.installed for glusterfs-server
2017-10-27 22:51:58,072 [salt.loaded.int.module.cmdmod][INFO    ][26163] 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-10-27 22:52:00,265 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027225200251000
2017-10-27 22:52:00,279 [salt.minion      ][INFO    ][27201] Starting a new job with PID 27201
2017-10-27 22:52:00,291 [salt.minion      ][INFO    ][27201] Returning information for job: 20171027225200251000
2017-10-27 22:52:10,462 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027225210449205
2017-10-27 22:52:10,477 [salt.minion      ][INFO    ][30011] Starting a new job with PID 30011
2017-10-27 22:52:10,487 [salt.minion      ][INFO    ][30011] Returning information for job: 20171027225210449205
2017-10-27 22:52:15,551 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:52:15,576 [salt.state       ][INFO    ][26163] 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-10-27 22:52:15,583 [salt.state       ][INFO    ][26163] Loading fresh modules for state activity
2017-10-27 22:52:15,595 [salt.state       ][INFO    ][26163] Completed state [glusterfs-server] at time 22:52:15.594703 duration_in_ms=17709.795
2017-10-27 22:52:15,599 [salt.state       ][INFO    ][26163] Running state [bc] at time 22:52:15.599092
2017-10-27 22:52:15,599 [salt.state       ][INFO    ][26163] Executing state pkg.installed for bc
2017-10-27 22:52:15,843 [salt.loaded.int.module.cmdmod][INFO    ][26163] 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-10-27 22:52:18,666 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:52:18,693 [salt.state       ][INFO    ][26163] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-10-27 22:52:18,702 [salt.state       ][INFO    ][26163] Loading fresh modules for state activity
2017-10-27 22:52:18,714 [salt.state       ][INFO    ][26163] Completed state [bc] at time 22:52:18.714220 duration_in_ms=3115.127
2017-10-27 22:52:18,774 [salt.state       ][INFO    ][26163] Running state [glusterfs-server] at time 22:52:18.773831
2017-10-27 22:52:18,774 [salt.state       ][INFO    ][26163] Executing state service.running for glusterfs-server
2017-10-27 22:52:18,775 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 22:52:18,784 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:52:18,792 [salt.loaded.int.module.cmdmod][INFO    ][26163] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:52:18,800 [salt.state       ][INFO    ][26163] The service glusterfs-server is already running
2017-10-27 22:52:18,800 [salt.state       ][INFO    ][26163] Completed state [glusterfs-server] at time 22:52:18.800089 duration_in_ms=26.259
2017-10-27 22:52:18,802 [salt.state       ][INFO    ][26163] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:52:18.801506
2017-10-27 22:52:18,802 [salt.state       ][INFO    ][26163] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 22:52:18,812 [salt.state       ][INFO    ][26163] File changed:
New file
2017-10-27 22:52:18,812 [salt.state       ][INFO    ][26163] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:52:18.811719 duration_in_ms=10.213
2017-10-27 22:52:18,812 [salt.state       ][INFO    ][26163] Running state [/srv/glusterfs/glance] at time 22:52:18.811896
2017-10-27 22:52:18,812 [salt.state       ][INFO    ][26163] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 22:52:18,813 [salt.state       ][INFO    ][26163] {'/srv/glusterfs/glance': 'New Dir'}
2017-10-27 22:52:18,813 [salt.state       ][INFO    ][26163] Completed state [/srv/glusterfs/glance] at time 22:52:18.812731 duration_in_ms=0.836
2017-10-27 22:52:18,813 [salt.state       ][INFO    ][26163] Running state [/srv/glusterfs/keystone-keys] at time 22:52:18.812889
2017-10-27 22:52:18,813 [salt.state       ][INFO    ][26163] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 22:52:18,813 [salt.state       ][INFO    ][26163] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-10-27 22:52:18,814 [salt.state       ][INFO    ][26163] Completed state [/srv/glusterfs/keystone-keys] at time 22:52:18.813558 duration_in_ms=0.668
2017-10-27 22:52:18,814 [salt.state       ][INFO    ][26163] Running state [/srv/glusterfs/nova_instances] at time 22:52:18.813705
2017-10-27 22:52:18,814 [salt.state       ][INFO    ][26163] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 22:52:18,814 [salt.state       ][INFO    ][26163] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-10-27 22:52:18,814 [salt.state       ][INFO    ][26163] Completed state [/srv/glusterfs/nova_instances] at time 22:52:18.814353 duration_in_ms=0.648
2017-10-27 22:52:18,815 [salt.state       ][INFO    ][26163] Running state [/srv/glusterfs/keystone-credential-keys] at time 22:52:18.814503
2017-10-27 22:52:18,815 [salt.state       ][INFO    ][26163] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 22:52:18,815 [salt.state       ][INFO    ][26163] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-10-27 22:52:18,815 [salt.state       ][INFO    ][26163] Completed state [/srv/glusterfs/keystone-credential-keys] at time 22:52:18.815154 duration_in_ms=0.651
2017-10-27 22:52:18,816 [salt.minion      ][INFO    ][26163] Returning information for job: 20171027225150159896
2017-10-27 22:52:19,901 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command test.ping with jid 20171027225219888051
2017-10-27 22:52:19,915 [salt.minion      ][INFO    ][30953] Starting a new job with PID 30953
2017-10-27 22:52:19,941 [salt.minion      ][INFO    ][30953] Returning information for job: 20171027225219888051
2017-10-27 22:52:20,088 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027225220075213
2017-10-27 22:52:20,101 [salt.minion      ][INFO    ][30958] Starting a new job with PID 30958
2017-10-27 22:52:21,654 [salt.state       ][INFO    ][30958] Loading fresh modules for state activity
2017-10-27 22:52:21,676 [salt.fileclient  ][INFO    ][30958] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-27 22:52:21,708 [salt.fileclient  ][INFO    ][30958] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 22:52:21,773 [salt.fileclient  ][INFO    ][30958] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 22:52:21,791 [salt.fileclient  ][INFO    ][30958] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 22:52:22,045 [salt.state       ][INFO    ][30958] Running state [attr] at time 22:52:22.044508
2017-10-27 22:52:22,045 [salt.state       ][INFO    ][30958] Executing state pkg.installed for attr
2017-10-27 22:52:22,045 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:52:22,327 [salt.state       ][INFO    ][30958] Package attr is already installed
2017-10-27 22:52:22,327 [salt.state       ][INFO    ][30958] Completed state [attr] at time 22:52:22.327434 duration_in_ms=282.925
2017-10-27 22:52:22,328 [salt.state       ][INFO    ][30958] Running state [glusterfs-server] at time 22:52:22.327635
2017-10-27 22:52:22,328 [salt.state       ][INFO    ][30958] Executing state pkg.installed for glusterfs-server
2017-10-27 22:52:22,330 [salt.state       ][INFO    ][30958] Package glusterfs-server is already installed
2017-10-27 22:52:22,330 [salt.state       ][INFO    ][30958] Completed state [glusterfs-server] at time 22:52:22.330412 duration_in_ms=2.777
2017-10-27 22:52:22,331 [salt.state       ][INFO    ][30958] Running state [bc] at time 22:52:22.330543
2017-10-27 22:52:22,331 [salt.state       ][INFO    ][30958] Executing state pkg.installed for bc
2017-10-27 22:52:22,333 [salt.state       ][INFO    ][30958] Package bc is already installed
2017-10-27 22:52:22,333 [salt.state       ][INFO    ][30958] Completed state [bc] at time 22:52:22.333211 duration_in_ms=2.667
2017-10-27 22:52:22,334 [salt.state       ][INFO    ][30958] Running state [glusterfs-server] at time 22:52:22.333983
2017-10-27 22:52:22,334 [salt.state       ][INFO    ][30958] Executing state service.running for glusterfs-server
2017-10-27 22:52:22,335 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 22:52:22,345 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-10-27 22:52:22,388 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 22:52:22,403 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:52:22,410 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:52:22,419 [salt.state       ][INFO    ][30958] The service glusterfs-server is already running
2017-10-27 22:52:22,419 [salt.state       ][INFO    ][30958] Completed state [glusterfs-server] at time 22:52:22.419185 duration_in_ms=85.202
2017-10-27 22:52:22,421 [salt.state       ][INFO    ][30958] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:52:22.420691
2017-10-27 22:52:22,421 [salt.state       ][INFO    ][30958] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 22:52:22,429 [salt.state       ][INFO    ][30958] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-27 22:52:22,430 [salt.state       ][INFO    ][30958] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:52:22.429584 duration_in_ms=8.892
2017-10-27 22:52:22,430 [salt.state       ][INFO    ][30958] Running state [/srv/glusterfs/glance] at time 22:52:22.429754
2017-10-27 22:52:22,430 [salt.state       ][INFO    ][30958] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 22:52:22,430 [salt.state       ][INFO    ][30958] Directory /srv/glusterfs/glance is in the correct state
2017-10-27 22:52:22,430 [salt.state       ][INFO    ][30958] Completed state [/srv/glusterfs/glance] at time 22:52:22.430444 duration_in_ms=0.69
2017-10-27 22:52:22,431 [salt.state       ][INFO    ][30958] Running state [/srv/glusterfs/keystone-keys] at time 22:52:22.430613
2017-10-27 22:52:22,431 [salt.state       ][INFO    ][30958] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 22:52:22,431 [salt.state       ][INFO    ][30958] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-27 22:52:22,431 [salt.state       ][INFO    ][30958] Completed state [/srv/glusterfs/keystone-keys] at time 22:52:22.431295 duration_in_ms=0.681
2017-10-27 22:52:22,431 [salt.state       ][INFO    ][30958] Running state [/srv/glusterfs/nova_instances] at time 22:52:22.431470
2017-10-27 22:52:22,432 [salt.state       ][INFO    ][30958] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 22:52:22,432 [salt.state       ][INFO    ][30958] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-27 22:52:22,432 [salt.state       ][INFO    ][30958] Completed state [/srv/glusterfs/nova_instances] at time 22:52:22.432128 duration_in_ms=0.657
2017-10-27 22:52:22,432 [salt.state       ][INFO    ][30958] Running state [/srv/glusterfs/keystone-credential-keys] at time 22:52:22.432292
2017-10-27 22:52:22,432 [salt.state       ][INFO    ][30958] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 22:52:22,433 [salt.state       ][INFO    ][30958] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-27 22:52:22,433 [salt.state       ][INFO    ][30958] Completed state [/srv/glusterfs/keystone-credential-keys] at time 22:52:22.432952 duration_in_ms=0.661
2017-10-27 22:52:22,479 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command ['git', '--version'] in directory '/root'
2017-10-27 22:52:22,568 [salt.state       ][INFO    ][30958] Running state [sleep 5] at time 22:52:22.568105
2017-10-27 22:52:22,568 [salt.state       ][INFO    ][30958] Executing state cmd.wait for sleep 5
2017-10-27 22:52:22,569 [salt.state       ][INFO    ][30958] No changes made for sleep 5
2017-10-27 22:52:22,569 [salt.state       ][INFO    ][30958] Completed state [sleep 5] at time 22:52:22.568627 duration_in_ms=0.523
2017-10-27 22:52:22,569 [salt.state       ][INFO    ][30958] Running state [10.167.4.141] at time 22:52:22.568994
2017-10-27 22:52:22,569 [salt.state       ][INFO    ][30958] Executing state glusterfs.peered for 10.167.4.141
2017-10-27 22:52:22,569 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:22,579 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:22,630 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:22,639 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,005 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,017 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,076 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,086 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,147 [salt.state       ][INFO    ][30958] {'new': {'10.167.4.141': []}, 'old': None}
2017-10-27 22:52:23,148 [salt.state       ][INFO    ][30958] Completed state [10.167.4.141] at time 22:52:23.147451 duration_in_ms=578.455
2017-10-27 22:52:23,150 [salt.state       ][INFO    ][30958] Running state [10.167.4.142] at time 22:52:23.149716
2017-10-27 22:52:23,150 [salt.state       ][INFO    ][30958] Executing state glusterfs.peered for 10.167.4.142
2017-10-27 22:52:23,150 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,161 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,288 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,298 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,352 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,362 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,416 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,426 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,476 [salt.state       ][INFO    ][30958] Peering with localhost is not needed
2017-10-27 22:52:23,477 [salt.state       ][INFO    ][30958] Completed state [10.167.4.142] at time 22:52:23.476549 duration_in_ms=326.832
2017-10-27 22:52:23,479 [salt.state       ][INFO    ][30958] Running state [10.167.4.143] at time 22:52:23.478826
2017-10-27 22:52:23,479 [salt.state       ][INFO    ][30958] Executing state glusterfs.peered for 10.167.4.143
2017-10-27 22:52:23,480 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,490 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,542 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,552 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,838 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,850 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,922 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:23,933 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:23,994 [salt.state       ][INFO    ][30958] {'new': {'10.167.4.141': [], '10.167.4.143': []}, 'old': {'10.167.4.141': []}}
2017-10-27 22:52:23,994 [salt.state       ][INFO    ][30958] Completed state [10.167.4.143] at time 22:52:23.993930 duration_in_ms=515.102
2017-10-27 22:52:23,995 [salt.state       ][INFO    ][30958] Running state [glance] at time 22:52:23.994741
2017-10-27 22:52:23,995 [salt.state       ][INFO    ][30958] Executing state glusterfs.created for glance
2017-10-27 22:52:23,995 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:24,006 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:24,157 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:24,167 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:25,008 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:25,020 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:26,104 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:26,117 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:26,170 [salt.state       ][INFO    ][30958] {'new': ['glance'], 'old': []}
2017-10-27 22:52:26,171 [salt.state       ][INFO    ][30958] Completed state [glance] at time 22:52:26.170662 duration_in_ms=2175.919
2017-10-27 22:52:26,174 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:26.173538
2017-10-27 22:52:26,174 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:52:26,175 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:26,229 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:27,160 [salt.state       ][INFO    ][30958] {'pid': 31181, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:27,161 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:27.160537 duration_in_ms=986.998
2017-10-27 22:52:27,161 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 22:52:27.161091
2017-10-27 22:52:27,161 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-27 22:52:27,162 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 22:52:27,219 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-10-27 22:52:27,864 [salt.state       ][INFO    ][30958] {'pid': 31199, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:27,865 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 22:52:27.864454 duration_in_ms=703.363
2017-10-27 22:52:27,865 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 22:52:27.864995
2017-10-27 22:52:27,865 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-27 22:52:27,866 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 22:52:27,923 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-10-27 22:52:30,128 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027225230115730
2017-10-27 22:52:30,145 [salt.minion      ][INFO    ][31226] Starting a new job with PID 31226
2017-10-27 22:52:30,155 [salt.minion      ][INFO    ][31226] Returning information for job: 20171027225230115730
2017-10-27 22:52:30,651 [salt.state       ][INFO    ][30958] {'pid': 31216, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:30,652 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 22:52:30.651655 duration_in_ms=2786.66
2017-10-27 22:52:30,652 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:30.652154
2017-10-27 22:52:30,652 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:52:30,653 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:30,707 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:31,309 [salt.state       ][INFO    ][30958] {'pid': 31241, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:31,310 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:31.309521 duration_in_ms=657.366
2017-10-27 22:52:31,310 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 22:52:31.310102
2017-10-27 22:52:31,310 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-27 22:52:31,311 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 22:52:31,370 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-27 22:52:32,017 [salt.state       ][INFO    ][30958] {'pid': 31260, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:32,018 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 22:52:32.017448 duration_in_ms=707.346
2017-10-27 22:52:32,019 [salt.state       ][INFO    ][30958] Running state [glance] at time 22:52:32.018480
2017-10-27 22:52:32,019 [salt.state       ][INFO    ][30958] Executing state glusterfs.started for glance
2017-10-27 22:52:32,019 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:32,032 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:32,084 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:32,094 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:32,152 [salt.state       ][INFO    ][30958] Volume glance is already started
2017-10-27 22:52:32,152 [salt.state       ][INFO    ][30958] Completed state [glance] at time 22:52:32.151769 duration_in_ms=133.288
2017-10-27 22:52:32,153 [salt.state       ][INFO    ][30958] Running state [keystone-keys] at time 22:52:32.152614
2017-10-27 22:52:32,153 [salt.state       ][INFO    ][30958] Executing state glusterfs.created for keystone-keys
2017-10-27 22:52:32,153 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:32,164 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:32,213 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:32,224 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:32,945 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:32,957 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:35,751 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:35,764 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:35,818 [salt.state       ][INFO    ][30958] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-10-27 22:52:35,818 [salt.state       ][INFO    ][30958] Completed state [keystone-keys] at time 22:52:35.817788 duration_in_ms=3665.172
2017-10-27 22:52:35,826 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 22:52:35.825464
2017-10-27 22:52:35,827 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-27 22:52:35,828 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 22:52:35,880 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-10-27 22:52:36,518 [salt.state       ][INFO    ][30958] {'pid': 31370, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:36,518 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 22:52:36.518414 duration_in_ms=692.959
2017-10-27 22:52:36,519 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:36.518998
2017-10-27 22:52:36,519 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:52:36,520 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:36,577 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:37,203 [salt.state       ][INFO    ][30958] {'pid': 31387, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:37,203 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:37.202884 duration_in_ms=683.885
2017-10-27 22:52:37,204 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:37.203600
2017-10-27 22:52:37,204 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:52:37,205 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:37,261 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:37,922 [salt.state       ][INFO    ][30958] {'pid': 31405, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:37,923 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:37.922721 duration_in_ms=719.121
2017-10-27 22:52:37,923 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 22:52:37.923361
2017-10-27 22:52:37,924 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-27 22:52:37,924 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 22:52:37,978 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-10-27 22:52:40,270 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027225240257153
2017-10-27 22:52:40,282 [salt.minion      ][INFO    ][31435] Starting a new job with PID 31435
2017-10-27 22:52:40,294 [salt.minion      ][INFO    ][31435] Returning information for job: 20171027225240257153
2017-10-27 22:52:40,611 [salt.state       ][INFO    ][30958] {'pid': 31422, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:40,611 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 22:52:40.611034 duration_in_ms=2687.673
2017-10-27 22:52:40,612 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 22:52:40.611611
2017-10-27 22:52:40,612 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 22:52:40,613 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 22:52:40,670 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-27 22:52:41,268 [salt.state       ][INFO    ][30958] {'pid': 31447, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:41,269 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 22:52:41.268702 duration_in_ms=657.089
2017-10-27 22:52:41,270 [salt.state       ][INFO    ][30958] Running state [keystone-keys] at time 22:52:41.269757
2017-10-27 22:52:41,270 [salt.state       ][INFO    ][30958] Executing state glusterfs.started for keystone-keys
2017-10-27 22:52:41,271 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:41,283 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:41,335 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:41,346 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:41,405 [salt.state       ][INFO    ][30958] Volume keystone-keys is already started
2017-10-27 22:52:41,405 [salt.state       ][INFO    ][30958] Completed state [keystone-keys] at time 22:52:41.405069 duration_in_ms=135.312
2017-10-27 22:52:41,407 [salt.state       ][INFO    ][30958] Running state [nova_instances] at time 22:52:41.406802
2017-10-27 22:52:41,407 [salt.state       ][INFO    ][30958] Executing state glusterfs.created for nova_instances
2017-10-27 22:52:41,408 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:41,418 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:41,470 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:41,480 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:42,208 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:42,221 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:45,104 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:45,117 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:45,170 [salt.state       ][INFO    ][30958] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-10-27 22:52:45,170 [salt.state       ][INFO    ][30958] Completed state [nova_instances] at time 22:52:45.170416 duration_in_ms=3763.614
2017-10-27 22:52:45,174 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:45.173482
2017-10-27 22:52:45,174 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:52:45,174 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:45,226 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:45,863 [salt.state       ][INFO    ][30958] {'pid': 31559, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:45,863 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:45.862887 duration_in_ms=689.404
2017-10-27 22:52:45,863 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 22:52:45.863454
2017-10-27 22:52:45,864 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-27 22:52:45,864 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-27 22:52:45,921 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'' in directory '/root'
2017-10-27 22:52:46,537 [salt.state       ][INFO    ][30958] {'pid': 31577, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:46,537 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 22:52:46.537340 duration_in_ms=673.886
2017-10-27 22:52:46,538 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 22:52:46.537916
2017-10-27 22:52:46,538 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-27 22:52:46,539 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-27 22:52:46,597 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'On'' in directory '/root'
2017-10-27 22:52:47,257 [salt.state       ][INFO    ][30958] {'pid': 31594, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:47,257 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 22:52:47.257212 duration_in_ms=719.295
2017-10-27 22:52:47,258 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 22:52:47.257902
2017-10-27 22:52:47,258 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-27 22:52:47,259 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-27 22:52:47,315 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'On'' in directory '/root'
2017-10-27 22:52:49,998 [salt.state       ][INFO    ][30958] {'pid': 31611, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:49,998 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 22:52:49.998166 duration_in_ms=2740.264
2017-10-27 22:52:49,999 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:49.998724
2017-10-27 22:52:49,999 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:52:49,1000 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:50,055 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:50,409 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027225250396240
2017-10-27 22:52:50,424 [salt.minion      ][INFO    ][31641] Starting a new job with PID 31641
2017-10-27 22:52:50,435 [salt.minion      ][INFO    ][31641] Returning information for job: 20171027225250396240
2017-10-27 22:52:50,667 [salt.state       ][INFO    ][30958] {'pid': 31631, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:50,668 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:50.667782 duration_in_ms=669.057
2017-10-27 22:52:50,669 [salt.state       ][INFO    ][30958] Running state [nova_instances] at time 22:52:50.668888
2017-10-27 22:52:50,669 [salt.state       ][INFO    ][30958] Executing state glusterfs.started for nova_instances
2017-10-27 22:52:50,670 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:50,682 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:50,735 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:50,746 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:50,801 [salt.state       ][INFO    ][30958] Volume nova_instances is already started
2017-10-27 22:52:50,801 [salt.state       ][INFO    ][30958] Completed state [nova_instances] at time 22:52:50.800744 duration_in_ms=131.854
2017-10-27 22:52:50,802 [salt.state       ][INFO    ][30958] Running state [keystone-credential-keys] at time 22:52:50.801703
2017-10-27 22:52:50,802 [salt.state       ][INFO    ][30958] Executing state glusterfs.created for keystone-credential-keys
2017-10-27 22:52:50,802 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:50,812 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:50,863 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:50,874 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:51,653 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:51,667 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:54,544 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:52:54,557 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:52:54,609 [salt.state       ][INFO    ][30958] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-10-27 22:52:54,609 [salt.state       ][INFO    ][30958] Completed state [keystone-credential-keys] at time 22:52:54.609126 duration_in_ms=3807.422
2017-10-27 22:52:54,613 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 22:52:54.612571
2017-10-27 22:52:54,613 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 22:52:54,613 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 22:52:54,666 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-27 22:52:55,316 [salt.state       ][INFO    ][30958] {'pid': 31754, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:55,316 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 22:52:55.316179 duration_in_ms=703.607
2017-10-27 22:52:55,317 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:55.316721
2017-10-27 22:52:55,317 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:52:55,318 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:55,373 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:56,019 [salt.state       ][INFO    ][30958] {'pid': 31771, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:56,019 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:52:56.018983 duration_in_ms=702.262
2017-10-27 22:52:56,020 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 22:52:56.019555
2017-10-27 22:52:56,020 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-27 22:52:56,020 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 22:52:56,078 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-10-27 22:52:58,832 [salt.state       ][INFO    ][30958] {'pid': 31790, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:58,832 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 22:52:58.831892 duration_in_ms=2812.336
2017-10-27 22:52:58,832 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:58.832407
2017-10-27 22:52:58,833 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:52:58,833 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:52:58,889 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-27 22:52:59,529 [salt.state       ][INFO    ][30958] {'pid': 31820, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:52:59,530 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:52:59.529751 duration_in_ms=697.343
2017-10-27 22:52:59,530 [salt.state       ][INFO    ][30958] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 22:52:59.530330
2017-10-27 22:52:59,531 [salt.state       ][INFO    ][30958] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-27 22:52:59,531 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 22:52:59,589 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-10-27 22:53:00,249 [salt.state       ][INFO    ][30958] {'pid': 31837, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-27 22:53:00,249 [salt.state       ][INFO    ][30958] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 22:53:00.249197 duration_in_ms=718.866
2017-10-27 22:53:00,250 [salt.state       ][INFO    ][30958] Running state [keystone-credential-keys] at time 22:53:00.250226
2017-10-27 22:53:00,251 [salt.state       ][INFO    ][30958] Executing state glusterfs.started for keystone-credential-keys
2017-10-27 22:53:00,251 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:00,263 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:00,318 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:00,328 [salt.loaded.int.module.cmdmod][INFO    ][30958] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:00,386 [salt.state       ][INFO    ][30958] Volume keystone-credential-keys is already started
2017-10-27 22:53:00,386 [salt.state       ][INFO    ][30958] Completed state [keystone-credential-keys] at time 22:53:00.385807 duration_in_ms=135.58
2017-10-27 22:53:00,388 [salt.minion      ][INFO    ][30958] Returning information for job: 20171027225220075213
2017-10-27 22:53:10,689 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command test.ping with jid 20171027225310680841
2017-10-27 22:53:10,704 [salt.minion      ][INFO    ][31868] Starting a new job with PID 31868
2017-10-27 22:53:10,731 [salt.minion      ][INFO    ][31868] Returning information for job: 20171027225310680841
2017-10-27 22:53:10,866 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command cmd.run with jid 20171027225310853148
2017-10-27 22:53:10,880 [salt.minion      ][INFO    ][31873] Starting a new job with PID 31873
2017-10-27 22:53:10,884 [salt.loaded.int.module.cmdmod][INFO    ][31873] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-27 22:53:10,994 [salt.minion      ][INFO    ][31873] Returning information for job: 20171027225310853148
2017-10-27 23:10:04,251 [salt.utils.schedule][INFO    ][23366] Running scheduled job: __mine_interval
2017-10-27 23:53:37,116 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command test.ping with jid 20171027235337112025
2017-10-27 23:53:37,134 [salt.minion      ][INFO    ][32624] Starting a new job with PID 32624
2017-10-27 23:53:37,166 [salt.minion      ][INFO    ][32624] Returning information for job: 20171027235337112025
2017-10-27 23:53:53,614 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027235353605449
2017-10-27 23:53:53,630 [salt.minion      ][INFO    ][32629] Starting a new job with PID 32629
2017-10-27 23:53:54,043 [salt.state       ][INFO    ][32629] Loading fresh modules for state activity
2017-10-27 23:53:54,068 [salt.fileclient  ][INFO    ][32629] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-27 23:53:54,086 [salt.fileclient  ][INFO    ][32629] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-27 23:53:54,110 [salt.fileclient  ][INFO    ][32629] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 23:53:54,382 [salt.state       ][INFO    ][32629] Running state [lsof] at time 23:53:54.381547
2017-10-27 23:53:54,382 [salt.state       ][INFO    ][32629] Executing state pkg.installed for lsof
2017-10-27 23:53:54,382 [salt.loaded.int.module.cmdmod][INFO    ][32629] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 23:53:54,656 [salt.state       ][INFO    ][32629] Package lsof is already installed
2017-10-27 23:53:54,656 [salt.state       ][INFO    ][32629] Completed state [lsof] at time 23:53:54.656026 duration_in_ms=274.479
2017-10-27 23:53:54,656 [salt.state       ][INFO    ][32629] Running state [keepalived] at time 23:53:54.656248
2017-10-27 23:53:54,656 [salt.state       ][INFO    ][32629] Executing state pkg.installed for keepalived
2017-10-27 23:53:54,659 [salt.state       ][INFO    ][32629] Package keepalived is already installed
2017-10-27 23:53:54,659 [salt.state       ][INFO    ][32629] Completed state [keepalived] at time 23:53:54.659122 duration_in_ms=2.874
2017-10-27 23:53:54,661 [salt.state       ][INFO    ][32629] Running state [/etc/keepalived/keepalived.conf] at time 23:53:54.660505
2017-10-27 23:53:54,661 [salt.state       ][INFO    ][32629] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-27 23:53:54,682 [salt.fileclient  ][INFO    ][32629] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-27 23:53:54,714 [salt.fileclient  ][INFO    ][32629] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 23:53:54,718 [salt.state       ][INFO    ][32629] File /etc/keepalived/keepalived.conf is in the correct state
2017-10-27 23:53:54,718 [salt.state       ][INFO    ][32629] Completed state [/etc/keepalived/keepalived.conf] at time 23:53:54.718073 duration_in_ms=57.567
2017-10-27 23:53:54,719 [salt.state       ][INFO    ][32629] Running state [keepalived] at time 23:53:54.718570
2017-10-27 23:53:54,719 [salt.state       ][INFO    ][32629] Executing state service.running for keepalived
2017-10-27 23:53:54,719 [salt.loaded.int.module.cmdmod][INFO    ][32629] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-27 23:53:54,735 [salt.loaded.int.module.cmdmod][INFO    ][32629] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-27 23:53:54,742 [salt.loaded.int.module.cmdmod][INFO    ][32629] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 23:53:54,748 [salt.state       ][INFO    ][32629] The service keepalived is already running
2017-10-27 23:53:54,748 [salt.state       ][INFO    ][32629] Completed state [keepalived] at time 23:53:54.748059 duration_in_ms=29.488
2017-10-27 23:53:54,749 [salt.minion      ][INFO    ][32629] Returning information for job: 20171027235353605449
2017-10-27 23:54:09,173 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command pillar.get with jid 20171027235409166751
2017-10-27 23:54:09,188 [salt.minion      ][INFO    ][32653] Starting a new job with PID 32653
2017-10-27 23:54:09,194 [salt.minion      ][INFO    ][32653] Returning information for job: 20171027235409166751
2017-10-27 23:54:46,123 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027235446114974
2017-10-27 23:54:46,138 [salt.minion      ][INFO    ][32669] Starting a new job with PID 32669
2017-10-27 23:54:46,589 [salt.state       ][INFO    ][32669] Loading fresh modules for state activity
2017-10-27 23:54:46,610 [salt.fileclient  ][INFO    ][32669] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 23:54:46,630 [salt.fileclient  ][INFO    ][32669] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 23:54:46,895 [salt.state       ][INFO    ][32669] Running state [attr] at time 23:54:46.895264
2017-10-27 23:54:46,896 [salt.state       ][INFO    ][32669] Executing state pkg.installed for attr
2017-10-27 23:54:46,896 [salt.loaded.int.module.cmdmod][INFO    ][32669] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 23:54:47,161 [salt.state       ][INFO    ][32669] Package attr is already installed
2017-10-27 23:54:47,162 [salt.state       ][INFO    ][32669] Completed state [attr] at time 23:54:47.161597 duration_in_ms=266.338
2017-10-27 23:54:47,162 [salt.state       ][INFO    ][32669] Running state [glusterfs-server] at time 23:54:47.161782
2017-10-27 23:54:47,162 [salt.state       ][INFO    ][32669] Executing state pkg.installed for glusterfs-server
2017-10-27 23:54:47,165 [salt.state       ][INFO    ][32669] Package glusterfs-server is already installed
2017-10-27 23:54:47,165 [salt.state       ][INFO    ][32669] Completed state [glusterfs-server] at time 23:54:47.164664 duration_in_ms=2.881
2017-10-27 23:54:47,165 [salt.state       ][INFO    ][32669] Running state [bc] at time 23:54:47.164800
2017-10-27 23:54:47,165 [salt.state       ][INFO    ][32669] Executing state pkg.installed for bc
2017-10-27 23:54:47,167 [salt.state       ][INFO    ][32669] Package bc is already installed
2017-10-27 23:54:47,168 [salt.state       ][INFO    ][32669] Completed state [bc] at time 23:54:47.167540 duration_in_ms=2.739
2017-10-27 23:54:47,168 [salt.state       ][INFO    ][32669] Running state [glusterfs-server] at time 23:54:47.168169
2017-10-27 23:54:47,168 [salt.state       ][INFO    ][32669] Executing state service.running for glusterfs-server
2017-10-27 23:54:47,169 [salt.loaded.int.module.cmdmod][INFO    ][32669] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 23:54:47,186 [salt.loaded.int.module.cmdmod][INFO    ][32669] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:47,193 [salt.loaded.int.module.cmdmod][INFO    ][32669] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:47,203 [salt.state       ][INFO    ][32669] The service glusterfs-server is already running
2017-10-27 23:54:47,203 [salt.state       ][INFO    ][32669] Completed state [glusterfs-server] at time 23:54:47.203021 duration_in_ms=34.85
2017-10-27 23:54:47,204 [salt.state       ][INFO    ][32669] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:47.204390
2017-10-27 23:54:47,205 [salt.state       ][INFO    ][32669] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 23:54:47,215 [salt.state       ][INFO    ][32669] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-27 23:54:47,215 [salt.state       ][INFO    ][32669] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:47.214702 duration_in_ms=10.311
2017-10-27 23:54:47,215 [salt.state       ][INFO    ][32669] Running state [/srv/glusterfs/glance] at time 23:54:47.214846
2017-10-27 23:54:47,215 [salt.state       ][INFO    ][32669] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 23:54:47,215 [salt.state       ][INFO    ][32669] Directory /srv/glusterfs/glance is in the correct state
2017-10-27 23:54:47,215 [salt.state       ][INFO    ][32669] Completed state [/srv/glusterfs/glance] at time 23:54:47.215465 duration_in_ms=0.618
2017-10-27 23:54:47,216 [salt.state       ][INFO    ][32669] Running state [/srv/glusterfs/keystone-keys] at time 23:54:47.215606
2017-10-27 23:54:47,216 [salt.state       ][INFO    ][32669] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 23:54:47,216 [salt.state       ][INFO    ][32669] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-27 23:54:47,216 [salt.state       ][INFO    ][32669] Completed state [/srv/glusterfs/keystone-keys] at time 23:54:47.216188 duration_in_ms=0.582
2017-10-27 23:54:47,216 [salt.state       ][INFO    ][32669] Running state [/srv/glusterfs/nova_instances] at time 23:54:47.216328
2017-10-27 23:54:47,216 [salt.state       ][INFO    ][32669] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 23:54:47,217 [salt.state       ][INFO    ][32669] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-27 23:54:47,217 [salt.state       ][INFO    ][32669] Completed state [/srv/glusterfs/nova_instances] at time 23:54:47.216905 duration_in_ms=0.577
2017-10-27 23:54:47,217 [salt.state       ][INFO    ][32669] Running state [/srv/glusterfs/keystone-credential-keys] at time 23:54:47.217042
2017-10-27 23:54:47,217 [salt.state       ][INFO    ][32669] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 23:54:47,218 [salt.state       ][INFO    ][32669] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-27 23:54:47,218 [salt.state       ][INFO    ][32669] Completed state [/srv/glusterfs/keystone-credential-keys] at time 23:54:47.217608 duration_in_ms=0.565
2017-10-27 23:54:47,218 [salt.minion      ][INFO    ][32669] Returning information for job: 20171027235446114974
2017-10-27 23:54:48,445 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command test.ping with jid 20171027235448432393
2017-10-27 23:54:48,461 [salt.minion      ][INFO    ][32692] Starting a new job with PID 32692
2017-10-27 23:54:48,488 [salt.minion      ][INFO    ][32692] Returning information for job: 20171027235448432393
2017-10-27 23:54:48,620 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command state.sls with jid 20171027235448611752
2017-10-27 23:54:48,634 [salt.minion      ][INFO    ][32697] Starting a new job with PID 32697
2017-10-27 23:54:50,138 [salt.state       ][INFO    ][32697] Loading fresh modules for state activity
2017-10-27 23:54:50,160 [salt.fileclient  ][INFO    ][32697] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-27 23:54:50,192 [salt.fileclient  ][INFO    ][32697] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 23:54:50,256 [salt.fileclient  ][INFO    ][32697] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 23:54:50,275 [salt.fileclient  ][INFO    ][32697] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 23:54:50,548 [salt.state       ][INFO    ][32697] Running state [attr] at time 23:54:50.547722
2017-10-27 23:54:50,548 [salt.state       ][INFO    ][32697] Executing state pkg.installed for attr
2017-10-27 23:54:50,548 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 23:54:50,838 [salt.state       ][INFO    ][32697] Package attr is already installed
2017-10-27 23:54:50,838 [salt.state       ][INFO    ][32697] Completed state [attr] at time 23:54:50.838008 duration_in_ms=290.285
2017-10-27 23:54:50,838 [salt.state       ][INFO    ][32697] Running state [glusterfs-server] at time 23:54:50.838214
2017-10-27 23:54:50,838 [salt.state       ][INFO    ][32697] Executing state pkg.installed for glusterfs-server
2017-10-27 23:54:50,841 [salt.state       ][INFO    ][32697] Package glusterfs-server is already installed
2017-10-27 23:54:50,841 [salt.state       ][INFO    ][32697] Completed state [glusterfs-server] at time 23:54:50.841052 duration_in_ms=2.838
2017-10-27 23:54:50,841 [salt.state       ][INFO    ][32697] Running state [bc] at time 23:54:50.841192
2017-10-27 23:54:50,841 [salt.state       ][INFO    ][32697] Executing state pkg.installed for bc
2017-10-27 23:54:50,844 [salt.state       ][INFO    ][32697] Package bc is already installed
2017-10-27 23:54:50,844 [salt.state       ][INFO    ][32697] Completed state [bc] at time 23:54:50.843924 duration_in_ms=2.731
2017-10-27 23:54:50,845 [salt.state       ][INFO    ][32697] Running state [glusterfs-server] at time 23:54:50.844732
2017-10-27 23:54:50,845 [salt.state       ][INFO    ][32697] Executing state service.running for glusterfs-server
2017-10-27 23:54:50,845 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 23:54:50,863 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:50,870 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:50,880 [salt.state       ][INFO    ][32697] The service glusterfs-server is already running
2017-10-27 23:54:50,880 [salt.state       ][INFO    ][32697] Completed state [glusterfs-server] at time 23:54:50.880156 duration_in_ms=35.423
2017-10-27 23:54:50,881 [salt.state       ][INFO    ][32697] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:50.881447
2017-10-27 23:54:50,882 [salt.state       ][INFO    ][32697] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 23:54:50,892 [salt.state       ][INFO    ][32697] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-27 23:54:50,892 [salt.state       ][INFO    ][32697] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:50.892459 duration_in_ms=11.012
2017-10-27 23:54:50,893 [salt.state       ][INFO    ][32697] Running state [/srv/glusterfs/glance] at time 23:54:50.892609
2017-10-27 23:54:50,893 [salt.state       ][INFO    ][32697] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 23:54:50,893 [salt.state       ][INFO    ][32697] Directory /srv/glusterfs/glance is in the correct state
2017-10-27 23:54:50,893 [salt.state       ][INFO    ][32697] Completed state [/srv/glusterfs/glance] at time 23:54:50.893234 duration_in_ms=0.624
2017-10-27 23:54:50,893 [salt.state       ][INFO    ][32697] Running state [/srv/glusterfs/keystone-keys] at time 23:54:50.893374
2017-10-27 23:54:50,894 [salt.state       ][INFO    ][32697] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 23:54:50,894 [salt.state       ][INFO    ][32697] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-27 23:54:50,894 [salt.state       ][INFO    ][32697] Completed state [/srv/glusterfs/keystone-keys] at time 23:54:50.893958 duration_in_ms=0.585
2017-10-27 23:54:50,894 [salt.state       ][INFO    ][32697] Running state [/srv/glusterfs/nova_instances] at time 23:54:50.894098
2017-10-27 23:54:50,894 [salt.state       ][INFO    ][32697] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 23:54:50,895 [salt.state       ][INFO    ][32697] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-27 23:54:50,895 [salt.state       ][INFO    ][32697] Completed state [/srv/glusterfs/nova_instances] at time 23:54:50.894708 duration_in_ms=0.609
2017-10-27 23:54:50,895 [salt.state       ][INFO    ][32697] Running state [/srv/glusterfs/keystone-credential-keys] at time 23:54:50.894849
2017-10-27 23:54:50,895 [salt.state       ][INFO    ][32697] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 23:54:50,895 [salt.state       ][INFO    ][32697] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-27 23:54:50,895 [salt.state       ][INFO    ][32697] Completed state [/srv/glusterfs/keystone-credential-keys] at time 23:54:50.895456 duration_in_ms=0.607
2017-10-27 23:54:50,925 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command ['git', '--version'] in directory '/root'
2017-10-27 23:54:51,013 [salt.state       ][INFO    ][32697] Running state [sleep 5] at time 23:54:51.013171
2017-10-27 23:54:51,013 [salt.state       ][INFO    ][32697] Executing state cmd.wait for sleep 5
2017-10-27 23:54:51,014 [salt.state       ][INFO    ][32697] No changes made for sleep 5
2017-10-27 23:54:51,014 [salt.state       ][INFO    ][32697] Completed state [sleep 5] at time 23:54:51.013746 duration_in_ms=0.576
2017-10-27 23:54:51,014 [salt.state       ][INFO    ][32697] Running state [10.167.4.141] at time 23:54:51.014132
2017-10-27 23:54:51,014 [salt.state       ][INFO    ][32697] Executing state glusterfs.peered for 10.167.4.141
2017-10-27 23:54:51,015 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,025 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,075 [salt.state       ][INFO    ][32697] Host 10.167.4.141 already peered
2017-10-27 23:54:51,076 [salt.state       ][INFO    ][32697] Completed state [10.167.4.141] at time 23:54:51.075680 duration_in_ms=61.547
2017-10-27 23:54:51,078 [salt.state       ][INFO    ][32697] Running state [10.167.4.142] at time 23:54:51.077821
2017-10-27 23:54:51,078 [salt.state       ][INFO    ][32697] Executing state glusterfs.peered for 10.167.4.142
2017-10-27 23:54:51,078 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,087 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,136 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,145 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,193 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,202 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,251 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,259 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,311 [salt.state       ][INFO    ][32697] Peering with localhost is not needed
2017-10-27 23:54:51,311 [salt.state       ][INFO    ][32697] Completed state [10.167.4.142] at time 23:54:51.311307 duration_in_ms=233.485
2017-10-27 23:54:51,314 [salt.state       ][INFO    ][32697] Running state [10.167.4.143] at time 23:54:51.313678
2017-10-27 23:54:51,314 [salt.state       ][INFO    ][32697] Executing state glusterfs.peered for 10.167.4.143
2017-10-27 23:54:51,314 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,323 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,372 [salt.state       ][INFO    ][32697] Host 10.167.4.143 already peered
2017-10-27 23:54:51,372 [salt.state       ][INFO    ][32697] Completed state [10.167.4.143] at time 23:54:51.371961 duration_in_ms=58.282
2017-10-27 23:54:51,373 [salt.state       ][INFO    ][32697] Running state [glance] at time 23:54:51.372840
2017-10-27 23:54:51,373 [salt.state       ][INFO    ][32697] Executing state glusterfs.created for glance
2017-10-27 23:54:51,374 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,383 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,430 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,440 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,496 [salt.state       ][INFO    ][32697] Volume glance already exists and is started.
2017-10-27 23:54:51,497 [salt.state       ][INFO    ][32697] Completed state [glance] at time 23:54:51.496511 duration_in_ms=123.67
2017-10-27 23:54:51,499 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:51.499089
2017-10-27 23:54:51,499 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:51,500 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:51,551 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:51,551 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:51.550726 duration_in_ms=51.637
2017-10-27 23:54:51,551 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 23:54:51.551185
2017-10-27 23:54:51,551 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-27 23:54:51,552 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 23:54:51,605 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:51,605 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 23:54:51.605183 duration_in_ms=53.997
2017-10-27 23:54:51,606 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 23:54:51.605673
2017-10-27 23:54:51,606 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-27 23:54:51,606 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 23:54:51,655 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:51,656 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 23:54:51.655613 duration_in_ms=49.939
2017-10-27 23:54:51,656 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:51.656080
2017-10-27 23:54:51,656 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:51,657 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:51,708 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:51,708 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:51.708376 duration_in_ms=52.296
2017-10-27 23:54:51,709 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 23:54:51.708811
2017-10-27 23:54:51,709 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-27 23:54:51,710 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 23:54:51,757 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:51,757 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 23:54:51.757070 duration_in_ms=48.259
2017-10-27 23:54:51,758 [salt.state       ][INFO    ][32697] Running state [glance] at time 23:54:51.757975
2017-10-27 23:54:51,758 [salt.state       ][INFO    ][32697] Executing state glusterfs.started for glance
2017-10-27 23:54:51,759 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,767 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,816 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,824 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,876 [salt.state       ][INFO    ][32697] Volume glance is already started
2017-10-27 23:54:51,877 [salt.state       ][INFO    ][32697] Completed state [glance] at time 23:54:51.876640 duration_in_ms=118.663
2017-10-27 23:54:51,877 [salt.state       ][INFO    ][32697] Running state [keystone-keys] at time 23:54:51.877439
2017-10-27 23:54:51,878 [salt.state       ][INFO    ][32697] Executing state glusterfs.created for keystone-keys
2017-10-27 23:54:51,878 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,887 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,935 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:51,943 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:51,994 [salt.state       ][INFO    ][32697] Volume keystone-keys already exists and is started.
2017-10-27 23:54:51,994 [salt.state       ][INFO    ][32697] Completed state [keystone-keys] at time 23:54:51.994097 duration_in_ms=116.657
2017-10-27 23:54:51,997 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 23:54:51.996866
2017-10-27 23:54:51,997 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-27 23:54:51,998 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 23:54:52,048 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,048 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 23:54:52.048406 duration_in_ms=51.54
2017-10-27 23:54:52,049 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:52.048861
2017-10-27 23:54:52,049 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:52,050 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:52,096 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,097 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:52.096523 duration_in_ms=47.661
2017-10-27 23:54:52,097 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:52.096968
2017-10-27 23:54:52,097 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:52,098 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:52,147 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,148 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:52.147528 duration_in_ms=50.559
2017-10-27 23:54:52,148 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 23:54:52.147997
2017-10-27 23:54:52,148 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-27 23:54:52,149 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 23:54:52,198 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,198 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 23:54:52.197867 duration_in_ms=49.87
2017-10-27 23:54:52,198 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:52.198286
2017-10-27 23:54:52,199 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 23:54:52,199 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 23:54:52,247 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,247 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:52.246823 duration_in_ms=48.537
2017-10-27 23:54:52,248 [salt.state       ][INFO    ][32697] Running state [keystone-keys] at time 23:54:52.247800
2017-10-27 23:54:52,248 [salt.state       ][INFO    ][32697] Executing state glusterfs.started for keystone-keys
2017-10-27 23:54:52,248 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,256 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,303 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,312 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,365 [salt.state       ][INFO    ][32697] Volume keystone-keys is already started
2017-10-27 23:54:52,365 [salt.state       ][INFO    ][32697] Completed state [keystone-keys] at time 23:54:52.365153 duration_in_ms=117.352
2017-10-27 23:54:52,366 [salt.state       ][INFO    ][32697] Running state [nova_instances] at time 23:54:52.366128
2017-10-27 23:54:52,366 [salt.state       ][INFO    ][32697] Executing state glusterfs.created for nova_instances
2017-10-27 23:54:52,367 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,375 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,422 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,430 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,480 [salt.state       ][INFO    ][32697] Volume nova_instances already exists and is started.
2017-10-27 23:54:52,480 [salt.state       ][INFO    ][32697] Completed state [nova_instances] at time 23:54:52.480076 duration_in_ms=113.947
2017-10-27 23:54:52,483 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:52.482792
2017-10-27 23:54:52,483 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:52,483 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:52,531 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,532 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:52.531632 duration_in_ms=48.84
2017-10-27 23:54:52,532 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 23:54:52.532079
2017-10-27 23:54:52,532 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-27 23:54:52,533 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-27 23:54:52,580 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,581 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 23:54:52.580505 duration_in_ms=48.425
2017-10-27 23:54:52,581 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 23:54:52.580956
2017-10-27 23:54:52,581 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-27 23:54:52,582 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-27 23:54:52,634 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,638 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 23:54:52.637398 duration_in_ms=56.44
2017-10-27 23:54:52,638 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 23:54:52.637935
2017-10-27 23:54:52,638 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-27 23:54:52,639 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-27 23:54:52,687 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,687 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 23:54:52.687328 duration_in_ms=49.393
2017-10-27 23:54:52,688 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:52.687803
2017-10-27 23:54:52,688 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:52,688 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:52,739 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:52,740 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:52.739492 duration_in_ms=51.688
2017-10-27 23:54:52,740 [salt.state       ][INFO    ][32697] Running state [nova_instances] at time 23:54:52.740422
2017-10-27 23:54:52,741 [salt.state       ][INFO    ][32697] Executing state glusterfs.started for nova_instances
2017-10-27 23:54:52,741 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,750 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,799 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,808 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,860 [salt.state       ][INFO    ][32697] Volume nova_instances is already started
2017-10-27 23:54:52,861 [salt.state       ][INFO    ][32697] Completed state [nova_instances] at time 23:54:52.860492 duration_in_ms=120.069
2017-10-27 23:54:52,861 [salt.state       ][INFO    ][32697] Running state [keystone-credential-keys] at time 23:54:52.861417
2017-10-27 23:54:52,862 [salt.state       ][INFO    ][32697] Executing state glusterfs.created for keystone-credential-keys
2017-10-27 23:54:52,862 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,872 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,920 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:52,928 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:52,983 [salt.state       ][INFO    ][32697] Volume keystone-credential-keys already exists and is started.
2017-10-27 23:54:52,984 [salt.state       ][INFO    ][32697] Completed state [keystone-credential-keys] at time 23:54:52.984074 duration_in_ms=122.657
2017-10-27 23:54:52,988 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:52.987914
2017-10-27 23:54:52,988 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 23:54:52,989 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 23:54:53,039 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:53,039 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:53.039229 duration_in_ms=51.314
2017-10-27 23:54:53,040 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:53.039807
2017-10-27 23:54:53,040 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:53,041 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:53,095 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:53,095 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:53.095383 duration_in_ms=55.576
2017-10-27 23:54:53,096 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 23:54:53.095819
2017-10-27 23:54:53,096 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-27 23:54:53,096 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 23:54:53,147 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:53,147 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 23:54:53.147458 duration_in_ms=51.637
2017-10-27 23:54:53,148 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:53.147980
2017-10-27 23:54:53,148 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:53,149 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:53,197 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:53,198 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:53.197596 duration_in_ms=49.616
2017-10-27 23:54:53,198 [salt.state       ][INFO    ][32697] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 23:54:53.198051
2017-10-27 23:54:53,198 [salt.state       ][INFO    ][32697] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-27 23:54:53,199 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 23:54:53,247 [salt.state       ][INFO    ][32697] unless execution succeeded
2017-10-27 23:54:53,247 [salt.state       ][INFO    ][32697] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 23:54:53.246792 duration_in_ms=48.74
2017-10-27 23:54:53,248 [salt.state       ][INFO    ][32697] Running state [keystone-credential-keys] at time 23:54:53.247789
2017-10-27 23:54:53,248 [salt.state       ][INFO    ][32697] Executing state glusterfs.started for keystone-credential-keys
2017-10-27 23:54:53,249 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:53,257 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:53,307 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:53,316 [salt.loaded.int.module.cmdmod][INFO    ][32697] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:53,367 [salt.state       ][INFO    ][32697] Volume keystone-credential-keys is already started
2017-10-27 23:54:53,367 [salt.state       ][INFO    ][32697] Completed state [keystone-credential-keys] at time 23:54:53.366802 duration_in_ms=119.013
2017-10-27 23:54:53,369 [salt.minion      ][INFO    ][32697] Returning information for job: 20171027235448611752
2017-10-27 23:55:02,072 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command test.ping with jid 20171027235502063823
2017-10-27 23:55:02,087 [salt.minion      ][INFO    ][613] Starting a new job with PID 613
2017-10-27 23:55:02,113 [salt.minion      ][INFO    ][613] Returning information for job: 20171027235502063823
2017-10-27 23:55:02,245 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command cmd.run with jid 20171027235502236262
2017-10-27 23:55:02,259 [salt.minion      ][INFO    ][618] Starting a new job with PID 618
2017-10-27 23:55:02,265 [salt.loaded.int.module.cmdmod][INFO    ][618] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-27 23:55:02,370 [salt.minion      ][INFO    ][618] Returning information for job: 20171027235502236262
2017-10-28 00:10:04,252 [salt.utils.schedule][INFO    ][23366] Running scheduled job: __mine_interval
2017-10-28 00:23:54,762 [salt.minion      ][INFO    ][23366] User sudo_ubuntu Executing command cp.push_dir with jid 20171028002354758856
2017-10-28 00:23:54,783 [salt.minion      ][INFO    ][857] Starting a new job with PID 857
