2017-10-27 22:01:12,245 [salt.cli.daemons ][WARNING ][2528] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-27 22:01:29,278 [salt.state       ][ERROR   ][3780] No contents found in top file
2017-10-27 22:03:04,941 [salt.utils.parsers][WARNING ][2528] Minion received a SIGTERM. Exiting.
2017-10-27 22:05:18,852 [salt.cli.daemons ][WARNING ][3153] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-27 22:08:16,652 [salt.loaded.int.module.cmdmod][ERROR   ][11011] Command 'virsh net-destroy default 2>&1 1>/dev/null; virsh net-undefine default' failed with return code: 1
2017-10-27 22:08:16,652 [salt.loaded.int.module.cmdmod][ERROR   ][11011] stderr: error: failed to get network 'default'
error: Network not found: no network with matching name 'default'
2017-10-27 22:08:16,653 [salt.loaded.int.module.cmdmod][ERROR   ][11011] retcode: 1
2017-10-27 22:08:16,653 [salt.state       ][ERROR   ][11011] {'pid': 15901, 'retcode': 1, 'stderr': "error: failed to get network 'default'\nerror: Network not found: no network with matching name 'default'", 'stdout': ''}
2017-10-27 22:08:35,292 [py.warnings      ][WARNING ][15978] /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-10-27 22:09:31,799 [salt.loaded.int.module.cmdmod][ERROR   ][15978] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:31,799 [salt.loaded.int.module.cmdmod][ERROR   ][15978] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:09:37,986 [salt.loaded.int.module.cmdmod][ERROR   ][15978] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:37,987 [salt.loaded.int.module.cmdmod][ERROR   ][15978] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:09:46,699 [salt.loaded.int.module.cmdmod][ERROR   ][15978] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:46,700 [salt.loaded.int.module.cmdmod][ERROR   ][15978] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:09:54,882 [salt.loaded.int.module.cmdmod][ERROR   ][15978] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:09:54,882 [salt.loaded.int.module.cmdmod][ERROR   ][15978] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:10:06,473 [salt.loaded.int.module.cmdmod][ERROR   ][15978] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:10:06,474 [salt.loaded.int.module.cmdmod][ERROR   ][15978] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:10:14,688 [salt.loaded.int.module.cmdmod][ERROR   ][15978] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-27 22:10:14,688 [salt.loaded.int.module.cmdmod][ERROR   ][15978] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-27 22:10:22,419 [salt.loaded.int.module.cmdmod][ERROR   ][15978] 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-10-27 22:10:25,898 [salt.state       ][ERROR   ][23828] No contents found in top file
2017-10-27 22:10:34,933 [salt.loaded.int.module.cmdmod][INFO    ][24402] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-27 22:10:34,940 [salt.loaded.int.module.cmdmod][INFO    ][24402] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-27 22:10:34,951 [salt.loaded.int.module.cmdmod][INFO    ][24402] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-10-27 22:10:34,976 [salt.utils.parsers][WARNING ][3153] Minion received a SIGTERM. Exiting.
2017-10-27 22:10:35,223 [salt.cli.daemons ][INFO    ][24551] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-odl-ha.local"
2017-10-27 22:10:35,748 [salt.minion      ][INFO    ][24551] Creating minion process manager
2017-10-27 22:10:35,749 [salt.cli.daemons ][WARNING ][24551] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-27 22:10:35,749 [salt.cli.daemons ][INFO    ][24551] The Salt Minion is starting up
2017-10-27 22:10:35,749 [salt.minion      ][INFO    ][24551] Minion is starting as user 'root'
2017-10-27 22:10:35,749 [salt.utils.event ][INFO    ][24551] Starting pull socket on /var/run/salt/minion/minion_event_2d6d7b82d1_pull.ipc
2017-10-27 22:10:36,532 [salt.loaded.int.module.cmdmod][INFO    ][24551] Executing command ['date', '+%z'] in directory '/root'
2017-10-27 22:10:36,539 [salt.utils.schedule][INFO    ][24551] Updating job settings for scheduled job: __mine_interval
2017-10-27 22:10:36,541 [salt.minion      ][INFO    ][24551] Added mine.update to scheduler
2017-10-27 22:10:36,731 [salt.minion      ][INFO    ][24551] Minion is ready to receive requests!
2017-10-27 22:10:37,733 [salt.utils.schedule][INFO    ][24551] Running scheduled job: __mine_interval
2017-10-27 22:10:45,485 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027221045475466
2017-10-27 22:10:45,498 [salt.minion      ][INFO    ][24685] Starting a new job with PID 24685
2017-10-27 22:10:45,505 [salt.minion      ][INFO    ][24685] Returning information for job: 20171027221045475466
2017-10-27 22:11:06,518 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command state.sls with jid 20171027221106498839
2017-10-27 22:11:06,532 [salt.minion      ][INFO    ][24692] Starting a new job with PID 24692
2017-10-27 22:11:08,001 [salt.state       ][INFO    ][24692] Loading fresh modules for state activity
2017-10-27 22:11:08,024 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-10-27 22:11:08,040 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-10-27 22:11:08,061 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:08,180 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-10-27 22:11:08,194 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-10-27 22:11:08,225 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:08,280 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-27 22:11:08,298 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:11:08,339 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-27 22:11:08,355 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-27 22:11:08,605 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:11:08,624 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-27 22:11:08,631 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-27 22:11:08,650 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-27 22:11:08,664 [py.warnings      ][WARNING ][24692] /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-10-27 22:11:08,665 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'salt-minion --version' in directory '/root'
2017-10-27 22:11:08,902 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-27 22:11:08,916 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-27 22:11:08,925 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-27 22:11:08,934 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-27 22:11:08,974 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-27 22:11:09,091 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-27 22:11:09,151 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-10-27 22:11:09,171 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:09,221 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-27 22:11:09,295 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:11:09,336 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-27 22:11:09,355 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-27 22:11:09,368 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:11:09,384 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-27 22:11:09,392 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-27 22:11:09,415 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-27 22:11:09,431 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'salt-minion --version' in directory '/root'
2017-10-27 22:11:09,664 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-27 22:11:09,683 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-27 22:11:09,745 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-27 22:11:09,756 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-27 22:11:09,834 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-27 22:11:09,881 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-27 22:11:09,950 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-10-27 22:11:10,005 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:10,440 [salt.state       ][INFO    ][24692] Running state [salt-minion] at time 22:11:10.439741
2017-10-27 22:11:10,440 [salt.state       ][INFO    ][24692] Executing state pkg.installed for salt-minion
2017-10-27 22:11:10,441 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:11:10,696 [salt.state       ][INFO    ][24692] Package salt-minion is already installed
2017-10-27 22:11:10,697 [salt.state       ][INFO    ][24692] Completed state [salt-minion] at time 22:11:10.696666 duration_in_ms=256.924
2017-10-27 22:11:10,697 [salt.state       ][INFO    ][24692] Running state [salt_minion_dependency_packages] at time 22:11:10.696886
2017-10-27 22:11:10,697 [salt.state       ][INFO    ][24692] Executing state pkg.installed for salt_minion_dependency_packages
2017-10-27 22:11:10,700 [salt.state       ][INFO    ][24692] All specified packages are already installed
2017-10-27 22:11:10,700 [salt.state       ][INFO    ][24692] Completed state [salt_minion_dependency_packages] at time 22:11:10.699821 duration_in_ms=2.936
2017-10-27 22:11:10,701 [salt.state       ][INFO    ][24692] Running state [/etc/salt/minion.d/minion.conf] at time 22:11:10.701293
2017-10-27 22:11:10,701 [salt.state       ][INFO    ][24692] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-10-27 22:11:10,724 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-10-27 22:11:10,769 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-27 22:11:10,855 [salt.fileclient  ][INFO    ][24692] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-27 22:11:10,888 [salt.state       ][INFO    ][24692] File /etc/salt/minion.d/minion.conf is in the correct state
2017-10-27 22:11:10,888 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/minion.d/minion.conf] at time 22:11:10.888338 duration_in_ms=187.044
2017-10-27 22:11:10,889 [salt.state       ][INFO    ][24692] Running state [/etc/salt/minion.d/_virt.conf] at time 22:11:10.888677
2017-10-27 22:11:10,889 [salt.state       ][INFO    ][24692] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-10-27 22:11:11,037 [salt.state       ][INFO    ][24692] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-10-27 22:11:11,037 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/minion.d/_virt.conf] at time 22:11:11.037176 duration_in_ms=148.497
2017-10-27 22:11:11,039 [salt.state       ][INFO    ][24692] Running state [salt-minion] at time 22:11:11.038861
2017-10-27 22:11:11,039 [salt.state       ][INFO    ][24692] Executing state service.running for salt-minion
2017-10-27 22:11:11,040 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-27 22:11:11,054 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-10-27 22:11:11,060 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-27 22:11:11,066 [salt.state       ][INFO    ][24692] The service salt-minion is already running
2017-10-27 22:11:11,067 [salt.state       ][INFO    ][24692] Completed state [salt-minion] at time 22:11:11.066694 duration_in_ms=27.833
2017-10-27 22:11:11,068 [salt.state       ][INFO    ][24692] Running state [/etc/salt/grains.d] at time 22:11:11.067968
2017-10-27 22:11:11,068 [salt.state       ][INFO    ][24692] Executing state file.directory for /etc/salt/grains.d
2017-10-27 22:11:11,069 [salt.state       ][INFO    ][24692] Directory /etc/salt/grains.d is in the correct state
2017-10-27 22:11:11,069 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/grains.d] at time 22:11:11.068793 duration_in_ms=0.825
2017-10-27 22:11:11,069 [salt.state       ][INFO    ][24692] Running state [/etc/salt/grains] at time 22:11:11.069208
2017-10-27 22:11:11,069 [salt.state       ][INFO    ][24692] Executing state file.managed for /etc/salt/grains
2017-10-27 22:11:11,070 [salt.state       ][INFO    ][24692] File /etc/salt/grains exists with proper permissions. No changes made.
2017-10-27 22:11:11,070 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/grains] at time 22:11:11.069807 duration_in_ms=0.599
2017-10-27 22:11:11,070 [salt.state       ][INFO    ][24692] Running state [/etc/salt/grains.d/placeholder] at time 22:11:11.070099
2017-10-27 22:11:11,070 [salt.state       ][INFO    ][24692] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-10-27 22:11:11,071 [salt.state       ][INFO    ][24692] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-10-27 22:11:11,071 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/grains.d/placeholder] at time 22:11:11.070672 duration_in_ms=0.573
2017-10-27 22:11:11,071 [salt.state       ][INFO    ][24692] Running state [/etc/salt/grains.d/sphinx] at time 22:11:11.070948
2017-10-27 22:11:11,071 [salt.state       ][INFO    ][24692] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-10-27 22:11:11,205 [salt.state       ][INFO    ][24692] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.143
               - 127.0.0.1
               - 192.168.11.251
-              - 192.168.122.1
         system:
           name: System
           param:

2017-10-27 22:11:11,205 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/grains.d/sphinx] at time 22:11:11.204979 duration_in_ms=134.03
2017-10-27 22:11:11,206 [salt.state       ][INFO    ][24692] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.205541
2017-10-27 22:11:11,206 [salt.state       ][INFO    ][24692] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,206 [salt.state       ][INFO    ][24692] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,206 [salt.state       ][INFO    ][24692] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.206066 duration_in_ms=0.525
2017-10-27 22:11:11,206 [salt.state       ][INFO    ][24692] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.206197
2017-10-27 22:11:11,206 [salt.state       ][INFO    ][24692] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,207 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-10-27 22:11:11,296 [salt.state       ][INFO    ][24692] {'pid': 24743, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-27 22:11:11,296 [salt.state       ][INFO    ][24692] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.295954 duration_in_ms=89.757
2017-10-27 22:11:11,296 [salt.state       ][INFO    ][24692] Running state [/etc/salt/grains.d/dns_records] at time 22:11:11.296390
2017-10-27 22:11:11,297 [salt.state       ][INFO    ][24692] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-10-27 22:11:11,409 [salt.state       ][INFO    ][24692] File /etc/salt/grains.d/dns_records is in the correct state
2017-10-27 22:11:11,409 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/grains.d/dns_records] at time 22:11:11.408913 duration_in_ms=112.524
2017-10-27 22:11:11,410 [salt.state       ][INFO    ][24692] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.409618
2017-10-27 22:11:11,410 [salt.state       ][INFO    ][24692] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,410 [salt.state       ][INFO    ][24692] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,410 [salt.state       ][INFO    ][24692] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.410333 duration_in_ms=0.716
2017-10-27 22:11:11,411 [salt.state       ][INFO    ][24692] Running state [/etc/salt/grains.d/salt] at time 22:11:11.410649
2017-10-27 22:11:11,411 [salt.state       ][INFO    ][24692] Executing state file.managed for /etc/salt/grains.d/salt
2017-10-27 22:11:11,597 [salt.state       ][INFO    ][24692] File /etc/salt/grains.d/salt is in the correct state
2017-10-27 22:11:11,597 [salt.state       ][INFO    ][24692] Completed state [/etc/salt/grains.d/salt] at time 22:11:11.596931 duration_in_ms=186.282
2017-10-27 22:11:11,598 [salt.state       ][INFO    ][24692] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.597638
2017-10-27 22:11:11,598 [salt.state       ][INFO    ][24692] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,598 [salt.state       ][INFO    ][24692] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-27 22:11:11,599 [salt.state       ][INFO    ][24692] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:11:11.598553 duration_in_ms=0.913
2017-10-27 22:11:11,600 [salt.state       ][INFO    ][24692] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:11:11.599901
2017-10-27 22:11:11,600 [salt.state       ][INFO    ][24692] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:11:11,601 [salt.state       ][INFO    ][24692] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:11:11,601 [salt.state       ][INFO    ][24692] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:11:11.600731 duration_in_ms=0.83
2017-10-27 22:11:11,601 [salt.state       ][INFO    ][24692] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:11:11.600962
2017-10-27 22:11:11,601 [salt.state       ][INFO    ][24692] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-27 22:11:11,603 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-10-27 22:11:11,610 [salt.state       ][INFO    ][24692] {'pid': 24745, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-27 22:11:11,611 [salt.state       ][INFO    ][24692] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:11:11.610761 duration_in_ms=9.799
2017-10-27 22:11:11,611 [salt.state       ][INFO    ][24692] Running state [mine.update] at time 22:11:11.611413
2017-10-27 22:11:11,612 [salt.state       ][INFO    ][24692] Executing state module.wait for mine.update
2017-10-27 22:11:11,612 [salt.state       ][INFO    ][24692] No changes made for mine.update
2017-10-27 22:11:11,612 [salt.state       ][INFO    ][24692] Completed state [mine.update] at time 22:11:11.612007 duration_in_ms=0.594
2017-10-27 22:11:11,612 [salt.state       ][INFO    ][24692] Running state [mine.update] at time 22:11:11.612191
2017-10-27 22:11:11,612 [salt.state       ][INFO    ][24692] Executing state module.mod_watch for mine.update
2017-10-27 22:11:12,133 [salt.state       ][INFO    ][24692] {'ret': True}
2017-10-27 22:11:12,133 [salt.state       ][INFO    ][24692] Completed state [mine.update] at time 22:11:12.132995 duration_in_ms=520.803
2017-10-27 22:11:12,133 [salt.state       ][INFO    ][24692] Running state [ca-certificates] at time 22:11:12.133289
2017-10-27 22:11:12,134 [salt.state       ][INFO    ][24692] Executing state pkg.installed for ca-certificates
2017-10-27 22:11:12,137 [salt.state       ][INFO    ][24692] Package ca-certificates is already installed
2017-10-27 22:11:12,137 [salt.state       ][INFO    ][24692] Completed state [ca-certificates] at time 22:11:12.137081 duration_in_ms=3.792
2017-10-27 22:11:12,138 [salt.state       ][INFO    ][24692] Running state [update-ca-certificates] at time 22:11:12.137736
2017-10-27 22:11:12,138 [salt.state       ][INFO    ][24692] Executing state cmd.wait for update-ca-certificates
2017-10-27 22:11:12,138 [salt.state       ][INFO    ][24692] No changes made for update-ca-certificates
2017-10-27 22:11:12,139 [salt.state       ][INFO    ][24692] Completed state [update-ca-certificates] at time 22:11:12.138465 duration_in_ms=0.729
2017-10-27 22:11:12,139 [salt.state       ][INFO    ][24692] Running state [libvirt-dev] at time 22:11:12.138681
2017-10-27 22:11:12,139 [salt.state       ][INFO    ][24692] Executing state pkg.installed for libvirt-dev
2017-10-27 22:11:12,142 [salt.state       ][INFO    ][24692] Package libvirt-dev is already installed
2017-10-27 22:11:12,142 [salt.state       ][INFO    ][24692] Completed state [libvirt-dev] at time 22:11:12.141842 duration_in_ms=3.161
2017-10-27 22:11:12,142 [salt.state       ][INFO    ][24692] Running state [pkg-config] at time 22:11:12.142064
2017-10-27 22:11:12,142 [salt.state       ][INFO    ][24692] Executing state pkg.installed for pkg-config
2017-10-27 22:11:12,145 [salt.state       ][INFO    ][24692] Package pkg-config is already installed
2017-10-27 22:11:12,145 [salt.state       ][INFO    ][24692] Completed state [pkg-config] at time 22:11:12.145191 duration_in_ms=3.127
2017-10-27 22:11:12,145 [salt.state       ][INFO    ][24692] Running state [virtng.init] at time 22:11:12.145412
2017-10-27 22:11:12,146 [salt.state       ][INFO    ][24692] Executing state module.run for virtng.init
2017-10-27 22:11:12,216 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command ['git', '--version'] in directory '/root'
2017-10-27 22:11:12,384 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,400 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,400 [salt.state       ][INFO    ][24692] Completed state [virtng.init] at time 22:11:12.400407 duration_in_ms=254.994
2017-10-27 22:11:12,401 [salt.state       ][INFO    ][24692] Running state [virt.set_autostart] at time 22:11:12.400686
2017-10-27 22:11:12,401 [salt.state       ][INFO    ][24692] Executing state module.run for virt.set_autostart
2017-10-27 22:11:12,403 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,419 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,419 [salt.state       ][INFO    ][24692] Completed state [virt.set_autostart] at time 22:11:12.419083 duration_in_ms=18.396
2017-10-27 22:11:12,419 [salt.state       ][INFO    ][24692] Running state [virtng.init] at time 22:11:12.419335
2017-10-27 22:11:12,420 [salt.state       ][INFO    ][24692] Executing state module.run for virtng.init
2017-10-27 22:11:12,422 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,436 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,512 [salt.state       ][INFO    ][24692] Completed state [virtng.init] at time 22:11:12.511534 duration_in_ms=92.197
2017-10-27 22:11:12,512 [salt.state       ][INFO    ][24692] Running state [virt.set_autostart] at time 22:11:12.512033
2017-10-27 22:11:12,512 [salt.state       ][INFO    ][24692] Executing state module.run for virt.set_autostart
2017-10-27 22:11:12,514 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,532 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,532 [salt.state       ][INFO    ][24692] Completed state [virt.set_autostart] at time 22:11:12.531973 duration_in_ms=19.94
2017-10-27 22:11:12,532 [salt.state       ][INFO    ][24692] Running state [virtng.init] at time 22:11:12.532213
2017-10-27 22:11:12,532 [salt.state       ][INFO    ][24692] Executing state module.run for virtng.init
2017-10-27 22:11:12,534 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,549 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,549 [salt.state       ][INFO    ][24692] Completed state [virtng.init] at time 22:11:12.549155 duration_in_ms=16.941
2017-10-27 22:11:12,549 [salt.state       ][INFO    ][24692] Running state [virt.set_autostart] at time 22:11:12.549460
2017-10-27 22:11:12,550 [salt.state       ][INFO    ][24692] Executing state module.run for virt.set_autostart
2017-10-27 22:11:12,552 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,566 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,567 [salt.state       ][INFO    ][24692] Completed state [virt.set_autostart] at time 22:11:12.566612 duration_in_ms=17.152
2017-10-27 22:11:12,567 [salt.state       ][INFO    ][24692] Running state [virtng.init] at time 22:11:12.566892
2017-10-27 22:11:12,567 [salt.state       ][INFO    ][24692] Executing state module.run for virtng.init
2017-10-27 22:11:12,569 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --all --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,583 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,583 [salt.state       ][INFO    ][24692] Completed state [virtng.init] at time 22:11:12.582717 duration_in_ms=15.824
2017-10-27 22:11:12,583 [salt.state       ][INFO    ][24692] Running state [virt.set_autostart] at time 22:11:12.583000
2017-10-27 22:11:12,583 [salt.state       ][INFO    ][24692] Executing state module.run for virt.set_autostart
2017-10-27 22:11:12,585 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --autostart --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,598 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,598 [salt.state       ][INFO    ][24692] Completed state [virt.set_autostart] at time 22:11:12.597926 duration_in_ms=14.925
2017-10-27 22:11:12,598 [salt.state       ][INFO    ][24692] Running state [virtng.init] at time 22:11:12.598227
2017-10-27 22:11:12,598 [salt.state       ][INFO    ][24692] Executing state module.run for virtng.init
2017-10-27 22:11:12,600 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,613 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,613 [salt.state       ][INFO    ][24692] Completed state [virtng.init] at time 22:11:12.613308 duration_in_ms=15.08
2017-10-27 22:11:12,614 [salt.state       ][INFO    ][24692] Running state [virt.set_autostart] at time 22:11:12.613585
2017-10-27 22:11:12,614 [salt.state       ][INFO    ][24692] Executing state module.run for virt.set_autostart
2017-10-27 22:11:12,616 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,628 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,628 [salt.state       ][INFO    ][24692] Completed state [virt.set_autostart] at time 22:11:12.628428 duration_in_ms=14.842
2017-10-27 22:11:12,629 [salt.state       ][INFO    ][24692] Running state [virtng.init] at time 22:11:12.628708
2017-10-27 22:11:12,629 [salt.state       ][INFO    ][24692] Executing state module.run for virtng.init
2017-10-27 22:11:12,631 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,644 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,644 [salt.state       ][INFO    ][24692] Completed state [virtng.init] at time 22:11:12.643991 duration_in_ms=15.283
2017-10-27 22:11:12,644 [salt.state       ][INFO    ][24692] Running state [virt.set_autostart] at time 22:11:12.644281
2017-10-27 22:11:12,645 [salt.state       ][INFO    ][24692] Executing state module.run for virt.set_autostart
2017-10-27 22:11:12,647 [salt.loaded.int.module.cmdmod][INFO    ][24692] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-10-27 22:11:12,659 [salt.state       ][INFO    ][24692] unless execution succeeded
2017-10-27 22:11:12,659 [salt.state       ][INFO    ][24692] Completed state [virt.set_autostart] at time 22:11:12.659181 duration_in_ms=14.9
2017-10-27 22:11:12,661 [salt.minion      ][INFO    ][24692] Returning information for job: 20171027221106498839
2017-10-27 22:11:58,490 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171027221158485288
2017-10-27 22:11:58,507 [salt.minion      ][INFO    ][24816] Starting a new job with PID 24816
2017-10-27 22:12:01,753 [salt.state       ][INFO    ][24816] Loading fresh modules for state activity
2017-10-27 22:12:02,194 [salt.state       ][ERROR   ][24816] No contents found in top file
2017-10-27 22:12:02,195 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-10-27 22:12:02,198 [salt.utils.extmods][INFO    ][24816] Syncing beacons for environment 'base'
2017-10-27 22:12:02,198 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_beacons, for base)
2017-10-27 22:12:02,198 [salt.fileclient  ][INFO    ][24816] Caching directory '_beacons/' for environment 'base'
2017-10-27 22:12:02,231 [salt.utils.extmods][INFO    ][24816] Syncing modules for environment 'base'
2017-10-27 22:12:02,231 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_modules, for base)
2017-10-27 22:12:02,231 [salt.fileclient  ][INFO    ][24816] Caching directory '_modules/' for environment 'base'
2017-10-27 22:12:02,305 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-10-27 22:12:02,332 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-10-27 22:12:02,369 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-10-27 22:12:02,378 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-10-27 22:12:02,419 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-10-27 22:12:02,477 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-10-27 22:12:02,487 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-10-27 22:12:02,501 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-10-27 22:12:02,521 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-10-27 22:12:02,556 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-10-27 22:12:02,563 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-10-27 22:12:02,598 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-10-27 22:12:02,636 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-10-27 22:12:02,654 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-10-27 22:12:02,680 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-10-27 22:12:02,692 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-10-27 22:12:02,736 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-10-27 22:12:02,753 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-10-27 22:12:02,764 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-10-27 22:12:02,781 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-10-27 22:12:02,798 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-10-27 22:12:02,863 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-10-27 22:12:02,893 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-10-27 22:12:02,918 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-10-27 22:12:02,929 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-10-27 22:12:02,970 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-10-27 22:12:02,986 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-10-27 22:12:03,052 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-10-27 22:12:03,062 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-10-27 22:12:03,088 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-10-27 22:12:03,144 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-10-27 22:12:03,216 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-10-27 22:12:03,219 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-10-27 22:12:03,219 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-10-27 22:12:03,219 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-10-27 22:12:03,220 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-10-27 22:12:03,220 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-10-27 22:12:03,220 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-10-27 22:12:03,221 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-10-27 22:12:03,221 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-10-27 22:12:03,221 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-10-27 22:12:03,221 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-10-27 22:12:03,222 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-10-27 22:12:03,222 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-10-27 22:12:03,222 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-10-27 22:12:03,222 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-10-27 22:12:03,223 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-10-27 22:12:03,223 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-10-27 22:12:03,223 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-10-27 22:12:03,223 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-10-27 22:12:03,224 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-10-27 22:12:03,224 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-10-27 22:12:03,224 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-10-27 22:12:03,224 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-10-27 22:12:03,225 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-10-27 22:12:03,225 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-10-27 22:12:03,225 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-10-27 22:12:03,226 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-10-27 22:12:03,226 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-10-27 22:12:03,226 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-10-27 22:12:03,226 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-10-27 22:12:03,227 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-10-27 22:12:03,227 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-10-27 22:12:03,227 [salt.utils.extmods][INFO    ][24816] 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-10-27 22:12:03,227 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-10-27 22:12:03,228 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-10-27 22:12:03,232 [salt.utils.extmods][INFO    ][24816] Syncing states for environment 'base'
2017-10-27 22:12:03,232 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_states, for base)
2017-10-27 22:12:03,232 [salt.fileclient  ][INFO    ][24816] Caching directory '_states/' for environment 'base'
2017-10-27 22:12:03,276 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-10-27 22:12:03,287 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-10-27 22:12:03,299 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-10-27 22:12:03,318 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-10-27 22:12:03,357 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-10-27 22:12:03,400 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-10-27 22:12:03,418 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-10-27 22:12:03,477 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-10-27 22:12:03,487 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-10-27 22:12:03,507 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-10-27 22:12:03,608 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-10-27 22:12:03,656 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-10-27 22:12:03,692 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-10-27 22:12:03,703 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-10-27 22:12:03,768 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-10-27 22:12:03,783 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-10-27 22:12:03,807 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-10-27 22:12:03,852 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-10-27 22:12:03,861 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-10-27 22:12:03,880 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-10-27 22:12:03,900 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-10-27 22:12:03,910 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-10-27 22:12:03,929 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-10-27 22:12:03,958 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-10-27 22:12:03,970 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-10-27 22:12:04,005 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-10-27 22:12:04,018 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-10-27 22:12:04,030 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-10-27 22:12:04,051 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-10-27 22:12:04,053 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-10-27 22:12:04,054 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-10-27 22:12:04,054 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-10-27 22:12:04,054 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-10-27 22:12:04,055 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-10-27 22:12:04,055 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-10-27 22:12:04,055 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-10-27 22:12:04,055 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-10-27 22:12:04,056 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-10-27 22:12:04,056 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-10-27 22:12:04,056 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-10-27 22:12:04,056 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-10-27 22:12:04,057 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-10-27 22:12:04,057 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-10-27 22:12:04,057 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-10-27 22:12:04,057 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-10-27 22:12:04,058 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-10-27 22:12:04,058 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-10-27 22:12:04,058 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-10-27 22:12:04,058 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-10-27 22:12:04,059 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-10-27 22:12:04,059 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-10-27 22:12:04,059 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-10-27 22:12:04,059 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-10-27 22:12:04,060 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-10-27 22:12:04,060 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-10-27 22:12:04,060 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-10-27 22:12:04,060 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-10-27 22:12:04,061 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-10-27 22:12:04,061 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-10-27 22:12:04,063 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-10-27 22:12:04,065 [salt.utils.extmods][INFO    ][24816] Syncing sdb for environment 'base'
2017-10-27 22:12:04,065 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_sdb, for base)
2017-10-27 22:12:04,066 [salt.fileclient  ][INFO    ][24816] Caching directory '_sdb/' for environment 'base'
2017-10-27 22:12:04,098 [salt.utils.extmods][INFO    ][24816] Syncing grains for environment 'base'
2017-10-27 22:12:04,098 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_grains, for base)
2017-10-27 22:12:04,098 [salt.fileclient  ][INFO    ][24816] Caching directory '_grains/' for environment 'base'
2017-10-27 22:12:04,139 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-10-27 22:12:04,152 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-10-27 22:12:04,166 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-10-27 22:12:04,359 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-10-27 22:12:04,375 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-10-27 22:12:04,400 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-10-27 22:12:04,414 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-10-27 22:12:04,424 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-10-27 22:12:04,433 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-10-27 22:12:04,452 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-10-27 22:12:04,459 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-10-27 22:12:04,473 [salt.fileclient  ][INFO    ][24816] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-10-27 22:12:04,476 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-10-27 22:12:04,476 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-10-27 22:12:04,476 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-10-27 22:12:04,476 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-10-27 22:12:04,477 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-10-27 22:12:04,477 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-10-27 22:12:04,477 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-10-27 22:12:04,477 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-10-27 22:12:04,477 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-10-27 22:12:04,477 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-10-27 22:12:04,478 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-10-27 22:12:04,478 [salt.utils.extmods][INFO    ][24816] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-10-27 22:12:04,479 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-10-27 22:12:04,482 [salt.utils.extmods][INFO    ][24816] Syncing renderers for environment 'base'
2017-10-27 22:12:04,482 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_renderers, for base)
2017-10-27 22:12:04,482 [salt.fileclient  ][INFO    ][24816] Caching directory '_renderers/' for environment 'base'
2017-10-27 22:12:04,507 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-10-27 22:12:04,509 [salt.utils.extmods][INFO    ][24816] Syncing returners for environment 'base'
2017-10-27 22:12:04,510 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_returners, for base)
2017-10-27 22:12:04,510 [salt.fileclient  ][INFO    ][24816] Caching directory '_returners/' for environment 'base'
2017-10-27 22:12:04,539 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-10-27 22:12:04,541 [salt.utils.extmods][INFO    ][24816] Syncing output for environment 'base'
2017-10-27 22:12:04,541 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_output, for base)
2017-10-27 22:12:04,541 [salt.fileclient  ][INFO    ][24816] Caching directory '_output/' for environment 'base'
2017-10-27 22:12:04,567 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-10-27 22:12:04,570 [salt.utils.extmods][INFO    ][24816] Syncing utils for environment 'base'
2017-10-27 22:12:04,570 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_utils, for base)
2017-10-27 22:12:04,570 [salt.fileclient  ][INFO    ][24816] Caching directory '_utils/' for environment 'base'
2017-10-27 22:12:04,595 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-10-27 22:12:04,597 [salt.utils.extmods][INFO    ][24816] Syncing log_handlers for environment 'base'
2017-10-27 22:12:04,597 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_log_handlers, for base)
2017-10-27 22:12:04,597 [salt.fileclient  ][INFO    ][24816] Caching directory '_log_handlers/' for environment 'base'
2017-10-27 22:12:04,614 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-10-27 22:12:04,616 [salt.utils.extmods][INFO    ][24816] Syncing proxy for environment 'base'
2017-10-27 22:12:04,617 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_proxy, for base)
2017-10-27 22:12:04,617 [salt.fileclient  ][INFO    ][24816] Caching directory '_proxy/' for environment 'base'
2017-10-27 22:12:04,643 [salt.utils.extmods][INFO    ][24816] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-10-27 22:12:04,645 [salt.utils.extmods][INFO    ][24816] Syncing engines for environment 'base'
2017-10-27 22:12:04,645 [salt.utils.extmods][INFO    ][24816] Loading cache from salt://_engines, for base)
2017-10-27 22:12:04,646 [salt.fileclient  ][INFO    ][24816] Caching directory '_engines/' for environment 'base'
2017-10-27 22:12:04,665 [salt.minion      ][INFO    ][24816] Returning information for job: 20171027221158485288
2017-10-27 22:41:42,050 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command test.ping with jid 20171027224142042223
2017-10-27 22:41:42,065 [salt.minion      ][INFO    ][25489] Starting a new job with PID 25489
2017-10-27 22:41:42,091 [salt.minion      ][INFO    ][25489] Returning information for job: 20171027224142042223
2017-10-27 22:41:57,620 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command state.sls with jid 20171027224157608859
2017-10-27 22:41:57,634 [salt.minion      ][INFO    ][25498] Starting a new job with PID 25498
2017-10-27 22:41:58,059 [salt.state       ][INFO    ][25498] Loading fresh modules for state activity
2017-10-27 22:41:58,132 [salt.fileclient  ][INFO    ][25498] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-27 22:41:58,149 [salt.fileclient  ][INFO    ][25498] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-27 22:41:58,173 [salt.fileclient  ][INFO    ][25498] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:41:58,470 [salt.state       ][INFO    ][25498] Running state [lsof] at time 22:41:58.469962
2017-10-27 22:41:58,470 [salt.state       ][INFO    ][25498] Executing state pkg.installed for lsof
2017-10-27 22:41:58,471 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:41:58,733 [salt.state       ][INFO    ][25498] Package lsof is already installed
2017-10-27 22:41:58,733 [salt.state       ][INFO    ][25498] Completed state [lsof] at time 22:41:58.733310 duration_in_ms=263.348
2017-10-27 22:41:58,734 [salt.state       ][INFO    ][25498] Running state [keepalived] at time 22:41:58.733512
2017-10-27 22:41:58,734 [salt.state       ][INFO    ][25498] Executing state pkg.installed for keepalived
2017-10-27 22:41:58,748 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-27 22:42:00,604 [salt.loaded.int.module.cmdmod][INFO    ][25498] 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-10-27 22:42:07,663 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027224207651998
2017-10-27 22:42:07,675 [salt.minion      ][INFO    ][26996] Starting a new job with PID 26996
2017-10-27 22:42:07,684 [salt.minion      ][INFO    ][26996] Returning information for job: 20171027224207651998
2017-10-27 22:42:07,875 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:42:07,897 [salt.state       ][INFO    ][25498] 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-10-27 22:42:07,904 [salt.state       ][INFO    ][25498] Loading fresh modules for state activity
2017-10-27 22:42:07,916 [salt.state       ][INFO    ][25498] Completed state [keepalived] at time 22:42:07.916084 duration_in_ms=9182.572
2017-10-27 22:42:07,918 [salt.state       ][INFO    ][25498] Running state [/etc/keepalived/keepalived.conf] at time 22:42:07.917753
2017-10-27 22:42:07,918 [salt.state       ][INFO    ][25498] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-27 22:42:07,938 [salt.fileclient  ][INFO    ][25498] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-27 22:42:07,968 [salt.fileclient  ][INFO    ][25498] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 22:42:07,974 [salt.state       ][INFO    ][25498] File changed:
New file
2017-10-27 22:42:07,974 [salt.state       ][INFO    ][25498] Completed state [/etc/keepalived/keepalived.conf] at time 22:42:07.973997 duration_in_ms=56.244
2017-10-27 22:42:08,029 [salt.state       ][INFO    ][25498] Running state [keepalived] at time 22:42:08.028588
2017-10-27 22:42:08,029 [salt.state       ][INFO    ][25498] Executing state service.running for keepalived
2017-10-27 22:42:08,030 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-27 22:42:08,039 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-27 22:42:08,046 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:42:08,053 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:42:08,061 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-10-27 22:42:08,121 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-27 22:42:08,127 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:42:08,134 [salt.loaded.int.module.cmdmod][INFO    ][25498] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 22:42:08,140 [salt.state       ][INFO    ][25498] {'keepalived': True}
2017-10-27 22:42:08,140 [salt.state       ][INFO    ][25498] Completed state [keepalived] at time 22:42:08.139889 duration_in_ms=111.302
2017-10-27 22:42:08,141 [salt.minion      ][INFO    ][25498] Returning information for job: 20171027224157608859
2017-10-27 22:45:35,353 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command pillar.get with jid 20171027224535346310
2017-10-27 22:45:35,373 [salt.minion      ][INFO    ][27168] Starting a new job with PID 27168
2017-10-27 22:45:35,378 [salt.minion      ][INFO    ][27168] Returning information for job: 20171027224535346310
2017-10-27 22:51:50,169 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command state.sls with jid 20171027225150159896
2017-10-27 22:51:50,182 [salt.minion      ][INFO    ][27301] Starting a new job with PID 27301
2017-10-27 22:51:51,617 [salt.state       ][INFO    ][27301] Loading fresh modules for state activity
2017-10-27 22:51:51,638 [salt.fileclient  ][INFO    ][27301] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 22:51:51,658 [salt.fileclient  ][INFO    ][27301] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 22:51:51,920 [salt.state       ][INFO    ][27301] Running state [attr] at time 22:51:51.919963
2017-10-27 22:51:51,920 [salt.state       ][INFO    ][27301] Executing state pkg.installed for attr
2017-10-27 22:51:51,921 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:51:52,217 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-27 22:51:55,419 [salt.loaded.int.module.cmdmod][INFO    ][27301] 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-10-27 22:51:57,616 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:51:57,640 [salt.state       ][INFO    ][27301] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-10-27 22:51:57,648 [salt.state       ][INFO    ][27301] Loading fresh modules for state activity
2017-10-27 22:51:57,661 [salt.state       ][INFO    ][27301] Completed state [attr] at time 22:51:57.660638 duration_in_ms=5740.676
2017-10-27 22:51:57,666 [salt.state       ][INFO    ][27301] Running state [glusterfs-server] at time 22:51:57.665649
2017-10-27 22:51:57,666 [salt.state       ][INFO    ][27301] Executing state pkg.installed for glusterfs-server
2017-10-27 22:51:57,845 [salt.loaded.int.module.cmdmod][INFO    ][27301] 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-10-27 22:52:00,262 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027225200251000
2017-10-27 22:52:00,277 [salt.minion      ][INFO    ][28344] Starting a new job with PID 28344
2017-10-27 22:52:00,288 [salt.minion      ][INFO    ][28344] Returning information for job: 20171027225200251000
2017-10-27 22:52:10,460 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command saltutil.find_job with jid 20171027225210449205
2017-10-27 22:52:10,476 [salt.minion      ][INFO    ][31157] Starting a new job with PID 31157
2017-10-27 22:52:10,487 [salt.minion      ][INFO    ][31157] Returning information for job: 20171027225210449205
2017-10-27 22:52:14,985 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:52:15,009 [salt.state       ][INFO    ][27301] 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-10-27 22:52:15,016 [salt.state       ][INFO    ][27301] Loading fresh modules for state activity
2017-10-27 22:52:15,029 [salt.state       ][INFO    ][27301] Completed state [glusterfs-server] at time 22:52:15.029336 duration_in_ms=17363.685
2017-10-27 22:52:15,034 [salt.state       ][INFO    ][27301] Running state [bc] at time 22:52:15.034388
2017-10-27 22:52:15,035 [salt.state       ][INFO    ][27301] Executing state pkg.installed for bc
2017-10-27 22:52:15,290 [salt.loaded.int.module.cmdmod][INFO    ][27301] 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-10-27 22:52:18,114 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:52:18,137 [salt.state       ][INFO    ][27301] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-10-27 22:52:18,144 [salt.state       ][INFO    ][27301] Loading fresh modules for state activity
2017-10-27 22:52:18,159 [salt.state       ][INFO    ][27301] Completed state [bc] at time 22:52:18.158744 duration_in_ms=3124.356
2017-10-27 22:52:18,216 [salt.state       ][INFO    ][27301] Running state [glusterfs-server] at time 22:52:18.216256
2017-10-27 22:52:18,217 [salt.state       ][INFO    ][27301] Executing state service.running for glusterfs-server
2017-10-27 22:52:18,218 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 22:52:18,226 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:52:18,232 [salt.loaded.int.module.cmdmod][INFO    ][27301] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:52:18,240 [salt.state       ][INFO    ][27301] The service glusterfs-server is already running
2017-10-27 22:52:18,241 [salt.state       ][INFO    ][27301] Completed state [glusterfs-server] at time 22:52:18.240631 duration_in_ms=24.376
2017-10-27 22:52:18,242 [salt.state       ][INFO    ][27301] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:52:18.242034
2017-10-27 22:52:18,242 [salt.state       ][INFO    ][27301] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 22:52:18,257 [salt.state       ][INFO    ][27301] File changed:
New file
2017-10-27 22:52:18,258 [salt.state       ][INFO    ][27301] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:52:18.257517 duration_in_ms=15.483
2017-10-27 22:52:18,258 [salt.state       ][INFO    ][27301] Running state [/srv/glusterfs/glance] at time 22:52:18.257662
2017-10-27 22:52:18,258 [salt.state       ][INFO    ][27301] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 22:52:18,258 [salt.state       ][INFO    ][27301] {'/srv/glusterfs/glance': 'New Dir'}
2017-10-27 22:52:18,259 [salt.state       ][INFO    ][27301] Completed state [/srv/glusterfs/glance] at time 22:52:18.258502 duration_in_ms=0.841
2017-10-27 22:52:18,259 [salt.state       ][INFO    ][27301] Running state [/srv/glusterfs/keystone-keys] at time 22:52:18.258643
2017-10-27 22:52:18,259 [salt.state       ][INFO    ][27301] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 22:52:18,259 [salt.state       ][INFO    ][27301] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-10-27 22:52:18,259 [salt.state       ][INFO    ][27301] Completed state [/srv/glusterfs/keystone-keys] at time 22:52:18.259253 duration_in_ms=0.609
2017-10-27 22:52:18,259 [salt.state       ][INFO    ][27301] Running state [/srv/glusterfs/nova_instances] at time 22:52:18.259393
2017-10-27 22:52:18,260 [salt.state       ][INFO    ][27301] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 22:52:18,260 [salt.state       ][INFO    ][27301] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-10-27 22:52:18,260 [salt.state       ][INFO    ][27301] Completed state [/srv/glusterfs/nova_instances] at time 22:52:18.259999 duration_in_ms=0.606
2017-10-27 22:52:18,260 [salt.state       ][INFO    ][27301] Running state [/srv/glusterfs/keystone-credential-keys] at time 22:52:18.260137
2017-10-27 22:52:18,260 [salt.state       ][INFO    ][27301] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 22:52:18,261 [salt.state       ][INFO    ][27301] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-10-27 22:52:18,261 [salt.state       ][INFO    ][27301] Completed state [/srv/glusterfs/keystone-credential-keys] at time 22:52:18.260743 duration_in_ms=0.606
2017-10-27 22:52:18,261 [salt.minion      ][INFO    ][27301] Returning information for job: 20171027225150159896
2017-10-27 22:52:19,898 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command test.ping with jid 20171027225219888051
2017-10-27 22:52:19,913 [salt.minion      ][INFO    ][32069] Starting a new job with PID 32069
2017-10-27 22:52:19,940 [salt.minion      ][INFO    ][32069] Returning information for job: 20171027225219888051
2017-10-27 22:53:00,583 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command state.sls with jid 20171027225300571383
2017-10-27 22:53:00,599 [salt.minion      ][INFO    ][32301] Starting a new job with PID 32301
2017-10-27 22:53:02,296 [salt.state       ][INFO    ][32301] Loading fresh modules for state activity
2017-10-27 22:53:02,320 [salt.fileclient  ][INFO    ][32301] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-27 22:53:02,354 [salt.fileclient  ][INFO    ][32301] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 22:53:02,416 [salt.fileclient  ][INFO    ][32301] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 22:53:02,437 [salt.fileclient  ][INFO    ][32301] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 22:53:02,710 [salt.state       ][INFO    ][32301] Running state [attr] at time 22:53:02.710456
2017-10-27 22:53:02,711 [salt.state       ][INFO    ][32301] Executing state pkg.installed for attr
2017-10-27 22:53:02,711 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 22:53:02,991 [salt.state       ][INFO    ][32301] Package attr is already installed
2017-10-27 22:53:02,991 [salt.state       ][INFO    ][32301] Completed state [attr] at time 22:53:02.990947 duration_in_ms=280.491
2017-10-27 22:53:02,991 [salt.state       ][INFO    ][32301] Running state [glusterfs-server] at time 22:53:02.991141
2017-10-27 22:53:02,991 [salt.state       ][INFO    ][32301] Executing state pkg.installed for glusterfs-server
2017-10-27 22:53:02,994 [salt.state       ][INFO    ][32301] Package glusterfs-server is already installed
2017-10-27 22:53:02,994 [salt.state       ][INFO    ][32301] Completed state [glusterfs-server] at time 22:53:02.994031 duration_in_ms=2.89
2017-10-27 22:53:02,994 [salt.state       ][INFO    ][32301] Running state [bc] at time 22:53:02.994180
2017-10-27 22:53:02,994 [salt.state       ][INFO    ][32301] Executing state pkg.installed for bc
2017-10-27 22:53:02,997 [salt.state       ][INFO    ][32301] Package bc is already installed
2017-10-27 22:53:02,997 [salt.state       ][INFO    ][32301] Completed state [bc] at time 22:53:02.996966 duration_in_ms=2.785
2017-10-27 22:53:02,998 [salt.state       ][INFO    ][32301] Running state [glusterfs-server] at time 22:53:02.997724
2017-10-27 22:53:02,998 [salt.state       ][INFO    ][32301] Executing state service.running for glusterfs-server
2017-10-27 22:53:02,998 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 22:53:03,008 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-10-27 22:53:03,051 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 22:53:03,061 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:53:03,066 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 22:53:03,073 [salt.state       ][INFO    ][32301] The service glusterfs-server is already running
2017-10-27 22:53:03,073 [salt.state       ][INFO    ][32301] Completed state [glusterfs-server] at time 22:53:03.072901 duration_in_ms=75.176
2017-10-27 22:53:03,074 [salt.state       ][INFO    ][32301] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:53:03.074164
2017-10-27 22:53:03,074 [salt.state       ][INFO    ][32301] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 22:53:03,087 [salt.state       ][INFO    ][32301] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-27 22:53:03,088 [salt.state       ][INFO    ][32301] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 22:53:03.087592 duration_in_ms=13.429
2017-10-27 22:53:03,088 [salt.state       ][INFO    ][32301] Running state [/srv/glusterfs/glance] at time 22:53:03.087734
2017-10-27 22:53:03,088 [salt.state       ][INFO    ][32301] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 22:53:03,088 [salt.state       ][INFO    ][32301] Directory /srv/glusterfs/glance is in the correct state
2017-10-27 22:53:03,088 [salt.state       ][INFO    ][32301] Completed state [/srv/glusterfs/glance] at time 22:53:03.088271 duration_in_ms=0.537
2017-10-27 22:53:03,088 [salt.state       ][INFO    ][32301] Running state [/srv/glusterfs/keystone-keys] at time 22:53:03.088402
2017-10-27 22:53:03,089 [salt.state       ][INFO    ][32301] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 22:53:03,089 [salt.state       ][INFO    ][32301] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-27 22:53:03,089 [salt.state       ][INFO    ][32301] Completed state [/srv/glusterfs/keystone-keys] at time 22:53:03.088935 duration_in_ms=0.534
2017-10-27 22:53:03,089 [salt.state       ][INFO    ][32301] Running state [/srv/glusterfs/nova_instances] at time 22:53:03.089069
2017-10-27 22:53:03,089 [salt.state       ][INFO    ][32301] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 22:53:03,090 [salt.state       ][INFO    ][32301] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-27 22:53:03,090 [salt.state       ][INFO    ][32301] Completed state [/srv/glusterfs/nova_instances] at time 22:53:03.089625 duration_in_ms=0.556
2017-10-27 22:53:03,090 [salt.state       ][INFO    ][32301] Running state [/srv/glusterfs/keystone-credential-keys] at time 22:53:03.089769
2017-10-27 22:53:03,090 [salt.state       ][INFO    ][32301] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 22:53:03,090 [salt.state       ][INFO    ][32301] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-27 22:53:03,090 [salt.state       ][INFO    ][32301] Completed state [/srv/glusterfs/keystone-credential-keys] at time 22:53:03.090324 duration_in_ms=0.555
2017-10-27 22:53:03,148 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command ['git', '--version'] in directory '/root'
2017-10-27 22:53:03,185 [salt.state       ][INFO    ][32301] Running state [sleep 5] at time 22:53:03.185370
2017-10-27 22:53:03,186 [salt.state       ][INFO    ][32301] Executing state cmd.wait for sleep 5
2017-10-27 22:53:03,186 [salt.state       ][INFO    ][32301] No changes made for sleep 5
2017-10-27 22:53:03,186 [salt.state       ][INFO    ][32301] Completed state [sleep 5] at time 22:53:03.185923 duration_in_ms=0.553
2017-10-27 22:53:03,186 [salt.state       ][INFO    ][32301] Running state [10.167.4.141] at time 22:53:03.186299
2017-10-27 22:53:03,186 [salt.state       ][INFO    ][32301] Executing state glusterfs.peered for 10.167.4.141
2017-10-27 22:53:03,187 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,196 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,248 [salt.state       ][INFO    ][32301] Host 10.167.4.141 already peered
2017-10-27 22:53:03,248 [salt.state       ][INFO    ][32301] Completed state [10.167.4.141] at time 22:53:03.248040 duration_in_ms=61.741
2017-10-27 22:53:03,250 [salt.state       ][INFO    ][32301] Running state [10.167.4.142] at time 22:53:03.250152
2017-10-27 22:53:03,250 [salt.state       ][INFO    ][32301] Executing state glusterfs.peered for 10.167.4.142
2017-10-27 22:53:03,251 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,259 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,307 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,315 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,367 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,375 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,480 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,489 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,535 [salt.state       ][INFO    ][32301] {'new': {'kvm02.baremetal-mcp-ocata-odl-ha.local': ['10.167.4.142'], '10.167.4.141': []}, 'old': {'kvm02.baremetal-mcp-ocata-odl-ha.local': [], '10.167.4.141': []}}
2017-10-27 22:53:03,535 [salt.state       ][INFO    ][32301] Completed state [10.167.4.142] at time 22:53:03.535189 duration_in_ms=285.036
2017-10-27 22:53:03,538 [salt.state       ][INFO    ][32301] Running state [10.167.4.143] at time 22:53:03.537484
2017-10-27 22:53:03,538 [salt.state       ][INFO    ][32301] Executing state glusterfs.peered for 10.167.4.143
2017-10-27 22:53:03,538 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,546 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,591 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,599 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,646 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,653 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,701 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,709 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,754 [salt.state       ][INFO    ][32301] Peering with localhost is not needed
2017-10-27 22:53:03,755 [salt.state       ][INFO    ][32301] Completed state [10.167.4.143] at time 22:53:03.754643 duration_in_ms=217.159
2017-10-27 22:53:03,756 [salt.state       ][INFO    ][32301] Running state [glance] at time 22:53:03.755497
2017-10-27 22:53:03,756 [salt.state       ][INFO    ][32301] Executing state glusterfs.created for glance
2017-10-27 22:53:03,756 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,765 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,817 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:03,825 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:03,876 [salt.state       ][INFO    ][32301] Volume glance already exists and is started.
2017-10-27 22:53:03,877 [salt.state       ][INFO    ][32301] Completed state [glance] at time 22:53:03.876639 duration_in_ms=121.142
2017-10-27 22:53:03,879 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:03.879301
2017-10-27 22:53:03,880 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:53:03,880 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:03,927 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:03,927 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:03.927290 duration_in_ms=47.988
2017-10-27 22:53:03,928 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 22:53:03.927788
2017-10-27 22:53:03,928 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-27 22:53:03,929 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 22:53:03,976 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:03,976 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 22:53:03.976288 duration_in_ms=48.5
2017-10-27 22:53:03,977 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 22:53:03.976854
2017-10-27 22:53:03,977 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-27 22:53:03,978 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 22:53:04,030 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,030 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 22:53:04.029843 duration_in_ms=52.989
2017-10-27 22:53:04,030 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:04.030327
2017-10-27 22:53:04,031 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:53:04,031 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:04,078 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,079 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:04.078482 duration_in_ms=48.155
2017-10-27 22:53:04,079 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 22:53:04.078937
2017-10-27 22:53:04,079 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-27 22:53:04,080 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 22:53:04,126 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,126 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 22:53:04.126064 duration_in_ms=47.126
2017-10-27 22:53:04,127 [salt.state       ][INFO    ][32301] Running state [glance] at time 22:53:04.126982
2017-10-27 22:53:04,127 [salt.state       ][INFO    ][32301] Executing state glusterfs.started for glance
2017-10-27 22:53:04,128 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,136 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,185 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,192 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,242 [salt.state       ][INFO    ][32301] Volume glance is already started
2017-10-27 22:53:04,242 [salt.state       ][INFO    ][32301] Completed state [glance] at time 22:53:04.242258 duration_in_ms=115.276
2017-10-27 22:53:04,243 [salt.state       ][INFO    ][32301] Running state [keystone-keys] at time 22:53:04.243056
2017-10-27 22:53:04,243 [salt.state       ][INFO    ][32301] Executing state glusterfs.created for keystone-keys
2017-10-27 22:53:04,244 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,251 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,298 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,306 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,357 [salt.state       ][INFO    ][32301] Volume keystone-keys already exists and is started.
2017-10-27 22:53:04,357 [salt.state       ][INFO    ][32301] Completed state [keystone-keys] at time 22:53:04.356716 duration_in_ms=113.66
2017-10-27 22:53:04,359 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 22:53:04.359409
2017-10-27 22:53:04,360 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-27 22:53:04,360 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 22:53:04,407 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,408 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 22:53:04.407552 duration_in_ms=48.143
2017-10-27 22:53:04,408 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:04.407971
2017-10-27 22:53:04,408 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:53:04,409 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:04,454 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,454 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:04.453797 duration_in_ms=45.826
2017-10-27 22:53:04,454 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:04.454253
2017-10-27 22:53:04,454 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:53:04,455 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:04,500 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,500 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:04.499928 duration_in_ms=45.674
2017-10-27 22:53:04,500 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 22:53:04.500345
2017-10-27 22:53:04,501 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-27 22:53:04,501 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 22:53:04,548 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,548 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 22:53:04.548257 duration_in_ms=47.912
2017-10-27 22:53:04,549 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 22:53:04.548667
2017-10-27 22:53:04,549 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 22:53:04,549 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 22:53:04,596 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,596 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 22:53:04.596002 duration_in_ms=47.335
2017-10-27 22:53:04,597 [salt.state       ][INFO    ][32301] Running state [keystone-keys] at time 22:53:04.596894
2017-10-27 22:53:04,597 [salt.state       ][INFO    ][32301] Executing state glusterfs.started for keystone-keys
2017-10-27 22:53:04,598 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,605 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,652 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,660 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,710 [salt.state       ][INFO    ][32301] Volume keystone-keys is already started
2017-10-27 22:53:04,710 [salt.state       ][INFO    ][32301] Completed state [keystone-keys] at time 22:53:04.709903 duration_in_ms=113.008
2017-10-27 22:53:04,711 [salt.state       ][INFO    ][32301] Running state [nova_instances] at time 22:53:04.710905
2017-10-27 22:53:04,711 [salt.state       ][INFO    ][32301] Executing state glusterfs.created for nova_instances
2017-10-27 22:53:04,712 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,718 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,765 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:04,773 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:04,822 [salt.state       ][INFO    ][32301] Volume nova_instances already exists and is started.
2017-10-27 22:53:04,822 [salt.state       ][INFO    ][32301] Completed state [nova_instances] at time 22:53:04.822352 duration_in_ms=111.447
2017-10-27 22:53:04,825 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:04.825032
2017-10-27 22:53:04,825 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:53:04,826 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:04,873 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,873 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:04.872809 duration_in_ms=47.776
2017-10-27 22:53:04,873 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 22:53:04.873208
2017-10-27 22:53:04,873 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-27 22:53:04,874 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-27 22:53:04,921 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,921 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 22:53:04.921441 duration_in_ms=48.232
2017-10-27 22:53:04,922 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 22:53:04.921887
2017-10-27 22:53:04,922 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-27 22:53:04,923 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-27 22:53:04,971 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:04,971 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 22:53:04.970904 duration_in_ms=49.016
2017-10-27 22:53:04,971 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 22:53:04.971326
2017-10-27 22:53:04,972 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-27 22:53:04,972 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-27 22:53:05,016 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:05,017 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 22:53:05.016536 duration_in_ms=45.21
2017-10-27 22:53:05,017 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:05.016920
2017-10-27 22:53:05,017 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:53:05,018 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:05,067 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:05,067 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:05.067235 duration_in_ms=50.314
2017-10-27 22:53:05,069 [salt.state       ][INFO    ][32301] Running state [nova_instances] at time 22:53:05.068609
2017-10-27 22:53:05,069 [salt.state       ][INFO    ][32301] Executing state glusterfs.started for nova_instances
2017-10-27 22:53:05,070 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:05,078 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:05,127 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:05,136 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:05,188 [salt.state       ][INFO    ][32301] Volume nova_instances is already started
2017-10-27 22:53:05,188 [salt.state       ][INFO    ][32301] Completed state [nova_instances] at time 22:53:05.188086 duration_in_ms=119.476
2017-10-27 22:53:05,189 [salt.state       ][INFO    ][32301] Running state [keystone-credential-keys] at time 22:53:05.189018
2017-10-27 22:53:05,189 [salt.state       ][INFO    ][32301] Executing state glusterfs.created for keystone-credential-keys
2017-10-27 22:53:05,190 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:05,199 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:05,248 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:05,255 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:05,305 [salt.state       ][INFO    ][32301] Volume keystone-credential-keys already exists and is started.
2017-10-27 22:53:05,305 [salt.state       ][INFO    ][32301] Completed state [keystone-credential-keys] at time 22:53:05.305042 duration_in_ms=116.024
2017-10-27 22:53:05,308 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 22:53:05.308047
2017-10-27 22:53:05,308 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 22:53:05,309 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 22:53:05,357 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:05,358 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 22:53:05.357498 duration_in_ms=49.45
2017-10-27 22:53:05,358 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:05.357982
2017-10-27 22:53:05,358 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 22:53:05,359 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:05,408 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:05,409 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 22:53:05.408507 duration_in_ms=50.525
2017-10-27 22:53:05,409 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 22:53:05.408937
2017-10-27 22:53:05,409 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-27 22:53:05,410 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 22:53:05,458 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:05,458 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 22:53:05.457931 duration_in_ms=48.993
2017-10-27 22:53:05,458 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:05.458456
2017-10-27 22:53:05,459 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 22:53:05,459 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 22:53:05,507 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:05,507 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 22:53:05.507172 duration_in_ms=48.715
2017-10-27 22:53:05,508 [salt.state       ][INFO    ][32301] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 22:53:05.507671
2017-10-27 22:53:05,508 [salt.state       ][INFO    ][32301] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-27 22:53:05,508 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 22:53:05,558 [salt.state       ][INFO    ][32301] unless execution succeeded
2017-10-27 22:53:05,558 [salt.state       ][INFO    ][32301] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 22:53:05.558170 duration_in_ms=50.499
2017-10-27 22:53:05,559 [salt.state       ][INFO    ][32301] Running state [keystone-credential-keys] at time 22:53:05.559167
2017-10-27 22:53:05,559 [salt.state       ][INFO    ][32301] Executing state glusterfs.started for keystone-credential-keys
2017-10-27 22:53:05,560 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:05,569 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:05,619 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --version' in directory '/root'
2017-10-27 22:53:05,626 [salt.loaded.int.module.cmdmod][INFO    ][32301] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 22:53:05,683 [salt.state       ][INFO    ][32301] Volume keystone-credential-keys is already started
2017-10-27 22:53:05,683 [salt.state       ][INFO    ][32301] Completed state [keystone-credential-keys] at time 22:53:05.683116 duration_in_ms=123.949
2017-10-27 22:53:05,685 [salt.minion      ][INFO    ][32301] Returning information for job: 20171027225300571383
2017-10-27 22:53:10,688 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command test.ping with jid 20171027225310680841
2017-10-27 22:53:10,703 [salt.minion      ][INFO    ][32728] Starting a new job with PID 32728
2017-10-27 22:53:10,728 [salt.minion      ][INFO    ][32728] Returning information for job: 20171027225310680841
2017-10-27 22:53:11,122 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command cmd.run with jid 20171027225311110221
2017-10-27 22:53:11,137 [salt.minion      ][INFO    ][32733] Starting a new job with PID 32733
2017-10-27 22:53:11,141 [salt.loaded.int.module.cmdmod][INFO    ][32733] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-27 22:53:11,254 [salt.minion      ][INFO    ][32733] Returning information for job: 20171027225311110221
2017-10-27 23:10:37,732 [salt.utils.schedule][INFO    ][24551] Running scheduled job: __mine_interval
2017-10-27 23:53:37,115 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command test.ping with jid 20171027235337112025
2017-10-27 23:53:37,139 [salt.minion      ][INFO    ][1453] Starting a new job with PID 1453
2017-10-27 23:53:37,169 [salt.minion      ][INFO    ][1453] Returning information for job: 20171027235337112025
2017-10-27 23:53:39,763 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command state.sls with jid 20171027235339755239
2017-10-27 23:53:39,777 [salt.minion      ][INFO    ][1459] Starting a new job with PID 1459
2017-10-27 23:53:40,199 [salt.state       ][INFO    ][1459] Loading fresh modules for state activity
2017-10-27 23:53:40,226 [salt.fileclient  ][INFO    ][1459] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-27 23:53:40,243 [salt.fileclient  ][INFO    ][1459] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-27 23:53:40,276 [salt.fileclient  ][INFO    ][1459] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 23:53:40,533 [salt.state       ][INFO    ][1459] Running state [lsof] at time 23:53:40.533015
2017-10-27 23:53:40,533 [salt.state       ][INFO    ][1459] Executing state pkg.installed for lsof
2017-10-27 23:53:40,534 [salt.loaded.int.module.cmdmod][INFO    ][1459] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 23:53:40,816 [salt.state       ][INFO    ][1459] Package lsof is already installed
2017-10-27 23:53:40,816 [salt.state       ][INFO    ][1459] Completed state [lsof] at time 23:53:40.816424 duration_in_ms=283.409
2017-10-27 23:53:40,817 [salt.state       ][INFO    ][1459] Running state [keepalived] at time 23:53:40.816696
2017-10-27 23:53:40,817 [salt.state       ][INFO    ][1459] Executing state pkg.installed for keepalived
2017-10-27 23:53:40,820 [salt.state       ][INFO    ][1459] Package keepalived is already installed
2017-10-27 23:53:40,820 [salt.state       ][INFO    ][1459] Completed state [keepalived] at time 23:53:40.819752 duration_in_ms=3.056
2017-10-27 23:53:40,821 [salt.state       ][INFO    ][1459] Running state [/etc/keepalived/keepalived.conf] at time 23:53:40.821122
2017-10-27 23:53:40,821 [salt.state       ][INFO    ][1459] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-27 23:53:40,850 [salt.fileclient  ][INFO    ][1459] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-27 23:53:40,881 [salt.fileclient  ][INFO    ][1459] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-27 23:53:40,885 [salt.state       ][INFO    ][1459] File /etc/keepalived/keepalived.conf is in the correct state
2017-10-27 23:53:40,885 [salt.state       ][INFO    ][1459] Completed state [/etc/keepalived/keepalived.conf] at time 23:53:40.885098 duration_in_ms=63.975
2017-10-27 23:53:40,886 [salt.state       ][INFO    ][1459] Running state [keepalived] at time 23:53:40.885654
2017-10-27 23:53:40,886 [salt.state       ][INFO    ][1459] Executing state service.running for keepalived
2017-10-27 23:53:40,886 [salt.loaded.int.module.cmdmod][INFO    ][1459] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-27 23:53:40,901 [salt.loaded.int.module.cmdmod][INFO    ][1459] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-27 23:53:40,908 [salt.loaded.int.module.cmdmod][INFO    ][1459] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-27 23:53:40,914 [salt.state       ][INFO    ][1459] The service keepalived is already running
2017-10-27 23:53:40,914 [salt.state       ][INFO    ][1459] Completed state [keepalived] at time 23:53:40.914386 duration_in_ms=28.731
2017-10-27 23:53:40,915 [salt.minion      ][INFO    ][1459] Returning information for job: 20171027235339755239
2017-10-27 23:54:09,173 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command pillar.get with jid 20171027235409166751
2017-10-27 23:54:09,191 [salt.minion      ][INFO    ][1487] Starting a new job with PID 1487
2017-10-27 23:54:09,197 [salt.minion      ][INFO    ][1487] Returning information for job: 20171027235409166751
2017-10-27 23:54:46,123 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command state.sls with jid 20171027235446114974
2017-10-27 23:54:46,137 [salt.minion      ][INFO    ][1507] Starting a new job with PID 1507
2017-10-27 23:54:46,538 [salt.state       ][INFO    ][1507] Loading fresh modules for state activity
2017-10-27 23:54:46,559 [salt.fileclient  ][INFO    ][1507] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 23:54:46,581 [salt.fileclient  ][INFO    ][1507] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 23:54:46,850 [salt.state       ][INFO    ][1507] Running state [attr] at time 23:54:46.849646
2017-10-27 23:54:46,850 [salt.state       ][INFO    ][1507] Executing state pkg.installed for attr
2017-10-27 23:54:46,850 [salt.loaded.int.module.cmdmod][INFO    ][1507] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 23:54:47,141 [salt.state       ][INFO    ][1507] Package attr is already installed
2017-10-27 23:54:47,141 [salt.state       ][INFO    ][1507] Completed state [attr] at time 23:54:47.141296 duration_in_ms=291.65
2017-10-27 23:54:47,142 [salt.state       ][INFO    ][1507] Running state [glusterfs-server] at time 23:54:47.141483
2017-10-27 23:54:47,142 [salt.state       ][INFO    ][1507] Executing state pkg.installed for glusterfs-server
2017-10-27 23:54:47,144 [salt.state       ][INFO    ][1507] Package glusterfs-server is already installed
2017-10-27 23:54:47,144 [salt.state       ][INFO    ][1507] Completed state [glusterfs-server] at time 23:54:47.144359 duration_in_ms=2.876
2017-10-27 23:54:47,145 [salt.state       ][INFO    ][1507] Running state [bc] at time 23:54:47.144496
2017-10-27 23:54:47,145 [salt.state       ][INFO    ][1507] Executing state pkg.installed for bc
2017-10-27 23:54:47,147 [salt.state       ][INFO    ][1507] Package bc is already installed
2017-10-27 23:54:47,147 [salt.state       ][INFO    ][1507] Completed state [bc] at time 23:54:47.147328 duration_in_ms=2.831
2017-10-27 23:54:47,148 [salt.state       ][INFO    ][1507] Running state [glusterfs-server] at time 23:54:47.147964
2017-10-27 23:54:47,148 [salt.state       ][INFO    ][1507] Executing state service.running for glusterfs-server
2017-10-27 23:54:47,149 [salt.loaded.int.module.cmdmod][INFO    ][1507] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 23:54:47,163 [salt.loaded.int.module.cmdmod][INFO    ][1507] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:47,170 [salt.loaded.int.module.cmdmod][INFO    ][1507] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:47,178 [salt.state       ][INFO    ][1507] The service glusterfs-server is already running
2017-10-27 23:54:47,178 [salt.state       ][INFO    ][1507] Completed state [glusterfs-server] at time 23:54:47.178190 duration_in_ms=30.225
2017-10-27 23:54:47,180 [salt.state       ][INFO    ][1507] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:47.179621
2017-10-27 23:54:47,180 [salt.state       ][INFO    ][1507] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 23:54:47,186 [salt.state       ][INFO    ][1507] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-27 23:54:47,186 [salt.state       ][INFO    ][1507] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:47.185892 duration_in_ms=6.271
2017-10-27 23:54:47,186 [salt.state       ][INFO    ][1507] Running state [/srv/glusterfs/glance] at time 23:54:47.186095
2017-10-27 23:54:47,186 [salt.state       ][INFO    ][1507] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 23:54:47,187 [salt.state       ][INFO    ][1507] Directory /srv/glusterfs/glance is in the correct state
2017-10-27 23:54:47,187 [salt.state       ][INFO    ][1507] Completed state [/srv/glusterfs/glance] at time 23:54:47.186773 duration_in_ms=0.678
2017-10-27 23:54:47,187 [salt.state       ][INFO    ][1507] Running state [/srv/glusterfs/keystone-keys] at time 23:54:47.186927
2017-10-27 23:54:47,187 [salt.state       ][INFO    ][1507] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 23:54:47,187 [salt.state       ][INFO    ][1507] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-27 23:54:47,188 [salt.state       ][INFO    ][1507] Completed state [/srv/glusterfs/keystone-keys] at time 23:54:47.187568 duration_in_ms=0.641
2017-10-27 23:54:47,188 [salt.state       ][INFO    ][1507] Running state [/srv/glusterfs/nova_instances] at time 23:54:47.187714
2017-10-27 23:54:47,188 [salt.state       ][INFO    ][1507] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 23:54:47,188 [salt.state       ][INFO    ][1507] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-27 23:54:47,188 [salt.state       ][INFO    ][1507] Completed state [/srv/glusterfs/nova_instances] at time 23:54:47.188331 duration_in_ms=0.617
2017-10-27 23:54:47,189 [salt.state       ][INFO    ][1507] Running state [/srv/glusterfs/keystone-credential-keys] at time 23:54:47.188479
2017-10-27 23:54:47,189 [salt.state       ][INFO    ][1507] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 23:54:47,189 [salt.state       ][INFO    ][1507] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-27 23:54:47,189 [salt.state       ][INFO    ][1507] Completed state [/srv/glusterfs/keystone-credential-keys] at time 23:54:47.189099 duration_in_ms=0.62
2017-10-27 23:54:47,190 [salt.minion      ][INFO    ][1507] Returning information for job: 20171027235446114974
2017-10-27 23:54:48,445 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command test.ping with jid 20171027235448432393
2017-10-27 23:54:48,460 [salt.minion      ][INFO    ][1530] Starting a new job with PID 1530
2017-10-27 23:54:48,484 [salt.minion      ][INFO    ][1530] Returning information for job: 20171027235448432393
2017-10-27 23:54:53,518 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command state.sls with jid 20171027235453495426
2017-10-27 23:54:53,533 [salt.minion      ][INFO    ][1536] Starting a new job with PID 1536
2017-10-27 23:54:53,957 [salt.state       ][INFO    ][1536] Loading fresh modules for state activity
2017-10-27 23:54:53,981 [salt.fileclient  ][INFO    ][1536] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-27 23:54:54,012 [salt.fileclient  ][INFO    ][1536] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 23:54:54,075 [salt.fileclient  ][INFO    ][1536] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-27 23:54:54,094 [salt.fileclient  ][INFO    ][1536] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-27 23:54:54,357 [salt.state       ][INFO    ][1536] Running state [attr] at time 23:54:54.357008
2017-10-27 23:54:54,357 [salt.state       ][INFO    ][1536] Executing state pkg.installed for attr
2017-10-27 23:54:54,358 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-27 23:54:54,638 [salt.state       ][INFO    ][1536] Package attr is already installed
2017-10-27 23:54:54,639 [salt.state       ][INFO    ][1536] Completed state [attr] at time 23:54:54.638529 duration_in_ms=281.521
2017-10-27 23:54:54,639 [salt.state       ][INFO    ][1536] Running state [glusterfs-server] at time 23:54:54.638745
2017-10-27 23:54:54,639 [salt.state       ][INFO    ][1536] Executing state pkg.installed for glusterfs-server
2017-10-27 23:54:54,642 [salt.state       ][INFO    ][1536] Package glusterfs-server is already installed
2017-10-27 23:54:54,642 [salt.state       ][INFO    ][1536] Completed state [glusterfs-server] at time 23:54:54.641689 duration_in_ms=2.944
2017-10-27 23:54:54,642 [salt.state       ][INFO    ][1536] Running state [bc] at time 23:54:54.641842
2017-10-27 23:54:54,642 [salt.state       ][INFO    ][1536] Executing state pkg.installed for bc
2017-10-27 23:54:54,645 [salt.state       ][INFO    ][1536] Package bc is already installed
2017-10-27 23:54:54,645 [salt.state       ][INFO    ][1536] Completed state [bc] at time 23:54:54.644692 duration_in_ms=2.849
2017-10-27 23:54:54,646 [salt.state       ][INFO    ][1536] Running state [glusterfs-server] at time 23:54:54.645504
2017-10-27 23:54:54,646 [salt.state       ][INFO    ][1536] Executing state service.running for glusterfs-server
2017-10-27 23:54:54,646 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-27 23:54:54,663 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:54,671 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-27 23:54:54,679 [salt.state       ][INFO    ][1536] The service glusterfs-server is already running
2017-10-27 23:54:54,679 [salt.state       ][INFO    ][1536] Completed state [glusterfs-server] at time 23:54:54.679365 duration_in_ms=33.861
2017-10-27 23:54:54,681 [salt.state       ][INFO    ][1536] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:54.680644
2017-10-27 23:54:54,681 [salt.state       ][INFO    ][1536] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-27 23:54:54,689 [salt.state       ][INFO    ][1536] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-27 23:54:54,689 [salt.state       ][INFO    ][1536] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 23:54:54.688892 duration_in_ms=8.248
2017-10-27 23:54:54,689 [salt.state       ][INFO    ][1536] Running state [/srv/glusterfs/glance] at time 23:54:54.689081
2017-10-27 23:54:54,689 [salt.state       ][INFO    ][1536] Executing state file.directory for /srv/glusterfs/glance
2017-10-27 23:54:54,690 [salt.state       ][INFO    ][1536] Directory /srv/glusterfs/glance is in the correct state
2017-10-27 23:54:54,690 [salt.state       ][INFO    ][1536] Completed state [/srv/glusterfs/glance] at time 23:54:54.689751 duration_in_ms=0.669
2017-10-27 23:54:54,690 [salt.state       ][INFO    ][1536] Running state [/srv/glusterfs/keystone-keys] at time 23:54:54.689905
2017-10-27 23:54:54,690 [salt.state       ][INFO    ][1536] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-27 23:54:54,690 [salt.state       ][INFO    ][1536] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-27 23:54:54,691 [salt.state       ][INFO    ][1536] Completed state [/srv/glusterfs/keystone-keys] at time 23:54:54.690560 duration_in_ms=0.655
2017-10-27 23:54:54,691 [salt.state       ][INFO    ][1536] Running state [/srv/glusterfs/nova_instances] at time 23:54:54.690710
2017-10-27 23:54:54,691 [salt.state       ][INFO    ][1536] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-27 23:54:54,691 [salt.state       ][INFO    ][1536] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-27 23:54:54,691 [salt.state       ][INFO    ][1536] Completed state [/srv/glusterfs/nova_instances] at time 23:54:54.691344 duration_in_ms=0.633
2017-10-27 23:54:54,692 [salt.state       ][INFO    ][1536] Running state [/srv/glusterfs/keystone-credential-keys] at time 23:54:54.691496
2017-10-27 23:54:54,692 [salt.state       ][INFO    ][1536] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-27 23:54:54,692 [salt.state       ][INFO    ][1536] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-27 23:54:54,692 [salt.state       ][INFO    ][1536] Completed state [/srv/glusterfs/keystone-credential-keys] at time 23:54:54.692106 duration_in_ms=0.609
2017-10-27 23:54:54,736 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command ['git', '--version'] in directory '/root'
2017-10-27 23:54:54,817 [salt.state       ][INFO    ][1536] Running state [sleep 5] at time 23:54:54.817373
2017-10-27 23:54:54,818 [salt.state       ][INFO    ][1536] Executing state cmd.wait for sleep 5
2017-10-27 23:54:54,818 [salt.state       ][INFO    ][1536] No changes made for sleep 5
2017-10-27 23:54:54,818 [salt.state       ][INFO    ][1536] Completed state [sleep 5] at time 23:54:54.818033 duration_in_ms=0.66
2017-10-27 23:54:54,818 [salt.state       ][INFO    ][1536] Running state [10.167.4.141] at time 23:54:54.818449
2017-10-27 23:54:54,819 [salt.state       ][INFO    ][1536] Executing state glusterfs.peered for 10.167.4.141
2017-10-27 23:54:54,819 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:54,828 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:54,877 [salt.state       ][INFO    ][1536] Host 10.167.4.141 already peered
2017-10-27 23:54:54,877 [salt.state       ][INFO    ][1536] Completed state [10.167.4.141] at time 23:54:54.876724 duration_in_ms=58.275
2017-10-27 23:54:54,879 [salt.state       ][INFO    ][1536] Running state [10.167.4.142] at time 23:54:54.878952
2017-10-27 23:54:54,879 [salt.state       ][INFO    ][1536] Executing state glusterfs.peered for 10.167.4.142
2017-10-27 23:54:54,880 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:54,888 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:54,938 [salt.state       ][INFO    ][1536] Host 10.167.4.142 already peered
2017-10-27 23:54:54,938 [salt.state       ][INFO    ][1536] Completed state [10.167.4.142] at time 23:54:54.937706 duration_in_ms=58.753
2017-10-27 23:54:54,940 [salt.state       ][INFO    ][1536] Running state [10.167.4.143] at time 23:54:54.939893
2017-10-27 23:54:54,940 [salt.state       ][INFO    ][1536] Executing state glusterfs.peered for 10.167.4.143
2017-10-27 23:54:54,940 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:54,948 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:54,998 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,004 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,054 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,065 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,117 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,126 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,175 [salt.state       ][INFO    ][1536] Peering with localhost is not needed
2017-10-27 23:54:55,175 [salt.state       ][INFO    ][1536] Completed state [10.167.4.143] at time 23:54:55.174869 duration_in_ms=234.975
2017-10-27 23:54:55,176 [salt.state       ][INFO    ][1536] Running state [glance] at time 23:54:55.175662
2017-10-27 23:54:55,176 [salt.state       ][INFO    ][1536] Executing state glusterfs.created for glance
2017-10-27 23:54:55,176 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,185 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,232 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,239 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,291 [salt.state       ][INFO    ][1536] Volume glance already exists and is started.
2017-10-27 23:54:55,292 [salt.state       ][INFO    ][1536] Completed state [glance] at time 23:54:55.291499 duration_in_ms=115.836
2017-10-27 23:54:55,294 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:55.294285
2017-10-27 23:54:55,295 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:55,295 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:55,342 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,343 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:55.342673 duration_in_ms=48.387
2017-10-27 23:54:55,343 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 23:54:55.343184
2017-10-27 23:54:55,343 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-27 23:54:55,344 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 23:54:55,390 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,391 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 23:54:55.390608 duration_in_ms=47.423
2017-10-27 23:54:55,391 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 23:54:55.391030
2017-10-27 23:54:55,391 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-27 23:54:55,392 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 23:54:55,439 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,439 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 23:54:55.438931 duration_in_ms=47.901
2017-10-27 23:54:55,439 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:55.439344
2017-10-27 23:54:55,440 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:55,440 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:55,486 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,486 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:55.485987 duration_in_ms=46.643
2017-10-27 23:54:55,486 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 23:54:55.486437
2017-10-27 23:54:55,487 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-27 23:54:55,487 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 23:54:55,532 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,533 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 23:54:55.532537 duration_in_ms=46.1
2017-10-27 23:54:55,533 [salt.state       ][INFO    ][1536] Running state [glance] at time 23:54:55.533448
2017-10-27 23:54:55,534 [salt.state       ][INFO    ][1536] Executing state glusterfs.started for glance
2017-10-27 23:54:55,534 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,541 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,585 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,600 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,649 [salt.state       ][INFO    ][1536] Volume glance is already started
2017-10-27 23:54:55,650 [salt.state       ][INFO    ][1536] Completed state [glance] at time 23:54:55.649642 duration_in_ms=116.193
2017-10-27 23:54:55,650 [salt.state       ][INFO    ][1536] Running state [keystone-keys] at time 23:54:55.650430
2017-10-27 23:54:55,651 [salt.state       ][INFO    ][1536] Executing state glusterfs.created for keystone-keys
2017-10-27 23:54:55,651 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,660 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,706 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:55,714 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:55,761 [salt.state       ][INFO    ][1536] Volume keystone-keys already exists and is started.
2017-10-27 23:54:55,762 [salt.state       ][INFO    ][1536] Completed state [keystone-keys] at time 23:54:55.761605 duration_in_ms=111.175
2017-10-27 23:54:55,764 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 23:54:55.764250
2017-10-27 23:54:55,764 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-27 23:54:55,765 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 23:54:55,811 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,811 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 23:54:55.810748 duration_in_ms=46.498
2017-10-27 23:54:55,811 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:55.811113
2017-10-27 23:54:55,811 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:55,812 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:55,857 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,857 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:55.856982 duration_in_ms=45.87
2017-10-27 23:54:55,857 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:55.857372
2017-10-27 23:54:55,858 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:55,858 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:55,905 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,905 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:55.904787 duration_in_ms=47.415
2017-10-27 23:54:55,905 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 23:54:55.905243
2017-10-27 23:54:55,905 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-27 23:54:55,906 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 23:54:55,954 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:55,954 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 23:54:55.953967 duration_in_ms=48.722
2017-10-27 23:54:55,954 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:55.954406
2017-10-27 23:54:55,955 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 23:54:55,955 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 23:54:56,004 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,004 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:56.004002 duration_in_ms=49.596
2017-10-27 23:54:56,005 [salt.state       ][INFO    ][1536] Running state [keystone-keys] at time 23:54:56.004909
2017-10-27 23:54:56,005 [salt.state       ][INFO    ][1536] Executing state glusterfs.started for keystone-keys
2017-10-27 23:54:56,006 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,012 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,062 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,070 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,130 [salt.state       ][INFO    ][1536] Volume keystone-keys is already started
2017-10-27 23:54:56,130 [salt.state       ][INFO    ][1536] Completed state [keystone-keys] at time 23:54:56.130131 duration_in_ms=125.221
2017-10-27 23:54:56,131 [salt.state       ][INFO    ][1536] Running state [nova_instances] at time 23:54:56.131130
2017-10-27 23:54:56,131 [salt.state       ][INFO    ][1536] Executing state glusterfs.created for nova_instances
2017-10-27 23:54:56,132 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,140 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,189 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,197 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,247 [salt.state       ][INFO    ][1536] Volume nova_instances already exists and is started.
2017-10-27 23:54:56,247 [salt.state       ][INFO    ][1536] Completed state [nova_instances] at time 23:54:56.247380 duration_in_ms=116.249
2017-10-27 23:54:56,250 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:56.250129
2017-10-27 23:54:56,250 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:56,251 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:56,298 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,299 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:56.298613 duration_in_ms=48.484
2017-10-27 23:54:56,299 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 23:54:56.298999
2017-10-27 23:54:56,299 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-27 23:54:56,300 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-27 23:54:56,348 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,348 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 23:54:56.348402 duration_in_ms=49.403
2017-10-27 23:54:56,349 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 23:54:56.348822
2017-10-27 23:54:56,349 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-27 23:54:56,349 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-27 23:54:56,399 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,400 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 23:54:56.399669 duration_in_ms=50.846
2017-10-27 23:54:56,400 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 23:54:56.400138
2017-10-27 23:54:56,400 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-27 23:54:56,401 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-27 23:54:56,450 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,451 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 23:54:56.450557 duration_in_ms=50.418
2017-10-27 23:54:56,451 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:56.451071
2017-10-27 23:54:56,451 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:56,452 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:56,503 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,503 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:56.503357 duration_in_ms=52.286
2017-10-27 23:54:56,504 [salt.state       ][INFO    ][1536] Running state [nova_instances] at time 23:54:56.504319
2017-10-27 23:54:56,505 [salt.state       ][INFO    ][1536] Executing state glusterfs.started for nova_instances
2017-10-27 23:54:56,505 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,514 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,561 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,570 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,624 [salt.state       ][INFO    ][1536] Volume nova_instances is already started
2017-10-27 23:54:56,625 [salt.state       ][INFO    ][1536] Completed state [nova_instances] at time 23:54:56.624571 duration_in_ms=120.251
2017-10-27 23:54:56,625 [salt.state       ][INFO    ][1536] Running state [keystone-credential-keys] at time 23:54:56.625384
2017-10-27 23:54:56,626 [salt.state       ][INFO    ][1536] Executing state glusterfs.created for keystone-credential-keys
2017-10-27 23:54:56,626 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,635 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,682 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:56,690 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:56,743 [salt.state       ][INFO    ][1536] Volume keystone-credential-keys already exists and is started.
2017-10-27 23:54:56,743 [salt.state       ][INFO    ][1536] Completed state [keystone-credential-keys] at time 23:54:56.742940 duration_in_ms=117.555
2017-10-27 23:54:56,746 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:56.746064
2017-10-27 23:54:56,746 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-27 23:54:56,747 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-27 23:54:56,800 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,800 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 23:54:56.800416 duration_in_ms=54.351
2017-10-27 23:54:56,801 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:56.800875
2017-10-27 23:54:56,801 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-27 23:54:56,802 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:56,853 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,853 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 23:54:56.852874 duration_in_ms=51.998
2017-10-27 23:54:56,853 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 23:54:56.853328
2017-10-27 23:54:56,854 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-27 23:54:56,857 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-27 23:54:56,908 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,908 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 23:54:56.908016 duration_in_ms=54.688
2017-10-27 23:54:56,909 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:56.908975
2017-10-27 23:54:56,909 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-27 23:54:56,910 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-27 23:54:56,957 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:56,957 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 23:54:56.957174 duration_in_ms=48.2
2017-10-27 23:54:56,958 [salt.state       ][INFO    ][1536] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 23:54:56.957642
2017-10-27 23:54:56,958 [salt.state       ][INFO    ][1536] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-27 23:54:56,958 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-27 23:54:57,011 [salt.state       ][INFO    ][1536] unless execution succeeded
2017-10-27 23:54:57,011 [salt.state       ][INFO    ][1536] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 23:54:57.010936 duration_in_ms=53.294
2017-10-27 23:54:57,012 [salt.state       ][INFO    ][1536] Running state [keystone-credential-keys] at time 23:54:57.011901
2017-10-27 23:54:57,012 [salt.state       ][INFO    ][1536] Executing state glusterfs.started for keystone-credential-keys
2017-10-27 23:54:57,013 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:57,022 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:57,070 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --version' in directory '/root'
2017-10-27 23:54:57,078 [salt.loaded.int.module.cmdmod][INFO    ][1536] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-27 23:54:57,128 [salt.state       ][INFO    ][1536] Volume keystone-credential-keys is already started
2017-10-27 23:54:57,128 [salt.state       ][INFO    ][1536] Completed state [keystone-credential-keys] at time 23:54:57.128328 duration_in_ms=116.427
2017-10-27 23:54:57,131 [salt.minion      ][INFO    ][1536] Returning information for job: 20171027235453495426
2017-10-27 23:55:02,070 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command test.ping with jid 20171027235502063823
2017-10-27 23:55:02,083 [salt.minion      ][INFO    ][1920] Starting a new job with PID 1920
2017-10-27 23:55:02,110 [salt.minion      ][INFO    ][1920] Returning information for job: 20171027235502063823
2017-10-27 23:55:02,498 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command cmd.run with jid 20171027235502490937
2017-10-27 23:55:02,515 [salt.minion      ][INFO    ][1925] Starting a new job with PID 1925
2017-10-27 23:55:02,519 [salt.loaded.int.module.cmdmod][INFO    ][1925] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-27 23:55:02,630 [salt.minion      ][INFO    ][1925] Returning information for job: 20171027235502490937
2017-10-28 00:10:37,733 [salt.utils.schedule][INFO    ][24551] Running scheduled job: __mine_interval
2017-10-28 00:23:54,762 [salt.minion      ][INFO    ][24551] User sudo_ubuntu Executing command cp.push_dir with jid 20171028002354758856
2017-10-28 00:23:54,785 [salt.minion      ][INFO    ][2298] Starting a new job with PID 2298
