2017-11-30 09:18:33,145 [salt.cli.daemons ][WARNING ][2411] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-30 09:19:03,144 [salt.state       ][ERROR   ][3656] No contents found in top file
2017-11-30 09:22:21,541 [salt.utils.parsers][WARNING ][2411] Minion received a SIGTERM. Exiting.
2017-11-30 09:24:35,339 [salt.cli.daemons ][WARNING ][3144] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-30 09:27:27,405 [py.warnings      ][WARNING ][14064] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-30 09:29:03,370 [salt.loaded.int.module.cmdmod][ERROR   ][14064] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:03,371 [salt.loaded.int.module.cmdmod][ERROR   ][14064] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:11,893 [salt.loaded.int.module.cmdmod][ERROR   ][14064] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:11,893 [salt.loaded.int.module.cmdmod][ERROR   ][14064] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:20,789 [salt.loaded.int.module.cmdmod][ERROR   ][14064] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:20,789 [salt.loaded.int.module.cmdmod][ERROR   ][14064] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:30,969 [salt.loaded.int.module.cmdmod][ERROR   ][14064] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:30,970 [salt.loaded.int.module.cmdmod][ERROR   ][14064] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:41,146 [salt.loaded.int.module.cmdmod][ERROR   ][14064] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-30 09:29:41,147 [salt.loaded.int.module.cmdmod][ERROR   ][14064] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-30 09:29:47,658 [salt.loaded.int.module.cmdmod][ERROR   ][14064] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2017-11-30 09:29:50,638 [salt.loaded.int.module.cmdmod][INFO    ][21547] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-30 09:29:50,650 [salt.loaded.int.module.cmdmod][INFO    ][21547] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-30 09:29:50,665 [salt.loaded.int.module.cmdmod][INFO    ][21547] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-30 09:29:50,679 [salt.utils.parsers][WARNING ][3144] Minion received a SIGTERM. Exiting.
2017-11-30 09:29:51,057 [salt.cli.daemons ][INFO    ][21624] Setting up the Salt Minion "kvm01.baremetal-mcp-ocata-ovs-dpdk-ha.local"
2017-11-30 09:29:51,761 [salt.minion      ][INFO    ][21624] Creating minion process manager
2017-11-30 09:29:51,761 [salt.cli.daemons ][WARNING ][21624] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-30 09:29:51,761 [salt.cli.daemons ][INFO    ][21624] The Salt Minion is starting up
2017-11-30 09:29:51,762 [salt.minion      ][INFO    ][21624] Minion is starting as user 'root'
2017-11-30 09:29:51,762 [salt.utils.event ][INFO    ][21624] Starting pull socket on /var/run/salt/minion/minion_event_0fd62c7a6e_pull.ipc
2017-11-30 09:29:52,917 [salt.loaded.int.module.cmdmod][INFO    ][21624] Executing command ['date', '+%z'] in directory '/root'
2017-11-30 09:29:52,930 [salt.utils.schedule][INFO    ][21624] Updating job settings for scheduled job: __mine_interval
2017-11-30 09:29:52,932 [salt.minion      ][INFO    ][21624] Added mine.update to scheduler
2017-11-30 09:29:53,119 [salt.minion      ][INFO    ][21624] Minion is ready to receive requests!
2017-11-30 09:29:53,370 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171130092953363623
2017-11-30 09:29:53,391 [salt.minion      ][INFO    ][21717] Starting a new job with PID 21717
2017-11-30 09:29:54,120 [salt.utils.schedule][INFO    ][21624] Running scheduled job: __mine_interval
2017-11-30 09:29:55,008 [salt.state       ][INFO    ][21717] Loading fresh modules for state activity
2017-11-30 09:29:55,050 [salt.state       ][ERROR   ][21717] No contents found in top file
2017-11-30 09:29:55,086 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-30 09:29:55,092 [salt.utils.extmods][INFO    ][21717] Syncing beacons for environment 'base'
2017-11-30 09:29:55,092 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_beacons, for base)
2017-11-30 09:29:55,092 [salt.fileclient  ][INFO    ][21717] Caching directory '_beacons/' for environment 'base'
2017-11-30 09:29:55,142 [salt.utils.extmods][INFO    ][21717] Syncing modules for environment 'base'
2017-11-30 09:29:55,142 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_modules, for base)
2017-11-30 09:29:55,142 [salt.fileclient  ][INFO    ][21717] Caching directory '_modules/' for environment 'base'
2017-11-30 09:29:55,214 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-11-30 09:29:55,381 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-30 09:29:55,431 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-30 09:29:55,564 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-30 09:29:55,628 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-30 09:29:55,709 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-30 09:29:55,777 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-30 09:29:55,970 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-30 09:29:56,039 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-30 09:29:56,152 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-30 09:29:56,192 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-30 09:29:56,388 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-30 09:29:56,413 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-30 09:29:56,437 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-30 09:29:56,452 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-30 09:29:56,465 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-30 09:29:56,483 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-30 09:29:56,495 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-30 09:29:56,507 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-30 09:29:56,520 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-30 09:29:56,573 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-30 09:29:56,657 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-30 09:29:56,690 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-30 09:29:56,704 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-30 09:29:56,716 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-30 09:29:56,752 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-30 09:29:56,896 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-30 09:29:57,028 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-30 09:29:57,099 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-30 09:29:57,286 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-30 09:29:57,417 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-30 09:29:57,550 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-30 09:29:57,718 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-30 09:29:57,779 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-30 09:29:57,787 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-30 09:29:57,787 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-11-30 09:29:57,872 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-30 09:29:57,978 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-30 09:29:58,054 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-30 09:29:58,106 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-30 09:29:58,112 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-30 09:29:58,112 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-30 09:29:58,113 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-30 09:29:58,132 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-30 09:29:58,143 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-30 09:29:58,144 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-30 09:29:58,144 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-30 09:29:58,147 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-30 09:29:58,147 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-30 09:29:58,148 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-30 09:29:58,148 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-30 09:29:58,155 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-30 09:29:58,156 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-30 09:29:58,156 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-30 09:29:58,157 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-30 09:29:58,157 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-30 09:29:58,158 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-30 09:29:58,246 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-30 09:29:58,259 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-30 09:29:58,264 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-30 09:29:58,265 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-30 09:29:58,266 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-30 09:29:58,271 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-30 09:29:58,276 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-30 09:29:58,281 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-30 09:29:58,282 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-30 09:29:58,282 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-30 09:29:58,283 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-11-30 09:29:58,284 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-30 09:29:58,284 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-30 09:29:58,298 [salt.utils.extmods][INFO    ][21717] Syncing states for environment 'base'
2017-11-30 09:29:58,298 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_states, for base)
2017-11-30 09:29:58,299 [salt.fileclient  ][INFO    ][21717] Caching directory '_states/' for environment 'base'
2017-11-30 09:29:58,431 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-11-30 09:29:58,525 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-30 09:29:58,731 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-30 09:29:58,843 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-30 09:29:58,960 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-30 09:29:59,084 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-30 09:29:59,160 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-30 09:29:59,345 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-30 09:29:59,489 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-30 09:29:59,655 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-11-30 09:29:59,716 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-30 09:29:59,958 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-30 09:30:00,094 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-30 09:30:00,210 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-11-30 09:30:00,440 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-30 09:30:00,667 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-30 09:30:00,744 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-30 09:30:00,904 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-30 09:30:01,153 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-30 09:30:01,358 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-30 09:30:01,464 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-30 09:30:01,490 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-30 09:30:01,667 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-30 09:30:01,771 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-30 09:30:01,885 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-30 09:30:01,955 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-30 09:30:02,172 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-30 09:30:02,303 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-30 09:30:02,394 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-30 09:30:02,559 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-30 09:30:02,777 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-30 09:30:02,991 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-30 09:30:02,997 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-11-30 09:30:02,998 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-30 09:30:02,998 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-30 09:30:02,999 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-30 09:30:02,999 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-30 09:30:02,1000 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-30 09:30:03,001 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-30 09:30:03,001 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-30 09:30:03,002 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-30 09:30:03,002 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-11-30 09:30:03,002 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-30 09:30:03,003 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-30 09:30:03,003 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-30 09:30:03,004 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-11-30 09:30:03,004 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-30 09:30:03,004 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-30 09:30:03,005 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-30 09:30:03,005 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-30 09:30:03,005 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-30 09:30:03,006 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-30 09:30:03,006 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-30 09:30:03,007 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-30 09:30:03,007 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-30 09:30:03,007 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-30 09:30:03,008 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-30 09:30:03,008 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-30 09:30:03,009 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-30 09:30:03,009 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-30 09:30:03,010 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-30 09:30:03,010 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-30 09:30:03,011 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-30 09:30:03,012 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-30 09:30:03,012 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-30 09:30:03,017 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-30 09:30:03,024 [salt.utils.extmods][INFO    ][21717] Syncing sdb for environment 'base'
2017-11-30 09:30:03,024 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_sdb, for base)
2017-11-30 09:30:03,024 [salt.fileclient  ][INFO    ][21717] Caching directory '_sdb/' for environment 'base'
2017-11-30 09:30:03,090 [salt.utils.extmods][INFO    ][21717] Syncing grains for environment 'base'
2017-11-30 09:30:03,090 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_grains, for base)
2017-11-30 09:30:03,090 [salt.fileclient  ][INFO    ][21717] Caching directory '_grains/' for environment 'base'
2017-11-30 09:30:03,212 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-30 09:30:03,271 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-30 09:30:03,427 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130093003420820
2017-11-30 09:30:03,438 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-30 09:30:03,453 [salt.minion      ][INFO    ][21746] Starting a new job with PID 21746
2017-11-30 09:30:03,471 [salt.minion      ][INFO    ][21746] Returning information for job: 20171130093003420820
2017-11-30 09:30:03,522 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-30 09:30:03,609 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-30 09:30:03,697 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-30 09:30:03,881 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-30 09:30:04,102 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-30 09:30:04,177 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-30 09:30:04,269 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-30 09:30:04,371 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-30 09:30:04,558 [salt.fileclient  ][INFO    ][21717] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-30 09:30:04,565 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-30 09:30:04,689 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-30 09:30:04,785 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-30 09:30:04,854 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-30 09:30:04,866 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-30 09:30:04,866 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-30 09:30:04,867 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-30 09:30:04,868 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-30 09:30:04,868 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-30 09:30:04,869 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-30 09:30:04,870 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-30 09:30:04,870 [salt.utils.extmods][INFO    ][21717] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-30 09:30:04,873 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-30 09:30:04,879 [salt.utils.extmods][INFO    ][21717] Syncing renderers for environment 'base'
2017-11-30 09:30:04,879 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_renderers, for base)
2017-11-30 09:30:04,880 [salt.fileclient  ][INFO    ][21717] Caching directory '_renderers/' for environment 'base'
2017-11-30 09:30:04,927 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-30 09:30:04,932 [salt.utils.extmods][INFO    ][21717] Syncing returners for environment 'base'
2017-11-30 09:30:04,932 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_returners, for base)
2017-11-30 09:30:04,933 [salt.fileclient  ][INFO    ][21717] Caching directory '_returners/' for environment 'base'
2017-11-30 09:30:04,979 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-30 09:30:04,984 [salt.utils.extmods][INFO    ][21717] Syncing output for environment 'base'
2017-11-30 09:30:04,984 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_output, for base)
2017-11-30 09:30:04,984 [salt.fileclient  ][INFO    ][21717] Caching directory '_output/' for environment 'base'
2017-11-30 09:30:05,029 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-30 09:30:05,034 [salt.utils.extmods][INFO    ][21717] Syncing utils for environment 'base'
2017-11-30 09:30:05,034 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_utils, for base)
2017-11-30 09:30:05,035 [salt.fileclient  ][INFO    ][21717] Caching directory '_utils/' for environment 'base'
2017-11-30 09:30:05,083 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-30 09:30:05,089 [salt.utils.extmods][INFO    ][21717] Syncing log_handlers for environment 'base'
2017-11-30 09:30:05,089 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_log_handlers, for base)
2017-11-30 09:30:05,090 [salt.fileclient  ][INFO    ][21717] Caching directory '_log_handlers/' for environment 'base'
2017-11-30 09:30:05,136 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-30 09:30:05,141 [salt.utils.extmods][INFO    ][21717] Syncing proxy for environment 'base'
2017-11-30 09:30:05,141 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_proxy, for base)
2017-11-30 09:30:05,141 [salt.fileclient  ][INFO    ][21717] Caching directory '_proxy/' for environment 'base'
2017-11-30 09:30:05,188 [salt.utils.extmods][INFO    ][21717] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-30 09:30:05,193 [salt.utils.extmods][INFO    ][21717] Syncing engines for environment 'base'
2017-11-30 09:30:05,193 [salt.utils.extmods][INFO    ][21717] Loading cache from salt://_engines, for base)
2017-11-30 09:30:05,193 [salt.fileclient  ][INFO    ][21717] Caching directory '_engines/' for environment 'base'
2017-11-30 09:30:05,245 [salt.minion      ][INFO    ][21717] Returning information for job: 20171130092953363623
2017-11-30 09:30:06,235 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130093006235271
2017-11-30 09:30:06,256 [salt.minion      ][INFO    ][21801] Starting a new job with PID 21801
2017-11-30 09:30:06,865 [salt.state       ][INFO    ][21801] Loading fresh modules for state activity
2017-11-30 09:30:06,901 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-30 09:30:06,932 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-30 09:30:06,985 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:07,291 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-30 09:30:07,309 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-30 09:30:07,347 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:07,432 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:07,454 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:07,658 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:07,692 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:07,768 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:07,792 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:07,809 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:07,845 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:07,882 [py.warnings      ][WARNING ][21801] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-30 09:30:07,882 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:08,266 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:08,319 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:08,337 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:08,401 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:08,474 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:08,515 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:08,584 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:08,643 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:08,673 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:08,725 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:08,857 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-30 09:30:08,899 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:09,013 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:09,042 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:09,119 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:09,153 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:09,171 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:09,197 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:09,215 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:09,253 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:09,289 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:09,636 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:09,664 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:09,680 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:09,698 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:09,767 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:09,786 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:09,818 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:09,855 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:09,918 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:09,989 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:10,145 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-30 09:30:10,263 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:10,659 [salt.state       ][INFO    ][21801] Running state [salt-minion] at time 09:30:10.658662
2017-11-30 09:30:10,659 [salt.state       ][INFO    ][21801] Executing state pkg.installed for salt-minion
2017-11-30 09:30:10,659 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:30:11,095 [salt.state       ][INFO    ][21801] Package salt-minion is already installed
2017-11-30 09:30:11,095 [salt.state       ][INFO    ][21801] Completed state [salt-minion] at time 09:30:11.095404 duration_in_ms=436.741
2017-11-30 09:30:11,096 [salt.state       ][INFO    ][21801] Running state [salt_minion_dependency_packages] at time 09:30:11.095753
2017-11-30 09:30:11,096 [salt.state       ][INFO    ][21801] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-30 09:30:11,100 [salt.state       ][INFO    ][21801] All specified packages are already installed
2017-11-30 09:30:11,100 [salt.state       ][INFO    ][21801] Completed state [salt_minion_dependency_packages] at time 09:30:11.100320 duration_in_ms=4.568
2017-11-30 09:30:11,102 [salt.state       ][INFO    ][21801] Running state [/etc/salt/minion.d/minion.conf] at time 09:30:11.102400
2017-11-30 09:30:11,103 [salt.state       ][INFO    ][21801] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-30 09:30:11,141 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-30 09:30:11,214 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:11,291 [salt.fileclient  ][INFO    ][21801] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:11,352 [salt.state       ][INFO    ][21801] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-30 09:30:11,353 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/minion.d/minion.conf] at time 09:30:11.352626 duration_in_ms=250.226
2017-11-30 09:30:11,353 [salt.state       ][INFO    ][21801] Running state [/etc/salt/minion.d/_virt.conf] at time 09:30:11.353108
2017-11-30 09:30:11,353 [salt.state       ][INFO    ][21801] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-30 09:30:11,394 [salt.state       ][INFO    ][21801] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-30 09:30:11,394 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:30:11.394214 duration_in_ms=41.103
2017-11-30 09:30:11,398 [salt.state       ][INFO    ][21801] Running state [salt-minion] at time 09:30:11.398306
2017-11-30 09:30:11,399 [salt.state       ][INFO    ][21801] Executing state service.running for salt-minion
2017-11-30 09:30:11,400 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-30 09:30:11,430 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:11,449 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:11,462 [salt.state       ][INFO    ][21801] The service salt-minion is already running
2017-11-30 09:30:11,528 [salt.state       ][INFO    ][21801] Completed state [salt-minion] at time 09:30:11.527485 duration_in_ms=129.179
2017-11-30 09:30:11,570 [salt.state       ][INFO    ][21801] Running state [/etc/salt/grains.d] at time 09:30:11.570345
2017-11-30 09:30:11,571 [salt.state       ][INFO    ][21801] Executing state file.directory for /etc/salt/grains.d
2017-11-30 09:30:11,572 [salt.state       ][INFO    ][21801] Directory /etc/salt/grains.d is in the correct state
2017-11-30 09:30:11,573 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/grains.d] at time 09:30:11.572863 duration_in_ms=2.518
2017-11-30 09:30:11,574 [salt.state       ][INFO    ][21801] Running state [/etc/salt/grains] at time 09:30:11.574104
2017-11-30 09:30:11,575 [salt.state       ][INFO    ][21801] Executing state file.managed for /etc/salt/grains
2017-11-30 09:30:11,576 [salt.state       ][INFO    ][21801] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-30 09:30:11,576 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/grains] at time 09:30:11.576027 duration_in_ms=1.922
2017-11-30 09:30:11,577 [salt.state       ][INFO    ][21801] Running state [/etc/salt/grains.d/placeholder] at time 09:30:11.576907
2017-11-30 09:30:11,578 [salt.state       ][INFO    ][21801] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-30 09:30:11,578 [salt.state       ][INFO    ][21801] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-30 09:30:11,579 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/grains.d/placeholder] at time 09:30:11.578756 duration_in_ms=1.849
2017-11-30 09:30:11,580 [salt.state       ][INFO    ][21801] Running state [/etc/salt/grains.d/sphinx] at time 09:30:11.579678
2017-11-30 09:30:11,580 [salt.state       ][INFO    ][21801] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-30 09:30:11,628 [salt.state       ][INFO    ][21801] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.141
               - 127.0.0.1
               - 192.168.11.2
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-30 09:30:11,628 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/grains.d/sphinx] at time 09:30:11.628205 duration_in_ms=48.527
2017-11-30 09:30:11,630 [salt.state       ][INFO    ][21801] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.629603
2017-11-30 09:30:11,630 [salt.state       ][INFO    ][21801] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,631 [salt.state       ][INFO    ][21801] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,631 [salt.state       ][INFO    ][21801] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.630828 duration_in_ms=1.224
2017-11-30 09:30:11,631 [salt.state       ][INFO    ][21801] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.631147
2017-11-30 09:30:11,632 [salt.state       ][INFO    ][21801] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,633 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-30 09:30:11,772 [salt.state       ][INFO    ][21801] {'pid': 21881, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-30 09:30:11,773 [salt.state       ][INFO    ][21801] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.772763 duration_in_ms=141.615
2017-11-30 09:30:11,774 [salt.state       ][INFO    ][21801] Running state [/etc/salt/grains.d/dns_records] at time 09:30:11.773747
2017-11-30 09:30:11,774 [salt.state       ][INFO    ][21801] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-30 09:30:11,803 [salt.state       ][INFO    ][21801] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-30 09:30:11,804 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/grains.d/dns_records] at time 09:30:11.803466 duration_in_ms=29.719
2017-11-30 09:30:11,805 [salt.state       ][INFO    ][21801] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.805020
2017-11-30 09:30:11,805 [salt.state       ][INFO    ][21801] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,806 [salt.state       ][INFO    ][21801] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,806 [salt.state       ][INFO    ][21801] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.806196 duration_in_ms=1.175
2017-11-30 09:30:11,807 [salt.state       ][INFO    ][21801] Running state [/etc/salt/grains.d/salt] at time 09:30:11.806871
2017-11-30 09:30:11,807 [salt.state       ][INFO    ][21801] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-30 09:30:11,850 [salt.state       ][INFO    ][21801] File /etc/salt/grains.d/salt is in the correct state
2017-11-30 09:30:11,850 [salt.state       ][INFO    ][21801] Completed state [/etc/salt/grains.d/salt] at time 09:30:11.850355 duration_in_ms=43.484
2017-11-30 09:30:11,852 [salt.state       ][INFO    ][21801] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.851960
2017-11-30 09:30:11,852 [salt.state       ][INFO    ][21801] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,853 [salt.state       ][INFO    ][21801] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:11,853 [salt.state       ][INFO    ][21801] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:11.853300 duration_in_ms=1.339
2017-11-30 09:30:11,857 [salt.state       ][INFO    ][21801] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.856644
2017-11-30 09:30:11,857 [salt.state       ][INFO    ][21801] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:11,858 [salt.state       ][INFO    ][21801] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:11,858 [salt.state       ][INFO    ][21801] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.857967 duration_in_ms=1.322
2017-11-30 09:30:11,858 [salt.state       ][INFO    ][21801] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.858301
2017-11-30 09:30:11,859 [salt.state       ][INFO    ][21801] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:11,862 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-30 09:30:11,888 [salt.state       ][INFO    ][21801] {'pid': 21883, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-30 09:30:11,888 [salt.state       ][INFO    ][21801] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:11.888289 duration_in_ms=29.988
2017-11-30 09:30:11,890 [salt.state       ][INFO    ][21801] Running state [mine.update] at time 09:30:11.889767
2017-11-30 09:30:11,890 [salt.state       ][INFO    ][21801] Executing state module.wait for mine.update
2017-11-30 09:30:11,891 [salt.state       ][INFO    ][21801] No changes made for mine.update
2017-11-30 09:30:11,891 [salt.state       ][INFO    ][21801] Completed state [mine.update] at time 09:30:11.891081 duration_in_ms=1.314
2017-11-30 09:30:11,892 [salt.state       ][INFO    ][21801] Running state [mine.update] at time 09:30:11.891474
2017-11-30 09:30:11,892 [salt.state       ][INFO    ][21801] Executing state module.mod_watch for mine.update
2017-11-30 09:30:12,425 [salt.state       ][INFO    ][21801] {'ret': True}
2017-11-30 09:30:12,426 [salt.state       ][INFO    ][21801] Completed state [mine.update] at time 09:30:12.425595 duration_in_ms=534.119
2017-11-30 09:30:12,426 [salt.state       ][INFO    ][21801] Running state [ca-certificates] at time 09:30:12.426104
2017-11-30 09:30:12,427 [salt.state       ][INFO    ][21801] Executing state pkg.installed for ca-certificates
2017-11-30 09:30:12,435 [salt.state       ][INFO    ][21801] Package ca-certificates is already installed
2017-11-30 09:30:12,436 [salt.state       ][INFO    ][21801] Completed state [ca-certificates] at time 09:30:12.435421 duration_in_ms=9.315
2017-11-30 09:30:12,437 [salt.state       ][INFO    ][21801] Running state [update-ca-certificates] at time 09:30:12.436681
2017-11-30 09:30:12,437 [salt.state       ][INFO    ][21801] Executing state cmd.wait for update-ca-certificates
2017-11-30 09:30:12,438 [salt.state       ][INFO    ][21801] No changes made for update-ca-certificates
2017-11-30 09:30:12,438 [salt.state       ][INFO    ][21801] Completed state [update-ca-certificates] at time 09:30:12.437937 duration_in_ms=1.255
2017-11-30 09:30:12,438 [salt.state       ][INFO    ][21801] Running state [libvirt-dev] at time 09:30:12.438357
2017-11-30 09:30:12,439 [salt.state       ][INFO    ][21801] Executing state pkg.installed for libvirt-dev
2017-11-30 09:30:12,446 [salt.state       ][INFO    ][21801] Package libvirt-dev is already installed
2017-11-30 09:30:12,447 [salt.state       ][INFO    ][21801] Completed state [libvirt-dev] at time 09:30:12.446465 duration_in_ms=8.108
2017-11-30 09:30:12,447 [salt.state       ][INFO    ][21801] Running state [pkg-config] at time 09:30:12.446848
2017-11-30 09:30:12,447 [salt.state       ][INFO    ][21801] Executing state pkg.installed for pkg-config
2017-11-30 09:30:12,454 [salt.state       ][INFO    ][21801] Package pkg-config is already installed
2017-11-30 09:30:12,462 [salt.state       ][INFO    ][21801] Completed state [pkg-config] at time 09:30:12.462043 duration_in_ms=15.194
2017-11-30 09:30:12,463 [salt.state       ][INFO    ][21801] Running state [virtng.init] at time 09:30:12.462477
2017-11-30 09:30:12,463 [salt.state       ][INFO    ][21801] Executing state module.run for virtng.init
2017-11-30 09:30:12,664 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command ['git', '--version'] in directory '/root'
2017-11-30 09:30:12,884 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,923 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:12,923 [salt.state       ][INFO    ][21801] Completed state [virtng.init] at time 09:30:12.923268 duration_in_ms=460.789
2017-11-30 09:30:12,924 [salt.state       ][INFO    ][21801] Running state [virt.set_autostart] at time 09:30:12.923916
2017-11-30 09:30:12,925 [salt.state       ][INFO    ][21801] Executing state module.run for virt.set_autostart
2017-11-30 09:30:12,930 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:12,966 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:12,966 [salt.state       ][INFO    ][21801] Completed state [virt.set_autostart] at time 09:30:12.966348 duration_in_ms=42.431
2017-11-30 09:30:12,967 [salt.state       ][INFO    ][21801] Running state [virtng.init] at time 09:30:12.966925
2017-11-30 09:30:12,967 [salt.state       ][INFO    ][21801] Executing state module.run for virtng.init
2017-11-30 09:30:12,973 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,005 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,006 [salt.state       ][INFO    ][21801] Completed state [virtng.init] at time 09:30:13.005693 duration_in_ms=38.768
2017-11-30 09:30:13,006 [salt.state       ][INFO    ][21801] Running state [virt.set_autostart] at time 09:30:13.006255
2017-11-30 09:30:13,007 [salt.state       ][INFO    ][21801] Executing state module.run for virt.set_autostart
2017-11-30 09:30:13,012 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,042 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,045 [salt.state       ][INFO    ][21801] Completed state [virt.set_autostart] at time 09:30:13.045075 duration_in_ms=38.819
2017-11-30 09:30:13,046 [salt.state       ][INFO    ][21801] Running state [virtng.init] at time 09:30:13.045683
2017-11-30 09:30:13,046 [salt.state       ][INFO    ][21801] Executing state module.run for virtng.init
2017-11-30 09:30:13,051 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,087 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,088 [salt.state       ][INFO    ][21801] Completed state [virtng.init] at time 09:30:13.087620 duration_in_ms=41.937
2017-11-30 09:30:13,088 [salt.state       ][INFO    ][21801] Running state [virt.set_autostart] at time 09:30:13.088176
2017-11-30 09:30:13,089 [salt.state       ][INFO    ][21801] Executing state module.run for virt.set_autostart
2017-11-30 09:30:13,094 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,125 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,125 [salt.state       ][INFO    ][21801] Completed state [virt.set_autostart] at time 09:30:13.125349 duration_in_ms=37.173
2017-11-30 09:30:13,126 [salt.state       ][INFO    ][21801] Running state [virtng.init] at time 09:30:13.125935
2017-11-30 09:30:13,126 [salt.state       ][INFO    ][21801] Executing state module.run for virtng.init
2017-11-30 09:30:13,132 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,163 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,163 [salt.state       ][INFO    ][21801] Completed state [virtng.init] at time 09:30:13.163175 duration_in_ms=37.239
2017-11-30 09:30:13,164 [salt.state       ][INFO    ][21801] Running state [virt.set_autostart] at time 09:30:13.163775
2017-11-30 09:30:13,164 [salt.state       ][INFO    ][21801] Executing state module.run for virt.set_autostart
2017-11-30 09:30:13,169 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,207 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,207 [salt.state       ][INFO    ][21801] Completed state [virt.set_autostart] at time 09:30:13.206989 duration_in_ms=43.214
2017-11-30 09:30:13,208 [salt.state       ][INFO    ][21801] Running state [virtng.init] at time 09:30:13.207579
2017-11-30 09:30:13,208 [salt.state       ][INFO    ][21801] Executing state module.run for virtng.init
2017-11-30 09:30:13,214 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,246 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,246 [salt.state       ][INFO    ][21801] Completed state [virtng.init] at time 09:30:13.246101 duration_in_ms=38.522
2017-11-30 09:30:13,247 [salt.state       ][INFO    ][21801] Running state [virt.set_autostart] at time 09:30:13.246675
2017-11-30 09:30:13,247 [salt.state       ][INFO    ][21801] Executing state module.run for virt.set_autostart
2017-11-30 09:30:13,253 [salt.loaded.int.module.cmdmod][INFO    ][21801] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:13,287 [salt.state       ][INFO    ][21801] unless execution succeeded
2017-11-30 09:30:13,287 [salt.state       ][INFO    ][21801] Completed state [virt.set_autostart] at time 09:30:13.287016 duration_in_ms=40.341
2017-11-30 09:30:13,300 [salt.minion      ][INFO    ][21801] Returning information for job: 20171130093006235271
2017-11-30 09:30:37,286 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130093037283762
2017-11-30 09:30:37,307 [salt.minion      ][INFO    ][21942] Starting a new job with PID 21942
2017-11-30 09:30:39,139 [salt.state       ][INFO    ][21942] Loading fresh modules for state activity
2017-11-30 09:30:39,189 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-30 09:30:39,219 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-30 09:30:39,354 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:39,438 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-30 09:30:39,454 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-30 09:30:39,484 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:39,587 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:39,619 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:39,700 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:39,726 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:39,744 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:39,766 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:39,782 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:39,881 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:39,915 [py.warnings      ][WARNING ][21942] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-30 09:30:39,916 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:40,299 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:40,360 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:40,376 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:40,393 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:40,460 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:40,478 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:40,506 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:40,523 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:40,546 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:40,661 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:40,769 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-30 09:30:40,796 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:40,903 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-30 09:30:40,947 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:41,035 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-30 09:30:41,061 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-30 09:30:41,077 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:30:41,097 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-30 09:30:41,107 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-30 09:30:41,135 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-30 09:30:41,157 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'salt-minion --version' in directory '/root'
2017-11-30 09:30:41,483 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-30 09:30:41,508 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-30 09:30:41,523 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-30 09:30:41,587 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-30 09:30:41,657 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-30 09:30:41,676 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-30 09:30:41,706 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'ntp/meta/meta.yml'
2017-11-30 09:30:41,723 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'ntp/map.jinja'
2017-11-30 09:30:41,840 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-30 09:30:41,899 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-30 09:30:42,059 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-30 09:30:42,167 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:42,896 [salt.state       ][INFO    ][21942] Running state [salt-minion] at time 09:30:42.895978
2017-11-30 09:30:42,896 [salt.state       ][INFO    ][21942] Executing state pkg.installed for salt-minion
2017-11-30 09:30:42,897 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:30:43,298 [salt.state       ][INFO    ][21942] Package salt-minion is already installed
2017-11-30 09:30:43,298 [salt.state       ][INFO    ][21942] Completed state [salt-minion] at time 09:30:43.297931 duration_in_ms=401.953
2017-11-30 09:30:43,298 [salt.state       ][INFO    ][21942] Running state [salt_minion_dependency_packages] at time 09:30:43.298207
2017-11-30 09:30:43,298 [salt.state       ][INFO    ][21942] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-30 09:30:43,302 [salt.state       ][INFO    ][21942] All specified packages are already installed
2017-11-30 09:30:43,302 [salt.state       ][INFO    ][21942] Completed state [salt_minion_dependency_packages] at time 09:30:43.302284 duration_in_ms=4.078
2017-11-30 09:30:43,304 [salt.state       ][INFO    ][21942] Running state [/etc/salt/minion.d/minion.conf] at time 09:30:43.304231
2017-11-30 09:30:43,304 [salt.state       ][INFO    ][21942] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-30 09:30:43,344 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-30 09:30:43,442 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-30 09:30:43,526 [salt.fileclient  ][INFO    ][21942] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-30 09:30:43,592 [salt.state       ][INFO    ][21942] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-30 09:30:43,592 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/minion.d/minion.conf] at time 09:30:43.592377 duration_in_ms=288.145
2017-11-30 09:30:43,593 [salt.state       ][INFO    ][21942] Running state [/etc/salt/minion.d/_virt.conf] at time 09:30:43.592833
2017-11-30 09:30:43,593 [salt.state       ][INFO    ][21942] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-30 09:30:43,627 [salt.state       ][INFO    ][21942] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-30 09:30:43,628 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:30:43.627533 duration_in_ms=34.699
2017-11-30 09:30:43,631 [salt.state       ][INFO    ][21942] Running state [salt-minion] at time 09:30:43.631029
2017-11-30 09:30:43,631 [salt.state       ][INFO    ][21942] Executing state service.running for salt-minion
2017-11-30 09:30:43,632 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-30 09:30:43,660 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:43,674 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-30 09:30:43,687 [salt.state       ][INFO    ][21942] The service salt-minion is already running
2017-11-30 09:30:43,687 [salt.state       ][INFO    ][21942] Completed state [salt-minion] at time 09:30:43.687215 duration_in_ms=56.185
2017-11-30 09:30:43,690 [salt.state       ][INFO    ][21942] Running state [/etc/salt/grains.d] at time 09:30:43.690251
2017-11-30 09:30:43,691 [salt.state       ][INFO    ][21942] Executing state file.directory for /etc/salt/grains.d
2017-11-30 09:30:43,692 [salt.state       ][INFO    ][21942] Directory /etc/salt/grains.d is in the correct state
2017-11-30 09:30:43,699 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/grains.d] at time 09:30:43.698864 duration_in_ms=8.612
2017-11-30 09:30:43,700 [salt.state       ][INFO    ][21942] Running state [/etc/salt/grains] at time 09:30:43.700380
2017-11-30 09:30:43,701 [salt.state       ][INFO    ][21942] Executing state file.managed for /etc/salt/grains
2017-11-30 09:30:43,702 [salt.state       ][INFO    ][21942] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-30 09:30:43,702 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/grains] at time 09:30:43.702058 duration_in_ms=1.677
2017-11-30 09:30:43,703 [salt.state       ][INFO    ][21942] Running state [/etc/salt/grains.d/placeholder] at time 09:30:43.702739
2017-11-30 09:30:43,703 [salt.state       ][INFO    ][21942] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-30 09:30:43,704 [salt.state       ][INFO    ][21942] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-30 09:30:43,704 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/grains.d/placeholder] at time 09:30:43.704186 duration_in_ms=1.447
2017-11-30 09:30:43,705 [salt.state       ][INFO    ][21942] Running state [/etc/salt/grains.d/sphinx] at time 09:30:43.704931
2017-11-30 09:30:43,705 [salt.state       ][INFO    ][21942] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-30 09:30:43,745 [salt.state       ][INFO    ][21942] File /etc/salt/grains.d/sphinx is in the correct state
2017-11-30 09:30:43,745 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/grains.d/sphinx] at time 09:30:43.745126 duration_in_ms=40.194
2017-11-30 09:30:43,747 [salt.state       ][INFO    ][21942] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:43.746768
2017-11-30 09:30:43,747 [salt.state       ][INFO    ][21942] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:43,748 [salt.state       ][INFO    ][21942] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:43,748 [salt.state       ][INFO    ][21942] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:30:43.748138 duration_in_ms=1.37
2017-11-30 09:30:43,749 [salt.state       ][INFO    ][21942] Running state [/etc/salt/grains.d/dns_records] at time 09:30:43.748826
2017-11-30 09:30:43,749 [salt.state       ][INFO    ][21942] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-30 09:30:43,780 [salt.state       ][INFO    ][21942] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-30 09:30:43,780 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/grains.d/dns_records] at time 09:30:43.779917 duration_in_ms=31.091
2017-11-30 09:30:43,782 [salt.state       ][INFO    ][21942] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:43.781502
2017-11-30 09:30:43,782 [salt.state       ][INFO    ][21942] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:43,783 [salt.state       ][INFO    ][21942] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:43,783 [salt.state       ][INFO    ][21942] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:30:43.782785 duration_in_ms=1.283
2017-11-30 09:30:43,795 [salt.state       ][INFO    ][21942] Running state [/etc/salt/grains.d/salt] at time 09:30:43.795255
2017-11-30 09:30:43,796 [salt.state       ][INFO    ][21942] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-30 09:30:43,817 [salt.state       ][INFO    ][21942] File /etc/salt/grains.d/salt is in the correct state
2017-11-30 09:30:43,817 [salt.state       ][INFO    ][21942] Completed state [/etc/salt/grains.d/salt] at time 09:30:43.816834 duration_in_ms=21.578
2017-11-30 09:30:43,818 [salt.state       ][INFO    ][21942] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:43.818262
2017-11-30 09:30:43,819 [salt.state       ][INFO    ][21942] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:43,819 [salt.state       ][INFO    ][21942] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-30 09:30:43,820 [salt.state       ][INFO    ][21942] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:30:43.819473 duration_in_ms=1.211
2017-11-30 09:30:43,822 [salt.state       ][INFO    ][21942] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:43.822318
2017-11-30 09:30:43,823 [salt.state       ][INFO    ][21942] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:43,823 [salt.state       ][INFO    ][21942] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-30 09:30:43,823 [salt.state       ][INFO    ][21942] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:30:43.823415 duration_in_ms=1.096
2017-11-30 09:30:43,824 [salt.state       ][INFO    ][21942] Running state [mine.update] at time 09:30:43.824342
2017-11-30 09:30:43,825 [salt.state       ][INFO    ][21942] Executing state module.wait for mine.update
2017-11-30 09:30:43,825 [salt.state       ][INFO    ][21942] No changes made for mine.update
2017-11-30 09:30:43,825 [salt.state       ][INFO    ][21942] Completed state [mine.update] at time 09:30:43.825317 duration_in_ms=0.976
2017-11-30 09:30:43,826 [salt.state       ][INFO    ][21942] Running state [ca-certificates] at time 09:30:43.825638
2017-11-30 09:30:43,826 [salt.state       ][INFO    ][21942] Executing state pkg.installed for ca-certificates
2017-11-30 09:30:43,834 [salt.state       ][INFO    ][21942] Package ca-certificates is already installed
2017-11-30 09:30:43,834 [salt.state       ][INFO    ][21942] Completed state [ca-certificates] at time 09:30:43.834394 duration_in_ms=8.756
2017-11-30 09:30:43,836 [salt.state       ][INFO    ][21942] Running state [update-ca-certificates] at time 09:30:43.835640
2017-11-30 09:30:43,836 [salt.state       ][INFO    ][21942] Executing state cmd.wait for update-ca-certificates
2017-11-30 09:30:43,837 [salt.state       ][INFO    ][21942] No changes made for update-ca-certificates
2017-11-30 09:30:43,837 [salt.state       ][INFO    ][21942] Completed state [update-ca-certificates] at time 09:30:43.836838 duration_in_ms=1.197
2017-11-30 09:30:43,837 [salt.state       ][INFO    ][21942] Running state [libvirt-dev] at time 09:30:43.837200
2017-11-30 09:30:43,838 [salt.state       ][INFO    ][21942] Executing state pkg.installed for libvirt-dev
2017-11-30 09:30:43,845 [salt.state       ][INFO    ][21942] Package libvirt-dev is already installed
2017-11-30 09:30:43,858 [salt.state       ][INFO    ][21942] Completed state [libvirt-dev] at time 09:30:43.858385 duration_in_ms=21.185
2017-11-30 09:30:43,859 [salt.state       ][INFO    ][21942] Running state [pkg-config] at time 09:30:43.858842
2017-11-30 09:30:43,859 [salt.state       ][INFO    ][21942] Executing state pkg.installed for pkg-config
2017-11-30 09:30:43,867 [salt.state       ][INFO    ][21942] Package pkg-config is already installed
2017-11-30 09:30:43,867 [salt.state       ][INFO    ][21942] Completed state [pkg-config] at time 09:30:43.867323 duration_in_ms=8.481
2017-11-30 09:30:43,868 [salt.state       ][INFO    ][21942] Running state [virtng.init] at time 09:30:43.867749
2017-11-30 09:30:43,868 [salt.state       ][INFO    ][21942] Executing state module.run for virtng.init
2017-11-30 09:30:44,054 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command ['git', '--version'] in directory '/root'
2017-11-30 09:30:44,143 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --all --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,176 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,177 [salt.state       ][INFO    ][21942] Completed state [virtng.init] at time 09:30:44.176890 duration_in_ms=309.141
2017-11-30 09:30:44,177 [salt.state       ][INFO    ][21942] Running state [virt.set_autostart] at time 09:30:44.177402
2017-11-30 09:30:44,178 [salt.state       ][INFO    ][21942] Executing state module.run for virt.set_autostart
2017-11-30 09:30:44,182 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --autostart --name| grep -E "^prx01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,214 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,219 [salt.state       ][INFO    ][21942] Completed state [virt.set_autostart] at time 09:30:44.218778 duration_in_ms=41.375
2017-11-30 09:30:44,220 [salt.state       ][INFO    ][21942] Running state [virtng.init] at time 09:30:44.219463
2017-11-30 09:30:44,220 [salt.state       ][INFO    ][21942] Executing state module.run for virtng.init
2017-11-30 09:30:44,225 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --all --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,256 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,256 [salt.state       ][INFO    ][21942] Completed state [virtng.init] at time 09:30:44.256392 duration_in_ms=36.929
2017-11-30 09:30:44,257 [salt.state       ][INFO    ][21942] Running state [virt.set_autostart] at time 09:30:44.256753
2017-11-30 09:30:44,257 [salt.state       ][INFO    ][21942] Executing state module.run for virt.set_autostart
2017-11-30 09:30:44,260 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --autostart --name| grep -E "^mdb01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,287 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,299 [salt.state       ][INFO    ][21942] Completed state [virt.set_autostart] at time 09:30:44.299356 duration_in_ms=42.602
2017-11-30 09:30:44,300 [salt.state       ][INFO    ][21942] Running state [virtng.init] at time 09:30:44.299789
2017-11-30 09:30:44,300 [salt.state       ][INFO    ][21942] Executing state module.run for virtng.init
2017-11-30 09:30:44,303 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --all --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,336 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,339 [salt.state       ][INFO    ][21942] Completed state [virtng.init] at time 09:30:44.338962 duration_in_ms=39.172
2017-11-30 09:30:44,340 [salt.state       ][INFO    ][21942] Running state [virt.set_autostart] at time 09:30:44.339669
2017-11-30 09:30:44,340 [salt.state       ][INFO    ][21942] Executing state module.run for virt.set_autostart
2017-11-30 09:30:44,345 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --autostart --name| grep -E "^ctl01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,378 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,379 [salt.state       ][INFO    ][21942] Completed state [virt.set_autostart] at time 09:30:44.378754 duration_in_ms=39.085
2017-11-30 09:30:44,379 [salt.state       ][INFO    ][21942] Running state [virtng.init] at time 09:30:44.379256
2017-11-30 09:30:44,380 [salt.state       ][INFO    ][21942] Executing state module.run for virtng.init
2017-11-30 09:30:44,384 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --all --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,417 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,420 [salt.state       ][INFO    ][21942] Completed state [virtng.init] at time 09:30:44.419535 duration_in_ms=40.279
2017-11-30 09:30:44,420 [salt.state       ][INFO    ][21942] Running state [virt.set_autostart] at time 09:30:44.420188
2017-11-30 09:30:44,421 [salt.state       ][INFO    ][21942] Executing state module.run for virt.set_autostart
2017-11-30 09:30:44,426 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --autostart --name| grep -E "^msg01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,460 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,460 [salt.state       ][INFO    ][21942] Completed state [virt.set_autostart] at time 09:30:44.459895 duration_in_ms=39.707
2017-11-30 09:30:44,460 [salt.state       ][INFO    ][21942] Running state [virtng.init] at time 09:30:44.460368
2017-11-30 09:30:44,461 [salt.state       ][INFO    ][21942] Executing state module.run for virtng.init
2017-11-30 09:30:44,465 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --all --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,497 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,497 [salt.state       ][INFO    ][21942] Completed state [virtng.init] at time 09:30:44.497116 duration_in_ms=36.746
2017-11-30 09:30:44,498 [salt.state       ][INFO    ][21942] Running state [virt.set_autostart] at time 09:30:44.497645
2017-11-30 09:30:44,498 [salt.state       ][INFO    ][21942] Executing state module.run for virt.set_autostart
2017-11-30 09:30:44,502 [salt.loaded.int.module.cmdmod][INFO    ][21942] Executing command 'virsh list --autostart --name| grep -E "^dbs01.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-30 09:30:44,532 [salt.state       ][INFO    ][21942] unless execution succeeded
2017-11-30 09:30:44,540 [salt.state       ][INFO    ][21942] Completed state [virt.set_autostart] at time 09:30:44.539568 duration_in_ms=41.922
2017-11-30 09:30:44,545 [salt.minion      ][INFO    ][21942] Returning information for job: 20171130093037283762
2017-11-30 09:30:48,215 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command pillar.get with jid 20171130093048210102
2017-11-30 09:30:48,236 [salt.minion      ][INFO    ][22035] Starting a new job with PID 22035
2017-11-30 09:30:48,242 [salt.minion      ][INFO    ][22035] Returning information for job: 20171130093048210102
2017-11-30 09:31:33,181 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171130093133171853
2017-11-30 09:31:33,204 [salt.minion      ][INFO    ][22046] Starting a new job with PID 22046
2017-11-30 09:31:36,476 [salt.state       ][INFO    ][22046] Loading fresh modules for state activity
2017-11-30 09:31:36,515 [salt.state       ][ERROR   ][22046] No contents found in top file
2017-11-30 09:31:36,517 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-30 09:31:36,522 [salt.utils.extmods][INFO    ][22046] Syncing beacons for environment 'base'
2017-11-30 09:31:36,522 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_beacons, for base)
2017-11-30 09:31:36,523 [salt.fileclient  ][INFO    ][22046] Caching directory '_beacons/' for environment 'base'
2017-11-30 09:31:36,554 [salt.utils.extmods][INFO    ][22046] Syncing modules for environment 'base'
2017-11-30 09:31:36,555 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_modules, for base)
2017-11-30 09:31:36,555 [salt.fileclient  ][INFO    ][22046] Caching directory '_modules/' for environment 'base'
2017-11-30 09:31:36,625 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-11-30 09:31:36,638 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-30 09:31:36,651 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-30 09:31:36,889 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-30 09:31:36,928 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-30 09:31:36,941 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-30 09:31:36,991 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-30 09:31:37,010 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-30 09:31:37,064 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-30 09:31:37,098 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-30 09:31:37,136 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-30 09:31:37,168 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-30 09:31:37,202 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-30 09:31:37,240 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-30 09:31:37,266 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-30 09:31:37,315 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-30 09:31:37,336 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-30 09:31:37,370 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-30 09:31:37,432 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-30 09:31:37,510 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-30 09:31:37,557 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-30 09:31:37,601 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-30 09:31:37,620 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-30 09:31:37,694 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-30 09:31:37,713 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-30 09:31:37,728 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-30 09:31:37,773 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-30 09:31:37,790 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-30 09:31:37,815 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-30 09:31:37,833 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-30 09:31:37,932 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-30 09:31:37,946 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-30 09:31:37,962 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-30 09:31:37,998 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-30 09:31:38,002 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-30 09:31:38,002 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-11-30 09:31:38,002 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-30 09:31:38,003 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-30 09:31:38,003 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-30 09:31:38,004 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-30 09:31:38,004 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-30 09:31:38,004 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-30 09:31:38,005 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-30 09:31:38,005 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-30 09:31:38,005 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-30 09:31:38,006 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-30 09:31:38,006 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-30 09:31:38,006 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-30 09:31:38,007 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-30 09:31:38,007 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-30 09:31:38,008 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-30 09:31:38,008 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-30 09:31:38,009 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-30 09:31:38,009 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-30 09:31:38,009 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-30 09:31:38,010 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-30 09:31:38,010 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-30 09:31:38,011 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-30 09:31:38,012 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-30 09:31:38,012 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-30 09:31:38,012 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-30 09:31:38,013 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-30 09:31:38,013 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-30 09:31:38,013 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-30 09:31:38,014 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-30 09:31:38,014 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-30 09:31:38,014 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-30 09:31:38,014 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-11-30 09:31:38,015 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-30 09:31:38,015 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-30 09:31:38,021 [salt.utils.extmods][INFO    ][22046] Syncing states for environment 'base'
2017-11-30 09:31:38,021 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_states, for base)
2017-11-30 09:31:38,022 [salt.fileclient  ][INFO    ][22046] Caching directory '_states/' for environment 'base'
2017-11-30 09:31:38,101 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-11-30 09:31:38,111 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-30 09:31:38,162 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-30 09:31:38,170 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-30 09:31:38,202 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-30 09:31:38,298 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-30 09:31:38,366 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-30 09:31:38,394 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-30 09:31:38,405 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-30 09:31:38,418 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-11-30 09:31:38,430 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-30 09:31:38,480 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-30 09:31:38,491 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-30 09:31:38,501 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-11-30 09:31:38,513 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-30 09:31:38,537 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-30 09:31:38,553 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-30 09:31:38,589 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-30 09:31:38,634 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-30 09:31:39,047 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-30 09:31:39,057 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-30 09:31:39,299 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-30 09:31:39,312 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-30 09:31:39,691 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-30 09:31:39,701 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-30 09:31:39,720 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-30 09:31:39,886 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-30 09:31:39,898 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-30 09:31:39,915 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-30 09:31:39,925 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-30 09:31:39,936 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-30 09:31:39,948 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-30 09:31:39,956 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-11-30 09:31:39,956 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-30 09:31:39,957 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-30 09:31:39,958 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-30 09:31:39,958 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-30 09:31:39,959 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-30 09:31:39,959 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-30 09:31:39,960 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-30 09:31:39,960 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-30 09:31:39,961 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-11-30 09:31:39,961 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-30 09:31:39,962 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-30 09:31:39,962 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-30 09:31:39,963 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-11-30 09:31:39,963 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-30 09:31:39,964 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-30 09:31:39,964 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-30 09:31:39,965 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-30 09:31:39,965 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-30 09:31:39,966 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-30 09:31:39,966 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-30 09:31:39,967 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-30 09:31:39,967 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-30 09:31:39,968 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-30 09:31:39,968 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-30 09:31:39,969 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-30 09:31:39,969 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-30 09:31:39,970 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-30 09:31:39,970 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-30 09:31:39,971 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-30 09:31:39,971 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-30 09:31:39,972 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-30 09:31:39,972 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-30 09:31:39,976 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-30 09:31:39,981 [salt.utils.extmods][INFO    ][22046] Syncing sdb for environment 'base'
2017-11-30 09:31:39,981 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_sdb, for base)
2017-11-30 09:31:39,982 [salt.fileclient  ][INFO    ][22046] Caching directory '_sdb/' for environment 'base'
2017-11-30 09:31:40,020 [salt.utils.extmods][INFO    ][22046] Syncing grains for environment 'base'
2017-11-30 09:31:40,020 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_grains, for base)
2017-11-30 09:31:40,020 [salt.fileclient  ][INFO    ][22046] Caching directory '_grains/' for environment 'base'
2017-11-30 09:31:40,048 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-30 09:31:40,058 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-30 09:31:40,071 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-30 09:31:40,077 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-30 09:31:40,084 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-30 09:31:40,094 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-30 09:31:40,103 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-30 09:31:40,109 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-30 09:31:40,122 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-30 09:31:40,128 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-30 09:31:40,134 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-30 09:31:40,141 [salt.fileclient  ][INFO    ][22046] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-30 09:31:40,144 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-30 09:31:40,144 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-30 09:31:40,144 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-30 09:31:40,144 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-30 09:31:40,145 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-30 09:31:40,145 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-30 09:31:40,145 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-30 09:31:40,145 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-30 09:31:40,146 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-30 09:31:40,146 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-30 09:31:40,146 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-30 09:31:40,146 [salt.utils.extmods][INFO    ][22046] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-30 09:31:40,148 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-30 09:31:40,150 [salt.utils.extmods][INFO    ][22046] Syncing renderers for environment 'base'
2017-11-30 09:31:40,150 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_renderers, for base)
2017-11-30 09:31:40,150 [salt.fileclient  ][INFO    ][22046] Caching directory '_renderers/' for environment 'base'
2017-11-30 09:31:40,169 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-30 09:31:40,171 [salt.utils.extmods][INFO    ][22046] Syncing returners for environment 'base'
2017-11-30 09:31:40,171 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_returners, for base)
2017-11-30 09:31:40,172 [salt.fileclient  ][INFO    ][22046] Caching directory '_returners/' for environment 'base'
2017-11-30 09:31:40,191 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-30 09:31:40,194 [salt.utils.extmods][INFO    ][22046] Syncing output for environment 'base'
2017-11-30 09:31:40,194 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_output, for base)
2017-11-30 09:31:40,194 [salt.fileclient  ][INFO    ][22046] Caching directory '_output/' for environment 'base'
2017-11-30 09:31:40,212 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-30 09:31:40,215 [salt.utils.extmods][INFO    ][22046] Syncing utils for environment 'base'
2017-11-30 09:31:40,215 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_utils, for base)
2017-11-30 09:31:40,215 [salt.fileclient  ][INFO    ][22046] Caching directory '_utils/' for environment 'base'
2017-11-30 09:31:40,235 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-30 09:31:40,237 [salt.utils.extmods][INFO    ][22046] Syncing log_handlers for environment 'base'
2017-11-30 09:31:40,237 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_log_handlers, for base)
2017-11-30 09:31:40,237 [salt.fileclient  ][INFO    ][22046] Caching directory '_log_handlers/' for environment 'base'
2017-11-30 09:31:40,256 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-30 09:31:40,258 [salt.utils.extmods][INFO    ][22046] Syncing proxy for environment 'base'
2017-11-30 09:31:40,258 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_proxy, for base)
2017-11-30 09:31:40,258 [salt.fileclient  ][INFO    ][22046] Caching directory '_proxy/' for environment 'base'
2017-11-30 09:31:40,276 [salt.utils.extmods][INFO    ][22046] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-30 09:31:40,278 [salt.utils.extmods][INFO    ][22046] Syncing engines for environment 'base'
2017-11-30 09:31:40,278 [salt.utils.extmods][INFO    ][22046] Loading cache from salt://_engines, for base)
2017-11-30 09:31:40,279 [salt.fileclient  ][INFO    ][22046] Caching directory '_engines/' for environment 'base'
2017-11-30 09:31:40,308 [salt.minion      ][INFO    ][22046] Returning information for job: 20171130093133171853
2017-11-30 09:45:06,628 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command test.ping with jid 20171130094506624351
2017-11-30 09:45:06,651 [salt.minion      ][INFO    ][22340] Starting a new job with PID 22340
2017-11-30 09:45:06,708 [salt.minion      ][INFO    ][22340] Returning information for job: 20171130094506624351
2017-11-30 09:49:07,992 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130094907985609
2017-11-30 09:49:08,016 [salt.minion      ][INFO    ][22401] Starting a new job with PID 22401
2017-11-30 09:49:08,484 [salt.state       ][INFO    ][22401] Loading fresh modules for state activity
2017-11-30 09:49:08,529 [salt.fileclient  ][INFO    ][22401] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-30 09:49:08,555 [salt.fileclient  ][INFO    ][22401] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-30 09:49:08,592 [salt.fileclient  ][INFO    ][22401] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:49:08,947 [salt.state       ][INFO    ][22401] Running state [lsof] at time 09:49:08.946897
2017-11-30 09:49:08,947 [salt.state       ][INFO    ][22401] Executing state pkg.installed for lsof
2017-11-30 09:49:08,948 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:49:09,356 [salt.state       ][INFO    ][22401] Package lsof is already installed
2017-11-30 09:49:09,356 [salt.state       ][INFO    ][22401] Completed state [lsof] at time 09:49:09.355974 duration_in_ms=409.078
2017-11-30 09:49:09,366 [salt.state       ][INFO    ][22401] Running state [keepalived] at time 09:49:09.365968
2017-11-30 09:49:09,367 [salt.state       ][INFO    ][22401] Executing state pkg.installed for keepalived
2017-11-30 09:49:09,402 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-30 09:49:11,426 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2017-11-30 09:49:18,037 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130094918029728
2017-11-30 09:49:18,060 [salt.minion      ][INFO    ][23619] Starting a new job with PID 23619
2017-11-30 09:49:18,079 [salt.minion      ][INFO    ][23619] Returning information for job: 20171130094918029728
2017-11-30 09:49:20,476 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:49:20,530 [salt.state       ][INFO    ][22401] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'keepalived' changed from 'absent' to '1:1.2.19-1ubuntu0.2'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libsensors4' changed from 'absent' to '1:3.4.0-2'

2017-11-30 09:49:20,549 [salt.state       ][INFO    ][22401] Loading fresh modules for state activity
2017-11-30 09:49:20,575 [salt.state       ][INFO    ][22401] Completed state [keepalived] at time 09:49:20.574903 duration_in_ms=11208.936
2017-11-30 09:49:20,581 [salt.state       ][INFO    ][22401] Running state [/etc/keepalived/keepalived.conf] at time 09:49:20.581317
2017-11-30 09:49:20,582 [salt.state       ][INFO    ][22401] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-30 09:49:20,623 [salt.fileclient  ][INFO    ][22401] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-30 09:49:20,665 [salt.fileclient  ][INFO    ][22401] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 09:49:20,674 [salt.state       ][INFO    ][22401] File changed:
New file
2017-11-30 09:49:20,674 [salt.state       ][INFO    ][22401] Completed state [/etc/keepalived/keepalived.conf] at time 09:49:20.673970 duration_in_ms=92.654
2017-11-30 09:49:20,739 [salt.state       ][INFO    ][22401] Running state [keepalived] at time 09:49:20.738776
2017-11-30 09:49:20,739 [salt.state       ][INFO    ][22401] Executing state service.running for keepalived
2017-11-30 09:49:20,741 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-30 09:49:20,755 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-30 09:49:20,769 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:49:20,783 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:49:20,799 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-30 09:49:20,876 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-30 09:49:20,889 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:49:20,907 [salt.loaded.int.module.cmdmod][INFO    ][22401] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 09:49:20,919 [salt.state       ][INFO    ][22401] {'keepalived': True}
2017-11-30 09:49:20,920 [salt.state       ][INFO    ][22401] Completed state [keepalived] at time 09:49:20.919493 duration_in_ms=180.716
2017-11-30 09:49:20,921 [salt.minion      ][INFO    ][22401] Returning information for job: 20171130094907985609
2017-11-30 09:49:38,309 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command pillar.get with jid 20171130094938308106
2017-11-30 09:49:38,338 [salt.minion      ][INFO    ][23878] Starting a new job with PID 23878
2017-11-30 09:49:38,345 [salt.minion      ][INFO    ][23878] Returning information for job: 20171130094938308106
2017-11-30 09:56:09,855 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130095609849627
2017-11-30 09:56:09,876 [salt.minion      ][INFO    ][23981] Starting a new job with PID 23981
2017-11-30 09:56:12,429 [salt.state       ][INFO    ][23981] Loading fresh modules for state activity
2017-11-30 09:56:12,476 [salt.fileclient  ][INFO    ][23981] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 09:56:12,509 [salt.fileclient  ][INFO    ][23981] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 09:56:12,926 [salt.state       ][INFO    ][23981] Running state [attr] at time 09:56:12.926392
2017-11-30 09:56:12,927 [salt.state       ][INFO    ][23981] Executing state pkg.installed for attr
2017-11-30 09:56:12,927 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:13,319 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-30 09:56:15,338 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'attr'] in directory '/root'
2017-11-30 09:56:18,322 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:18,379 [salt.state       ][INFO    ][23981] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-30 09:56:18,396 [salt.state       ][INFO    ][23981] Loading fresh modules for state activity
2017-11-30 09:56:18,422 [salt.state       ][INFO    ][23981] Completed state [attr] at time 09:56:18.421425 duration_in_ms=5495.032
2017-11-30 09:56:18,431 [salt.state       ][INFO    ][23981] Running state [glusterfs-server] at time 09:56:18.430719
2017-11-30 09:56:18,431 [salt.state       ][INFO    ][23981] Executing state pkg.installed for glusterfs-server
2017-11-30 09:56:18,722 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'glusterfs-server'] in directory '/root'
2017-11-30 09:56:19,943 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130095619934732
2017-11-30 09:56:19,964 [salt.minion      ][INFO    ][24866] Starting a new job with PID 24866
2017-11-30 09:56:19,989 [salt.minion      ][INFO    ][24866] Returning information for job: 20171130095619934732
2017-11-30 09:56:30,158 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130095630150684
2017-11-30 09:56:30,179 [salt.minion      ][INFO    ][25752] Starting a new job with PID 25752
2017-11-30 09:56:30,206 [salt.minion      ][INFO    ][25752] Returning information for job: 20171130095630150684
2017-11-30 09:56:40,394 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command saltutil.find_job with jid 20171130095640374016
2017-11-30 09:56:40,409 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:40,414 [salt.minion      ][INFO    ][26402] Starting a new job with PID 26402
2017-11-30 09:56:40,435 [salt.minion      ][INFO    ][26402] Returning information for job: 20171130095640374016
2017-11-30 09:56:40,449 [salt.state       ][INFO    ][23981] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'glusterfs-client' changed from 'absent' to '3.10.7-ubuntu1~xenial1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.2'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'glusterfs-common' changed from 'absent' to '3.10.7-ubuntu1~xenial1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'portmap' changed from 'absent' to '1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'
'nfs-client' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.10.7-ubuntu1~xenial1'

2017-11-30 09:56:40,465 [salt.state       ][INFO    ][23981] Loading fresh modules for state activity
2017-11-30 09:56:40,490 [salt.state       ][INFO    ][23981] Completed state [glusterfs-server] at time 09:56:40.489743 duration_in_ms=22059.024
2017-11-30 09:56:40,498 [salt.state       ][INFO    ][23981] Running state [bc] at time 09:56:40.497814
2017-11-30 09:56:40,498 [salt.state       ][INFO    ][23981] Executing state pkg.installed for bc
2017-11-30 09:56:40,795 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'bc'] in directory '/root'
2017-11-30 09:56:44,389 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:56:44,446 [salt.state       ][INFO    ][23981] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-30 09:56:44,461 [salt.state       ][INFO    ][23981] Loading fresh modules for state activity
2017-11-30 09:56:44,487 [salt.state       ][INFO    ][23981] Completed state [bc] at time 09:56:44.487243 duration_in_ms=3989.427
2017-11-30 09:56:44,589 [salt.state       ][INFO    ][23981] Running state [glusterfs-server] at time 09:56:44.588503
2017-11-30 09:56:44,589 [salt.state       ][INFO    ][23981] Executing state service.running for glusterfs-server
2017-11-30 09:56:44,590 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 09:56:44,606 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:56:44,620 [salt.loaded.int.module.cmdmod][INFO    ][23981] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:56:44,640 [salt.state       ][INFO    ][23981] The service glusterfs-server is already running
2017-11-30 09:56:44,640 [salt.state       ][INFO    ][23981] Completed state [glusterfs-server] at time 09:56:44.639880 duration_in_ms=51.377
2017-11-30 09:56:44,642 [salt.state       ][INFO    ][23981] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:56:44.641643
2017-11-30 09:56:44,642 [salt.state       ][INFO    ][23981] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 09:56:44,661 [salt.state       ][INFO    ][23981] File changed:
New file
2017-11-30 09:56:44,662 [salt.state       ][INFO    ][23981] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:56:44.661722 duration_in_ms=20.079
2017-11-30 09:56:44,662 [salt.state       ][INFO    ][23981] Running state [/srv/glusterfs/glance] at time 09:56:44.662084
2017-11-30 09:56:44,662 [salt.state       ][INFO    ][23981] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 09:56:44,664 [salt.state       ][INFO    ][23981] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-30 09:56:44,664 [salt.state       ][INFO    ][23981] Completed state [/srv/glusterfs/glance] at time 09:56:44.664008 duration_in_ms=1.924
2017-11-30 09:56:44,664 [salt.state       ][INFO    ][23981] Running state [/srv/glusterfs/keystone-keys] at time 09:56:44.664347
2017-11-30 09:56:44,665 [salt.state       ][INFO    ][23981] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 09:56:44,666 [salt.state       ][INFO    ][23981] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-30 09:56:44,666 [salt.state       ][INFO    ][23981] Completed state [/srv/glusterfs/keystone-keys] at time 09:56:44.665844 duration_in_ms=1.497
2017-11-30 09:56:44,666 [salt.state       ][INFO    ][23981] Running state [/srv/glusterfs/nova_instances] at time 09:56:44.666181
2017-11-30 09:56:44,667 [salt.state       ][INFO    ][23981] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 09:56:44,667 [salt.state       ][INFO    ][23981] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-30 09:56:44,668 [salt.state       ][INFO    ][23981] Completed state [/srv/glusterfs/nova_instances] at time 09:56:44.667709 duration_in_ms=1.528
2017-11-30 09:56:44,668 [salt.state       ][INFO    ][23981] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:56:44.668048
2017-11-30 09:56:44,668 [salt.state       ][INFO    ][23981] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 09:56:44,669 [salt.state       ][INFO    ][23981] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-30 09:56:44,670 [salt.state       ][INFO    ][23981] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:56:44.669534 duration_in_ms=1.486
2017-11-30 09:56:44,671 [salt.minion      ][INFO    ][23981] Returning information for job: 20171130095609849627
2017-11-30 09:56:46,260 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command test.ping with jid 20171130095646255040
2017-11-30 09:56:46,282 [salt.minion      ][INFO    ][26857] Starting a new job with PID 26857
2017-11-30 09:56:46,333 [salt.minion      ][INFO    ][26857] Returning information for job: 20171130095646255040
2017-11-30 09:57:38,567 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130095738558172
2017-11-30 09:57:38,590 [salt.minion      ][INFO    ][27497] Starting a new job with PID 27497
2017-11-30 09:57:39,128 [salt.state       ][INFO    ][27497] Loading fresh modules for state activity
2017-11-30 09:57:39,172 [salt.fileclient  ][INFO    ][27497] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-30 09:57:39,232 [salt.fileclient  ][INFO    ][27497] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 09:57:39,340 [salt.fileclient  ][INFO    ][27497] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 09:57:39,362 [salt.fileclient  ][INFO    ][27497] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 09:57:39,670 [salt.state       ][INFO    ][27497] Running state [attr] at time 09:57:39.669526
2017-11-30 09:57:39,670 [salt.state       ][INFO    ][27497] Executing state pkg.installed for attr
2017-11-30 09:57:39,670 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 09:57:40,057 [salt.state       ][INFO    ][27497] Package attr is already installed
2017-11-30 09:57:40,058 [salt.state       ][INFO    ][27497] Completed state [attr] at time 09:57:40.057441 duration_in_ms=387.915
2017-11-30 09:57:40,058 [salt.state       ][INFO    ][27497] Running state [glusterfs-server] at time 09:57:40.057769
2017-11-30 09:57:40,058 [salt.state       ][INFO    ][27497] Executing state pkg.installed for glusterfs-server
2017-11-30 09:57:40,061 [salt.state       ][INFO    ][27497] Package glusterfs-server is already installed
2017-11-30 09:57:40,061 [salt.state       ][INFO    ][27497] Completed state [glusterfs-server] at time 09:57:40.061108 duration_in_ms=3.339
2017-11-30 09:57:40,061 [salt.state       ][INFO    ][27497] Running state [bc] at time 09:57:40.061336
2017-11-30 09:57:40,062 [salt.state       ][INFO    ][27497] Executing state pkg.installed for bc
2017-11-30 09:57:40,064 [salt.state       ][INFO    ][27497] Package bc is already installed
2017-11-30 09:57:40,065 [salt.state       ][INFO    ][27497] Completed state [bc] at time 09:57:40.064499 duration_in_ms=3.163
2017-11-30 09:57:40,065 [salt.state       ][INFO    ][27497] Running state [glusterfs-server] at time 09:57:40.065445
2017-11-30 09:57:40,066 [salt.state       ][INFO    ][27497] Executing state service.running for glusterfs-server
2017-11-30 09:57:40,066 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 09:57:40,082 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-30 09:57:40,176 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 09:57:40,205 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:57:40,217 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 09:57:40,236 [salt.state       ][INFO    ][27497] The service glusterfs-server is already running
2017-11-30 09:57:40,236 [salt.state       ][INFO    ][27497] Completed state [glusterfs-server] at time 09:57:40.236211 duration_in_ms=170.765
2017-11-30 09:57:40,240 [salt.state       ][INFO    ][27497] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:57:40.239518
2017-11-30 09:57:40,240 [salt.state       ][INFO    ][27497] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 09:57:40,258 [salt.state       ][INFO    ][27497] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-30 09:57:40,258 [salt.state       ][INFO    ][27497] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:57:40.257809 duration_in_ms=18.291
2017-11-30 09:57:40,258 [salt.state       ][INFO    ][27497] Running state [/srv/glusterfs/glance] at time 09:57:40.258217
2017-11-30 09:57:40,259 [salt.state       ][INFO    ][27497] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 09:57:40,259 [salt.state       ][INFO    ][27497] Directory /srv/glusterfs/glance is in the correct state
2017-11-30 09:57:40,260 [salt.state       ][INFO    ][27497] Completed state [/srv/glusterfs/glance] at time 09:57:40.259743 duration_in_ms=1.526
2017-11-30 09:57:40,260 [salt.state       ][INFO    ][27497] Running state [/srv/glusterfs/keystone-keys] at time 09:57:40.260119
2017-11-30 09:57:40,261 [salt.state       ][INFO    ][27497] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 09:57:40,261 [salt.state       ][INFO    ][27497] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-30 09:57:40,262 [salt.state       ][INFO    ][27497] Completed state [/srv/glusterfs/keystone-keys] at time 09:57:40.261587 duration_in_ms=1.468
2017-11-30 09:57:40,262 [salt.state       ][INFO    ][27497] Running state [/srv/glusterfs/nova_instances] at time 09:57:40.261974
2017-11-30 09:57:40,262 [salt.state       ][INFO    ][27497] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 09:57:40,263 [salt.state       ][INFO    ][27497] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-30 09:57:40,264 [salt.state       ][INFO    ][27497] Completed state [/srv/glusterfs/nova_instances] at time 09:57:40.263484 duration_in_ms=1.496
2017-11-30 09:57:40,264 [salt.state       ][INFO    ][27497] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:57:40.263839
2017-11-30 09:57:40,264 [salt.state       ][INFO    ][27497] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 09:57:40,265 [salt.state       ][INFO    ][27497] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-30 09:57:40,265 [salt.state       ][INFO    ][27497] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:57:40.265220 duration_in_ms=1.38
2017-11-30 09:57:40,404 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command ['git', '--version'] in directory '/root'
2017-11-30 09:57:40,561 [salt.state       ][INFO    ][27497] Running state [sleep 5] at time 09:57:40.560511
2017-11-30 09:57:40,561 [salt.state       ][INFO    ][27497] Executing state cmd.wait for sleep 5
2017-11-30 09:57:40,561 [salt.state       ][INFO    ][27497] No changes made for sleep 5
2017-11-30 09:57:40,561 [salt.state       ][INFO    ][27497] Completed state [sleep 5] at time 09:57:40.561172 duration_in_ms=0.661
2017-11-30 09:57:40,562 [salt.state       ][INFO    ][27497] Running state [10.167.4.141] at time 09:57:40.561622
2017-11-30 09:57:40,562 [salt.state       ][INFO    ][27497] Executing state glusterfs.peered for 10.167.4.141
2017-11-30 09:57:40,562 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:40,579 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:40,693 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:40,710 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:40,823 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:40,839 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:40,955 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:40,973 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:41,088 [salt.state       ][INFO    ][27497] Peering with localhost is not needed
2017-11-30 09:57:41,089 [salt.state       ][INFO    ][27497] Completed state [10.167.4.141] at time 09:57:41.088620 duration_in_ms=526.996
2017-11-30 09:57:41,095 [salt.state       ][INFO    ][27497] Running state [10.167.4.142] at time 09:57:41.094953
2017-11-30 09:57:41,096 [salt.state       ][INFO    ][27497] Executing state glusterfs.peered for 10.167.4.142
2017-11-30 09:57:41,097 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:41,115 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:41,228 [salt.state       ][INFO    ][27497] Host 10.167.4.142 already peered
2017-11-30 09:57:41,228 [salt.state       ][INFO    ][27497] Completed state [10.167.4.142] at time 09:57:41.228248 duration_in_ms=133.294
2017-11-30 09:57:41,234 [salt.state       ][INFO    ][27497] Running state [10.167.4.143] at time 09:57:41.234020
2017-11-30 09:57:41,235 [salt.state       ][INFO    ][27497] Executing state glusterfs.peered for 10.167.4.143
2017-11-30 09:57:41,235 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:41,254 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:41,368 [salt.state       ][INFO    ][27497] Host 10.167.4.143 already peered
2017-11-30 09:57:41,368 [salt.state       ][INFO    ][27497] Completed state [10.167.4.143] at time 09:57:41.367957 duration_in_ms=133.936
2017-11-30 09:57:41,370 [salt.state       ][INFO    ][27497] Running state [glance] at time 09:57:41.369801
2017-11-30 09:57:41,370 [salt.state       ][INFO    ][27497] Executing state glusterfs.created for glance
2017-11-30 09:57:41,371 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:41,390 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:41,505 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:41,524 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:41,642 [salt.state       ][INFO    ][27497] Volume glance already exists and is started.
2017-11-30 09:57:41,642 [salt.state       ][INFO    ][27497] Completed state [glance] at time 09:57:41.642067 duration_in_ms=272.265
2017-11-30 09:57:41,650 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:41.650322
2017-11-30 09:57:41,651 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:41,652 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:41,761 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:41,762 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:41.761833 duration_in_ms=111.511
2017-11-30 09:57:41,763 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:57:41.762900
2017-11-30 09:57:41,763 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-30 09:57:41,764 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:41,869 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:41,870 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:57:41.869721 duration_in_ms=106.82
2017-11-30 09:57:41,871 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:57:41.870869
2017-11-30 09:57:41,871 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-30 09:57:41,872 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:41,984 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:41,985 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:57:41.984536 duration_in_ms=113.666
2017-11-30 09:57:41,986 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:41.985703
2017-11-30 09:57:41,986 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:41,987 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:42,099 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:42,099 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:42.099315 duration_in_ms=113.612
2017-11-30 09:57:42,101 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:42.100513
2017-11-30 09:57:42,101 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:42,102 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:42,217 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:42,218 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:42.217934 duration_in_ms=117.421
2017-11-30 09:57:42,219 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:57:42.219094
2017-11-30 09:57:42,220 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:42,221 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:42,333 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:42,333 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:57:42.333052 duration_in_ms=113.957
2017-11-30 09:57:42,336 [salt.state       ][INFO    ][27497] Running state [glance] at time 09:57:42.336126
2017-11-30 09:57:42,337 [salt.state       ][INFO    ][27497] Executing state glusterfs.started for glance
2017-11-30 09:57:42,338 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:42,356 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:42,470 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:42,488 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:42,612 [salt.state       ][INFO    ][27497] Volume glance is already started
2017-11-30 09:57:42,612 [salt.state       ][INFO    ][27497] Completed state [glance] at time 09:57:42.612134 duration_in_ms=276.007
2017-11-30 09:57:42,614 [salt.state       ][INFO    ][27497] Running state [keystone-keys] at time 09:57:42.614267
2017-11-30 09:57:42,615 [salt.state       ][INFO    ][27497] Executing state glusterfs.created for keystone-keys
2017-11-30 09:57:42,616 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:42,633 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:42,746 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:42,762 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:42,882 [salt.state       ][INFO    ][27497] Volume keystone-keys already exists and is started.
2017-11-30 09:57:42,883 [salt.state       ][INFO    ][27497] Completed state [keystone-keys] at time 09:57:42.882844 duration_in_ms=268.576
2017-11-30 09:57:42,892 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:57:42.892028
2017-11-30 09:57:42,893 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-30 09:57:42,894 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:43,001 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:43,002 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:57:43.001765 duration_in_ms=109.736
2017-11-30 09:57:43,003 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:43.002921
2017-11-30 09:57:43,004 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:43,005 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:43,114 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:43,115 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:43.114693 duration_in_ms=111.772
2017-11-30 09:57:43,116 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:43.115908
2017-11-30 09:57:43,116 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:43,118 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:43,228 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:43,229 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:43.228594 duration_in_ms=112.684
2017-11-30 09:57:43,230 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:43.229819
2017-11-30 09:57:43,230 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:43,232 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:43,342 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:43,342 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:43.342124 duration_in_ms=112.304
2017-11-30 09:57:43,343 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:57:43.343225
2017-11-30 09:57:43,344 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-30 09:57:43,345 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:43,462 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:43,475 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:57:43.475092 duration_in_ms=131.866
2017-11-30 09:57:43,476 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:43.476328
2017-11-30 09:57:43,477 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:43,478 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:43,595 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:43,595 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:43.595307 duration_in_ms=118.979
2017-11-30 09:57:43,598 [salt.state       ][INFO    ][27497] Running state [keystone-keys] at time 09:57:43.598256
2017-11-30 09:57:43,599 [salt.state       ][INFO    ][27497] Executing state glusterfs.started for keystone-keys
2017-11-30 09:57:43,600 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:43,616 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:43,730 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:43,744 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:43,861 [salt.state       ][INFO    ][27497] Volume keystone-keys is already started
2017-11-30 09:57:43,862 [salt.state       ][INFO    ][27497] Completed state [keystone-keys] at time 09:57:43.861800 duration_in_ms=263.544
2017-11-30 09:57:43,864 [salt.state       ][INFO    ][27497] Running state [nova_instances] at time 09:57:43.863741
2017-11-30 09:57:43,864 [salt.state       ][INFO    ][27497] Executing state glusterfs.created for nova_instances
2017-11-30 09:57:43,865 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:43,882 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:43,991 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:44,008 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:44,121 [salt.state       ][INFO    ][27497] Volume nova_instances already exists and is started.
2017-11-30 09:57:44,122 [salt.state       ][INFO    ][27497] Completed state [nova_instances] at time 09:57:44.121823 duration_in_ms=258.082
2017-11-30 09:57:44,130 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:44.130400
2017-11-30 09:57:44,131 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:44,132 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:44,245 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:44,246 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:44.245705 duration_in_ms=115.305
2017-11-30 09:57:44,247 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:57:44.246876
2017-11-30 09:57:44,247 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:44,248 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:44,360 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:44,360 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:57:44.360111 duration_in_ms=113.235
2017-11-30 09:57:44,361 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:57:44.361271
2017-11-30 09:57:44,362 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-11-30 09:57:44,363 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:44,473 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:44,473 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:57:44.473150 duration_in_ms=111.878
2017-11-30 09:57:44,474 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:44.474302
2017-11-30 09:57:44,475 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:44,476 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:44,591 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:44,591 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:44.591091 duration_in_ms=116.789
2017-11-30 09:57:44,592 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:57:44.592311
2017-11-30 09:57:44,593 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-11-30 09:57:44,594 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:44,708 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:44,709 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:57:44.708887 duration_in_ms=116.575
2017-11-30 09:57:44,710 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:44.710066
2017-11-30 09:57:44,711 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:44,712 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:44,823 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:44,824 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:44.823788 duration_in_ms=113.721
2017-11-30 09:57:44,827 [salt.state       ][INFO    ][27497] Running state [nova_instances] at time 09:57:44.826847
2017-11-30 09:57:44,827 [salt.state       ][INFO    ][27497] Executing state glusterfs.started for nova_instances
2017-11-30 09:57:44,828 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:44,847 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:44,961 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:44,979 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:45,102 [salt.state       ][INFO    ][27497] Volume nova_instances is already started
2017-11-30 09:57:45,102 [salt.state       ][INFO    ][27497] Completed state [nova_instances] at time 09:57:45.102263 duration_in_ms=275.415
2017-11-30 09:57:45,105 [salt.state       ][INFO    ][27497] Running state [keystone-credential-keys] at time 09:57:45.104575
2017-11-30 09:57:45,105 [salt.state       ][INFO    ][27497] Executing state glusterfs.created for keystone-credential-keys
2017-11-30 09:57:45,106 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:45,122 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:45,228 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:45,244 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:45,366 [salt.state       ][INFO    ][27497] Volume keystone-credential-keys already exists and is started.
2017-11-30 09:57:45,367 [salt.state       ][INFO    ][27497] Completed state [keystone-credential-keys] at time 09:57:45.366404 duration_in_ms=261.828
2017-11-30 09:57:45,376 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:45.375760
2017-11-30 09:57:45,376 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 09:57:45,377 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 09:57:45,491 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:45,492 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:57:45.491494 duration_in_ms=115.733
2017-11-30 09:57:45,493 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:45.492669
2017-11-30 09:57:45,493 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 09:57:45,494 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 09:57:45,608 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:45,608 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:57:45.608217 duration_in_ms=115.547
2017-11-30 09:57:45,610 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:45.609429
2017-11-30 09:57:45,610 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 09:57:45,611 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:45,722 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:45,722 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:57:45.722375 duration_in_ms=112.945
2017-11-30 09:57:45,724 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:57:45.723731
2017-11-30 09:57:45,724 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-30 09:57:45,725 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 09:57:45,837 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:45,837 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:57:45.837060 duration_in_ms=113.329
2017-11-30 09:57:45,838 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:45.838223
2017-11-30 09:57:45,839 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 09:57:45,840 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 09:57:45,945 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:45,946 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:57:45.945675 duration_in_ms=107.45
2017-11-30 09:57:45,947 [salt.state       ][INFO    ][27497] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:57:45.946871
2017-11-30 09:57:45,947 [salt.state       ][INFO    ][27497] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-30 09:57:45,949 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 09:57:46,060 [salt.state       ][INFO    ][27497] unless execution succeeded
2017-11-30 09:57:46,061 [salt.state       ][INFO    ][27497] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:57:46.060728 duration_in_ms=113.855
2017-11-30 09:57:46,064 [salt.state       ][INFO    ][27497] Running state [keystone-credential-keys] at time 09:57:46.063987
2017-11-30 09:57:46,065 [salt.state       ][INFO    ][27497] Executing state glusterfs.started for keystone-credential-keys
2017-11-30 09:57:46,066 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:46,085 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:46,196 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --version' in directory '/root'
2017-11-30 09:57:46,215 [salt.loaded.int.module.cmdmod][INFO    ][27497] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 09:57:46,340 [salt.state       ][INFO    ][27497] Volume keystone-credential-keys is already started
2017-11-30 09:57:46,340 [salt.state       ][INFO    ][27497] Completed state [keystone-credential-keys] at time 09:57:46.340234 duration_in_ms=276.247
2017-11-30 09:57:46,344 [salt.minion      ][INFO    ][27497] Returning information for job: 20171130095738558172
2017-11-30 09:57:47,379 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command test.ping with jid 20171130095747376510
2017-11-30 09:57:47,401 [salt.minion      ][INFO    ][28025] Starting a new job with PID 28025
2017-11-30 09:57:47,459 [salt.minion      ][INFO    ][28025] Returning information for job: 20171130095747376510
2017-11-30 09:57:48,378 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command cmd.run with jid 20171130095748370974
2017-11-30 09:57:48,400 [salt.minion      ][INFO    ][28030] Starting a new job with PID 28030
2017-11-30 09:57:48,408 [salt.loaded.int.module.cmdmod][INFO    ][28030] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-30 09:57:48,637 [salt.minion      ][INFO    ][28030] Returning information for job: 20171130095748370974
2017-11-30 10:29:54,120 [salt.utils.schedule][INFO    ][21624] Running scheduled job: __mine_interval
2017-11-30 11:11:33,389 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command test.ping with jid 20171130111133382649
2017-11-30 11:11:33,415 [salt.minion      ][INFO    ][29015] Starting a new job with PID 29015
2017-11-30 11:11:33,473 [salt.minion      ][INFO    ][29015] Returning information for job: 20171130111133382649
2017-11-30 11:12:10,619 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130111210606681
2017-11-30 11:12:10,642 [salt.minion      ][INFO    ][29030] Starting a new job with PID 29030
2017-11-30 11:12:11,118 [salt.state       ][INFO    ][29030] Loading fresh modules for state activity
2017-11-30 11:12:11,166 [salt.fileclient  ][INFO    ][29030] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-30 11:12:11,193 [salt.fileclient  ][INFO    ][29030] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-30 11:12:11,236 [salt.fileclient  ][INFO    ][29030] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 11:12:11,682 [salt.state       ][INFO    ][29030] Running state [lsof] at time 11:12:11.681892
2017-11-30 11:12:11,682 [salt.state       ][INFO    ][29030] Executing state pkg.installed for lsof
2017-11-30 11:12:11,683 [salt.loaded.int.module.cmdmod][INFO    ][29030] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 11:12:12,069 [salt.state       ][INFO    ][29030] Package lsof is already installed
2017-11-30 11:12:12,069 [salt.state       ][INFO    ][29030] Completed state [lsof] at time 11:12:12.069032 duration_in_ms=387.141
2017-11-30 11:12:12,069 [salt.state       ][INFO    ][29030] Running state [keepalived] at time 11:12:12.069250
2017-11-30 11:12:12,069 [salt.state       ][INFO    ][29030] Executing state pkg.installed for keepalived
2017-11-30 11:12:12,072 [salt.state       ][INFO    ][29030] Package keepalived is already installed
2017-11-30 11:12:12,072 [salt.state       ][INFO    ][29030] Completed state [keepalived] at time 11:12:12.072344 duration_in_ms=3.094
2017-11-30 11:12:12,074 [salt.state       ][INFO    ][29030] Running state [/etc/keepalived/keepalived.conf] at time 11:12:12.073779
2017-11-30 11:12:12,074 [salt.state       ][INFO    ][29030] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-30 11:12:12,098 [salt.fileclient  ][INFO    ][29030] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-30 11:12:12,131 [salt.fileclient  ][INFO    ][29030] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-30 11:12:12,136 [salt.state       ][INFO    ][29030] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-30 11:12:12,136 [salt.state       ][INFO    ][29030] Completed state [/etc/keepalived/keepalived.conf] at time 11:12:12.135909 duration_in_ms=62.131
2017-11-30 11:12:12,136 [salt.state       ][INFO    ][29030] Running state [keepalived] at time 11:12:12.136458
2017-11-30 11:12:12,137 [salt.state       ][INFO    ][29030] Executing state service.running for keepalived
2017-11-30 11:12:12,137 [salt.loaded.int.module.cmdmod][INFO    ][29030] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-30 11:12:12,164 [salt.loaded.int.module.cmdmod][INFO    ][29030] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-30 11:12:12,177 [salt.loaded.int.module.cmdmod][INFO    ][29030] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-30 11:12:12,190 [salt.state       ][INFO    ][29030] The service keepalived is already running
2017-11-30 11:12:12,191 [salt.state       ][INFO    ][29030] Completed state [keepalived] at time 11:12:12.190638 duration_in_ms=54.179
2017-11-30 11:12:12,192 [salt.minion      ][INFO    ][29030] Returning information for job: 20171130111210606681
2017-11-30 11:12:13,093 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command pillar.get with jid 20171130111213082415
2017-11-30 11:12:13,119 [salt.minion      ][INFO    ][29054] Starting a new job with PID 29054
2017-11-30 11:12:13,126 [salt.minion      ][INFO    ][29054] Returning information for job: 20171130111213082415
2017-11-30 11:13:11,077 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130111311066848
2017-11-30 11:13:11,100 [salt.minion      ][INFO    ][29061] Starting a new job with PID 29061
2017-11-30 11:13:13,661 [salt.state       ][INFO    ][29061] Loading fresh modules for state activity
2017-11-30 11:13:13,702 [salt.fileclient  ][INFO    ][29061] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 11:13:13,734 [salt.fileclient  ][INFO    ][29061] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 11:13:14,139 [salt.state       ][INFO    ][29061] Running state [attr] at time 11:13:14.138697
2017-11-30 11:13:14,139 [salt.state       ][INFO    ][29061] Executing state pkg.installed for attr
2017-11-30 11:13:14,140 [salt.loaded.int.module.cmdmod][INFO    ][29061] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 11:13:14,563 [salt.state       ][INFO    ][29061] Package attr is already installed
2017-11-30 11:13:14,563 [salt.state       ][INFO    ][29061] Completed state [attr] at time 11:13:14.563104 duration_in_ms=424.407
2017-11-30 11:13:14,564 [salt.state       ][INFO    ][29061] Running state [glusterfs-server] at time 11:13:14.563472
2017-11-30 11:13:14,564 [salt.state       ][INFO    ][29061] Executing state pkg.installed for glusterfs-server
2017-11-30 11:13:14,569 [salt.state       ][INFO    ][29061] Package glusterfs-server is already installed
2017-11-30 11:13:14,569 [salt.state       ][INFO    ][29061] Completed state [glusterfs-server] at time 11:13:14.568647 duration_in_ms=5.175
2017-11-30 11:13:14,569 [salt.state       ][INFO    ][29061] Running state [bc] at time 11:13:14.568945
2017-11-30 11:13:14,569 [salt.state       ][INFO    ][29061] Executing state pkg.installed for bc
2017-11-30 11:13:14,574 [salt.state       ][INFO    ][29061] Package bc is already installed
2017-11-30 11:13:14,574 [salt.state       ][INFO    ][29061] Completed state [bc] at time 11:13:14.573673 duration_in_ms=4.728
2017-11-30 11:13:14,575 [salt.state       ][INFO    ][29061] Running state [glusterfs-server] at time 11:13:14.574489
2017-11-30 11:13:14,575 [salt.state       ][INFO    ][29061] Executing state service.running for glusterfs-server
2017-11-30 11:13:14,575 [salt.loaded.int.module.cmdmod][INFO    ][29061] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 11:13:14,603 [salt.loaded.int.module.cmdmod][INFO    ][29061] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:14,618 [salt.loaded.int.module.cmdmod][INFO    ][29061] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:14,637 [salt.state       ][INFO    ][29061] The service glusterfs-server is already running
2017-11-30 11:13:14,638 [salt.state       ][INFO    ][29061] Completed state [glusterfs-server] at time 11:13:14.637782 duration_in_ms=63.292
2017-11-30 11:13:14,641 [salt.state       ][INFO    ][29061] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:14.641083
2017-11-30 11:13:14,642 [salt.state       ][INFO    ][29061] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 11:13:14,657 [salt.state       ][INFO    ][29061] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-30 11:13:14,657 [salt.state       ][INFO    ][29061] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:14.657290 duration_in_ms=16.207
2017-11-30 11:13:14,658 [salt.state       ][INFO    ][29061] Running state [/srv/glusterfs/glance] at time 11:13:14.657650
2017-11-30 11:13:14,658 [salt.state       ][INFO    ][29061] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 11:13:14,659 [salt.state       ][INFO    ][29061] Directory /srv/glusterfs/glance is in the correct state
2017-11-30 11:13:14,659 [salt.state       ][INFO    ][29061] Completed state [/srv/glusterfs/glance] at time 11:13:14.659323 duration_in_ms=1.673
2017-11-30 11:13:14,660 [salt.state       ][INFO    ][29061] Running state [/srv/glusterfs/keystone-keys] at time 11:13:14.659769
2017-11-30 11:13:14,660 [salt.state       ][INFO    ][29061] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 11:13:14,661 [salt.state       ][INFO    ][29061] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-30 11:13:14,661 [salt.state       ][INFO    ][29061] Completed state [/srv/glusterfs/keystone-keys] at time 11:13:14.661304 duration_in_ms=1.534
2017-11-30 11:13:14,662 [salt.state       ][INFO    ][29061] Running state [/srv/glusterfs/nova_instances] at time 11:13:14.661655
2017-11-30 11:13:14,662 [salt.state       ][INFO    ][29061] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 11:13:14,663 [salt.state       ][INFO    ][29061] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-30 11:13:14,663 [salt.state       ][INFO    ][29061] Completed state [/srv/glusterfs/nova_instances] at time 11:13:14.663137 duration_in_ms=1.482
2017-11-30 11:13:14,664 [salt.state       ][INFO    ][29061] Running state [/srv/glusterfs/keystone-credential-keys] at time 11:13:14.663511
2017-11-30 11:13:14,664 [salt.state       ][INFO    ][29061] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 11:13:14,665 [salt.state       ][INFO    ][29061] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-30 11:13:14,665 [salt.state       ][INFO    ][29061] Completed state [/srv/glusterfs/keystone-credential-keys] at time 11:13:14.664959 duration_in_ms=1.448
2017-11-30 11:13:14,666 [salt.minion      ][INFO    ][29061] Returning information for job: 20171130111311066848
2017-11-30 11:13:15,799 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command test.ping with jid 20171130111315788609
2017-11-30 11:13:15,822 [salt.minion      ][INFO    ][29084] Starting a new job with PID 29084
2017-11-30 11:13:15,881 [salt.minion      ][INFO    ][29084] Returning information for job: 20171130111315788609
2017-11-30 11:13:31,412 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command state.sls with jid 20171130111331400712
2017-11-30 11:13:31,436 [salt.minion      ][INFO    ][29089] Starting a new job with PID 29089
2017-11-30 11:13:31,922 [salt.state       ][INFO    ][29089] Loading fresh modules for state activity
2017-11-30 11:13:31,963 [salt.fileclient  ][INFO    ][29089] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-30 11:13:32,022 [salt.fileclient  ][INFO    ][29089] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 11:13:32,137 [salt.fileclient  ][INFO    ][29089] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-30 11:13:32,170 [salt.fileclient  ][INFO    ][29089] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-30 11:13:32,611 [salt.state       ][INFO    ][29089] Running state [attr] at time 11:13:32.610537
2017-11-30 11:13:32,611 [salt.state       ][INFO    ][29089] Executing state pkg.installed for attr
2017-11-30 11:13:32,611 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-30 11:13:33,077 [salt.state       ][INFO    ][29089] Package attr is already installed
2017-11-30 11:13:33,077 [salt.state       ][INFO    ][29089] Completed state [attr] at time 11:13:33.077308 duration_in_ms=466.771
2017-11-30 11:13:33,078 [salt.state       ][INFO    ][29089] Running state [glusterfs-server] at time 11:13:33.077826
2017-11-30 11:13:33,078 [salt.state       ][INFO    ][29089] Executing state pkg.installed for glusterfs-server
2017-11-30 11:13:33,082 [salt.state       ][INFO    ][29089] Package glusterfs-server is already installed
2017-11-30 11:13:33,083 [salt.state       ][INFO    ][29089] Completed state [glusterfs-server] at time 11:13:33.082631 duration_in_ms=4.805
2017-11-30 11:13:33,083 [salt.state       ][INFO    ][29089] Running state [bc] at time 11:13:33.082875
2017-11-30 11:13:33,083 [salt.state       ][INFO    ][29089] Executing state pkg.installed for bc
2017-11-30 11:13:33,087 [salt.state       ][INFO    ][29089] Package bc is already installed
2017-11-30 11:13:33,087 [salt.state       ][INFO    ][29089] Completed state [bc] at time 11:13:33.087254 duration_in_ms=4.379
2017-11-30 11:13:33,089 [salt.state       ][INFO    ][29089] Running state [glusterfs-server] at time 11:13:33.088969
2017-11-30 11:13:33,089 [salt.state       ][INFO    ][29089] Executing state service.running for glusterfs-server
2017-11-30 11:13:33,090 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-30 11:13:33,116 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:33,131 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-30 11:13:33,149 [salt.state       ][INFO    ][29089] The service glusterfs-server is already running
2017-11-30 11:13:33,149 [salt.state       ][INFO    ][29089] Completed state [glusterfs-server] at time 11:13:33.149046 duration_in_ms=60.076
2017-11-30 11:13:33,154 [salt.state       ][INFO    ][29089] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:33.153929
2017-11-30 11:13:33,154 [salt.state       ][INFO    ][29089] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-30 11:13:33,170 [salt.state       ][INFO    ][29089] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-30 11:13:33,170 [salt.state       ][INFO    ][29089] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 11:13:33.170078 duration_in_ms=16.148
2017-11-30 11:13:33,171 [salt.state       ][INFO    ][29089] Running state [/srv/glusterfs/glance] at time 11:13:33.170454
2017-11-30 11:13:33,171 [salt.state       ][INFO    ][29089] Executing state file.directory for /srv/glusterfs/glance
2017-11-30 11:13:33,172 [salt.state       ][INFO    ][29089] Directory /srv/glusterfs/glance is in the correct state
2017-11-30 11:13:33,172 [salt.state       ][INFO    ][29089] Completed state [/srv/glusterfs/glance] at time 11:13:33.172044 duration_in_ms=1.589
2017-11-30 11:13:33,172 [salt.state       ][INFO    ][29089] Running state [/srv/glusterfs/keystone-keys] at time 11:13:33.172401
2017-11-30 11:13:33,173 [salt.state       ][INFO    ][29089] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-30 11:13:33,174 [salt.state       ][INFO    ][29089] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-30 11:13:33,174 [salt.state       ][INFO    ][29089] Completed state [/srv/glusterfs/keystone-keys] at time 11:13:33.173886 duration_in_ms=1.484
2017-11-30 11:13:33,174 [salt.state       ][INFO    ][29089] Running state [/srv/glusterfs/nova_instances] at time 11:13:33.174256
2017-11-30 11:13:33,175 [salt.state       ][INFO    ][29089] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-30 11:13:33,176 [salt.state       ][INFO    ][29089] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-30 11:13:33,176 [salt.state       ][INFO    ][29089] Completed state [/srv/glusterfs/nova_instances] at time 11:13:33.175769 duration_in_ms=1.513
2017-11-30 11:13:33,176 [salt.state       ][INFO    ][29089] Running state [/srv/glusterfs/keystone-credential-keys] at time 11:13:33.176124
2017-11-30 11:13:33,176 [salt.state       ][INFO    ][29089] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-30 11:13:33,177 [salt.state       ][INFO    ][29089] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-30 11:13:33,178 [salt.state       ][INFO    ][29089] Completed state [/srv/glusterfs/keystone-credential-keys] at time 11:13:33.177600 duration_in_ms=1.477
2017-11-30 11:13:33,330 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command ['git', '--version'] in directory '/root'
2017-11-30 11:13:33,387 [salt.state       ][INFO    ][29089] Running state [sleep 5] at time 11:13:33.386792
2017-11-30 11:13:33,387 [salt.state       ][INFO    ][29089] Executing state cmd.wait for sleep 5
2017-11-30 11:13:33,387 [salt.state       ][INFO    ][29089] No changes made for sleep 5
2017-11-30 11:13:33,387 [salt.state       ][INFO    ][29089] Completed state [sleep 5] at time 11:13:33.387456 duration_in_ms=0.665
2017-11-30 11:13:33,388 [salt.state       ][INFO    ][29089] Running state [10.167.4.141] at time 11:13:33.387908
2017-11-30 11:13:33,388 [salt.state       ][INFO    ][29089] Executing state glusterfs.peered for 10.167.4.141
2017-11-30 11:13:33,388 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:33,405 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:33,489 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:33,507 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:33,604 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:33,621 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:33,727 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:33,742 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:33,855 [salt.state       ][INFO    ][29089] Peering with localhost is not needed
2017-11-30 11:13:33,856 [salt.state       ][INFO    ][29089] Completed state [10.167.4.141] at time 11:13:33.855731 duration_in_ms=467.82
2017-11-30 11:13:33,862 [salt.state       ][INFO    ][29089] Running state [10.167.4.142] at time 11:13:33.861526
2017-11-30 11:13:33,862 [salt.state       ][INFO    ][29089] Executing state glusterfs.peered for 10.167.4.142
2017-11-30 11:13:33,863 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:33,880 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:33,982 [salt.state       ][INFO    ][29089] Host 10.167.4.142 already peered
2017-11-30 11:13:33,983 [salt.state       ][INFO    ][29089] Completed state [10.167.4.142] at time 11:13:33.982786 duration_in_ms=121.26
2017-11-30 11:13:33,989 [salt.state       ][INFO    ][29089] Running state [10.167.4.143] at time 11:13:33.988552
2017-11-30 11:13:33,989 [salt.state       ][INFO    ][29089] Executing state glusterfs.peered for 10.167.4.143
2017-11-30 11:13:33,990 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:34,009 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:34,113 [salt.state       ][INFO    ][29089] Host 10.167.4.143 already peered
2017-11-30 11:13:34,114 [salt.state       ][INFO    ][29089] Completed state [10.167.4.143] at time 11:13:34.113483 duration_in_ms=124.93
2017-11-30 11:13:34,115 [salt.state       ][INFO    ][29089] Running state [glance] at time 11:13:34.115412
2017-11-30 11:13:34,116 [salt.state       ][INFO    ][29089] Executing state glusterfs.created for glance
2017-11-30 11:13:34,117 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:34,136 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:34,250 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:34,268 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:34,371 [salt.state       ][INFO    ][29089] Volume glance already exists and is started.
2017-11-30 11:13:34,372 [salt.state       ][INFO    ][29089] Completed state [glance] at time 11:13:34.371677 duration_in_ms=256.264
2017-11-30 11:13:34,381 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:34.380673
2017-11-30 11:13:34,381 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:34,382 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:34,490 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:34,490 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:34.490406 duration_in_ms=109.734
2017-11-30 11:13:34,492 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 11:13:34.491600
2017-11-30 11:13:34,492 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-30 11:13:34,493 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:34,602 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:34,603 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 11:13:34.602919 duration_in_ms=111.318
2017-11-30 11:13:34,604 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 11:13:34.604125
2017-11-30 11:13:34,605 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-30 11:13:34,606 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:34,710 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:34,710 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 11:13:34.710128 duration_in_ms=106.001
2017-11-30 11:13:34,711 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:34.711225
2017-11-30 11:13:34,712 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:34,713 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:34,816 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:34,817 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:34.816445 duration_in_ms=105.219
2017-11-30 11:13:34,818 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:34.817629
2017-11-30 11:13:34,818 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:34,819 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:34,930 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:34,931 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:34.930691 duration_in_ms=113.061
2017-11-30 11:13:34,932 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 11:13:34.931999
2017-11-30 11:13:34,933 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:34,934 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:35,046 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:35,047 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 11:13:35.046463 duration_in_ms=114.464
2017-11-30 11:13:35,050 [salt.state       ][INFO    ][29089] Running state [glance] at time 11:13:35.049615
2017-11-30 11:13:35,050 [salt.state       ][INFO    ][29089] Executing state glusterfs.started for glance
2017-11-30 11:13:35,051 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:35,070 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:35,179 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:35,198 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:35,314 [salt.state       ][INFO    ][29089] Volume glance is already started
2017-11-30 11:13:35,315 [salt.state       ][INFO    ][29089] Completed state [glance] at time 11:13:35.314433 duration_in_ms=264.817
2017-11-30 11:13:35,316 [salt.state       ][INFO    ][29089] Running state [keystone-keys] at time 11:13:35.316236
2017-11-30 11:13:35,317 [salt.state       ][INFO    ][29089] Executing state glusterfs.created for keystone-keys
2017-11-30 11:13:35,318 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:35,332 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:35,440 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:35,457 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:35,579 [salt.state       ][INFO    ][29089] Volume keystone-keys already exists and is started.
2017-11-30 11:13:35,580 [salt.state       ][INFO    ][29089] Completed state [keystone-keys] at time 11:13:35.579505 duration_in_ms=263.268
2017-11-30 11:13:35,588 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 11:13:35.588233
2017-11-30 11:13:35,589 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-30 11:13:35,590 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:35,702 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:35,703 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 11:13:35.702439 duration_in_ms=114.205
2017-11-30 11:13:35,704 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:35.703581
2017-11-30 11:13:35,704 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:35,705 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:35,813 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:35,814 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:35.813982 duration_in_ms=110.399
2017-11-30 11:13:35,815 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:35.815139
2017-11-30 11:13:35,816 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:35,817 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:35,928 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:35,928 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:35.928296 duration_in_ms=113.156
2017-11-30 11:13:35,929 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:35.929387
2017-11-30 11:13:35,930 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:35,931 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:36,043 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:36,044 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:36.043590 duration_in_ms=114.202
2017-11-30 11:13:36,045 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 11:13:36.044742
2017-11-30 11:13:36,045 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-30 11:13:36,046 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:36,164 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:36,164 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 11:13:36.163888 duration_in_ms=119.145
2017-11-30 11:13:36,165 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:36.164697
2017-11-30 11:13:36,165 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:36,166 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:36,280 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:36,280 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:36.280003 duration_in_ms=115.305
2017-11-30 11:13:36,283 [salt.state       ][INFO    ][29089] Running state [keystone-keys] at time 11:13:36.282659
2017-11-30 11:13:36,283 [salt.state       ][INFO    ][29089] Executing state glusterfs.started for keystone-keys
2017-11-30 11:13:36,284 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:36,299 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:36,410 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:36,428 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:36,539 [salt.state       ][INFO    ][29089] Volume keystone-keys is already started
2017-11-30 11:13:36,540 [salt.state       ][INFO    ][29089] Completed state [keystone-keys] at time 11:13:36.539436 duration_in_ms=256.776
2017-11-30 11:13:36,542 [salt.state       ][INFO    ][29089] Running state [nova_instances] at time 11:13:36.541687
2017-11-30 11:13:36,542 [salt.state       ][INFO    ][29089] Executing state glusterfs.created for nova_instances
2017-11-30 11:13:36,543 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:36,559 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:36,666 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:36,681 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:36,793 [salt.state       ][INFO    ][29089] Volume nova_instances already exists and is started.
2017-11-30 11:13:36,794 [salt.state       ][INFO    ][29089] Completed state [nova_instances] at time 11:13:36.793965 duration_in_ms=252.275
2017-11-30 11:13:36,804 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:36.803510
2017-11-30 11:13:36,804 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:36,805 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:36,905 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:36,906 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:36.905583 duration_in_ms=102.073
2017-11-30 11:13:36,907 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 11:13:36.906661
2017-11-30 11:13:36,907 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:36,908 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:37,017 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:37,017 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 11:13:37.017331 duration_in_ms=110.669
2017-11-30 11:13:37,019 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 11:13:37.018539
2017-11-30 11:13:37,019 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-11-30 11:13:37,020 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:37,133 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:37,141 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 11:13:37.140432 duration_in_ms=121.892
2017-11-30 11:13:37,142 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:37.141712
2017-11-30 11:13:37,142 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:37,143 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:37,258 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:37,258 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:37.258268 duration_in_ms=116.556
2017-11-30 11:13:37,259 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 11:13:37.259162
2017-11-30 11:13:37,260 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-11-30 11:13:37,260 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:37,375 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:37,376 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 11:13:37.375797 duration_in_ms=116.633
2017-11-30 11:13:37,377 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:37.377036
2017-11-30 11:13:37,378 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:37,379 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:37,488 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:37,488 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:37.488120 duration_in_ms=111.083
2017-11-30 11:13:37,491 [salt.state       ][INFO    ][29089] Running state [nova_instances] at time 11:13:37.491252
2017-11-30 11:13:37,492 [salt.state       ][INFO    ][29089] Executing state glusterfs.started for nova_instances
2017-11-30 11:13:37,493 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:37,508 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:37,605 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:37,619 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:37,740 [salt.state       ][INFO    ][29089] Volume nova_instances is already started
2017-11-30 11:13:37,740 [salt.state       ][INFO    ][29089] Completed state [nova_instances] at time 11:13:37.740346 duration_in_ms=249.093
2017-11-30 11:13:37,743 [salt.state       ][INFO    ][29089] Running state [keystone-credential-keys] at time 11:13:37.742611
2017-11-30 11:13:37,743 [salt.state       ][INFO    ][29089] Executing state glusterfs.created for keystone-credential-keys
2017-11-30 11:13:37,744 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:37,761 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:37,871 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:37,885 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:38,003 [salt.state       ][INFO    ][29089] Volume keystone-credential-keys already exists and is started.
2017-11-30 11:13:38,004 [salt.state       ][INFO    ][29089] Completed state [keystone-credential-keys] at time 11:13:38.003933 duration_in_ms=261.321
2017-11-30 11:13:38,013 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:38.013147
2017-11-30 11:13:38,014 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-30 11:13:38,015 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-30 11:13:38,123 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:38,124 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 11:13:38.123663 duration_in_ms=110.515
2017-11-30 11:13:38,125 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:38.124835
2017-11-30 11:13:38,125 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-30 11:13:38,126 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-30 11:13:38,238 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:38,239 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 11:13:38.238942 duration_in_ms=114.106
2017-11-30 11:13:38,240 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:38.240159
2017-11-30 11:13:38,241 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-30 11:13:38,242 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:38,335 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:38,336 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 11:13:38.335920 duration_in_ms=95.761
2017-11-30 11:13:38,337 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 11:13:38.337012
2017-11-30 11:13:38,338 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-30 11:13:38,338 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-30 11:13:38,450 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:38,451 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 11:13:38.450945 duration_in_ms=113.932
2017-11-30 11:13:38,452 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:38.452216
2017-11-30 11:13:38,453 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-30 11:13:38,454 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-30 11:13:38,570 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:38,570 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 11:13:38.570344 duration_in_ms=118.127
2017-11-30 11:13:38,572 [salt.state       ][INFO    ][29089] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 11:13:38.571572
2017-11-30 11:13:38,572 [salt.state       ][INFO    ][29089] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-30 11:13:38,573 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-30 11:13:38,684 [salt.state       ][INFO    ][29089] unless execution succeeded
2017-11-30 11:13:38,685 [salt.state       ][INFO    ][29089] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 11:13:38.684548 duration_in_ms=112.976
2017-11-30 11:13:38,688 [salt.state       ][INFO    ][29089] Running state [keystone-credential-keys] at time 11:13:38.687518
2017-11-30 11:13:38,688 [salt.state       ][INFO    ][29089] Executing state glusterfs.started for keystone-credential-keys
2017-11-30 11:13:38,689 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:38,707 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:38,807 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --version' in directory '/root'
2017-11-30 11:13:38,825 [salt.loaded.int.module.cmdmod][INFO    ][29089] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-30 11:13:38,947 [salt.state       ][INFO    ][29089] Volume keystone-credential-keys is already started
2017-11-30 11:13:38,948 [salt.state       ][INFO    ][29089] Completed state [keystone-credential-keys] at time 11:13:38.947811 duration_in_ms=260.292
2017-11-30 11:13:38,952 [salt.minion      ][INFO    ][29089] Returning information for job: 20171130111331400712
2017-11-30 11:13:40,090 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command test.ping with jid 20171130111340078877
2017-11-30 11:13:40,113 [salt.minion      ][INFO    ][29600] Starting a new job with PID 29600
2017-11-30 11:13:40,167 [salt.minion      ][INFO    ][29600] Returning information for job: 20171130111340078877
2017-11-30 11:13:41,063 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command cmd.run with jid 20171130111341052630
2017-11-30 11:13:41,086 [salt.minion      ][INFO    ][29605] Starting a new job with PID 29605
2017-11-30 11:13:41,096 [salt.loaded.int.module.cmdmod][INFO    ][29605] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-30 11:13:41,322 [salt.minion      ][INFO    ][29605] Returning information for job: 20171130111341052630
2017-11-30 11:29:54,121 [salt.utils.schedule][INFO    ][21624] Running scheduled job: __mine_interval
2017-11-30 11:52:13,907 [salt.minion      ][INFO    ][21624] User sudo_ubuntu Executing command cp.push_dir with jid 20171130115213896513
2017-11-30 11:52:13,933 [salt.minion      ][INFO    ][29904] Starting a new job with PID 29904
