2017-09-14 00:18:19,703 [salt.cli.daemons ][WARNING ][2475] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-14 00:59:28,372 [salt.state       ][ERROR   ][3808] No contents found in top file
2017-09-14 00:59:46,353 [salt.utils.parsers][WARNING ][2475] Minion received a SIGTERM. Exiting.
2017-09-14 01:02:10,841 [salt.cli.daemons ][WARNING ][3234] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-14 01:05:07,588 [py.warnings      ][WARNING ][15295] /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-09-14 01:06:52,871 [salt.loaded.int.module.cmdmod][ERROR   ][15295] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-14 01:06:52,871 [salt.loaded.int.module.cmdmod][ERROR   ][15295] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-14 01:07:00,370 [salt.loaded.int.module.cmdmod][ERROR   ][15295] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-14 01:07:00,371 [salt.loaded.int.module.cmdmod][ERROR   ][15295] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-14 01:07:09,664 [salt.loaded.int.module.cmdmod][ERROR   ][15295] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-14 01:07:09,665 [salt.loaded.int.module.cmdmod][ERROR   ][15295] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-14 01:07:21,322 [salt.loaded.int.module.cmdmod][ERROR   ][15295] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-14 01:07:21,323 [salt.loaded.int.module.cmdmod][ERROR   ][15295] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-14 01:07:31,157 [salt.loaded.int.module.cmdmod][ERROR   ][15295] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-14 01:07:31,157 [salt.loaded.int.module.cmdmod][ERROR   ][15295] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-14 01:07:38,341 [salt.loaded.int.module.cmdmod][ERROR   ][15295] 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-09-14 01:07:41,700 [salt.state       ][ERROR   ][22859] No contents found in top file
2017-09-14 01:07:51,221 [py.warnings      ][WARNING ][23296] /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-09-14 01:07:57,849 [salt.loaded.int.module.cmdmod][INFO    ][23640] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-14 01:07:57,863 [salt.loaded.int.module.cmdmod][INFO    ][23640] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-14 01:07:57,881 [salt.loaded.int.module.cmdmod][INFO    ][23640] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-09-14 01:07:57,947 [salt.utils.parsers][WARNING ][3234] Minion received a SIGTERM. Exiting.
2017-09-14 01:07:58,420 [salt.cli.daemons ][INFO    ][23723] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-ovs-ha.local"
2017-09-14 01:07:59,186 [salt.minion      ][INFO    ][23723] Creating minion process manager
2017-09-14 01:07:59,186 [salt.cli.daemons ][WARNING ][23723] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-14 01:07:59,186 [salt.cli.daemons ][INFO    ][23723] The Salt Minion is starting up
2017-09-14 01:07:59,187 [salt.minion      ][INFO    ][23723] Minion is starting as user 'root'
2017-09-14 01:07:59,187 [salt.utils.event ][INFO    ][23723] Starting pull socket on /var/run/salt/minion/minion_event_a1b2f32648_pull.ipc
2017-09-14 01:08:00,283 [salt.loaded.int.module.cmdmod][INFO    ][23723] Executing command ['date', '+%z'] in directory '/root'
2017-09-14 01:08:00,304 [salt.utils.schedule][INFO    ][23723] Updating job settings for scheduled job: __mine_interval
2017-09-14 01:08:00,337 [salt.minion      ][INFO    ][23723] Added mine.update to scheduler
2017-09-14 01:08:00,504 [salt.minion      ][INFO    ][23723] Minion is ready to receive requests!
2017-09-14 01:08:01,506 [salt.utils.schedule][INFO    ][23723] Running scheduled job: __mine_interval
2017-09-14 01:10:40,704 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170914011040696760
2017-09-14 01:10:40,726 [salt.minion      ][INFO    ][23846] Starting a new job with PID 23846
2017-09-14 01:10:44,540 [salt.state       ][INFO    ][23846] Loading fresh modules for state activity
2017-09-14 01:10:44,584 [salt.state       ][ERROR   ][23846] No contents found in top file
2017-09-14 01:10:44,585 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-14 01:10:44,591 [salt.utils.extmods][INFO    ][23846] Syncing beacons for environment 'base'
2017-09-14 01:10:44,592 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_beacons, for base)
2017-09-14 01:10:44,592 [salt.fileclient  ][INFO    ][23846] Caching directory '_beacons/' for environment 'base'
2017-09-14 01:10:44,640 [salt.utils.extmods][INFO    ][23846] Syncing modules for environment 'base'
2017-09-14 01:10:44,640 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_modules, for base)
2017-09-14 01:10:44,641 [salt.fileclient  ][INFO    ][23846] Caching directory '_modules/' for environment 'base'
2017-09-14 01:10:44,726 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-14 01:10:44,737 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-14 01:10:44,977 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-14 01:10:45,180 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-14 01:10:45,197 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-14 01:10:45,250 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-14 01:10:45,269 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-14 01:10:45,288 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-14 01:10:45,328 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-14 01:10:45,340 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-14 01:10:45,361 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-14 01:10:45,392 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-14 01:10:45,447 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-14 01:10:45,474 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-14 01:10:45,498 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-14 01:10:45,595 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-14 01:10:45,700 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-14 01:10:45,726 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-14 01:10:45,750 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-14 01:10:45,829 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-14 01:10:45,880 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-14 01:10:45,942 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-14 01:10:46,065 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-14 01:10:46,080 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-14 01:10:46,092 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-14 01:10:46,106 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-14 01:10:46,164 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-14 01:10:46,291 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-14 01:10:46,313 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-14 01:10:46,322 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-14 01:10:46,391 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-14 01:10:46,424 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-14 01:10:46,428 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-14 01:10:46,429 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-14 01:10:46,429 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-14 01:10:46,429 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-14 01:10:46,430 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-14 01:10:46,430 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-14 01:10:46,430 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-14 01:10:46,431 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-14 01:10:46,431 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-14 01:10:46,431 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-14 01:10:46,432 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-14 01:10:46,432 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-14 01:10:46,432 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-14 01:10:46,433 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-14 01:10:46,433 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-14 01:10:46,433 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-14 01:10:46,434 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-14 01:10:46,434 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-14 01:10:46,434 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-14 01:10:46,435 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-14 01:10:46,435 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-14 01:10:46,435 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-14 01:10:46,436 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-14 01:10:46,436 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-14 01:10:46,436 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-14 01:10:46,437 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-14 01:10:46,437 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-14 01:10:46,437 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-14 01:10:46,438 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-14 01:10:46,438 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-14 01:10:46,438 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-14 01:10:46,439 [salt.utils.extmods][INFO    ][23846] 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-09-14 01:10:46,439 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-14 01:10:46,439 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-14 01:10:46,445 [salt.utils.extmods][INFO    ][23846] Syncing states for environment 'base'
2017-09-14 01:10:46,445 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_states, for base)
2017-09-14 01:10:46,445 [salt.fileclient  ][INFO    ][23846] Caching directory '_states/' for environment 'base'
2017-09-14 01:10:46,524 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-14 01:10:46,534 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-14 01:10:46,566 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-14 01:10:46,601 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-14 01:10:46,620 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-14 01:10:46,647 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-14 01:10:46,666 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-14 01:10:46,752 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-14 01:10:46,774 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-14 01:10:46,977 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-14 01:10:47,010 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-14 01:10:47,041 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-14 01:10:47,072 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-14 01:10:47,111 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-14 01:10:47,178 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-14 01:10:47,190 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-14 01:10:47,221 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-14 01:10:47,250 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-14 01:10:47,320 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-14 01:10:47,331 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-14 01:10:47,348 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-14 01:10:47,429 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-14 01:10:47,453 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-14 01:10:47,577 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-14 01:10:47,598 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-14 01:10:47,645 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-14 01:10:47,658 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-14 01:10:47,670 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-14 01:10:47,687 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-14 01:10:47,695 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-14 01:10:47,695 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-14 01:10:47,696 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-14 01:10:47,696 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-14 01:10:47,697 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-14 01:10:47,698 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-14 01:10:47,698 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-14 01:10:47,698 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-14 01:10:47,699 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-14 01:10:47,699 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-14 01:10:47,700 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-14 01:10:47,700 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-14 01:10:47,701 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-14 01:10:47,701 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-14 01:10:47,702 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-14 01:10:47,702 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-14 01:10:47,725 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-14 01:10:47,726 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-14 01:10:47,726 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-14 01:10:47,727 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-14 01:10:47,727 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-14 01:10:47,728 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-14 01:10:47,728 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-14 01:10:47,729 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-14 01:10:47,729 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-14 01:10:47,730 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-14 01:10:47,731 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-14 01:10:47,731 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-14 01:10:47,732 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-14 01:10:47,732 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-14 01:10:47,737 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-14 01:10:47,742 [salt.utils.extmods][INFO    ][23846] Syncing sdb for environment 'base'
2017-09-14 01:10:47,743 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_sdb, for base)
2017-09-14 01:10:47,743 [salt.fileclient  ][INFO    ][23846] Caching directory '_sdb/' for environment 'base'
2017-09-14 01:10:47,830 [salt.utils.extmods][INFO    ][23846] Syncing grains for environment 'base'
2017-09-14 01:10:47,830 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_grains, for base)
2017-09-14 01:10:47,831 [salt.fileclient  ][INFO    ][23846] Caching directory '_grains/' for environment 'base'
2017-09-14 01:10:47,931 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-14 01:10:47,948 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-14 01:10:47,971 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-14 01:10:49,141 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-14 01:10:49,152 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-14 01:10:49,605 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-14 01:10:49,615 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-14 01:10:49,626 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-14 01:10:49,637 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-14 01:10:49,650 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-14 01:10:49,659 [salt.fileclient  ][INFO    ][23846] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-14 01:10:49,663 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-14 01:10:49,663 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-14 01:10:49,664 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-14 01:10:49,664 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-14 01:10:49,664 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-14 01:10:49,665 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-14 01:10:49,665 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-14 01:10:49,665 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-14 01:10:49,666 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-14 01:10:49,666 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-14 01:10:49,666 [salt.utils.extmods][INFO    ][23846] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-14 01:10:49,669 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-14 01:10:49,674 [salt.utils.extmods][INFO    ][23846] Syncing renderers for environment 'base'
2017-09-14 01:10:49,675 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_renderers, for base)
2017-09-14 01:10:49,675 [salt.fileclient  ][INFO    ][23846] Caching directory '_renderers/' for environment 'base'
2017-09-14 01:10:49,702 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-14 01:10:49,706 [salt.utils.extmods][INFO    ][23846] Syncing returners for environment 'base'
2017-09-14 01:10:49,707 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_returners, for base)
2017-09-14 01:10:49,707 [salt.fileclient  ][INFO    ][23846] Caching directory '_returners/' for environment 'base'
2017-09-14 01:10:49,760 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-14 01:10:49,765 [salt.utils.extmods][INFO    ][23846] Syncing output for environment 'base'
2017-09-14 01:10:49,765 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_output, for base)
2017-09-14 01:10:49,765 [salt.fileclient  ][INFO    ][23846] Caching directory '_output/' for environment 'base'
2017-09-14 01:10:49,809 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-14 01:10:49,814 [salt.utils.extmods][INFO    ][23846] Syncing utils for environment 'base'
2017-09-14 01:10:49,815 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_utils, for base)
2017-09-14 01:10:49,815 [salt.fileclient  ][INFO    ][23846] Caching directory '_utils/' for environment 'base'
2017-09-14 01:10:49,853 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-14 01:10:49,858 [salt.utils.extmods][INFO    ][23846] Syncing log_handlers for environment 'base'
2017-09-14 01:10:49,859 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_log_handlers, for base)
2017-09-14 01:10:49,859 [salt.fileclient  ][INFO    ][23846] Caching directory '_log_handlers/' for environment 'base'
2017-09-14 01:10:49,892 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-14 01:10:49,896 [salt.utils.extmods][INFO    ][23846] Syncing proxy for environment 'base'
2017-09-14 01:10:49,896 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_proxy, for base)
2017-09-14 01:10:49,896 [salt.fileclient  ][INFO    ][23846] Caching directory '_proxy/' for environment 'base'
2017-09-14 01:10:49,920 [salt.utils.extmods][INFO    ][23846] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-14 01:10:49,923 [salt.utils.extmods][INFO    ][23846] Syncing engines for environment 'base'
2017-09-14 01:10:49,923 [salt.utils.extmods][INFO    ][23846] Loading cache from salt://_engines, for base)
2017-09-14 01:10:49,923 [salt.fileclient  ][INFO    ][23846] Caching directory '_engines/' for environment 'base'
2017-09-14 01:10:49,965 [salt.minion      ][INFO    ][23846] Returning information for job: 20170914011040696760
2017-09-14 01:16:45,921 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command test.ping with jid 20170914011645900577
2017-09-14 01:16:45,943 [salt.minion      ][INFO    ][24019] Starting a new job with PID 24019
2017-09-14 01:16:46,011 [salt.minion      ][INFO    ][24019] Returning information for job: 20170914011645900577
2017-09-14 01:20:09,548 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command state.sls with jid 20170914012009537619
2017-09-14 01:20:09,568 [salt.minion      ][INFO    ][24083] Starting a new job with PID 24083
2017-09-14 01:20:10,062 [salt.state       ][INFO    ][24083] Loading fresh modules for state activity
2017-09-14 01:20:10,091 [salt.fileclient  ][INFO    ][24083] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-09-14 01:20:10,110 [salt.fileclient  ][INFO    ][24083] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-09-14 01:20:10,136 [salt.fileclient  ][INFO    ][24083] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-14 01:20:10,445 [salt.state       ][INFO    ][24083] Running state [lsof] at time 01:20:10.445052
2017-09-14 01:20:10,445 [salt.state       ][INFO    ][24083] Executing state pkg.installed for lsof
2017-09-14 01:20:10,446 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 01:20:10,780 [salt.state       ][INFO    ][24083] Package lsof is already installed
2017-09-14 01:20:10,780 [salt.state       ][INFO    ][24083] Completed state [lsof] at time 01:20:10.779971 duration_in_ms=334.919
2017-09-14 01:20:10,780 [salt.state       ][INFO    ][24083] Running state [keepalived] at time 01:20:10.780278
2017-09-14 01:20:10,781 [salt.state       ][INFO    ][24083] Executing state pkg.installed for keepalived
2017-09-14 01:20:10,800 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 01:20:13,140 [salt.loaded.int.module.cmdmod][INFO    ][24083] 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-09-14 01:20:19,589 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012019578751
2017-09-14 01:20:19,612 [salt.minion      ][INFO    ][25364] Starting a new job with PID 25364
2017-09-14 01:20:19,638 [salt.minion      ][INFO    ][25364] Returning information for job: 20170914012019578751
2017-09-14 01:20:22,627 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 01:20:22,679 [salt.state       ][INFO    ][24083] 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-09-14 01:20:22,696 [salt.state       ][INFO    ][24083] Loading fresh modules for state activity
2017-09-14 01:20:22,720 [salt.state       ][INFO    ][24083] Completed state [keepalived] at time 01:20:22.719515 duration_in_ms=11939.236
2017-09-14 01:20:22,723 [salt.state       ][INFO    ][24083] Running state [/etc/keepalived/keepalived.conf] at time 01:20:22.723181
2017-09-14 01:20:22,724 [salt.state       ][INFO    ][24083] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-09-14 01:20:22,749 [salt.fileclient  ][INFO    ][24083] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-09-14 01:20:22,803 [salt.fileclient  ][INFO    ][24083] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-14 01:20:22,819 [salt.state       ][INFO    ][24083] File changed:
New file
2017-09-14 01:20:22,819 [salt.state       ][INFO    ][24083] Completed state [/etc/keepalived/keepalived.conf] at time 01:20:22.819197 duration_in_ms=96.014
2017-09-14 01:20:22,931 [salt.state       ][INFO    ][24083] Running state [keepalived] at time 01:20:22.931250
2017-09-14 01:20:22,932 [salt.state       ][INFO    ][24083] Executing state service.running for keepalived
2017-09-14 01:20:22,933 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-09-14 01:20:22,951 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-14 01:20:22,963 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 01:20:22,976 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 01:20:22,990 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-09-14 01:20:23,091 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-14 01:20:23,106 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 01:20:23,117 [salt.loaded.int.module.cmdmod][INFO    ][24083] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-14 01:20:23,127 [salt.state       ][INFO    ][24083] {'keepalived': True}
2017-09-14 01:20:23,128 [salt.state       ][INFO    ][24083] Completed state [keepalived] at time 01:20:23.127576 duration_in_ms=196.326
2017-09-14 01:20:23,129 [salt.minion      ][INFO    ][24083] Returning information for job: 20170914012009537619
2017-09-14 01:21:06,938 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command pillar.get with jid 20170914012106932850
2017-09-14 01:21:06,967 [salt.minion      ][INFO    ][25656] Starting a new job with PID 25656
2017-09-14 01:21:06,977 [salt.minion      ][INFO    ][25656] Returning information for job: 20170914012106932850
2017-09-14 01:26:40,120 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command state.sls with jid 20170914012640109929
2017-09-14 01:26:40,143 [salt.minion      ][INFO    ][25756] Starting a new job with PID 25756
2017-09-14 01:26:42,606 [salt.state       ][INFO    ][25756] Loading fresh modules for state activity
2017-09-14 01:26:42,641 [salt.fileclient  ][INFO    ][25756] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-14 01:26:42,671 [salt.fileclient  ][INFO    ][25756] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-14 01:26:43,034 [salt.state       ][INFO    ][25756] Running state [attr] at time 01:26:43.034324
2017-09-14 01:26:43,035 [salt.state       ][INFO    ][25756] Executing state pkg.installed for attr
2017-09-14 01:26:43,035 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 01:26:43,410 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-14 01:26:45,668 [salt.loaded.int.module.cmdmod][INFO    ][25756] 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-09-14 01:26:49,137 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 01:26:49,190 [salt.state       ][INFO    ][25756] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-09-14 01:26:49,208 [salt.state       ][INFO    ][25756] Loading fresh modules for state activity
2017-09-14 01:26:49,230 [salt.state       ][INFO    ][25756] Completed state [attr] at time 01:26:49.230225 duration_in_ms=6195.901
2017-09-14 01:26:49,239 [salt.state       ][INFO    ][25756] Running state [glusterfs-server] at time 01:26:49.238910
2017-09-14 01:26:49,239 [salt.state       ][INFO    ][25756] Executing state pkg.installed for glusterfs-server
2017-09-14 01:26:49,537 [salt.loaded.int.module.cmdmod][INFO    ][25756] 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-09-14 01:26:50,212 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012650201240
2017-09-14 01:26:50,234 [salt.minion      ][INFO    ][26711] Starting a new job with PID 26711
2017-09-14 01:26:50,254 [salt.minion      ][INFO    ][26711] Returning information for job: 20170914012650201240
2017-09-14 01:27:00,419 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012700407684
2017-09-14 01:27:00,443 [salt.minion      ][INFO    ][29404] Starting a new job with PID 29404
2017-09-14 01:27:00,463 [salt.minion      ][INFO    ][29404] Returning information for job: 20170914012700407684
2017-09-14 01:27:10,504 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012710493061
2017-09-14 01:27:10,527 [salt.minion      ][INFO    ][29992] Starting a new job with PID 29992
2017-09-14 01:27:10,547 [salt.minion      ][INFO    ][29992] Returning information for job: 20170914012710493061
2017-09-14 01:27:11,742 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 01:27:11,799 [salt.state       ][INFO    ][25756] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'glusterfs-common' changed from 'absent' to '3.7.6-1ubuntu1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'portmap' changed from 'absent' to '1'
'libattr1-dev' changed from 'absent' to '1:2.4.47-2'
'attr-dev' changed from 'absent' to '1'
'acl-dev' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.7.6-1ubuntu1'
'glusterfs-client' changed from 'absent' to '3.7.6-1ubuntu1'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.1'
'nfs-client' changed from 'absent' to '1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'libacl1-dev' changed from 'absent' to '2.2.52-3'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'

2017-09-14 01:27:11,817 [salt.state       ][INFO    ][25756] Loading fresh modules for state activity
2017-09-14 01:27:11,843 [salt.state       ][INFO    ][25756] Completed state [glusterfs-server] at time 01:27:11.843042 duration_in_ms=22604.131
2017-09-14 01:27:11,852 [salt.state       ][INFO    ][25756] Running state [bc] at time 01:27:11.851905
2017-09-14 01:27:11,852 [salt.state       ][INFO    ][25756] Executing state pkg.installed for bc
2017-09-14 01:27:12,178 [salt.loaded.int.module.cmdmod][INFO    ][25756] 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-09-14 01:27:15,457 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 01:27:15,511 [salt.state       ][INFO    ][25756] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-09-14 01:27:15,527 [salt.state       ][INFO    ][25756] Loading fresh modules for state activity
2017-09-14 01:27:15,552 [salt.state       ][INFO    ][25756] Completed state [bc] at time 01:27:15.552005 duration_in_ms=3700.099
2017-09-14 01:27:15,644 [salt.state       ][INFO    ][25756] Running state [glusterfs-server] at time 01:27:15.643738
2017-09-14 01:27:15,644 [salt.state       ][INFO    ][25756] Executing state service.running for glusterfs-server
2017-09-14 01:27:15,646 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-14 01:27:15,663 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-14 01:27:15,675 [salt.loaded.int.module.cmdmod][INFO    ][25756] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-14 01:27:15,693 [salt.state       ][INFO    ][25756] The service glusterfs-server is already running
2017-09-14 01:27:15,694 [salt.state       ][INFO    ][25756] Completed state [glusterfs-server] at time 01:27:15.693883 duration_in_ms=50.144
2017-09-14 01:27:15,697 [salt.state       ][INFO    ][25756] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 01:27:15.697197
2017-09-14 01:27:15,698 [salt.state       ][INFO    ][25756] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-14 01:27:15,710 [salt.state       ][INFO    ][25756] File changed:
New file
2017-09-14 01:27:15,710 [salt.state       ][INFO    ][25756] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 01:27:15.710232 duration_in_ms=13.035
2017-09-14 01:27:15,711 [salt.state       ][INFO    ][25756] Running state [/srv/glusterfs/glance] at time 01:27:15.710558
2017-09-14 01:27:15,711 [salt.state       ][INFO    ][25756] Executing state file.directory for /srv/glusterfs/glance
2017-09-14 01:27:15,712 [salt.state       ][INFO    ][25756] {'/srv/glusterfs/glance': 'New Dir'}
2017-09-14 01:27:15,712 [salt.state       ][INFO    ][25756] Completed state [/srv/glusterfs/glance] at time 01:27:15.712221 duration_in_ms=1.664
2017-09-14 01:27:15,713 [salt.state       ][INFO    ][25756] Running state [/srv/glusterfs/keystone-keys] at time 01:27:15.712525
2017-09-14 01:27:15,713 [salt.state       ][INFO    ][25756] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-14 01:27:15,714 [salt.state       ][INFO    ][25756] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-09-14 01:27:15,714 [salt.state       ][INFO    ][25756] Completed state [/srv/glusterfs/keystone-keys] at time 01:27:15.713771 duration_in_ms=1.245
2017-09-14 01:27:15,714 [salt.state       ][INFO    ][25756] Running state [/srv/glusterfs/nova_instances] at time 01:27:15.714063
2017-09-14 01:27:15,714 [salt.state       ][INFO    ][25756] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-14 01:27:15,715 [salt.state       ][INFO    ][25756] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-09-14 01:27:15,715 [salt.state       ][INFO    ][25756] Completed state [/srv/glusterfs/nova_instances] at time 01:27:15.715333 duration_in_ms=1.269
2017-09-14 01:27:15,716 [salt.state       ][INFO    ][25756] Running state [/srv/glusterfs/keystone-credential-keys] at time 01:27:15.715623
2017-09-14 01:27:15,716 [salt.state       ][INFO    ][25756] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-14 01:27:15,717 [salt.state       ][INFO    ][25756] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-09-14 01:27:15,717 [salt.state       ][INFO    ][25756] Completed state [/srv/glusterfs/keystone-credential-keys] at time 01:27:15.716937 duration_in_ms=1.314
2017-09-14 01:27:15,718 [salt.minion      ][INFO    ][25756] Returning information for job: 20170914012640109929
2017-09-14 01:27:16,932 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command test.ping with jid 20170914012716922970
2017-09-14 01:27:16,953 [salt.minion      ][INFO    ][30484] Starting a new job with PID 30484
2017-09-14 01:27:17,000 [salt.minion      ][INFO    ][30484] Returning information for job: 20170914012716922970
2017-09-14 01:27:17,139 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command state.sls with jid 20170914012717129723
2017-09-14 01:27:17,160 [salt.minion      ][INFO    ][30489] Starting a new job with PID 30489
2017-09-14 01:27:18,648 [salt.state       ][INFO    ][30489] Loading fresh modules for state activity
2017-09-14 01:27:18,687 [salt.fileclient  ][INFO    ][30489] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-09-14 01:27:18,739 [salt.fileclient  ][INFO    ][30489] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-14 01:27:18,832 [salt.fileclient  ][INFO    ][30489] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-14 01:27:18,854 [salt.fileclient  ][INFO    ][30489] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-14 01:27:19,326 [salt.state       ][INFO    ][30489] Running state [attr] at time 01:27:19.325559
2017-09-14 01:27:19,326 [salt.state       ][INFO    ][30489] Executing state pkg.installed for attr
2017-09-14 01:27:19,327 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-14 01:27:19,699 [salt.state       ][INFO    ][30489] Package attr is already installed
2017-09-14 01:27:19,707 [salt.state       ][INFO    ][30489] Completed state [attr] at time 01:27:19.706396 duration_in_ms=380.837
2017-09-14 01:27:19,707 [salt.state       ][INFO    ][30489] Running state [glusterfs-server] at time 01:27:19.707122
2017-09-14 01:27:19,708 [salt.state       ][INFO    ][30489] Executing state pkg.installed for glusterfs-server
2017-09-14 01:27:19,716 [salt.state       ][INFO    ][30489] Package glusterfs-server is already installed
2017-09-14 01:27:19,716 [salt.state       ][INFO    ][30489] Completed state [glusterfs-server] at time 01:27:19.716130 duration_in_ms=9.006
2017-09-14 01:27:19,717 [salt.state       ][INFO    ][30489] Running state [bc] at time 01:27:19.716708
2017-09-14 01:27:19,717 [salt.state       ][INFO    ][30489] Executing state pkg.installed for bc
2017-09-14 01:27:19,725 [salt.state       ][INFO    ][30489] Package bc is already installed
2017-09-14 01:27:19,725 [salt.state       ][INFO    ][30489] Completed state [bc] at time 01:27:19.725193 duration_in_ms=8.485
2017-09-14 01:27:19,729 [salt.state       ][INFO    ][30489] Running state [glusterfs-server] at time 01:27:19.728525
2017-09-14 01:27:19,729 [salt.state       ][INFO    ][30489] Executing state service.running for glusterfs-server
2017-09-14 01:27:19,731 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-14 01:27:19,749 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-09-14 01:27:19,841 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-14 01:27:19,868 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-14 01:27:19,881 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-14 01:27:19,898 [salt.state       ][INFO    ][30489] The service glusterfs-server is already running
2017-09-14 01:27:19,898 [salt.state       ][INFO    ][30489] Completed state [glusterfs-server] at time 01:27:19.898274 duration_in_ms=169.748
2017-09-14 01:27:19,903 [salt.state       ][INFO    ][30489] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 01:27:19.902777
2017-09-14 01:27:19,903 [salt.state       ][INFO    ][30489] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-14 01:27:19,915 [salt.state       ][INFO    ][30489] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-09-14 01:27:19,916 [salt.state       ][INFO    ][30489] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 01:27:19.915426 duration_in_ms=12.648
2017-09-14 01:27:19,916 [salt.state       ][INFO    ][30489] Running state [/srv/glusterfs/glance] at time 01:27:19.915860
2017-09-14 01:27:19,916 [salt.state       ][INFO    ][30489] Executing state file.directory for /srv/glusterfs/glance
2017-09-14 01:27:19,917 [salt.state       ][INFO    ][30489] Directory /srv/glusterfs/glance is in the correct state
2017-09-14 01:27:19,918 [salt.state       ][INFO    ][30489] Completed state [/srv/glusterfs/glance] at time 01:27:19.917535 duration_in_ms=1.675
2017-09-14 01:27:19,918 [salt.state       ][INFO    ][30489] Running state [/srv/glusterfs/keystone-keys] at time 01:27:19.917917
2017-09-14 01:27:19,918 [salt.state       ][INFO    ][30489] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-14 01:27:19,919 [salt.state       ][INFO    ][30489] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-09-14 01:27:19,919 [salt.state       ][INFO    ][30489] Completed state [/srv/glusterfs/keystone-keys] at time 01:27:19.919404 duration_in_ms=1.487
2017-09-14 01:27:19,920 [salt.state       ][INFO    ][30489] Running state [/srv/glusterfs/nova_instances] at time 01:27:19.919794
2017-09-14 01:27:19,920 [salt.state       ][INFO    ][30489] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-14 01:27:19,921 [salt.state       ][INFO    ][30489] Directory /srv/glusterfs/nova_instances is in the correct state
2017-09-14 01:27:19,921 [salt.state       ][INFO    ][30489] Completed state [/srv/glusterfs/nova_instances] at time 01:27:19.921292 duration_in_ms=1.498
2017-09-14 01:27:19,922 [salt.state       ][INFO    ][30489] Running state [/srv/glusterfs/keystone-credential-keys] at time 01:27:19.921676
2017-09-14 01:27:19,922 [salt.state       ][INFO    ][30489] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-14 01:27:19,923 [salt.state       ][INFO    ][30489] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-09-14 01:27:19,923 [salt.state       ][INFO    ][30489] Completed state [/srv/glusterfs/keystone-credential-keys] at time 01:27:19.923142 duration_in_ms=1.466
2017-09-14 01:27:20,037 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command ['git', '--version'] in directory '/root'
2017-09-14 01:27:20,107 [salt.state       ][INFO    ][30489] Running state [sleep 5] at time 01:27:20.106889
2017-09-14 01:27:20,107 [salt.state       ][INFO    ][30489] Executing state cmd.wait for sleep 5
2017-09-14 01:27:20,108 [salt.state       ][INFO    ][30489] No changes made for sleep 5
2017-09-14 01:27:20,108 [salt.state       ][INFO    ][30489] Completed state [sleep 5] at time 01:27:20.107712 duration_in_ms=0.825
2017-09-14 01:27:20,108 [salt.state       ][INFO    ][30489] Running state [10.167.4.141] at time 01:27:20.108283
2017-09-14 01:27:20,109 [salt.state       ][INFO    ][30489] Executing state glusterfs.peered for 10.167.4.141
2017-09-14 01:27:20,109 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:20,126 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:20,232 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:20,249 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:20,696 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:20,713 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:20,825 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:20,841 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:20,986 [salt.state       ][INFO    ][30489] {'new': {'10.167.4.141': []}, 'old': None}
2017-09-14 01:27:20,986 [salt.state       ][INFO    ][30489] Completed state [10.167.4.141] at time 01:27:20.986177 duration_in_ms=877.892
2017-09-14 01:27:20,992 [salt.state       ][INFO    ][30489] Running state [10.167.4.142] at time 01:27:20.991927
2017-09-14 01:27:20,992 [salt.state       ][INFO    ][30489] Executing state glusterfs.peered for 10.167.4.142
2017-09-14 01:27:20,993 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:21,010 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:21,115 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:21,131 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:21,237 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:21,342 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:21,453 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:21,471 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:21,578 [salt.state       ][INFO    ][30489] Peering with localhost is not needed
2017-09-14 01:27:21,579 [salt.state       ][INFO    ][30489] Completed state [10.167.4.142] at time 01:27:21.578621 duration_in_ms=586.694
2017-09-14 01:27:21,585 [salt.state       ][INFO    ][30489] Running state [10.167.4.143] at time 01:27:21.584898
2017-09-14 01:27:21,585 [salt.state       ][INFO    ][30489] Executing state glusterfs.peered for 10.167.4.143
2017-09-14 01:27:21,586 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:21,606 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:21,712 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:21,726 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:22,116 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:22,136 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:22,243 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:22,261 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:22,399 [salt.state       ][INFO    ][30489] {'new': {'10.167.4.141': [], '10.167.4.143': []}, 'old': {'10.167.4.141': []}}
2017-09-14 01:27:22,400 [salt.state       ][INFO    ][30489] Completed state [10.167.4.143] at time 01:27:22.399723 duration_in_ms=814.824
2017-09-14 01:27:22,402 [salt.state       ][INFO    ][30489] Running state [glance] at time 01:27:22.401661
2017-09-14 01:27:22,402 [salt.state       ][INFO    ][30489] Executing state glusterfs.created for glance
2017-09-14 01:27:22,403 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:22,419 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:22,524 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:22,541 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:23,406 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:23,425 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:24,703 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:24,721 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:24,830 [salt.state       ][INFO    ][30489] {'new': ['glance'], 'old': []}
2017-09-14 01:27:24,831 [salt.state       ][INFO    ][30489] Completed state [glance] at time 01:27:24.830977 duration_in_ms=2429.315
2017-09-14 01:27:24,839 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:24.838467
2017-09-14 01:27:24,839 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-09-14 01:27:24,840 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-14 01:27:24,950 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:27:25,624 [salt.state       ][INFO    ][30489] {'pid': 30711, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:25,625 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:25.624618 duration_in_ms=786.15
2017-09-14 01:27:25,626 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 01:27:25.625743
2017-09-14 01:27:25,626 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-09-14 01:27:25,627 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-14 01:27:25,738 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-09-14 01:27:26,457 [salt.state       ][INFO    ][30489] {'pid': 30729, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:26,457 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 01:27:26.457059 duration_in_ms=831.315
2017-09-14 01:27:26,458 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 01:27:26.458190
2017-09-14 01:27:26,459 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-09-14 01:27:26,460 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-09-14 01:27:26,564 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-09-14 01:27:27,180 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012727169119
2017-09-14 01:27:27,201 [salt.minion      ][INFO    ][30757] Starting a new job with PID 30757
2017-09-14 01:27:27,220 [salt.minion      ][INFO    ][30757] Returning information for job: 20170914012727169119
2017-09-14 01:27:29,327 [salt.state       ][INFO    ][30489] {'pid': 30747, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:29,328 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 01:27:29.327920 duration_in_ms=2869.728
2017-09-14 01:27:29,329 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 01:27:29.329205
2017-09-14 01:27:29,330 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-09-14 01:27:29,331 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-14 01:27:29,431 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:27:30,113 [salt.state       ][INFO    ][30489] {'pid': 30772, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:30,114 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 01:27:30.113697 duration_in_ms=784.491
2017-09-14 01:27:30,115 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 01:27:30.114776
2017-09-14 01:27:30,115 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-09-14 01:27:30,116 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-14 01:27:30,222 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-14 01:27:30,918 [salt.state       ][INFO    ][30489] {'pid': 30789, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:30,918 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 01:27:30.918205 duration_in_ms=803.428
2017-09-14 01:27:30,921 [salt.state       ][INFO    ][30489] Running state [glance] at time 01:27:30.920728
2017-09-14 01:27:30,921 [salt.state       ][INFO    ][30489] Executing state glusterfs.started for glance
2017-09-14 01:27:30,922 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:30,940 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:31,050 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:31,068 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:31,182 [salt.state       ][INFO    ][30489] Volume glance is already started
2017-09-14 01:27:31,182 [salt.state       ][INFO    ][30489] Completed state [glance] at time 01:27:31.182166 duration_in_ms=261.436
2017-09-14 01:27:31,184 [salt.state       ][INFO    ][30489] Running state [keystone-keys] at time 01:27:31.183760
2017-09-14 01:27:31,184 [salt.state       ][INFO    ][30489] Executing state glusterfs.created for keystone-keys
2017-09-14 01:27:31,185 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:31,202 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:31,303 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:31,316 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:32,123 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:32,142 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:35,059 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:35,078 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:35,183 [salt.state       ][INFO    ][30489] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-09-14 01:27:35,184 [salt.state       ][INFO    ][30489] Completed state [keystone-keys] at time 01:27:35.183486 duration_in_ms=3999.726
2017-09-14 01:27:35,191 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 01:27:35.190754
2017-09-14 01:27:35,191 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-09-14 01:27:35,192 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-14 01:27:35,291 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-09-14 01:27:36,040 [salt.state       ][INFO    ][30489] {'pid': 30901, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:36,041 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 01:27:36.040538 duration_in_ms=849.782
2017-09-14 01:27:36,042 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:36.041709
2017-09-14 01:27:36,042 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-14 01:27:36,043 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-14 01:27:36,149 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:27:37,090 [salt.state       ][INFO    ][30489] {'pid': 30919, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:37,091 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:37.090805 duration_in_ms=1049.095
2017-09-14 01:27:37,092 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 01:27:37.092076
2017-09-14 01:27:37,093 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-14 01:27:37,094 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-14 01:27:37,198 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:27:37,335 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012737324063
2017-09-14 01:27:37,355 [salt.minion      ][INFO    ][30947] Starting a new job with PID 30947
2017-09-14 01:27:37,375 [salt.minion      ][INFO    ][30947] Returning information for job: 20170914012737324063
2017-09-14 01:27:37,923 [salt.state       ][INFO    ][30489] {'pid': 30937, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:37,923 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 01:27:37.923178 duration_in_ms=831.102
2017-09-14 01:27:37,924 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 01:27:37.924227
2017-09-14 01:27:37,925 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-09-14 01:27:37,926 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-14 01:27:38,030 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-09-14 01:27:40,783 [salt.state       ][INFO    ][30489] {'pid': 30962, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:40,784 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 01:27:40.783449 duration_in_ms=2859.22
2017-09-14 01:27:40,785 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 01:27:40.784577
2017-09-14 01:27:40,785 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-09-14 01:27:40,786 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-14 01:27:40,898 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-14 01:27:41,633 [salt.state       ][INFO    ][30489] {'pid': 30979, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:41,634 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 01:27:41.633579 duration_in_ms=849.0
2017-09-14 01:27:41,636 [salt.state       ][INFO    ][30489] Running state [keystone-keys] at time 01:27:41.636164
2017-09-14 01:27:41,637 [salt.state       ][INFO    ][30489] Executing state glusterfs.started for keystone-keys
2017-09-14 01:27:41,638 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:41,657 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:41,767 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:41,785 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:41,901 [salt.state       ][INFO    ][30489] Volume keystone-keys is already started
2017-09-14 01:27:41,902 [salt.state       ][INFO    ][30489] Completed state [keystone-keys] at time 01:27:41.901824 duration_in_ms=265.659
2017-09-14 01:27:41,904 [salt.state       ][INFO    ][30489] Running state [nova_instances] at time 01:27:41.903877
2017-09-14 01:27:41,904 [salt.state       ][INFO    ][30489] Executing state glusterfs.created for nova_instances
2017-09-14 01:27:41,905 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:41,922 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:42,027 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:42,044 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:42,793 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:42,814 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:45,827 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:45,846 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:45,949 [salt.state       ][INFO    ][30489] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-09-14 01:27:45,950 [salt.state       ][INFO    ][30489] Completed state [nova_instances] at time 01:27:45.949819 duration_in_ms=4045.941
2017-09-14 01:27:45,958 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:45.958176
2017-09-14 01:27:45,959 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-09-14 01:27:45,960 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-14 01:27:46,064 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:27:46,777 [salt.state       ][INFO    ][30489] {'pid': 31094, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:46,777 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:46.777341 duration_in_ms=819.164
2017-09-14 01:27:46,779 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 01:27:46.778461
2017-09-14 01:27:46,779 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-09-14 01:27:46,780 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-14 01:27:46,891 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-14 01:27:47,493 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012747481971
2017-09-14 01:27:47,516 [salt.minion      ][INFO    ][31122] Starting a new job with PID 31122
2017-09-14 01:27:47,535 [salt.minion      ][INFO    ][31122] Returning information for job: 20170914012747481971
2017-09-14 01:27:47,612 [salt.state       ][INFO    ][30489] {'pid': 31112, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:47,613 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 01:27:47.612549 duration_in_ms=834.087
2017-09-14 01:27:47,614 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 01:27:47.613685
2017-09-14 01:27:47,614 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-09-14 01:27:47,615 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-14 01:27:47,719 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'True'' in directory '/root'
2017-09-14 01:27:48,444 [salt.state       ][INFO    ][30489] {'pid': 31134, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:48,445 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 01:27:48.444750 duration_in_ms=831.064
2017-09-14 01:27:48,446 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 01:27:48.445894
2017-09-14 01:27:48,446 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-09-14 01:27:48,448 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-09-14 01:27:48,561 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'True'' in directory '/root'
2017-09-14 01:27:51,357 [salt.state       ][INFO    ][30489] {'pid': 31154, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:51,358 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 01:27:51.357629 duration_in_ms=2911.734
2017-09-14 01:27:51,359 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 01:27:51.358791
2017-09-14 01:27:51,359 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-09-14 01:27:51,361 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-14 01:27:51,471 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:27:52,179 [salt.state       ][INFO    ][30489] {'pid': 31171, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:52,180 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 01:27:52.179969 duration_in_ms=821.177
2017-09-14 01:27:52,183 [salt.state       ][INFO    ][30489] Running state [nova_instances] at time 01:27:52.183361
2017-09-14 01:27:52,184 [salt.state       ][INFO    ][30489] Executing state glusterfs.started for nova_instances
2017-09-14 01:27:52,186 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:52,206 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:52,314 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:52,333 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:52,450 [salt.state       ][INFO    ][30489] Volume nova_instances is already started
2017-09-14 01:27:52,451 [salt.state       ][INFO    ][30489] Completed state [nova_instances] at time 01:27:52.450719 duration_in_ms=267.357
2017-09-14 01:27:52,453 [salt.state       ][INFO    ][30489] Running state [keystone-credential-keys] at time 01:27:52.453387
2017-09-14 01:27:52,454 [salt.state       ][INFO    ][30489] Executing state glusterfs.created for keystone-credential-keys
2017-09-14 01:27:52,455 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:52,472 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:52,584 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:52,602 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:53,360 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:53,378 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:56,369 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:27:56,387 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:27:56,491 [salt.state       ][INFO    ][30489] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-09-14 01:27:56,491 [salt.state       ][INFO    ][30489] Completed state [keystone-credential-keys] at time 01:27:56.491093 duration_in_ms=4037.705
2017-09-14 01:27:56,500 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 01:27:56.499888
2017-09-14 01:27:56,501 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-09-14 01:27:56,502 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-14 01:27:56,614 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-09-14 01:27:57,396 [salt.state       ][INFO    ][30489] {'pid': 31289, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:57,397 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 01:27:57.396901 duration_in_ms=897.012
2017-09-14 01:27:57,398 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:57.397986
2017-09-14 01:27:57,399 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-14 01:27:57,400 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-14 01:27:57,506 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:27:57,651 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command saltutil.find_job with jid 20170914012757641370
2017-09-14 01:27:57,674 [salt.minion      ][INFO    ][31316] Starting a new job with PID 31316
2017-09-14 01:27:57,698 [salt.minion      ][INFO    ][31316] Returning information for job: 20170914012757641370
2017-09-14 01:27:58,191 [salt.state       ][INFO    ][30489] {'pid': 31306, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:27:58,191 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 01:27:58.191283 duration_in_ms=793.295
2017-09-14 01:27:58,193 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 01:27:58.192461
2017-09-14 01:27:58,193 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-09-14 01:27:58,195 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-14 01:27:58,297 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-09-14 01:28:01,263 [salt.state       ][INFO    ][30489] {'pid': 31329, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:28:01,264 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 01:28:01.263427 duration_in_ms=3070.965
2017-09-14 01:28:01,265 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 01:28:01.264567
2017-09-14 01:28:01,265 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-14 01:28:01,266 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-14 01:28:01,372 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-09-14 01:28:02,091 [salt.state       ][INFO    ][30489] {'pid': 31348, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:28:02,092 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 01:28:02.091726 duration_in_ms=827.158
2017-09-14 01:28:02,093 [salt.state       ][INFO    ][30489] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 01:28:02.093041
2017-09-14 01:28:02,094 [salt.state       ][INFO    ][30489] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-09-14 01:28:02,095 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-14 01:28:02,202 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-09-14 01:28:02,924 [salt.state       ][INFO    ][30489] {'pid': 31365, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-09-14 01:28:02,925 [salt.state       ][INFO    ][30489] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 01:28:02.925001 duration_in_ms=831.96
2017-09-14 01:28:02,927 [salt.state       ][INFO    ][30489] Running state [keystone-credential-keys] at time 01:28:02.927386
2017-09-14 01:28:02,928 [salt.state       ][INFO    ][30489] Executing state glusterfs.started for keystone-credential-keys
2017-09-14 01:28:02,929 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:28:02,946 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:28:03,057 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --version' in directory '/root'
2017-09-14 01:28:03,074 [salt.loaded.int.module.cmdmod][INFO    ][30489] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-14 01:28:03,186 [salt.state       ][INFO    ][30489] Volume keystone-credential-keys is already started
2017-09-14 01:28:03,187 [salt.state       ][INFO    ][30489] Completed state [keystone-credential-keys] at time 01:28:03.186433 duration_in_ms=259.046
2017-09-14 01:28:03,191 [salt.minion      ][INFO    ][30489] Returning information for job: 20170914012717129723
2017-09-14 01:28:16,565 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command test.ping with jid 20170914012816560281
2017-09-14 01:28:16,586 [salt.minion      ][INFO    ][31398] Starting a new job with PID 31398
2017-09-14 01:28:16,638 [salt.minion      ][INFO    ][31398] Returning information for job: 20170914012816560281
2017-09-14 01:28:16,773 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command cmd.run with jid 20170914012816763304
2017-09-14 01:28:16,794 [salt.minion      ][INFO    ][31403] Starting a new job with PID 31403
2017-09-14 01:28:16,801 [salt.loaded.int.module.cmdmod][INFO    ][31403] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-09-14 01:28:17,023 [salt.minion      ][INFO    ][31403] Returning information for job: 20170914012816763304
2017-09-14 02:08:01,506 [salt.utils.schedule][INFO    ][23723] Running scheduled job: __mine_interval
2017-09-14 02:33:50,647 [salt.minion      ][INFO    ][23723] User sudo_ubuntu Executing command cp.push_dir with jid 20170914023350637259
2017-09-14 02:33:50,708 [salt.minion      ][INFO    ][32396] Starting a new job with PID 32396
