2017-10-30 09:28:09,831 [salt.cli.daemons ][WARNING ][2459] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-30 09:29:09,793 [salt.state       ][ERROR   ][3965] No contents found in top file
2017-10-30 09:31:51,472 [salt.utils.parsers][WARNING ][2459] Minion received a SIGTERM. Exiting.
2017-10-30 09:36:16,181 [salt.cli.daemons ][WARNING ][3557] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-30 09:38:34,628 [salt.loaded.int.module.cmdmod][ERROR   ][11710] Command 'virsh net-destroy default 2>&1 1>/dev/null; virsh net-undefine default' failed with return code: 1
2017-10-30 09:38:34,629 [salt.loaded.int.module.cmdmod][ERROR   ][11710] stderr: error: failed to get network 'default'
error: Network not found: no network with matching name 'default'
2017-10-30 09:38:34,629 [salt.loaded.int.module.cmdmod][ERROR   ][11710] retcode: 1
2017-10-30 09:38:34,629 [salt.state       ][ERROR   ][11710] {'pid': 16636, 'retcode': 1, 'stderr': "error: failed to get network 'default'\nerror: Network not found: no network with matching name 'default'", 'stdout': ''}
2017-10-30 09:38:51,320 [py.warnings      ][WARNING ][16713] /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-30 09:38:58,599 [salt.transport.ipc][ERROR   ][3557] Exception occurred while handling stream: [Errno 0] Success
2017-10-30 09:39:18,953 [salt.loaded.int.module.cmdmod][ERROR   ][16713] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:18,954 [salt.loaded.int.module.cmdmod][ERROR   ][16713] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:24,123 [salt.loaded.int.module.cmdmod][ERROR   ][16713] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:24,125 [salt.loaded.int.module.cmdmod][ERROR   ][16713] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:29,071 [salt.loaded.int.module.cmdmod][ERROR   ][16713] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:29,072 [salt.loaded.int.module.cmdmod][ERROR   ][16713] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:34,403 [salt.loaded.int.module.cmdmod][ERROR   ][16713] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:34,403 [salt.loaded.int.module.cmdmod][ERROR   ][16713] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:38,406 [salt.loaded.int.module.cmdmod][ERROR   ][16713] 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-30 09:39:42,741 [salt.state       ][ERROR   ][24213] No contents found in top file
2017-10-30 09:39:47,540 [salt.loaded.int.module.cmdmod][INFO    ][24515] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-30 09:39:47,555 [salt.loaded.int.module.cmdmod][INFO    ][24515] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-30 09:39:47,583 [salt.loaded.int.module.cmdmod][INFO    ][24515] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-10-30 09:39:47,606 [salt.utils.parsers][WARNING ][3557] Minion received a SIGTERM. Exiting.
2017-10-30 09:39:48,038 [salt.cli.daemons ][INFO    ][24616] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-ovs-dpdk-ha.local"
2017-10-30 09:39:48,957 [salt.minion      ][INFO    ][24616] Creating minion process manager
2017-10-30 09:39:48,958 [salt.cli.daemons ][WARNING ][24616] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-30 09:39:48,959 [salt.cli.daemons ][INFO    ][24616] The Salt Minion is starting up
2017-10-30 09:39:48,959 [salt.minion      ][INFO    ][24616] Minion is starting as user 'root'
2017-10-30 09:39:48,961 [salt.utils.event ][INFO    ][24616] Starting pull socket on /var/run/salt/minion/minion_event_0031115955_pull.ipc
2017-10-30 09:39:50,359 [salt.loaded.int.module.cmdmod][INFO    ][24616] Executing command ['date', '+%z'] in directory '/root'
2017-10-30 09:39:50,381 [salt.utils.schedule][INFO    ][24616] Updating job settings for scheduled job: __mine_interval
2017-10-30 09:39:50,387 [salt.minion      ][INFO    ][24616] Added mine.update to scheduler
2017-10-30 09:39:51,071 [salt.minion      ][INFO    ][24616] Minion is ready to receive requests!
2017-10-30 09:39:52,073 [salt.utils.schedule][INFO    ][24616] Running scheduled job: __mine_interval
2017-10-30 09:40:01,074 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command state.sls with jid 20171030094001063456
2017-10-30 09:40:01,099 [salt.minion      ][INFO    ][24741] Starting a new job with PID 24741
2017-10-30 09:40:03,786 [salt.state       ][INFO    ][24741] Loading fresh modules for state activity
2017-10-30 09:40:03,824 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-10-30 09:40:03,852 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-10-30 09:40:03,889 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:03,992 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-10-30 09:40:04,008 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-10-30 09:40:04,048 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:04,126 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-30 09:40:04,313 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-30 09:40:04,459 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-30 09:40:04,485 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-30 09:40:04,501 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:40:04,523 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-30 09:40:04,535 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-30 09:40:04,561 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-30 09:40:04,585 [py.warnings      ][WARNING ][24741] /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-30 09:40:04,586 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'salt-minion --version' in directory '/root'
2017-10-30 09:40:04,912 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-30 09:40:05,131 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-30 09:40:05,147 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-30 09:40:05,161 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-30 09:40:05,229 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-30 09:40:05,274 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-30 09:40:05,369 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-10-30 09:40:05,402 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:05,478 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-30 09:40:05,506 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-30 09:40:05,574 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-30 09:40:05,605 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-30 09:40:05,626 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:40:05,651 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-30 09:40:05,666 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-30 09:40:05,718 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-30 09:40:05,746 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'salt-minion --version' in directory '/root'
2017-10-30 09:40:06,069 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-30 09:40:06,090 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-30 09:40:06,104 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-30 09:40:06,117 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-30 09:40:06,182 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-30 09:40:06,456 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-30 09:40:06,561 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-10-30 09:40:06,655 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:07,106 [salt.state       ][INFO    ][24741] Running state [salt-minion] at time 09:40:07.106123
2017-10-30 09:40:07,106 [salt.state       ][INFO    ][24741] Executing state pkg.installed for salt-minion
2017-10-30 09:40:07,107 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:40:07,490 [salt.state       ][INFO    ][24741] Package salt-minion is already installed
2017-10-30 09:40:07,491 [salt.state       ][INFO    ][24741] Completed state [salt-minion] at time 09:40:07.490517 duration_in_ms=384.394
2017-10-30 09:40:07,491 [salt.state       ][INFO    ][24741] Running state [salt_minion_dependency_packages] at time 09:40:07.491329
2017-10-30 09:40:07,492 [salt.state       ][INFO    ][24741] Executing state pkg.installed for salt_minion_dependency_packages
2017-10-30 09:40:07,497 [salt.state       ][INFO    ][24741] All specified packages are already installed
2017-10-30 09:40:07,498 [salt.state       ][INFO    ][24741] Completed state [salt_minion_dependency_packages] at time 09:40:07.497543 duration_in_ms=6.214
2017-10-30 09:40:07,499 [salt.state       ][INFO    ][24741] Running state [/etc/salt/minion.d/minion.conf] at time 09:40:07.499266
2017-10-30 09:40:07,500 [salt.state       ][INFO    ][24741] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-10-30 09:40:07,521 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-10-30 09:40:07,610 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:07,681 [salt.fileclient  ][INFO    ][24741] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-30 09:40:07,749 [salt.state       ][INFO    ][24741] File /etc/salt/minion.d/minion.conf is in the correct state
2017-10-30 09:40:07,750 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/minion.d/minion.conf] at time 09:40:07.750117 duration_in_ms=250.851
2017-10-30 09:40:07,751 [salt.state       ][INFO    ][24741] Running state [/etc/salt/minion.d/_virt.conf] at time 09:40:07.750535
2017-10-30 09:40:07,751 [salt.state       ][INFO    ][24741] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-10-30 09:40:07,751 [salt.state       ][INFO    ][24741] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-10-30 09:40:07,752 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:40:07.751602 duration_in_ms=1.067
2017-10-30 09:40:07,754 [salt.state       ][INFO    ][24741] Running state [salt-minion] at time 09:40:07.753631
2017-10-30 09:40:07,754 [salt.state       ][INFO    ][24741] Executing state service.running for salt-minion
2017-10-30 09:40:07,754 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-30 09:40:07,782 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-10-30 09:40:07,798 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-30 09:40:07,815 [salt.state       ][INFO    ][24741] The service salt-minion is already running
2017-10-30 09:40:07,817 [salt.state       ][INFO    ][24741] Completed state [salt-minion] at time 09:40:07.816476 duration_in_ms=62.845
2017-10-30 09:40:07,821 [salt.state       ][INFO    ][24741] Running state [/etc/salt/grains.d] at time 09:40:07.821019
2017-10-30 09:40:07,822 [salt.state       ][INFO    ][24741] Executing state file.directory for /etc/salt/grains.d
2017-10-30 09:40:07,823 [salt.state       ][INFO    ][24741] Directory /etc/salt/grains.d is in the correct state
2017-10-30 09:40:07,823 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/grains.d] at time 09:40:07.822806 duration_in_ms=1.787
2017-10-30 09:40:07,824 [salt.state       ][INFO    ][24741] Running state [/etc/salt/grains] at time 09:40:07.824238
2017-10-30 09:40:07,825 [salt.state       ][INFO    ][24741] Executing state file.managed for /etc/salt/grains
2017-10-30 09:40:07,825 [salt.state       ][INFO    ][24741] File /etc/salt/grains exists with proper permissions. No changes made.
2017-10-30 09:40:07,826 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/grains] at time 09:40:07.825791 duration_in_ms=1.554
2017-10-30 09:40:07,826 [salt.state       ][INFO    ][24741] Running state [/etc/salt/grains.d/placeholder] at time 09:40:07.826375
2017-10-30 09:40:07,827 [salt.state       ][INFO    ][24741] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-10-30 09:40:07,827 [salt.state       ][INFO    ][24741] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-10-30 09:40:07,828 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/grains.d/placeholder] at time 09:40:07.828414 duration_in_ms=2.04
2017-10-30 09:40:07,829 [salt.state       ][INFO    ][24741] Running state [/etc/salt/grains.d/sphinx] at time 09:40:07.828973
2017-10-30 09:40:07,829 [salt.state       ][INFO    ][24741] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-10-30 09:40:07,833 [salt.state       ][INFO    ][24741] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 127.0.0.1
               - 192.168.10.142
               - 192.168.11.252
-              - 192.168.122.1
         system:
           name: System
           param:

2017-10-30 09:40:07,833 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/grains.d/sphinx] at time 09:40:07.833324 duration_in_ms=4.351
2017-10-30 09:40:07,834 [salt.state       ][INFO    ][24741] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.834058
2017-10-30 09:40:07,834 [salt.state       ][INFO    ][24741] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-30 09:40:07,835 [salt.state       ][INFO    ][24741] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-10-30 09:40:07,835 [salt.state       ][INFO    ][24741] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.834894 duration_in_ms=0.836
2017-10-30 09:40:07,835 [salt.state       ][INFO    ][24741] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.835071
2017-10-30 09:40:07,835 [salt.state       ][INFO    ][24741] 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-30 09:40:07,836 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-10-30 09:40:07,978 [salt.state       ][INFO    ][24741] {'pid': 24799, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-30 09:40:07,978 [salt.state       ][INFO    ][24741] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.978178 duration_in_ms=143.106
2017-10-30 09:40:07,979 [salt.state       ][INFO    ][24741] Running state [/etc/salt/grains.d/dns_records] at time 09:40:07.979049
2017-10-30 09:40:07,979 [salt.state       ][INFO    ][24741] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-10-30 09:40:07,981 [salt.state       ][INFO    ][24741] File /etc/salt/grains.d/dns_records is in the correct state
2017-10-30 09:40:07,981 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/grains.d/dns_records] at time 09:40:07.981186 duration_in_ms=2.137
2017-10-30 09:40:07,982 [salt.state       ][INFO    ][24741] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.982057
2017-10-30 09:40:07,982 [salt.state       ][INFO    ][24741] 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-30 09:40:07,983 [salt.state       ][INFO    ][24741] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-10-30 09:40:07,983 [salt.state       ][INFO    ][24741] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.982706 duration_in_ms=0.65
2017-10-30 09:40:07,983 [salt.state       ][INFO    ][24741] Running state [/etc/salt/grains.d/salt] at time 09:40:07.983097
2017-10-30 09:40:07,983 [salt.state       ][INFO    ][24741] Executing state file.managed for /etc/salt/grains.d/salt
2017-10-30 09:40:07,985 [salt.state       ][INFO    ][24741] File /etc/salt/grains.d/salt is in the correct state
2017-10-30 09:40:07,985 [salt.state       ][INFO    ][24741] Completed state [/etc/salt/grains.d/salt] at time 09:40:07.984838 duration_in_ms=1.74
2017-10-30 09:40:07,986 [salt.state       ][INFO    ][24741] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.985612
2017-10-30 09:40:07,986 [salt.state       ][INFO    ][24741] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-30 09:40:07,986 [salt.state       ][INFO    ][24741] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-10-30 09:40:07,986 [salt.state       ][INFO    ][24741] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:40:07.986248 duration_in_ms=0.635
2017-10-30 09:40:07,988 [salt.state       ][INFO    ][24741] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:07.988029
2017-10-30 09:40:07,988 [salt.state       ][INFO    ][24741] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-30 09:40:07,989 [salt.state       ][INFO    ][24741] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-30 09:40:07,989 [salt.state       ][INFO    ][24741] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:07.988962 duration_in_ms=0.934
2017-10-30 09:40:07,989 [salt.state       ][INFO    ][24741] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:07.989127
2017-10-30 09:40:07,989 [salt.state       ][INFO    ][24741] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-30 09:40:07,991 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-10-30 09:40:08,002 [salt.state       ][INFO    ][24741] {'pid': 24801, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-30 09:40:08,002 [salt.state       ][INFO    ][24741] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:08.002234 duration_in_ms=13.106
2017-10-30 09:40:08,003 [salt.state       ][INFO    ][24741] Running state [mine.update] at time 09:40:08.003041
2017-10-30 09:40:08,003 [salt.state       ][INFO    ][24741] Executing state module.wait for mine.update
2017-10-30 09:40:08,004 [salt.state       ][INFO    ][24741] No changes made for mine.update
2017-10-30 09:40:08,004 [salt.state       ][INFO    ][24741] Completed state [mine.update] at time 09:40:08.004295 duration_in_ms=1.254
2017-10-30 09:40:08,005 [salt.state       ][INFO    ][24741] Running state [mine.update] at time 09:40:08.004732
2017-10-30 09:40:08,005 [salt.state       ][INFO    ][24741] Executing state module.mod_watch for mine.update
2017-10-30 09:40:08,535 [salt.state       ][INFO    ][24741] {'ret': True}
2017-10-30 09:40:08,535 [salt.state       ][INFO    ][24741] Completed state [mine.update] at time 09:40:08.534986 duration_in_ms=530.253
2017-10-30 09:40:08,536 [salt.state       ][INFO    ][24741] Running state [ca-certificates] at time 09:40:08.536072
2017-10-30 09:40:08,536 [salt.state       ][INFO    ][24741] Executing state pkg.installed for ca-certificates
2017-10-30 09:40:08,545 [salt.state       ][INFO    ][24741] Package ca-certificates is already installed
2017-10-30 09:40:08,545 [salt.state       ][INFO    ][24741] Completed state [ca-certificates] at time 09:40:08.545326 duration_in_ms=9.254
2017-10-30 09:40:08,546 [salt.state       ][INFO    ][24741] Running state [update-ca-certificates] at time 09:40:08.546215
2017-10-30 09:40:08,547 [salt.state       ][INFO    ][24741] Executing state cmd.wait for update-ca-certificates
2017-10-30 09:40:08,547 [salt.state       ][INFO    ][24741] No changes made for update-ca-certificates
2017-10-30 09:40:08,547 [salt.state       ][INFO    ][24741] Completed state [update-ca-certificates] at time 09:40:08.547002 duration_in_ms=0.787
2017-10-30 09:40:08,547 [salt.state       ][INFO    ][24741] Running state [libvirt-dev] at time 09:40:08.547263
2017-10-30 09:40:08,548 [salt.state       ][INFO    ][24741] Executing state pkg.installed for libvirt-dev
2017-10-30 09:40:08,554 [salt.state       ][INFO    ][24741] Package libvirt-dev is already installed
2017-10-30 09:40:08,555 [salt.state       ][INFO    ][24741] Completed state [libvirt-dev] at time 09:40:08.554735 duration_in_ms=7.472
2017-10-30 09:40:08,555 [salt.state       ][INFO    ][24741] Running state [pkg-config] at time 09:40:08.554933
2017-10-30 09:40:08,555 [salt.state       ][INFO    ][24741] Executing state pkg.installed for pkg-config
2017-10-30 09:40:08,559 [salt.state       ][INFO    ][24741] Package pkg-config is already installed
2017-10-30 09:40:08,559 [salt.state       ][INFO    ][24741] Completed state [pkg-config] at time 09:40:08.559108 duration_in_ms=4.175
2017-10-30 09:40:08,559 [salt.state       ][INFO    ][24741] Running state [virtng.init] at time 09:40:08.559318
2017-10-30 09:40:08,560 [salt.state       ][INFO    ][24741] Executing state module.run for virtng.init
2017-10-30 09:40:08,632 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command ['git', '--version'] in directory '/root'
2017-10-30 09:40:08,706 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:08,737 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:08,738 [salt.state       ][INFO    ][24741] Completed state [virtng.init] at time 09:40:08.737547 duration_in_ms=178.228
2017-10-30 09:40:08,738 [salt.state       ][INFO    ][24741] Running state [virt.set_autostart] at time 09:40:08.737907
2017-10-30 09:40:08,738 [salt.state       ][INFO    ][24741] Executing state module.run for virt.set_autostart
2017-10-30 09:40:08,745 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:08,778 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:08,778 [salt.state       ][INFO    ][24741] Completed state [virt.set_autostart] at time 09:40:08.778221 duration_in_ms=40.314
2017-10-30 09:40:08,779 [salt.state       ][INFO    ][24741] Running state [virtng.init] at time 09:40:08.778531
2017-10-30 09:40:08,779 [salt.state       ][INFO    ][24741] Executing state module.run for virtng.init
2017-10-30 09:40:08,786 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:08,825 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:08,826 [salt.state       ][INFO    ][24741] Completed state [virtng.init] at time 09:40:08.825666 duration_in_ms=47.134
2017-10-30 09:40:08,827 [salt.state       ][INFO    ][24741] Running state [virt.set_autostart] at time 09:40:08.826791
2017-10-30 09:40:08,827 [salt.state       ][INFO    ][24741] Executing state module.run for virt.set_autostart
2017-10-30 09:40:08,837 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:08,875 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:08,875 [salt.state       ][INFO    ][24741] Completed state [virt.set_autostart] at time 09:40:08.875190 duration_in_ms=48.399
2017-10-30 09:40:08,877 [salt.state       ][INFO    ][24741] Running state [virtng.init] at time 09:40:08.875492
2017-10-30 09:40:08,878 [salt.state       ][INFO    ][24741] Executing state module.run for virtng.init
2017-10-30 09:40:08,882 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:08,919 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:08,919 [salt.state       ][INFO    ][24741] Completed state [virtng.init] at time 09:40:08.919082 duration_in_ms=43.59
2017-10-30 09:40:08,919 [salt.state       ][INFO    ][24741] Running state [virt.set_autostart] at time 09:40:08.919369
2017-10-30 09:40:08,920 [salt.state       ][INFO    ][24741] Executing state module.run for virt.set_autostart
2017-10-30 09:40:08,923 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:08,962 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:08,962 [salt.state       ][INFO    ][24741] Completed state [virt.set_autostart] at time 09:40:08.962367 duration_in_ms=42.998
2017-10-30 09:40:08,963 [salt.state       ][INFO    ][24741] Running state [virtng.init] at time 09:40:08.962647
2017-10-30 09:40:08,963 [salt.state       ][INFO    ][24741] Executing state module.run for virtng.init
2017-10-30 09:40:08,967 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:09,002 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:09,002 [salt.state       ][INFO    ][24741] Completed state [virtng.init] at time 09:40:09.001849 duration_in_ms=39.201
2017-10-30 09:40:09,002 [salt.state       ][INFO    ][24741] Running state [virt.set_autostart] at time 09:40:09.002134
2017-10-30 09:40:09,002 [salt.state       ][INFO    ][24741] Executing state module.run for virt.set_autostart
2017-10-30 09:40:09,007 [salt.loaded.int.module.cmdmod][INFO    ][24741] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:09,041 [salt.state       ][INFO    ][24741] unless execution succeeded
2017-10-30 09:40:09,042 [salt.state       ][INFO    ][24741] Completed state [virt.set_autostart] at time 09:40:09.041591 duration_in_ms=39.455
2017-10-30 09:40:09,047 [salt.minion      ][INFO    ][24741] Returning information for job: 20171030094001063456
2017-10-30 09:40:35,150 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171030094035138274
2017-10-30 09:40:35,179 [salt.minion      ][INFO    ][24849] Starting a new job with PID 24849
2017-10-30 09:40:39,600 [salt.state       ][INFO    ][24849] Loading fresh modules for state activity
2017-10-30 09:40:40,185 [salt.state       ][ERROR   ][24849] No contents found in top file
2017-10-30 09:40:40,186 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-10-30 09:40:40,193 [salt.utils.extmods][INFO    ][24849] Syncing beacons for environment 'base'
2017-10-30 09:40:40,193 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_beacons, for base)
2017-10-30 09:40:40,193 [salt.fileclient  ][INFO    ][24849] Caching directory '_beacons/' for environment 'base'
2017-10-30 09:40:40,277 [salt.utils.extmods][INFO    ][24849] Syncing modules for environment 'base'
2017-10-30 09:40:40,277 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_modules, for base)
2017-10-30 09:40:40,277 [salt.fileclient  ][INFO    ][24849] Caching directory '_modules/' for environment 'base'
2017-10-30 09:40:40,398 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-10-30 09:40:40,416 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-10-30 09:40:40,438 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-10-30 09:40:40,458 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-10-30 09:40:40,470 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-10-30 09:40:40,487 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-10-30 09:40:40,525 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-10-30 09:40:40,546 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-10-30 09:40:40,566 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-10-30 09:40:40,583 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-10-30 09:40:40,615 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-10-30 09:40:40,634 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-10-30 09:40:40,657 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-10-30 09:40:40,678 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-10-30 09:40:40,694 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-10-30 09:40:40,709 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-10-30 09:40:40,723 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-10-30 09:40:40,737 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-10-30 09:40:40,755 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-10-30 09:40:40,786 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-10-30 09:40:40,802 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-10-30 09:40:40,820 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-10-30 09:40:40,835 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-10-30 09:40:40,849 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-10-30 09:40:40,898 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-10-30 09:40:40,917 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-10-30 09:40:40,936 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-10-30 09:40:40,965 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-10-30 09:40:40,983 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-10-30 09:40:41,019 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-10-30 09:40:41,046 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-10-30 09:40:41,097 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-10-30 09:40:41,106 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-10-30 09:40:41,108 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-10-30 09:40:41,108 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-10-30 09:40:41,109 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-10-30 09:40:41,109 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-10-30 09:40:41,111 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-10-30 09:40:41,111 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-10-30 09:40:41,111 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-10-30 09:40:41,113 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-10-30 09:40:41,113 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-10-30 09:40:41,113 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-10-30 09:40:41,113 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-10-30 09:40:41,114 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-10-30 09:40:41,114 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-10-30 09:40:41,114 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-10-30 09:40:41,114 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-10-30 09:40:41,115 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-10-30 09:40:41,115 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-10-30 09:40:41,115 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-10-30 09:40:41,115 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-10-30 09:40:41,116 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-10-30 09:40:41,116 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-10-30 09:40:41,116 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-10-30 09:40:41,117 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-10-30 09:40:41,117 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-10-30 09:40:41,118 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-10-30 09:40:41,119 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-10-30 09:40:41,119 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-10-30 09:40:41,120 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-10-30 09:40:41,121 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-10-30 09:40:41,121 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-10-30 09:40:41,121 [salt.utils.extmods][INFO    ][24849] 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-30 09:40:41,122 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-10-30 09:40:41,122 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-10-30 09:40:41,135 [salt.utils.extmods][INFO    ][24849] Syncing states for environment 'base'
2017-10-30 09:40:41,136 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_states, for base)
2017-10-30 09:40:41,137 [salt.fileclient  ][INFO    ][24849] Caching directory '_states/' for environment 'base'
2017-10-30 09:40:41,238 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-10-30 09:40:41,261 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-10-30 09:40:41,288 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-10-30 09:40:41,309 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-10-30 09:40:41,331 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-10-30 09:40:41,386 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-10-30 09:40:41,419 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-10-30 09:40:41,447 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-10-30 09:40:41,467 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-10-30 09:40:41,487 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-10-30 09:40:41,513 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-10-30 09:40:41,546 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-10-30 09:40:41,577 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-10-30 09:40:41,598 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-10-30 09:40:41,619 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-10-30 09:40:41,646 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-10-30 09:40:41,661 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-10-30 09:40:41,683 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-10-30 09:40:41,723 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-10-30 09:40:41,744 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-10-30 09:40:41,781 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-10-30 09:40:41,817 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-10-30 09:40:41,849 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-10-30 09:40:41,885 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-10-30 09:40:41,935 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-10-30 09:40:41,959 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-10-30 09:40:41,985 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-10-30 09:40:42,009 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-10-30 09:40:42,038 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-10-30 09:40:42,047 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-10-30 09:40:42,048 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-10-30 09:40:42,048 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-10-30 09:40:42,049 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-10-30 09:40:42,049 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-10-30 09:40:42,050 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-10-30 09:40:42,050 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-10-30 09:40:42,050 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-10-30 09:40:42,051 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-10-30 09:40:42,051 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-10-30 09:40:42,052 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-10-30 09:40:42,054 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-10-30 09:40:42,055 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-10-30 09:40:42,055 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-10-30 09:40:42,055 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-10-30 09:40:42,057 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-10-30 09:40:42,057 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-10-30 09:40:42,057 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-10-30 09:40:42,058 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-10-30 09:40:42,058 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-10-30 09:40:42,058 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-10-30 09:40:42,058 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-10-30 09:40:42,059 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-10-30 09:40:42,059 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-10-30 09:40:42,059 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-10-30 09:40:42,059 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-10-30 09:40:42,060 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-10-30 09:40:42,060 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-10-30 09:40:42,061 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-10-30 09:40:42,061 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-10-30 09:40:42,065 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-10-30 09:40:42,074 [salt.utils.extmods][INFO    ][24849] Syncing sdb for environment 'base'
2017-10-30 09:40:42,075 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_sdb, for base)
2017-10-30 09:40:42,075 [salt.fileclient  ][INFO    ][24849] Caching directory '_sdb/' for environment 'base'
2017-10-30 09:40:42,130 [salt.utils.extmods][INFO    ][24849] Syncing grains for environment 'base'
2017-10-30 09:40:42,130 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_grains, for base)
2017-10-30 09:40:42,130 [salt.fileclient  ][INFO    ][24849] Caching directory '_grains/' for environment 'base'
2017-10-30 09:40:42,194 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-10-30 09:40:42,225 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-10-30 09:40:42,249 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-10-30 09:40:42,266 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-10-30 09:40:42,285 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-10-30 09:40:42,298 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-10-30 09:40:42,333 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-10-30 09:40:42,369 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-10-30 09:40:42,392 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-10-30 09:40:42,433 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-10-30 09:40:42,457 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-10-30 09:40:42,473 [salt.fileclient  ][INFO    ][24849] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-10-30 09:40:42,482 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-10-30 09:40:42,482 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-10-30 09:40:42,483 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-10-30 09:40:42,483 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-10-30 09:40:42,484 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-10-30 09:40:42,484 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-10-30 09:40:42,485 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-10-30 09:40:42,485 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-10-30 09:40:42,485 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-10-30 09:40:42,485 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-10-30 09:40:42,486 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-10-30 09:40:42,486 [salt.utils.extmods][INFO    ][24849] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-10-30 09:40:42,488 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-10-30 09:40:42,492 [salt.utils.extmods][INFO    ][24849] Syncing renderers for environment 'base'
2017-10-30 09:40:42,493 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_renderers, for base)
2017-10-30 09:40:42,493 [salt.fileclient  ][INFO    ][24849] Caching directory '_renderers/' for environment 'base'
2017-10-30 09:40:42,562 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-10-30 09:40:42,567 [salt.utils.extmods][INFO    ][24849] Syncing returners for environment 'base'
2017-10-30 09:40:42,567 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_returners, for base)
2017-10-30 09:40:42,567 [salt.fileclient  ][INFO    ][24849] Caching directory '_returners/' for environment 'base'
2017-10-30 09:40:44,016 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-10-30 09:40:44,020 [salt.utils.extmods][INFO    ][24849] Syncing output for environment 'base'
2017-10-30 09:40:44,020 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_output, for base)
2017-10-30 09:40:44,020 [salt.fileclient  ][INFO    ][24849] Caching directory '_output/' for environment 'base'
2017-10-30 09:40:44,064 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-10-30 09:40:44,067 [salt.utils.extmods][INFO    ][24849] Syncing utils for environment 'base'
2017-10-30 09:40:44,067 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_utils, for base)
2017-10-30 09:40:44,068 [salt.fileclient  ][INFO    ][24849] Caching directory '_utils/' for environment 'base'
2017-10-30 09:40:44,113 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-10-30 09:40:44,121 [salt.utils.extmods][INFO    ][24849] Syncing log_handlers for environment 'base'
2017-10-30 09:40:44,121 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_log_handlers, for base)
2017-10-30 09:40:44,122 [salt.fileclient  ][INFO    ][24849] Caching directory '_log_handlers/' for environment 'base'
2017-10-30 09:40:44,440 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-10-30 09:40:44,445 [salt.utils.extmods][INFO    ][24849] Syncing proxy for environment 'base'
2017-10-30 09:40:44,445 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_proxy, for base)
2017-10-30 09:40:44,446 [salt.fileclient  ][INFO    ][24849] Caching directory '_proxy/' for environment 'base'
2017-10-30 09:40:44,488 [salt.utils.extmods][INFO    ][24849] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-10-30 09:40:44,491 [salt.utils.extmods][INFO    ][24849] Syncing engines for environment 'base'
2017-10-30 09:40:44,491 [salt.utils.extmods][INFO    ][24849] Loading cache from salt://_engines, for base)
2017-10-30 09:40:44,492 [salt.fileclient  ][INFO    ][24849] Caching directory '_engines/' for environment 'base'
2017-10-30 09:40:44,529 [salt.minion      ][INFO    ][24849] Returning information for job: 20171030094035138274
2017-10-30 09:45:34,494 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command test.ping with jid 20171030094534483409
2017-10-30 09:45:34,527 [salt.minion      ][INFO    ][25022] Starting a new job with PID 25022
2017-10-30 09:45:34,578 [salt.minion      ][INFO    ][25022] Returning information for job: 20171030094534483409
2017-10-30 09:46:21,973 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command state.sls with jid 20171030094621962055
2017-10-30 09:46:21,997 [salt.minion      ][INFO    ][25035] Starting a new job with PID 25035
2017-10-30 09:46:24,215 [salt.state       ][INFO    ][25035] Loading fresh modules for state activity
2017-10-30 09:46:24,253 [salt.fileclient  ][INFO    ][25035] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-30 09:46:24,317 [salt.fileclient  ][INFO    ][25035] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-30 09:46:24,357 [salt.fileclient  ][INFO    ][25035] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:46:24,738 [salt.state       ][INFO    ][25035] Running state [lsof] at time 09:46:24.737995
2017-10-30 09:46:24,738 [salt.state       ][INFO    ][25035] Executing state pkg.installed for lsof
2017-10-30 09:46:24,739 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:46:25,073 [salt.state       ][INFO    ][25035] Package lsof is already installed
2017-10-30 09:46:25,073 [salt.state       ][INFO    ][25035] Completed state [lsof] at time 09:46:25.073097 duration_in_ms=335.101
2017-10-30 09:46:25,074 [salt.state       ][INFO    ][25035] Running state [keepalived] at time 09:46:25.073680
2017-10-30 09:46:25,074 [salt.state       ][INFO    ][25035] Executing state pkg.installed for keepalived
2017-10-30 09:46:25,097 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-30 09:46:27,720 [salt.loaded.int.module.cmdmod][INFO    ][25035] 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-30 09:46:32,027 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:46:32,029 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command saltutil.find_job with jid 20171030094632007187
2017-10-30 09:46:32,055 [salt.minion      ][INFO    ][26539] Starting a new job with PID 26539
2017-10-30 09:46:32,075 [salt.minion      ][INFO    ][26539] Returning information for job: 20171030094632007187
2017-10-30 09:46:32,083 [salt.state       ][INFO    ][25035] 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-30 09:46:32,097 [salt.state       ][INFO    ][25035] Loading fresh modules for state activity
2017-10-30 09:46:32,117 [salt.state       ][INFO    ][25035] Completed state [keepalived] at time 09:46:32.117174 duration_in_ms=7043.494
2017-10-30 09:46:32,120 [salt.state       ][INFO    ][25035] Running state [/etc/keepalived/keepalived.conf] at time 09:46:32.119462
2017-10-30 09:46:32,120 [salt.state       ][INFO    ][25035] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-30 09:46:32,144 [salt.fileclient  ][INFO    ][25035] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-30 09:46:32,189 [salt.fileclient  ][INFO    ][25035] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:46:32,203 [salt.state       ][INFO    ][25035] File changed:
New file
2017-10-30 09:46:32,204 [salt.state       ][INFO    ][25035] Completed state [/etc/keepalived/keepalived.conf] at time 09:46:32.203647 duration_in_ms=84.184
2017-10-30 09:46:32,334 [salt.state       ][INFO    ][25035] Running state [keepalived] at time 09:46:32.333453
2017-10-30 09:46:32,334 [salt.state       ][INFO    ][25035] Executing state service.running for keepalived
2017-10-30 09:46:32,338 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-30 09:46:32,358 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-30 09:46:32,376 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:46:32,393 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:46:32,417 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-10-30 09:46:32,495 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-30 09:46:32,524 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:46:32,542 [salt.loaded.int.module.cmdmod][INFO    ][25035] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:46:32,554 [salt.state       ][INFO    ][25035] {'keepalived': True}
2017-10-30 09:46:32,555 [salt.state       ][INFO    ][25035] Completed state [keepalived] at time 09:46:32.554535 duration_in_ms=221.081
2017-10-30 09:46:32,557 [salt.minion      ][INFO    ][25035] Returning information for job: 20171030094621962055
2017-10-30 09:47:47,168 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command pillar.get with jid 20171030094747157035
2017-10-30 09:47:47,195 [salt.minion      ][INFO    ][26647] Starting a new job with PID 26647
2017-10-30 09:47:47,202 [salt.minion      ][INFO    ][26647] Returning information for job: 20171030094747157035
2017-10-30 09:52:40,743 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command state.sls with jid 20171030095240732225
2017-10-30 09:52:40,810 [salt.minion      ][INFO    ][26734] Starting a new job with PID 26734
2017-10-30 09:52:43,427 [salt.state       ][INFO    ][26734] Loading fresh modules for state activity
2017-10-30 09:52:43,454 [salt.fileclient  ][INFO    ][26734] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 09:52:43,487 [salt.fileclient  ][INFO    ][26734] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 09:52:43,839 [salt.state       ][INFO    ][26734] Running state [attr] at time 09:52:43.838854
2017-10-30 09:52:43,839 [salt.state       ][INFO    ][26734] Executing state pkg.installed for attr
2017-10-30 09:52:43,840 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:52:44,182 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-30 09:52:46,794 [salt.loaded.int.module.cmdmod][INFO    ][26734] 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-30 09:52:48,817 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:52:48,870 [salt.state       ][INFO    ][26734] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-10-30 09:52:48,884 [salt.state       ][INFO    ][26734] Loading fresh modules for state activity
2017-10-30 09:52:48,910 [salt.state       ][INFO    ][26734] Completed state [attr] at time 09:52:48.909707 duration_in_ms=5070.854
2017-10-30 09:52:48,941 [salt.state       ][INFO    ][26734] Running state [glusterfs-server] at time 09:52:48.941181
2017-10-30 09:52:48,942 [salt.state       ][INFO    ][26734] Executing state pkg.installed for glusterfs-server
2017-10-30 09:52:49,266 [salt.loaded.int.module.cmdmod][INFO    ][26734] 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-30 09:52:50,852 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command saltutil.find_job with jid 20171030095250837529
2017-10-30 09:52:50,874 [salt.minion      ][INFO    ][27832] Starting a new job with PID 27832
2017-10-30 09:52:50,892 [salt.minion      ][INFO    ][27832] Returning information for job: 20171030095250837529
2017-10-30 09:53:00,009 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:53:00,063 [salt.state       ][INFO    ][26734] 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-30 09:53:00,081 [salt.state       ][INFO    ][26734] Loading fresh modules for state activity
2017-10-30 09:53:00,101 [salt.state       ][INFO    ][26734] Completed state [glusterfs-server] at time 09:53:00.100546 duration_in_ms=11159.365
2017-10-30 09:53:00,107 [salt.state       ][INFO    ][26734] Running state [bc] at time 09:53:00.106838
2017-10-30 09:53:00,107 [salt.state       ][INFO    ][26734] Executing state pkg.installed for bc
2017-10-30 09:53:00,457 [salt.loaded.int.module.cmdmod][INFO    ][26734] 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-30 09:53:01,065 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command saltutil.find_job with jid 20171030095301048427
2017-10-30 09:53:01,091 [salt.minion      ][INFO    ][31106] Starting a new job with PID 31106
2017-10-30 09:53:01,107 [salt.minion      ][INFO    ][31106] Returning information for job: 20171030095301048427
2017-10-30 09:53:02,514 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:53:02,571 [salt.state       ][INFO    ][26734] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-10-30 09:53:02,582 [salt.state       ][INFO    ][26734] Loading fresh modules for state activity
2017-10-30 09:53:02,626 [salt.state       ][INFO    ][26734] Completed state [bc] at time 09:53:02.625497 duration_in_ms=2518.659
2017-10-30 09:53:02,719 [salt.state       ][INFO    ][26734] Running state [glusterfs-server] at time 09:53:02.718975
2017-10-30 09:53:02,719 [salt.state       ][INFO    ][26734] Executing state service.running for glusterfs-server
2017-10-30 09:53:02,721 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 09:53:02,736 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:53:02,749 [salt.loaded.int.module.cmdmod][INFO    ][26734] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:53:02,764 [salt.state       ][INFO    ][26734] The service glusterfs-server is already running
2017-10-30 09:53:02,765 [salt.state       ][INFO    ][26734] Completed state [glusterfs-server] at time 09:53:02.764882 duration_in_ms=45.905
2017-10-30 09:53:02,769 [salt.state       ][INFO    ][26734] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:53:02.768474
2017-10-30 09:53:02,769 [salt.state       ][INFO    ][26734] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 09:53:02,781 [salt.state       ][INFO    ][26734] File changed:
New file
2017-10-30 09:53:02,781 [salt.state       ][INFO    ][26734] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:53:02.781397 duration_in_ms=12.923
2017-10-30 09:53:02,782 [salt.state       ][INFO    ][26734] Running state [/srv/glusterfs/glance] at time 09:53:02.781566
2017-10-30 09:53:02,782 [salt.state       ][INFO    ][26734] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 09:53:02,782 [salt.state       ][INFO    ][26734] {'/srv/glusterfs/glance': 'New Dir'}
2017-10-30 09:53:02,783 [salt.state       ][INFO    ][26734] Completed state [/srv/glusterfs/glance] at time 09:53:02.782767 duration_in_ms=1.201
2017-10-30 09:53:02,783 [salt.state       ][INFO    ][26734] Running state [/srv/glusterfs/keystone-keys] at time 09:53:02.782951
2017-10-30 09:53:02,783 [salt.state       ][INFO    ][26734] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 09:53:02,785 [salt.state       ][INFO    ][26734] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-10-30 09:53:02,785 [salt.state       ][INFO    ][26734] Completed state [/srv/glusterfs/keystone-keys] at time 09:53:02.785073 duration_in_ms=2.122
2017-10-30 09:53:02,785 [salt.state       ][INFO    ][26734] Running state [/srv/glusterfs/nova_instances] at time 09:53:02.785257
2017-10-30 09:53:02,785 [salt.state       ][INFO    ][26734] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 09:53:02,786 [salt.state       ][INFO    ][26734] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-10-30 09:53:02,786 [salt.state       ][INFO    ][26734] Completed state [/srv/glusterfs/nova_instances] at time 09:53:02.785961 duration_in_ms=0.705
2017-10-30 09:53:02,786 [salt.state       ][INFO    ][26734] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:53:02.786117
2017-10-30 09:53:02,786 [salt.state       ][INFO    ][26734] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 09:53:02,787 [salt.state       ][INFO    ][26734] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-10-30 09:53:02,787 [salt.state       ][INFO    ][26734] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:53:02.786818 duration_in_ms=0.701
2017-10-30 09:53:02,788 [salt.minion      ][INFO    ][26734] Returning information for job: 20171030095240732225
2017-10-30 09:53:03,871 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command test.ping with jid 20171030095303857499
2017-10-30 09:53:03,895 [salt.minion      ][INFO    ][31491] Starting a new job with PID 31491
2017-10-30 09:53:03,939 [salt.minion      ][INFO    ][31491] Returning information for job: 20171030095303857499
2017-10-30 09:53:28,257 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command state.sls with jid 20171030095328244541
2017-10-30 09:53:28,283 [salt.minion      ][INFO    ][31715] Starting a new job with PID 31715
2017-10-30 09:53:30,520 [salt.state       ][INFO    ][31715] Loading fresh modules for state activity
2017-10-30 09:53:30,550 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-30 09:53:30,601 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 09:53:30,710 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 09:53:30,741 [salt.fileclient  ][INFO    ][31715] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 09:53:31,097 [salt.state       ][INFO    ][31715] Running state [attr] at time 09:53:31.097252
2017-10-30 09:53:31,098 [salt.state       ][INFO    ][31715] Executing state pkg.installed for attr
2017-10-30 09:53:31,098 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:53:31,423 [salt.state       ][INFO    ][31715] Package attr is already installed
2017-10-30 09:53:31,424 [salt.state       ][INFO    ][31715] Completed state [attr] at time 09:53:31.423696 duration_in_ms=326.443
2017-10-30 09:53:31,424 [salt.state       ][INFO    ][31715] Running state [glusterfs-server] at time 09:53:31.423947
2017-10-30 09:53:31,424 [salt.state       ][INFO    ][31715] Executing state pkg.installed for glusterfs-server
2017-10-30 09:53:31,428 [salt.state       ][INFO    ][31715] Package glusterfs-server is already installed
2017-10-30 09:53:31,428 [salt.state       ][INFO    ][31715] Completed state [glusterfs-server] at time 09:53:31.427988 duration_in_ms=4.041
2017-10-30 09:53:31,428 [salt.state       ][INFO    ][31715] Running state [bc] at time 09:53:31.428152
2017-10-30 09:53:31,428 [salt.state       ][INFO    ][31715] Executing state pkg.installed for bc
2017-10-30 09:53:31,433 [salt.state       ][INFO    ][31715] Package bc is already installed
2017-10-30 09:53:31,433 [salt.state       ][INFO    ][31715] Completed state [bc] at time 09:53:31.433031 duration_in_ms=4.879
2017-10-30 09:53:31,435 [salt.state       ][INFO    ][31715] Running state [glusterfs-server] at time 09:53:31.434678
2017-10-30 09:53:31,435 [salt.state       ][INFO    ][31715] Executing state service.running for glusterfs-server
2017-10-30 09:53:31,435 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 09:53:31,454 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-10-30 09:53:31,558 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 09:53:31,590 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:53:31,605 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:53:31,623 [salt.state       ][INFO    ][31715] The service glusterfs-server is already running
2017-10-30 09:53:31,624 [salt.state       ][INFO    ][31715] Completed state [glusterfs-server] at time 09:53:31.623767 duration_in_ms=189.088
2017-10-30 09:53:31,627 [salt.state       ][INFO    ][31715] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:53:31.626765
2017-10-30 09:53:31,627 [salt.state       ][INFO    ][31715] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 09:53:31,633 [salt.state       ][INFO    ][31715] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-30 09:53:31,633 [salt.state       ][INFO    ][31715] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:53:31.633006 duration_in_ms=6.242
2017-10-30 09:53:31,633 [salt.state       ][INFO    ][31715] Running state [/srv/glusterfs/glance] at time 09:53:31.633311
2017-10-30 09:53:31,634 [salt.state       ][INFO    ][31715] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 09:53:31,634 [salt.state       ][INFO    ][31715] Directory /srv/glusterfs/glance is in the correct state
2017-10-30 09:53:31,635 [salt.state       ][INFO    ][31715] Completed state [/srv/glusterfs/glance] at time 09:53:31.634500 duration_in_ms=1.189
2017-10-30 09:53:31,635 [salt.state       ][INFO    ][31715] Running state [/srv/glusterfs/keystone-keys] at time 09:53:31.634791
2017-10-30 09:53:31,635 [salt.state       ][INFO    ][31715] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 09:53:31,636 [salt.state       ][INFO    ][31715] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-30 09:53:31,636 [salt.state       ][INFO    ][31715] Completed state [/srv/glusterfs/keystone-keys] at time 09:53:31.635954 duration_in_ms=1.163
2017-10-30 09:53:31,636 [salt.state       ][INFO    ][31715] Running state [/srv/glusterfs/nova_instances] at time 09:53:31.636243
2017-10-30 09:53:31,637 [salt.state       ][INFO    ][31715] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 09:53:31,637 [salt.state       ][INFO    ][31715] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-30 09:53:31,637 [salt.state       ][INFO    ][31715] Completed state [/srv/glusterfs/nova_instances] at time 09:53:31.637379 duration_in_ms=1.136
2017-10-30 09:53:31,638 [salt.state       ][INFO    ][31715] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:53:31.637664
2017-10-30 09:53:31,638 [salt.state       ][INFO    ][31715] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 09:53:31,639 [salt.state       ][INFO    ][31715] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-30 09:53:31,639 [salt.state       ][INFO    ][31715] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:53:31.638784 duration_in_ms=1.12
2017-10-30 09:53:31,708 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command ['git', '--version'] in directory '/root'
2017-10-30 09:53:31,835 [salt.state       ][INFO    ][31715] Running state [sleep 5] at time 09:53:31.835063
2017-10-30 09:53:31,835 [salt.state       ][INFO    ][31715] Executing state cmd.wait for sleep 5
2017-10-30 09:53:31,836 [salt.state       ][INFO    ][31715] No changes made for sleep 5
2017-10-30 09:53:31,836 [salt.state       ][INFO    ][31715] Completed state [sleep 5] at time 09:53:31.836408 duration_in_ms=1.345
2017-10-30 09:53:31,837 [salt.state       ][INFO    ][31715] Running state [192.168.10.143] at time 09:53:31.836999
2017-10-30 09:53:31,837 [salt.state       ][INFO    ][31715] Executing state glusterfs.peered for 192.168.10.143
2017-10-30 09:53:31,838 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:31,855 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:31,935 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:31,953 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,033 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,047 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,134 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,150 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,226 [salt.state       ][INFO    ][31715] {'new': {'192.168.10.141': [], 'kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local': ['192.168.10.143']}, 'old': {'192.168.10.141': [], 'kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local': []}}
2017-10-30 09:53:32,227 [salt.state       ][INFO    ][31715] Completed state [192.168.10.143] at time 09:53:32.226598 duration_in_ms=389.595
2017-10-30 09:53:32,234 [salt.state       ][INFO    ][31715] Running state [192.168.10.142] at time 09:53:32.233809
2017-10-30 09:53:32,234 [salt.state       ][INFO    ][31715] Executing state glusterfs.peered for 192.168.10.142
2017-10-30 09:53:32,235 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,253 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,338 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,357 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,434 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,451 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,530 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,551 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,631 [salt.state       ][INFO    ][31715] Peering with localhost is not needed
2017-10-30 09:53:32,631 [salt.state       ][INFO    ][31715] Completed state [192.168.10.142] at time 09:53:32.631143 duration_in_ms=397.332
2017-10-30 09:53:32,640 [salt.state       ][INFO    ][31715] Running state [192.168.10.141] at time 09:53:32.640350
2017-10-30 09:53:32,641 [salt.state       ][INFO    ][31715] Executing state glusterfs.peered for 192.168.10.141
2017-10-30 09:53:32,642 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,665 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,744 [salt.state       ][INFO    ][31715] Host 192.168.10.141 already peered
2017-10-30 09:53:32,745 [salt.state       ][INFO    ][31715] Completed state [192.168.10.141] at time 09:53:32.744941 duration_in_ms=104.589
2017-10-30 09:53:32,749 [salt.state       ][INFO    ][31715] Running state [glance] at time 09:53:32.749234
2017-10-30 09:53:32,750 [salt.state       ][INFO    ][31715] Executing state glusterfs.created for glance
2017-10-30 09:53:32,751 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,769 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,849 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:32,869 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:32,959 [salt.state       ][INFO    ][31715] Volume glance already exists and is started.
2017-10-30 09:53:32,959 [salt.state       ][INFO    ][31715] Completed state [glance] at time 09:53:32.958978 duration_in_ms=209.744
2017-10-30 09:53:32,969 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:32.968938
2017-10-30 09:53:32,969 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:32,970 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:33,059 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:33,061 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:33.060474 duration_in_ms=91.535
2017-10-30 09:53:33,062 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:53:33.062218
2017-10-30 09:53:33,063 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-30 09:53:33,064 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 09:53:33,155 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:33,156 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:53:33.156085 duration_in_ms=93.866
2017-10-30 09:53:33,157 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:53:33.157120
2017-10-30 09:53:33,158 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-30 09:53:33,158 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 09:53:33,242 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:33,243 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:53:33.242589 duration_in_ms=85.468
2017-10-30 09:53:33,245 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:33.245104
2017-10-30 09:53:33,246 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:33,247 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:33,338 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:33,338 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:33.337930 duration_in_ms=92.826
2017-10-30 09:53:33,339 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:53:33.339095
2017-10-30 09:53:33,341 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-30 09:53:33,342 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 09:53:33,429 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:33,429 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:53:33.429357 duration_in_ms=90.262
2017-10-30 09:53:33,433 [salt.state       ][INFO    ][31715] Running state [glance] at time 09:53:33.433314
2017-10-30 09:53:33,434 [salt.state       ][INFO    ][31715] Executing state glusterfs.started for glance
2017-10-30 09:53:33,435 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:33,455 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:33,534 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:33,551 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:33,636 [salt.state       ][INFO    ][31715] Volume glance is already started
2017-10-30 09:53:33,637 [salt.state       ][INFO    ][31715] Completed state [glance] at time 09:53:33.636626 duration_in_ms=203.312
2017-10-30 09:53:33,639 [salt.state       ][INFO    ][31715] Running state [keystone-keys] at time 09:53:33.638973
2017-10-30 09:53:33,640 [salt.state       ][INFO    ][31715] Executing state glusterfs.created for keystone-keys
2017-10-30 09:53:33,641 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:33,660 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:33,750 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:33,769 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:33,857 [salt.state       ][INFO    ][31715] Volume keystone-keys already exists and is started.
2017-10-30 09:53:33,857 [salt.state       ][INFO    ][31715] Completed state [keystone-keys] at time 09:53:33.857328 duration_in_ms=218.355
2017-10-30 09:53:33,867 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:53:33.866855
2017-10-30 09:53:33,867 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-30 09:53:33,868 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 09:53:33,947 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:33,948 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:53:33.947537 duration_in_ms=80.638
2017-10-30 09:53:33,949 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:33.948979
2017-10-30 09:53:33,950 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:33,950 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:34,035 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:34,035 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:34.035391 duration_in_ms=86.412
2017-10-30 09:53:34,037 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:34.036819
2017-10-30 09:53:34,037 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:34,038 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:34,123 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:34,125 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:34.124421 duration_in_ms=87.602
2017-10-30 09:53:34,126 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:53:34.125785
2017-10-30 09:53:34,126 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-30 09:53:34,127 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 09:53:34,213 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:34,214 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:53:34.213809 duration_in_ms=88.024
2017-10-30 09:53:34,215 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:34.214861
2017-10-30 09:53:34,215 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 09:53:34,217 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 09:53:34,301 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:34,301 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:34.300976 duration_in_ms=86.115
2017-10-30 09:53:34,305 [salt.state       ][INFO    ][31715] Running state [keystone-keys] at time 09:53:34.305024
2017-10-30 09:53:34,306 [salt.state       ][INFO    ][31715] Executing state glusterfs.started for keystone-keys
2017-10-30 09:53:34,307 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:34,323 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:34,404 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:34,423 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:34,515 [salt.state       ][INFO    ][31715] Volume keystone-keys is already started
2017-10-30 09:53:34,517 [salt.state       ][INFO    ][31715] Completed state [keystone-keys] at time 09:53:34.516650 duration_in_ms=211.626
2017-10-30 09:53:34,519 [salt.state       ][INFO    ][31715] Running state [nova_instances] at time 09:53:34.518884
2017-10-30 09:53:34,519 [salt.state       ][INFO    ][31715] Executing state glusterfs.created for nova_instances
2017-10-30 09:53:34,522 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:34,538 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:34,617 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:34,635 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:34,731 [salt.state       ][INFO    ][31715] Volume nova_instances already exists and is started.
2017-10-30 09:53:34,734 [salt.state       ][INFO    ][31715] Completed state [nova_instances] at time 09:53:34.733541 duration_in_ms=214.656
2017-10-30 09:53:34,750 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:34.750021
2017-10-30 09:53:34,751 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:34,753 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:34,837 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:34,837 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:34.837214 duration_in_ms=87.194
2017-10-30 09:53:34,839 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:53:34.838496
2017-10-30 09:53:34,839 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-30 09:53:34,840 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-30 09:53:34,929 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:34,930 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:53:34.929835 duration_in_ms=91.339
2017-10-30 09:53:34,931 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:53:34.931080
2017-10-30 09:53:34,933 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-30 09:53:34,934 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-30 09:53:35,025 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:35,026 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:53:35.025486 duration_in_ms=94.405
2017-10-30 09:53:35,027 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:53:35.026592
2017-10-30 09:53:35,027 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-30 09:53:35,029 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-30 09:53:35,113 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:35,114 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:53:35.113900 duration_in_ms=87.309
2017-10-30 09:53:35,115 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:35.115011
2017-10-30 09:53:35,116 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:35,117 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:35,201 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:35,202 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:35.201632 duration_in_ms=86.62
2017-10-30 09:53:35,205 [salt.state       ][INFO    ][31715] Running state [nova_instances] at time 09:53:35.205108
2017-10-30 09:53:35,206 [salt.state       ][INFO    ][31715] Executing state glusterfs.started for nova_instances
2017-10-30 09:53:35,207 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:35,223 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:35,303 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:35,323 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:35,412 [salt.state       ][INFO    ][31715] Volume nova_instances is already started
2017-10-30 09:53:35,412 [salt.state       ][INFO    ][31715] Completed state [nova_instances] at time 09:53:35.412358 duration_in_ms=207.248
2017-10-30 09:53:35,415 [salt.state       ][INFO    ][31715] Running state [keystone-credential-keys] at time 09:53:35.414825
2017-10-30 09:53:35,415 [salt.state       ][INFO    ][31715] Executing state glusterfs.created for keystone-credential-keys
2017-10-30 09:53:35,418 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:35,437 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:35,525 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:35,546 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:35,635 [salt.state       ][INFO    ][31715] Volume keystone-credential-keys already exists and is started.
2017-10-30 09:53:35,635 [salt.state       ][INFO    ][31715] Completed state [keystone-credential-keys] at time 09:53:35.635077 duration_in_ms=220.251
2017-10-30 09:53:35,646 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:35.645903
2017-10-30 09:53:35,647 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 09:53:35,649 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 09:53:35,735 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:35,735 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:35.735102 duration_in_ms=89.199
2017-10-30 09:53:35,738 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:35.737501
2017-10-30 09:53:35,738 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:35,739 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:35,826 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:35,827 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:35.827205 duration_in_ms=89.706
2017-10-30 09:53:35,830 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:53:35.829619
2017-10-30 09:53:35,830 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-30 09:53:35,831 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 09:53:35,922 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:35,922 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:53:35.922363 duration_in_ms=92.743
2017-10-30 09:53:35,924 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:35.924268
2017-10-30 09:53:35,925 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:35,926 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:36,013 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:36,013 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:36.013223 duration_in_ms=88.954
2017-10-30 09:53:36,015 [salt.state       ][INFO    ][31715] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:53:36.014490
2017-10-30 09:53:36,015 [salt.state       ][INFO    ][31715] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-30 09:53:36,017 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 09:53:36,106 [salt.state       ][INFO    ][31715] unless execution succeeded
2017-10-30 09:53:36,107 [salt.state       ][INFO    ][31715] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:53:36.106842 duration_in_ms=92.35
2017-10-30 09:53:36,111 [salt.state       ][INFO    ][31715] Running state [keystone-credential-keys] at time 09:53:36.110945
2017-10-30 09:53:36,112 [salt.state       ][INFO    ][31715] Executing state glusterfs.started for keystone-credential-keys
2017-10-30 09:53:36,113 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:36,135 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:36,214 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:36,233 [salt.loaded.int.module.cmdmod][INFO    ][31715] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:36,322 [salt.state       ][INFO    ][31715] Volume keystone-credential-keys is already started
2017-10-30 09:53:36,323 [salt.state       ][INFO    ][31715] Completed state [keystone-credential-keys] at time 09:53:36.322658 duration_in_ms=211.712
2017-10-30 09:53:36,327 [salt.minion      ][INFO    ][31715] Returning information for job: 20171030095328244541
2017-10-30 09:53:41,477 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command test.ping with jid 20171030095341465826
2017-10-30 09:53:41,506 [salt.minion      ][INFO    ][32141] Starting a new job with PID 32141
2017-10-30 09:53:41,548 [salt.minion      ][INFO    ][32141] Returning information for job: 20171030095341465826
2017-10-30 09:53:41,945 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command cmd.run with jid 20171030095341931972
2017-10-30 09:53:41,969 [salt.minion      ][INFO    ][32146] Starting a new job with PID 32146
2017-10-30 09:53:41,977 [salt.loaded.int.module.cmdmod][INFO    ][32146] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-30 09:53:42,150 [salt.minion      ][INFO    ][32146] Returning information for job: 20171030095341931972
2017-10-30 10:34:51,306 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command test.ping with jid 20171030103451295865
2017-10-30 10:34:51,338 [salt.minion      ][INFO    ][32719] Starting a new job with PID 32719
2017-10-30 10:34:51,393 [salt.minion      ][INFO    ][32719] Returning information for job: 20171030103451295865
2017-10-30 10:35:08,315 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command state.sls with jid 20171030103508303400
2017-10-30 10:35:08,338 [salt.minion      ][INFO    ][32725] Starting a new job with PID 32725
2017-10-30 10:35:08,953 [salt.state       ][INFO    ][32725] Loading fresh modules for state activity
2017-10-30 10:35:08,997 [salt.fileclient  ][INFO    ][32725] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-30 10:35:09,023 [salt.fileclient  ][INFO    ][32725] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-30 10:35:09,066 [salt.fileclient  ][INFO    ][32725] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 10:35:09,448 [salt.state       ][INFO    ][32725] Running state [lsof] at time 10:35:09.448236
2017-10-30 10:35:09,449 [salt.state       ][INFO    ][32725] Executing state pkg.installed for lsof
2017-10-30 10:35:09,449 [salt.loaded.int.module.cmdmod][INFO    ][32725] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 10:35:09,814 [salt.state       ][INFO    ][32725] Package lsof is already installed
2017-10-30 10:35:09,815 [salt.state       ][INFO    ][32725] Completed state [lsof] at time 10:35:09.814498 duration_in_ms=366.262
2017-10-30 10:35:09,815 [salt.state       ][INFO    ][32725] Running state [keepalived] at time 10:35:09.814776
2017-10-30 10:35:09,815 [salt.state       ][INFO    ][32725] Executing state pkg.installed for keepalived
2017-10-30 10:35:09,819 [salt.state       ][INFO    ][32725] Package keepalived is already installed
2017-10-30 10:35:09,819 [salt.state       ][INFO    ][32725] Completed state [keepalived] at time 10:35:09.818971 duration_in_ms=4.194
2017-10-30 10:35:09,821 [salt.state       ][INFO    ][32725] Running state [/etc/keepalived/keepalived.conf] at time 10:35:09.821230
2017-10-30 10:35:09,821 [salt.state       ][INFO    ][32725] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-30 10:35:09,851 [salt.fileclient  ][INFO    ][32725] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-30 10:35:09,903 [salt.fileclient  ][INFO    ][32725] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 10:35:09,913 [salt.state       ][INFO    ][32725] File /etc/keepalived/keepalived.conf is in the correct state
2017-10-30 10:35:09,913 [salt.state       ][INFO    ][32725] Completed state [/etc/keepalived/keepalived.conf] at time 10:35:09.913295 duration_in_ms=92.064
2017-10-30 10:35:09,914 [salt.state       ][INFO    ][32725] Running state [keepalived] at time 10:35:09.914177
2017-10-30 10:35:09,914 [salt.state       ][INFO    ][32725] Executing state service.running for keepalived
2017-10-30 10:35:09,916 [salt.loaded.int.module.cmdmod][INFO    ][32725] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-30 10:35:09,954 [salt.loaded.int.module.cmdmod][INFO    ][32725] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-30 10:35:09,974 [salt.loaded.int.module.cmdmod][INFO    ][32725] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 10:35:09,993 [salt.state       ][INFO    ][32725] The service keepalived is already running
2017-10-30 10:35:09,994 [salt.state       ][INFO    ][32725] Completed state [keepalived] at time 10:35:09.993911 duration_in_ms=79.733
2017-10-30 10:35:09,997 [salt.minion      ][INFO    ][32725] Returning information for job: 20171030103508303400
2017-10-30 10:35:29,261 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command pillar.get with jid 20171030103529250637
2017-10-30 10:35:29,290 [salt.minion      ][INFO    ][32760] Starting a new job with PID 32760
2017-10-30 10:35:29,294 [salt.minion      ][INFO    ][32760] Returning information for job: 20171030103529250637
2017-10-30 10:36:19,838 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command state.sls with jid 20171030103619826166
2017-10-30 10:36:19,868 [salt.minion      ][INFO    ][32769] Starting a new job with PID 32769
2017-10-30 10:36:22,492 [salt.state       ][INFO    ][32769] Loading fresh modules for state activity
2017-10-30 10:36:22,524 [salt.fileclient  ][INFO    ][32769] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 10:36:22,558 [salt.fileclient  ][INFO    ][32769] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 10:36:22,967 [salt.state       ][INFO    ][32769] Running state [attr] at time 10:36:22.966681
2017-10-30 10:36:22,967 [salt.state       ][INFO    ][32769] Executing state pkg.installed for attr
2017-10-30 10:36:22,967 [salt.loaded.int.module.cmdmod][INFO    ][32769] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 10:36:23,329 [salt.state       ][INFO    ][32769] Package attr is already installed
2017-10-30 10:36:23,330 [salt.state       ][INFO    ][32769] Completed state [attr] at time 10:36:23.329480 duration_in_ms=362.799
2017-10-30 10:36:23,330 [salt.state       ][INFO    ][32769] Running state [glusterfs-server] at time 10:36:23.329751
2017-10-30 10:36:23,330 [salt.state       ][INFO    ][32769] Executing state pkg.installed for glusterfs-server
2017-10-30 10:36:23,334 [salt.state       ][INFO    ][32769] Package glusterfs-server is already installed
2017-10-30 10:36:23,334 [salt.state       ][INFO    ][32769] Completed state [glusterfs-server] at time 10:36:23.333926 duration_in_ms=4.175
2017-10-30 10:36:23,335 [salt.state       ][INFO    ][32769] Running state [bc] at time 10:36:23.334730
2017-10-30 10:36:23,335 [salt.state       ][INFO    ][32769] Executing state pkg.installed for bc
2017-10-30 10:36:23,339 [salt.state       ][INFO    ][32769] Package bc is already installed
2017-10-30 10:36:23,340 [salt.state       ][INFO    ][32769] Completed state [bc] at time 10:36:23.339588 duration_in_ms=4.857
2017-10-30 10:36:23,341 [salt.state       ][INFO    ][32769] Running state [glusterfs-server] at time 10:36:23.340767
2017-10-30 10:36:23,341 [salt.state       ][INFO    ][32769] Executing state service.running for glusterfs-server
2017-10-30 10:36:23,341 [salt.loaded.int.module.cmdmod][INFO    ][32769] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 10:36:23,376 [salt.loaded.int.module.cmdmod][INFO    ][32769] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:23,396 [salt.loaded.int.module.cmdmod][INFO    ][32769] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:23,421 [salt.state       ][INFO    ][32769] The service glusterfs-server is already running
2017-10-30 10:36:23,423 [salt.state       ][INFO    ][32769] Completed state [glusterfs-server] at time 10:36:23.422547 duration_in_ms=81.778
2017-10-30 10:36:23,427 [salt.state       ][INFO    ][32769] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:23.426918
2017-10-30 10:36:23,427 [salt.state       ][INFO    ][32769] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 10:36:23,434 [salt.state       ][INFO    ][32769] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-30 10:36:23,435 [salt.state       ][INFO    ][32769] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:23.434755 duration_in_ms=7.837
2017-10-30 10:36:23,435 [salt.state       ][INFO    ][32769] Running state [/srv/glusterfs/glance] at time 10:36:23.435098
2017-10-30 10:36:23,435 [salt.state       ][INFO    ][32769] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 10:36:23,437 [salt.state       ][INFO    ][32769] Directory /srv/glusterfs/glance is in the correct state
2017-10-30 10:36:23,437 [salt.state       ][INFO    ][32769] Completed state [/srv/glusterfs/glance] at time 10:36:23.436968 duration_in_ms=1.871
2017-10-30 10:36:23,437 [salt.state       ][INFO    ][32769] Running state [/srv/glusterfs/keystone-keys] at time 10:36:23.437126
2017-10-30 10:36:23,437 [salt.state       ][INFO    ][32769] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 10:36:23,438 [salt.state       ][INFO    ][32769] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-30 10:36:23,438 [salt.state       ][INFO    ][32769] Completed state [/srv/glusterfs/keystone-keys] at time 10:36:23.437814 duration_in_ms=0.687
2017-10-30 10:36:23,438 [salt.state       ][INFO    ][32769] Running state [/srv/glusterfs/nova_instances] at time 10:36:23.437972
2017-10-30 10:36:23,438 [salt.state       ][INFO    ][32769] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 10:36:23,439 [salt.state       ][INFO    ][32769] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-30 10:36:23,439 [salt.state       ][INFO    ][32769] Completed state [/srv/glusterfs/nova_instances] at time 10:36:23.438618 duration_in_ms=0.646
2017-10-30 10:36:23,439 [salt.state       ][INFO    ][32769] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:36:23.438773
2017-10-30 10:36:23,439 [salt.state       ][INFO    ][32769] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 10:36:23,439 [salt.state       ][INFO    ][32769] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-30 10:36:23,439 [salt.state       ][INFO    ][32769] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:36:23.439443 duration_in_ms=0.67
2017-10-30 10:36:23,441 [salt.minion      ][INFO    ][32769] Returning information for job: 20171030103619826166
2017-10-30 10:36:24,458 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command test.ping with jid 20171030103624444443
2017-10-30 10:36:24,484 [salt.minion      ][INFO    ][32792] Starting a new job with PID 32792
2017-10-30 10:36:24,531 [salt.minion      ][INFO    ][32792] Returning information for job: 20171030103624444443
2017-10-30 10:36:28,801 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command state.sls with jid 20171030103628788124
2017-10-30 10:36:28,829 [salt.minion      ][INFO    ][32798] Starting a new job with PID 32798
2017-10-30 10:36:29,430 [salt.state       ][INFO    ][32798] Loading fresh modules for state activity
2017-10-30 10:36:29,477 [salt.fileclient  ][INFO    ][32798] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-30 10:36:29,526 [salt.fileclient  ][INFO    ][32798] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 10:36:29,633 [salt.fileclient  ][INFO    ][32798] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 10:36:29,664 [salt.fileclient  ][INFO    ][32798] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 10:36:30,088 [salt.state       ][INFO    ][32798] Running state [attr] at time 10:36:30.088114
2017-10-30 10:36:30,088 [salt.state       ][INFO    ][32798] Executing state pkg.installed for attr
2017-10-30 10:36:30,089 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 10:36:30,490 [salt.state       ][INFO    ][32798] Package attr is already installed
2017-10-30 10:36:30,490 [salt.state       ][INFO    ][32798] Completed state [attr] at time 10:36:30.490338 duration_in_ms=402.223
2017-10-30 10:36:30,491 [salt.state       ][INFO    ][32798] Running state [glusterfs-server] at time 10:36:30.490606
2017-10-30 10:36:30,491 [salt.state       ][INFO    ][32798] Executing state pkg.installed for glusterfs-server
2017-10-30 10:36:30,495 [salt.state       ][INFO    ][32798] Package glusterfs-server is already installed
2017-10-30 10:36:30,496 [salt.state       ][INFO    ][32798] Completed state [glusterfs-server] at time 10:36:30.495830 duration_in_ms=5.224
2017-10-30 10:36:30,496 [salt.state       ][INFO    ][32798] Running state [bc] at time 10:36:30.496020
2017-10-30 10:36:30,496 [salt.state       ][INFO    ][32798] Executing state pkg.installed for bc
2017-10-30 10:36:30,500 [salt.state       ][INFO    ][32798] Package bc is already installed
2017-10-30 10:36:30,500 [salt.state       ][INFO    ][32798] Completed state [bc] at time 10:36:30.499983 duration_in_ms=3.963
2017-10-30 10:36:30,501 [salt.state       ][INFO    ][32798] Running state [glusterfs-server] at time 10:36:30.500915
2017-10-30 10:36:30,501 [salt.state       ][INFO    ][32798] Executing state service.running for glusterfs-server
2017-10-30 10:36:30,502 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 10:36:30,537 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:30,560 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:30,583 [salt.state       ][INFO    ][32798] The service glusterfs-server is already running
2017-10-30 10:36:30,583 [salt.state       ][INFO    ][32798] Completed state [glusterfs-server] at time 10:36:30.583347 duration_in_ms=82.431
2017-10-30 10:36:30,591 [salt.state       ][INFO    ][32798] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:30.591028
2017-10-30 10:36:30,593 [salt.state       ][INFO    ][32798] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 10:36:30,597 [salt.state       ][INFO    ][32798] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-30 10:36:30,597 [salt.state       ][INFO    ][32798] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:30.597333 duration_in_ms=6.307
2017-10-30 10:36:30,598 [salt.state       ][INFO    ][32798] Running state [/srv/glusterfs/glance] at time 10:36:30.597582
2017-10-30 10:36:30,598 [salt.state       ][INFO    ][32798] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 10:36:30,598 [salt.state       ][INFO    ][32798] Directory /srv/glusterfs/glance is in the correct state
2017-10-30 10:36:30,599 [salt.state       ][INFO    ][32798] Completed state [/srv/glusterfs/glance] at time 10:36:30.598587 duration_in_ms=1.005
2017-10-30 10:36:30,601 [salt.state       ][INFO    ][32798] Running state [/srv/glusterfs/keystone-keys] at time 10:36:30.600806
2017-10-30 10:36:30,601 [salt.state       ][INFO    ][32798] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 10:36:30,602 [salt.state       ][INFO    ][32798] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-30 10:36:30,602 [salt.state       ][INFO    ][32798] Completed state [/srv/glusterfs/keystone-keys] at time 10:36:30.601767 duration_in_ms=0.961
2017-10-30 10:36:30,602 [salt.state       ][INFO    ][32798] Running state [/srv/glusterfs/nova_instances] at time 10:36:30.601995
2017-10-30 10:36:30,602 [salt.state       ][INFO    ][32798] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 10:36:30,603 [salt.state       ][INFO    ][32798] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-30 10:36:30,603 [salt.state       ][INFO    ][32798] Completed state [/srv/glusterfs/nova_instances] at time 10:36:30.602866 duration_in_ms=0.871
2017-10-30 10:36:30,603 [salt.state       ][INFO    ][32798] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:36:30.603080
2017-10-30 10:36:30,603 [salt.state       ][INFO    ][32798] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 10:36:30,605 [salt.state       ][INFO    ][32798] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-30 10:36:30,605 [salt.state       ][INFO    ][32798] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:36:30.604808 duration_in_ms=1.727
2017-10-30 10:36:30,662 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command ['git', '--version'] in directory '/root'
2017-10-30 10:36:30,785 [salt.state       ][INFO    ][32798] Running state [sleep 5] at time 10:36:30.785035
2017-10-30 10:36:30,785 [salt.state       ][INFO    ][32798] Executing state cmd.wait for sleep 5
2017-10-30 10:36:30,786 [salt.state       ][INFO    ][32798] No changes made for sleep 5
2017-10-30 10:36:30,786 [salt.state       ][INFO    ][32798] Completed state [sleep 5] at time 10:36:30.785751 duration_in_ms=0.716
2017-10-30 10:36:30,786 [salt.state       ][INFO    ][32798] Running state [192.168.10.143] at time 10:36:30.786302
2017-10-30 10:36:30,787 [salt.state       ][INFO    ][32798] Executing state glusterfs.peered for 192.168.10.143
2017-10-30 10:36:30,787 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:30,805 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:30,891 [salt.state       ][INFO    ][32798] Host 192.168.10.143 already peered
2017-10-30 10:36:30,892 [salt.state       ][INFO    ][32798] Completed state [192.168.10.143] at time 10:36:30.892108 duration_in_ms=105.803
2017-10-30 10:36:30,903 [salt.state       ][INFO    ][32798] Running state [192.168.10.142] at time 10:36:30.903181
2017-10-30 10:36:30,906 [salt.state       ][INFO    ][32798] Executing state glusterfs.peered for 192.168.10.142
2017-10-30 10:36:30,906 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:30,926 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:31,016 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:31,035 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:31,129 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:31,147 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:31,233 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:31,254 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:31,353 [salt.state       ][INFO    ][32798] Peering with localhost is not needed
2017-10-30 10:36:31,354 [salt.state       ][INFO    ][32798] Completed state [192.168.10.142] at time 10:36:31.353878 duration_in_ms=450.698
2017-10-30 10:36:31,362 [salt.state       ][INFO    ][32798] Running state [192.168.10.141] at time 10:36:31.362054
2017-10-30 10:36:31,362 [salt.state       ][INFO    ][32798] Executing state glusterfs.peered for 192.168.10.141
2017-10-30 10:36:31,363 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:31,382 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:31,476 [salt.state       ][INFO    ][32798] Host 192.168.10.141 already peered
2017-10-30 10:36:31,476 [salt.state       ][INFO    ][32798] Completed state [192.168.10.141] at time 10:36:31.476328 duration_in_ms=114.273
2017-10-30 10:36:31,479 [salt.state       ][INFO    ][32798] Running state [glance] at time 10:36:31.478627
2017-10-30 10:36:31,479 [salt.state       ][INFO    ][32798] Executing state glusterfs.created for glance
2017-10-30 10:36:31,481 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:31,500 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:31,586 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:31,607 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:31,702 [salt.state       ][INFO    ][32798] Volume glance already exists and is started.
2017-10-30 10:36:31,707 [salt.state       ][INFO    ][32798] Completed state [glance] at time 10:36:31.703469 duration_in_ms=224.842
2017-10-30 10:36:31,715 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:31.715009
2017-10-30 10:36:31,717 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:31,718 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:31,807 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:31,809 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:31.808899 duration_in_ms=93.888
2017-10-30 10:36:31,811 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:36:31.810508
2017-10-30 10:36:31,811 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-30 10:36:31,812 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 10:36:31,905 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:31,906 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:36:31.905821 duration_in_ms=95.313
2017-10-30 10:36:31,907 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:36:31.906843
2017-10-30 10:36:31,907 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-30 10:36:31,910 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 10:36:32,011 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:32,013 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:36:32.013400 duration_in_ms=106.556
2017-10-30 10:36:32,015 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:32.014490
2017-10-30 10:36:32,015 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:32,016 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:32,107 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:32,108 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:32.107601 duration_in_ms=93.11
2017-10-30 10:36:32,109 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:36:32.109155
2017-10-30 10:36:32,110 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-30 10:36:32,110 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 10:36:32,205 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:32,205 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:36:32.205069 duration_in_ms=95.912
2017-10-30 10:36:32,209 [salt.state       ][INFO    ][32798] Running state [glance] at time 10:36:32.208985
2017-10-30 10:36:32,210 [salt.state       ][INFO    ][32798] Executing state glusterfs.started for glance
2017-10-30 10:36:32,211 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:32,231 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:32,322 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:32,339 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:32,435 [salt.state       ][INFO    ][32798] Volume glance is already started
2017-10-30 10:36:32,437 [salt.state       ][INFO    ][32798] Completed state [glance] at time 10:36:32.436548 duration_in_ms=227.562
2017-10-30 10:36:32,441 [salt.state       ][INFO    ][32798] Running state [keystone-keys] at time 10:36:32.441365
2017-10-30 10:36:32,442 [salt.state       ][INFO    ][32798] Executing state glusterfs.created for keystone-keys
2017-10-30 10:36:32,443 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:32,463 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:32,548 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:32,571 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:32,678 [salt.state       ][INFO    ][32798] Volume keystone-keys already exists and is started.
2017-10-30 10:36:32,678 [salt.state       ][INFO    ][32798] Completed state [keystone-keys] at time 10:36:32.678167 duration_in_ms=236.8
2017-10-30 10:36:32,686 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:36:32.686459
2017-10-30 10:36:32,687 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-30 10:36:32,689 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 10:36:32,785 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:32,786 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:36:32.785533 duration_in_ms=99.072
2017-10-30 10:36:32,787 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:32.786764
2017-10-30 10:36:32,787 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:32,789 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:32,893 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:32,893 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:32.893109 duration_in_ms=106.346
2017-10-30 10:36:32,894 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:32.894296
2017-10-30 10:36:32,895 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:32,896 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:32,997 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:32,997 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:32.997030 duration_in_ms=102.725
2017-10-30 10:36:32,998 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:36:32.998401
2017-10-30 10:36:32,999 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-30 10:36:33,000 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 10:36:33,095 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:33,095 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:36:33.095238 duration_in_ms=96.836
2017-10-30 10:36:33,098 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:33.097781
2017-10-30 10:36:33,098 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 10:36:33,099 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 10:36:33,187 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:33,189 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:33.187471 duration_in_ms=89.689
2017-10-30 10:36:33,193 [salt.state       ][INFO    ][32798] Running state [keystone-keys] at time 10:36:33.192236
2017-10-30 10:36:33,193 [salt.state       ][INFO    ][32798] Executing state glusterfs.started for keystone-keys
2017-10-30 10:36:33,194 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:33,211 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:33,307 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:33,330 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:33,433 [salt.state       ][INFO    ][32798] Volume keystone-keys is already started
2017-10-30 10:36:33,433 [salt.state       ][INFO    ][32798] Completed state [keystone-keys] at time 10:36:33.433023 duration_in_ms=240.787
2017-10-30 10:36:33,436 [salt.state       ][INFO    ][32798] Running state [nova_instances] at time 10:36:33.436177
2017-10-30 10:36:33,437 [salt.state       ][INFO    ][32798] Executing state glusterfs.created for nova_instances
2017-10-30 10:36:33,438 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:33,458 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:33,541 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:33,561 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:33,658 [salt.state       ][INFO    ][32798] Volume nova_instances already exists and is started.
2017-10-30 10:36:33,658 [salt.state       ][INFO    ][32798] Completed state [nova_instances] at time 10:36:33.658136 duration_in_ms=221.956
2017-10-30 10:36:33,672 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:33.672219
2017-10-30 10:36:33,673 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:33,674 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:33,763 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:33,763 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:33.763340 duration_in_ms=91.121
2017-10-30 10:36:33,765 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:36:33.765242
2017-10-30 10:36:33,766 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-30 10:36:33,767 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-30 10:36:33,858 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:33,859 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:36:33.858935 duration_in_ms=93.693
2017-10-30 10:36:33,861 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:36:33.861254
2017-10-30 10:36:33,862 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-30 10:36:33,863 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-30 10:36:33,956 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:33,957 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:36:33.956451 duration_in_ms=95.195
2017-10-30 10:36:33,958 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:36:33.957608
2017-10-30 10:36:33,958 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-30 10:36:33,959 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-30 10:36:34,053 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:34,054 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:36:34.054279 duration_in_ms=96.671
2017-10-30 10:36:34,057 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:34.056992
2017-10-30 10:36:34,058 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:34,059 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:34,153 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:34,154 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:34.153567 duration_in_ms=96.573
2017-10-30 10:36:34,157 [salt.state       ][INFO    ][32798] Running state [nova_instances] at time 10:36:34.157092
2017-10-30 10:36:34,158 [salt.state       ][INFO    ][32798] Executing state glusterfs.started for nova_instances
2017-10-30 10:36:34,159 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:34,179 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:34,261 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:34,280 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:34,383 [salt.state       ][INFO    ][32798] Volume nova_instances is already started
2017-10-30 10:36:34,385 [salt.state       ][INFO    ][32798] Completed state [nova_instances] at time 10:36:34.385057 duration_in_ms=227.963
2017-10-30 10:36:34,389 [salt.state       ][INFO    ][32798] Running state [keystone-credential-keys] at time 10:36:34.388745
2017-10-30 10:36:34,390 [salt.state       ][INFO    ][32798] Executing state glusterfs.created for keystone-credential-keys
2017-10-30 10:36:34,391 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:34,414 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:34,503 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:34,522 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:34,621 [salt.state       ][INFO    ][32798] Volume keystone-credential-keys already exists and is started.
2017-10-30 10:36:34,621 [salt.state       ][INFO    ][32798] Completed state [keystone-credential-keys] at time 10:36:34.621331 duration_in_ms=232.585
2017-10-30 10:36:34,632 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:34.631953
2017-10-30 10:36:34,633 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 10:36:34,633 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 10:36:34,730 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:34,730 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:34.730066 duration_in_ms=98.112
2017-10-30 10:36:34,731 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:34.731166
2017-10-30 10:36:34,732 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:34,733 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:34,829 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:34,830 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:34.829561 duration_in_ms=98.394
2017-10-30 10:36:34,831 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:36:34.830712
2017-10-30 10:36:34,832 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-30 10:36:34,833 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 10:36:34,937 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:34,938 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:36:34.937659 duration_in_ms=106.947
2017-10-30 10:36:34,939 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:34.938802
2017-10-30 10:36:34,940 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:34,941 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:35,033 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:35,033 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:35.032847 duration_in_ms=94.045
2017-10-30 10:36:35,034 [salt.state       ][INFO    ][32798] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:36:35.033580
2017-10-30 10:36:35,034 [salt.state       ][INFO    ][32798] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-30 10:36:35,035 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 10:36:35,129 [salt.state       ][INFO    ][32798] unless execution succeeded
2017-10-30 10:36:35,129 [salt.state       ][INFO    ][32798] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:36:35.129361 duration_in_ms=95.781
2017-10-30 10:36:35,131 [salt.state       ][INFO    ][32798] Running state [keystone-credential-keys] at time 10:36:35.131186
2017-10-30 10:36:35,132 [salt.state       ][INFO    ][32798] Executing state glusterfs.started for keystone-credential-keys
2017-10-30 10:36:35,133 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:35,150 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:35,238 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:35,258 [salt.loaded.int.module.cmdmod][INFO    ][32798] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:35,358 [salt.state       ][INFO    ][32798] Volume keystone-credential-keys is already started
2017-10-30 10:36:35,359 [salt.state       ][INFO    ][32798] Completed state [keystone-credential-keys] at time 10:36:35.358682 duration_in_ms=227.494
2017-10-30 10:36:35,363 [salt.minion      ][INFO    ][32798] Returning information for job: 20171030103628788124
2017-10-30 10:36:40,633 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command test.ping with jid 20171030103640619817
2017-10-30 10:36:40,662 [salt.minion      ][INFO    ][33182] Starting a new job with PID 33182
2017-10-30 10:36:40,708 [salt.minion      ][INFO    ][33182] Returning information for job: 20171030103640619817
2017-10-30 10:36:41,110 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command cmd.run with jid 20171030103641099743
2017-10-30 10:36:41,137 [salt.minion      ][INFO    ][33187] Starting a new job with PID 33187
2017-10-30 10:36:41,146 [salt.loaded.int.module.cmdmod][INFO    ][33187] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-30 10:36:41,328 [salt.minion      ][INFO    ][33187] Returning information for job: 20171030103641099743
2017-10-30 10:39:52,073 [salt.utils.schedule][INFO    ][24616] Running scheduled job: __mine_interval
2017-10-30 10:59:49,857 [salt.minion      ][INFO    ][24616] User sudo_ubuntu Executing command cp.push_dir with jid 20171030105949841940
2017-10-30 10:59:49,883 [salt.minion      ][INFO    ][33387] Starting a new job with PID 33387
