2017-12-02 02:37:06,132 [salt.cli.daemons ][WARNING ][2420] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-02 02:37:44,905 [salt.state       ][ERROR   ][3670] No contents found in top file
2017-12-02 02:39:17,751 [salt.utils.parsers][WARNING ][2420] Minion received a SIGTERM. Exiting.
2017-12-02 02:41:42,795 [salt.cli.daemons ][WARNING ][3303] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-02 02:44:52,081 [py.warnings      ][WARNING ][13354] /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-12-02 02:46:13,380 [salt.loaded.int.module.cmdmod][ERROR   ][13354] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-02 02:46:13,381 [salt.loaded.int.module.cmdmod][ERROR   ][13354] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-02 02:46:19,464 [salt.loaded.int.module.cmdmod][ERROR   ][13354] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-02 02:46:19,465 [salt.loaded.int.module.cmdmod][ERROR   ][13354] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-02 02:46:27,229 [salt.loaded.int.module.cmdmod][ERROR   ][13354] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-02 02:46:27,229 [salt.loaded.int.module.cmdmod][ERROR   ][13354] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-02 02:46:36,118 [salt.loaded.int.module.cmdmod][ERROR   ][13354] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-02 02:46:36,119 [salt.loaded.int.module.cmdmod][ERROR   ][13354] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-02 02:46:42,196 [salt.loaded.int.module.cmdmod][ERROR   ][13354] 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-12-02 02:46:44,323 [salt.loaded.int.module.cmdmod][INFO    ][20412] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-02 02:46:44,329 [salt.loaded.int.module.cmdmod][INFO    ][20412] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-02 02:46:44,337 [salt.loaded.int.module.cmdmod][INFO    ][20412] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-12-02 02:46:44,343 [salt.utils.parsers][WARNING ][3303] Minion received a SIGTERM. Exiting.
2017-12-02 02:46:44,611 [salt.cli.daemons ][INFO    ][20486] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-ovs-ha.local"
2017-12-02 02:46:45,107 [salt.minion      ][INFO    ][20486] Creating minion process manager
2017-12-02 02:46:45,107 [salt.cli.daemons ][WARNING ][20486] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-02 02:46:45,108 [salt.cli.daemons ][INFO    ][20486] The Salt Minion is starting up
2017-12-02 02:46:45,108 [salt.minion      ][INFO    ][20486] Minion is starting as user 'root'
2017-12-02 02:46:45,108 [salt.utils.event ][INFO    ][20486] Starting pull socket on /var/run/salt/minion/minion_event_a1b2f32648_pull.ipc
2017-12-02 02:46:45,955 [salt.loaded.int.module.cmdmod][INFO    ][20486] Executing command ['date', '+%z'] in directory '/root'
2017-12-02 02:46:45,963 [salt.utils.schedule][INFO    ][20486] Updating job settings for scheduled job: __mine_interval
2017-12-02 02:46:45,965 [salt.minion      ][INFO    ][20486] Added mine.update to scheduler
2017-12-02 02:46:46,176 [salt.minion      ][INFO    ][20486] Minion is ready to receive requests!
2017-12-02 02:46:47,178 [salt.utils.schedule][INFO    ][20486] Running scheduled job: __mine_interval
2017-12-02 02:59:51,031 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171202025951015928
2017-12-02 02:59:51,047 [salt.minion      ][INFO    ][20799] Starting a new job with PID 20799
2017-12-02 02:59:53,492 [salt.state       ][INFO    ][20799] Loading fresh modules for state activity
2017-12-02 02:59:53,519 [salt.state       ][ERROR   ][20799] No contents found in top file
2017-12-02 02:59:53,520 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-12-02 02:59:53,523 [salt.utils.extmods][INFO    ][20799] Syncing beacons for environment 'base'
2017-12-02 02:59:53,523 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_beacons, for base)
2017-12-02 02:59:53,523 [salt.fileclient  ][INFO    ][20799] Caching directory '_beacons/' for environment 'base'
2017-12-02 02:59:53,550 [salt.utils.extmods][INFO    ][20799] Syncing modules for environment 'base'
2017-12-02 02:59:53,551 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_modules, for base)
2017-12-02 02:59:53,551 [salt.fileclient  ][INFO    ][20799] Caching directory '_modules/' for environment 'base'
2017-12-02 02:59:53,597 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-12-02 02:59:53,614 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-12-02 02:59:53,635 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-12-02 02:59:53,650 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-12-02 02:59:53,663 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-12-02 02:59:53,673 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-12-02 02:59:53,685 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-12-02 02:59:53,699 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-12-02 02:59:53,709 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-12-02 02:59:53,918 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-12-02 02:59:53,928 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-12-02 02:59:53,939 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-12-02 02:59:53,960 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-12-02 02:59:53,970 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-12-02 02:59:53,980 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-12-02 02:59:53,991 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-12-02 02:59:54,002 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-12-02 02:59:54,012 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-12-02 02:59:54,023 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-12-02 02:59:54,048 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-12-02 02:59:54,058 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-12-02 02:59:54,083 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-12-02 02:59:54,096 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-12-02 02:59:54,108 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-12-02 02:59:54,118 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-12-02 02:59:54,130 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-12-02 02:59:54,143 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-12-02 02:59:54,162 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-12-02 02:59:54,187 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-12-02 02:59:54,207 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-12-02 02:59:54,221 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-12-02 02:59:54,233 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-12-02 02:59:54,245 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-12-02 02:59:54,257 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-12-02 02:59:54,260 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-12-02 02:59:54,261 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-12-02 02:59:54,286 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-12-02 02:59:54,287 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-12-02 02:59:54,287 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-12-02 02:59:54,288 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-12-02 02:59:54,288 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-12-02 02:59:54,289 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-12-02 02:59:54,289 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-12-02 02:59:54,290 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-12-02 02:59:54,290 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-12-02 02:59:54,291 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-12-02 02:59:54,291 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-12-02 02:59:54,297 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-12-02 02:59:54,301 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-12-02 02:59:54,302 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-12-02 02:59:54,302 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-12-02 02:59:54,303 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-12-02 02:59:54,303 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-12-02 02:59:54,304 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-12-02 02:59:54,304 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-12-02 02:59:54,305 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-12-02 02:59:54,305 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-12-02 02:59:54,306 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-12-02 02:59:54,307 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-12-02 02:59:54,308 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-12-02 02:59:54,309 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-12-02 02:59:54,309 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-12-02 02:59:54,310 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-12-02 02:59:54,310 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-12-02 02:59:54,311 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-12-02 02:59:54,311 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-12-02 02:59:54,311 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-12-02 02:59:54,312 [salt.utils.extmods][INFO    ][20799] 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-12-02 02:59:54,312 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-12-02 02:59:54,313 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-12-02 02:59:54,318 [salt.utils.extmods][INFO    ][20799] Syncing states for environment 'base'
2017-12-02 02:59:54,318 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_states, for base)
2017-12-02 02:59:54,318 [salt.fileclient  ][INFO    ][20799] Caching directory '_states/' for environment 'base'
2017-12-02 02:59:54,359 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-12-02 02:59:54,376 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-12-02 02:59:54,390 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-12-02 02:59:54,407 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-12-02 02:59:54,425 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-12-02 02:59:54,446 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-12-02 02:59:54,457 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-12-02 02:59:54,467 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-12-02 02:59:54,479 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-12-02 02:59:54,491 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-12-02 02:59:54,501 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-12-02 02:59:54,514 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-12-02 02:59:54,526 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-12-02 02:59:54,538 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-12-02 02:59:54,549 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-12-02 02:59:54,560 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-12-02 02:59:54,573 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-12-02 02:59:54,585 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-12-02 02:59:54,596 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-12-02 02:59:54,607 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-12-02 02:59:54,618 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-12-02 02:59:54,632 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-12-02 02:59:54,642 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-12-02 02:59:54,653 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-12-02 02:59:54,664 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-12-02 02:59:54,674 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-12-02 02:59:54,703 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-12-02 02:59:54,715 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-12-02 02:59:54,729 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-12-02 02:59:54,739 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-12-02 02:59:54,759 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-12-02 02:59:54,769 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-12-02 02:59:54,772 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-12-02 02:59:54,772 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-12-02 02:59:54,773 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-12-02 02:59:54,773 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-12-02 02:59:54,773 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-12-02 02:59:54,773 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-12-02 02:59:54,774 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-12-02 02:59:54,774 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-12-02 02:59:54,774 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-12-02 02:59:54,775 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-12-02 02:59:54,775 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-12-02 02:59:54,775 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-12-02 02:59:54,775 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-12-02 02:59:54,776 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-12-02 02:59:54,776 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-12-02 02:59:54,776 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-12-02 02:59:54,776 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-12-02 02:59:54,777 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-12-02 02:59:54,777 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-12-02 02:59:54,777 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-12-02 02:59:54,777 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-12-02 02:59:54,778 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-12-02 02:59:54,778 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-12-02 02:59:54,778 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-12-02 02:59:54,778 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-12-02 02:59:54,779 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-12-02 02:59:54,779 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-12-02 02:59:54,779 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-12-02 02:59:54,779 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-12-02 02:59:54,780 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-12-02 02:59:54,780 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-12-02 02:59:54,780 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-12-02 02:59:54,780 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-12-02 02:59:54,783 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-12-02 02:59:54,785 [salt.utils.extmods][INFO    ][20799] Syncing sdb for environment 'base'
2017-12-02 02:59:54,785 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_sdb, for base)
2017-12-02 02:59:54,786 [salt.fileclient  ][INFO    ][20799] Caching directory '_sdb/' for environment 'base'
2017-12-02 02:59:54,825 [salt.utils.extmods][INFO    ][20799] Syncing grains for environment 'base'
2017-12-02 02:59:54,825 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_grains, for base)
2017-12-02 02:59:54,825 [salt.fileclient  ][INFO    ][20799] Caching directory '_grains/' for environment 'base'
2017-12-02 02:59:54,866 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-12-02 02:59:54,877 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-12-02 02:59:54,888 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-12-02 02:59:54,898 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-12-02 02:59:54,908 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-12-02 02:59:54,918 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-12-02 02:59:54,929 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-12-02 02:59:54,939 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-12-02 02:59:54,950 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-12-02 02:59:55,205 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-12-02 02:59:55,215 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-12-02 02:59:55,227 [salt.fileclient  ][INFO    ][20799] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-12-02 02:59:55,230 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-12-02 02:59:55,230 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-12-02 02:59:55,242 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-12-02 02:59:55,243 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-12-02 02:59:55,243 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-12-02 02:59:55,244 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-12-02 02:59:55,244 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-12-02 02:59:55,245 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-12-02 02:59:55,245 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-12-02 02:59:55,246 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-12-02 02:59:55,246 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-12-02 02:59:55,247 [salt.utils.extmods][INFO    ][20799] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-12-02 02:59:55,248 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-12-02 02:59:55,251 [salt.utils.extmods][INFO    ][20799] Syncing renderers for environment 'base'
2017-12-02 02:59:55,251 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_renderers, for base)
2017-12-02 02:59:55,251 [salt.fileclient  ][INFO    ][20799] Caching directory '_renderers/' for environment 'base'
2017-12-02 02:59:55,285 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-12-02 02:59:55,288 [salt.utils.extmods][INFO    ][20799] Syncing returners for environment 'base'
2017-12-02 02:59:55,288 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_returners, for base)
2017-12-02 02:59:55,288 [salt.fileclient  ][INFO    ][20799] Caching directory '_returners/' for environment 'base'
2017-12-02 02:59:55,321 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-12-02 02:59:55,324 [salt.utils.extmods][INFO    ][20799] Syncing output for environment 'base'
2017-12-02 02:59:55,324 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_output, for base)
2017-12-02 02:59:55,324 [salt.fileclient  ][INFO    ][20799] Caching directory '_output/' for environment 'base'
2017-12-02 02:59:55,355 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-12-02 02:59:55,357 [salt.utils.extmods][INFO    ][20799] Syncing utils for environment 'base'
2017-12-02 02:59:55,358 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_utils, for base)
2017-12-02 02:59:55,358 [salt.fileclient  ][INFO    ][20799] Caching directory '_utils/' for environment 'base'
2017-12-02 02:59:55,387 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-12-02 02:59:55,390 [salt.utils.extmods][INFO    ][20799] Syncing log_handlers for environment 'base'
2017-12-02 02:59:55,390 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_log_handlers, for base)
2017-12-02 02:59:55,390 [salt.fileclient  ][INFO    ][20799] Caching directory '_log_handlers/' for environment 'base'
2017-12-02 02:59:55,419 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-12-02 02:59:55,421 [salt.utils.extmods][INFO    ][20799] Syncing proxy for environment 'base'
2017-12-02 02:59:55,421 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_proxy, for base)
2017-12-02 02:59:55,422 [salt.fileclient  ][INFO    ][20799] Caching directory '_proxy/' for environment 'base'
2017-12-02 02:59:55,450 [salt.utils.extmods][INFO    ][20799] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-12-02 02:59:55,452 [salt.utils.extmods][INFO    ][20799] Syncing engines for environment 'base'
2017-12-02 02:59:55,453 [salt.utils.extmods][INFO    ][20799] Loading cache from salt://_engines, for base)
2017-12-02 02:59:55,453 [salt.fileclient  ][INFO    ][20799] Caching directory '_engines/' for environment 'base'
2017-12-02 02:59:55,489 [salt.minion      ][INFO    ][20799] Returning information for job: 20171202025951015928
2017-12-02 03:00:00,642 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command state.sls with jid 20171202030000634115
2017-12-02 03:00:00,655 [salt.minion      ][INFO    ][20897] Starting a new job with PID 20897
2017-12-02 03:00:01,253 [salt.state       ][INFO    ][20897] Loading fresh modules for state activity
2017-12-02 03:00:01,276 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-02 03:00:01,292 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-02 03:00:01,314 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:01,374 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-02 03:00:01,387 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-02 03:00:01,409 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:01,461 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-02 03:00:01,477 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-02 03:00:01,519 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-02 03:00:01,537 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-02 03:00:01,549 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-02 03:00:01,563 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-02 03:00:01,569 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-02 03:00:01,592 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-02 03:00:01,606 [py.warnings      ][WARNING ][20897] /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-12-02 03:00:01,606 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'salt-minion --version' in directory '/root'
2017-12-02 03:00:01,847 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-02 03:00:01,865 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-02 03:00:01,877 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-02 03:00:01,888 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-02 03:00:01,921 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-02 03:00:01,934 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-02 03:00:01,957 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-02 03:00:01,969 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-02 03:00:01,985 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-02 03:00:02,020 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-02 03:00:02,073 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-02 03:00:02,094 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:02,146 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-02 03:00:02,163 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-02 03:00:02,205 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-02 03:00:02,223 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-02 03:00:02,236 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-02 03:00:02,250 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-02 03:00:02,256 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-02 03:00:02,278 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-02 03:00:02,294 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'salt-minion --version' in directory '/root'
2017-12-02 03:00:02,547 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-02 03:00:02,563 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-02 03:00:02,575 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-02 03:00:02,587 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-02 03:00:02,620 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-02 03:00:02,633 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-02 03:00:02,773 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-02 03:00:02,786 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-02 03:00:02,801 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-02 03:00:02,829 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-02 03:00:02,892 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-02 03:00:02,948 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:03,262 [salt.state       ][INFO    ][20897] Running state [salt-minion] at time 03:00:03.261691
2017-12-02 03:00:03,262 [salt.state       ][INFO    ][20897] Executing state pkg.installed for salt-minion
2017-12-02 03:00:03,262 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:00:03,534 [salt.state       ][INFO    ][20897] Package salt-minion is already installed
2017-12-02 03:00:03,534 [salt.state       ][INFO    ][20897] Completed state [salt-minion] at time 03:00:03.533994 duration_in_ms=272.304
2017-12-02 03:00:03,534 [salt.state       ][INFO    ][20897] Running state [salt_minion_dependency_packages] at time 03:00:03.534231
2017-12-02 03:00:03,534 [salt.state       ][INFO    ][20897] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-02 03:00:03,537 [salt.state       ][INFO    ][20897] All specified packages are already installed
2017-12-02 03:00:03,537 [salt.state       ][INFO    ][20897] Completed state [salt_minion_dependency_packages] at time 03:00:03.537227 duration_in_ms=2.996
2017-12-02 03:00:03,539 [salt.state       ][INFO    ][20897] Running state [/etc/salt/minion.d/minion.conf] at time 03:00:03.538649
2017-12-02 03:00:03,539 [salt.state       ][INFO    ][20897] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-02 03:00:03,559 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-02 03:00:03,603 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:03,645 [salt.fileclient  ][INFO    ][20897] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-02 03:00:03,677 [salt.state       ][INFO    ][20897] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-02 03:00:03,677 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/minion.d/minion.conf] at time 03:00:03.677136 duration_in_ms=138.487
2017-12-02 03:00:03,677 [salt.state       ][INFO    ][20897] Running state [/etc/salt/minion.d/_virt.conf] at time 03:00:03.677420
2017-12-02 03:00:03,678 [salt.state       ][INFO    ][20897] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-02 03:00:03,679 [salt.state       ][INFO    ][20897] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-02 03:00:03,679 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/minion.d/_virt.conf] at time 03:00:03.679008 duration_in_ms=1.588
2017-12-02 03:00:03,680 [salt.state       ][INFO    ][20897] Running state [salt-minion] at time 03:00:03.680266
2017-12-02 03:00:03,680 [salt.state       ][INFO    ][20897] Executing state service.running for salt-minion
2017-12-02 03:00:03,681 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-02 03:00:03,693 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-02 03:00:03,699 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-02 03:00:03,704 [salt.state       ][INFO    ][20897] The service salt-minion is already running
2017-12-02 03:00:03,704 [salt.state       ][INFO    ][20897] Completed state [salt-minion] at time 03:00:03.704450 duration_in_ms=24.183
2017-12-02 03:00:03,706 [salt.state       ][INFO    ][20897] Running state [/etc/salt/grains.d] at time 03:00:03.705606
2017-12-02 03:00:03,706 [salt.state       ][INFO    ][20897] Executing state file.directory for /etc/salt/grains.d
2017-12-02 03:00:03,706 [salt.state       ][INFO    ][20897] Directory /etc/salt/grains.d is in the correct state
2017-12-02 03:00:03,706 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/grains.d] at time 03:00:03.706435 duration_in_ms=0.828
2017-12-02 03:00:03,707 [salt.state       ][INFO    ][20897] Running state [/etc/salt/grains] at time 03:00:03.706841
2017-12-02 03:00:03,707 [salt.state       ][INFO    ][20897] Executing state file.managed for /etc/salt/grains
2017-12-02 03:00:03,707 [salt.state       ][INFO    ][20897] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-02 03:00:03,707 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/grains] at time 03:00:03.707435 duration_in_ms=0.594
2017-12-02 03:00:03,708 [salt.state       ][INFO    ][20897] Running state [/etc/salt/grains.d/placeholder] at time 03:00:03.707692
2017-12-02 03:00:03,708 [salt.state       ][INFO    ][20897] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-02 03:00:03,708 [salt.state       ][INFO    ][20897] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-02 03:00:03,708 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/grains.d/placeholder] at time 03:00:03.708277 duration_in_ms=0.585
2017-12-02 03:00:03,709 [salt.state       ][INFO    ][20897] Running state [/etc/salt/grains.d/sphinx] at time 03:00:03.708535
2017-12-02 03:00:03,709 [salt.state       ][INFO    ][20897] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-02 03:00:03,715 [salt.state       ][INFO    ][20897] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.142
               - 127.0.0.1
               - 192.168.11.253
-              - 192.168.122.1
         system:
           name: System
           param:

2017-12-02 03:00:03,715 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/grains.d/sphinx] at time 03:00:03.715313 duration_in_ms=6.778
2017-12-02 03:00:03,716 [salt.state       ][INFO    ][20897] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.715885
2017-12-02 03:00:03,716 [salt.state       ][INFO    ][20897] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:03,716 [salt.state       ][INFO    ][20897] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:03,716 [salt.state       ][INFO    ][20897] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.716367 duration_in_ms=0.482
2017-12-02 03:00:03,717 [salt.state       ][INFO    ][20897] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.716496
2017-12-02 03:00:03,717 [salt.state       ][INFO    ][20897] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:03,717 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-12-02 03:00:03,791 [salt.state       ][INFO    ][20897] {'pid': 20946, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-12-02 03:00:03,791 [salt.state       ][INFO    ][20897] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.791311 duration_in_ms=74.814
2017-12-02 03:00:03,792 [salt.state       ][INFO    ][20897] Running state [/etc/salt/grains.d/dns_records] at time 03:00:03.791745
2017-12-02 03:00:03,792 [salt.state       ][INFO    ][20897] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-02 03:00:03,805 [salt.state       ][INFO    ][20897] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-02 03:00:03,805 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/grains.d/dns_records] at time 03:00:03.804944 duration_in_ms=13.199
2017-12-02 03:00:03,806 [salt.state       ][INFO    ][20897] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.805545
2017-12-02 03:00:03,806 [salt.state       ][INFO    ][20897] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:03,806 [salt.state       ][INFO    ][20897] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:03,806 [salt.state       ][INFO    ][20897] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.806067 duration_in_ms=0.523
2017-12-02 03:00:03,806 [salt.state       ][INFO    ][20897] Running state [/etc/salt/grains.d/salt] at time 03:00:03.806329
2017-12-02 03:00:03,807 [salt.state       ][INFO    ][20897] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-02 03:00:03,811 [salt.state       ][INFO    ][20897] File /etc/salt/grains.d/salt is in the correct state
2017-12-02 03:00:03,811 [salt.state       ][INFO    ][20897] Completed state [/etc/salt/grains.d/salt] at time 03:00:03.811150 duration_in_ms=4.821
2017-12-02 03:00:03,812 [salt.state       ][INFO    ][20897] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.811667
2017-12-02 03:00:03,812 [salt.state       ][INFO    ][20897] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:03,812 [salt.state       ][INFO    ][20897] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:03,812 [salt.state       ][INFO    ][20897] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:00:03.812152 duration_in_ms=0.485
2017-12-02 03:00:03,813 [salt.state       ][INFO    ][20897] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:00:03.813268
2017-12-02 03:00:03,813 [salt.state       ][INFO    ][20897] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-02 03:00:03,814 [salt.state       ][INFO    ][20897] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-02 03:00:03,814 [salt.state       ][INFO    ][20897] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:00:03.813759 duration_in_ms=0.49
2017-12-02 03:00:03,814 [salt.state       ][INFO    ][20897] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:00:03.813900
2017-12-02 03:00:03,814 [salt.state       ][INFO    ][20897] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-02 03:00:03,815 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-12-02 03:00:03,823 [salt.state       ][INFO    ][20897] {'pid': 20948, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-12-02 03:00:03,824 [salt.state       ][INFO    ][20897] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:00:03.823584 duration_in_ms=9.683
2017-12-02 03:00:03,824 [salt.state       ][INFO    ][20897] Running state [mine.update] at time 03:00:03.824241
2017-12-02 03:00:03,825 [salt.state       ][INFO    ][20897] Executing state module.wait for mine.update
2017-12-02 03:00:03,825 [salt.state       ][INFO    ][20897] No changes made for mine.update
2017-12-02 03:00:03,825 [salt.state       ][INFO    ][20897] Completed state [mine.update] at time 03:00:03.824888 duration_in_ms=0.647
2017-12-02 03:00:03,825 [salt.state       ][INFO    ][20897] Running state [mine.update] at time 03:00:03.825051
2017-12-02 03:00:03,825 [salt.state       ][INFO    ][20897] Executing state module.mod_watch for mine.update
2017-12-02 03:00:04,346 [salt.state       ][INFO    ][20897] {'ret': True}
2017-12-02 03:00:04,346 [salt.state       ][INFO    ][20897] Completed state [mine.update] at time 03:00:04.346429 duration_in_ms=521.378
2017-12-02 03:00:04,347 [salt.state       ][INFO    ][20897] Running state [ca-certificates] at time 03:00:04.346699
2017-12-02 03:00:04,347 [salt.state       ][INFO    ][20897] Executing state pkg.installed for ca-certificates
2017-12-02 03:00:04,350 [salt.state       ][INFO    ][20897] Package ca-certificates is already installed
2017-12-02 03:00:04,351 [salt.state       ][INFO    ][20897] Completed state [ca-certificates] at time 03:00:04.350564 duration_in_ms=3.865
2017-12-02 03:00:04,351 [salt.state       ][INFO    ][20897] Running state [update-ca-certificates] at time 03:00:04.351120
2017-12-02 03:00:04,351 [salt.state       ][INFO    ][20897] Executing state cmd.wait for update-ca-certificates
2017-12-02 03:00:04,352 [salt.state       ][INFO    ][20897] No changes made for update-ca-certificates
2017-12-02 03:00:04,352 [salt.state       ][INFO    ][20897] Completed state [update-ca-certificates] at time 03:00:04.351755 duration_in_ms=0.634
2017-12-02 03:00:04,352 [salt.state       ][INFO    ][20897] Running state [libvirt-dev] at time 03:00:04.351953
2017-12-02 03:00:04,352 [salt.state       ][INFO    ][20897] Executing state pkg.installed for libvirt-dev
2017-12-02 03:00:04,355 [salt.state       ][INFO    ][20897] Package libvirt-dev is already installed
2017-12-02 03:00:04,355 [salt.state       ][INFO    ][20897] Completed state [libvirt-dev] at time 03:00:04.355207 duration_in_ms=3.254
2017-12-02 03:00:04,355 [salt.state       ][INFO    ][20897] Running state [pkg-config] at time 03:00:04.355405
2017-12-02 03:00:04,356 [salt.state       ][INFO    ][20897] Executing state pkg.installed for pkg-config
2017-12-02 03:00:04,359 [salt.state       ][INFO    ][20897] Package pkg-config is already installed
2017-12-02 03:00:04,359 [salt.state       ][INFO    ][20897] Completed state [pkg-config] at time 03:00:04.358666 duration_in_ms=3.261
2017-12-02 03:00:04,359 [salt.state       ][INFO    ][20897] Running state [virtng.init] at time 03:00:04.358866
2017-12-02 03:00:04,359 [salt.state       ][INFO    ][20897] Executing state module.run for virtng.init
2017-12-02 03:00:04,466 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command ['git', '--version'] in directory '/root'
2017-12-02 03:00:04,516 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,536 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,536 [salt.state       ][INFO    ][20897] Completed state [virtng.init] at time 03:00:04.536166 duration_in_ms=177.299
2017-12-02 03:00:04,536 [salt.state       ][INFO    ][20897] Running state [virt.set_autostart] at time 03:00:04.536447
2017-12-02 03:00:04,537 [salt.state       ][INFO    ][20897] Executing state module.run for virt.set_autostart
2017-12-02 03:00:04,539 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,555 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,555 [salt.state       ][INFO    ][20897] Completed state [virt.set_autostart] at time 03:00:04.555333 duration_in_ms=18.886
2017-12-02 03:00:04,556 [salt.state       ][INFO    ][20897] Running state [virtng.init] at time 03:00:04.555564
2017-12-02 03:00:04,556 [salt.state       ][INFO    ][20897] Executing state module.run for virtng.init
2017-12-02 03:00:04,558 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,574 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,575 [salt.state       ][INFO    ][20897] Completed state [virtng.init] at time 03:00:04.574702 duration_in_ms=19.137
2017-12-02 03:00:04,575 [salt.state       ][INFO    ][20897] Running state [virt.set_autostart] at time 03:00:04.574953
2017-12-02 03:00:04,575 [salt.state       ][INFO    ][20897] Executing state module.run for virt.set_autostart
2017-12-02 03:00:04,577 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,593 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,593 [salt.state       ][INFO    ][20897] Completed state [virt.set_autostart] at time 03:00:04.593206 duration_in_ms=18.253
2017-12-02 03:00:04,593 [salt.state       ][INFO    ][20897] Running state [virtng.init] at time 03:00:04.593431
2017-12-02 03:00:04,594 [salt.state       ][INFO    ][20897] Executing state module.run for virtng.init
2017-12-02 03:00:04,596 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,611 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,612 [salt.state       ][INFO    ][20897] Completed state [virtng.init] at time 03:00:04.611608 duration_in_ms=18.177
2017-12-02 03:00:04,612 [salt.state       ][INFO    ][20897] Running state [virt.set_autostart] at time 03:00:04.611848
2017-12-02 03:00:04,612 [salt.state       ][INFO    ][20897] Executing state module.run for virt.set_autostart
2017-12-02 03:00:04,614 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,627 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,627 [salt.state       ][INFO    ][20897] Completed state [virt.set_autostart] at time 03:00:04.627423 duration_in_ms=15.575
2017-12-02 03:00:04,628 [salt.state       ][INFO    ][20897] Running state [virtng.init] at time 03:00:04.627645
2017-12-02 03:00:04,628 [salt.state       ][INFO    ][20897] Executing state module.run for virtng.init
2017-12-02 03:00:04,630 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,646 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,646 [salt.state       ][INFO    ][20897] Completed state [virtng.init] at time 03:00:04.645947 duration_in_ms=18.302
2017-12-02 03:00:04,646 [salt.state       ][INFO    ][20897] Running state [virt.set_autostart] at time 03:00:04.646170
2017-12-02 03:00:04,646 [salt.state       ][INFO    ][20897] Executing state module.run for virt.set_autostart
2017-12-02 03:00:04,648 [salt.loaded.int.module.cmdmod][INFO    ][20897] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:04,661 [salt.state       ][INFO    ][20897] unless execution succeeded
2017-12-02 03:00:04,661 [salt.state       ][INFO    ][20897] Completed state [virt.set_autostart] at time 03:00:04.661359 duration_in_ms=15.19
2017-12-02 03:00:04,664 [salt.minion      ][INFO    ][20897] Returning information for job: 20171202030000634115
2017-12-02 03:00:31,750 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command state.sls with jid 20171202030031748953
2017-12-02 03:00:31,764 [salt.minion      ][INFO    ][21000] Starting a new job with PID 21000
2017-12-02 03:00:34,220 [salt.state       ][INFO    ][21000] Loading fresh modules for state activity
2017-12-02 03:00:34,245 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-02 03:00:34,262 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-02 03:00:34,284 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:34,345 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-02 03:00:34,359 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-02 03:00:34,383 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:34,438 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-02 03:00:34,455 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-02 03:00:34,497 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-02 03:00:34,514 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-02 03:00:34,527 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-02 03:00:34,544 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-02 03:00:34,551 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-02 03:00:34,574 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-02 03:00:34,590 [py.warnings      ][WARNING ][21000] /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-12-02 03:00:34,591 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'salt-minion --version' in directory '/root'
2017-12-02 03:00:34,855 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-02 03:00:34,876 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-02 03:00:34,888 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-02 03:00:34,900 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-02 03:00:34,935 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-02 03:00:34,950 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-02 03:00:34,971 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-02 03:00:34,985 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-02 03:00:35,001 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-02 03:00:35,035 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-02 03:00:35,093 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-02 03:00:35,115 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:35,168 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-02 03:00:35,185 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-02 03:00:35,226 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-02 03:00:35,244 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-02 03:00:35,259 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-02 03:00:35,275 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-02 03:00:35,281 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-02 03:00:35,304 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-02 03:00:35,319 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'salt-minion --version' in directory '/root'
2017-12-02 03:00:35,569 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-02 03:00:35,589 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-02 03:00:35,604 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-02 03:00:35,616 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-02 03:00:35,654 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-02 03:00:35,668 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-02 03:00:35,690 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-02 03:00:35,703 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-02 03:00:35,724 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-02 03:00:35,761 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-02 03:00:35,826 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-02 03:00:35,883 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:36,165 [salt.state       ][INFO    ][21000] Running state [salt-minion] at time 03:00:36.165409
2017-12-02 03:00:36,166 [salt.state       ][INFO    ][21000] Executing state pkg.installed for salt-minion
2017-12-02 03:00:36,166 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:00:36,435 [salt.state       ][INFO    ][21000] Package salt-minion is already installed
2017-12-02 03:00:36,435 [salt.state       ][INFO    ][21000] Completed state [salt-minion] at time 03:00:36.435146 duration_in_ms=269.737
2017-12-02 03:00:36,435 [salt.state       ][INFO    ][21000] Running state [salt_minion_dependency_packages] at time 03:00:36.435386
2017-12-02 03:00:36,436 [salt.state       ][INFO    ][21000] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-02 03:00:36,438 [salt.state       ][INFO    ][21000] All specified packages are already installed
2017-12-02 03:00:36,438 [salt.state       ][INFO    ][21000] Completed state [salt_minion_dependency_packages] at time 03:00:36.438446 duration_in_ms=3.061
2017-12-02 03:00:36,440 [salt.state       ][INFO    ][21000] Running state [/etc/salt/minion.d/minion.conf] at time 03:00:36.439922
2017-12-02 03:00:36,440 [salt.state       ][INFO    ][21000] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-02 03:00:36,463 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-02 03:00:36,510 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-02 03:00:36,553 [salt.fileclient  ][INFO    ][21000] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-02 03:00:36,587 [salt.state       ][INFO    ][21000] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-02 03:00:36,587 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/minion.d/minion.conf] at time 03:00:36.587063 duration_in_ms=147.14
2017-12-02 03:00:36,587 [salt.state       ][INFO    ][21000] Running state [/etc/salt/minion.d/_virt.conf] at time 03:00:36.587345
2017-12-02 03:00:36,588 [salt.state       ][INFO    ][21000] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-02 03:00:36,592 [salt.state       ][INFO    ][21000] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-02 03:00:36,592 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/minion.d/_virt.conf] at time 03:00:36.592261 duration_in_ms=4.915
2017-12-02 03:00:36,594 [salt.state       ][INFO    ][21000] Running state [salt-minion] at time 03:00:36.593584
2017-12-02 03:00:36,594 [salt.state       ][INFO    ][21000] Executing state service.running for salt-minion
2017-12-02 03:00:36,594 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-02 03:00:36,612 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-02 03:00:36,620 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-02 03:00:36,628 [salt.state       ][INFO    ][21000] The service salt-minion is already running
2017-12-02 03:00:36,629 [salt.state       ][INFO    ][21000] Completed state [salt-minion] at time 03:00:36.628656 duration_in_ms=35.071
2017-12-02 03:00:36,630 [salt.state       ][INFO    ][21000] Running state [/etc/salt/grains.d] at time 03:00:36.630045
2017-12-02 03:00:36,630 [salt.state       ][INFO    ][21000] Executing state file.directory for /etc/salt/grains.d
2017-12-02 03:00:36,631 [salt.state       ][INFO    ][21000] Directory /etc/salt/grains.d is in the correct state
2017-12-02 03:00:36,631 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/grains.d] at time 03:00:36.631015 duration_in_ms=0.97
2017-12-02 03:00:36,631 [salt.state       ][INFO    ][21000] Running state [/etc/salt/grains] at time 03:00:36.631457
2017-12-02 03:00:36,632 [salt.state       ][INFO    ][21000] Executing state file.managed for /etc/salt/grains
2017-12-02 03:00:36,632 [salt.state       ][INFO    ][21000] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-02 03:00:36,632 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/grains] at time 03:00:36.632174 duration_in_ms=0.717
2017-12-02 03:00:36,632 [salt.state       ][INFO    ][21000] Running state [/etc/salt/grains.d/placeholder] at time 03:00:36.632470
2017-12-02 03:00:36,633 [salt.state       ][INFO    ][21000] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-02 03:00:36,633 [salt.state       ][INFO    ][21000] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-02 03:00:36,633 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/grains.d/placeholder] at time 03:00:36.633184 duration_in_ms=0.713
2017-12-02 03:00:36,633 [salt.state       ][INFO    ][21000] Running state [/etc/salt/grains.d/sphinx] at time 03:00:36.633469
2017-12-02 03:00:36,634 [salt.state       ][INFO    ][21000] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-02 03:00:36,645 [salt.state       ][INFO    ][21000] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-02 03:00:36,646 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/grains.d/sphinx] at time 03:00:36.645638 duration_in_ms=12.169
2017-12-02 03:00:36,646 [salt.state       ][INFO    ][21000] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:00:36.646246
2017-12-02 03:00:36,646 [salt.state       ][INFO    ][21000] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:36,647 [salt.state       ][INFO    ][21000] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:36,647 [salt.state       ][INFO    ][21000] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:00:36.646829 duration_in_ms=0.583
2017-12-02 03:00:36,647 [salt.state       ][INFO    ][21000] Running state [/etc/salt/grains.d/dns_records] at time 03:00:36.647118
2017-12-02 03:00:36,647 [salt.state       ][INFO    ][21000] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-02 03:00:36,652 [salt.state       ][INFO    ][21000] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-02 03:00:36,652 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/grains.d/dns_records] at time 03:00:36.651703 duration_in_ms=4.584
2017-12-02 03:00:36,652 [salt.state       ][INFO    ][21000] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:00:36.652276
2017-12-02 03:00:36,652 [salt.state       ][INFO    ][21000] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:36,653 [salt.state       ][INFO    ][21000] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:36,653 [salt.state       ][INFO    ][21000] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:00:36.652846 duration_in_ms=0.57
2017-12-02 03:00:36,653 [salt.state       ][INFO    ][21000] Running state [/etc/salt/grains.d/salt] at time 03:00:36.653143
2017-12-02 03:00:36,653 [salt.state       ][INFO    ][21000] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-02 03:00:36,657 [salt.state       ][INFO    ][21000] File /etc/salt/grains.d/salt is in the correct state
2017-12-02 03:00:36,657 [salt.state       ][INFO    ][21000] Completed state [/etc/salt/grains.d/salt] at time 03:00:36.657449 duration_in_ms=4.307
2017-12-02 03:00:36,658 [salt.state       ][INFO    ][21000] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:00:36.658036
2017-12-02 03:00:36,658 [salt.state       ][INFO    ][21000] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:36,658 [salt.state       ][INFO    ][21000] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-02 03:00:36,659 [salt.state       ][INFO    ][21000] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:00:36.658620 duration_in_ms=0.584
2017-12-02 03:00:36,660 [salt.state       ][INFO    ][21000] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:00:36.660159
2017-12-02 03:00:36,660 [salt.state       ][INFO    ][21000] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-02 03:00:36,661 [salt.state       ][INFO    ][21000] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-02 03:00:36,661 [salt.state       ][INFO    ][21000] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:00:36.660732 duration_in_ms=0.574
2017-12-02 03:00:36,661 [salt.state       ][INFO    ][21000] Running state [mine.update] at time 03:00:36.661117
2017-12-02 03:00:36,661 [salt.state       ][INFO    ][21000] Executing state module.wait for mine.update
2017-12-02 03:00:36,661 [salt.state       ][INFO    ][21000] No changes made for mine.update
2017-12-02 03:00:36,662 [salt.state       ][INFO    ][21000] Completed state [mine.update] at time 03:00:36.661534 duration_in_ms=0.417
2017-12-02 03:00:36,662 [salt.state       ][INFO    ][21000] Running state [ca-certificates] at time 03:00:36.661666
2017-12-02 03:00:36,662 [salt.state       ][INFO    ][21000] Executing state pkg.installed for ca-certificates
2017-12-02 03:00:36,665 [salt.state       ][INFO    ][21000] Package ca-certificates is already installed
2017-12-02 03:00:36,665 [salt.state       ][INFO    ][21000] Completed state [ca-certificates] at time 03:00:36.664865 duration_in_ms=3.198
2017-12-02 03:00:36,665 [salt.state       ][INFO    ][21000] Running state [update-ca-certificates] at time 03:00:36.665240
2017-12-02 03:00:36,665 [salt.state       ][INFO    ][21000] Executing state cmd.wait for update-ca-certificates
2017-12-02 03:00:36,666 [salt.state       ][INFO    ][21000] No changes made for update-ca-certificates
2017-12-02 03:00:36,666 [salt.state       ][INFO    ][21000] Completed state [update-ca-certificates] at time 03:00:36.665671 duration_in_ms=0.43
2017-12-02 03:00:36,666 [salt.state       ][INFO    ][21000] Running state [libvirt-dev] at time 03:00:36.665803
2017-12-02 03:00:36,666 [salt.state       ][INFO    ][21000] Executing state pkg.installed for libvirt-dev
2017-12-02 03:00:36,669 [salt.state       ][INFO    ][21000] Package libvirt-dev is already installed
2017-12-02 03:00:36,669 [salt.state       ][INFO    ][21000] Completed state [libvirt-dev] at time 03:00:36.668627 duration_in_ms=2.823
2017-12-02 03:00:36,669 [salt.state       ][INFO    ][21000] Running state [pkg-config] at time 03:00:36.668764
2017-12-02 03:00:36,669 [salt.state       ][INFO    ][21000] Executing state pkg.installed for pkg-config
2017-12-02 03:00:36,671 [salt.state       ][INFO    ][21000] Package pkg-config is already installed
2017-12-02 03:00:36,672 [salt.state       ][INFO    ][21000] Completed state [pkg-config] at time 03:00:36.671592 duration_in_ms=2.828
2017-12-02 03:00:36,672 [salt.state       ][INFO    ][21000] Running state [virtng.init] at time 03:00:36.671726
2017-12-02 03:00:36,672 [salt.state       ][INFO    ][21000] Executing state module.run for virtng.init
2017-12-02 03:00:36,751 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command ['git', '--version'] in directory '/root'
2017-12-02 03:00:36,794 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,811 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,811 [salt.state       ][INFO    ][21000] Completed state [virtng.init] at time 03:00:36.811227 duration_in_ms=139.499
2017-12-02 03:00:36,812 [salt.state       ][INFO    ][21000] Running state [virt.set_autostart] at time 03:00:36.811489
2017-12-02 03:00:36,812 [salt.state       ][INFO    ][21000] Executing state module.run for virt.set_autostart
2017-12-02 03:00:36,814 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,829 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,829 [salt.state       ][INFO    ][21000] Completed state [virt.set_autostart] at time 03:00:36.829406 duration_in_ms=17.916
2017-12-02 03:00:36,830 [salt.state       ][INFO    ][21000] Running state [virtng.init] at time 03:00:36.829614
2017-12-02 03:00:36,830 [salt.state       ][INFO    ][21000] Executing state module.run for virtng.init
2017-12-02 03:00:36,832 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,847 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,847 [salt.state       ][INFO    ][21000] Completed state [virtng.init] at time 03:00:36.846770 duration_in_ms=17.156
2017-12-02 03:00:36,847 [salt.state       ][INFO    ][21000] Running state [virt.set_autostart] at time 03:00:36.847014
2017-12-02 03:00:36,847 [salt.state       ][INFO    ][21000] Executing state module.run for virt.set_autostart
2017-12-02 03:00:36,849 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,865 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,865 [salt.state       ][INFO    ][21000] Completed state [virt.set_autostart] at time 03:00:36.865118 duration_in_ms=18.104
2017-12-02 03:00:36,865 [salt.state       ][INFO    ][21000] Running state [virtng.init] at time 03:00:36.865325
2017-12-02 03:00:36,866 [salt.state       ][INFO    ][21000] Executing state module.run for virtng.init
2017-12-02 03:00:36,867 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,881 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,882 [salt.state       ][INFO    ][21000] Completed state [virtng.init] at time 03:00:36.881505 duration_in_ms=16.18
2017-12-02 03:00:36,882 [salt.state       ][INFO    ][21000] Running state [virt.set_autostart] at time 03:00:36.881736
2017-12-02 03:00:36,882 [salt.state       ][INFO    ][21000] Executing state module.run for virt.set_autostart
2017-12-02 03:00:36,884 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,899 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,899 [salt.state       ][INFO    ][21000] Completed state [virt.set_autostart] at time 03:00:36.899143 duration_in_ms=17.407
2017-12-02 03:00:36,899 [salt.state       ][INFO    ][21000] Running state [virtng.init] at time 03:00:36.899350
2017-12-02 03:00:36,900 [salt.state       ][INFO    ][21000] Executing state module.run for virtng.init
2017-12-02 03:00:36,901 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,915 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,915 [salt.state       ][INFO    ][21000] Completed state [virtng.init] at time 03:00:36.915369 duration_in_ms=16.019
2017-12-02 03:00:36,916 [salt.state       ][INFO    ][21000] Running state [virt.set_autostart] at time 03:00:36.915618
2017-12-02 03:00:36,916 [salt.state       ][INFO    ][21000] Executing state module.run for virt.set_autostart
2017-12-02 03:00:36,918 [salt.loaded.int.module.cmdmod][INFO    ][21000] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-02 03:00:36,933 [salt.state       ][INFO    ][21000] unless execution succeeded
2017-12-02 03:00:36,933 [salt.state       ][INFO    ][21000] Completed state [virt.set_autostart] at time 03:00:36.932893 duration_in_ms=17.274
2017-12-02 03:00:36,935 [salt.minion      ][INFO    ][21000] Returning information for job: 20171202030031748953
2017-12-02 03:01:18,320 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171202030118314278
2017-12-02 03:01:18,337 [salt.minion      ][INFO    ][21102] Starting a new job with PID 21102
2017-12-02 03:01:21,652 [salt.state       ][INFO    ][21102] Loading fresh modules for state activity
2017-12-02 03:01:21,713 [salt.state       ][ERROR   ][21102] No contents found in top file
2017-12-02 03:01:21,714 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-12-02 03:01:21,717 [salt.utils.extmods][INFO    ][21102] Syncing beacons for environment 'base'
2017-12-02 03:01:21,717 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_beacons, for base)
2017-12-02 03:01:21,717 [salt.fileclient  ][INFO    ][21102] Caching directory '_beacons/' for environment 'base'
2017-12-02 03:01:21,757 [salt.utils.extmods][INFO    ][21102] Syncing modules for environment 'base'
2017-12-02 03:01:21,757 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_modules, for base)
2017-12-02 03:01:21,758 [salt.fileclient  ][INFO    ][21102] Caching directory '_modules/' for environment 'base'
2017-12-02 03:01:21,794 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-12-02 03:01:21,801 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-12-02 03:01:21,810 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-12-02 03:01:21,819 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-12-02 03:01:21,827 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-12-02 03:01:21,1000 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-12-02 03:01:22,014 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-12-02 03:01:22,030 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-12-02 03:01:22,049 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-12-02 03:01:22,070 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-12-02 03:01:22,084 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-12-02 03:01:22,092 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-12-02 03:01:22,104 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-12-02 03:01:22,115 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-12-02 03:01:22,127 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-12-02 03:01:22,143 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-12-02 03:01:22,163 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-12-02 03:01:22,214 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-12-02 03:01:22,233 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-12-02 03:01:22,246 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-12-02 03:01:22,282 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-12-02 03:01:22,293 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-12-02 03:01:22,336 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-12-02 03:01:22,346 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-12-02 03:01:22,377 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-12-02 03:01:22,388 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-12-02 03:01:22,399 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-12-02 03:01:22,413 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-12-02 03:01:22,441 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-12-02 03:01:22,455 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-12-02 03:01:22,461 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-12-02 03:01:22,471 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-12-02 03:01:22,495 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-12-02 03:01:22,550 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-12-02 03:01:22,553 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-12-02 03:01:22,554 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-12-02 03:01:22,554 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-12-02 03:01:22,554 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-12-02 03:01:22,554 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-12-02 03:01:22,555 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-12-02 03:01:22,555 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-12-02 03:01:22,555 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-12-02 03:01:22,556 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-12-02 03:01:22,556 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-12-02 03:01:22,556 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-12-02 03:01:22,556 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-12-02 03:01:22,557 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-12-02 03:01:22,557 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-12-02 03:01:22,557 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-12-02 03:01:22,557 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-12-02 03:01:22,557 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-12-02 03:01:22,558 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-12-02 03:01:22,558 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-12-02 03:01:22,558 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-12-02 03:01:22,558 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-12-02 03:01:22,559 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-12-02 03:01:22,559 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-12-02 03:01:22,559 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-12-02 03:01:22,559 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-12-02 03:01:22,560 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-12-02 03:01:22,560 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-12-02 03:01:22,560 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-12-02 03:01:22,560 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-12-02 03:01:22,560 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-12-02 03:01:22,561 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-12-02 03:01:22,561 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-12-02 03:01:22,561 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-12-02 03:01:22,561 [salt.utils.extmods][INFO    ][21102] 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-12-02 03:01:22,561 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-12-02 03:01:22,562 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-12-02 03:01:22,567 [salt.utils.extmods][INFO    ][21102] Syncing states for environment 'base'
2017-12-02 03:01:22,567 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_states, for base)
2017-12-02 03:01:22,567 [salt.fileclient  ][INFO    ][21102] Caching directory '_states/' for environment 'base'
2017-12-02 03:01:22,613 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-12-02 03:01:22,622 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-12-02 03:01:22,634 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-12-02 03:01:22,651 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-12-02 03:01:22,661 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-12-02 03:01:22,703 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-12-02 03:01:22,714 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-12-02 03:01:22,736 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-12-02 03:01:22,756 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-12-02 03:01:22,769 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-12-02 03:01:22,808 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-12-02 03:01:22,825 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-12-02 03:01:22,836 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-12-02 03:01:22,843 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-12-02 03:01:22,861 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-12-02 03:01:22,868 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-12-02 03:01:22,876 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-12-02 03:01:22,888 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-12-02 03:01:22,899 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-12-02 03:01:22,909 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-12-02 03:01:22,916 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-12-02 03:01:22,922 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-12-02 03:01:22,928 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-12-02 03:01:22,956 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-12-02 03:01:22,965 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-12-02 03:01:22,976 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-12-02 03:01:22,992 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-12-02 03:01:23,002 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-12-02 03:01:23,013 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-12-02 03:01:23,060 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-12-02 03:01:23,085 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-12-02 03:01:23,099 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-12-02 03:01:23,102 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-12-02 03:01:23,102 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-12-02 03:01:23,102 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-12-02 03:01:23,102 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-12-02 03:01:23,103 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-12-02 03:01:23,103 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-12-02 03:01:23,103 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-12-02 03:01:23,103 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-12-02 03:01:23,103 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-12-02 03:01:23,104 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-12-02 03:01:23,104 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-12-02 03:01:23,104 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-12-02 03:01:23,104 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-12-02 03:01:23,104 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-12-02 03:01:23,104 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-12-02 03:01:23,105 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-12-02 03:01:23,105 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-12-02 03:01:23,105 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-12-02 03:01:23,105 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-12-02 03:01:23,105 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-12-02 03:01:23,106 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-12-02 03:01:23,106 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-12-02 03:01:23,106 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-12-02 03:01:23,106 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-12-02 03:01:23,106 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-12-02 03:01:23,107 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-12-02 03:01:23,107 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-12-02 03:01:23,107 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-12-02 03:01:23,107 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-12-02 03:01:23,107 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-12-02 03:01:23,107 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-12-02 03:01:23,108 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-12-02 03:01:23,108 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-12-02 03:01:23,110 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-12-02 03:01:23,112 [salt.utils.extmods][INFO    ][21102] Syncing sdb for environment 'base'
2017-12-02 03:01:23,112 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_sdb, for base)
2017-12-02 03:01:23,113 [salt.fileclient  ][INFO    ][21102] Caching directory '_sdb/' for environment 'base'
2017-12-02 03:01:23,171 [salt.utils.extmods][INFO    ][21102] Syncing grains for environment 'base'
2017-12-02 03:01:23,171 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_grains, for base)
2017-12-02 03:01:23,171 [salt.fileclient  ][INFO    ][21102] Caching directory '_grains/' for environment 'base'
2017-12-02 03:01:23,201 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-12-02 03:01:23,218 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-12-02 03:01:23,243 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-12-02 03:01:23,252 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-12-02 03:01:23,290 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-12-02 03:01:23,300 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-12-02 03:01:23,311 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-12-02 03:01:23,349 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-12-02 03:01:23,379 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-12-02 03:01:23,392 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-12-02 03:01:23,400 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-12-02 03:01:23,410 [salt.fileclient  ][INFO    ][21102] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-12-02 03:01:23,413 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-12-02 03:01:23,413 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-12-02 03:01:23,413 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-12-02 03:01:23,413 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-12-02 03:01:23,413 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-12-02 03:01:23,414 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-12-02 03:01:23,414 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-12-02 03:01:23,414 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-12-02 03:01:23,414 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-12-02 03:01:23,414 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-12-02 03:01:23,415 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-12-02 03:01:23,415 [salt.utils.extmods][INFO    ][21102] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-12-02 03:01:23,416 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-12-02 03:01:23,418 [salt.utils.extmods][INFO    ][21102] Syncing renderers for environment 'base'
2017-12-02 03:01:23,418 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_renderers, for base)
2017-12-02 03:01:23,418 [salt.fileclient  ][INFO    ][21102] Caching directory '_renderers/' for environment 'base'
2017-12-02 03:01:23,453 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-12-02 03:01:23,456 [salt.utils.extmods][INFO    ][21102] Syncing returners for environment 'base'
2017-12-02 03:01:23,456 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_returners, for base)
2017-12-02 03:01:23,456 [salt.fileclient  ][INFO    ][21102] Caching directory '_returners/' for environment 'base'
2017-12-02 03:01:23,493 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-12-02 03:01:23,495 [salt.utils.extmods][INFO    ][21102] Syncing output for environment 'base'
2017-12-02 03:01:23,495 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_output, for base)
2017-12-02 03:01:23,495 [salt.fileclient  ][INFO    ][21102] Caching directory '_output/' for environment 'base'
2017-12-02 03:01:23,528 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-12-02 03:01:23,530 [salt.utils.extmods][INFO    ][21102] Syncing utils for environment 'base'
2017-12-02 03:01:23,531 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_utils, for base)
2017-12-02 03:01:23,531 [salt.fileclient  ][INFO    ][21102] Caching directory '_utils/' for environment 'base'
2017-12-02 03:01:23,562 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-12-02 03:01:23,565 [salt.utils.extmods][INFO    ][21102] Syncing log_handlers for environment 'base'
2017-12-02 03:01:23,565 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_log_handlers, for base)
2017-12-02 03:01:23,565 [salt.fileclient  ][INFO    ][21102] Caching directory '_log_handlers/' for environment 'base'
2017-12-02 03:01:23,595 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-12-02 03:01:23,597 [salt.utils.extmods][INFO    ][21102] Syncing proxy for environment 'base'
2017-12-02 03:01:23,597 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_proxy, for base)
2017-12-02 03:01:23,597 [salt.fileclient  ][INFO    ][21102] Caching directory '_proxy/' for environment 'base'
2017-12-02 03:01:23,621 [salt.utils.extmods][INFO    ][21102] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-12-02 03:01:23,623 [salt.utils.extmods][INFO    ][21102] Syncing engines for environment 'base'
2017-12-02 03:01:23,624 [salt.utils.extmods][INFO    ][21102] Loading cache from salt://_engines, for base)
2017-12-02 03:01:23,624 [salt.fileclient  ][INFO    ][21102] Caching directory '_engines/' for environment 'base'
2017-12-02 03:01:23,671 [salt.minion      ][INFO    ][21102] Returning information for job: 20171202030118314278
2017-12-02 03:01:24,124 [salt.transport.ipc][ERROR   ][20486] Exception occurred while handling stream: [Errno 0] Success
2017-12-02 03:12:37,075 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command test.ping with jid 20171202031237066665
2017-12-02 03:12:37,099 [salt.minion      ][INFO    ][21292] Starting a new job with PID 21292
2017-12-02 03:12:37,125 [salt.minion      ][INFO    ][21292] Returning information for job: 20171202031237066665
2017-12-02 03:16:03,607 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command state.sls with jid 20171202031603598808
2017-12-02 03:16:03,621 [salt.minion      ][INFO    ][21346] Starting a new job with PID 21346
2017-12-02 03:16:05,259 [salt.state       ][INFO    ][21346] Loading fresh modules for state activity
2017-12-02 03:16:05,286 [salt.fileclient  ][INFO    ][21346] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-12-02 03:16:05,303 [salt.fileclient  ][INFO    ][21346] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-12-02 03:16:05,327 [salt.fileclient  ][INFO    ][21346] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-02 03:16:05,627 [salt.state       ][INFO    ][21346] Running state [lsof] at time 03:16:05.626582
2017-12-02 03:16:05,627 [salt.state       ][INFO    ][21346] Executing state pkg.installed for lsof
2017-12-02 03:16:05,627 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:16:05,894 [salt.state       ][INFO    ][21346] Package lsof is already installed
2017-12-02 03:16:05,894 [salt.state       ][INFO    ][21346] Completed state [lsof] at time 03:16:05.894101 duration_in_ms=267.519
2017-12-02 03:16:05,894 [salt.state       ][INFO    ][21346] Running state [keepalived] at time 03:16:05.894305
2017-12-02 03:16:05,894 [salt.state       ][INFO    ][21346] Executing state pkg.installed for keepalived
2017-12-02 03:16:05,911 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-02 03:16:07,286 [salt.loaded.int.module.cmdmod][INFO    ][21346] 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-12-02 03:16:13,650 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.find_job with jid 20171202031613640106
2017-12-02 03:16:13,663 [salt.minion      ][INFO    ][22560] Starting a new job with PID 22560
2017-12-02 03:16:13,680 [salt.minion      ][INFO    ][22560] Returning information for job: 20171202031613640106
2017-12-02 03:16:15,271 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:16:15,293 [salt.state       ][INFO    ][21346] 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-12-02 03:16:15,301 [salt.state       ][INFO    ][21346] Loading fresh modules for state activity
2017-12-02 03:16:15,314 [salt.state       ][INFO    ][21346] Completed state [keepalived] at time 03:16:15.313523 duration_in_ms=9419.216
2017-12-02 03:16:15,316 [salt.state       ][INFO    ][21346] Running state [/etc/keepalived/keepalived.conf] at time 03:16:15.315570
2017-12-02 03:16:15,316 [salt.state       ][INFO    ][21346] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-12-02 03:16:15,337 [salt.fileclient  ][INFO    ][21346] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-12-02 03:16:15,368 [salt.fileclient  ][INFO    ][21346] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-02 03:16:15,375 [salt.state       ][INFO    ][21346] File changed:
New file
2017-12-02 03:16:15,375 [salt.state       ][INFO    ][21346] Completed state [/etc/keepalived/keepalived.conf] at time 03:16:15.375022 duration_in_ms=59.451
2017-12-02 03:16:15,434 [salt.state       ][INFO    ][21346] Running state [keepalived] at time 03:16:15.434242
2017-12-02 03:16:15,435 [salt.state       ][INFO    ][21346] Executing state service.running for keepalived
2017-12-02 03:16:15,436 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-12-02 03:16:15,446 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-02 03:16:15,453 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-02 03:16:15,461 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-02 03:16:15,470 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-12-02 03:16:15,527 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-02 03:16:15,534 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-02 03:16:15,542 [salt.loaded.int.module.cmdmod][INFO    ][21346] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-02 03:16:15,549 [salt.state       ][INFO    ][21346] {'keepalived': True}
2017-12-02 03:16:15,549 [salt.state       ][INFO    ][21346] Completed state [keepalived] at time 03:16:15.548735 duration_in_ms=114.494
2017-12-02 03:16:15,550 [salt.minion      ][INFO    ][21346] Returning information for job: 20171202031603598808
2017-12-02 03:16:38,924 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command pillar.get with jid 20171202031638915956
2017-12-02 03:16:38,943 [salt.minion      ][INFO    ][22764] Starting a new job with PID 22764
2017-12-02 03:16:38,952 [salt.minion      ][INFO    ][22764] Returning information for job: 20171202031638915956
2017-12-02 03:21:50,556 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command state.sls with jid 20171202032150542625
2017-12-02 03:21:50,571 [salt.minion      ][INFO    ][22857] Starting a new job with PID 22857
2017-12-02 03:21:52,355 [salt.state       ][INFO    ][22857] Loading fresh modules for state activity
2017-12-02 03:21:52,378 [salt.fileclient  ][INFO    ][22857] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-02 03:21:52,400 [salt.fileclient  ][INFO    ][22857] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-02 03:21:52,681 [salt.state       ][INFO    ][22857] Running state [attr] at time 03:21:52.681039
2017-12-02 03:21:52,681 [salt.state       ][INFO    ][22857] Executing state pkg.installed for attr
2017-12-02 03:21:52,682 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:21:52,975 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-02 03:21:54,880 [salt.loaded.int.module.cmdmod][INFO    ][22857] 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-12-02 03:21:57,565 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:21:57,589 [salt.state       ][INFO    ][22857] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-12-02 03:21:57,597 [salt.state       ][INFO    ][22857] Loading fresh modules for state activity
2017-12-02 03:21:57,610 [salt.state       ][INFO    ][22857] Completed state [attr] at time 03:21:57.609551 duration_in_ms=4928.512
2017-12-02 03:21:57,614 [salt.state       ][INFO    ][22857] Running state [glusterfs-server] at time 03:21:57.614450
2017-12-02 03:21:57,615 [salt.state       ][INFO    ][22857] Executing state pkg.installed for glusterfs-server
2017-12-02 03:21:57,809 [salt.loaded.int.module.cmdmod][INFO    ][22857] 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-12-02 03:22:00,648 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.find_job with jid 20171202032200635378
2017-12-02 03:22:00,663 [salt.minion      ][INFO    ][23689] Starting a new job with PID 23689
2017-12-02 03:22:00,675 [salt.minion      ][INFO    ][23689] Returning information for job: 20171202032200635378
2017-12-02 03:22:10,845 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.find_job with jid 20171202032210831547
2017-12-02 03:22:10,859 [salt.minion      ][INFO    ][23696] Starting a new job with PID 23696
2017-12-02 03:22:10,870 [salt.minion      ][INFO    ][23696] Returning information for job: 20171202032210831547
2017-12-02 03:22:21,040 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.find_job with jid 20171202032221027115
2017-12-02 03:22:21,053 [salt.minion      ][INFO    ][24757] Starting a new job with PID 24757
2017-12-02 03:22:21,064 [salt.minion      ][INFO    ][24757] Returning information for job: 20171202032221027115
2017-12-02 03:22:25,940 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:22:25,963 [salt.state       ][INFO    ][22857] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'glusterfs-client' changed from 'absent' to '3.10.8-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.8-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.8-ubuntu1~xenial1'

2017-12-02 03:22:25,970 [salt.state       ][INFO    ][22857] Loading fresh modules for state activity
2017-12-02 03:22:26,026 [salt.state       ][INFO    ][22857] Completed state [glusterfs-server] at time 03:22:26.026032 duration_in_ms=28411.581
2017-12-02 03:22:26,031 [salt.state       ][INFO    ][22857] Running state [bc] at time 03:22:26.030611
2017-12-02 03:22:26,031 [salt.state       ][INFO    ][22857] Executing state pkg.installed for bc
2017-12-02 03:22:26,233 [salt.loaded.int.module.cmdmod][INFO    ][22857] 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-12-02 03:22:29,150 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:22:29,177 [salt.state       ][INFO    ][22857] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-12-02 03:22:29,185 [salt.state       ][INFO    ][22857] Loading fresh modules for state activity
2017-12-02 03:22:29,199 [salt.state       ][INFO    ][22857] Completed state [bc] at time 03:22:29.199138 duration_in_ms=3168.526
2017-12-02 03:22:29,263 [salt.state       ][INFO    ][22857] Running state [glusterfs-server] at time 03:22:29.262902
2017-12-02 03:22:29,263 [salt.state       ][INFO    ][22857] Executing state service.running for glusterfs-server
2017-12-02 03:22:29,264 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-02 03:22:29,274 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-02 03:22:29,281 [salt.loaded.int.module.cmdmod][INFO    ][22857] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-02 03:22:29,290 [salt.state       ][INFO    ][22857] The service glusterfs-server is already running
2017-12-02 03:22:29,291 [salt.state       ][INFO    ][22857] Completed state [glusterfs-server] at time 03:22:29.290667 duration_in_ms=27.766
2017-12-02 03:22:29,292 [salt.state       ][INFO    ][22857] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:22:29.292188
2017-12-02 03:22:29,292 [salt.state       ][INFO    ][22857] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-02 03:22:29,303 [salt.state       ][INFO    ][22857] File changed:
New file
2017-12-02 03:22:29,303 [salt.state       ][INFO    ][22857] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:22:29.303356 duration_in_ms=11.168
2017-12-02 03:22:29,304 [salt.state       ][INFO    ][22857] Running state [/srv/glusterfs/glance] at time 03:22:29.303510
2017-12-02 03:22:29,304 [salt.state       ][INFO    ][22857] Executing state file.directory for /srv/glusterfs/glance
2017-12-02 03:22:29,304 [salt.state       ][INFO    ][22857] {'/srv/glusterfs/glance': 'New Dir'}
2017-12-02 03:22:29,304 [salt.state       ][INFO    ][22857] Completed state [/srv/glusterfs/glance] at time 03:22:29.304324 duration_in_ms=0.814
2017-12-02 03:22:29,304 [salt.state       ][INFO    ][22857] Running state [/srv/glusterfs/keystone-keys] at time 03:22:29.304467
2017-12-02 03:22:29,305 [salt.state       ][INFO    ][22857] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-02 03:22:29,305 [salt.state       ][INFO    ][22857] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-12-02 03:22:29,305 [salt.state       ][INFO    ][22857] Completed state [/srv/glusterfs/keystone-keys] at time 03:22:29.305110 duration_in_ms=0.643
2017-12-02 03:22:29,305 [salt.state       ][INFO    ][22857] Running state [/srv/glusterfs/nova_instances] at time 03:22:29.305253
2017-12-02 03:22:29,305 [salt.state       ][INFO    ][22857] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-02 03:22:29,306 [salt.state       ][INFO    ][22857] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-12-02 03:22:29,306 [salt.state       ][INFO    ][22857] Completed state [/srv/glusterfs/nova_instances] at time 03:22:29.305900 duration_in_ms=0.646
2017-12-02 03:22:29,306 [salt.state       ][INFO    ][22857] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:22:29.306047
2017-12-02 03:22:29,306 [salt.state       ][INFO    ][22857] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-02 03:22:29,307 [salt.state       ][INFO    ][22857] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-12-02 03:22:29,307 [salt.state       ][INFO    ][22857] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:22:29.306666 duration_in_ms=0.619
2017-12-02 03:22:29,307 [salt.minion      ][INFO    ][22857] Returning information for job: 20171202032150542625
2017-12-02 03:22:35,271 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command test.ping with jid 20171202032235260364
2017-12-02 03:22:35,284 [salt.minion      ][INFO    ][25675] Starting a new job with PID 25675
2017-12-02 03:22:35,312 [salt.minion      ][INFO    ][25675] Returning information for job: 20171202032235260364
2017-12-02 03:22:35,447 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command state.sls with jid 20171202032235434656
2017-12-02 03:22:35,458 [salt.minion      ][INFO    ][25680] Starting a new job with PID 25680
2017-12-02 03:22:37,152 [salt.state       ][INFO    ][25680] Loading fresh modules for state activity
2017-12-02 03:22:37,176 [salt.fileclient  ][INFO    ][25680] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-12-02 03:22:37,214 [salt.fileclient  ][INFO    ][25680] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-02 03:22:37,283 [salt.fileclient  ][INFO    ][25680] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-02 03:22:37,302 [salt.fileclient  ][INFO    ][25680] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-02 03:22:37,579 [salt.state       ][INFO    ][25680] Running state [attr] at time 03:22:37.578618
2017-12-02 03:22:37,579 [salt.state       ][INFO    ][25680] Executing state pkg.installed for attr
2017-12-02 03:22:37,579 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-02 03:22:37,854 [salt.state       ][INFO    ][25680] Package attr is already installed
2017-12-02 03:22:37,855 [salt.state       ][INFO    ][25680] Completed state [attr] at time 03:22:37.854708 duration_in_ms=276.09
2017-12-02 03:22:37,855 [salt.state       ][INFO    ][25680] Running state [glusterfs-server] at time 03:22:37.854935
2017-12-02 03:22:37,855 [salt.state       ][INFO    ][25680] Executing state pkg.installed for glusterfs-server
2017-12-02 03:22:37,858 [salt.state       ][INFO    ][25680] Package glusterfs-server is already installed
2017-12-02 03:22:37,858 [salt.state       ][INFO    ][25680] Completed state [glusterfs-server] at time 03:22:37.857871 duration_in_ms=2.936
2017-12-02 03:22:37,858 [salt.state       ][INFO    ][25680] Running state [bc] at time 03:22:37.858035
2017-12-02 03:22:37,858 [salt.state       ][INFO    ][25680] Executing state pkg.installed for bc
2017-12-02 03:22:37,861 [salt.state       ][INFO    ][25680] Package bc is already installed
2017-12-02 03:22:37,861 [salt.state       ][INFO    ][25680] Completed state [bc] at time 03:22:37.860870 duration_in_ms=2.835
2017-12-02 03:22:37,862 [salt.state       ][INFO    ][25680] Running state [glusterfs-server] at time 03:22:37.861715
2017-12-02 03:22:37,862 [salt.state       ][INFO    ][25680] Executing state service.running for glusterfs-server
2017-12-02 03:22:37,862 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-02 03:22:37,873 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-12-02 03:22:37,916 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-02 03:22:37,931 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-02 03:22:37,939 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-02 03:22:37,948 [salt.state       ][INFO    ][25680] The service glusterfs-server is already running
2017-12-02 03:22:37,948 [salt.state       ][INFO    ][25680] Completed state [glusterfs-server] at time 03:22:37.948425 duration_in_ms=86.71
2017-12-02 03:22:37,950 [salt.state       ][INFO    ][25680] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:22:37.949806
2017-12-02 03:22:37,950 [salt.state       ][INFO    ][25680] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-02 03:22:37,963 [salt.state       ][INFO    ][25680] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-12-02 03:22:37,963 [salt.state       ][INFO    ][25680] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:22:37.963121 duration_in_ms=13.315
2017-12-02 03:22:37,963 [salt.state       ][INFO    ][25680] Running state [/srv/glusterfs/glance] at time 03:22:37.963287
2017-12-02 03:22:37,963 [salt.state       ][INFO    ][25680] Executing state file.directory for /srv/glusterfs/glance
2017-12-02 03:22:37,964 [salt.state       ][INFO    ][25680] Directory /srv/glusterfs/glance is in the correct state
2017-12-02 03:22:37,964 [salt.state       ][INFO    ][25680] Completed state [/srv/glusterfs/glance] at time 03:22:37.963864 duration_in_ms=0.577
2017-12-02 03:22:37,964 [salt.state       ][INFO    ][25680] Running state [/srv/glusterfs/keystone-keys] at time 03:22:37.964008
2017-12-02 03:22:37,964 [salt.state       ][INFO    ][25680] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-02 03:22:37,964 [salt.state       ][INFO    ][25680] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-12-02 03:22:37,965 [salt.state       ][INFO    ][25680] Completed state [/srv/glusterfs/keystone-keys] at time 03:22:37.964582 duration_in_ms=0.573
2017-12-02 03:22:37,965 [salt.state       ][INFO    ][25680] Running state [/srv/glusterfs/nova_instances] at time 03:22:37.964729
2017-12-02 03:22:37,965 [salt.state       ][INFO    ][25680] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-02 03:22:37,965 [salt.state       ][INFO    ][25680] Directory /srv/glusterfs/nova_instances is in the correct state
2017-12-02 03:22:37,965 [salt.state       ][INFO    ][25680] Completed state [/srv/glusterfs/nova_instances] at time 03:22:37.965310 duration_in_ms=0.582
2017-12-02 03:22:37,965 [salt.state       ][INFO    ][25680] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:22:37.965457
2017-12-02 03:22:37,966 [salt.state       ][INFO    ][25680] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-02 03:22:37,966 [salt.state       ][INFO    ][25680] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-12-02 03:22:37,966 [salt.state       ][INFO    ][25680] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:22:37.966034 duration_in_ms=0.577
2017-12-02 03:22:38,062 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command ['git', '--version'] in directory '/root'
2017-12-02 03:22:38,108 [salt.state       ][INFO    ][25680] Running state [sleep 5] at time 03:22:38.108359
2017-12-02 03:22:38,109 [salt.state       ][INFO    ][25680] Executing state cmd.wait for sleep 5
2017-12-02 03:22:38,109 [salt.state       ][INFO    ][25680] No changes made for sleep 5
2017-12-02 03:22:38,109 [salt.state       ][INFO    ][25680] Completed state [sleep 5] at time 03:22:38.108927 duration_in_ms=0.569
2017-12-02 03:22:38,109 [salt.state       ][INFO    ][25680] Running state [10.167.4.141] at time 03:22:38.109330
2017-12-02 03:22:38,109 [salt.state       ][INFO    ][25680] Executing state glusterfs.peered for 10.167.4.141
2017-12-02 03:22:38,110 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:38,120 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:38,173 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:38,181 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:38,576 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:38,587 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:38,696 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:38,708 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:38,901 [salt.state       ][INFO    ][25680] {'new': {'10.167.4.141': []}, 'old': None}
2017-12-02 03:22:38,901 [salt.state       ][INFO    ][25680] Completed state [10.167.4.141] at time 03:22:38.901425 duration_in_ms=792.094
2017-12-02 03:22:38,904 [salt.state       ][INFO    ][25680] Running state [10.167.4.142] at time 03:22:38.903886
2017-12-02 03:22:38,904 [salt.state       ][INFO    ][25680] Executing state glusterfs.peered for 10.167.4.142
2017-12-02 03:22:38,905 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:38,917 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:38,975 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:38,985 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,044 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,054 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,109 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,118 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,170 [salt.state       ][INFO    ][25680] Peering with localhost is not needed
2017-12-02 03:22:39,170 [salt.state       ][INFO    ][25680] Completed state [10.167.4.142] at time 03:22:39.170299 duration_in_ms=266.411
2017-12-02 03:22:39,173 [salt.state       ][INFO    ][25680] Running state [10.167.4.143] at time 03:22:39.172490
2017-12-02 03:22:39,173 [salt.state       ][INFO    ][25680] Executing state glusterfs.peered for 10.167.4.143
2017-12-02 03:22:39,173 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,183 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,238 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,248 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,548 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,560 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,624 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,634 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,695 [salt.state       ][INFO    ][25680] {'new': {'10.167.4.141': [], '10.167.4.143': []}, 'old': {'10.167.4.141': []}}
2017-12-02 03:22:39,696 [salt.state       ][INFO    ][25680] Completed state [10.167.4.143] at time 03:22:39.695640 duration_in_ms=523.15
2017-12-02 03:22:39,697 [salt.state       ][INFO    ][25680] Running state [glance] at time 03:22:39.696492
2017-12-02 03:22:39,697 [salt.state       ][INFO    ][25680] Executing state glusterfs.created for glance
2017-12-02 03:22:39,697 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,707 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:39,849 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:39,860 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:40,906 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:40,917 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:43,691 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:43,702 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:43,759 [salt.state       ][INFO    ][25680] {'new': ['glance'], 'old': []}
2017-12-02 03:22:43,759 [salt.state       ][INFO    ][25680] Completed state [glance] at time 03:22:43.758922 duration_in_ms=4062.429
2017-12-02 03:22:43,762 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:22:43.762249
2017-12-02 03:22:43,763 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-12-02 03:22:43,763 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-02 03:22:43,818 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:22:44,476 [salt.state       ][INFO    ][25680] {'pid': 25942, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:44,476 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:22:44.475866 duration_in_ms=713.617
2017-12-02 03:22:44,476 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:22:44.476439
2017-12-02 03:22:44,477 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-12-02 03:22:44,477 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-02 03:22:44,538 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-12-02 03:22:45,227 [salt.state       ][INFO    ][25680] {'pid': 25981, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:45,228 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:22:45.227751 duration_in_ms=751.312
2017-12-02 03:22:45,228 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:22:45.228353
2017-12-02 03:22:45,229 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-12-02 03:22:45,229 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-12-02 03:22:45,292 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-12-02 03:22:45,489 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.find_job with jid 20171202032245476235
2017-12-02 03:22:45,507 [salt.minion      ][INFO    ][26031] Starting a new job with PID 26031
2017-12-02 03:22:45,518 [salt.minion      ][INFO    ][26031] Returning information for job: 20171202032245476235
2017-12-02 03:22:45,973 [salt.state       ][INFO    ][25680] {'pid': 26019, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:45,973 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:22:45.973256 duration_in_ms=744.902
2017-12-02 03:22:45,974 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 03:22:45.973839
2017-12-02 03:22:45,974 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-12-02 03:22:45,975 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-02 03:22:46,033 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-02 03:22:46,715 [salt.state       ][INFO    ][25680] {'pid': 26061, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:46,716 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 03:22:46.715563 duration_in_ms=741.723
2017-12-02 03:22:46,716 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:22:46.716121
2017-12-02 03:22:46,716 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-12-02 03:22:46,717 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-02 03:22:46,779 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:22:47,560 [salt.state       ][INFO    ][25680] {'pid': 26100, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:47,561 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:22:47.560781 duration_in_ms=844.659
2017-12-02 03:22:47,561 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:22:47.561379
2017-12-02 03:22:47,562 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-12-02 03:22:47,569 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-02 03:22:47,631 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-02 03:22:48,233 [salt.state       ][INFO    ][25680] {'pid': 26137, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:48,233 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:22:48.232973 duration_in_ms=671.593
2017-12-02 03:22:48,235 [salt.state       ][INFO    ][25680] Running state [glance] at time 03:22:48.234997
2017-12-02 03:22:48,236 [salt.state       ][INFO    ][25680] Executing state glusterfs.started for glance
2017-12-02 03:22:48,236 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:48,248 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:48,309 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:48,320 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:48,380 [salt.state       ][INFO    ][25680] Volume glance is already started
2017-12-02 03:22:48,381 [salt.state       ][INFO    ][25680] Completed state [glance] at time 03:22:48.380710 duration_in_ms=145.713
2017-12-02 03:22:48,382 [salt.state       ][INFO    ][25680] Running state [keystone-keys] at time 03:22:48.381736
2017-12-02 03:22:48,382 [salt.state       ][INFO    ][25680] Executing state glusterfs.created for keystone-keys
2017-12-02 03:22:48,382 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:48,393 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:48,451 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:48,460 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:49,424 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:49,435 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:52,274 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:52,286 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:52,345 [salt.state       ][INFO    ][25680] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-12-02 03:22:52,346 [salt.state       ][INFO    ][25680] Completed state [keystone-keys] at time 03:22:52.345563 duration_in_ms=3963.826
2017-12-02 03:22:52,350 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:22:52.349793
2017-12-02 03:22:52,350 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-12-02 03:22:52,351 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-02 03:22:52,414 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-12-02 03:22:53,124 [salt.state       ][INFO    ][25680] {'pid': 26290, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:53,124 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:22:53.124178 duration_in_ms=774.383
2017-12-02 03:22:53,125 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:22:53.124785
2017-12-02 03:22:53,125 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-02 03:22:53,126 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-02 03:22:53,186 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:22:53,828 [salt.state       ][INFO    ][25680] {'pid': 26327, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:53,828 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:22:53.828294 duration_in_ms=703.508
2017-12-02 03:22:53,829 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:22:53.829036
2017-12-02 03:22:53,830 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-02 03:22:53,830 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-02 03:22:53,889 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-02 03:22:54,519 [salt.state       ][INFO    ][25680] {'pid': 26366, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:54,520 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:22:54.519516 duration_in_ms=690.479
2017-12-02 03:22:54,520 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:22:54.520219
2017-12-02 03:22:54,521 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-02 03:22:54,521 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-02 03:22:54,577 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:22:55,223 [salt.state       ][INFO    ][25680] {'pid': 26403, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:55,224 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:22:55.223727 duration_in_ms=703.507
2017-12-02 03:22:55,225 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:22:55.224675
2017-12-02 03:22:55,225 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-12-02 03:22:55,226 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-02 03:22:55,286 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-12-02 03:22:55,633 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.find_job with jid 20171202032255621055
2017-12-02 03:22:55,647 [salt.minion      ][INFO    ][26454] Starting a new job with PID 26454
2017-12-02 03:22:55,658 [salt.minion      ][INFO    ][26454] Returning information for job: 20171202032255621055
2017-12-02 03:22:55,969 [salt.state       ][INFO    ][25680] {'pid': 26442, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:55,969 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:22:55.968984 duration_in_ms=744.31
2017-12-02 03:22:55,970 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:22:55.969530
2017-12-02 03:22:55,970 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-12-02 03:22:55,970 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-02 03:22:56,032 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-02 03:22:56,688 [salt.state       ][INFO    ][25680] {'pid': 26485, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:22:56,688 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:22:56.688126 duration_in_ms=718.596
2017-12-02 03:22:56,689 [salt.state       ][INFO    ][25680] Running state [keystone-keys] at time 03:22:56.689308
2017-12-02 03:22:56,690 [salt.state       ][INFO    ][25680] Executing state glusterfs.started for keystone-keys
2017-12-02 03:22:56,690 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:56,703 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:56,761 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:56,772 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:56,833 [salt.state       ][INFO    ][25680] Volume keystone-keys is already started
2017-12-02 03:22:56,834 [salt.state       ][INFO    ][25680] Completed state [keystone-keys] at time 03:22:56.833572 duration_in_ms=144.264
2017-12-02 03:22:56,835 [salt.state       ][INFO    ][25680] Running state [nova_instances] at time 03:22:56.834570
2017-12-02 03:22:56,835 [salt.state       ][INFO    ][25680] Executing state glusterfs.created for nova_instances
2017-12-02 03:22:56,835 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:56,845 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:56,901 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:56,911 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:22:57,650 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:22:57,663 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:00,489 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:00,502 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:00,560 [salt.state       ][INFO    ][25680] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-12-02 03:23:00,561 [salt.state       ][INFO    ][25680] Completed state [nova_instances] at time 03:23:00.560742 duration_in_ms=3726.172
2017-12-02 03:23:00,564 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:23:00.564377
2017-12-02 03:23:00,565 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-12-02 03:23:00,565 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-02 03:23:00,623 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:23:01,288 [salt.state       ][INFO    ][25680] {'pid': 26639, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:01,289 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:23:01.288464 duration_in_ms=724.086
2017-12-02 03:23:01,289 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 03:23:01.289034
2017-12-02 03:23:01,289 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-12-02 03:23:01,290 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-02 03:23:01,351 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-02 03:23:02,018 [salt.state       ][INFO    ][25680] {'pid': 26678, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:02,018 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 03:23:02.018345 duration_in_ms=729.311
2017-12-02 03:23:02,019 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 03:23:02.018932
2017-12-02 03:23:02,019 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-12-02 03:23:02,020 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-02 03:23:02,080 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'True'' in directory '/root'
2017-12-02 03:23:02,815 [salt.state       ][INFO    ][25680] {'pid': 26716, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:02,815 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 03:23:02.814973 duration_in_ms=796.04
2017-12-02 03:23:02,816 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 03:23:02.815554
2017-12-02 03:23:02,816 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-12-02 03:23:02,816 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-02 03:23:02,880 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-02 03:23:03,519 [salt.state       ][INFO    ][25680] {'pid': 26753, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:03,519 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 03:23:03.519351 duration_in_ms=703.796
2017-12-02 03:23:03,520 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 03:23:03.519940
2017-12-02 03:23:03,520 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-12-02 03:23:03,521 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-12-02 03:23:03,582 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'True'' in directory '/root'
2017-12-02 03:23:04,237 [salt.state       ][INFO    ][25680] {'pid': 26792, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:04,237 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 03:23:04.236842 duration_in_ms=716.902
2017-12-02 03:23:04,237 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:23:04.237449
2017-12-02 03:23:04,238 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-12-02 03:23:04,238 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-02 03:23:04,299 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:23:04,951 [salt.state       ][INFO    ][25680] {'pid': 26829, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:04,951 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:23:04.951410 duration_in_ms=713.96
2017-12-02 03:23:04,953 [salt.state       ][INFO    ][25680] Running state [nova_instances] at time 03:23:04.952652
2017-12-02 03:23:04,953 [salt.state       ][INFO    ][25680] Executing state glusterfs.started for nova_instances
2017-12-02 03:23:04,954 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:04,966 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:05,024 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:05,034 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:05,094 [salt.state       ][INFO    ][25680] Volume nova_instances is already started
2017-12-02 03:23:05,095 [salt.state       ][INFO    ][25680] Completed state [nova_instances] at time 03:23:05.094581 duration_in_ms=141.928
2017-12-02 03:23:05,096 [salt.state       ][INFO    ][25680] Running state [keystone-credential-keys] at time 03:23:05.095583
2017-12-02 03:23:05,096 [salt.state       ][INFO    ][25680] Executing state glusterfs.created for keystone-credential-keys
2017-12-02 03:23:05,096 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:05,107 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:05,162 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:05,173 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:05,774 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command saltutil.find_job with jid 20171202032305761190
2017-12-02 03:23:05,789 [salt.minion      ][INFO    ][26898] Starting a new job with PID 26898
2017-12-02 03:23:05,800 [salt.minion      ][INFO    ][26898] Returning information for job: 20171202032305761190
2017-12-02 03:23:05,934 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:05,945 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:08,653 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:08,665 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:08,724 [salt.state       ][INFO    ][25680] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-12-02 03:23:08,725 [salt.state       ][INFO    ][25680] Completed state [keystone-credential-keys] at time 03:23:08.724743 duration_in_ms=3629.16
2017-12-02 03:23:08,728 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:23:08.728423
2017-12-02 03:23:08,729 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-12-02 03:23:08,729 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-02 03:23:08,785 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-02 03:23:09,431 [salt.state       ][INFO    ][25680] {'pid': 26992, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:09,431 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:23:09.431046 duration_in_ms=702.623
2017-12-02 03:23:09,432 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:23:09.431626
2017-12-02 03:23:09,432 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-02 03:23:09,432 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-02 03:23:09,491 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-02 03:23:10,154 [salt.state       ][INFO    ][25680] {'pid': 27029, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:10,154 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:23:10.154257 duration_in_ms=722.63
2017-12-02 03:23:10,155 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:23:10.154993
2017-12-02 03:23:10,155 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-02 03:23:10,156 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-02 03:23:10,218 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:23:10,909 [salt.state       ][INFO    ][25680] {'pid': 27066, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:10,909 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:23:10.909185 duration_in_ms=754.193
2017-12-02 03:23:10,910 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:23:10.909754
2017-12-02 03:23:10,910 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-12-02 03:23:10,911 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-02 03:23:10,970 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-12-02 03:23:11,658 [salt.state       ][INFO    ][25680] {'pid': 27105, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:11,659 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:23:11.658712 duration_in_ms=748.957
2017-12-02 03:23:11,659 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:23:11.659304
2017-12-02 03:23:11,660 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-02 03:23:11,660 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-02 03:23:11,721 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-02 03:23:12,394 [salt.state       ][INFO    ][25680] {'pid': 27145, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:12,395 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:23:12.394490 duration_in_ms=735.185
2017-12-02 03:23:12,395 [salt.state       ][INFO    ][25680] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:23:12.395152
2017-12-02 03:23:12,396 [salt.state       ][INFO    ][25680] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-12-02 03:23:12,396 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-02 03:23:12,455 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-12-02 03:23:13,177 [salt.state       ][INFO    ][25680] {'pid': 27182, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-02 03:23:13,178 [salt.state       ][INFO    ][25680] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:23:13.177784 duration_in_ms=782.632
2017-12-02 03:23:13,179 [salt.state       ][INFO    ][25680] Running state [keystone-credential-keys] at time 03:23:13.179013
2017-12-02 03:23:13,179 [salt.state       ][INFO    ][25680] Executing state glusterfs.started for keystone-credential-keys
2017-12-02 03:23:13,180 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:13,192 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:13,248 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --version' in directory '/root'
2017-12-02 03:23:13,259 [salt.loaded.int.module.cmdmod][INFO    ][25680] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-02 03:23:13,322 [salt.state       ][INFO    ][25680] Volume keystone-credential-keys is already started
2017-12-02 03:23:13,323 [salt.state       ][INFO    ][25680] Completed state [keystone-credential-keys] at time 03:23:13.322789 duration_in_ms=143.775
2017-12-02 03:23:13,325 [salt.minion      ][INFO    ][25680] Returning information for job: 20171202032235434656
2017-12-02 03:23:24,311 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command test.ping with jid 20171202032324298461
2017-12-02 03:23:24,326 [salt.minion      ][INFO    ][27234] Starting a new job with PID 27234
2017-12-02 03:23:24,352 [salt.minion      ][INFO    ][27234] Returning information for job: 20171202032324298461
2017-12-02 03:23:24,483 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command cmd.run with jid 20171202032324469706
2017-12-02 03:23:24,497 [salt.minion      ][INFO    ][27239] Starting a new job with PID 27239
2017-12-02 03:23:24,501 [salt.loaded.int.module.cmdmod][INFO    ][27239] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-12-02 03:23:24,617 [salt.minion      ][INFO    ][27239] Returning information for job: 20171202032324469706
2017-12-02 03:46:47,177 [salt.utils.schedule][INFO    ][20486] Running scheduled job: __mine_interval
2017-12-02 04:46:47,178 [salt.utils.schedule][INFO    ][20486] Running scheduled job: __mine_interval
2017-12-02 04:55:23,269 [salt.minion      ][INFO    ][20486] User sudo_ubuntu Executing command cp.push_dir with jid 20171202045523260463
2017-12-02 04:55:23,289 [salt.minion      ][INFO    ][28336] Starting a new job with PID 28336
