2017-11-30 09:18:09,540 [salt.cli.daemons ][WARNING ][2391] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-30 09:19:03,081 [salt.state       ][ERROR   ][3698] No contents found in top file
2017-11-30 09:22:21,617 [salt.utils.parsers][WARNING ][2391] Minion received a SIGTERM. Exiting.
2017-11-30 09:24:35,034 [salt.cli.daemons ][WARNING ][3136] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-30 09:27:27,477 [py.warnings      ][WARNING ][14058] /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-11-30 09:28:59,153 [salt.loaded.int.module.cmdmod][ERROR   ][14058] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:28:59,154 [salt.loaded.int.module.cmdmod][ERROR   ][14058] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:06,898 [salt.loaded.int.module.cmdmod][ERROR   ][14058] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:06,898 [salt.loaded.int.module.cmdmod][ERROR   ][14058] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:16,763 [salt.loaded.int.module.cmdmod][ERROR   ][14058] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:16,764 [salt.loaded.int.module.cmdmod][ERROR   ][14058] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:26,135 [salt.loaded.int.module.cmdmod][ERROR   ][14058] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:26,136 [salt.loaded.int.module.cmdmod][ERROR   ][14058] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:33,120 [salt.loaded.int.module.cmdmod][ERROR   ][14058] 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-11-30 09:29:36,244 [salt.loaded.int.module.cmdmod][INFO    ][21184] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-30 09:29:36,258 [salt.loaded.int.module.cmdmod][INFO    ][21184] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-30 09:29:36,279 [salt.loaded.int.module.cmdmod][INFO    ][21184] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-30 09:29:36,294 [salt.utils.parsers][WARNING ][3136] Minion received a SIGTERM. Exiting.
2017-11-30 09:29:36,690 [salt.cli.daemons ][INFO    ][21259] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-ovs-dpdk-ha.local"
2017-11-30 09:29:37,379 [salt.minion      ][INFO    ][21259] Creating minion process manager
2017-11-30 09:29:37,380 [salt.cli.daemons ][WARNING ][21259] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-30 09:29:37,380 [salt.cli.daemons ][INFO    ][21259] The Salt Minion is starting up
2017-11-30 09:29:37,380 [salt.minion      ][INFO    ][21259] Minion is starting as user 'root'
2017-11-30 09:29:37,380 [salt.utils.event ][INFO    ][21259] Starting pull socket on /var/run/salt/minion/minion_event_0031115955_pull.ipc
2017-11-30 09:29:38,504 [salt.loaded.int.module.cmdmod][INFO    ][21259] Executing command ['date', '+%z'] in directory '/root'
2017-11-30 09:29:38,518 [salt.utils.schedule][INFO    ][21259] Updating job settings for scheduled job: __mine_interval
2017-11-30 09:29:38,521 [salt.minion      ][INFO    ][21259] Added mine.update to scheduler
2017-11-30 09:29:38,719 [salt.minion      ][INFO    ][21259] Minion is ready to receive requests!
2017-11-30 09:29:39,720 [salt.utils.schedule][INFO    ][21259] Running scheduled job: __mine_interval
2017-11-30 09:29:53,361 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171130092953363623
2017-11-30 09:29:53,385 [salt.minion      ][INFO    ][21364] Starting a new job with PID 21364
2017-11-30 09:29:56,013 [salt.state       ][INFO    ][21364] Loading fresh modules for state activity
2017-11-30 09:29:56,057 [salt.state       ][ERROR   ][21364] No contents found in top file
2017-11-30 09:29:56,070 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-30 09:29:56,076 [salt.utils.extmods][INFO    ][21364] Syncing beacons for environment 'base'
2017-11-30 09:29:56,076 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_beacons, for base)
2017-11-30 09:29:56,077 [salt.fileclient  ][INFO    ][21364] Caching directory '_beacons/' for environment 'base'
2017-11-30 09:29:56,128 [salt.utils.extmods][INFO    ][21364] Syncing modules for environment 'base'
2017-11-30 09:29:56,129 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_modules, for base)
2017-11-30 09:29:56,129 [salt.fileclient  ][INFO    ][21364] Caching directory '_modules/' for environment 'base'
2017-11-30 09:29:56,217 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-11-30 09:29:56,278 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-30 09:29:56,313 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-30 09:29:56,355 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-30 09:29:56,445 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-30 09:29:56,569 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-30 09:29:56,667 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-30 09:29:56,793 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-30 09:29:56,853 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-30 09:29:56,955 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-30 09:29:57,033 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-30 09:29:57,153 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-30 09:29:57,201 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-30 09:29:57,241 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-30 09:29:57,287 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-30 09:29:57,335 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-30 09:29:57,405 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-30 09:29:57,491 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-30 09:29:57,518 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-30 09:29:57,567 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-30 09:29:57,714 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-30 09:29:57,744 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-30 09:29:57,806 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-30 09:29:57,871 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-30 09:29:57,892 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-30 09:29:57,917 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-30 09:29:57,951 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-30 09:29:57,994 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-30 09:29:58,066 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-30 09:29:58,103 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-30 09:29:58,125 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-30 09:29:58,167 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-30 09:29:58,181 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-30 09:29:58,210 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-30 09:29:58,218 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-30 09:29:58,218 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-11-30 09:29:58,240 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-30 09:29:58,262 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-30 09:29:58,263 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-30 09:29:58,264 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-30 09:29:58,265 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-30 09:29:58,265 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-30 09:29:58,266 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-30 09:29:58,267 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-30 09:29:58,268 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-30 09:29:58,268 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-30 09:29:58,272 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-30 09:29:58,297 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-30 09:29:58,314 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-30 09:29:58,315 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-30 09:29:58,316 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-30 09:29:58,316 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-30 09:29:58,317 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-30 09:29:58,318 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-30 09:29:58,319 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-30 09:29:58,320 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-30 09:29:58,320 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-30 09:29:58,321 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-30 09:29:58,322 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-30 09:29:58,323 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-30 09:29:58,324 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-30 09:29:58,325 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-30 09:29:58,325 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-30 09:29:58,334 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-30 09:29:58,335 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-30 09:29:58,335 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-30 09:29:58,336 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-30 09:29:58,336 [salt.utils.extmods][INFO    ][21364] 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-11-30 09:29:58,337 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-30 09:29:58,338 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-30 09:29:58,349 [salt.utils.extmods][INFO    ][21364] Syncing states for environment 'base'
2017-11-30 09:29:58,349 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_states, for base)
2017-11-30 09:29:58,350 [salt.fileclient  ][INFO    ][21364] Caching directory '_states/' for environment 'base'
2017-11-30 09:29:58,408 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-11-30 09:29:58,463 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-30 09:29:58,512 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-30 09:29:58,548 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-30 09:29:58,617 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-30 09:29:58,684 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-30 09:29:58,762 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-30 09:29:58,793 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-30 09:29:58,845 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-30 09:29:58,877 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-11-30 09:29:58,947 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-30 09:29:58,991 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-30 09:29:59,025 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-30 09:29:59,063 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-11-30 09:29:59,080 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-30 09:29:59,123 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-30 09:29:59,138 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-30 09:29:59,184 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-30 09:29:59,219 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-30 09:29:59,248 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-30 09:29:59,402 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-30 09:29:59,429 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-30 09:29:59,477 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-30 09:29:59,501 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-30 09:29:59,549 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-30 09:29:59,610 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-30 09:29:59,637 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-30 09:29:59,674 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-30 09:29:59,715 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-30 09:29:59,731 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-30 09:29:59,755 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-30 09:29:59,813 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-30 09:29:59,821 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-11-30 09:29:59,822 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-30 09:29:59,822 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-30 09:29:59,823 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-30 09:29:59,823 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-30 09:29:59,824 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-30 09:29:59,825 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-30 09:29:59,825 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-30 09:29:59,826 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-30 09:29:59,826 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-11-30 09:29:59,827 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-30 09:29:59,827 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-30 09:29:59,828 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-30 09:29:59,828 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-11-30 09:29:59,829 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-30 09:29:59,829 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-30 09:29:59,830 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-30 09:29:59,830 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-30 09:29:59,831 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-30 09:29:59,831 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-30 09:29:59,831 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-30 09:29:59,832 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-30 09:29:59,832 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-30 09:29:59,833 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-30 09:29:59,833 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-30 09:29:59,834 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-30 09:29:59,834 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-30 09:29:59,835 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-30 09:29:59,836 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-30 09:29:59,836 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-30 09:29:59,837 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-30 09:29:59,837 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-30 09:29:59,838 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-30 09:29:59,843 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-30 09:29:59,849 [salt.utils.extmods][INFO    ][21364] Syncing sdb for environment 'base'
2017-11-30 09:29:59,849 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_sdb, for base)
2017-11-30 09:29:59,849 [salt.fileclient  ][INFO    ][21364] Caching directory '_sdb/' for environment 'base'
2017-11-30 09:29:59,900 [salt.utils.extmods][INFO    ][21364] Syncing grains for environment 'base'
2017-11-30 09:29:59,905 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_grains, for base)
2017-11-30 09:29:59,905 [salt.fileclient  ][INFO    ][21364] Caching directory '_grains/' for environment 'base'
2017-11-30 09:30:00,006 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-30 09:30:00,075 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-30 09:30:00,101 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-30 09:30:00,157 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-30 09:30:00,295 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-30 09:30:00,310 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-30 09:30:00,325 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-30 09:30:00,340 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-30 09:30:00,358 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-30 09:30:00,396 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-30 09:30:00,432 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-30 09:30:00,449 [salt.fileclient  ][INFO    ][21364] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-30 09:30:00,457 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-30 09:30:00,482 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-30 09:30:00,522 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-30 09:30:00,574 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-30 09:30:00,593 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-30 09:30:00,626 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-30 09:30:00,657 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-30 09:30:00,712 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-30 09:30:00,713 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-30 09:30:00,714 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-30 09:30:00,714 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-30 09:30:00,715 [salt.utils.extmods][INFO    ][21364] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-30 09:30:00,718 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-30 09:30:00,724 [salt.utils.extmods][INFO    ][21364] Syncing renderers for environment 'base'
2017-11-30 09:30:00,724 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_renderers, for base)
2017-11-30 09:30:00,739 [salt.fileclient  ][INFO    ][21364] Caching directory '_renderers/' for environment 'base'
2017-11-30 09:30:00,786 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-30 09:30:00,791 [salt.utils.extmods][INFO    ][21364] Syncing returners for environment 'base'
2017-11-30 09:30:00,791 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_returners, for base)
2017-11-30 09:30:00,792 [salt.fileclient  ][INFO    ][21364] Caching directory '_returners/' for environment 'base'
2017-11-30 09:30:00,848 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-30 09:30:00,853 [salt.utils.extmods][INFO    ][21364] Syncing output for environment 'base'
2017-11-30 09:30:00,853 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_output, for base)
2017-11-30 09:30:00,854 [salt.fileclient  ][INFO    ][21364] Caching directory '_output/' for environment 'base'
2017-11-30 09:30:00,901 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-30 09:30:00,906 [salt.utils.extmods][INFO    ][21364] Syncing utils for environment 'base'
2017-11-30 09:30:00,907 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_utils, for base)
2017-11-30 09:30:00,907 [salt.fileclient  ][INFO    ][21364] Caching directory '_utils/' for environment 'base'
2017-11-30 09:30:00,956 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-30 09:30:00,961 [salt.utils.extmods][INFO    ][21364] Syncing log_handlers for environment 'base'
2017-11-30 09:30:00,962 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_log_handlers, for base)
2017-11-30 09:30:00,962 [salt.fileclient  ][INFO    ][21364] Caching directory '_log_handlers/' for environment 'base'
2017-11-30 09:30:01,007 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-30 09:30:01,012 [salt.utils.extmods][INFO    ][21364] Syncing proxy for environment 'base'
2017-11-30 09:30:01,022 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_proxy, for base)
2017-11-30 09:30:01,022 [salt.fileclient  ][INFO    ][21364] Caching directory '_proxy/' for environment 'base'
2017-11-30 09:30:01,067 [salt.utils.extmods][INFO    ][21364] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-30 09:30:01,072 [salt.utils.extmods][INFO    ][21364] Syncing engines for environment 'base'
2017-11-30 09:30:01,072 [salt.utils.extmods][INFO    ][21364] Loading cache from salt://_engines, for base)
2017-11-30 09:30:01,072 [salt.fileclient  ][INFO    ][21364] Caching directory '_engines/' for environment 'base'
2017-11-30 09:30:01,122 [salt.minion      ][INFO    ][21364] Returning information for job: 20171130092953363623
2017-11-30 09:30:06,225 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130093006235271
2017-11-30 09:30:06,247 [salt.minion      ][INFO    ][21461] Starting a new job with PID 21461
2017-11-30 09:30:06,785 [salt.state       ][INFO    ][21461] Loading fresh modules for state activity
2017-11-30 09:30:06,833 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-30 09:30:06,864 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-30 09:30:06,992 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:07,076 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-30 09:30:07,100 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-30 09:30:07,145 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:07,250 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:07,292 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:07,363 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:07,449 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:07,467 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:07,490 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:07,507 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:07,538 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:07,570 [py.warnings      ][WARNING ][21461] /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-11-30 09:30:07,584 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:07,957 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:07,982 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:07,998 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:08,015 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:08,073 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:08,090 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:08,117 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:08,201 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:08,230 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:08,283 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:08,437 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-30 09:30:08,481 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:08,605 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:08,637 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:08,716 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:08,741 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:08,756 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:08,775 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:08,785 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:08,844 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:08,876 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:09,394 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:09,425 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:09,442 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:09,458 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:09,516 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:09,603 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:09,636 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:09,650 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:09,670 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:09,709 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:09,798 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-30 09:30:09,981 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:10,487 [salt.state       ][INFO    ][21461] Running state [salt-minion] at time 09:30:10.486723
2017-11-30 09:30:10,487 [salt.state       ][INFO    ][21461] Executing state pkg.installed for salt-minion
2017-11-30 09:30:10,487 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:30:10,877 [salt.state       ][INFO    ][21461] Package salt-minion is already installed
2017-11-30 09:30:10,877 [salt.state       ][INFO    ][21461] Completed state [salt-minion] at time 09:30:10.877358 duration_in_ms=390.636
2017-11-30 09:30:10,878 [salt.state       ][INFO    ][21461] Running state [salt_minion_dependency_packages] at time 09:30:10.877602
2017-11-30 09:30:10,878 [salt.state       ][INFO    ][21461] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-30 09:30:10,881 [salt.state       ][INFO    ][21461] All specified packages are already installed
2017-11-30 09:30:10,881 [salt.state       ][INFO    ][21461] Completed state [salt_minion_dependency_packages] at time 09:30:10.880927 duration_in_ms=3.325
2017-11-30 09:30:10,883 [salt.state       ][INFO    ][21461] Running state [/etc/salt/minion.d/minion.conf] at time 09:30:10.882522
2017-11-30 09:30:10,883 [salt.state       ][INFO    ][21461] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-30 09:30:10,908 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-30 09:30:10,957 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:11,002 [salt.fileclient  ][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:11,038 [salt.state       ][INFO    ][21461] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-30 09:30:11,038 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/minion.d/minion.conf] at time 09:30:11.037849 duration_in_ms=155.326
2017-11-30 09:30:11,038 [salt.state       ][INFO    ][21461] Running state [/etc/salt/minion.d/_virt.conf] at time 09:30:11.038131
2017-11-30 09:30:11,038 [salt.state       ][INFO    ][21461] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-30 09:30:11,173 [salt.state       ][INFO    ][21461] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-30 09:30:11,174 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:30:11.173730 duration_in_ms=135.598
2017-11-30 09:30:11,177 [salt.state       ][INFO    ][21461] Running state [salt-minion] at time 09:30:11.177404
2017-11-30 09:30:11,178 [salt.state       ][INFO    ][21461] Executing state service.running for salt-minion
2017-11-30 09:30:11,179 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-30 09:30:11,208 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:11,223 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:11,236 [salt.state       ][INFO    ][21461] The service salt-minion is already running
2017-11-30 09:30:11,236 [salt.state       ][INFO    ][21461] Completed state [salt-minion] at time 09:30:11.236261 duration_in_ms=58.857
2017-11-30 09:30:11,239 [salt.state       ][INFO    ][21461] Running state [/etc/salt/grains.d] at time 09:30:11.239122
2017-11-30 09:30:11,240 [salt.state       ][INFO    ][21461] Executing state file.directory for /etc/salt/grains.d
2017-11-30 09:30:11,241 [salt.state       ][INFO    ][21461] Directory /etc/salt/grains.d is in the correct state
2017-11-30 09:30:11,241 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/grains.d] at time 09:30:11.241049 duration_in_ms=1.927
2017-11-30 09:30:11,242 [salt.state       ][INFO    ][21461] Running state [/etc/salt/grains] at time 09:30:11.241985
2017-11-30 09:30:11,242 [salt.state       ][INFO    ][21461] Executing state file.managed for /etc/salt/grains
2017-11-30 09:30:11,243 [salt.state       ][INFO    ][21461] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-30 09:30:11,243 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/grains] at time 09:30:11.243377 duration_in_ms=1.392
2017-11-30 09:30:11,244 [salt.state       ][INFO    ][21461] Running state [/etc/salt/grains.d/placeholder] at time 09:30:11.244000
2017-11-30 09:30:11,244 [salt.state       ][INFO    ][21461] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-30 09:30:11,245 [salt.state       ][INFO    ][21461] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-30 09:30:11,245 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/grains.d/placeholder] at time 09:30:11.245345 duration_in_ms=1.344
2017-11-30 09:30:11,246 [salt.state       ][INFO    ][21461] Running state [/etc/salt/grains.d/sphinx] at time 09:30:11.245972
2017-11-30 09:30:11,246 [salt.state       ][INFO    ][21461] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-30 09:30:11,350 [salt.state       ][INFO    ][21461] 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-11-30 09:30:11,350 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/grains.d/sphinx] at time 09:30:11.349959 duration_in_ms=103.987
2017-11-30 09:30:11,351 [salt.state       ][INFO    ][21461] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.351404
2017-11-30 09:30:11,352 [salt.state       ][INFO    ][21461] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,352 [salt.state       ][INFO    ][21461] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,353 [salt.state       ][INFO    ][21461] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.352754 duration_in_ms=1.35
2017-11-30 09:30:11,353 [salt.state       ][INFO    ][21461] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.353100
2017-11-30 09:30:11,354 [salt.state       ][INFO    ][21461] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,355 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-30 09:30:11,510 [salt.state       ][INFO    ][21461] {'pid': 21510, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-30 09:30:11,511 [salt.state       ][INFO    ][21461] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.510846 duration_in_ms=157.744
2017-11-30 09:30:11,512 [salt.state       ][INFO    ][21461] Running state [/etc/salt/grains.d/dns_records] at time 09:30:11.511929
2017-11-30 09:30:11,512 [salt.state       ][INFO    ][21461] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-30 09:30:11,611 [salt.state       ][INFO    ][21461] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-30 09:30:11,611 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/grains.d/dns_records] at time 09:30:11.611177 duration_in_ms=99.248
2017-11-30 09:30:11,613 [salt.state       ][INFO    ][21461] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.612858
2017-11-30 09:30:11,613 [salt.state       ][INFO    ][21461] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,614 [salt.state       ][INFO    ][21461] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,614 [salt.state       ][INFO    ][21461] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.614300 duration_in_ms=1.443
2017-11-30 09:30:11,615 [salt.state       ][INFO    ][21461] Running state [/etc/salt/grains.d/salt] at time 09:30:11.615082
2017-11-30 09:30:11,616 [salt.state       ][INFO    ][21461] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-30 09:30:11,729 [salt.state       ][INFO    ][21461] File /etc/salt/grains.d/salt is in the correct state
2017-11-30 09:30:11,729 [salt.state       ][INFO    ][21461] Completed state [/etc/salt/grains.d/salt] at time 09:30:11.729165 duration_in_ms=114.082
2017-11-30 09:30:11,731 [salt.state       ][INFO    ][21461] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.730735
2017-11-30 09:30:11,731 [salt.state       ][INFO    ][21461] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,732 [salt.state       ][INFO    ][21461] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,732 [salt.state       ][INFO    ][21461] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.732038 duration_in_ms=1.303
2017-11-30 09:30:11,735 [salt.state       ][INFO    ][21461] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.735105
2017-11-30 09:30:11,736 [salt.state       ][INFO    ][21461] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:11,736 [salt.state       ][INFO    ][21461] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:11,737 [salt.state       ][INFO    ][21461] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.736464 duration_in_ms=1.359
2017-11-30 09:30:11,737 [salt.state       ][INFO    ][21461] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.736772
2017-11-30 09:30:11,737 [salt.state       ][INFO    ][21461] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:11,740 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-30 09:30:11,755 [salt.state       ][INFO    ][21461] {'pid': 21512, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-30 09:30:11,755 [salt.state       ][INFO    ][21461] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.755392 duration_in_ms=18.618
2017-11-30 09:30:11,757 [salt.state       ][INFO    ][21461] Running state [mine.update] at time 09:30:11.756978
2017-11-30 09:30:11,758 [salt.state       ][INFO    ][21461] Executing state module.wait for mine.update
2017-11-30 09:30:11,758 [salt.state       ][INFO    ][21461] No changes made for mine.update
2017-11-30 09:30:11,758 [salt.state       ][INFO    ][21461] Completed state [mine.update] at time 09:30:11.758386 duration_in_ms=1.408
2017-11-30 09:30:11,759 [salt.state       ][INFO    ][21461] Running state [mine.update] at time 09:30:11.758739
2017-11-30 09:30:11,759 [salt.state       ][INFO    ][21461] Executing state module.mod_watch for mine.update
2017-11-30 09:30:12,296 [salt.state       ][INFO    ][21461] {'ret': True}
2017-11-30 09:30:12,297 [salt.state       ][INFO    ][21461] Completed state [mine.update] at time 09:30:12.296653 duration_in_ms=537.912
2017-11-30 09:30:12,297 [salt.state       ][INFO    ][21461] Running state [ca-certificates] at time 09:30:12.297070
2017-11-30 09:30:12,297 [salt.state       ][INFO    ][21461] Executing state pkg.installed for ca-certificates
2017-11-30 09:30:12,306 [salt.state       ][INFO    ][21461] Package ca-certificates is already installed
2017-11-30 09:30:12,306 [salt.state       ][INFO    ][21461] Completed state [ca-certificates] at time 09:30:12.306059 duration_in_ms=8.988
2017-11-30 09:30:12,308 [salt.state       ][INFO    ][21461] Running state [update-ca-certificates] at time 09:30:12.307443
2017-11-30 09:30:12,308 [salt.state       ][INFO    ][21461] Executing state cmd.wait for update-ca-certificates
2017-11-30 09:30:12,308 [salt.state       ][INFO    ][21461] No changes made for update-ca-certificates
2017-11-30 09:30:12,309 [salt.state       ][INFO    ][21461] Completed state [update-ca-certificates] at time 09:30:12.308721 duration_in_ms=1.278
2017-11-30 09:30:12,309 [salt.state       ][INFO    ][21461] Running state [libvirt-dev] at time 09:30:12.309103
2017-11-30 09:30:12,310 [salt.state       ][INFO    ][21461] Executing state pkg.installed for libvirt-dev
2017-11-30 09:30:12,316 [salt.state       ][INFO    ][21461] Package libvirt-dev is already installed
2017-11-30 09:30:12,316 [salt.state       ][INFO    ][21461] Completed state [libvirt-dev] at time 09:30:12.315898 duration_in_ms=6.796
2017-11-30 09:30:12,316 [salt.state       ][INFO    ][21461] Running state [pkg-config] at time 09:30:12.316165
2017-11-30 09:30:12,316 [salt.state       ][INFO    ][21461] Executing state pkg.installed for pkg-config
2017-11-30 09:30:12,323 [salt.state       ][INFO    ][21461] Package pkg-config is already installed
2017-11-30 09:30:12,323 [salt.state       ][INFO    ][21461] Completed state [pkg-config] at time 09:30:12.322964 duration_in_ms=6.799
2017-11-30 09:30:12,323 [salt.state       ][INFO    ][21461] Running state [virtng.init] at time 09:30:12.323222
2017-11-30 09:30:12,323 [salt.state       ][INFO    ][21461] Executing state module.run for virtng.init
2017-11-30 09:30:12,662 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command ['git', '--version'] in directory '/root'
2017-11-30 09:30:12,756 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,786 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:12,787 [salt.state       ][INFO    ][21461] Completed state [virtng.init] at time 09:30:12.786711 duration_in_ms=463.487
2017-11-30 09:30:12,787 [salt.state       ][INFO    ][21461] Running state [virt.set_autostart] at time 09:30:12.787329
2017-11-30 09:30:12,788 [salt.state       ][INFO    ][21461] Executing state module.run for virt.set_autostart
2017-11-30 09:30:12,793 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,827 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:12,827 [salt.state       ][INFO    ][21461] Completed state [virt.set_autostart] at time 09:30:12.827120 duration_in_ms=39.79
2017-11-30 09:30:12,828 [salt.state       ][INFO    ][21461] Running state [virtng.init] at time 09:30:12.827702
2017-11-30 09:30:12,828 [salt.state       ][INFO    ][21461] Executing state module.run for virtng.init
2017-11-30 09:30:12,833 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,863 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:12,864 [salt.state       ][INFO    ][21461] Completed state [virtng.init] at time 09:30:12.863512 duration_in_ms=35.808
2017-11-30 09:30:12,864 [salt.state       ][INFO    ][21461] Running state [virt.set_autostart] at time 09:30:12.864098
2017-11-30 09:30:12,865 [salt.state       ][INFO    ][21461] Executing state module.run for virt.set_autostart
2017-11-30 09:30:12,869 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,897 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:12,897 [salt.state       ][INFO    ][21461] Completed state [virt.set_autostart] at time 09:30:12.897237 duration_in_ms=33.138
2017-11-30 09:30:12,898 [salt.state       ][INFO    ][21461] Running state [virtng.init] at time 09:30:12.897802
2017-11-30 09:30:12,898 [salt.state       ][INFO    ][21461] Executing state module.run for virtng.init
2017-11-30 09:30:12,902 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,928 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:12,928 [salt.state       ][INFO    ][21461] Completed state [virtng.init] at time 09:30:12.928023 duration_in_ms=30.219
2017-11-30 09:30:12,929 [salt.state       ][INFO    ][21461] Running state [virt.set_autostart] at time 09:30:12.928458
2017-11-30 09:30:12,929 [salt.state       ][INFO    ][21461] Executing state module.run for virt.set_autostart
2017-11-30 09:30:12,932 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,952 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:12,953 [salt.state       ][INFO    ][21461] Completed state [virt.set_autostart] at time 09:30:12.952510 duration_in_ms=24.051
2017-11-30 09:30:12,953 [salt.state       ][INFO    ][21461] Running state [virtng.init] at time 09:30:12.952918
2017-11-30 09:30:12,953 [salt.state       ][INFO    ][21461] Executing state module.run for virtng.init
2017-11-30 09:30:12,957 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,976 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:12,976 [salt.state       ][INFO    ][21461] Completed state [virtng.init] at time 09:30:12.976132 duration_in_ms=23.214
2017-11-30 09:30:12,977 [salt.state       ][INFO    ][21461] Running state [virt.set_autostart] at time 09:30:12.976534
2017-11-30 09:30:12,977 [salt.state       ][INFO    ][21461] Executing state module.run for virt.set_autostart
2017-11-30 09:30:12,980 [salt.loaded.int.module.cmdmod][INFO    ][21461] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,004 [salt.state       ][INFO    ][21461] unless execution succeeded
2017-11-30 09:30:13,157 [salt.state       ][INFO    ][21461] Completed state [virt.set_autostart] at time 09:30:13.156377 duration_in_ms=179.84
2017-11-30 09:30:13,162 [salt.minion      ][INFO    ][21461] Returning information for job: 20171130093006235271
2017-11-30 09:30:37,276 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130093037283762
2017-11-30 09:30:37,297 [salt.minion      ][INFO    ][21563] Starting a new job with PID 21563
2017-11-30 09:30:39,899 [salt.state       ][INFO    ][21563] Loading fresh modules for state activity
2017-11-30 09:30:39,951 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-30 09:30:39,981 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-30 09:30:40,071 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:40,142 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-30 09:30:40,158 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-30 09:30:40,185 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:40,245 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:40,263 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:40,308 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:40,325 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:40,339 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:40,353 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:40,361 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:40,382 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:40,398 [py.warnings      ][WARNING ][21563] /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-11-30 09:30:40,398 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:40,748 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:40,773 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:40,788 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:40,804 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:40,868 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:40,890 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:40,923 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:40,941 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:40,968 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:41,018 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:41,117 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-30 09:30:41,146 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:41,261 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:41,294 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:41,381 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:41,408 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:41,424 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:41,444 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:41,454 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:41,488 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:41,513 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:41,933 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:41,956 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:41,971 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:41,985 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:42,029 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:42,044 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:42,090 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:42,111 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:42,168 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:42,288 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:42,412 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-30 09:30:42,515 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:42,988 [salt.state       ][INFO    ][21563] Running state [salt-minion] at time 09:30:42.988245
2017-11-30 09:30:42,989 [salt.state       ][INFO    ][21563] Executing state pkg.installed for salt-minion
2017-11-30 09:30:42,989 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:30:43,453 [salt.state       ][INFO    ][21563] Package salt-minion is already installed
2017-11-30 09:30:43,453 [salt.state       ][INFO    ][21563] Completed state [salt-minion] at time 09:30:43.453151 duration_in_ms=464.906
2017-11-30 09:30:43,454 [salt.state       ][INFO    ][21563] Running state [salt_minion_dependency_packages] at time 09:30:43.453462
2017-11-30 09:30:43,454 [salt.state       ][INFO    ][21563] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-30 09:30:43,458 [salt.state       ][INFO    ][21563] All specified packages are already installed
2017-11-30 09:30:43,458 [salt.state       ][INFO    ][21563] Completed state [salt_minion_dependency_packages] at time 09:30:43.457689 duration_in_ms=4.227
2017-11-30 09:30:43,460 [salt.state       ][INFO    ][21563] Running state [/etc/salt/minion.d/minion.conf] at time 09:30:43.459641
2017-11-30 09:30:43,460 [salt.state       ][INFO    ][21563] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-30 09:30:43,624 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-30 09:30:43,715 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:43,826 [salt.fileclient  ][INFO    ][21563] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:43,902 [salt.state       ][INFO    ][21563] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-30 09:30:43,903 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/minion.d/minion.conf] at time 09:30:43.902695 duration_in_ms=443.053
2017-11-30 09:30:43,903 [salt.state       ][INFO    ][21563] Running state [/etc/salt/minion.d/_virt.conf] at time 09:30:43.903215
2017-11-30 09:30:43,904 [salt.state       ][INFO    ][21563] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-30 09:30:44,034 [salt.state       ][INFO    ][21563] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-30 09:30:44,034 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:30:44.034280 duration_in_ms=131.062
2017-11-30 09:30:44,039 [salt.state       ][INFO    ][21563] Running state [salt-minion] at time 09:30:44.038464
2017-11-30 09:30:44,039 [salt.state       ][INFO    ][21563] Executing state service.running for salt-minion
2017-11-30 09:30:44,040 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-30 09:30:44,072 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:44,087 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:44,104 [salt.state       ][INFO    ][21563] The service salt-minion is already running
2017-11-30 09:30:44,104 [salt.state       ][INFO    ][21563] Completed state [salt-minion] at time 09:30:44.104228 duration_in_ms=65.764
2017-11-30 09:30:44,107 [salt.state       ][INFO    ][21563] Running state [/etc/salt/grains.d] at time 09:30:44.106928
2017-11-30 09:30:44,107 [salt.state       ][INFO    ][21563] Executing state file.directory for /etc/salt/grains.d
2017-11-30 09:30:44,109 [salt.state       ][INFO    ][21563] Directory /etc/salt/grains.d is in the correct state
2017-11-30 09:30:44,109 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/grains.d] at time 09:30:44.108776 duration_in_ms=1.848
2017-11-30 09:30:44,110 [salt.state       ][INFO    ][21563] Running state [/etc/salt/grains] at time 09:30:44.109577
2017-11-30 09:30:44,110 [salt.state       ][INFO    ][21563] Executing state file.managed for /etc/salt/grains
2017-11-30 09:30:44,111 [salt.state       ][INFO    ][21563] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-30 09:30:44,111 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/grains] at time 09:30:44.110791 duration_in_ms=1.213
2017-11-30 09:30:44,111 [salt.state       ][INFO    ][21563] Running state [/etc/salt/grains.d/placeholder] at time 09:30:44.111321
2017-11-30 09:30:44,112 [salt.state       ][INFO    ][21563] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-30 09:30:44,112 [salt.state       ][INFO    ][21563] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-30 09:30:44,113 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/grains.d/placeholder] at time 09:30:44.112493 duration_in_ms=1.173
2017-11-30 09:30:44,113 [salt.state       ][INFO    ][21563] Running state [/etc/salt/grains.d/sphinx] at time 09:30:44.113024
2017-11-30 09:30:44,113 [salt.state       ][INFO    ][21563] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-30 09:30:44,243 [salt.state       ][INFO    ][21563] File /etc/salt/grains.d/sphinx is in the correct state
2017-11-30 09:30:44,244 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/grains.d/sphinx] at time 09:30:44.243638 duration_in_ms=130.612
2017-11-30 09:30:44,245 [salt.state       ][INFO    ][21563] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:44.245040
2017-11-30 09:30:44,245 [salt.state       ][INFO    ][21563] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:44,246 [salt.state       ][INFO    ][21563] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:44,246 [salt.state       ][INFO    ][21563] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:44.246229 duration_in_ms=1.189
2017-11-30 09:30:44,247 [salt.state       ][INFO    ][21563] Running state [/etc/salt/grains.d/dns_records] at time 09:30:44.246858
2017-11-30 09:30:44,247 [salt.state       ][INFO    ][21563] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-30 09:30:44,366 [salt.state       ][INFO    ][21563] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-30 09:30:44,366 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/grains.d/dns_records] at time 09:30:44.365958 duration_in_ms=119.098
2017-11-30 09:30:44,367 [salt.state       ][INFO    ][21563] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:44.367391
2017-11-30 09:30:44,368 [salt.state       ][INFO    ][21563] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:44,368 [salt.state       ][INFO    ][21563] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:44,369 [salt.state       ][INFO    ][21563] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:44.368697 duration_in_ms=1.306
2017-11-30 09:30:44,369 [salt.state       ][INFO    ][21563] Running state [/etc/salt/grains.d/salt] at time 09:30:44.369327
2017-11-30 09:30:44,370 [salt.state       ][INFO    ][21563] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-30 09:30:44,459 [salt.state       ][INFO    ][21563] File /etc/salt/grains.d/salt is in the correct state
2017-11-30 09:30:44,460 [salt.state       ][INFO    ][21563] Completed state [/etc/salt/grains.d/salt] at time 09:30:44.459429 duration_in_ms=90.1
2017-11-30 09:30:44,461 [salt.state       ][INFO    ][21563] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:44.460807
2017-11-30 09:30:44,461 [salt.state       ][INFO    ][21563] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:44,462 [salt.state       ][INFO    ][21563] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:44,462 [salt.state       ][INFO    ][21563] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:44.462044 duration_in_ms=1.237
2017-11-30 09:30:44,465 [salt.state       ][INFO    ][21563] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:44.465016
2017-11-30 09:30:44,466 [salt.state       ][INFO    ][21563] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:44,466 [salt.state       ][INFO    ][21563] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:44,466 [salt.state       ][INFO    ][21563] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:44.466380 duration_in_ms=1.363
2017-11-30 09:30:44,468 [salt.state       ][INFO    ][21563] Running state [mine.update] at time 09:30:44.467494
2017-11-30 09:30:44,468 [salt.state       ][INFO    ][21563] Executing state module.wait for mine.update
2017-11-30 09:30:44,469 [salt.state       ][INFO    ][21563] No changes made for mine.update
2017-11-30 09:30:44,469 [salt.state       ][INFO    ][21563] Completed state [mine.update] at time 09:30:44.468792 duration_in_ms=1.297
2017-11-30 09:30:44,469 [salt.state       ][INFO    ][21563] Running state [ca-certificates] at time 09:30:44.469230
2017-11-30 09:30:44,470 [salt.state       ][INFO    ][21563] Executing state pkg.installed for ca-certificates
2017-11-30 09:30:44,477 [salt.state       ][INFO    ][21563] Package ca-certificates is already installed
2017-11-30 09:30:44,478 [salt.state       ][INFO    ][21563] Completed state [ca-certificates] at time 09:30:44.477428 duration_in_ms=8.198
2017-11-30 09:30:44,479 [salt.state       ][INFO    ][21563] Running state [update-ca-certificates] at time 09:30:44.478449
2017-11-30 09:30:44,479 [salt.state       ][INFO    ][21563] Executing state cmd.wait for update-ca-certificates
2017-11-30 09:30:44,479 [salt.state       ][INFO    ][21563] No changes made for update-ca-certificates
2017-11-30 09:30:44,480 [salt.state       ][INFO    ][21563] Completed state [update-ca-certificates] at time 09:30:44.479613 duration_in_ms=1.164
2017-11-30 09:30:44,480 [salt.state       ][INFO    ][21563] Running state [libvirt-dev] at time 09:30:44.480019
2017-11-30 09:30:44,480 [salt.state       ][INFO    ][21563] Executing state pkg.installed for libvirt-dev
2017-11-30 09:30:44,487 [salt.state       ][INFO    ][21563] Package libvirt-dev is already installed
2017-11-30 09:30:44,487 [salt.state       ][INFO    ][21563] Completed state [libvirt-dev] at time 09:30:44.487136 duration_in_ms=7.116
2017-11-30 09:30:44,488 [salt.state       ][INFO    ][21563] Running state [pkg-config] at time 09:30:44.487504
2017-11-30 09:30:44,488 [salt.state       ][INFO    ][21563] Executing state pkg.installed for pkg-config
2017-11-30 09:30:44,494 [salt.state       ][INFO    ][21563] Package pkg-config is already installed
2017-11-30 09:30:44,494 [salt.state       ][INFO    ][21563] Completed state [pkg-config] at time 09:30:44.494345 duration_in_ms=6.84
2017-11-30 09:30:44,495 [salt.state       ][INFO    ][21563] Running state [virtng.init] at time 09:30:44.494737
2017-11-30 09:30:44,495 [salt.state       ][INFO    ][21563] Executing state module.run for virtng.init
2017-11-30 09:30:44,727 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command ['git', '--version'] in directory '/root'
2017-11-30 09:30:44,846 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,885 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:44,886 [salt.state       ][INFO    ][21563] Completed state [virtng.init] at time 09:30:44.885403 duration_in_ms=390.665
2017-11-30 09:30:44,886 [salt.state       ][INFO    ][21563] Running state [virt.set_autostart] at time 09:30:44.886008
2017-11-30 09:30:44,887 [salt.state       ][INFO    ][21563] Executing state module.run for virt.set_autostart
2017-11-30 09:30:44,892 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,928 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:44,928 [salt.state       ][INFO    ][21563] Completed state [virt.set_autostart] at time 09:30:44.928035 duration_in_ms=42.025
2017-11-30 09:30:44,929 [salt.state       ][INFO    ][21563] Running state [virtng.init] at time 09:30:44.928549
2017-11-30 09:30:44,929 [salt.state       ][INFO    ][21563] Executing state module.run for virtng.init
2017-11-30 09:30:44,934 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,966 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:44,966 [salt.state       ][INFO    ][21563] Completed state [virtng.init] at time 09:30:44.966254 duration_in_ms=37.705
2017-11-30 09:30:44,967 [salt.state       ][INFO    ][21563] Running state [virt.set_autostart] at time 09:30:44.966757
2017-11-30 09:30:44,967 [salt.state       ][INFO    ][21563] Executing state module.run for virt.set_autostart
2017-11-30 09:30:44,972 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:45,003 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:45,003 [salt.state       ][INFO    ][21563] Completed state [virt.set_autostart] at time 09:30:45.002940 duration_in_ms=36.181
2017-11-30 09:30:45,003 [salt.state       ][INFO    ][21563] Running state [virtng.init] at time 09:30:45.003421
2017-11-30 09:30:45,004 [salt.state       ][INFO    ][21563] Executing state module.run for virtng.init
2017-11-30 09:30:45,008 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:45,037 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:45,130 [salt.state       ][INFO    ][21563] Completed state [virtng.init] at time 09:30:45.129873 duration_in_ms=126.45
2017-11-30 09:30:45,131 [salt.state       ][INFO    ][21563] Running state [virt.set_autostart] at time 09:30:45.130512
2017-11-30 09:30:45,131 [salt.state       ][INFO    ][21563] Executing state module.run for virt.set_autostart
2017-11-30 09:30:45,137 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:45,170 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:45,170 [salt.state       ][INFO    ][21563] Completed state [virt.set_autostart] at time 09:30:45.170341 duration_in_ms=39.828
2017-11-30 09:30:45,171 [salt.state       ][INFO    ][21563] Running state [virtng.init] at time 09:30:45.170908
2017-11-30 09:30:45,171 [salt.state       ][INFO    ][21563] Executing state module.run for virtng.init
2017-11-30 09:30:45,177 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:45,210 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:45,211 [salt.state       ][INFO    ][21563] Completed state [virtng.init] at time 09:30:45.210824 duration_in_ms=39.915
2017-11-30 09:30:45,211 [salt.state       ][INFO    ][21563] Running state [virt.set_autostart] at time 09:30:45.211396
2017-11-30 09:30:45,212 [salt.state       ][INFO    ][21563] Executing state module.run for virt.set_autostart
2017-11-30 09:30:45,217 [salt.loaded.int.module.cmdmod][INFO    ][21563] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:45,246 [salt.state       ][INFO    ][21563] unless execution succeeded
2017-11-30 09:30:45,247 [salt.state       ][INFO    ][21563] Completed state [virt.set_autostart] at time 09:30:45.246449 duration_in_ms=35.052
2017-11-30 09:30:45,251 [salt.minion      ][INFO    ][21563] Returning information for job: 20171130093037283762
2017-11-30 09:31:33,170 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171130093133171853
2017-11-30 09:31:33,194 [salt.minion      ][INFO    ][21655] Starting a new job with PID 21655
2017-11-30 09:31:36,450 [salt.state       ][INFO    ][21655] Loading fresh modules for state activity
2017-11-30 09:31:36,805 [salt.state       ][ERROR   ][21655] No contents found in top file
2017-11-30 09:31:36,807 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-30 09:31:36,813 [salt.utils.extmods][INFO    ][21655] Syncing beacons for environment 'base'
2017-11-30 09:31:36,813 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_beacons, for base)
2017-11-30 09:31:36,814 [salt.fileclient  ][INFO    ][21655] Caching directory '_beacons/' for environment 'base'
2017-11-30 09:31:36,863 [salt.utils.extmods][INFO    ][21655] Syncing modules for environment 'base'
2017-11-30 09:31:36,863 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_modules, for base)
2017-11-30 09:31:36,864 [salt.fileclient  ][INFO    ][21655] Caching directory '_modules/' for environment 'base'
2017-11-30 09:31:36,928 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-11-30 09:31:36,963 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-30 09:31:36,976 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-30 09:31:36,993 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-30 09:31:37,019 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-30 09:31:37,041 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-30 09:31:37,099 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-30 09:31:37,143 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-30 09:31:37,157 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-30 09:31:37,173 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-30 09:31:37,195 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-30 09:31:37,226 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-30 09:31:37,240 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-30 09:31:37,272 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-30 09:31:37,311 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-30 09:31:37,326 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-30 09:31:37,344 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-30 09:31:37,414 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-30 09:31:37,431 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-30 09:31:37,463 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-30 09:31:37,512 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-30 09:31:37,535 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-30 09:31:37,552 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-30 09:31:37,583 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-30 09:31:37,600 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-30 09:31:37,632 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-30 09:31:37,648 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-30 09:31:37,657 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-30 09:31:37,707 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-30 09:31:37,769 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-30 09:31:37,796 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-30 09:31:37,818 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-30 09:31:37,862 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-30 09:31:37,877 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-30 09:31:37,882 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-30 09:31:37,882 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-11-30 09:31:37,883 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-30 09:31:37,883 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-30 09:31:37,883 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-30 09:31:37,884 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-30 09:31:37,884 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-30 09:31:37,885 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-30 09:31:37,885 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-30 09:31:37,885 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-30 09:31:37,886 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-30 09:31:37,886 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-30 09:31:37,886 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-30 09:31:37,887 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-30 09:31:37,887 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-30 09:31:37,888 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-30 09:31:37,888 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-30 09:31:37,888 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-30 09:31:37,889 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-30 09:31:37,889 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-30 09:31:37,889 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-30 09:31:37,890 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-30 09:31:37,890 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-30 09:31:37,890 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-30 09:31:37,891 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-30 09:31:37,891 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-30 09:31:37,891 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-30 09:31:37,892 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-30 09:31:37,892 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-30 09:31:37,893 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-30 09:31:37,893 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-30 09:31:37,894 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-30 09:31:37,894 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-30 09:31:37,894 [salt.utils.extmods][INFO    ][21655] 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-11-30 09:31:37,895 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-30 09:31:37,895 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-30 09:31:37,904 [salt.utils.extmods][INFO    ][21655] Syncing states for environment 'base'
2017-11-30 09:31:37,905 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_states, for base)
2017-11-30 09:31:37,905 [salt.fileclient  ][INFO    ][21655] Caching directory '_states/' for environment 'base'
2017-11-30 09:31:37,958 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-11-30 09:31:37,981 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-30 09:31:38,016 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-30 09:31:38,028 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-30 09:31:38,077 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-30 09:31:38,094 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-30 09:31:38,104 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-30 09:31:38,117 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-30 09:31:38,158 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-30 09:31:38,198 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-11-30 09:31:38,208 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-30 09:31:38,287 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-30 09:31:38,308 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-30 09:31:38,320 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-11-30 09:31:38,333 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-30 09:31:38,347 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-30 09:31:38,381 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-30 09:31:38,390 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-30 09:31:38,408 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-30 09:31:38,420 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-30 09:31:38,433 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-30 09:31:38,465 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-30 09:31:38,480 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-30 09:31:38,491 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-30 09:31:38,506 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-30 09:31:38,519 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-30 09:31:38,538 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-30 09:31:38,559 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-30 09:31:38,585 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-30 09:31:38,593 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-30 09:31:38,601 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-30 09:31:38,628 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-30 09:31:38,631 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-11-30 09:31:38,631 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-30 09:31:38,632 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-30 09:31:38,632 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-30 09:31:38,632 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-30 09:31:38,633 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-30 09:31:38,633 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-30 09:31:38,633 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-30 09:31:38,633 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-30 09:31:38,634 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-11-30 09:31:38,634 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-30 09:31:38,634 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-30 09:31:38,634 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-30 09:31:38,635 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-11-30 09:31:38,635 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-30 09:31:38,635 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-30 09:31:38,636 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-30 09:31:38,636 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-30 09:31:38,636 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-30 09:31:38,636 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-30 09:31:38,637 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-30 09:31:38,637 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-30 09:31:38,637 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-30 09:31:38,637 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-30 09:31:38,638 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-30 09:31:38,638 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-30 09:31:38,638 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-30 09:31:38,639 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-30 09:31:38,639 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-30 09:31:38,639 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-30 09:31:38,639 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-30 09:31:38,640 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-30 09:31:38,640 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-30 09:31:38,642 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-30 09:31:38,649 [salt.utils.extmods][INFO    ][21655] Syncing sdb for environment 'base'
2017-11-30 09:31:38,649 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_sdb, for base)
2017-11-30 09:31:38,649 [salt.fileclient  ][INFO    ][21655] Caching directory '_sdb/' for environment 'base'
2017-11-30 09:31:38,694 [salt.utils.extmods][INFO    ][21655] Syncing grains for environment 'base'
2017-11-30 09:31:38,694 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_grains, for base)
2017-11-30 09:31:38,695 [salt.fileclient  ][INFO    ][21655] Caching directory '_grains/' for environment 'base'
2017-11-30 09:31:38,755 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-30 09:31:38,770 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-30 09:31:38,786 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-30 09:31:38,806 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-30 09:31:38,821 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-30 09:31:39,198 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-30 09:31:39,207 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-30 09:31:39,218 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-30 09:31:39,231 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-30 09:31:39,480 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-30 09:31:39,492 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-30 09:31:39,695 [salt.fileclient  ][INFO    ][21655] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-30 09:31:39,701 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-30 09:31:39,702 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-30 09:31:39,702 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-30 09:31:39,702 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-30 09:31:39,703 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-30 09:31:39,703 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-30 09:31:39,703 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-30 09:31:39,703 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-30 09:31:39,704 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-30 09:31:39,704 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-30 09:31:39,704 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-30 09:31:39,704 [salt.utils.extmods][INFO    ][21655] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-30 09:31:39,706 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-30 09:31:39,712 [salt.utils.extmods][INFO    ][21655] Syncing renderers for environment 'base'
2017-11-30 09:31:39,713 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_renderers, for base)
2017-11-30 09:31:39,713 [salt.fileclient  ][INFO    ][21655] Caching directory '_renderers/' for environment 'base'
2017-11-30 09:31:39,753 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-30 09:31:39,758 [salt.utils.extmods][INFO    ][21655] Syncing returners for environment 'base'
2017-11-30 09:31:39,758 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_returners, for base)
2017-11-30 09:31:39,758 [salt.fileclient  ][INFO    ][21655] Caching directory '_returners/' for environment 'base'
2017-11-30 09:31:39,789 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-30 09:31:39,793 [salt.utils.extmods][INFO    ][21655] Syncing output for environment 'base'
2017-11-30 09:31:39,793 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_output, for base)
2017-11-30 09:31:39,794 [salt.fileclient  ][INFO    ][21655] Caching directory '_output/' for environment 'base'
2017-11-30 09:31:39,821 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-30 09:31:39,825 [salt.utils.extmods][INFO    ][21655] Syncing utils for environment 'base'
2017-11-30 09:31:39,825 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_utils, for base)
2017-11-30 09:31:39,826 [salt.fileclient  ][INFO    ][21655] Caching directory '_utils/' for environment 'base'
2017-11-30 09:31:39,851 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-30 09:31:39,855 [salt.utils.extmods][INFO    ][21655] Syncing log_handlers for environment 'base'
2017-11-30 09:31:39,855 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_log_handlers, for base)
2017-11-30 09:31:39,855 [salt.fileclient  ][INFO    ][21655] Caching directory '_log_handlers/' for environment 'base'
2017-11-30 09:31:39,883 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-30 09:31:39,886 [salt.utils.extmods][INFO    ][21655] Syncing proxy for environment 'base'
2017-11-30 09:31:39,887 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_proxy, for base)
2017-11-30 09:31:39,887 [salt.fileclient  ][INFO    ][21655] Caching directory '_proxy/' for environment 'base'
2017-11-30 09:31:39,911 [salt.utils.extmods][INFO    ][21655] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-30 09:31:39,914 [salt.utils.extmods][INFO    ][21655] Syncing engines for environment 'base'
2017-11-30 09:31:39,915 [salt.utils.extmods][INFO    ][21655] Loading cache from salt://_engines, for base)
2017-11-30 09:31:39,915 [salt.fileclient  ][INFO    ][21655] Caching directory '_engines/' for environment 'base'
2017-11-30 09:31:39,941 [salt.minion      ][INFO    ][21655] Returning information for job: 20171130093133171853
2017-11-30 09:45:06,634 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command test.ping with jid 20171130094506624351
2017-11-30 09:45:06,659 [salt.minion      ][INFO    ][21941] Starting a new job with PID 21941
2017-11-30 09:45:06,721 [salt.minion      ][INFO    ][21941] Returning information for job: 20171130094506624351
2017-11-30 09:46:28,547 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130094628536887
2017-11-30 09:46:28,567 [salt.minion      ][INFO    ][21974] Starting a new job with PID 21974
2017-11-30 09:46:29,140 [salt.state       ][INFO    ][21974] Loading fresh modules for state activity
2017-11-30 09:46:29,175 [salt.fileclient  ][INFO    ][21974] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-30 09:46:29,198 [salt.fileclient  ][INFO    ][21974] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-30 09:46:29,232 [salt.fileclient  ][INFO    ][21974] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:46:29,680 [salt.state       ][INFO    ][21974] Running state [lsof] at time 09:46:29.680432
2017-11-30 09:46:29,681 [salt.state       ][INFO    ][21974] Executing state pkg.installed for lsof
2017-11-30 09:46:29,681 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:46:30,115 [salt.state       ][INFO    ][21974] Package lsof is already installed
2017-11-30 09:46:30,115 [salt.state       ][INFO    ][21974] Completed state [lsof] at time 09:46:30.115266 duration_in_ms=434.834
2017-11-30 09:46:30,116 [salt.state       ][INFO    ][21974] Running state [keepalived] at time 09:46:30.115562
2017-11-30 09:46:30,116 [salt.state       ][INFO    ][21974] Executing state pkg.installed for keepalived
2017-11-30 09:46:30,142 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-30 09:46:33,061 [salt.loaded.int.module.cmdmod][INFO    ][21974] 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-11-30 09:46:38,607 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130094638585692
2017-11-30 09:46:38,630 [salt.minion      ][INFO    ][23151] Starting a new job with PID 23151
2017-11-30 09:46:38,661 [salt.minion      ][INFO    ][23151] Returning information for job: 20171130094638585692
2017-11-30 09:46:42,123 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:46:42,187 [salt.state       ][INFO    ][21974] 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-11-30 09:46:42,219 [salt.state       ][INFO    ][21974] Loading fresh modules for state activity
2017-11-30 09:46:42,253 [salt.state       ][INFO    ][21974] Completed state [keepalived] at time 09:46:42.253252 duration_in_ms=12137.689
2017-11-30 09:46:42,261 [salt.state       ][INFO    ][21974] Running state [/etc/keepalived/keepalived.conf] at time 09:46:42.260559
2017-11-30 09:46:42,261 [salt.state       ][INFO    ][21974] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-30 09:46:42,299 [salt.fileclient  ][INFO    ][21974] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-30 09:46:42,347 [salt.fileclient  ][INFO    ][21974] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:46:42,357 [salt.state       ][INFO    ][21974] File changed:
New file
2017-11-30 09:46:42,357 [salt.state       ][INFO    ][21974] Completed state [/etc/keepalived/keepalived.conf] at time 09:46:42.356771 duration_in_ms=96.212
2017-11-30 09:46:42,440 [salt.state       ][INFO    ][21974] Running state [keepalived] at time 09:46:42.440196
2017-11-30 09:46:42,441 [salt.state       ][INFO    ][21974] Executing state service.running for keepalived
2017-11-30 09:46:42,442 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-30 09:46:42,458 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-30 09:46:42,473 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:46:42,487 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:46:42,503 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-30 09:46:42,586 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-30 09:46:42,600 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:46:42,615 [salt.loaded.int.module.cmdmod][INFO    ][21974] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:46:42,626 [salt.state       ][INFO    ][21974] {'keepalived': True}
2017-11-30 09:46:42,627 [salt.state       ][INFO    ][21974] Completed state [keepalived] at time 09:46:42.626645 duration_in_ms=186.448
2017-11-30 09:46:42,628 [salt.minion      ][INFO    ][21974] Returning information for job: 20171130094628536887
2017-11-30 09:49:38,314 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command pillar.get with jid 20171130094938308106
2017-11-30 09:49:38,339 [salt.minion      ][INFO    ][23476] Starting a new job with PID 23476
2017-11-30 09:49:38,345 [salt.minion      ][INFO    ][23476] Returning information for job: 20171130094938308106
2017-11-30 09:56:09,860 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130095609849627
2017-11-30 09:56:09,882 [salt.minion      ][INFO    ][23591] Starting a new job with PID 23591
2017-11-30 09:56:12,433 [salt.state       ][INFO    ][23591] Loading fresh modules for state activity
2017-11-30 09:56:12,474 [salt.fileclient  ][INFO    ][23591] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 09:56:12,505 [salt.fileclient  ][INFO    ][23591] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 09:56:12,826 [salt.state       ][INFO    ][23591] Running state [attr] at time 09:56:12.826274
2017-11-30 09:56:12,827 [salt.state       ][INFO    ][23591] Executing state pkg.installed for attr
2017-11-30 09:56:12,827 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:13,265 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-30 09:56:15,329 [salt.loaded.int.module.cmdmod][INFO    ][23591] 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-11-30 09:56:18,316 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:18,369 [salt.state       ][INFO    ][23591] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-30 09:56:18,384 [salt.state       ][INFO    ][23591] Loading fresh modules for state activity
2017-11-30 09:56:18,407 [salt.state       ][INFO    ][23591] Completed state [attr] at time 09:56:18.407130 duration_in_ms=5580.855
2017-11-30 09:56:18,416 [salt.state       ][INFO    ][23591] Running state [glusterfs-server] at time 09:56:18.415730
2017-11-30 09:56:18,416 [salt.state       ][INFO    ][23591] Executing state pkg.installed for glusterfs-server
2017-11-30 09:56:18,665 [salt.loaded.int.module.cmdmod][INFO    ][23591] 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-11-30 09:56:19,948 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130095619934732
2017-11-30 09:56:19,970 [salt.minion      ][INFO    ][24456] Starting a new job with PID 24456
2017-11-30 09:56:19,995 [salt.minion      ][INFO    ][24456] Returning information for job: 20171130095619934732
2017-11-30 09:56:30,162 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130095630150684
2017-11-30 09:56:30,184 [salt.minion      ][INFO    ][24584] Starting a new job with PID 24584
2017-11-30 09:56:30,214 [salt.minion      ][INFO    ][24584] Returning information for job: 20171130095630150684
2017-11-30 09:56:40,398 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130095640374016
2017-11-30 09:56:40,420 [salt.minion      ][INFO    ][25979] Starting a new job with PID 25979
2017-11-30 09:56:40,442 [salt.minion      ][INFO    ][25979] Returning information for job: 20171130095640374016
2017-11-30 09:56:41,016 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:41,070 [salt.state       ][INFO    ][23591] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'glusterfs-client' changed from 'absent' to '3.10.7-ubuntu1~xenial1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.2'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'glusterfs-common' changed from 'absent' to '3.10.7-ubuntu1~xenial1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'portmap' changed from 'absent' to '1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'
'nfs-client' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.10.7-ubuntu1~xenial1'

2017-11-30 09:56:41,090 [salt.state       ][INFO    ][23591] Loading fresh modules for state activity
2017-11-30 09:56:41,116 [salt.state       ][INFO    ][23591] Completed state [glusterfs-server] at time 09:56:41.116074 duration_in_ms=22700.342
2017-11-30 09:56:41,125 [salt.state       ][INFO    ][23591] Running state [bc] at time 09:56:41.124961
2017-11-30 09:56:41,125 [salt.state       ][INFO    ][23591] Executing state pkg.installed for bc
2017-11-30 09:56:41,442 [salt.loaded.int.module.cmdmod][INFO    ][23591] 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-11-30 09:56:44,994 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:45,047 [salt.state       ][INFO    ][23591] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-30 09:56:45,061 [salt.state       ][INFO    ][23591] Loading fresh modules for state activity
2017-11-30 09:56:45,086 [salt.state       ][INFO    ][23591] Completed state [bc] at time 09:56:45.085659 duration_in_ms=3960.698
2017-11-30 09:56:45,179 [salt.state       ][INFO    ][23591] Running state [glusterfs-server] at time 09:56:45.179121
2017-11-30 09:56:45,179 [salt.state       ][INFO    ][23591] Executing state service.running for glusterfs-server
2017-11-30 09:56:45,181 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 09:56:45,199 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:56:45,211 [salt.loaded.int.module.cmdmod][INFO    ][23591] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:56:45,226 [salt.state       ][INFO    ][23591] The service glusterfs-server is already running
2017-11-30 09:56:45,226 [salt.state       ][INFO    ][23591] Completed state [glusterfs-server] at time 09:56:45.226024 duration_in_ms=46.902
2017-11-30 09:56:45,229 [salt.state       ][INFO    ][23591] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:56:45.228848
2017-11-30 09:56:45,229 [salt.state       ][INFO    ][23591] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 09:56:45,242 [salt.state       ][INFO    ][23591] File changed:
New file
2017-11-30 09:56:45,243 [salt.state       ][INFO    ][23591] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:56:45.242458 duration_in_ms=13.611
2017-11-30 09:56:45,243 [salt.state       ][INFO    ][23591] Running state [/srv/glusterfs/glance] at time 09:56:45.242795
2017-11-30 09:56:45,243 [salt.state       ][INFO    ][23591] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 09:56:45,244 [salt.state       ][INFO    ][23591] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-30 09:56:45,245 [salt.state       ][INFO    ][23591] Completed state [/srv/glusterfs/glance] at time 09:56:45.244468 duration_in_ms=1.673
2017-11-30 09:56:45,245 [salt.state       ][INFO    ][23591] Running state [/srv/glusterfs/keystone-keys] at time 09:56:45.244788
2017-11-30 09:56:45,245 [salt.state       ][INFO    ][23591] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 09:56:45,246 [salt.state       ][INFO    ][23591] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-30 09:56:45,246 [salt.state       ][INFO    ][23591] Completed state [/srv/glusterfs/keystone-keys] at time 09:56:45.246111 duration_in_ms=1.323
2017-11-30 09:56:45,246 [salt.state       ][INFO    ][23591] Running state [/srv/glusterfs/nova_instances] at time 09:56:45.246413
2017-11-30 09:56:45,247 [salt.state       ][INFO    ][23591] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 09:56:45,247 [salt.state       ][INFO    ][23591] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-30 09:56:45,248 [salt.state       ][INFO    ][23591] Completed state [/srv/glusterfs/nova_instances] at time 09:56:45.247712 duration_in_ms=1.3
2017-11-30 09:56:45,248 [salt.state       ][INFO    ][23591] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:56:45.248042
2017-11-30 09:56:45,248 [salt.state       ][INFO    ][23591] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 09:56:45,249 [salt.state       ][INFO    ][23591] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-30 09:56:45,249 [salt.state       ][INFO    ][23591] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:56:45.249416 duration_in_ms=1.375
2017-11-30 09:56:45,251 [salt.minion      ][INFO    ][23591] Returning information for job: 20171130095609849627
2017-11-30 09:56:46,264 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command test.ping with jid 20171130095646255040
2017-11-30 09:56:46,287 [salt.minion      ][INFO    ][26420] Starting a new job with PID 26420
2017-11-30 09:56:46,342 [salt.minion      ][INFO    ][26420] Returning information for job: 20171130095646255040
2017-11-30 09:57:29,001 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130095728989191
2017-11-30 09:57:29,023 [salt.minion      ][INFO    ][27059] Starting a new job with PID 27059
2017-11-30 09:57:30,635 [salt.state       ][INFO    ][27059] Loading fresh modules for state activity
2017-11-30 09:57:30,677 [salt.fileclient  ][INFO    ][27059] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-30 09:57:30,737 [salt.fileclient  ][INFO    ][27059] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 09:57:30,853 [salt.fileclient  ][INFO    ][27059] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 09:57:30,884 [salt.fileclient  ][INFO    ][27059] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 09:57:31,338 [salt.state       ][INFO    ][27059] Running state [attr] at time 09:57:31.337591
2017-11-30 09:57:31,338 [salt.state       ][INFO    ][27059] Executing state pkg.installed for attr
2017-11-30 09:57:31,338 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:57:31,809 [salt.state       ][INFO    ][27059] Package attr is already installed
2017-11-30 09:57:31,810 [salt.state       ][INFO    ][27059] Completed state [attr] at time 09:57:31.809924 duration_in_ms=472.332
2017-11-30 09:57:31,810 [salt.state       ][INFO    ][27059] Running state [glusterfs-server] at time 09:57:31.810326
2017-11-30 09:57:31,811 [salt.state       ][INFO    ][27059] Executing state pkg.installed for glusterfs-server
2017-11-30 09:57:31,815 [salt.state       ][INFO    ][27059] Package glusterfs-server is already installed
2017-11-30 09:57:31,815 [salt.state       ][INFO    ][27059] Completed state [glusterfs-server] at time 09:57:31.814792 duration_in_ms=4.466
2017-11-30 09:57:31,815 [salt.state       ][INFO    ][27059] Running state [bc] at time 09:57:31.815011
2017-11-30 09:57:31,815 [salt.state       ][INFO    ][27059] Executing state pkg.installed for bc
2017-11-30 09:57:31,819 [salt.state       ][INFO    ][27059] Package bc is already installed
2017-11-30 09:57:31,819 [salt.state       ][INFO    ][27059] Completed state [bc] at time 09:57:31.819055 duration_in_ms=4.044
2017-11-30 09:57:31,821 [salt.state       ][INFO    ][27059] Running state [glusterfs-server] at time 09:57:31.820558
2017-11-30 09:57:31,821 [salt.state       ][INFO    ][27059] Executing state service.running for glusterfs-server
2017-11-30 09:57:31,822 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 09:57:31,839 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-30 09:57:31,926 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 09:57:31,957 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:57:31,971 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:57:31,988 [salt.state       ][INFO    ][27059] The service glusterfs-server is already running
2017-11-30 09:57:31,989 [salt.state       ][INFO    ][27059] Completed state [glusterfs-server] at time 09:57:31.988648 duration_in_ms=168.087
2017-11-30 09:57:31,994 [salt.state       ][INFO    ][27059] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:57:31.994001
2017-11-30 09:57:31,995 [salt.state       ][INFO    ][27059] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 09:57:32,009 [salt.state       ][INFO    ][27059] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-30 09:57:32,010 [salt.state       ][INFO    ][27059] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:57:32.009482 duration_in_ms=15.481
2017-11-30 09:57:32,010 [salt.state       ][INFO    ][27059] Running state [/srv/glusterfs/glance] at time 09:57:32.009924
2017-11-30 09:57:32,010 [salt.state       ][INFO    ][27059] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 09:57:32,011 [salt.state       ][INFO    ][27059] Directory /srv/glusterfs/glance is in the correct state
2017-11-30 09:57:32,012 [salt.state       ][INFO    ][27059] Completed state [/srv/glusterfs/glance] at time 09:57:32.011589 duration_in_ms=1.664
2017-11-30 09:57:32,012 [salt.state       ][INFO    ][27059] Running state [/srv/glusterfs/keystone-keys] at time 09:57:32.012015
2017-11-30 09:57:32,012 [salt.state       ][INFO    ][27059] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 09:57:32,013 [salt.state       ][INFO    ][27059] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-30 09:57:32,014 [salt.state       ][INFO    ][27059] Completed state [/srv/glusterfs/keystone-keys] at time 09:57:32.013444 duration_in_ms=1.429
2017-11-30 09:57:32,014 [salt.state       ][INFO    ][27059] Running state [/srv/glusterfs/nova_instances] at time 09:57:32.013801
2017-11-30 09:57:32,014 [salt.state       ][INFO    ][27059] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 09:57:32,015 [salt.state       ][INFO    ][27059] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-30 09:57:32,015 [salt.state       ][INFO    ][27059] Completed state [/srv/glusterfs/nova_instances] at time 09:57:32.015176 duration_in_ms=1.376
2017-11-30 09:57:32,016 [salt.state       ][INFO    ][27059] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:57:32.015532
2017-11-30 09:57:32,016 [salt.state       ][INFO    ][27059] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 09:57:32,017 [salt.state       ][INFO    ][27059] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-30 09:57:32,017 [salt.state       ][INFO    ][27059] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:57:32.016927 duration_in_ms=1.395
2017-11-30 09:57:32,184 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command ['git', '--version'] in directory '/root'
2017-11-30 09:57:32,239 [salt.state       ][INFO    ][27059] Running state [sleep 5] at time 09:57:32.239308
2017-11-30 09:57:32,240 [salt.state       ][INFO    ][27059] Executing state cmd.wait for sleep 5
2017-11-30 09:57:32,240 [salt.state       ][INFO    ][27059] No changes made for sleep 5
2017-11-30 09:57:32,240 [salt.state       ][INFO    ][27059] Completed state [sleep 5] at time 09:57:32.240000 duration_in_ms=0.693
2017-11-30 09:57:32,240 [salt.state       ][INFO    ][27059] Running state [10.167.4.141] at time 09:57:32.240457
2017-11-30 09:57:32,241 [salt.state       ][INFO    ][27059] Executing state glusterfs.peered for 10.167.4.141
2017-11-30 09:57:32,241 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:32,257 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:32,371 [salt.state       ][INFO    ][27059] Host 10.167.4.141 already peered
2017-11-30 09:57:32,372 [salt.state       ][INFO    ][27059] Completed state [10.167.4.141] at time 09:57:32.371554 duration_in_ms=131.096
2017-11-30 09:57:32,377 [salt.state       ][INFO    ][27059] Running state [10.167.4.142] at time 09:57:32.377120
2017-11-30 09:57:32,378 [salt.state       ][INFO    ][27059] Executing state glusterfs.peered for 10.167.4.142
2017-11-30 09:57:32,378 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:32,395 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:32,512 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:32,530 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:32,643 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:32,663 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:32,782 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:32,799 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:32,910 [salt.state       ][INFO    ][27059] Peering with localhost is not needed
2017-11-30 09:57:32,911 [salt.state       ][INFO    ][27059] Completed state [10.167.4.142] at time 09:57:32.910625 duration_in_ms=533.504
2017-11-30 09:57:32,917 [salt.state       ][INFO    ][27059] Running state [10.167.4.143] at time 09:57:32.916455
2017-11-30 09:57:32,917 [salt.state       ][INFO    ][27059] Executing state glusterfs.peered for 10.167.4.143
2017-11-30 09:57:32,918 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:32,935 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:33,039 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:33,054 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:33,170 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:33,188 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:33,302 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:33,318 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:33,426 [salt.state       ][INFO    ][27059] {'new': {'kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local': ['10.167.4.143'], '10.167.4.141': []}, 'old': {'kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local': [], '10.167.4.141': []}}
2017-11-30 09:57:33,427 [salt.state       ][INFO    ][27059] Completed state [10.167.4.143] at time 09:57:33.426476 duration_in_ms=510.02
2017-11-30 09:57:33,429 [salt.state       ][INFO    ][27059] Running state [glance] at time 09:57:33.428592
2017-11-30 09:57:33,429 [salt.state       ][INFO    ][27059] Executing state glusterfs.created for glance
2017-11-30 09:57:33,430 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:33,447 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:33,562 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:33,580 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:33,698 [salt.state       ][INFO    ][27059] Volume glance already exists and is started.
2017-11-30 09:57:33,699 [salt.state       ][INFO    ][27059] Completed state [glance] at time 09:57:33.698720 duration_in_ms=270.126
2017-11-30 09:57:33,707 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:33.706631
2017-11-30 09:57:33,707 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:33,708 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:33,819 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:33,820 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:33.819731 duration_in_ms=113.099
2017-11-30 09:57:33,821 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:57:33.820915
2017-11-30 09:57:33,821 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-30 09:57:33,822 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:33,936 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:33,936 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:57:33.936182 duration_in_ms=115.266
2017-11-30 09:57:33,937 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:57:33.937390
2017-11-30 09:57:33,938 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-30 09:57:33,939 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:34,045 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:34,046 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:57:34.045913 duration_in_ms=108.522
2017-11-30 09:57:34,047 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:34.047059
2017-11-30 09:57:34,048 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:34,049 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:34,162 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:34,162 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:34.162350 duration_in_ms=115.289
2017-11-30 09:57:34,163 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:34.163294
2017-11-30 09:57:34,164 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:34,165 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:34,271 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:34,271 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:34.271390 duration_in_ms=108.095
2017-11-30 09:57:34,273 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:57:34.272658
2017-11-30 09:57:34,273 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:34,274 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:34,388 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:34,389 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:57:34.388886 duration_in_ms=116.227
2017-11-30 09:57:34,392 [salt.state       ][INFO    ][27059] Running state [glance] at time 09:57:34.392011
2017-11-30 09:57:34,393 [salt.state       ][INFO    ][27059] Executing state glusterfs.started for glance
2017-11-30 09:57:34,393 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:34,410 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:34,522 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:34,538 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:34,658 [salt.state       ][INFO    ][27059] Volume glance is already started
2017-11-30 09:57:34,659 [salt.state       ][INFO    ][27059] Completed state [glance] at time 09:57:34.658601 duration_in_ms=266.59
2017-11-30 09:57:34,661 [salt.state       ][INFO    ][27059] Running state [keystone-keys] at time 09:57:34.660548
2017-11-30 09:57:34,661 [salt.state       ][INFO    ][27059] Executing state glusterfs.created for keystone-keys
2017-11-30 09:57:34,662 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:34,678 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:34,792 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:34,810 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:34,932 [salt.state       ][INFO    ][27059] Volume keystone-keys already exists and is started.
2017-11-30 09:57:34,932 [salt.state       ][INFO    ][27059] Completed state [keystone-keys] at time 09:57:34.932202 duration_in_ms=271.653
2017-11-30 09:57:34,942 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:57:34.941864
2017-11-30 09:57:34,942 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-30 09:57:34,943 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:35,065 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:35,065 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:57:35.065090 duration_in_ms=123.225
2017-11-30 09:57:35,066 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:35.066185
2017-11-30 09:57:35,067 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:35,068 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:35,185 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:35,185 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:35.184971 duration_in_ms=118.785
2017-11-30 09:57:35,186 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:35.186137
2017-11-30 09:57:35,187 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:35,188 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:35,289 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:35,289 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:35.289232 duration_in_ms=103.094
2017-11-30 09:57:35,290 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:35.290421
2017-11-30 09:57:35,291 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:35,292 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:35,404 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:35,405 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:35.404632 duration_in_ms=114.211
2017-11-30 09:57:35,406 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:57:35.405724
2017-11-30 09:57:35,406 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-30 09:57:35,407 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:35,514 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:35,515 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:57:35.514439 duration_in_ms=108.714
2017-11-30 09:57:35,516 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:35.515587
2017-11-30 09:57:35,516 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:35,517 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:35,633 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:35,633 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:35.633184 duration_in_ms=117.597
2017-11-30 09:57:35,636 [salt.state       ][INFO    ][27059] Running state [keystone-keys] at time 09:57:35.636044
2017-11-30 09:57:35,637 [salt.state       ][INFO    ][27059] Executing state glusterfs.started for keystone-keys
2017-11-30 09:57:35,637 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:35,655 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:35,773 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:35,791 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:35,915 [salt.state       ][INFO    ][27059] Volume keystone-keys is already started
2017-11-30 09:57:35,916 [salt.state       ][INFO    ][27059] Completed state [keystone-keys] at time 09:57:35.915399 duration_in_ms=279.354
2017-11-30 09:57:35,918 [salt.state       ][INFO    ][27059] Running state [nova_instances] at time 09:57:35.917525
2017-11-30 09:57:35,918 [salt.state       ][INFO    ][27059] Executing state glusterfs.created for nova_instances
2017-11-30 09:57:35,919 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:35,936 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:36,048 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:36,064 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:36,178 [salt.state       ][INFO    ][27059] Volume nova_instances already exists and is started.
2017-11-30 09:57:36,179 [salt.state       ][INFO    ][27059] Completed state [nova_instances] at time 09:57:36.178622 duration_in_ms=261.097
2017-11-30 09:57:36,187 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:36.187163
2017-11-30 09:57:36,188 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:36,189 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:36,303 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:36,304 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:36.303464 duration_in_ms=116.3
2017-11-30 09:57:36,305 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:57:36.304689
2017-11-30 09:57:36,305 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:36,306 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:36,421 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:36,421 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:57:36.420949 duration_in_ms=116.259
2017-11-30 09:57:36,422 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:57:36.422031
2017-11-30 09:57:36,423 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-11-30 09:57:36,423 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:36,535 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:36,535 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:57:36.535275 duration_in_ms=113.242
2017-11-30 09:57:36,537 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:36.536496
2017-11-30 09:57:36,537 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:36,538 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:36,649 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:36,649 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:36.649354 duration_in_ms=112.857
2017-11-30 09:57:36,651 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:57:36.650452
2017-11-30 09:57:36,651 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-11-30 09:57:36,652 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:36,766 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:36,766 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:57:36.766222 duration_in_ms=115.769
2017-11-30 09:57:36,768 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:36.767425
2017-11-30 09:57:36,768 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:36,769 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:36,886 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:36,886 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:36.886120 duration_in_ms=118.695
2017-11-30 09:57:36,889 [salt.state       ][INFO    ][27059] Running state [nova_instances] at time 09:57:36.889005
2017-11-30 09:57:36,890 [salt.state       ][INFO    ][27059] Executing state glusterfs.started for nova_instances
2017-11-30 09:57:36,890 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:36,906 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:37,021 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:37,039 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:37,160 [salt.state       ][INFO    ][27059] Volume nova_instances is already started
2017-11-30 09:57:37,161 [salt.state       ][INFO    ][27059] Completed state [nova_instances] at time 09:57:37.160557 duration_in_ms=271.551
2017-11-30 09:57:37,163 [salt.state       ][INFO    ][27059] Running state [keystone-credential-keys] at time 09:57:37.162633
2017-11-30 09:57:37,163 [salt.state       ][INFO    ][27059] Executing state glusterfs.created for keystone-credential-keys
2017-11-30 09:57:37,164 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:37,182 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:37,299 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:37,315 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:37,433 [salt.state       ][INFO    ][27059] Volume keystone-credential-keys already exists and is started.
2017-11-30 09:57:37,434 [salt.state       ][INFO    ][27059] Completed state [keystone-credential-keys] at time 09:57:37.433654 duration_in_ms=271.021
2017-11-30 09:57:37,442 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:37.442243
2017-11-30 09:57:37,443 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:37,444 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:37,552 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:37,553 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:37.552915 duration_in_ms=110.671
2017-11-30 09:57:37,554 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:37.554127
2017-11-30 09:57:37,555 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:37,556 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:37,661 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:37,661 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:37.661095 duration_in_ms=106.967
2017-11-30 09:57:37,662 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:37.662268
2017-11-30 09:57:37,663 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:37,664 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:37,769 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:37,769 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:37.769327 duration_in_ms=107.059
2017-11-30 09:57:37,771 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:57:37.770503
2017-11-30 09:57:37,771 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-30 09:57:37,772 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:37,878 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:37,879 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:57:37.878865 duration_in_ms=108.361
2017-11-30 09:57:37,880 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:37.880063
2017-11-30 09:57:37,881 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:37,882 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:37,998 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:37,998 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:37.998126 duration_in_ms=118.062
2017-11-30 09:57:37,999 [salt.state       ][INFO    ][27059] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:57:37.999265
2017-11-30 09:57:37,1000 [salt.state       ][INFO    ][27059] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-30 09:57:38,001 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:38,113 [salt.state       ][INFO    ][27059] unless execution succeeded
2017-11-30 09:57:38,113 [salt.state       ][INFO    ][27059] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:57:38.113162 duration_in_ms=113.896
2017-11-30 09:57:38,116 [salt.state       ][INFO    ][27059] Running state [keystone-credential-keys] at time 09:57:38.116302
2017-11-30 09:57:38,117 [salt.state       ][INFO    ][27059] Executing state glusterfs.started for keystone-credential-keys
2017-11-30 09:57:38,118 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:38,136 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:38,267 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:38,285 [salt.loaded.int.module.cmdmod][INFO    ][27059] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:38,422 [salt.state       ][INFO    ][27059] Volume keystone-credential-keys is already started
2017-11-30 09:57:38,423 [salt.state       ][INFO    ][27059] Completed state [keystone-credential-keys] at time 09:57:38.422442 duration_in_ms=306.139
2017-11-30 09:57:38,426 [salt.minion      ][INFO    ][27059] Returning information for job: 20171130095728989191
2017-11-30 09:57:47,383 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command test.ping with jid 20171130095747376510
2017-11-30 09:57:47,406 [salt.minion      ][INFO    ][27619] Starting a new job with PID 27619
2017-11-30 09:57:47,474 [salt.minion      ][INFO    ][27619] Returning information for job: 20171130095747376510
2017-11-30 09:57:47,989 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command cmd.run with jid 20171130095747977678
2017-11-30 09:57:48,010 [salt.minion      ][INFO    ][27625] Starting a new job with PID 27625
2017-11-30 09:57:48,018 [salt.loaded.int.module.cmdmod][INFO    ][27625] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-30 09:57:48,248 [salt.minion      ][INFO    ][27625] Returning information for job: 20171130095747977678
2017-11-30 10:29:39,720 [salt.utils.schedule][INFO    ][21259] Running scheduled job: __mine_interval
2017-11-30 11:11:33,393 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command test.ping with jid 20171130111133382649
2017-11-30 11:11:33,422 [salt.minion      ][INFO    ][28605] Starting a new job with PID 28605
2017-11-30 11:11:33,488 [salt.minion      ][INFO    ][28605] Returning information for job: 20171130111133382649
2017-11-30 11:11:48,886 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130111148870900
2017-11-30 11:11:48,908 [salt.minion      ][INFO    ][28610] Starting a new job with PID 28610
2017-11-30 11:11:49,457 [salt.state       ][INFO    ][28610] Loading fresh modules for state activity
2017-11-30 11:11:49,505 [salt.fileclient  ][INFO    ][28610] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-30 11:11:49,533 [salt.fileclient  ][INFO    ][28610] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-30 11:11:49,572 [salt.fileclient  ][INFO    ][28610] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 11:11:49,916 [salt.state       ][INFO    ][28610] Running state [lsof] at time 11:11:49.915889
2017-11-30 11:11:49,916 [salt.state       ][INFO    ][28610] Executing state pkg.installed for lsof
2017-11-30 11:11:49,917 [salt.loaded.int.module.cmdmod][INFO    ][28610] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 11:11:50,364 [salt.state       ][INFO    ][28610] Package lsof is already installed
2017-11-30 11:11:50,364 [salt.state       ][INFO    ][28610] Completed state [lsof] at time 11:11:50.363934 duration_in_ms=448.045
2017-11-30 11:11:50,364 [salt.state       ][INFO    ][28610] Running state [keepalived] at time 11:11:50.364241
2017-11-30 11:11:50,365 [salt.state       ][INFO    ][28610] Executing state pkg.installed for keepalived
2017-11-30 11:11:50,369 [salt.state       ][INFO    ][28610] Package keepalived is already installed
2017-11-30 11:11:50,369 [salt.state       ][INFO    ][28610] Completed state [keepalived] at time 11:11:50.368728 duration_in_ms=4.487
2017-11-30 11:11:50,371 [salt.state       ][INFO    ][28610] Running state [/etc/keepalived/keepalived.conf] at time 11:11:50.370720
2017-11-30 11:11:50,371 [salt.state       ][INFO    ][28610] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-30 11:11:50,451 [salt.fileclient  ][INFO    ][28610] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-30 11:11:50,499 [salt.fileclient  ][INFO    ][28610] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 11:11:50,506 [salt.state       ][INFO    ][28610] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-30 11:11:50,506 [salt.state       ][INFO    ][28610] Completed state [/etc/keepalived/keepalived.conf] at time 11:11:50.505916 duration_in_ms=135.195
2017-11-30 11:11:50,507 [salt.state       ][INFO    ][28610] Running state [keepalived] at time 11:11:50.506820
2017-11-30 11:11:50,507 [salt.state       ][INFO    ][28610] Executing state service.running for keepalived
2017-11-30 11:11:50,508 [salt.loaded.int.module.cmdmod][INFO    ][28610] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-30 11:11:50,538 [salt.loaded.int.module.cmdmod][INFO    ][28610] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-30 11:11:50,552 [salt.loaded.int.module.cmdmod][INFO    ][28610] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 11:11:50,567 [salt.state       ][INFO    ][28610] The service keepalived is already running
2017-11-30 11:11:50,567 [salt.state       ][INFO    ][28610] Completed state [keepalived] at time 11:11:50.566930 duration_in_ms=60.109
2017-11-30 11:11:50,569 [salt.minion      ][INFO    ][28610] Returning information for job: 20171130111148870900
2017-11-30 11:12:13,097 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command pillar.get with jid 20171130111213082415
2017-11-30 11:12:13,123 [salt.minion      ][INFO    ][28635] Starting a new job with PID 28635
2017-11-30 11:12:13,130 [salt.minion      ][INFO    ][28635] Returning information for job: 20171130111213082415
2017-11-30 11:13:11,082 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130111311066848
2017-11-30 11:13:11,105 [salt.minion      ][INFO    ][28640] Starting a new job with PID 28640
2017-11-30 11:13:13,607 [salt.state       ][INFO    ][28640] Loading fresh modules for state activity
2017-11-30 11:13:13,642 [salt.fileclient  ][INFO    ][28640] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 11:13:13,673 [salt.fileclient  ][INFO    ][28640] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 11:13:14,067 [salt.state       ][INFO    ][28640] Running state [attr] at time 11:13:14.066560
2017-11-30 11:13:14,067 [salt.state       ][INFO    ][28640] Executing state pkg.installed for attr
2017-11-30 11:13:14,067 [salt.loaded.int.module.cmdmod][INFO    ][28640] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 11:13:14,468 [salt.state       ][INFO    ][28640] Package attr is already installed
2017-11-30 11:13:14,468 [salt.state       ][INFO    ][28640] Completed state [attr] at time 11:13:14.468200 duration_in_ms=401.641
2017-11-30 11:13:14,468 [salt.state       ][INFO    ][28640] Running state [glusterfs-server] at time 11:13:14.468461
2017-11-30 11:13:14,469 [salt.state       ][INFO    ][28640] Executing state pkg.installed for glusterfs-server
2017-11-30 11:13:14,471 [salt.state       ][INFO    ][28640] Package glusterfs-server is already installed
2017-11-30 11:13:14,472 [salt.state       ][INFO    ][28640] Completed state [glusterfs-server] at time 11:13:14.471548 duration_in_ms=3.087
2017-11-30 11:13:14,472 [salt.state       ][INFO    ][28640] Running state [bc] at time 11:13:14.471714
2017-11-30 11:13:14,472 [salt.state       ][INFO    ][28640] Executing state pkg.installed for bc
2017-11-30 11:13:14,475 [salt.state       ][INFO    ][28640] Package bc is already installed
2017-11-30 11:13:14,475 [salt.state       ][INFO    ][28640] Completed state [bc] at time 11:13:14.474713 duration_in_ms=2.998
2017-11-30 11:13:14,475 [salt.state       ][INFO    ][28640] Running state [glusterfs-server] at time 11:13:14.475398
2017-11-30 11:13:14,476 [salt.state       ][INFO    ][28640] Executing state service.running for glusterfs-server
2017-11-30 11:13:14,476 [salt.loaded.int.module.cmdmod][INFO    ][28640] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 11:13:14,504 [salt.loaded.int.module.cmdmod][INFO    ][28640] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:14,517 [salt.loaded.int.module.cmdmod][INFO    ][28640] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:14,532 [salt.state       ][INFO    ][28640] The service glusterfs-server is already running
2017-11-30 11:13:14,532 [salt.state       ][INFO    ][28640] Completed state [glusterfs-server] at time 11:13:14.532268 duration_in_ms=56.868
2017-11-30 11:13:14,535 [salt.state       ][INFO    ][28640] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:14.535318
2017-11-30 11:13:14,536 [salt.state       ][INFO    ][28640] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 11:13:14,551 [salt.state       ][INFO    ][28640] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-30 11:13:14,551 [salt.state       ][INFO    ][28640] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:14.550889 duration_in_ms=15.57
2017-11-30 11:13:14,551 [salt.state       ][INFO    ][28640] Running state [/srv/glusterfs/glance] at time 11:13:14.551305
2017-11-30 11:13:14,552 [salt.state       ][INFO    ][28640] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 11:13:14,553 [salt.state       ][INFO    ][28640] Directory /srv/glusterfs/glance is in the correct state
2017-11-30 11:13:14,553 [salt.state       ][INFO    ][28640] Completed state [/srv/glusterfs/glance] at time 11:13:14.552924 duration_in_ms=1.618
2017-11-30 11:13:14,553 [salt.state       ][INFO    ][28640] Running state [/srv/glusterfs/keystone-keys] at time 11:13:14.553311
2017-11-30 11:13:14,554 [salt.state       ][INFO    ][28640] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 11:13:14,555 [salt.state       ][INFO    ][28640] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-30 11:13:14,555 [salt.state       ][INFO    ][28640] Completed state [/srv/glusterfs/keystone-keys] at time 11:13:14.554828 duration_in_ms=1.515
2017-11-30 11:13:14,555 [salt.state       ][INFO    ][28640] Running state [/srv/glusterfs/nova_instances] at time 11:13:14.555205
2017-11-30 11:13:14,556 [salt.state       ][INFO    ][28640] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 11:13:14,557 [salt.state       ][INFO    ][28640] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-30 11:13:14,557 [salt.state       ][INFO    ][28640] Completed state [/srv/glusterfs/nova_instances] at time 11:13:14.556780 duration_in_ms=1.575
2017-11-30 11:13:14,557 [salt.state       ][INFO    ][28640] Running state [/srv/glusterfs/keystone-credential-keys] at time 11:13:14.557166
2017-11-30 11:13:14,558 [salt.state       ][INFO    ][28640] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 11:13:14,558 [salt.state       ][INFO    ][28640] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-30 11:13:14,559 [salt.state       ][INFO    ][28640] Completed state [/srv/glusterfs/keystone-credential-keys] at time 11:13:14.558675 duration_in_ms=1.507
2017-11-30 11:13:14,560 [salt.minion      ][INFO    ][28640] Returning information for job: 20171130111311066848
2017-11-30 11:13:15,803 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command test.ping with jid 20171130111315788609
2017-11-30 11:13:15,825 [salt.minion      ][INFO    ][28663] Starting a new job with PID 28663
2017-11-30 11:13:15,879 [salt.minion      ][INFO    ][28663] Returning information for job: 20171130111315788609
2017-11-30 11:13:23,659 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command state.sls with jid 20171130111323644914
2017-11-30 11:13:23,681 [salt.minion      ][INFO    ][28668] Starting a new job with PID 28668
2017-11-30 11:13:24,189 [salt.state       ][INFO    ][28668] Loading fresh modules for state activity
2017-11-30 11:13:24,232 [salt.fileclient  ][INFO    ][28668] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-30 11:13:24,293 [salt.fileclient  ][INFO    ][28668] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 11:13:24,406 [salt.fileclient  ][INFO    ][28668] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 11:13:24,429 [salt.fileclient  ][INFO    ][28668] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 11:13:24,864 [salt.state       ][INFO    ][28668] Running state [attr] at time 11:13:24.864426
2017-11-30 11:13:24,865 [salt.state       ][INFO    ][28668] Executing state pkg.installed for attr
2017-11-30 11:13:24,865 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 11:13:25,369 [salt.state       ][INFO    ][28668] Package attr is already installed
2017-11-30 11:13:25,370 [salt.state       ][INFO    ][28668] Completed state [attr] at time 11:13:25.369446 duration_in_ms=505.019
2017-11-30 11:13:25,370 [salt.state       ][INFO    ][28668] Running state [glusterfs-server] at time 11:13:25.369888
2017-11-30 11:13:25,370 [salt.state       ][INFO    ][28668] Executing state pkg.installed for glusterfs-server
2017-11-30 11:13:25,374 [salt.state       ][INFO    ][28668] Package glusterfs-server is already installed
2017-11-30 11:13:25,375 [salt.state       ][INFO    ][28668] Completed state [glusterfs-server] at time 11:13:25.374608 duration_in_ms=4.72
2017-11-30 11:13:25,375 [salt.state       ][INFO    ][28668] Running state [bc] at time 11:13:25.374856
2017-11-30 11:13:25,375 [salt.state       ][INFO    ][28668] Executing state pkg.installed for bc
2017-11-30 11:13:25,379 [salt.state       ][INFO    ][28668] Package bc is already installed
2017-11-30 11:13:25,379 [salt.state       ][INFO    ][28668] Completed state [bc] at time 11:13:25.379143 duration_in_ms=4.286
2017-11-30 11:13:25,381 [salt.state       ][INFO    ][28668] Running state [glusterfs-server] at time 11:13:25.380741
2017-11-30 11:13:25,381 [salt.state       ][INFO    ][28668] Executing state service.running for glusterfs-server
2017-11-30 11:13:25,382 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 11:13:25,415 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:25,431 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:25,449 [salt.state       ][INFO    ][28668] The service glusterfs-server is already running
2017-11-30 11:13:25,450 [salt.state       ][INFO    ][28668] Completed state [glusterfs-server] at time 11:13:25.449793 duration_in_ms=69.049
2017-11-30 11:13:25,454 [salt.state       ][INFO    ][28668] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:25.453660
2017-11-30 11:13:25,454 [salt.state       ][INFO    ][28668] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 11:13:25,468 [salt.state       ][INFO    ][28668] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-30 11:13:25,469 [salt.state       ][INFO    ][28668] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:25.468599 duration_in_ms=14.939
2017-11-30 11:13:25,469 [salt.state       ][INFO    ][28668] Running state [/srv/glusterfs/glance] at time 11:13:25.469002
2017-11-30 11:13:25,469 [salt.state       ][INFO    ][28668] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 11:13:25,470 [salt.state       ][INFO    ][28668] Directory /srv/glusterfs/glance is in the correct state
2017-11-30 11:13:25,471 [salt.state       ][INFO    ][28668] Completed state [/srv/glusterfs/glance] at time 11:13:25.470548 duration_in_ms=1.545
2017-11-30 11:13:25,471 [salt.state       ][INFO    ][28668] Running state [/srv/glusterfs/keystone-keys] at time 11:13:25.470945
2017-11-30 11:13:25,471 [salt.state       ][INFO    ][28668] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 11:13:25,472 [salt.state       ][INFO    ][28668] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-30 11:13:25,473 [salt.state       ][INFO    ][28668] Completed state [/srv/glusterfs/keystone-keys] at time 11:13:25.472505 duration_in_ms=1.559
2017-11-30 11:13:25,473 [salt.state       ][INFO    ][28668] Running state [/srv/glusterfs/nova_instances] at time 11:13:25.472887
2017-11-30 11:13:25,473 [salt.state       ][INFO    ][28668] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 11:13:25,474 [salt.state       ][INFO    ][28668] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-30 11:13:25,474 [salt.state       ][INFO    ][28668] Completed state [/srv/glusterfs/nova_instances] at time 11:13:25.474390 duration_in_ms=1.504
2017-11-30 11:13:25,475 [salt.state       ][INFO    ][28668] Running state [/srv/glusterfs/keystone-credential-keys] at time 11:13:25.474774
2017-11-30 11:13:25,475 [salt.state       ][INFO    ][28668] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 11:13:25,476 [salt.state       ][INFO    ][28668] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-30 11:13:25,476 [salt.state       ][INFO    ][28668] Completed state [/srv/glusterfs/keystone-credential-keys] at time 11:13:25.476301 duration_in_ms=1.527
2017-11-30 11:13:25,611 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command ['git', '--version'] in directory '/root'
2017-11-30 11:13:25,659 [salt.state       ][INFO    ][28668] Running state [sleep 5] at time 11:13:25.659313
2017-11-30 11:13:25,660 [salt.state       ][INFO    ][28668] Executing state cmd.wait for sleep 5
2017-11-30 11:13:25,660 [salt.state       ][INFO    ][28668] No changes made for sleep 5
2017-11-30 11:13:25,660 [salt.state       ][INFO    ][28668] Completed state [sleep 5] at time 11:13:25.660051 duration_in_ms=0.738
2017-11-30 11:13:25,661 [salt.state       ][INFO    ][28668] Running state [10.167.4.141] at time 11:13:25.660497
2017-11-30 11:13:25,661 [salt.state       ][INFO    ][28668] Executing state glusterfs.peered for 10.167.4.141
2017-11-30 11:13:25,661 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:25,677 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:25,785 [salt.state       ][INFO    ][28668] Host 10.167.4.141 already peered
2017-11-30 11:13:25,786 [salt.state       ][INFO    ][28668] Completed state [10.167.4.141] at time 11:13:25.785625 duration_in_ms=125.126
2017-11-30 11:13:25,791 [salt.state       ][INFO    ][28668] Running state [10.167.4.142] at time 11:13:25.791071
2017-11-30 11:13:25,792 [salt.state       ][INFO    ][28668] Executing state glusterfs.peered for 10.167.4.142
2017-11-30 11:13:25,792 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:25,811 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:25,914 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:25,932 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:26,041 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:26,059 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:26,169 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:26,185 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:26,301 [salt.state       ][INFO    ][28668] Peering with localhost is not needed
2017-11-30 11:13:26,301 [salt.state       ][INFO    ][28668] Completed state [10.167.4.142] at time 11:13:26.301347 duration_in_ms=510.276
2017-11-30 11:13:26,307 [salt.state       ][INFO    ][28668] Running state [10.167.4.143] at time 11:13:26.306763
2017-11-30 11:13:26,307 [salt.state       ][INFO    ][28668] Executing state glusterfs.peered for 10.167.4.143
2017-11-30 11:13:26,308 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:26,326 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:26,442 [salt.state       ][INFO    ][28668] Host 10.167.4.143 already peered
2017-11-30 11:13:26,442 [salt.state       ][INFO    ][28668] Completed state [10.167.4.143] at time 11:13:26.442224 duration_in_ms=135.461
2017-11-30 11:13:26,444 [salt.state       ][INFO    ][28668] Running state [glance] at time 11:13:26.444212
2017-11-30 11:13:26,445 [salt.state       ][INFO    ][28668] Executing state glusterfs.created for glance
2017-11-30 11:13:26,446 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:26,461 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:26,567 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:26,585 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:26,699 [salt.state       ][INFO    ][28668] Volume glance already exists and is started.
2017-11-30 11:13:26,700 [salt.state       ][INFO    ][28668] Completed state [glance] at time 11:13:26.699937 duration_in_ms=255.724
2017-11-30 11:13:26,708 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:26.708381
2017-11-30 11:13:26,709 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:26,710 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:26,824 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:26,825 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:26.824697 duration_in_ms=116.315
2017-11-30 11:13:26,826 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 11:13:26.825515
2017-11-30 11:13:26,826 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-30 11:13:26,827 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:26,938 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:26,938 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 11:13:26.938148 duration_in_ms=112.632
2017-11-30 11:13:26,939 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 11:13:26.939011
2017-11-30 11:13:26,939 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-30 11:13:26,940 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:27,032 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:27,033 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 11:13:27.032485 duration_in_ms=93.472
2017-11-30 11:13:27,034 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:27.033668
2017-11-30 11:13:27,034 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:27,035 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:27,150 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:27,151 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:27.150446 duration_in_ms=116.778
2017-11-30 11:13:27,152 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:27.151595
2017-11-30 11:13:27,152 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:27,153 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:27,256 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:27,257 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:27.256802 duration_in_ms=105.206
2017-11-30 11:13:27,258 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 11:13:27.257973
2017-11-30 11:13:27,259 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:27,260 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:27,371 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:27,371 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 11:13:27.371040 duration_in_ms=113.067
2017-11-30 11:13:27,374 [salt.state       ][INFO    ][28668] Running state [glance] at time 11:13:27.374112
2017-11-30 11:13:27,375 [salt.state       ][INFO    ][28668] Executing state glusterfs.started for glance
2017-11-30 11:13:27,376 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:27,392 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:27,512 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:27,529 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:27,648 [salt.state       ][INFO    ][28668] Volume glance is already started
2017-11-30 11:13:27,648 [salt.state       ][INFO    ][28668] Completed state [glance] at time 11:13:27.648364 duration_in_ms=274.251
2017-11-30 11:13:27,650 [salt.state       ][INFO    ][28668] Running state [keystone-keys] at time 11:13:27.650303
2017-11-30 11:13:27,651 [salt.state       ][INFO    ][28668] Executing state glusterfs.created for keystone-keys
2017-11-30 11:13:27,652 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:27,669 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:27,777 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:27,797 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:27,912 [salt.state       ][INFO    ][28668] Volume keystone-keys already exists and is started.
2017-11-30 11:13:27,913 [salt.state       ][INFO    ][28668] Completed state [keystone-keys] at time 11:13:27.912825 duration_in_ms=262.521
2017-11-30 11:13:27,922 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 11:13:27.922374
2017-11-30 11:13:27,923 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-30 11:13:27,924 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:28,036 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:28,037 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 11:13:28.036752 duration_in_ms=114.379
2017-11-30 11:13:28,038 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:28.037868
2017-11-30 11:13:28,038 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:28,039 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:28,146 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:28,146 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:28.146397 duration_in_ms=108.529
2017-11-30 11:13:28,148 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:28.147489
2017-11-30 11:13:28,148 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:28,149 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:28,255 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:28,255 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:28.255052 duration_in_ms=107.562
2017-11-30 11:13:28,256 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:28.256230
2017-11-30 11:13:28,257 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:28,258 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:28,344 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:28,344 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:28.344265 duration_in_ms=88.035
2017-11-30 11:13:28,345 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 11:13:28.345409
2017-11-30 11:13:28,346 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-30 11:13:28,347 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:28,465 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:28,466 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 11:13:28.465547 duration_in_ms=120.137
2017-11-30 11:13:28,467 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:28.466703
2017-11-30 11:13:28,467 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:28,468 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:28,567 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:28,568 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:28.567694 duration_in_ms=100.99
2017-11-30 11:13:28,571 [salt.state       ][INFO    ][28668] Running state [keystone-keys] at time 11:13:28.570946
2017-11-30 11:13:28,571 [salt.state       ][INFO    ][28668] Executing state glusterfs.started for keystone-keys
2017-11-30 11:13:28,572 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:28,589 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:28,702 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:28,718 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:28,839 [salt.state       ][INFO    ][28668] Volume keystone-keys is already started
2017-11-30 11:13:28,840 [salt.state       ][INFO    ][28668] Completed state [keystone-keys] at time 11:13:28.839893 duration_in_ms=268.946
2017-11-30 11:13:28,842 [salt.state       ][INFO    ][28668] Running state [nova_instances] at time 11:13:28.841844
2017-11-30 11:13:28,842 [salt.state       ][INFO    ][28668] Executing state glusterfs.created for nova_instances
2017-11-30 11:13:28,843 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:28,858 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:28,973 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:28,992 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:29,121 [salt.state       ][INFO    ][28668] Volume nova_instances already exists and is started.
2017-11-30 11:13:29,121 [salt.state       ][INFO    ][28668] Completed state [nova_instances] at time 11:13:29.121300 duration_in_ms=279.455
2017-11-30 11:13:29,130 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:29.130368
2017-11-30 11:13:29,131 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:29,132 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:29,232 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:29,240 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:29.239499 duration_in_ms=109.13
2017-11-30 11:13:29,241 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 11:13:29.240806
2017-11-30 11:13:29,241 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:29,242 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:29,353 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:29,353 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 11:13:29.353093 duration_in_ms=112.287
2017-11-30 11:13:29,354 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 11:13:29.354061
2017-11-30 11:13:29,355 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-11-30 11:13:29,355 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:29,468 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:29,469 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 11:13:29.468744 duration_in_ms=114.682
2017-11-30 11:13:29,470 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:29.469879
2017-11-30 11:13:29,470 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:29,471 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:29,572 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:29,572 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:29.572382 duration_in_ms=102.504
2017-11-30 11:13:29,573 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 11:13:29.573395
2017-11-30 11:13:29,574 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-11-30 11:13:29,575 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:29,684 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:29,685 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 11:13:29.684673 duration_in_ms=111.278
2017-11-30 11:13:29,686 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:29.685819
2017-11-30 11:13:29,686 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:29,687 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:29,798 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:29,798 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:29.798222 duration_in_ms=112.402
2017-11-30 11:13:29,801 [salt.state       ][INFO    ][28668] Running state [nova_instances] at time 11:13:29.801288
2017-11-30 11:13:29,802 [salt.state       ][INFO    ][28668] Executing state glusterfs.started for nova_instances
2017-11-30 11:13:29,803 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:29,819 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:29,932 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:29,948 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:30,066 [salt.state       ][INFO    ][28668] Volume nova_instances is already started
2017-11-30 11:13:30,067 [salt.state       ][INFO    ][28668] Completed state [nova_instances] at time 11:13:30.066653 duration_in_ms=265.364
2017-11-30 11:13:30,069 [salt.state       ][INFO    ][28668] Running state [keystone-credential-keys] at time 11:13:30.068989
2017-11-30 11:13:30,070 [salt.state       ][INFO    ][28668] Executing state glusterfs.created for keystone-credential-keys
2017-11-30 11:13:30,071 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:30,088 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:30,203 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:30,222 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:30,335 [salt.state       ][INFO    ][28668] Volume keystone-credential-keys already exists and is started.
2017-11-30 11:13:30,335 [salt.state       ][INFO    ][28668] Completed state [keystone-credential-keys] at time 11:13:30.335147 duration_in_ms=266.158
2017-11-30 11:13:30,345 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:30.344491
2017-11-30 11:13:30,345 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:30,346 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:30,433 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:30,434 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:30.433617 duration_in_ms=89.124
2017-11-30 11:13:30,435 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:30.434865
2017-11-30 11:13:30,435 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:30,436 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:30,552 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:30,553 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:30.552460 duration_in_ms=117.594
2017-11-30 11:13:30,554 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:30.553662
2017-11-30 11:13:30,554 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:30,555 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:30,673 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:30,674 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:30.673551 duration_in_ms=119.888
2017-11-30 11:13:30,675 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 11:13:30.674784
2017-11-30 11:13:30,675 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-30 11:13:30,676 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:30,782 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:30,783 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 11:13:30.782547 duration_in_ms=107.762
2017-11-30 11:13:30,784 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:30.783777
2017-11-30 11:13:30,784 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:30,785 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:30,901 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:30,902 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:30.901896 duration_in_ms=118.118
2017-11-30 11:13:30,903 [salt.state       ][INFO    ][28668] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 11:13:30.902994
2017-11-30 11:13:30,904 [salt.state       ][INFO    ][28668] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-30 11:13:30,905 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:31,011 [salt.state       ][INFO    ][28668] unless execution succeeded
2017-11-30 11:13:31,011 [salt.state       ][INFO    ][28668] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 11:13:31.011410 duration_in_ms=108.415
2017-11-30 11:13:31,015 [salt.state       ][INFO    ][28668] Running state [keystone-credential-keys] at time 11:13:31.014462
2017-11-30 11:13:31,015 [salt.state       ][INFO    ][28668] Executing state glusterfs.started for keystone-credential-keys
2017-11-30 11:13:31,016 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:31,033 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:31,138 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:31,155 [salt.loaded.int.module.cmdmod][INFO    ][28668] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:31,273 [salt.state       ][INFO    ][28668] Volume keystone-credential-keys is already started
2017-11-30 11:13:31,274 [salt.state       ][INFO    ][28668] Completed state [keystone-credential-keys] at time 11:13:31.273884 duration_in_ms=259.42
2017-11-30 11:13:31,277 [salt.minion      ][INFO    ][28668] Returning information for job: 20171130111323644914
2017-11-30 11:13:40,094 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command test.ping with jid 20171130111340078877
2017-11-30 11:13:40,118 [salt.minion      ][INFO    ][29179] Starting a new job with PID 29179
2017-11-30 11:13:40,171 [salt.minion      ][INFO    ][29179] Returning information for job: 20171130111340078877
2017-11-30 11:13:40,669 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command cmd.run with jid 20171130111340654095
2017-11-30 11:13:40,690 [salt.minion      ][INFO    ][29184] Starting a new job with PID 29184
2017-11-30 11:13:40,699 [salt.loaded.int.module.cmdmod][INFO    ][29184] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-30 11:13:40,936 [salt.minion      ][INFO    ][29184] Returning information for job: 20171130111340654095
2017-11-30 11:29:39,720 [salt.utils.schedule][INFO    ][21259] Running scheduled job: __mine_interval
2017-11-30 11:52:13,909 [salt.minion      ][INFO    ][21259] User sudo_ubuntu Executing command cp.push_dir with jid 20171130115213896513
2017-11-30 11:52:13,935 [salt.minion      ][INFO    ][29468] Starting a new job with PID 29468
