2017-12-03 01:43:06,136 [salt.cli.daemons ][WARNING ][2395] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-03 02:52:55,287 [salt.state       ][ERROR   ][3797] No contents found in top file
2017-12-03 02:54:29,299 [salt.utils.parsers][WARNING ][2395] Minion received a SIGTERM. Exiting.
2017-12-03 02:56:41,303 [salt.cli.daemons ][WARNING ][3188] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-03 02:59:01,091 [salt.transport.ipc][ERROR   ][3188] Exception occurred while handling stream: [Errno 0] Success
2017-12-03 02:59:42,442 [py.warnings      ][WARNING ][13254] /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-03 03:01:06,001 [salt.loaded.int.module.cmdmod][ERROR   ][13254] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-03 03:01:06,001 [salt.loaded.int.module.cmdmod][ERROR   ][13254] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-03 03:01:11,984 [salt.loaded.int.module.cmdmod][ERROR   ][13254] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-03 03:01:11,984 [salt.loaded.int.module.cmdmod][ERROR   ][13254] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-03 03:01:20,043 [salt.loaded.int.module.cmdmod][ERROR   ][13254] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-03 03:01:20,075 [salt.loaded.int.module.cmdmod][ERROR   ][13254] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-03 03:01:29,207 [salt.loaded.int.module.cmdmod][ERROR   ][13254] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-12-03 03:01:29,207 [salt.loaded.int.module.cmdmod][ERROR   ][13254] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-12-03 03:01:35,644 [salt.loaded.int.module.cmdmod][ERROR   ][13254] 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-03 03:01:37,836 [salt.loaded.int.module.cmdmod][INFO    ][20303] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-03 03:01:37,843 [salt.loaded.int.module.cmdmod][INFO    ][20303] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-03 03:01:37,852 [salt.loaded.int.module.cmdmod][INFO    ][20303] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-12-03 03:01:37,860 [salt.utils.parsers][WARNING ][3188] Minion received a SIGTERM. Exiting.
2017-12-03 03:01:38,113 [salt.cli.daemons ][INFO    ][20375] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-ovs-ha.local"
2017-12-03 03:01:38,614 [salt.minion      ][INFO    ][20375] Creating minion process manager
2017-12-03 03:01:38,614 [salt.cli.daemons ][WARNING ][20375] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-12-03 03:01:38,615 [salt.cli.daemons ][INFO    ][20375] The Salt Minion is starting up
2017-12-03 03:01:38,615 [salt.minion      ][INFO    ][20375] Minion is starting as user 'root'
2017-12-03 03:01:38,615 [salt.utils.event ][INFO    ][20375] Starting pull socket on /var/run/salt/minion/minion_event_a1b2f32648_pull.ipc
2017-12-03 03:01:39,501 [salt.loaded.int.module.cmdmod][INFO    ][20375] Executing command ['date', '+%z'] in directory '/root'
2017-12-03 03:01:39,510 [salt.utils.schedule][INFO    ][20375] Updating job settings for scheduled job: __mine_interval
2017-12-03 03:01:39,512 [salt.minion      ][INFO    ][20375] Added mine.update to scheduler
2017-12-03 03:01:39,680 [salt.minion      ][INFO    ][20375] Minion is ready to receive requests!
2017-12-03 03:01:40,681 [salt.utils.schedule][INFO    ][20375] Running scheduled job: __mine_interval
2017-12-03 03:07:02,806 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171203030702789450
2017-12-03 03:07:02,823 [salt.minion      ][INFO    ][20550] Starting a new job with PID 20550
2017-12-03 03:07:04,366 [salt.state       ][INFO    ][20550] Loading fresh modules for state activity
2017-12-03 03:07:04,388 [salt.state       ][ERROR   ][20550] No contents found in top file
2017-12-03 03:07:04,389 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-12-03 03:07:04,392 [salt.utils.extmods][INFO    ][20550] Syncing beacons for environment 'base'
2017-12-03 03:07:04,392 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_beacons, for base)
2017-12-03 03:07:04,392 [salt.fileclient  ][INFO    ][20550] Caching directory '_beacons/' for environment 'base'
2017-12-03 03:07:04,426 [salt.utils.extmods][INFO    ][20550] Syncing modules for environment 'base'
2017-12-03 03:07:04,426 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_modules, for base)
2017-12-03 03:07:04,426 [salt.fileclient  ][INFO    ][20550] Caching directory '_modules/' for environment 'base'
2017-12-03 03:07:04,483 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-12-03 03:07:04,500 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-12-03 03:07:04,515 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-12-03 03:07:04,537 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-12-03 03:07:04,552 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-12-03 03:07:04,564 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-12-03 03:07:04,576 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-12-03 03:07:04,589 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-12-03 03:07:04,604 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-12-03 03:07:04,924 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-12-03 03:07:04,935 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-12-03 03:07:04,960 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-12-03 03:07:04,977 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-12-03 03:07:04,989 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-12-03 03:07:04,999 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-12-03 03:07:05,008 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-12-03 03:07:05,020 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-12-03 03:07:05,030 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-12-03 03:07:05,040 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-12-03 03:07:05,051 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-12-03 03:07:05,061 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-12-03 03:07:05,388 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-12-03 03:07:05,400 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-12-03 03:07:05,412 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-12-03 03:07:05,435 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-12-03 03:07:05,448 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-12-03 03:07:05,459 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-12-03 03:07:05,469 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-12-03 03:07:05,479 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-12-03 03:07:05,502 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-12-03 03:07:05,513 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-12-03 03:07:05,525 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-12-03 03:07:05,537 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-12-03 03:07:05,549 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-12-03 03:07:05,552 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-12-03 03:07:05,552 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-12-03 03:07:05,553 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-12-03 03:07:05,553 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-12-03 03:07:05,554 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-12-03 03:07:05,558 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-12-03 03:07:05,559 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-12-03 03:07:05,559 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-12-03 03:07:05,560 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-12-03 03:07:05,566 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-12-03 03:07:05,566 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-12-03 03:07:05,567 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-12-03 03:07:05,567 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-12-03 03:07:05,568 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-12-03 03:07:05,568 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-12-03 03:07:05,568 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-12-03 03:07:05,569 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-12-03 03:07:05,569 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-12-03 03:07:05,571 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-12-03 03:07:05,572 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-12-03 03:07:05,572 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-12-03 03:07:05,572 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-12-03 03:07:05,573 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-12-03 03:07:05,573 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-12-03 03:07:05,574 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-12-03 03:07:05,575 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-12-03 03:07:05,575 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-12-03 03:07:05,575 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-12-03 03:07:05,576 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-12-03 03:07:05,576 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-12-03 03:07:05,577 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-12-03 03:07:05,577 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-12-03 03:07:05,578 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-12-03 03:07:05,578 [salt.utils.extmods][INFO    ][20550] 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-03 03:07:05,579 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-12-03 03:07:05,579 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-12-03 03:07:05,584 [salt.utils.extmods][INFO    ][20550] Syncing states for environment 'base'
2017-12-03 03:07:05,584 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_states, for base)
2017-12-03 03:07:05,584 [salt.fileclient  ][INFO    ][20550] Caching directory '_states/' for environment 'base'
2017-12-03 03:07:05,618 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-12-03 03:07:05,634 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-12-03 03:07:05,646 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-12-03 03:07:05,660 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-12-03 03:07:05,674 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-12-03 03:07:05,687 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-12-03 03:07:05,698 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-12-03 03:07:05,710 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-12-03 03:07:05,721 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-12-03 03:07:05,735 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-12-03 03:07:05,747 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-12-03 03:07:05,759 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-12-03 03:07:05,772 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-12-03 03:07:05,785 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-12-03 03:07:05,798 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-12-03 03:07:05,811 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-12-03 03:07:05,824 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-12-03 03:07:05,837 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-12-03 03:07:05,848 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-12-03 03:07:05,860 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-12-03 03:07:05,879 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-12-03 03:07:05,893 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-12-03 03:07:05,904 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-12-03 03:07:05,919 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-12-03 03:07:05,932 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-12-03 03:07:05,944 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-12-03 03:07:05,967 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-12-03 03:07:05,979 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-12-03 03:07:05,992 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-12-03 03:07:06,004 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-12-03 03:07:06,016 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-12-03 03:07:06,028 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-12-03 03:07:06,031 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-12-03 03:07:06,031 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-12-03 03:07:06,031 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-12-03 03:07:06,032 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-12-03 03:07:06,032 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-12-03 03:07:06,032 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-12-03 03:07:06,033 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-12-03 03:07:06,033 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-12-03 03:07:06,033 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-12-03 03:07:06,034 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-12-03 03:07:06,034 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-12-03 03:07:06,034 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-12-03 03:07:06,034 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-12-03 03:07:06,035 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-12-03 03:07:06,035 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-12-03 03:07:06,035 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-12-03 03:07:06,036 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-12-03 03:07:06,036 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-12-03 03:07:06,036 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-12-03 03:07:06,036 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-12-03 03:07:06,037 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-12-03 03:07:06,037 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-12-03 03:07:06,037 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-12-03 03:07:06,037 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-12-03 03:07:06,038 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-12-03 03:07:06,038 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-12-03 03:07:06,038 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-12-03 03:07:06,038 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-12-03 03:07:06,039 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-12-03 03:07:06,039 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-12-03 03:07:06,039 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-12-03 03:07:06,040 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-12-03 03:07:06,040 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-12-03 03:07:06,042 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-12-03 03:07:06,044 [salt.utils.extmods][INFO    ][20550] Syncing sdb for environment 'base'
2017-12-03 03:07:06,044 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_sdb, for base)
2017-12-03 03:07:06,045 [salt.fileclient  ][INFO    ][20550] Caching directory '_sdb/' for environment 'base'
2017-12-03 03:07:06,082 [salt.utils.extmods][INFO    ][20550] Syncing grains for environment 'base'
2017-12-03 03:07:06,082 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_grains, for base)
2017-12-03 03:07:06,082 [salt.fileclient  ][INFO    ][20550] Caching directory '_grains/' for environment 'base'
2017-12-03 03:07:06,127 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-12-03 03:07:06,138 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-12-03 03:07:06,152 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-12-03 03:07:06,166 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-12-03 03:07:06,182 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-12-03 03:07:06,195 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-12-03 03:07:06,207 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-12-03 03:07:06,218 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-12-03 03:07:06,230 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-12-03 03:07:06,241 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-12-03 03:07:06,251 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-12-03 03:07:06,263 [salt.fileclient  ][INFO    ][20550] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-12-03 03:07:06,266 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-12-03 03:07:06,267 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-12-03 03:07:06,267 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-12-03 03:07:06,267 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-12-03 03:07:06,268 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-12-03 03:07:06,268 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-12-03 03:07:06,269 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-12-03 03:07:06,269 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-12-03 03:07:06,270 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-12-03 03:07:06,270 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-12-03 03:07:06,271 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-12-03 03:07:06,271 [salt.utils.extmods][INFO    ][20550] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-12-03 03:07:06,272 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-12-03 03:07:06,275 [salt.utils.extmods][INFO    ][20550] Syncing renderers for environment 'base'
2017-12-03 03:07:06,275 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_renderers, for base)
2017-12-03 03:07:06,275 [salt.fileclient  ][INFO    ][20550] Caching directory '_renderers/' for environment 'base'
2017-12-03 03:07:06,307 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-12-03 03:07:06,309 [salt.utils.extmods][INFO    ][20550] Syncing returners for environment 'base'
2017-12-03 03:07:06,310 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_returners, for base)
2017-12-03 03:07:06,310 [salt.fileclient  ][INFO    ][20550] Caching directory '_returners/' for environment 'base'
2017-12-03 03:07:06,347 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-12-03 03:07:06,349 [salt.utils.extmods][INFO    ][20550] Syncing output for environment 'base'
2017-12-03 03:07:06,349 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_output, for base)
2017-12-03 03:07:06,349 [salt.fileclient  ][INFO    ][20550] Caching directory '_output/' for environment 'base'
2017-12-03 03:07:06,372 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-12-03 03:07:06,374 [salt.utils.extmods][INFO    ][20550] Syncing utils for environment 'base'
2017-12-03 03:07:06,374 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_utils, for base)
2017-12-03 03:07:06,374 [salt.fileclient  ][INFO    ][20550] Caching directory '_utils/' for environment 'base'
2017-12-03 03:07:06,398 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-12-03 03:07:06,400 [salt.utils.extmods][INFO    ][20550] Syncing log_handlers for environment 'base'
2017-12-03 03:07:06,400 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_log_handlers, for base)
2017-12-03 03:07:06,401 [salt.fileclient  ][INFO    ][20550] Caching directory '_log_handlers/' for environment 'base'
2017-12-03 03:07:06,426 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-12-03 03:07:06,429 [salt.utils.extmods][INFO    ][20550] Syncing proxy for environment 'base'
2017-12-03 03:07:06,429 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_proxy, for base)
2017-12-03 03:07:06,429 [salt.fileclient  ][INFO    ][20550] Caching directory '_proxy/' for environment 'base'
2017-12-03 03:07:06,459 [salt.utils.extmods][INFO    ][20550] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-12-03 03:07:06,462 [salt.utils.extmods][INFO    ][20550] Syncing engines for environment 'base'
2017-12-03 03:07:06,462 [salt.utils.extmods][INFO    ][20550] Loading cache from salt://_engines, for base)
2017-12-03 03:07:06,462 [salt.fileclient  ][INFO    ][20550] Caching directory '_engines/' for environment 'base'
2017-12-03 03:07:06,489 [salt.minion      ][INFO    ][20550] Returning information for job: 20171203030702789450
2017-12-03 03:07:12,183 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command state.sls with jid 20171203030712168832
2017-12-03 03:07:12,199 [salt.minion      ][INFO    ][20646] Starting a new job with PID 20646
2017-12-03 03:07:12,666 [salt.state       ][INFO    ][20646] Loading fresh modules for state activity
2017-12-03 03:07:12,690 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-03 03:07:12,747 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-03 03:07:12,772 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:12,837 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-03 03:07:12,848 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-03 03:07:12,869 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:12,917 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-03 03:07:12,978 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-03 03:07:13,021 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-03 03:07:13,036 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-03 03:07:13,047 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-03 03:07:13,060 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-03 03:07:13,069 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-03 03:07:13,091 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-03 03:07:13,105 [py.warnings      ][WARNING ][20646] /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-03 03:07:13,105 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'salt-minion --version' in directory '/root'
2017-12-03 03:07:13,346 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-03 03:07:13,365 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-03 03:07:13,377 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-03 03:07:13,387 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-03 03:07:13,419 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-03 03:07:13,431 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-03 03:07:13,453 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-03 03:07:13,465 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-03 03:07:13,480 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-03 03:07:13,511 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-03 03:07:13,567 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-03 03:07:13,585 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:13,633 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-03 03:07:13,650 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-03 03:07:13,690 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-03 03:07:13,705 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-03 03:07:13,716 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-03 03:07:13,728 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-03 03:07:13,734 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-03 03:07:13,754 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-03 03:07:13,770 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'salt-minion --version' in directory '/root'
2017-12-03 03:07:14,024 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-03 03:07:14,043 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-03 03:07:14,055 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-03 03:07:14,067 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-03 03:07:14,103 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-03 03:07:14,117 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-03 03:07:14,139 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-03 03:07:14,152 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-03 03:07:14,170 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-03 03:07:14,209 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-03 03:07:14,274 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-03 03:07:14,330 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:14,659 [salt.state       ][INFO    ][20646] Running state [salt-minion] at time 03:07:14.659295
2017-12-03 03:07:14,660 [salt.state       ][INFO    ][20646] Executing state pkg.installed for salt-minion
2017-12-03 03:07:14,660 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:07:14,931 [salt.state       ][INFO    ][20646] Package salt-minion is already installed
2017-12-03 03:07:14,931 [salt.state       ][INFO    ][20646] Completed state [salt-minion] at time 03:07:14.931124 duration_in_ms=271.828
2017-12-03 03:07:14,931 [salt.state       ][INFO    ][20646] Running state [salt_minion_dependency_packages] at time 03:07:14.931370
2017-12-03 03:07:14,932 [salt.state       ][INFO    ][20646] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-03 03:07:14,934 [salt.state       ][INFO    ][20646] All specified packages are already installed
2017-12-03 03:07:14,934 [salt.state       ][INFO    ][20646] Completed state [salt_minion_dependency_packages] at time 03:07:14.934320 duration_in_ms=2.95
2017-12-03 03:07:14,936 [salt.state       ][INFO    ][20646] Running state [/etc/salt/minion.d/minion.conf] at time 03:07:14.935734
2017-12-03 03:07:14,936 [salt.state       ][INFO    ][20646] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-03 03:07:14,958 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-03 03:07:15,003 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:15,045 [salt.fileclient  ][INFO    ][20646] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-03 03:07:15,077 [salt.state       ][INFO    ][20646] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-03 03:07:15,077 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/minion.d/minion.conf] at time 03:07:15.077209 duration_in_ms=141.475
2017-12-03 03:07:15,078 [salt.state       ][INFO    ][20646] Running state [/etc/salt/minion.d/_virt.conf] at time 03:07:15.077494
2017-12-03 03:07:15,078 [salt.state       ][INFO    ][20646] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-03 03:07:15,082 [salt.state       ][INFO    ][20646] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-03 03:07:15,082 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/minion.d/_virt.conf] at time 03:07:15.081818 duration_in_ms=4.324
2017-12-03 03:07:15,083 [salt.state       ][INFO    ][20646] Running state [salt-minion] at time 03:07:15.083133
2017-12-03 03:07:15,083 [salt.state       ][INFO    ][20646] Executing state service.running for salt-minion
2017-12-03 03:07:15,084 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-03 03:07:15,098 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-03 03:07:15,105 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-03 03:07:15,111 [salt.state       ][INFO    ][20646] The service salt-minion is already running
2017-12-03 03:07:15,112 [salt.state       ][INFO    ][20646] Completed state [salt-minion] at time 03:07:15.111481 duration_in_ms=28.347
2017-12-03 03:07:15,113 [salt.state       ][INFO    ][20646] Running state [/etc/salt/grains.d] at time 03:07:15.112689
2017-12-03 03:07:15,113 [salt.state       ][INFO    ][20646] Executing state file.directory for /etc/salt/grains.d
2017-12-03 03:07:15,113 [salt.state       ][INFO    ][20646] Directory /etc/salt/grains.d is in the correct state
2017-12-03 03:07:15,114 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/grains.d] at time 03:07:15.113519 duration_in_ms=0.83
2017-12-03 03:07:15,114 [salt.state       ][INFO    ][20646] Running state [/etc/salt/grains] at time 03:07:15.113921
2017-12-03 03:07:15,114 [salt.state       ][INFO    ][20646] Executing state file.managed for /etc/salt/grains
2017-12-03 03:07:15,114 [salt.state       ][INFO    ][20646] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-03 03:07:15,115 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/grains] at time 03:07:15.114546 duration_in_ms=0.625
2017-12-03 03:07:15,115 [salt.state       ][INFO    ][20646] Running state [/etc/salt/grains.d/placeholder] at time 03:07:15.114800
2017-12-03 03:07:15,115 [salt.state       ][INFO    ][20646] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-03 03:07:15,115 [salt.state       ][INFO    ][20646] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-03 03:07:15,115 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/grains.d/placeholder] at time 03:07:15.115382 duration_in_ms=0.582
2017-12-03 03:07:15,116 [salt.state       ][INFO    ][20646] Running state [/etc/salt/grains.d/sphinx] at time 03:07:15.115638
2017-12-03 03:07:15,116 [salt.state       ][INFO    ][20646] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-03 03:07:15,118 [salt.state       ][INFO    ][20646] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.142
               - 127.0.0.1
               - 192.168.11.252
-              - 192.168.122.1
         system:
           name: System
           param:

2017-12-03 03:07:15,118 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/grains.d/sphinx] at time 03:07:15.118097 duration_in_ms=2.458
2017-12-03 03:07:15,119 [salt.state       ][INFO    ][20646] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.118668
2017-12-03 03:07:15,119 [salt.state       ][INFO    ][20646] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:15,119 [salt.state       ][INFO    ][20646] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:15,119 [salt.state       ][INFO    ][20646] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.119153 duration_in_ms=0.486
2017-12-03 03:07:15,119 [salt.state       ][INFO    ][20646] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.119289
2017-12-03 03:07:15,119 [salt.state       ][INFO    ][20646] 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-03 03:07:15,120 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-12-03 03:07:15,196 [salt.state       ][INFO    ][20646] {'pid': 20695, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-12-03 03:07:15,196 [salt.state       ][INFO    ][20646] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.196092 duration_in_ms=76.8
2017-12-03 03:07:15,197 [salt.state       ][INFO    ][20646] Running state [/etc/salt/grains.d/dns_records] at time 03:07:15.196774
2017-12-03 03:07:15,197 [salt.state       ][INFO    ][20646] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-03 03:07:15,208 [salt.state       ][INFO    ][20646] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-03 03:07:15,208 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/grains.d/dns_records] at time 03:07:15.208171 duration_in_ms=11.397
2017-12-03 03:07:15,209 [salt.state       ][INFO    ][20646] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.208792
2017-12-03 03:07:15,209 [salt.state       ][INFO    ][20646] 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-03 03:07:15,209 [salt.state       ][INFO    ][20646] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:15,209 [salt.state       ][INFO    ][20646] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.209253 duration_in_ms=0.461
2017-12-03 03:07:15,210 [salt.state       ][INFO    ][20646] Running state [/etc/salt/grains.d/salt] at time 03:07:15.209502
2017-12-03 03:07:15,210 [salt.state       ][INFO    ][20646] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-03 03:07:15,214 [salt.state       ][INFO    ][20646] File /etc/salt/grains.d/salt is in the correct state
2017-12-03 03:07:15,214 [salt.state       ][INFO    ][20646] Completed state [/etc/salt/grains.d/salt] at time 03:07:15.214123 duration_in_ms=4.621
2017-12-03 03:07:15,215 [salt.state       ][INFO    ][20646] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.214619
2017-12-03 03:07:15,215 [salt.state       ][INFO    ][20646] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:15,215 [salt.state       ][INFO    ][20646] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:15,215 [salt.state       ][INFO    ][20646] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:07:15.215073 duration_in_ms=0.454
2017-12-03 03:07:15,216 [salt.state       ][INFO    ][20646] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:07:15.216202
2017-12-03 03:07:15,216 [salt.state       ][INFO    ][20646] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-03 03:07:15,217 [salt.state       ][INFO    ][20646] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-03 03:07:15,217 [salt.state       ][INFO    ][20646] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:07:15.216664 duration_in_ms=0.461
2017-12-03 03:07:15,217 [salt.state       ][INFO    ][20646] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:07:15.216781
2017-12-03 03:07:15,217 [salt.state       ][INFO    ][20646] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-03 03:07:15,218 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-12-03 03:07:15,226 [salt.state       ][INFO    ][20646] {'pid': 20697, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-12-03 03:07:15,226 [salt.state       ][INFO    ][20646] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:07:15.225780 duration_in_ms=8.998
2017-12-03 03:07:15,226 [salt.state       ][INFO    ][20646] Running state [mine.update] at time 03:07:15.226414
2017-12-03 03:07:15,227 [salt.state       ][INFO    ][20646] Executing state module.wait for mine.update
2017-12-03 03:07:15,227 [salt.state       ][INFO    ][20646] No changes made for mine.update
2017-12-03 03:07:15,227 [salt.state       ][INFO    ][20646] Completed state [mine.update] at time 03:07:15.227057 duration_in_ms=0.642
2017-12-03 03:07:15,227 [salt.state       ][INFO    ][20646] Running state [mine.update] at time 03:07:15.227220
2017-12-03 03:07:15,227 [salt.state       ][INFO    ][20646] Executing state module.mod_watch for mine.update
2017-12-03 03:07:15,748 [salt.state       ][INFO    ][20646] {'ret': True}
2017-12-03 03:07:15,748 [salt.state       ][INFO    ][20646] Completed state [mine.update] at time 03:07:15.748292 duration_in_ms=521.07
2017-12-03 03:07:15,749 [salt.state       ][INFO    ][20646] Running state [ca-certificates] at time 03:07:15.748558
2017-12-03 03:07:15,749 [salt.state       ][INFO    ][20646] Executing state pkg.installed for ca-certificates
2017-12-03 03:07:15,752 [salt.state       ][INFO    ][20646] Package ca-certificates is already installed
2017-12-03 03:07:15,752 [salt.state       ][INFO    ][20646] Completed state [ca-certificates] at time 03:07:15.752430 duration_in_ms=3.872
2017-12-03 03:07:15,753 [salt.state       ][INFO    ][20646] Running state [update-ca-certificates] at time 03:07:15.753025
2017-12-03 03:07:15,753 [salt.state       ][INFO    ][20646] Executing state cmd.wait for update-ca-certificates
2017-12-03 03:07:15,754 [salt.state       ][INFO    ][20646] No changes made for update-ca-certificates
2017-12-03 03:07:15,754 [salt.state       ][INFO    ][20646] Completed state [update-ca-certificates] at time 03:07:15.753649 duration_in_ms=0.624
2017-12-03 03:07:15,754 [salt.state       ][INFO    ][20646] Running state [libvirt-dev] at time 03:07:15.753842
2017-12-03 03:07:15,754 [salt.state       ][INFO    ][20646] Executing state pkg.installed for libvirt-dev
2017-12-03 03:07:15,757 [salt.state       ][INFO    ][20646] Package libvirt-dev is already installed
2017-12-03 03:07:15,757 [salt.state       ][INFO    ][20646] Completed state [libvirt-dev] at time 03:07:15.756980 duration_in_ms=3.139
2017-12-03 03:07:15,757 [salt.state       ][INFO    ][20646] Running state [pkg-config] at time 03:07:15.757168
2017-12-03 03:07:15,757 [salt.state       ][INFO    ][20646] Executing state pkg.installed for pkg-config
2017-12-03 03:07:15,760 [salt.state       ][INFO    ][20646] Package pkg-config is already installed
2017-12-03 03:07:15,760 [salt.state       ][INFO    ][20646] Completed state [pkg-config] at time 03:07:15.760292 duration_in_ms=3.125
2017-12-03 03:07:15,761 [salt.state       ][INFO    ][20646] Running state [virtng.init] at time 03:07:15.760486
2017-12-03 03:07:15,761 [salt.state       ][INFO    ][20646] Executing state module.run for virtng.init
2017-12-03 03:07:15,865 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command ['git', '--version'] in directory '/root'
2017-12-03 03:07:15,906 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:15,926 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:15,926 [salt.state       ][INFO    ][20646] Completed state [virtng.init] at time 03:07:15.926008 duration_in_ms=165.522
2017-12-03 03:07:15,926 [salt.state       ][INFO    ][20646] Running state [virt.set_autostart] at time 03:07:15.926284
2017-12-03 03:07:15,927 [salt.state       ][INFO    ][20646] Executing state module.run for virt.set_autostart
2017-12-03 03:07:15,929 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:15,945 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:15,945 [salt.state       ][INFO    ][20646] Completed state [virt.set_autostart] at time 03:07:15.945395 duration_in_ms=19.111
2017-12-03 03:07:15,946 [salt.state       ][INFO    ][20646] Running state [virtng.init] at time 03:07:15.945611
2017-12-03 03:07:15,946 [salt.state       ][INFO    ][20646] Executing state module.run for virtng.init
2017-12-03 03:07:15,948 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:15,964 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:15,964 [salt.state       ][INFO    ][20646] Completed state [virtng.init] at time 03:07:15.963887 duration_in_ms=18.276
2017-12-03 03:07:15,964 [salt.state       ][INFO    ][20646] Running state [virt.set_autostart] at time 03:07:15.964155
2017-12-03 03:07:15,964 [salt.state       ][INFO    ][20646] Executing state module.run for virt.set_autostart
2017-12-03 03:07:15,966 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:15,984 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:15,984 [salt.state       ][INFO    ][20646] Completed state [virt.set_autostart] at time 03:07:15.983966 duration_in_ms=19.81
2017-12-03 03:07:15,984 [salt.state       ][INFO    ][20646] Running state [virtng.init] at time 03:07:15.984178
2017-12-03 03:07:15,984 [salt.state       ][INFO    ][20646] Executing state module.run for virtng.init
2017-12-03 03:07:15,986 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:16,001 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:16,001 [salt.state       ][INFO    ][20646] Completed state [virtng.init] at time 03:07:16.001411 duration_in_ms=17.232
2017-12-03 03:07:16,002 [salt.state       ][INFO    ][20646] Running state [virt.set_autostart] at time 03:07:16.001654
2017-12-03 03:07:16,002 [salt.state       ][INFO    ][20646] Executing state module.run for virt.set_autostart
2017-12-03 03:07:16,004 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:16,022 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:16,022 [salt.state       ][INFO    ][20646] Completed state [virt.set_autostart] at time 03:07:16.021803 duration_in_ms=20.149
2017-12-03 03:07:16,022 [salt.state       ][INFO    ][20646] Running state [virtng.init] at time 03:07:16.022026
2017-12-03 03:07:16,022 [salt.state       ][INFO    ][20646] Executing state module.run for virtng.init
2017-12-03 03:07:16,024 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:16,039 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:16,039 [salt.state       ][INFO    ][20646] Completed state [virtng.init] at time 03:07:16.039292 duration_in_ms=17.266
2017-12-03 03:07:16,040 [salt.state       ][INFO    ][20646] Running state [virt.set_autostart] at time 03:07:16.039543
2017-12-03 03:07:16,040 [salt.state       ][INFO    ][20646] Executing state module.run for virt.set_autostart
2017-12-03 03:07:16,042 [salt.loaded.int.module.cmdmod][INFO    ][20646] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:16,057 [salt.state       ][INFO    ][20646] unless execution succeeded
2017-12-03 03:07:16,058 [salt.state       ][INFO    ][20646] Completed state [virt.set_autostart] at time 03:07:16.057545 duration_in_ms=18.001
2017-12-03 03:07:16,060 [salt.minion      ][INFO    ][20646] Returning information for job: 20171203030712168832
2017-12-03 03:07:43,295 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command state.sls with jid 20171203030743287640
2017-12-03 03:07:43,312 [salt.minion      ][INFO    ][20748] Starting a new job with PID 20748
2017-12-03 03:07:44,861 [salt.state       ][INFO    ][20748] Loading fresh modules for state activity
2017-12-03 03:07:44,884 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-12-03 03:07:44,936 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-12-03 03:07:44,961 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:45,026 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-12-03 03:07:45,041 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-12-03 03:07:45,069 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:45,126 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-03 03:07:45,145 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-03 03:07:45,188 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-03 03:07:45,206 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-03 03:07:45,425 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-03 03:07:45,453 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-03 03:07:45,461 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-03 03:07:45,484 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-03 03:07:45,499 [py.warnings      ][WARNING ][20748] /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-03 03:07:45,499 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'salt-minion --version' in directory '/root'
2017-12-03 03:07:45,756 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-03 03:07:45,771 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-03 03:07:45,780 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-03 03:07:45,789 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-03 03:07:45,823 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-03 03:07:45,835 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-03 03:07:45,853 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-03 03:07:45,863 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-03 03:07:45,879 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-03 03:07:45,911 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-03 03:07:45,971 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-12-03 03:07:45,991 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:46,045 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-12-03 03:07:46,063 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-03 03:07:46,108 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-12-03 03:07:46,128 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-12-03 03:07:46,142 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-03 03:07:46,157 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-12-03 03:07:46,163 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-12-03 03:07:46,186 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-12-03 03:07:46,201 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'salt-minion --version' in directory '/root'
2017-12-03 03:07:46,451 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-12-03 03:07:46,470 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-12-03 03:07:46,482 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-12-03 03:07:46,493 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-12-03 03:07:46,529 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-12-03 03:07:46,545 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-12-03 03:07:46,566 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-12-03 03:07:46,579 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-12-03 03:07:46,598 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-12-03 03:07:46,633 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-12-03 03:07:46,695 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-12-03 03:07:46,749 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:47,021 [salt.state       ][INFO    ][20748] Running state [salt-minion] at time 03:07:47.021302
2017-12-03 03:07:47,022 [salt.state       ][INFO    ][20748] Executing state pkg.installed for salt-minion
2017-12-03 03:07:47,022 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:07:47,288 [salt.state       ][INFO    ][20748] Package salt-minion is already installed
2017-12-03 03:07:47,289 [salt.state       ][INFO    ][20748] Completed state [salt-minion] at time 03:07:47.288624 duration_in_ms=267.322
2017-12-03 03:07:47,289 [salt.state       ][INFO    ][20748] Running state [salt_minion_dependency_packages] at time 03:07:47.288902
2017-12-03 03:07:47,289 [salt.state       ][INFO    ][20748] Executing state pkg.installed for salt_minion_dependency_packages
2017-12-03 03:07:47,292 [salt.state       ][INFO    ][20748] All specified packages are already installed
2017-12-03 03:07:47,292 [salt.state       ][INFO    ][20748] Completed state [salt_minion_dependency_packages] at time 03:07:47.292185 duration_in_ms=3.283
2017-12-03 03:07:47,294 [salt.state       ][INFO    ][20748] Running state [/etc/salt/minion.d/minion.conf] at time 03:07:47.293954
2017-12-03 03:07:47,294 [salt.state       ][INFO    ][20748] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-12-03 03:07:47,315 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-12-03 03:07:47,362 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-12-03 03:07:47,406 [salt.fileclient  ][INFO    ][20748] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-12-03 03:07:47,439 [salt.state       ][INFO    ][20748] File /etc/salt/minion.d/minion.conf is in the correct state
2017-12-03 03:07:47,439 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/minion.d/minion.conf] at time 03:07:47.439142 duration_in_ms=145.188
2017-12-03 03:07:47,439 [salt.state       ][INFO    ][20748] Running state [/etc/salt/minion.d/_virt.conf] at time 03:07:47.439417
2017-12-03 03:07:47,440 [salt.state       ][INFO    ][20748] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-12-03 03:07:47,441 [salt.state       ][INFO    ][20748] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-12-03 03:07:47,442 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/minion.d/_virt.conf] at time 03:07:47.441492 duration_in_ms=2.074
2017-12-03 03:07:47,443 [salt.state       ][INFO    ][20748] Running state [salt-minion] at time 03:07:47.442820
2017-12-03 03:07:47,443 [salt.state       ][INFO    ][20748] Executing state service.running for salt-minion
2017-12-03 03:07:47,443 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-12-03 03:07:47,462 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-12-03 03:07:47,470 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-12-03 03:07:47,479 [salt.state       ][INFO    ][20748] The service salt-minion is already running
2017-12-03 03:07:47,479 [salt.state       ][INFO    ][20748] Completed state [salt-minion] at time 03:07:47.479054 duration_in_ms=36.234
2017-12-03 03:07:47,480 [salt.state       ][INFO    ][20748] Running state [/etc/salt/grains.d] at time 03:07:47.480398
2017-12-03 03:07:47,481 [salt.state       ][INFO    ][20748] Executing state file.directory for /etc/salt/grains.d
2017-12-03 03:07:47,481 [salt.state       ][INFO    ][20748] Directory /etc/salt/grains.d is in the correct state
2017-12-03 03:07:47,481 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/grains.d] at time 03:07:47.481273 duration_in_ms=0.874
2017-12-03 03:07:47,482 [salt.state       ][INFO    ][20748] Running state [/etc/salt/grains] at time 03:07:47.481676
2017-12-03 03:07:47,482 [salt.state       ][INFO    ][20748] Executing state file.managed for /etc/salt/grains
2017-12-03 03:07:47,482 [salt.state       ][INFO    ][20748] File /etc/salt/grains exists with proper permissions. No changes made.
2017-12-03 03:07:47,482 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/grains] at time 03:07:47.482310 duration_in_ms=0.634
2017-12-03 03:07:47,483 [salt.state       ][INFO    ][20748] Running state [/etc/salt/grains.d/placeholder] at time 03:07:47.482567
2017-12-03 03:07:47,483 [salt.state       ][INFO    ][20748] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-12-03 03:07:47,483 [salt.state       ][INFO    ][20748] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-12-03 03:07:47,483 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/grains.d/placeholder] at time 03:07:47.483159 duration_in_ms=0.593
2017-12-03 03:07:47,483 [salt.state       ][INFO    ][20748] Running state [/etc/salt/grains.d/sphinx] at time 03:07:47.483415
2017-12-03 03:07:47,484 [salt.state       ][INFO    ][20748] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-12-03 03:07:47,490 [salt.state       ][INFO    ][20748] File /etc/salt/grains.d/sphinx is in the correct state
2017-12-03 03:07:47,490 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/grains.d/sphinx] at time 03:07:47.490357 duration_in_ms=6.942
2017-12-03 03:07:47,491 [salt.state       ][INFO    ][20748] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:07:47.490894
2017-12-03 03:07:47,491 [salt.state       ][INFO    ][20748] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:47,491 [salt.state       ][INFO    ][20748] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:47,491 [salt.state       ][INFO    ][20748] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:07:47.491383 duration_in_ms=0.49
2017-12-03 03:07:47,492 [salt.state       ][INFO    ][20748] Running state [/etc/salt/grains.d/dns_records] at time 03:07:47.491656
2017-12-03 03:07:47,492 [salt.state       ][INFO    ][20748] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-12-03 03:07:47,496 [salt.state       ][INFO    ][20748] File /etc/salt/grains.d/dns_records is in the correct state
2017-12-03 03:07:47,496 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/grains.d/dns_records] at time 03:07:47.496152 duration_in_ms=4.496
2017-12-03 03:07:47,497 [salt.state       ][INFO    ][20748] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:07:47.496671
2017-12-03 03:07:47,497 [salt.state       ][INFO    ][20748] 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-03 03:07:47,497 [salt.state       ][INFO    ][20748] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:47,497 [salt.state       ][INFO    ][20748] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:07:47.497157 duration_in_ms=0.485
2017-12-03 03:07:47,497 [salt.state       ][INFO    ][20748] Running state [/etc/salt/grains.d/salt] at time 03:07:47.497416
2017-12-03 03:07:47,498 [salt.state       ][INFO    ][20748] Executing state file.managed for /etc/salt/grains.d/salt
2017-12-03 03:07:47,502 [salt.state       ][INFO    ][20748] File /etc/salt/grains.d/salt is in the correct state
2017-12-03 03:07:47,502 [salt.state       ][INFO    ][20748] Completed state [/etc/salt/grains.d/salt] at time 03:07:47.502379 duration_in_ms=4.962
2017-12-03 03:07:47,503 [salt.state       ][INFO    ][20748] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:07:47.502914
2017-12-03 03:07:47,503 [salt.state       ][INFO    ][20748] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:47,503 [salt.state       ][INFO    ][20748] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-12-03 03:07:47,503 [salt.state       ][INFO    ][20748] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:07:47.503403 duration_in_ms=0.488
2017-12-03 03:07:47,505 [salt.state       ][INFO    ][20748] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:07:47.504572
2017-12-03 03:07:47,505 [salt.state       ][INFO    ][20748] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-03 03:07:47,505 [salt.state       ][INFO    ][20748] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-12-03 03:07:47,505 [salt.state       ][INFO    ][20748] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:07:47.505073 duration_in_ms=0.501
2017-12-03 03:07:47,505 [salt.state       ][INFO    ][20748] Running state [mine.update] at time 03:07:47.505474
2017-12-03 03:07:47,506 [salt.state       ][INFO    ][20748] Executing state module.wait for mine.update
2017-12-03 03:07:47,506 [salt.state       ][INFO    ][20748] No changes made for mine.update
2017-12-03 03:07:47,506 [salt.state       ][INFO    ][20748] Completed state [mine.update] at time 03:07:47.505919 duration_in_ms=0.445
2017-12-03 03:07:47,506 [salt.state       ][INFO    ][20748] Running state [ca-certificates] at time 03:07:47.506060
2017-12-03 03:07:47,506 [salt.state       ][INFO    ][20748] Executing state pkg.installed for ca-certificates
2017-12-03 03:07:47,509 [salt.state       ][INFO    ][20748] Package ca-certificates is already installed
2017-12-03 03:07:47,509 [salt.state       ][INFO    ][20748] Completed state [ca-certificates] at time 03:07:47.509281 duration_in_ms=3.221
2017-12-03 03:07:47,510 [salt.state       ][INFO    ][20748] Running state [update-ca-certificates] at time 03:07:47.509684
2017-12-03 03:07:47,510 [salt.state       ][INFO    ][20748] Executing state cmd.wait for update-ca-certificates
2017-12-03 03:07:47,510 [salt.state       ][INFO    ][20748] No changes made for update-ca-certificates
2017-12-03 03:07:47,510 [salt.state       ][INFO    ][20748] Completed state [update-ca-certificates] at time 03:07:47.510150 duration_in_ms=0.466
2017-12-03 03:07:47,510 [salt.state       ][INFO    ][20748] Running state [libvirt-dev] at time 03:07:47.510292
2017-12-03 03:07:47,510 [salt.state       ][INFO    ][20748] Executing state pkg.installed for libvirt-dev
2017-12-03 03:07:47,513 [salt.state       ][INFO    ][20748] Package libvirt-dev is already installed
2017-12-03 03:07:47,513 [salt.state       ][INFO    ][20748] Completed state [libvirt-dev] at time 03:07:47.513151 duration_in_ms=2.86
2017-12-03 03:07:47,513 [salt.state       ][INFO    ][20748] Running state [pkg-config] at time 03:07:47.513296
2017-12-03 03:07:47,513 [salt.state       ][INFO    ][20748] Executing state pkg.installed for pkg-config
2017-12-03 03:07:47,516 [salt.state       ][INFO    ][20748] Package pkg-config is already installed
2017-12-03 03:07:47,516 [salt.state       ][INFO    ][20748] Completed state [pkg-config] at time 03:07:47.516159 duration_in_ms=2.863
2017-12-03 03:07:47,516 [salt.state       ][INFO    ][20748] Running state [virtng.init] at time 03:07:47.516304
2017-12-03 03:07:47,516 [salt.state       ][INFO    ][20748] Executing state module.run for virtng.init
2017-12-03 03:07:47,595 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command ['git', '--version'] in directory '/root'
2017-12-03 03:07:47,629 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,648 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,649 [salt.state       ][INFO    ][20748] Completed state [virtng.init] at time 03:07:47.648582 duration_in_ms=132.278
2017-12-03 03:07:47,649 [salt.state       ][INFO    ][20748] Running state [virt.set_autostart] at time 03:07:47.648848
2017-12-03 03:07:47,649 [salt.state       ][INFO    ][20748] Executing state module.run for virt.set_autostart
2017-12-03 03:07:47,651 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,668 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,668 [salt.state       ][INFO    ][20748] Completed state [virt.set_autostart] at time 03:07:47.668394 duration_in_ms=19.546
2017-12-03 03:07:47,669 [salt.state       ][INFO    ][20748] Running state [virtng.init] at time 03:07:47.668615
2017-12-03 03:07:47,669 [salt.state       ][INFO    ][20748] Executing state module.run for virtng.init
2017-12-03 03:07:47,671 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,686 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,686 [salt.state       ][INFO    ][20748] Completed state [virtng.init] at time 03:07:47.686423 duration_in_ms=17.808
2017-12-03 03:07:47,687 [salt.state       ][INFO    ][20748] Running state [virt.set_autostart] at time 03:07:47.686658
2017-12-03 03:07:47,687 [salt.state       ][INFO    ][20748] Executing state module.run for virt.set_autostart
2017-12-03 03:07:47,689 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,705 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,705 [salt.state       ][INFO    ][20748] Completed state [virt.set_autostart] at time 03:07:47.704748 duration_in_ms=18.09
2017-12-03 03:07:47,705 [salt.state       ][INFO    ][20748] Running state [virtng.init] at time 03:07:47.704944
2017-12-03 03:07:47,705 [salt.state       ][INFO    ][20748] Executing state module.run for virtng.init
2017-12-03 03:07:47,707 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,722 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,722 [salt.state       ][INFO    ][20748] Completed state [virtng.init] at time 03:07:47.722351 duration_in_ms=17.406
2017-12-03 03:07:47,723 [salt.state       ][INFO    ][20748] Running state [virt.set_autostart] at time 03:07:47.722613
2017-12-03 03:07:47,723 [salt.state       ][INFO    ][20748] Executing state module.run for virt.set_autostart
2017-12-03 03:07:47,725 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,741 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,741 [salt.state       ][INFO    ][20748] Completed state [virt.set_autostart] at time 03:07:47.740895 duration_in_ms=18.282
2017-12-03 03:07:47,741 [salt.state       ][INFO    ][20748] Running state [virtng.init] at time 03:07:47.741093
2017-12-03 03:07:47,741 [salt.state       ][INFO    ][20748] Executing state module.run for virtng.init
2017-12-03 03:07:47,743 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,758 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,759 [salt.state       ][INFO    ][20748] Completed state [virtng.init] at time 03:07:47.758486 duration_in_ms=17.392
2017-12-03 03:07:47,759 [salt.state       ][INFO    ][20748] Running state [virt.set_autostart] at time 03:07:47.758743
2017-12-03 03:07:47,759 [salt.state       ][INFO    ][20748] Executing state module.run for virt.set_autostart
2017-12-03 03:07:47,761 [salt.loaded.int.module.cmdmod][INFO    ][20748] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-ha.local$"' in directory '/root'
2017-12-03 03:07:47,776 [salt.state       ][INFO    ][20748] unless execution succeeded
2017-12-03 03:07:47,777 [salt.state       ][INFO    ][20748] Completed state [virt.set_autostart] at time 03:07:47.776599 duration_in_ms=17.855
2017-12-03 03:07:47,779 [salt.minion      ][INFO    ][20748] Returning information for job: 20171203030743287640
2017-12-03 03:08:32,006 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171203030831993077
2017-12-03 03:08:32,024 [salt.minion      ][INFO    ][20843] Starting a new job with PID 20843
2017-12-03 03:08:35,181 [salt.state       ][INFO    ][20843] Loading fresh modules for state activity
2017-12-03 03:08:35,403 [salt.state       ][ERROR   ][20843] No contents found in top file
2017-12-03 03:08:35,404 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-12-03 03:08:35,407 [salt.utils.extmods][INFO    ][20843] Syncing beacons for environment 'base'
2017-12-03 03:08:35,407 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_beacons, for base)
2017-12-03 03:08:35,407 [salt.fileclient  ][INFO    ][20843] Caching directory '_beacons/' for environment 'base'
2017-12-03 03:08:35,427 [salt.utils.extmods][INFO    ][20843] Syncing modules for environment 'base'
2017-12-03 03:08:35,427 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_modules, for base)
2017-12-03 03:08:35,427 [salt.fileclient  ][INFO    ][20843] Caching directory '_modules/' for environment 'base'
2017-12-03 03:08:35,468 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-12-03 03:08:35,476 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-12-03 03:08:35,490 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-12-03 03:08:35,656 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-12-03 03:08:35,775 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-12-03 03:08:35,786 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-12-03 03:08:35,797 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-12-03 03:08:35,806 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-12-03 03:08:35,829 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-12-03 03:08:35,862 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-12-03 03:08:35,876 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-12-03 03:08:35,889 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-12-03 03:08:35,903 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-12-03 03:08:35,930 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-12-03 03:08:35,964 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-12-03 03:08:35,972 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-12-03 03:08:35,997 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-12-03 03:08:36,008 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-12-03 03:08:36,043 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-12-03 03:08:36,087 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-12-03 03:08:36,117 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-12-03 03:08:36,126 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-12-03 03:08:36,174 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-12-03 03:08:36,183 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-12-03 03:08:36,209 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-12-03 03:08:36,219 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-12-03 03:08:36,228 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-12-03 03:08:36,270 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-12-03 03:08:36,277 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-12-03 03:08:36,333 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-12-03 03:08:36,347 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-12-03 03:08:36,376 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-12-03 03:08:36,387 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-12-03 03:08:36,420 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-12-03 03:08:36,425 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-12-03 03:08:36,425 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-12-03 03:08:36,426 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-12-03 03:08:36,426 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-12-03 03:08:36,426 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-12-03 03:08:36,426 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-12-03 03:08:36,427 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-12-03 03:08:36,427 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-12-03 03:08:36,427 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-12-03 03:08:36,427 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-12-03 03:08:36,427 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-12-03 03:08:36,428 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-12-03 03:08:36,428 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-12-03 03:08:36,428 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-12-03 03:08:36,428 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-12-03 03:08:36,428 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-12-03 03:08:36,429 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-12-03 03:08:36,429 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-12-03 03:08:36,429 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-12-03 03:08:36,429 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-12-03 03:08:36,430 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-12-03 03:08:36,430 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-12-03 03:08:36,430 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-12-03 03:08:36,430 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-12-03 03:08:36,431 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-12-03 03:08:36,431 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-12-03 03:08:36,431 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-12-03 03:08:36,431 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-12-03 03:08:36,431 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-12-03 03:08:36,432 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-12-03 03:08:36,432 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-12-03 03:08:36,432 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-12-03 03:08:36,432 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-12-03 03:08:36,432 [salt.utils.extmods][INFO    ][20843] 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-03 03:08:36,433 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-12-03 03:08:36,433 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-12-03 03:08:36,438 [salt.utils.extmods][INFO    ][20843] Syncing states for environment 'base'
2017-12-03 03:08:36,438 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_states, for base)
2017-12-03 03:08:36,438 [salt.fileclient  ][INFO    ][20843] Caching directory '_states/' for environment 'base'
2017-12-03 03:08:36,471 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-12-03 03:08:36,488 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-12-03 03:08:36,528 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-12-03 03:08:36,545 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-12-03 03:08:36,574 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-12-03 03:08:36,586 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-12-03 03:08:36,612 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-12-03 03:08:36,622 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-12-03 03:08:36,632 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-12-03 03:08:36,659 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-12-03 03:08:36,691 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-12-03 03:08:36,704 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-12-03 03:08:36,725 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-12-03 03:08:36,732 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-12-03 03:08:36,771 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-12-03 03:08:36,782 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-12-03 03:08:36,803 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-12-03 03:08:36,811 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-12-03 03:08:36,821 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-12-03 03:08:36,831 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-12-03 03:08:36,854 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-12-03 03:08:36,861 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-12-03 03:08:36,867 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-12-03 03:08:36,880 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-12-03 03:08:36,894 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-12-03 03:08:36,921 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-12-03 03:08:36,963 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-12-03 03:08:36,983 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-12-03 03:08:36,995 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-12-03 03:08:37,059 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-12-03 03:08:37,139 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-12-03 03:08:37,211 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-12-03 03:08:37,214 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-12-03 03:08:37,214 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-12-03 03:08:37,214 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-12-03 03:08:37,215 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-12-03 03:08:37,215 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-12-03 03:08:37,215 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-12-03 03:08:37,216 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-12-03 03:08:37,216 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-12-03 03:08:37,216 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-12-03 03:08:37,216 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-12-03 03:08:37,216 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-12-03 03:08:37,217 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-12-03 03:08:37,217 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-12-03 03:08:37,217 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-12-03 03:08:37,217 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-12-03 03:08:37,217 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-12-03 03:08:37,218 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-12-03 03:08:37,218 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-12-03 03:08:37,218 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-12-03 03:08:37,218 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-12-03 03:08:37,219 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-12-03 03:08:37,219 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-12-03 03:08:37,219 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-12-03 03:08:37,219 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-12-03 03:08:37,219 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-12-03 03:08:37,220 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-12-03 03:08:37,220 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-12-03 03:08:37,220 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-12-03 03:08:37,220 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-12-03 03:08:37,221 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-12-03 03:08:37,221 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-12-03 03:08:37,221 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-12-03 03:08:37,221 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-12-03 03:08:37,223 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-12-03 03:08:37,226 [salt.utils.extmods][INFO    ][20843] Syncing sdb for environment 'base'
2017-12-03 03:08:37,226 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_sdb, for base)
2017-12-03 03:08:37,226 [salt.fileclient  ][INFO    ][20843] Caching directory '_sdb/' for environment 'base'
2017-12-03 03:08:37,292 [salt.utils.extmods][INFO    ][20843] Syncing grains for environment 'base'
2017-12-03 03:08:37,292 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_grains, for base)
2017-12-03 03:08:37,292 [salt.fileclient  ][INFO    ][20843] Caching directory '_grains/' for environment 'base'
2017-12-03 03:08:37,335 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-12-03 03:08:37,348 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-12-03 03:08:37,358 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-12-03 03:08:37,369 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-12-03 03:08:37,379 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-12-03 03:08:37,389 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-12-03 03:08:37,399 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-12-03 03:08:37,424 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-12-03 03:08:37,434 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-12-03 03:08:37,446 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-12-03 03:08:37,455 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-12-03 03:08:37,466 [salt.fileclient  ][INFO    ][20843] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-12-03 03:08:37,468 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-12-03 03:08:37,469 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-12-03 03:08:37,469 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-12-03 03:08:37,469 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-12-03 03:08:37,469 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-12-03 03:08:37,470 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-12-03 03:08:37,470 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-12-03 03:08:37,470 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-12-03 03:08:37,470 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-12-03 03:08:37,471 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-12-03 03:08:37,471 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-12-03 03:08:37,471 [salt.utils.extmods][INFO    ][20843] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-12-03 03:08:37,472 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-12-03 03:08:37,475 [salt.utils.extmods][INFO    ][20843] Syncing renderers for environment 'base'
2017-12-03 03:08:37,475 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_renderers, for base)
2017-12-03 03:08:37,475 [salt.fileclient  ][INFO    ][20843] Caching directory '_renderers/' for environment 'base'
2017-12-03 03:08:37,506 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-12-03 03:08:37,509 [salt.utils.extmods][INFO    ][20843] Syncing returners for environment 'base'
2017-12-03 03:08:37,509 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_returners, for base)
2017-12-03 03:08:37,509 [salt.fileclient  ][INFO    ][20843] Caching directory '_returners/' for environment 'base'
2017-12-03 03:08:37,532 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-12-03 03:08:37,534 [salt.utils.extmods][INFO    ][20843] Syncing output for environment 'base'
2017-12-03 03:08:37,587 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_output, for base)
2017-12-03 03:08:37,587 [salt.fileclient  ][INFO    ][20843] Caching directory '_output/' for environment 'base'
2017-12-03 03:08:37,615 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-12-03 03:08:37,618 [salt.utils.extmods][INFO    ][20843] Syncing utils for environment 'base'
2017-12-03 03:08:37,618 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_utils, for base)
2017-12-03 03:08:37,618 [salt.fileclient  ][INFO    ][20843] Caching directory '_utils/' for environment 'base'
2017-12-03 03:08:37,643 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-12-03 03:08:37,645 [salt.utils.extmods][INFO    ][20843] Syncing log_handlers for environment 'base'
2017-12-03 03:08:37,645 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_log_handlers, for base)
2017-12-03 03:08:37,645 [salt.fileclient  ][INFO    ][20843] Caching directory '_log_handlers/' for environment 'base'
2017-12-03 03:08:37,670 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-12-03 03:08:37,672 [salt.utils.extmods][INFO    ][20843] Syncing proxy for environment 'base'
2017-12-03 03:08:37,672 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_proxy, for base)
2017-12-03 03:08:37,673 [salt.fileclient  ][INFO    ][20843] Caching directory '_proxy/' for environment 'base'
2017-12-03 03:08:37,701 [salt.utils.extmods][INFO    ][20843] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-12-03 03:08:37,703 [salt.utils.extmods][INFO    ][20843] Syncing engines for environment 'base'
2017-12-03 03:08:37,703 [salt.utils.extmods][INFO    ][20843] Loading cache from salt://_engines, for base)
2017-12-03 03:08:37,703 [salt.fileclient  ][INFO    ][20843] Caching directory '_engines/' for environment 'base'
2017-12-03 03:08:37,731 [salt.minion      ][INFO    ][20843] Returning information for job: 20171203030831993077
2017-12-03 03:19:27,366 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command test.ping with jid 20171203031927357185
2017-12-03 03:19:27,384 [salt.minion      ][INFO    ][21071] Starting a new job with PID 21071
2017-12-03 03:19:27,409 [salt.minion      ][INFO    ][21071] Returning information for job: 20171203031927357185
2017-12-03 03:22:47,474 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command state.sls with jid 20171203032247462704
2017-12-03 03:22:47,489 [salt.minion      ][INFO    ][21118] Starting a new job with PID 21118
2017-12-03 03:22:49,012 [salt.state       ][INFO    ][21118] Loading fresh modules for state activity
2017-12-03 03:22:49,036 [salt.fileclient  ][INFO    ][21118] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-12-03 03:22:49,052 [salt.fileclient  ][INFO    ][21118] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-12-03 03:22:49,073 [salt.fileclient  ][INFO    ][21118] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-03 03:22:49,366 [salt.state       ][INFO    ][21118] Running state [lsof] at time 03:22:49.365918
2017-12-03 03:22:49,366 [salt.state       ][INFO    ][21118] Executing state pkg.installed for lsof
2017-12-03 03:22:49,367 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:22:49,640 [salt.state       ][INFO    ][21118] Package lsof is already installed
2017-12-03 03:22:49,641 [salt.state       ][INFO    ][21118] Completed state [lsof] at time 03:22:49.640548 duration_in_ms=274.63
2017-12-03 03:22:49,641 [salt.state       ][INFO    ][21118] Running state [keepalived] at time 03:22:49.640736
2017-12-03 03:22:49,641 [salt.state       ][INFO    ][21118] Executing state pkg.installed for keepalived
2017-12-03 03:22:49,656 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-03 03:22:52,888 [salt.loaded.int.module.cmdmod][INFO    ][21118] 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-03 03:22:57,520 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.find_job with jid 20171203032257508364
2017-12-03 03:22:57,535 [salt.minion      ][INFO    ][22261] Starting a new job with PID 22261
2017-12-03 03:22:57,553 [salt.minion      ][INFO    ][22261] Returning information for job: 20171203032257508364
2017-12-03 03:22:59,787 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:22:59,811 [salt.state       ][INFO    ][21118] 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-03 03:22:59,819 [salt.state       ][INFO    ][21118] Loading fresh modules for state activity
2017-12-03 03:22:59,831 [salt.state       ][INFO    ][21118] Completed state [keepalived] at time 03:22:59.830906 duration_in_ms=10190.169
2017-12-03 03:22:59,834 [salt.state       ][INFO    ][21118] Running state [/etc/keepalived/keepalived.conf] at time 03:22:59.833852
2017-12-03 03:22:59,834 [salt.state       ][INFO    ][21118] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-12-03 03:22:59,862 [salt.fileclient  ][INFO    ][21118] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-12-03 03:22:59,924 [salt.fileclient  ][INFO    ][21118] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-12-03 03:22:59,929 [salt.state       ][INFO    ][21118] File changed:
New file
2017-12-03 03:22:59,930 [salt.state       ][INFO    ][21118] Completed state [/etc/keepalived/keepalived.conf] at time 03:22:59.929562 duration_in_ms=95.711
2017-12-03 03:22:59,983 [salt.state       ][INFO    ][21118] Running state [keepalived] at time 03:22:59.982935
2017-12-03 03:22:59,983 [salt.state       ][INFO    ][21118] Executing state service.running for keepalived
2017-12-03 03:22:59,985 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-12-03 03:22:59,993 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-03 03:23:00,000 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-03 03:23:00,007 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-03 03:23:00,015 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-12-03 03:23:00,073 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-12-03 03:23:00,081 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-03 03:23:00,090 [salt.loaded.int.module.cmdmod][INFO    ][21118] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-12-03 03:23:00,097 [salt.state       ][INFO    ][21118] {'keepalived': True}
2017-12-03 03:23:00,097 [salt.state       ][INFO    ][21118] Completed state [keepalived] at time 03:23:00.096837 duration_in_ms=113.901
2017-12-03 03:23:00,098 [salt.minion      ][INFO    ][21118] Returning information for job: 20171203032247462704
2017-12-03 03:23:26,027 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command pillar.get with jid 20171203032326020913
2017-12-03 03:23:26,046 [salt.minion      ][INFO    ][22554] Starting a new job with PID 22554
2017-12-03 03:23:26,049 [salt.minion      ][INFO    ][22554] Returning information for job: 20171203032326020913
2017-12-03 03:29:10,827 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command state.sls with jid 20171203032910814763
2017-12-03 03:29:10,840 [salt.minion      ][INFO    ][22657] Starting a new job with PID 22657
2017-12-03 03:29:12,277 [salt.state       ][INFO    ][22657] Loading fresh modules for state activity
2017-12-03 03:29:12,299 [salt.fileclient  ][INFO    ][22657] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-03 03:29:12,319 [salt.fileclient  ][INFO    ][22657] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-03 03:29:12,579 [salt.state       ][INFO    ][22657] Running state [attr] at time 03:29:12.579294
2017-12-03 03:29:12,580 [salt.state       ][INFO    ][22657] Executing state pkg.installed for attr
2017-12-03 03:29:12,580 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:29:12,844 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-12-03 03:29:14,587 [salt.loaded.int.module.cmdmod][INFO    ][22657] 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-03 03:29:16,928 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:29:16,954 [salt.state       ][INFO    ][22657] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-12-03 03:29:16,963 [salt.state       ][INFO    ][22657] Loading fresh modules for state activity
2017-12-03 03:29:16,976 [salt.state       ][INFO    ][22657] Completed state [attr] at time 03:29:16.975621 duration_in_ms=4396.326
2017-12-03 03:29:16,981 [salt.state       ][INFO    ][22657] Running state [glusterfs-server] at time 03:29:16.981114
2017-12-03 03:29:16,981 [salt.state       ][INFO    ][22657] Executing state pkg.installed for glusterfs-server
2017-12-03 03:29:17,182 [salt.loaded.int.module.cmdmod][INFO    ][22657] 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-03 03:29:20,922 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.find_job with jid 20171203032920909064
2017-12-03 03:29:20,937 [salt.minion      ][INFO    ][23480] Starting a new job with PID 23480
2017-12-03 03:29:20,949 [salt.minion      ][INFO    ][23480] Returning information for job: 20171203032920909064
2017-12-03 03:29:31,134 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.find_job with jid 20171203032931121613
2017-12-03 03:29:31,149 [salt.minion      ][INFO    ][23551] Starting a new job with PID 23551
2017-12-03 03:29:31,163 [salt.minion      ][INFO    ][23551] Returning information for job: 20171203032931121613
2017-12-03 03:29:41,343 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.find_job with jid 20171203032941329577
2017-12-03 03:29:41,357 [salt.minion      ][INFO    ][24996] Starting a new job with PID 24996
2017-12-03 03:29:41,369 [salt.minion      ][INFO    ][24996] Returning information for job: 20171203032941329577
2017-12-03 03:29:41,789 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:29:41,814 [salt.state       ][INFO    ][22657] 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-03 03:29:41,821 [salt.state       ][INFO    ][22657] Loading fresh modules for state activity
2017-12-03 03:29:41,833 [salt.state       ][INFO    ][22657] Completed state [glusterfs-server] at time 03:29:41.832748 duration_in_ms=24851.634
2017-12-03 03:29:41,837 [salt.state       ][INFO    ][22657] Running state [bc] at time 03:29:41.837111
2017-12-03 03:29:41,837 [salt.state       ][INFO    ][22657] Executing state pkg.installed for bc
2017-12-03 03:29:42,070 [salt.loaded.int.module.cmdmod][INFO    ][22657] 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-03 03:29:44,819 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:29:44,845 [salt.state       ][INFO    ][22657] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-12-03 03:29:44,855 [salt.state       ][INFO    ][22657] Loading fresh modules for state activity
2017-12-03 03:29:44,868 [salt.state       ][INFO    ][22657] Completed state [bc] at time 03:29:44.868054 duration_in_ms=3030.942
2017-12-03 03:29:44,932 [salt.state       ][INFO    ][22657] Running state [glusterfs-server] at time 03:29:44.932302
2017-12-03 03:29:44,933 [salt.state       ][INFO    ][22657] Executing state service.running for glusterfs-server
2017-12-03 03:29:44,934 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-03 03:29:44,943 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-03 03:29:44,951 [salt.loaded.int.module.cmdmod][INFO    ][22657] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-03 03:29:44,959 [salt.state       ][INFO    ][22657] The service glusterfs-server is already running
2017-12-03 03:29:44,959 [salt.state       ][INFO    ][22657] Completed state [glusterfs-server] at time 03:29:44.959364 duration_in_ms=27.061
2017-12-03 03:29:44,961 [salt.state       ][INFO    ][22657] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:29:44.961063
2017-12-03 03:29:44,961 [salt.state       ][INFO    ][22657] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-03 03:29:44,971 [salt.state       ][INFO    ][22657] File changed:
New file
2017-12-03 03:29:44,971 [salt.state       ][INFO    ][22657] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:29:44.970799 duration_in_ms=9.737
2017-12-03 03:29:44,971 [salt.state       ][INFO    ][22657] Running state [/srv/glusterfs/glance] at time 03:29:44.970950
2017-12-03 03:29:44,971 [salt.state       ][INFO    ][22657] Executing state file.directory for /srv/glusterfs/glance
2017-12-03 03:29:44,972 [salt.state       ][INFO    ][22657] {'/srv/glusterfs/glance': 'New Dir'}
2017-12-03 03:29:44,972 [salt.state       ][INFO    ][22657] Completed state [/srv/glusterfs/glance] at time 03:29:44.971734 duration_in_ms=0.783
2017-12-03 03:29:44,972 [salt.state       ][INFO    ][22657] Running state [/srv/glusterfs/keystone-keys] at time 03:29:44.971880
2017-12-03 03:29:44,972 [salt.state       ][INFO    ][22657] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-03 03:29:44,972 [salt.state       ][INFO    ][22657] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-12-03 03:29:44,973 [salt.state       ][INFO    ][22657] Completed state [/srv/glusterfs/keystone-keys] at time 03:29:44.972504 duration_in_ms=0.623
2017-12-03 03:29:44,973 [salt.state       ][INFO    ][22657] Running state [/srv/glusterfs/nova_instances] at time 03:29:44.972641
2017-12-03 03:29:44,973 [salt.state       ][INFO    ][22657] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-03 03:29:44,973 [salt.state       ][INFO    ][22657] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-12-03 03:29:44,973 [salt.state       ][INFO    ][22657] Completed state [/srv/glusterfs/nova_instances] at time 03:29:44.973237 duration_in_ms=0.596
2017-12-03 03:29:44,973 [salt.state       ][INFO    ][22657] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:29:44.973376
2017-12-03 03:29:44,974 [salt.state       ][INFO    ][22657] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-03 03:29:44,974 [salt.state       ][INFO    ][22657] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-12-03 03:29:44,974 [salt.state       ][INFO    ][22657] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:29:44.973976 duration_in_ms=0.601
2017-12-03 03:29:44,975 [salt.minion      ][INFO    ][22657] Returning information for job: 20171203032910814763
2017-12-03 03:29:48,120 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command test.ping with jid 20171203032948108420
2017-12-03 03:29:48,135 [salt.minion      ][INFO    ][25432] Starting a new job with PID 25432
2017-12-03 03:29:48,160 [salt.minion      ][INFO    ][25432] Returning information for job: 20171203032948108420
2017-12-03 03:29:48,299 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command state.sls with jid 20171203032948287606
2017-12-03 03:29:48,313 [salt.minion      ][INFO    ][25437] Starting a new job with PID 25437
2017-12-03 03:29:50,015 [salt.state       ][INFO    ][25437] Loading fresh modules for state activity
2017-12-03 03:29:50,036 [salt.fileclient  ][INFO    ][25437] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-12-03 03:29:50,070 [salt.fileclient  ][INFO    ][25437] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-03 03:29:50,139 [salt.fileclient  ][INFO    ][25437] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-12-03 03:29:50,159 [salt.fileclient  ][INFO    ][25437] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-12-03 03:29:50,431 [salt.state       ][INFO    ][25437] Running state [attr] at time 03:29:50.430574
2017-12-03 03:29:50,431 [salt.state       ][INFO    ][25437] Executing state pkg.installed for attr
2017-12-03 03:29:50,431 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-12-03 03:29:50,705 [salt.state       ][INFO    ][25437] Package attr is already installed
2017-12-03 03:29:50,706 [salt.state       ][INFO    ][25437] Completed state [attr] at time 03:29:50.705735 duration_in_ms=275.16
2017-12-03 03:29:50,706 [salt.state       ][INFO    ][25437] Running state [glusterfs-server] at time 03:29:50.705936
2017-12-03 03:29:50,706 [salt.state       ][INFO    ][25437] Executing state pkg.installed for glusterfs-server
2017-12-03 03:29:50,709 [salt.state       ][INFO    ][25437] Package glusterfs-server is already installed
2017-12-03 03:29:50,709 [salt.state       ][INFO    ][25437] Completed state [glusterfs-server] at time 03:29:50.708912 duration_in_ms=2.976
2017-12-03 03:29:50,709 [salt.state       ][INFO    ][25437] Running state [bc] at time 03:29:50.709047
2017-12-03 03:29:50,709 [salt.state       ][INFO    ][25437] Executing state pkg.installed for bc
2017-12-03 03:29:50,712 [salt.state       ][INFO    ][25437] Package bc is already installed
2017-12-03 03:29:50,712 [salt.state       ][INFO    ][25437] Completed state [bc] at time 03:29:50.711845 duration_in_ms=2.798
2017-12-03 03:29:50,713 [salt.state       ][INFO    ][25437] Running state [glusterfs-server] at time 03:29:50.712677
2017-12-03 03:29:50,713 [salt.state       ][INFO    ][25437] Executing state service.running for glusterfs-server
2017-12-03 03:29:50,713 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-03 03:29:50,723 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-12-03 03:29:50,767 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-12-03 03:29:50,783 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-12-03 03:29:50,790 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-12-03 03:29:50,799 [salt.state       ][INFO    ][25437] The service glusterfs-server is already running
2017-12-03 03:29:50,799 [salt.state       ][INFO    ][25437] Completed state [glusterfs-server] at time 03:29:50.799200 duration_in_ms=86.522
2017-12-03 03:29:50,801 [salt.state       ][INFO    ][25437] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:29:50.800636
2017-12-03 03:29:50,801 [salt.state       ][INFO    ][25437] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-12-03 03:29:50,809 [salt.state       ][INFO    ][25437] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-12-03 03:29:50,809 [salt.state       ][INFO    ][25437] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 03:29:50.809049 duration_in_ms=8.413
2017-12-03 03:29:50,809 [salt.state       ][INFO    ][25437] Running state [/srv/glusterfs/glance] at time 03:29:50.809202
2017-12-03 03:29:50,809 [salt.state       ][INFO    ][25437] Executing state file.directory for /srv/glusterfs/glance
2017-12-03 03:29:50,810 [salt.state       ][INFO    ][25437] Directory /srv/glusterfs/glance is in the correct state
2017-12-03 03:29:50,810 [salt.state       ][INFO    ][25437] Completed state [/srv/glusterfs/glance] at time 03:29:50.809784 duration_in_ms=0.583
2017-12-03 03:29:50,810 [salt.state       ][INFO    ][25437] Running state [/srv/glusterfs/keystone-keys] at time 03:29:50.809932
2017-12-03 03:29:50,810 [salt.state       ][INFO    ][25437] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-12-03 03:29:50,810 [salt.state       ][INFO    ][25437] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-12-03 03:29:50,811 [salt.state       ][INFO    ][25437] Completed state [/srv/glusterfs/keystone-keys] at time 03:29:50.810499 duration_in_ms=0.566
2017-12-03 03:29:50,811 [salt.state       ][INFO    ][25437] Running state [/srv/glusterfs/nova_instances] at time 03:29:50.810642
2017-12-03 03:29:50,811 [salt.state       ][INFO    ][25437] Executing state file.directory for /srv/glusterfs/nova_instances
2017-12-03 03:29:50,811 [salt.state       ][INFO    ][25437] Directory /srv/glusterfs/nova_instances is in the correct state
2017-12-03 03:29:50,811 [salt.state       ][INFO    ][25437] Completed state [/srv/glusterfs/nova_instances] at time 03:29:50.811216 duration_in_ms=0.575
2017-12-03 03:29:50,811 [salt.state       ][INFO    ][25437] Running state [/srv/glusterfs/keystone-credential-keys] at time 03:29:50.811358
2017-12-03 03:29:50,812 [salt.state       ][INFO    ][25437] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-12-03 03:29:50,812 [salt.state       ][INFO    ][25437] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-12-03 03:29:50,812 [salt.state       ][INFO    ][25437] Completed state [/srv/glusterfs/keystone-credential-keys] at time 03:29:50.811929 duration_in_ms=0.571
2017-12-03 03:29:50,867 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command ['git', '--version'] in directory '/root'
2017-12-03 03:29:50,948 [salt.state       ][INFO    ][25437] Running state [sleep 5] at time 03:29:50.947759
2017-12-03 03:29:50,948 [salt.state       ][INFO    ][25437] Executing state cmd.wait for sleep 5
2017-12-03 03:29:50,948 [salt.state       ][INFO    ][25437] No changes made for sleep 5
2017-12-03 03:29:50,948 [salt.state       ][INFO    ][25437] Completed state [sleep 5] at time 03:29:50.948329 duration_in_ms=0.572
2017-12-03 03:29:50,949 [salt.state       ][INFO    ][25437] Running state [10.167.4.141] at time 03:29:50.948729
2017-12-03 03:29:50,949 [salt.state       ][INFO    ][25437] Executing state glusterfs.peered for 10.167.4.141
2017-12-03 03:29:50,949 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:50,960 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,013 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,022 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,360 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,371 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,456 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,467 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,631 [salt.state       ][INFO    ][25437] {'new': {'10.167.4.141': []}, 'old': None}
2017-12-03 03:29:51,632 [salt.state       ][INFO    ][25437] Completed state [10.167.4.141] at time 03:29:51.631537 duration_in_ms=682.806
2017-12-03 03:29:51,634 [salt.state       ][INFO    ][25437] Running state [10.167.4.142] at time 03:29:51.633938
2017-12-03 03:29:51,634 [salt.state       ][INFO    ][25437] Executing state glusterfs.peered for 10.167.4.142
2017-12-03 03:29:51,635 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,645 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,703 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,714 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,772 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,783 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,838 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,849 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,905 [salt.state       ][INFO    ][25437] Peering with localhost is not needed
2017-12-03 03:29:51,905 [salt.state       ][INFO    ][25437] Completed state [10.167.4.142] at time 03:29:51.904955 duration_in_ms=271.017
2017-12-03 03:29:51,907 [salt.state       ][INFO    ][25437] Running state [10.167.4.143] at time 03:29:51.907152
2017-12-03 03:29:51,907 [salt.state       ][INFO    ][25437] Executing state glusterfs.peered for 10.167.4.143
2017-12-03 03:29:51,908 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,917 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:51,970 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:51,981 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:52,284 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:52,295 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:52,353 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:52,363 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:52,423 [salt.state       ][INFO    ][25437] {'new': {'10.167.4.141': [], '10.167.4.143': []}, 'old': {'10.167.4.141': []}}
2017-12-03 03:29:52,423 [salt.state       ][INFO    ][25437] Completed state [10.167.4.143] at time 03:29:52.423284 duration_in_ms=516.131
2017-12-03 03:29:52,424 [salt.state       ][INFO    ][25437] Running state [glance] at time 03:29:52.424097
2017-12-03 03:29:52,424 [salt.state       ][INFO    ][25437] Executing state glusterfs.created for glance
2017-12-03 03:29:52,425 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:52,434 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:52,557 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:52,567 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:53,488 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:53,501 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:56,243 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:29:56,256 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:29:56,313 [salt.state       ][INFO    ][25437] {'new': ['glance'], 'old': []}
2017-12-03 03:29:56,314 [salt.state       ][INFO    ][25437] Completed state [glance] at time 03:29:56.313456 duration_in_ms=3889.357
2017-12-03 03:29:56,317 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:29:56.316720
2017-12-03 03:29:56,317 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-12-03 03:29:56,318 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-03 03:29:56,374 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:29:57,047 [salt.state       ][INFO    ][25437] {'pid': 25701, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:29:57,047 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 03:29:57.047132 duration_in_ms=730.41
2017-12-03 03:29:57,048 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:29:57.047692
2017-12-03 03:29:57,048 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-12-03 03:29:57,049 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-03 03:29:57,109 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-12-03 03:29:57,704 [salt.state       ][INFO    ][25437] {'pid': 25740, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:29:57,705 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 03:29:57.704526 duration_in_ms=656.832
2017-12-03 03:29:57,705 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:29:57.705091
2017-12-03 03:29:57,705 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-12-03 03:29:57,706 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-12-03 03:29:57,768 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-12-03 03:29:58,343 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.find_job with jid 20171203032958330233
2017-12-03 03:29:58,358 [salt.minion      ][INFO    ][25805] Starting a new job with PID 25805
2017-12-03 03:29:58,368 [salt.minion      ][INFO    ][25805] Returning information for job: 20171203032958330233
2017-12-03 03:29:58,377 [salt.state       ][INFO    ][25437] {'pid': 25777, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:29:58,378 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 03:29:58.377579 duration_in_ms=672.486
2017-12-03 03:29:58,378 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 03:29:58.378101
2017-12-03 03:29:58,378 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-12-03 03:29:58,379 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-03 03:29:58,433 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-03 03:29:59,080 [salt.state       ][INFO    ][25437] {'pid': 25819, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:29:59,081 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 03:29:59.080547 duration_in_ms=702.445
2017-12-03 03:29:59,081 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:29:59.081039
2017-12-03 03:29:59,081 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-12-03 03:29:59,082 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-03 03:29:59,142 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:29:59,781 [salt.state       ][INFO    ][25437] {'pid': 25859, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:29:59,781 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 03:29:59.781254 duration_in_ms=700.214
2017-12-03 03:29:59,782 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:29:59.781800
2017-12-03 03:29:59,782 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-12-03 03:29:59,783 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-03 03:29:59,844 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-03 03:30:00,457 [salt.state       ][INFO    ][25437] {'pid': 25896, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:00,457 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 03:30:00.456858 duration_in_ms=675.058
2017-12-03 03:30:00,458 [salt.state       ][INFO    ][25437] Running state [glance] at time 03:30:00.458050
2017-12-03 03:30:00,458 [salt.state       ][INFO    ][25437] Executing state glusterfs.started for glance
2017-12-03 03:30:00,459 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:00,470 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:00,530 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:00,540 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:00,601 [salt.state       ][INFO    ][25437] Volume glance is already started
2017-12-03 03:30:00,602 [salt.state       ][INFO    ][25437] Completed state [glance] at time 03:30:00.601622 duration_in_ms=143.57
2017-12-03 03:30:00,603 [salt.state       ][INFO    ][25437] Running state [keystone-keys] at time 03:30:00.602531
2017-12-03 03:30:00,603 [salt.state       ][INFO    ][25437] Executing state glusterfs.created for keystone-keys
2017-12-03 03:30:00,603 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:00,613 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:00,676 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:00,687 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:01,427 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:01,440 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:04,129 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:04,141 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:04,197 [salt.state       ][INFO    ][25437] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-12-03 03:30:04,197 [salt.state       ][INFO    ][25437] Completed state [keystone-keys] at time 03:30:04.197146 duration_in_ms=3594.614
2017-12-03 03:30:04,203 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:30:04.203443
2017-12-03 03:30:04,204 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-12-03 03:30:04,205 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-03 03:30:04,262 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-12-03 03:30:04,928 [salt.state       ][INFO    ][25437] {'pid': 26049, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:04,928 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 03:30:04.927824 duration_in_ms=724.38
2017-12-03 03:30:04,928 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:30:04.928449
2017-12-03 03:30:04,929 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-03 03:30:04,929 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-03 03:30:04,989 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:30:05,635 [salt.state       ][INFO    ][25437] {'pid': 26086, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:05,636 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:30:05.635606 duration_in_ms=707.156
2017-12-03 03:30:05,636 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:30:05.636214
2017-12-03 03:30:05,637 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-03 03:30:05,637 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-03 03:30:05,699 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-03 03:30:06,387 [salt.state       ][INFO    ][25437] {'pid': 26125, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:06,387 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:30:06.386935 duration_in_ms=750.721
2017-12-03 03:30:06,388 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:30:06.387531
2017-12-03 03:30:06,388 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-03 03:30:06,389 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-03 03:30:06,450 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:30:07,051 [salt.state       ][INFO    ][25437] {'pid': 26162, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:07,052 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:30:07.051571 duration_in_ms=664.038
2017-12-03 03:30:07,053 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:30:07.052494
2017-12-03 03:30:07,053 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-12-03 03:30:07,054 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-03 03:30:07,114 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-12-03 03:30:07,733 [salt.state       ][INFO    ][25437] {'pid': 26202, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:07,733 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 03:30:07.733029 duration_in_ms=680.536
2017-12-03 03:30:07,734 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:30:07.733643
2017-12-03 03:30:07,734 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-12-03 03:30:07,735 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-03 03:30:07,795 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-03 03:30:08,429 [salt.state       ][INFO    ][25437] {'pid': 26239, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:08,429 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 03:30:08.429187 duration_in_ms=695.542
2017-12-03 03:30:08,430 [salt.state       ][INFO    ][25437] Running state [keystone-keys] at time 03:30:08.430399
2017-12-03 03:30:08,431 [salt.state       ][INFO    ][25437] Executing state glusterfs.started for keystone-keys
2017-12-03 03:30:08,432 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:08,444 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:08,485 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.find_job with jid 20171203033008472004
2017-12-03 03:30:08,500 [salt.minion      ][INFO    ][26275] Starting a new job with PID 26275
2017-12-03 03:30:08,501 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:08,510 [salt.minion      ][INFO    ][26275] Returning information for job: 20171203033008472004
2017-12-03 03:30:08,510 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:08,569 [salt.state       ][INFO    ][25437] Volume keystone-keys is already started
2017-12-03 03:30:08,570 [salt.state       ][INFO    ][25437] Completed state [keystone-keys] at time 03:30:08.569532 duration_in_ms=139.132
2017-12-03 03:30:08,570 [salt.state       ][INFO    ][25437] Running state [nova_instances] at time 03:30:08.570414
2017-12-03 03:30:08,571 [salt.state       ][INFO    ][25437] Executing state glusterfs.created for nova_instances
2017-12-03 03:30:08,571 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:08,581 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:08,634 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:08,644 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:09,325 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:09,337 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:12,027 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:12,040 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:12,099 [salt.state       ][INFO    ][25437] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-12-03 03:30:12,100 [salt.state       ][INFO    ][25437] Completed state [nova_instances] at time 03:30:12.099528 duration_in_ms=3529.114
2017-12-03 03:30:12,103 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:30:12.103017
2017-12-03 03:30:12,103 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-12-03 03:30:12,104 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-03 03:30:12,161 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:30:12,844 [salt.state       ][INFO    ][25437] {'pid': 26399, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:12,844 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 03:30:12.844273 duration_in_ms=741.255
2017-12-03 03:30:12,845 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 03:30:12.844983
2017-12-03 03:30:12,845 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-12-03 03:30:12,846 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-03 03:30:12,908 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-03 03:30:13,554 [salt.state       ][INFO    ][25437] {'pid': 26438, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:13,554 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 03:30:13.554107 duration_in_ms=709.123
2017-12-03 03:30:13,555 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 03:30:13.554958
2017-12-03 03:30:13,555 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-12-03 03:30:13,556 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-03 03:30:13,615 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'True'' in directory '/root'
2017-12-03 03:30:14,381 [salt.state       ][INFO    ][25437] {'pid': 26475, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:14,381 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 03:30:14.380838 duration_in_ms=825.88
2017-12-03 03:30:14,381 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 03:30:14.381392
2017-12-03 03:30:14,382 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-12-03 03:30:14,382 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-03 03:30:14,444 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-03 03:30:15,100 [salt.state       ][INFO    ][25437] {'pid': 26512, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:15,101 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 03:30:15.100679 duration_in_ms=719.286
2017-12-03 03:30:15,101 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 03:30:15.101266
2017-12-03 03:30:15,102 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-12-03 03:30:15,102 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-12-03 03:30:15,164 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'True'' in directory '/root'
2017-12-03 03:30:15,810 [salt.state       ][INFO    ][25437] {'pid': 26551, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:15,811 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 03:30:15.810667 duration_in_ms=709.4
2017-12-03 03:30:15,811 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:30:15.811286
2017-12-03 03:30:15,812 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-12-03 03:30:15,812 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-03 03:30:15,872 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:30:16,521 [salt.state       ][INFO    ][25437] {'pid': 26588, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:16,521 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 03:30:16.521022 duration_in_ms=709.735
2017-12-03 03:30:16,522 [salt.state       ][INFO    ][25437] Running state [nova_instances] at time 03:30:16.522227
2017-12-03 03:30:16,523 [salt.state       ][INFO    ][25437] Executing state glusterfs.started for nova_instances
2017-12-03 03:30:16,523 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:16,535 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:16,592 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:16,602 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:16,662 [salt.state       ][INFO    ][25437] Volume nova_instances is already started
2017-12-03 03:30:16,662 [salt.state       ][INFO    ][25437] Completed state [nova_instances] at time 03:30:16.661965 duration_in_ms=139.737
2017-12-03 03:30:16,663 [salt.state       ][INFO    ][25437] Running state [keystone-credential-keys] at time 03:30:16.662913
2017-12-03 03:30:16,663 [salt.state       ][INFO    ][25437] Executing state glusterfs.created for keystone-credential-keys
2017-12-03 03:30:16,664 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:16,674 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:16,730 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:16,740 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:17,460 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:17,473 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:18,626 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command saltutil.find_job with jid 20171203033018612393
2017-12-03 03:30:18,641 [salt.minion      ][INFO    ][26686] Starting a new job with PID 26686
2017-12-03 03:30:18,651 [salt.minion      ][INFO    ][26686] Returning information for job: 20171203033018612393
2017-12-03 03:30:20,148 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:20,161 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:20,218 [salt.state       ][INFO    ][25437] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-12-03 03:30:20,219 [salt.state       ][INFO    ][25437] Completed state [keystone-credential-keys] at time 03:30:20.218633 duration_in_ms=3555.72
2017-12-03 03:30:20,222 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:30:20.222400
2017-12-03 03:30:20,223 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-12-03 03:30:20,223 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-12-03 03:30:20,284 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-12-03 03:30:20,903 [salt.state       ][INFO    ][25437] {'pid': 26750, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:20,904 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 03:30:20.903707 duration_in_ms=681.307
2017-12-03 03:30:20,905 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:30:20.904436
2017-12-03 03:30:20,905 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-12-03 03:30:20,906 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-12-03 03:30:20,965 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-12-03 03:30:21,628 [salt.state       ][INFO    ][25437] {'pid': 26787, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:21,629 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 03:30:21.628695 duration_in_ms=724.259
2017-12-03 03:30:21,629 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:30:21.629347
2017-12-03 03:30:21,630 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-12-03 03:30:21,630 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-12-03 03:30:21,691 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:30:22,294 [salt.state       ][INFO    ][25437] {'pid': 26825, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:22,295 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 03:30:22.294542 duration_in_ms=665.193
2017-12-03 03:30:22,295 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:30:22.295131
2017-12-03 03:30:22,295 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-12-03 03:30:22,296 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-12-03 03:30:22,358 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-12-03 03:30:23,002 [salt.state       ][INFO    ][25437] {'pid': 26865, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:23,002 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 03:30:23.002032 duration_in_ms=706.901
2017-12-03 03:30:23,003 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:30:23.002735
2017-12-03 03:30:23,003 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-12-03 03:30:23,004 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-12-03 03:30:23,061 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-12-03 03:30:23,746 [salt.state       ][INFO    ][25437] {'pid': 26905, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:23,747 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 03:30:23.746673 duration_in_ms=743.939
2017-12-03 03:30:23,747 [salt.state       ][INFO    ][25437] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:30:23.747253
2017-12-03 03:30:23,748 [salt.state       ][INFO    ][25437] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-12-03 03:30:23,748 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-12-03 03:30:23,809 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-12-03 03:30:24,455 [salt.state       ][INFO    ][25437] {'pid': 26943, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-12-03 03:30:24,455 [salt.state       ][INFO    ][25437] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 03:30:24.455300 duration_in_ms=708.046
2017-12-03 03:30:24,457 [salt.state       ][INFO    ][25437] Running state [keystone-credential-keys] at time 03:30:24.456622
2017-12-03 03:30:24,457 [salt.state       ][INFO    ][25437] Executing state glusterfs.started for keystone-credential-keys
2017-12-03 03:30:24,458 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:24,470 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:24,529 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --version' in directory '/root'
2017-12-03 03:30:24,540 [salt.loaded.int.module.cmdmod][INFO    ][25437] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-12-03 03:30:24,599 [salt.state       ][INFO    ][25437] Volume keystone-credential-keys is already started
2017-12-03 03:30:24,600 [salt.state       ][INFO    ][25437] Completed state [keystone-credential-keys] at time 03:30:24.599614 duration_in_ms=142.99
2017-12-03 03:30:24,603 [salt.minion      ][INFO    ][25437] Returning information for job: 20171203032948287606
2017-12-03 03:30:35,845 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command test.ping with jid 20171203033035834421
2017-12-03 03:30:35,861 [salt.minion      ][INFO    ][26994] Starting a new job with PID 26994
2017-12-03 03:30:35,886 [salt.minion      ][INFO    ][26994] Returning information for job: 20171203033035834421
2017-12-03 03:30:36,018 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command cmd.run with jid 20171203033036005760
2017-12-03 03:30:36,033 [salt.minion      ][INFO    ][26999] Starting a new job with PID 26999
2017-12-03 03:30:36,037 [salt.loaded.int.module.cmdmod][INFO    ][26999] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-12-03 03:30:36,157 [salt.minion      ][INFO    ][26999] Returning information for job: 20171203033036005760
2017-12-03 04:01:40,682 [salt.utils.schedule][INFO    ][20375] Running scheduled job: __mine_interval
2017-12-03 05:01:40,681 [salt.utils.schedule][INFO    ][20375] Running scheduled job: __mine_interval
2017-12-03 05:04:10,487 [salt.minion      ][INFO    ][20375] User sudo_ubuntu Executing command cp.push_dir with jid 20171203050410466410
2017-12-03 05:04:10,507 [salt.minion      ][INFO    ][28089] Starting a new job with PID 28089
