2017-10-30 09:28:44,908 [salt.cli.daemons ][WARNING ][2449] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-30 09:29:10,247 [salt.state       ][ERROR   ][3947] No contents found in top file
2017-10-30 09:31:52,023 [salt.utils.parsers][WARNING ][2449] Minion received a SIGTERM. Exiting.
2017-10-30 09:36:15,913 [salt.cli.daemons ][WARNING ][3700] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-30 09:38:23,525 [salt.loaded.int.module.cmdmod][ERROR   ][11884] Command 'virsh net-destroy default 2>&1 1>/dev/null; virsh net-undefine default' failed with return code: 1
2017-10-30 09:38:23,525 [salt.loaded.int.module.cmdmod][ERROR   ][11884] stderr: error: failed to get network 'default'
error: Network not found: no network with matching name 'default'
2017-10-30 09:38:23,526 [salt.loaded.int.module.cmdmod][ERROR   ][11884] retcode: 1
2017-10-30 09:38:23,526 [salt.state       ][ERROR   ][11884] {'pid': 16823, '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,294 [py.warnings      ][WARNING ][16900] /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:39:15,272 [salt.loaded.int.module.cmdmod][ERROR   ][16900] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:15,273 [salt.loaded.int.module.cmdmod][ERROR   ][16900] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:19,774 [salt.loaded.int.module.cmdmod][ERROR   ][16900] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:19,775 [salt.loaded.int.module.cmdmod][ERROR   ][16900] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:24,238 [salt.loaded.int.module.cmdmod][ERROR   ][16900] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:24,239 [salt.loaded.int.module.cmdmod][ERROR   ][16900] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:28,809 [salt.loaded.int.module.cmdmod][ERROR   ][16900] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:28,809 [salt.loaded.int.module.cmdmod][ERROR   ][16900] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:33,269 [salt.loaded.int.module.cmdmod][ERROR   ][16900] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-10-30 09:39:33,269 [salt.loaded.int.module.cmdmod][ERROR   ][16900] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-10-30 09:39:36,666 [salt.loaded.int.module.cmdmod][ERROR   ][16900] 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:39,809 [salt.loaded.int.module.cmdmod][INFO    ][24719] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-30 09:39:39,816 [salt.loaded.int.module.cmdmod][INFO    ][24719] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-30 09:39:39,826 [salt.loaded.int.module.cmdmod][INFO    ][24719] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-10-30 09:39:39,833 [salt.utils.parsers][WARNING ][3700] Minion received a SIGTERM. Exiting.
2017-10-30 09:39:40,158 [salt.cli.daemons ][INFO    ][24807] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local"
2017-10-30 09:39:40,817 [salt.minion      ][INFO    ][24807] Creating minion process manager
2017-10-30 09:39:40,817 [salt.cli.daemons ][WARNING ][24807] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-10-30 09:39:40,817 [salt.cli.daemons ][INFO    ][24807] The Salt Minion is starting up
2017-10-30 09:39:40,818 [salt.minion      ][INFO    ][24807] Minion is starting as user 'root'
2017-10-30 09:39:40,818 [salt.utils.event ][INFO    ][24807] Starting pull socket on /var/run/salt/minion/minion_event_4fcafbd0f5_pull.ipc
2017-10-30 09:39:41,931 [salt.loaded.int.module.cmdmod][INFO    ][24807] Executing command ['date', '+%z'] in directory '/root'
2017-10-30 09:39:41,943 [salt.utils.schedule][INFO    ][24807] Updating job settings for scheduled job: __mine_interval
2017-10-30 09:39:41,946 [salt.minion      ][INFO    ][24807] Added mine.update to scheduler
2017-10-30 09:39:41,968 [salt.minion      ][INFO    ][24807] Minion is ready to receive requests!
2017-10-30 09:39:42,970 [salt.utils.schedule][INFO    ][24807] Running scheduled job: __mine_interval
2017-10-30 09:39:50,141 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command saltutil.find_job with jid 20171030093950129252
2017-10-30 09:39:50,152 [salt.minion      ][INFO    ][24932] Starting a new job with PID 24932
2017-10-30 09:39:50,160 [salt.minion      ][INFO    ][24932] Returning information for job: 20171030093950129252
2017-10-30 09:40:01,075 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command state.sls with jid 20171030094001063456
2017-10-30 09:40:01,086 [salt.minion      ][INFO    ][24937] Starting a new job with PID 24937
2017-10-30 09:40:03,722 [salt.state       ][INFO    ][24937] Loading fresh modules for state activity
2017-10-30 09:40:03,747 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-10-30 09:40:04,305 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-10-30 09:40:04,339 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:04,429 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-10-30 09:40:04,448 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-10-30 09:40:04,481 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:04,581 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-30 09:40:04,600 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-30 09:40:04,655 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-30 09:40:04,673 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-30 09:40:04,685 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:40:04,699 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-30 09:40:04,709 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-30 09:40:04,728 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-30 09:40:04,747 [py.warnings      ][WARNING ][24937] /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,748 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'salt-minion --version' in directory '/root'
2017-10-30 09:40:05,006 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-30 09:40:05,023 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-30 09:40:05,034 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-30 09:40:05,044 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-30 09:40:05,092 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-30 09:40:05,150 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-30 09:40:05,220 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-10-30 09:40:05,245 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:05,312 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-10-30 09:40:05,333 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-30 09:40:05,392 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-10-30 09:40:05,412 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-10-30 09:40:05,429 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:40:05,447 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-10-30 09:40:05,456 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-10-30 09:40:05,476 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-10-30 09:40:05,494 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'salt-minion --version' in directory '/root'
2017-10-30 09:40:05,759 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-10-30 09:40:05,779 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-10-30 09:40:05,791 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-10-30 09:40:05,805 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-10-30 09:40:05,867 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-10-30 09:40:05,911 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-10-30 09:40:05,990 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-10-30 09:40:06,059 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:06,381 [salt.state       ][INFO    ][24937] Running state [salt-minion] at time 09:40:06.380803
2017-10-30 09:40:06,381 [salt.state       ][INFO    ][24937] Executing state pkg.installed for salt-minion
2017-10-30 09:40:06,382 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:40:06,641 [salt.state       ][INFO    ][24937] Package salt-minion is already installed
2017-10-30 09:40:06,642 [salt.state       ][INFO    ][24937] Completed state [salt-minion] at time 09:40:06.641641 duration_in_ms=260.837
2017-10-30 09:40:06,642 [salt.state       ][INFO    ][24937] Running state [salt_minion_dependency_packages] at time 09:40:06.641996
2017-10-30 09:40:06,642 [salt.state       ][INFO    ][24937] Executing state pkg.installed for salt_minion_dependency_packages
2017-10-30 09:40:06,646 [salt.state       ][INFO    ][24937] All specified packages are already installed
2017-10-30 09:40:06,647 [salt.state       ][INFO    ][24937] Completed state [salt_minion_dependency_packages] at time 09:40:06.646483 duration_in_ms=4.487
2017-10-30 09:40:06,648 [salt.state       ][INFO    ][24937] Running state [/etc/salt/minion.d/minion.conf] at time 09:40:06.648149
2017-10-30 09:40:06,648 [salt.state       ][INFO    ][24937] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-10-30 09:40:06,668 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-10-30 09:40:06,725 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-10-30 09:40:06,780 [salt.fileclient  ][INFO    ][24937] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-10-30 09:40:06,826 [salt.state       ][INFO    ][24937] File /etc/salt/minion.d/minion.conf is in the correct state
2017-10-30 09:40:06,826 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/minion.d/minion.conf] at time 09:40:06.825915 duration_in_ms=177.765
2017-10-30 09:40:06,826 [salt.state       ][INFO    ][24937] Running state [/etc/salt/minion.d/_virt.conf] at time 09:40:06.826365
2017-10-30 09:40:06,827 [salt.state       ][INFO    ][24937] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-10-30 09:40:06,827 [salt.state       ][INFO    ][24937] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-10-30 09:40:06,828 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:40:06.827482 duration_in_ms=1.115
2017-10-30 09:40:06,829 [salt.state       ][INFO    ][24937] Running state [salt-minion] at time 09:40:06.829132
2017-10-30 09:40:06,829 [salt.state       ][INFO    ][24937] Executing state service.running for salt-minion
2017-10-30 09:40:06,830 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-10-30 09:40:06,846 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-10-30 09:40:06,853 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-10-30 09:40:06,860 [salt.state       ][INFO    ][24937] The service salt-minion is already running
2017-10-30 09:40:06,861 [salt.state       ][INFO    ][24937] Completed state [salt-minion] at time 09:40:06.860500 duration_in_ms=31.354
2017-10-30 09:40:06,862 [salt.state       ][INFO    ][24937] Running state [/etc/salt/grains.d] at time 09:40:06.862078
2017-10-30 09:40:06,862 [salt.state       ][INFO    ][24937] Executing state file.directory for /etc/salt/grains.d
2017-10-30 09:40:06,863 [salt.state       ][INFO    ][24937] Directory /etc/salt/grains.d is in the correct state
2017-10-30 09:40:06,863 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/grains.d] at time 09:40:06.863101 duration_in_ms=1.023
2017-10-30 09:40:06,864 [salt.state       ][INFO    ][24937] Running state [/etc/salt/grains] at time 09:40:06.863661
2017-10-30 09:40:06,864 [salt.state       ][INFO    ][24937] Executing state file.managed for /etc/salt/grains
2017-10-30 09:40:06,864 [salt.state       ][INFO    ][24937] File /etc/salt/grains exists with proper permissions. No changes made.
2017-10-30 09:40:06,864 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/grains] at time 09:40:06.864403 duration_in_ms=0.742
2017-10-30 09:40:06,865 [salt.state       ][INFO    ][24937] Running state [/etc/salt/grains.d/placeholder] at time 09:40:06.864764
2017-10-30 09:40:06,865 [salt.state       ][INFO    ][24937] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-10-30 09:40:06,865 [salt.state       ][INFO    ][24937] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-10-30 09:40:06,866 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/grains.d/placeholder] at time 09:40:06.865525 duration_in_ms=0.762
2017-10-30 09:40:06,866 [salt.state       ][INFO    ][24937] Running state [/etc/salt/grains.d/sphinx] at time 09:40:06.865899
2017-10-30 09:40:06,866 [salt.state       ][INFO    ][24937] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-10-30 09:40:06,867 [salt.state       ][INFO    ][24937] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 127.0.0.1
               - 192.168.10.143
               - 192.168.11.251
-              - 192.168.122.1
         system:
           name: System
           param:

2017-10-30 09:40:06,868 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/grains.d/sphinx] at time 09:40:06.867631 duration_in_ms=1.733
2017-10-30 09:40:06,868 [salt.state       ][INFO    ][24937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.868365
2017-10-30 09:40:06,869 [salt.state       ][INFO    ][24937] 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:06,869 [salt.state       ][INFO    ][24937] 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:06,869 [salt.state       ][INFO    ][24937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.868900 duration_in_ms=0.534
2017-10-30 09:40:06,869 [salt.state       ][INFO    ][24937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.869053
2017-10-30 09:40:06,869 [salt.state       ][INFO    ][24937] 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:06,870 [salt.loaded.int.module.cmdmod][INFO    ][24937] 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:06,946 [salt.state       ][INFO    ][24937] {'pid': 24986, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-30 09:40:06,946 [salt.state       ][INFO    ][24937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.946180 duration_in_ms=77.127
2017-10-30 09:40:06,947 [salt.state       ][INFO    ][24937] Running state [/etc/salt/grains.d/dns_records] at time 09:40:06.946709
2017-10-30 09:40:06,947 [salt.state       ][INFO    ][24937] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-10-30 09:40:06,948 [salt.state       ][INFO    ][24937] File /etc/salt/grains.d/dns_records is in the correct state
2017-10-30 09:40:06,948 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/grains.d/dns_records] at time 09:40:06.948070 duration_in_ms=1.36
2017-10-30 09:40:06,949 [salt.state       ][INFO    ][24937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.948852
2017-10-30 09:40:06,949 [salt.state       ][INFO    ][24937] 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:06,949 [salt.state       ][INFO    ][24937] 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:06,949 [salt.state       ][INFO    ][24937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.949433 duration_in_ms=0.581
2017-10-30 09:40:06,950 [salt.state       ][INFO    ][24937] Running state [/etc/salt/grains.d/salt] at time 09:40:06.949824
2017-10-30 09:40:06,950 [salt.state       ][INFO    ][24937] Executing state file.managed for /etc/salt/grains.d/salt
2017-10-30 09:40:06,951 [salt.state       ][INFO    ][24937] File /etc/salt/grains.d/salt is in the correct state
2017-10-30 09:40:06,951 [salt.state       ][INFO    ][24937] Completed state [/etc/salt/grains.d/salt] at time 09:40:06.950736 duration_in_ms=0.911
2017-10-30 09:40:06,951 [salt.state       ][INFO    ][24937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.951386
2017-10-30 09:40:06,952 [salt.state       ][INFO    ][24937] 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:06,952 [salt.state       ][INFO    ][24937] 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:06,952 [salt.state       ][INFO    ][24937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:40:06.951896 duration_in_ms=0.51
2017-10-30 09:40:06,953 [salt.state       ][INFO    ][24937] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:06.953433
2017-10-30 09:40:06,954 [salt.state       ][INFO    ][24937] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-30 09:40:06,954 [salt.state       ][INFO    ][24937] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-30 09:40:06,954 [salt.state       ][INFO    ][24937] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:06.953988 duration_in_ms=0.556
2017-10-30 09:40:06,954 [salt.state       ][INFO    ][24937] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:06.954127
2017-10-30 09:40:06,954 [salt.state       ][INFO    ][24937] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-10-30 09:40:06,956 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-10-30 09:40:06,963 [salt.state       ][INFO    ][24937] {'pid': 24988, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-10-30 09:40:06,963 [salt.state       ][INFO    ][24937] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:40:06.963165 duration_in_ms=9.038
2017-10-30 09:40:06,964 [salt.state       ][INFO    ][24937] Running state [mine.update] at time 09:40:06.963866
2017-10-30 09:40:06,964 [salt.state       ][INFO    ][24937] Executing state module.wait for mine.update
2017-10-30 09:40:06,964 [salt.state       ][INFO    ][24937] No changes made for mine.update
2017-10-30 09:40:06,965 [salt.state       ][INFO    ][24937] Completed state [mine.update] at time 09:40:06.964480 duration_in_ms=0.614
2017-10-30 09:40:06,965 [salt.state       ][INFO    ][24937] Running state [mine.update] at time 09:40:06.964639
2017-10-30 09:40:06,965 [salt.state       ][INFO    ][24937] Executing state module.mod_watch for mine.update
2017-10-30 09:40:07,481 [salt.state       ][INFO    ][24937] {'ret': True}
2017-10-30 09:40:07,481 [salt.state       ][INFO    ][24937] Completed state [mine.update] at time 09:40:07.480894 duration_in_ms=516.254
2017-10-30 09:40:07,481 [salt.state       ][INFO    ][24937] Running state [ca-certificates] at time 09:40:07.481185
2017-10-30 09:40:07,481 [salt.state       ][INFO    ][24937] Executing state pkg.installed for ca-certificates
2017-10-30 09:40:07,486 [salt.state       ][INFO    ][24937] Package ca-certificates is already installed
2017-10-30 09:40:07,486 [salt.state       ][INFO    ][24937] Completed state [ca-certificates] at time 09:40:07.485837 duration_in_ms=4.651
2017-10-30 09:40:07,487 [salt.state       ][INFO    ][24937] Running state [update-ca-certificates] at time 09:40:07.486522
2017-10-30 09:40:07,487 [salt.state       ][INFO    ][24937] Executing state cmd.wait for update-ca-certificates
2017-10-30 09:40:07,487 [salt.state       ][INFO    ][24937] No changes made for update-ca-certificates
2017-10-30 09:40:07,487 [salt.state       ][INFO    ][24937] Completed state [update-ca-certificates] at time 09:40:07.487109 duration_in_ms=0.586
2017-10-30 09:40:07,487 [salt.state       ][INFO    ][24937] Running state [libvirt-dev] at time 09:40:07.487282
2017-10-30 09:40:07,487 [salt.state       ][INFO    ][24937] Executing state pkg.installed for libvirt-dev
2017-10-30 09:40:07,491 [salt.state       ][INFO    ][24937] Package libvirt-dev is already installed
2017-10-30 09:40:07,491 [salt.state       ][INFO    ][24937] Completed state [libvirt-dev] at time 09:40:07.491315 duration_in_ms=4.019
2017-10-30 09:40:07,492 [salt.state       ][INFO    ][24937] Running state [pkg-config] at time 09:40:07.491491
2017-10-30 09:40:07,492 [salt.state       ][INFO    ][24937] Executing state pkg.installed for pkg-config
2017-10-30 09:40:07,495 [salt.state       ][INFO    ][24937] Package pkg-config is already installed
2017-10-30 09:40:07,495 [salt.state       ][INFO    ][24937] Completed state [pkg-config] at time 09:40:07.495451 duration_in_ms=3.961
2017-10-30 09:40:07,496 [salt.state       ][INFO    ][24937] Running state [virtng.init] at time 09:40:07.495642
2017-10-30 09:40:07,496 [salt.state       ][INFO    ][24937] Executing state module.run for virtng.init
2017-10-30 09:40:07,539 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command ['git', '--version'] in directory '/root'
2017-10-30 09:40:07,588 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,605 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,605 [salt.state       ][INFO    ][24937] Completed state [virtng.init] at time 09:40:07.605289 duration_in_ms=109.646
2017-10-30 09:40:07,606 [salt.state       ][INFO    ][24937] Running state [virt.set_autostart] at time 09:40:07.605577
2017-10-30 09:40:07,606 [salt.state       ][INFO    ][24937] Executing state module.run for virt.set_autostart
2017-10-30 09:40:07,608 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,625 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,625 [salt.state       ][INFO    ][24937] Completed state [virt.set_autostart] at time 09:40:07.624859 duration_in_ms=19.282
2017-10-30 09:40:07,625 [salt.state       ][INFO    ][24937] Running state [virtng.init] at time 09:40:07.625091
2017-10-30 09:40:07,625 [salt.state       ][INFO    ][24937] Executing state module.run for virtng.init
2017-10-30 09:40:07,628 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,643 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,643 [salt.state       ][INFO    ][24937] Completed state [virtng.init] at time 09:40:07.642834 duration_in_ms=17.743
2017-10-30 09:40:07,643 [salt.state       ][INFO    ][24937] Running state [virt.set_autostart] at time 09:40:07.643099
2017-10-30 09:40:07,643 [salt.state       ][INFO    ][24937] Executing state module.run for virt.set_autostart
2017-10-30 09:40:07,646 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,662 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,662 [salt.state       ][INFO    ][24937] Completed state [virt.set_autostart] at time 09:40:07.662032 duration_in_ms=18.931
2017-10-30 09:40:07,662 [salt.state       ][INFO    ][24937] Running state [virtng.init] at time 09:40:07.662297
2017-10-30 09:40:07,663 [salt.state       ][INFO    ][24937] Executing state module.run for virtng.init
2017-10-30 09:40:07,665 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,679 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,680 [salt.state       ][INFO    ][24937] Completed state [virtng.init] at time 09:40:07.679487 duration_in_ms=17.19
2017-10-30 09:40:07,680 [salt.state       ][INFO    ][24937] Running state [virt.set_autostart] at time 09:40:07.679724
2017-10-30 09:40:07,680 [salt.state       ][INFO    ][24937] Executing state module.run for virt.set_autostart
2017-10-30 09:40:07,683 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,698 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,698 [salt.state       ][INFO    ][24937] Completed state [virt.set_autostart] at time 09:40:07.698036 duration_in_ms=18.312
2017-10-30 09:40:07,698 [salt.state       ][INFO    ][24937] Running state [virtng.init] at time 09:40:07.698293
2017-10-30 09:40:07,699 [salt.state       ][INFO    ][24937] Executing state module.run for virtng.init
2017-10-30 09:40:07,701 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,715 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,715 [salt.state       ][INFO    ][24937] Completed state [virtng.init] at time 09:40:07.715098 duration_in_ms=16.805
2017-10-30 09:40:07,715 [salt.state       ][INFO    ][24937] Running state [virt.set_autostart] at time 09:40:07.715340
2017-10-30 09:40:07,716 [salt.state       ][INFO    ][24937] Executing state module.run for virt.set_autostart
2017-10-30 09:40:07,718 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,731 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,732 [salt.state       ][INFO    ][24937] Completed state [virt.set_autostart] at time 09:40:07.731511 duration_in_ms=16.171
2017-10-30 09:40:07,732 [salt.state       ][INFO    ][24937] Running state [virtng.init] at time 09:40:07.731752
2017-10-30 09:40:07,732 [salt.state       ][INFO    ][24937] Executing state module.run for virtng.init
2017-10-30 09:40:07,735 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,748 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,748 [salt.state       ][INFO    ][24937] Completed state [virtng.init] at time 09:40:07.748308 duration_in_ms=16.555
2017-10-30 09:40:07,749 [salt.state       ][INFO    ][24937] Running state [virt.set_autostart] at time 09:40:07.748552
2017-10-30 09:40:07,749 [salt.state       ][INFO    ][24937] Executing state module.run for virt.set_autostart
2017-10-30 09:40:07,751 [salt.loaded.int.module.cmdmod][INFO    ][24937] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-10-30 09:40:07,766 [salt.state       ][INFO    ][24937] unless execution succeeded
2017-10-30 09:40:07,766 [salt.state       ][INFO    ][24937] Completed state [virt.set_autostart] at time 09:40:07.766368 duration_in_ms=17.816
2017-10-30 09:40:07,769 [salt.minion      ][INFO    ][24937] Returning information for job: 20171030094001063456
2017-10-30 09:40:35,150 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171030094035138274
2017-10-30 09:40:35,163 [salt.minion      ][INFO    ][25054] Starting a new job with PID 25054
2017-10-30 09:40:38,198 [salt.state       ][INFO    ][25054] Loading fresh modules for state activity
2017-10-30 09:40:38,224 [salt.state       ][ERROR   ][25054] No contents found in top file
2017-10-30 09:40:38,225 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-10-30 09:40:38,228 [salt.utils.extmods][INFO    ][25054] Syncing beacons for environment 'base'
2017-10-30 09:40:38,228 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_beacons, for base)
2017-10-30 09:40:38,228 [salt.fileclient  ][INFO    ][25054] Caching directory '_beacons/' for environment 'base'
2017-10-30 09:40:38,277 [salt.utils.extmods][INFO    ][25054] Syncing modules for environment 'base'
2017-10-30 09:40:38,278 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_modules, for base)
2017-10-30 09:40:38,278 [salt.fileclient  ][INFO    ][25054] Caching directory '_modules/' for environment 'base'
2017-10-30 09:40:38,323 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-10-30 09:40:38,331 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-10-30 09:40:38,342 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-10-30 09:40:38,350 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-10-30 09:40:39,618 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-10-30 09:40:39,628 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-10-30 09:40:39,638 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-10-30 09:40:39,647 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-10-30 09:40:39,738 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-10-30 09:40:39,758 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-10-30 09:40:39,772 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-10-30 09:40:39,793 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-10-30 09:40:39,809 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-10-30 09:40:40,116 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-10-30 09:40:40,127 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-10-30 09:40:40,141 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-10-30 09:40:40,151 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-10-30 09:40:40,160 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-10-30 09:40:40,175 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-10-30 09:40:40,190 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-10-30 09:40:40,209 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-10-30 09:40:40,238 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-10-30 09:40:40,253 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-10-30 09:40:40,268 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-10-30 09:40:40,298 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-10-30 09:40:40,316 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-10-30 09:40:40,338 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-10-30 09:40:40,371 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-10-30 09:40:40,385 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-10-30 09:40:40,404 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-10-30 09:40:40,421 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-10-30 09:40:40,455 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-10-30 09:40:40,459 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-10-30 09:40:40,459 [salt.utils.extmods][INFO    ][25054] 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:40,460 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-10-30 09:40:40,460 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-10-30 09:40:40,460 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-10-30 09:40:40,461 [salt.utils.extmods][INFO    ][25054] 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:40,461 [salt.utils.extmods][INFO    ][25054] 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:40,461 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-10-30 09:40:40,462 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-10-30 09:40:40,462 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-10-30 09:40:40,462 [salt.utils.extmods][INFO    ][25054] 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:40,462 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-10-30 09:40:40,463 [salt.utils.extmods][INFO    ][25054] 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:40,463 [salt.utils.extmods][INFO    ][25054] 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:40,463 [salt.utils.extmods][INFO    ][25054] 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:40,463 [salt.utils.extmods][INFO    ][25054] 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:40,464 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-10-30 09:40:40,464 [salt.utils.extmods][INFO    ][25054] 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:40,464 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-10-30 09:40:40,464 [salt.utils.extmods][INFO    ][25054] 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:40,465 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-10-30 09:40:40,465 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-10-30 09:40:40,465 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-10-30 09:40:40,466 [salt.utils.extmods][INFO    ][25054] 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:40,466 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-10-30 09:40:40,466 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-10-30 09:40:40,467 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-10-30 09:40:40,467 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-10-30 09:40:40,467 [salt.utils.extmods][INFO    ][25054] 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:40,467 [salt.utils.extmods][INFO    ][25054] 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:40,468 [salt.utils.extmods][INFO    ][25054] 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:40,468 [salt.utils.extmods][INFO    ][25054] 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:40,468 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-10-30 09:40:40,468 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-10-30 09:40:40,474 [salt.utils.extmods][INFO    ][25054] Syncing states for environment 'base'
2017-10-30 09:40:40,475 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_states, for base)
2017-10-30 09:40:40,475 [salt.fileclient  ][INFO    ][25054] Caching directory '_states/' for environment 'base'
2017-10-30 09:40:40,554 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-10-30 09:40:40,580 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-10-30 09:40:40,594 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-10-30 09:40:40,605 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-10-30 09:40:40,636 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-10-30 09:40:40,652 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-10-30 09:40:40,710 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-10-30 09:40:40,726 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-10-30 09:40:40,736 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-10-30 09:40:40,750 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-10-30 09:40:40,763 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-10-30 09:40:40,781 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-10-30 09:40:40,795 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-10-30 09:40:40,817 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-10-30 09:40:40,829 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-10-30 09:40:40,843 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-10-30 09:40:40,854 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-10-30 09:40:40,880 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-10-30 09:40:40,892 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-10-30 09:40:40,904 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-10-30 09:40:40,920 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-10-30 09:40:40,934 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-10-30 09:40:40,946 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-10-30 09:40:40,966 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-10-30 09:40:40,979 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-10-30 09:40:40,988 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-10-30 09:40:41,000 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-10-30 09:40:41,008 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-10-30 09:40:41,028 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-10-30 09:40:41,033 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-10-30 09:40:41,033 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-10-30 09:40:41,033 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-10-30 09:40:41,033 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-10-30 09:40:41,034 [salt.utils.extmods][INFO    ][25054] 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:41,034 [salt.utils.extmods][INFO    ][25054] 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:41,034 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-10-30 09:40:41,035 [salt.utils.extmods][INFO    ][25054] 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:41,035 [salt.utils.extmods][INFO    ][25054] 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:41,035 [salt.utils.extmods][INFO    ][25054] 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:41,035 [salt.utils.extmods][INFO    ][25054] 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:41,035 [salt.utils.extmods][INFO    ][25054] 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:41,036 [salt.utils.extmods][INFO    ][25054] 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:41,036 [salt.utils.extmods][INFO    ][25054] 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:41,036 [salt.utils.extmods][INFO    ][25054] 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:41,037 [salt.utils.extmods][INFO    ][25054] 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:41,037 [salt.utils.extmods][INFO    ][25054] 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:41,037 [salt.utils.extmods][INFO    ][25054] 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:41,037 [salt.utils.extmods][INFO    ][25054] 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:41,038 [salt.utils.extmods][INFO    ][25054] 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:41,038 [salt.utils.extmods][INFO    ][25054] 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:41,038 [salt.utils.extmods][INFO    ][25054] 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:41,038 [salt.utils.extmods][INFO    ][25054] 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:41,039 [salt.utils.extmods][INFO    ][25054] 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:41,039 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-10-30 09:40:41,039 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-10-30 09:40:41,040 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-10-30 09:40:41,040 [salt.utils.extmods][INFO    ][25054] 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:41,040 [salt.utils.extmods][INFO    ][25054] 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:41,040 [salt.utils.extmods][INFO    ][25054] 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:41,043 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-10-30 09:40:41,046 [salt.utils.extmods][INFO    ][25054] Syncing sdb for environment 'base'
2017-10-30 09:40:41,046 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_sdb, for base)
2017-10-30 09:40:41,046 [salt.fileclient  ][INFO    ][25054] Caching directory '_sdb/' for environment 'base'
2017-10-30 09:40:41,112 [salt.utils.extmods][INFO    ][25054] Syncing grains for environment 'base'
2017-10-30 09:40:41,112 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_grains, for base)
2017-10-30 09:40:41,112 [salt.fileclient  ][INFO    ][25054] Caching directory '_grains/' for environment 'base'
2017-10-30 09:40:41,178 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-10-30 09:40:41,188 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-10-30 09:40:41,204 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-10-30 09:40:41,256 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-10-30 09:40:41,273 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-10-30 09:40:41,292 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-10-30 09:40:41,305 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-10-30 09:40:41,315 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-10-30 09:40:41,325 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-10-30 09:40:41,337 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-10-30 09:40:41,350 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-10-30 09:40:41,363 [salt.fileclient  ][INFO    ][25054] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-10-30 09:40:41,366 [salt.utils.extmods][INFO    ][25054] 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:41,366 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-10-30 09:40:41,367 [salt.utils.extmods][INFO    ][25054] 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:41,367 [salt.utils.extmods][INFO    ][25054] 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:41,367 [salt.utils.extmods][INFO    ][25054] 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:41,367 [salt.utils.extmods][INFO    ][25054] 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:41,367 [salt.utils.extmods][INFO    ][25054] 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:41,368 [salt.utils.extmods][INFO    ][25054] 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:41,368 [salt.utils.extmods][INFO    ][25054] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-10-30 09:40:41,368 [salt.utils.extmods][INFO    ][25054] 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:41,368 [salt.utils.extmods][INFO    ][25054] 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:41,369 [salt.utils.extmods][INFO    ][25054] 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:41,370 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-10-30 09:40:41,373 [salt.utils.extmods][INFO    ][25054] Syncing renderers for environment 'base'
2017-10-30 09:40:41,373 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_renderers, for base)
2017-10-30 09:40:41,373 [salt.fileclient  ][INFO    ][25054] Caching directory '_renderers/' for environment 'base'
2017-10-30 09:40:41,425 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-10-30 09:40:41,428 [salt.utils.extmods][INFO    ][25054] Syncing returners for environment 'base'
2017-10-30 09:40:41,428 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_returners, for base)
2017-10-30 09:40:41,428 [salt.fileclient  ][INFO    ][25054] Caching directory '_returners/' for environment 'base'
2017-10-30 09:40:41,469 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-10-30 09:40:41,472 [salt.utils.extmods][INFO    ][25054] Syncing output for environment 'base'
2017-10-30 09:40:41,472 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_output, for base)
2017-10-30 09:40:41,472 [salt.fileclient  ][INFO    ][25054] Caching directory '_output/' for environment 'base'
2017-10-30 09:40:41,551 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-10-30 09:40:41,554 [salt.utils.extmods][INFO    ][25054] Syncing utils for environment 'base'
2017-10-30 09:40:41,554 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_utils, for base)
2017-10-30 09:40:41,554 [salt.fileclient  ][INFO    ][25054] Caching directory '_utils/' for environment 'base'
2017-10-30 09:40:41,609 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-10-30 09:40:41,612 [salt.utils.extmods][INFO    ][25054] Syncing log_handlers for environment 'base'
2017-10-30 09:40:41,612 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_log_handlers, for base)
2017-10-30 09:40:41,612 [salt.fileclient  ][INFO    ][25054] Caching directory '_log_handlers/' for environment 'base'
2017-10-30 09:40:41,671 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-10-30 09:40:41,674 [salt.utils.extmods][INFO    ][25054] Syncing proxy for environment 'base'
2017-10-30 09:40:41,674 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_proxy, for base)
2017-10-30 09:40:41,674 [salt.fileclient  ][INFO    ][25054] Caching directory '_proxy/' for environment 'base'
2017-10-30 09:40:41,718 [salt.utils.extmods][INFO    ][25054] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-10-30 09:40:41,721 [salt.utils.extmods][INFO    ][25054] Syncing engines for environment 'base'
2017-10-30 09:40:41,721 [salt.utils.extmods][INFO    ][25054] Loading cache from salt://_engines, for base)
2017-10-30 09:40:41,721 [salt.fileclient  ][INFO    ][25054] Caching directory '_engines/' for environment 'base'
2017-10-30 09:40:41,788 [salt.minion      ][INFO    ][25054] Returning information for job: 20171030094035138274
2017-10-30 09:45:34,493 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command test.ping with jid 20171030094534483409
2017-10-30 09:45:34,506 [salt.minion      ][INFO    ][25236] Starting a new job with PID 25236
2017-10-30 09:45:34,540 [salt.minion      ][INFO    ][25236] Returning information for job: 20171030094534483409
2017-10-30 09:47:17,812 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command state.sls with jid 20171030094717801943
2017-10-30 09:47:17,824 [salt.minion      ][INFO    ][25266] Starting a new job with PID 25266
2017-10-30 09:47:18,390 [salt.state       ][INFO    ][25266] Loading fresh modules for state activity
2017-10-30 09:47:18,453 [salt.fileclient  ][INFO    ][25266] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-30 09:47:18,468 [salt.fileclient  ][INFO    ][25266] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-30 09:47:18,494 [salt.fileclient  ][INFO    ][25266] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:47:18,827 [salt.state       ][INFO    ][25266] Running state [lsof] at time 09:47:18.826618
2017-10-30 09:47:18,827 [salt.state       ][INFO    ][25266] Executing state pkg.installed for lsof
2017-10-30 09:47:18,827 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:47:19,102 [salt.state       ][INFO    ][25266] Package lsof is already installed
2017-10-30 09:47:19,102 [salt.state       ][INFO    ][25266] Completed state [lsof] at time 09:47:19.102427 duration_in_ms=275.81
2017-10-30 09:47:19,103 [salt.state       ][INFO    ][25266] Running state [keepalived] at time 09:47:19.102707
2017-10-30 09:47:19,103 [salt.state       ][INFO    ][25266] Executing state pkg.installed for keepalived
2017-10-30 09:47:19,120 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-30 09:47:21,067 [salt.loaded.int.module.cmdmod][INFO    ][25266] 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:47:23,878 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:47:23,904 [salt.state       ][INFO    ][25266] 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:47:23,915 [salt.state       ][INFO    ][25266] Loading fresh modules for state activity
2017-10-30 09:47:23,931 [salt.state       ][INFO    ][25266] Completed state [keepalived] at time 09:47:23.930954 duration_in_ms=4828.246
2017-10-30 09:47:23,933 [salt.state       ][INFO    ][25266] Running state [/etc/keepalived/keepalived.conf] at time 09:47:23.933368
2017-10-30 09:47:23,934 [salt.state       ][INFO    ][25266] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-30 09:47:23,959 [salt.fileclient  ][INFO    ][25266] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-30 09:47:24,001 [salt.fileclient  ][INFO    ][25266] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 09:47:24,010 [salt.state       ][INFO    ][25266] File changed:
New file
2017-10-30 09:47:24,010 [salt.state       ][INFO    ][25266] Completed state [/etc/keepalived/keepalived.conf] at time 09:47:24.009934 duration_in_ms=76.566
2017-10-30 09:47:24,081 [salt.state       ][INFO    ][25266] Running state [keepalived] at time 09:47:24.081199
2017-10-30 09:47:24,082 [salt.state       ][INFO    ][25266] Executing state service.running for keepalived
2017-10-30 09:47:24,083 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-30 09:47:24,092 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-30 09:47:24,099 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:47:24,107 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:47:24,114 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-10-30 09:47:24,146 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-30 09:47:24,154 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:47:24,160 [salt.loaded.int.module.cmdmod][INFO    ][25266] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 09:47:24,166 [salt.state       ][INFO    ][25266] {'keepalived': True}
2017-10-30 09:47:24,166 [salt.state       ][INFO    ][25266] Completed state [keepalived] at time 09:47:24.166097 duration_in_ms=84.899
2017-10-30 09:47:24,167 [salt.minion      ][INFO    ][25266] Returning information for job: 20171030094717801943
2017-10-30 09:47:47,166 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command pillar.get with jid 20171030094747157035
2017-10-30 09:47:47,182 [salt.minion      ][INFO    ][26865] Starting a new job with PID 26865
2017-10-30 09:47:47,191 [salt.minion      ][INFO    ][26865] Returning information for job: 20171030094747157035
2017-10-30 09:52:40,742 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command state.sls with jid 20171030095240732225
2017-10-30 09:52:40,755 [salt.minion      ][INFO    ][26971] Starting a new job with PID 26971
2017-10-30 09:52:43,443 [salt.state       ][INFO    ][26971] Loading fresh modules for state activity
2017-10-30 09:52:43,471 [salt.fileclient  ][INFO    ][26971] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 09:52:43,496 [salt.fileclient  ][INFO    ][26971] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 09:52:43,789 [salt.state       ][INFO    ][26971] Running state [attr] at time 09:52:43.789253
2017-10-30 09:52:43,790 [salt.state       ][INFO    ][26971] Executing state pkg.installed for attr
2017-10-30 09:52:43,790 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:52:44,092 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-10-30 09:52:45,536 [salt.loaded.int.module.cmdmod][INFO    ][26971] 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:46,807 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:52:46,836 [salt.state       ][INFO    ][26971] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-10-30 09:52:46,847 [salt.state       ][INFO    ][26971] Loading fresh modules for state activity
2017-10-30 09:52:46,863 [salt.state       ][INFO    ][26971] Completed state [attr] at time 09:52:46.862843 duration_in_ms=3073.591
2017-10-30 09:52:46,868 [salt.state       ][INFO    ][26971] Running state [glusterfs-server] at time 09:52:46.867670
2017-10-30 09:52:46,868 [salt.state       ][INFO    ][26971] Executing state pkg.installed for glusterfs-server
2017-10-30 09:52:47,085 [salt.loaded.int.module.cmdmod][INFO    ][26971] 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,851 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command saltutil.find_job with jid 20171030095250837529
2017-10-30 09:52:50,862 [salt.minion      ][INFO    ][30853] Starting a new job with PID 30853
2017-10-30 09:52:50,873 [salt.minion      ][INFO    ][30853] Returning information for job: 20171030095250837529
2017-10-30 09:52:53,008 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:52:53,037 [salt.state       ][INFO    ][26971] 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:52:53,047 [salt.state       ][INFO    ][26971] Loading fresh modules for state activity
2017-10-30 09:52:53,063 [salt.state       ][INFO    ][26971] Completed state [glusterfs-server] at time 09:52:53.062857 duration_in_ms=6195.186
2017-10-30 09:52:53,068 [salt.state       ][INFO    ][26971] Running state [bc] at time 09:52:53.067645
2017-10-30 09:52:53,068 [salt.state       ][INFO    ][26971] Executing state pkg.installed for bc
2017-10-30 09:52:53,348 [salt.loaded.int.module.cmdmod][INFO    ][26971] 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:52:54,781 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:52:54,812 [salt.state       ][INFO    ][26971] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-10-30 09:52:54,822 [salt.state       ][INFO    ][26971] Loading fresh modules for state activity
2017-10-30 09:52:54,841 [salt.state       ][INFO    ][26971] Completed state [bc] at time 09:52:54.841212 duration_in_ms=1773.566
2017-10-30 09:52:54,912 [salt.state       ][INFO    ][26971] Running state [glusterfs-server] at time 09:52:54.911513
2017-10-30 09:52:54,912 [salt.state       ][INFO    ][26971] Executing state service.running for glusterfs-server
2017-10-30 09:52:54,914 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 09:52:54,923 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:52:54,930 [salt.loaded.int.module.cmdmod][INFO    ][26971] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:52:54,940 [salt.state       ][INFO    ][26971] The service glusterfs-server is already running
2017-10-30 09:52:54,940 [salt.state       ][INFO    ][26971] Completed state [glusterfs-server] at time 09:52:54.939842 duration_in_ms=28.33
2017-10-30 09:52:54,941 [salt.state       ][INFO    ][26971] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:52:54.941351
2017-10-30 09:52:54,942 [salt.state       ][INFO    ][26971] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 09:52:54,947 [salt.state       ][INFO    ][26971] File changed:
New file
2017-10-30 09:52:54,947 [salt.state       ][INFO    ][26971] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:52:54.946731 duration_in_ms=5.38
2017-10-30 09:52:54,947 [salt.state       ][INFO    ][26971] Running state [/srv/glusterfs/glance] at time 09:52:54.946915
2017-10-30 09:52:54,947 [salt.state       ][INFO    ][26971] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 09:52:54,948 [salt.state       ][INFO    ][26971] {'/srv/glusterfs/glance': 'New Dir'}
2017-10-30 09:52:54,948 [salt.state       ][INFO    ][26971] Completed state [/srv/glusterfs/glance] at time 09:52:54.947921 duration_in_ms=1.006
2017-10-30 09:52:54,948 [salt.state       ][INFO    ][26971] Running state [/srv/glusterfs/keystone-keys] at time 09:52:54.948109
2017-10-30 09:52:54,948 [salt.state       ][INFO    ][26971] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 09:52:54,949 [salt.state       ][INFO    ][26971] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-10-30 09:52:54,949 [salt.state       ][INFO    ][26971] Completed state [/srv/glusterfs/keystone-keys] at time 09:52:54.948901 duration_in_ms=0.792
2017-10-30 09:52:54,949 [salt.state       ][INFO    ][26971] Running state [/srv/glusterfs/nova_instances] at time 09:52:54.949075
2017-10-30 09:52:54,949 [salt.state       ][INFO    ][26971] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 09:52:54,950 [salt.state       ][INFO    ][26971] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-10-30 09:52:54,950 [salt.state       ][INFO    ][26971] Completed state [/srv/glusterfs/nova_instances] at time 09:52:54.949925 duration_in_ms=0.85
2017-10-30 09:52:54,950 [salt.state       ][INFO    ][26971] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:52:54.950096
2017-10-30 09:52:54,950 [salt.state       ][INFO    ][26971] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 09:52:54,951 [salt.state       ][INFO    ][26971] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-10-30 09:52:54,951 [salt.state       ][INFO    ][26971] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:52:54.950858 duration_in_ms=0.763
2017-10-30 09:52:54,952 [salt.minion      ][INFO    ][26971] Returning information for job: 20171030095240732225
2017-10-30 09:53:03,869 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command test.ping with jid 20171030095303857499
2017-10-30 09:53:03,881 [salt.minion      ][INFO    ][31741] Starting a new job with PID 31741
2017-10-30 09:53:03,916 [salt.minion      ][INFO    ][31741] Returning information for job: 20171030095303857499
2017-10-30 09:53:04,072 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command state.sls with jid 20171030095304054412
2017-10-30 09:53:04,082 [salt.minion      ][INFO    ][31746] Starting a new job with PID 31746
2017-10-30 09:53:06,317 [salt.state       ][INFO    ][31746] Loading fresh modules for state activity
2017-10-30 09:53:06,346 [salt.fileclient  ][INFO    ][31746] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-30 09:53:06,383 [salt.fileclient  ][INFO    ][31746] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 09:53:06,459 [salt.fileclient  ][INFO    ][31746] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 09:53:06,479 [salt.fileclient  ][INFO    ][31746] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 09:53:06,779 [salt.state       ][INFO    ][31746] Running state [attr] at time 09:53:06.779202
2017-10-30 09:53:06,779 [salt.state       ][INFO    ][31746] Executing state pkg.installed for attr
2017-10-30 09:53:06,780 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 09:53:07,046 [salt.state       ][INFO    ][31746] Package attr is already installed
2017-10-30 09:53:07,046 [salt.state       ][INFO    ][31746] Completed state [attr] at time 09:53:07.045904 duration_in_ms=266.694
2017-10-30 09:53:07,046 [salt.state       ][INFO    ][31746] Running state [glusterfs-server] at time 09:53:07.046126
2017-10-30 09:53:07,046 [salt.state       ][INFO    ][31746] Executing state pkg.installed for glusterfs-server
2017-10-30 09:53:07,050 [salt.state       ][INFO    ][31746] Package glusterfs-server is already installed
2017-10-30 09:53:07,050 [salt.state       ][INFO    ][31746] Completed state [glusterfs-server] at time 09:53:07.050226 duration_in_ms=4.1
2017-10-30 09:53:07,050 [salt.state       ][INFO    ][31746] Running state [bc] at time 09:53:07.050422
2017-10-30 09:53:07,051 [salt.state       ][INFO    ][31746] Executing state pkg.installed for bc
2017-10-30 09:53:07,054 [salt.state       ][INFO    ][31746] Package bc is already installed
2017-10-30 09:53:07,054 [salt.state       ][INFO    ][31746] Completed state [bc] at time 09:53:07.054376 duration_in_ms=3.955
2017-10-30 09:53:07,055 [salt.state       ][INFO    ][31746] Running state [glusterfs-server] at time 09:53:07.055368
2017-10-30 09:53:07,056 [salt.state       ][INFO    ][31746] Executing state service.running for glusterfs-server
2017-10-30 09:53:07,056 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 09:53:07,065 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-10-30 09:53:07,112 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 09:53:07,125 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:53:07,131 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 09:53:07,140 [salt.state       ][INFO    ][31746] The service glusterfs-server is already running
2017-10-30 09:53:07,140 [salt.state       ][INFO    ][31746] Completed state [glusterfs-server] at time 09:53:07.139836 duration_in_ms=84.467
2017-10-30 09:53:07,142 [salt.state       ][INFO    ][31746] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:53:07.141692
2017-10-30 09:53:07,142 [salt.state       ][INFO    ][31746] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 09:53:07,144 [salt.state       ][INFO    ][31746] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-30 09:53:07,144 [salt.state       ][INFO    ][31746] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:53:07.144148 duration_in_ms=2.456
2017-10-30 09:53:07,144 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/glance] at time 09:53:07.144341
2017-10-30 09:53:07,145 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 09:53:07,145 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/glance is in the correct state
2017-10-30 09:53:07,145 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/glance] at time 09:53:07.145066 duration_in_ms=0.724
2017-10-30 09:53:07,145 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/keystone-keys] at time 09:53:07.145236
2017-10-30 09:53:07,145 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 09:53:07,146 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-30 09:53:07,146 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/keystone-keys] at time 09:53:07.145995 duration_in_ms=0.759
2017-10-30 09:53:07,146 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/nova_instances] at time 09:53:07.146181
2017-10-30 09:53:07,146 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 09:53:07,147 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-30 09:53:07,147 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/nova_instances] at time 09:53:07.146903 duration_in_ms=0.723
2017-10-30 09:53:07,147 [salt.state       ][INFO    ][31746] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:53:07.147072
2017-10-30 09:53:07,147 [salt.state       ][INFO    ][31746] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 09:53:07,148 [salt.state       ][INFO    ][31746] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-30 09:53:07,148 [salt.state       ][INFO    ][31746] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:53:07.147751 duration_in_ms=0.678
2017-10-30 09:53:07,204 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command ['git', '--version'] in directory '/root'
2017-10-30 09:53:07,302 [salt.state       ][INFO    ][31746] Running state [sleep 5] at time 09:53:07.301658
2017-10-30 09:53:07,302 [salt.state       ][INFO    ][31746] Executing state cmd.wait for sleep 5
2017-10-30 09:53:07,302 [salt.state       ][INFO    ][31746] No changes made for sleep 5
2017-10-30 09:53:07,302 [salt.state       ][INFO    ][31746] Completed state [sleep 5] at time 09:53:07.302394 duration_in_ms=0.736
2017-10-30 09:53:07,303 [salt.state       ][INFO    ][31746] Running state [192.168.10.143] at time 09:53:07.302953
2017-10-30 09:53:07,303 [salt.state       ][INFO    ][31746] Executing state glusterfs.peered for 192.168.10.143
2017-10-30 09:53:07,304 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,313 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,365 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,374 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,426 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,434 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,484 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,492 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,545 [salt.state       ][INFO    ][31746] Peering with localhost is not needed
2017-10-30 09:53:07,545 [salt.state       ][INFO    ][31746] Completed state [192.168.10.143] at time 09:53:07.545096 duration_in_ms=242.142
2017-10-30 09:53:07,548 [salt.state       ][INFO    ][31746] Running state [192.168.10.142] at time 09:53:07.548242
2017-10-30 09:53:07,548 [salt.state       ][INFO    ][31746] Executing state glusterfs.peered for 192.168.10.142
2017-10-30 09:53:07,549 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,557 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,609 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,616 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,686 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,695 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,747 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,755 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,805 [salt.state       ][INFO    ][31746] {'new': {'192.168.10.142': []}, 'old': None}
2017-10-30 09:53:07,805 [salt.state       ][INFO    ][31746] Completed state [192.168.10.142] at time 09:53:07.804954 duration_in_ms=256.711
2017-10-30 09:53:07,808 [salt.state       ][INFO    ][31746] Running state [192.168.10.141] at time 09:53:07.807944
2017-10-30 09:53:07,808 [salt.state       ][INFO    ][31746] Executing state glusterfs.peered for 192.168.10.141
2017-10-30 09:53:07,809 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,815 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,867 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,875 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,936 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:07,945 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:07,995 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:08,003 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:08,054 [salt.state       ][INFO    ][31746] {'new': {'192.168.10.142': [], '192.168.10.141': []}, 'old': {'192.168.10.142': []}}
2017-10-30 09:53:08,054 [salt.state       ][INFO    ][31746] Completed state [192.168.10.141] at time 09:53:08.053891 duration_in_ms=245.946
2017-10-30 09:53:08,055 [salt.state       ][INFO    ][31746] Running state [glance] at time 09:53:08.055016
2017-10-30 09:53:08,055 [salt.state       ][INFO    ][31746] Executing state glusterfs.created for glance
2017-10-30 09:53:08,056 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:08,064 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:08,114 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:08,122 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:08,208 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:08,216 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:08,686 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:08,696 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:08,748 [salt.state       ][INFO    ][31746] {'new': ['glance'], 'old': []}
2017-10-30 09:53:08,748 [salt.state       ][INFO    ][31746] Completed state [glance] at time 09:53:08.747989 duration_in_ms=692.972
2017-10-30 09:53:08,752 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:08.751619
2017-10-30 09:53:08,752 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:08,752 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:08,803 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:08,928 [salt.state       ][INFO    ][31746] {'pid': 31968, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:08,928 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:08.927906 duration_in_ms=176.286
2017-10-30 09:53:08,929 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:53:08.928592
2017-10-30 09:53:08,929 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-30 09:53:08,929 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 09:53:08,984 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-10-30 09:53:09,102 [salt.state       ][INFO    ][31746] {'pid': 31986, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:09,103 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:53:09.102487 duration_in_ms=173.895
2017-10-30 09:53:09,103 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:53:09.103119
2017-10-30 09:53:09,103 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-30 09:53:09,104 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 09:53:09,154 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-10-30 09:53:11,278 [salt.state       ][INFO    ][31746] {'pid': 32003, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:11,278 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:53:11.278412 duration_in_ms=2175.293
2017-10-30 09:53:11,279 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:11.278991
2017-10-30 09:53:11,279 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:11,280 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:11,332 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:11,439 [salt.state       ][INFO    ][31746] {'pid': 32020, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:11,440 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:11.439724 duration_in_ms=160.733
2017-10-30 09:53:11,440 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:53:11.440315
2017-10-30 09:53:11,441 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-30 09:53:11,441 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 09:53:11,492 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-30 09:53:11,600 [salt.state       ][INFO    ][31746] {'pid': 32037, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:11,601 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:53:11.600752 duration_in_ms=160.436
2017-10-30 09:53:11,602 [salt.state       ][INFO    ][31746] Running state [glance] at time 09:53:11.602355
2017-10-30 09:53:11,603 [salt.state       ][INFO    ][31746] Executing state glusterfs.started for glance
2017-10-30 09:53:11,603 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:11,611 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:11,663 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:11,669 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:11,724 [salt.state       ][INFO    ][31746] Volume glance is already started
2017-10-30 09:53:11,724 [salt.state       ][INFO    ][31746] Completed state [glance] at time 09:53:11.724152 duration_in_ms=121.796
2017-10-30 09:53:11,725 [salt.state       ][INFO    ][31746] Running state [keystone-keys] at time 09:53:11.725354
2017-10-30 09:53:11,726 [salt.state       ][INFO    ][31746] Executing state glusterfs.created for keystone-keys
2017-10-30 09:53:11,726 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:11,734 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:11,783 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:11,790 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:11,875 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:11,883 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:14,056 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:14,067 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:14,119 [salt.state       ][INFO    ][31746] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-10-30 09:53:14,119 [salt.state       ][INFO    ][31746] Completed state [keystone-keys] at time 09:53:14.119324 duration_in_ms=2393.968
2017-10-30 09:53:14,123 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:53:14.123125
2017-10-30 09:53:14,123 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-30 09:53:14,124 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 09:53:14,126 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command saltutil.find_job with jid 20171030095314109889
2017-10-30 09:53:14,136 [salt.minion      ][INFO    ][32145] Starting a new job with PID 32145
2017-10-30 09:53:14,147 [salt.minion      ][INFO    ][32145] Returning information for job: 20171030095314109889
2017-10-30 09:53:14,179 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-10-30 09:53:14,310 [salt.state       ][INFO    ][31746] {'pid': 32154, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:14,311 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:53:14.310652 duration_in_ms=187.526
2017-10-30 09:53:14,311 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:14.311355
2017-10-30 09:53:14,312 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:14,312 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:14,364 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:14,475 [salt.state       ][INFO    ][31746] {'pid': 32171, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:14,475 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:14.475441 duration_in_ms=164.085
2017-10-30 09:53:14,476 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:14.476190
2017-10-30 09:53:14,477 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:14,477 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:14,528 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:14,654 [salt.state       ][INFO    ][31746] {'pid': 32189, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:14,654 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:14.654366 duration_in_ms=178.175
2017-10-30 09:53:14,655 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:53:14.654959
2017-10-30 09:53:14,655 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-30 09:53:14,656 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 09:53:14,709 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-10-30 09:53:16,836 [salt.state       ][INFO    ][31746] {'pid': 32206, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:16,836 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:53:16.836046 duration_in_ms=2181.086
2017-10-30 09:53:16,837 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:16.836608
2017-10-30 09:53:16,837 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 09:53:16,837 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 09:53:16,888 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-30 09:53:17,003 [salt.state       ][INFO    ][31746] {'pid': 32224, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:17,004 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:17.003469 duration_in_ms=166.86
2017-10-30 09:53:17,005 [salt.state       ][INFO    ][31746] Running state [keystone-keys] at time 09:53:17.004715
2017-10-30 09:53:17,005 [salt.state       ][INFO    ][31746] Executing state glusterfs.started for keystone-keys
2017-10-30 09:53:17,006 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:17,013 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:17,064 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:17,073 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:17,128 [salt.state       ][INFO    ][31746] Volume keystone-keys is already started
2017-10-30 09:53:17,128 [salt.state       ][INFO    ][31746] Completed state [keystone-keys] at time 09:53:17.128303 duration_in_ms=123.587
2017-10-30 09:53:17,130 [salt.state       ][INFO    ][31746] Running state [nova_instances] at time 09:53:17.129471
2017-10-30 09:53:17,130 [salt.state       ][INFO    ][31746] Executing state glusterfs.created for nova_instances
2017-10-30 09:53:17,130 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:17,138 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:17,187 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:17,195 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:17,280 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:17,288 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:19,483 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:19,493 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:19,548 [salt.state       ][INFO    ][31746] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-10-30 09:53:19,548 [salt.state       ][INFO    ][31746] Completed state [nova_instances] at time 09:53:19.548076 duration_in_ms=2418.605
2017-10-30 09:53:19,552 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:19.552220
2017-10-30 09:53:19,552 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:19,553 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:19,605 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:19,728 [salt.state       ][INFO    ][31746] {'pid': 32341, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:19,728 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:19.728223 duration_in_ms=176.002
2017-10-30 09:53:19,729 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:53:19.728771
2017-10-30 09:53:19,729 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-30 09:53:19,730 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-30 09:53:19,780 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'' in directory '/root'
2017-10-30 09:53:19,907 [salt.state       ][INFO    ][31746] {'pid': 32359, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:19,907 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:53:19.907180 duration_in_ms=178.409
2017-10-30 09:53:19,908 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:53:19.907758
2017-10-30 09:53:19,908 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-30 09:53:19,909 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-30 09:53:19,961 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'On'' in directory '/root'
2017-10-30 09:53:20,091 [salt.state       ][INFO    ][31746] {'pid': 32376, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:20,091 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:53:20.091274 duration_in_ms=183.516
2017-10-30 09:53:20,092 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:53:20.091848
2017-10-30 09:53:20,092 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-30 09:53:20,092 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-30 09:53:20,145 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'On'' in directory '/root'
2017-10-30 09:53:22,283 [salt.state       ][INFO    ][31746] {'pid': 32393, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:22,284 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:53:22.283776 duration_in_ms=2191.926
2017-10-30 09:53:22,285 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:22.284502
2017-10-30 09:53:22,285 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:22,285 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:22,341 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:22,461 [salt.state       ][INFO    ][31746] {'pid': 32410, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:22,461 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:22.461423 duration_in_ms=176.92
2017-10-30 09:53:22,463 [salt.state       ][INFO    ][31746] Running state [nova_instances] at time 09:53:22.462775
2017-10-30 09:53:22,463 [salt.state       ][INFO    ][31746] Executing state glusterfs.started for nova_instances
2017-10-30 09:53:22,464 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:22,472 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:22,525 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:22,533 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:22,589 [salt.state       ][INFO    ][31746] Volume nova_instances is already started
2017-10-30 09:53:22,590 [salt.state       ][INFO    ][31746] Completed state [nova_instances] at time 09:53:22.589470 duration_in_ms=126.694
2017-10-30 09:53:22,591 [salt.state       ][INFO    ][31746] Running state [keystone-credential-keys] at time 09:53:22.590612
2017-10-30 09:53:22,591 [salt.state       ][INFO    ][31746] Executing state glusterfs.created for keystone-credential-keys
2017-10-30 09:53:22,591 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:22,600 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:22,652 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:22,660 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:22,747 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:22,755 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:24,276 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command saltutil.find_job with jid 20171030095324263992
2017-10-30 09:53:24,289 [salt.minion      ][INFO    ][32515] Starting a new job with PID 32515
2017-10-30 09:53:24,299 [salt.minion      ][INFO    ][32515] Returning information for job: 20171030095324263992
2017-10-30 09:53:24,948 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:24,957 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:25,010 [salt.state       ][INFO    ][31746] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-10-30 09:53:25,010 [salt.state       ][INFO    ][31746] Completed state [keystone-credential-keys] at time 09:53:25.010441 duration_in_ms=2419.829
2017-10-30 09:53:25,014 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:25.014400
2017-10-30 09:53:25,015 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 09:53:25,015 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 09:53:25,067 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-10-30 09:53:25,217 [salt.state       ][INFO    ][31746] {'pid': 32535, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:25,217 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:53:25.216935 duration_in_ms=202.534
2017-10-30 09:53:25,218 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:25.217612
2017-10-30 09:53:25,218 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 09:53:25,218 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:25,271 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:25,400 [salt.state       ][INFO    ][31746] {'pid': 32552, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:25,400 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:53:25.399759 duration_in_ms=182.137
2017-10-30 09:53:25,400 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:53:25.400281
2017-10-30 09:53:25,401 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-30 09:53:25,401 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 09:53:25,454 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-10-30 09:53:27,601 [salt.state       ][INFO    ][31746] {'pid': 32570, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:27,601 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:53:27.601335 duration_in_ms=2201.054
2017-10-30 09:53:27,602 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:27.601986
2017-10-30 09:53:27,602 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 09:53:27,603 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 09:53:27,656 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-10-30 09:53:27,783 [salt.state       ][INFO    ][31746] {'pid': 32587, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:27,783 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:53:27.782880 duration_in_ms=180.893
2017-10-30 09:53:27,784 [salt.state       ][INFO    ][31746] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:53:27.783657
2017-10-30 09:53:27,784 [salt.state       ][INFO    ][31746] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-30 09:53:27,785 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 09:53:27,838 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-10-30 09:53:27,956 [salt.state       ][INFO    ][31746] {'pid': 32604, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-10-30 09:53:27,957 [salt.state       ][INFO    ][31746] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:53:27.956492 duration_in_ms=172.835
2017-10-30 09:53:27,958 [salt.state       ][INFO    ][31746] Running state [keystone-credential-keys] at time 09:53:27.957827
2017-10-30 09:53:27,958 [salt.state       ][INFO    ][31746] Executing state glusterfs.started for keystone-credential-keys
2017-10-30 09:53:27,959 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:27,967 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:28,018 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --version' in directory '/root'
2017-10-30 09:53:28,026 [salt.loaded.int.module.cmdmod][INFO    ][31746] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 09:53:28,082 [salt.state       ][INFO    ][31746] Volume keystone-credential-keys is already started
2017-10-30 09:53:28,083 [salt.state       ][INFO    ][31746] Completed state [keystone-credential-keys] at time 09:53:28.082652 duration_in_ms=124.824
2017-10-30 09:53:28,085 [salt.minion      ][INFO    ][31746] Returning information for job: 20171030095304054412
2017-10-30 09:53:41,477 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command test.ping with jid 20171030095341465826
2017-10-30 09:53:41,488 [salt.minion      ][INFO    ][32640] Starting a new job with PID 32640
2017-10-30 09:53:41,521 [salt.minion      ][INFO    ][32640] Returning information for job: 20171030095341465826
2017-10-30 09:53:41,685 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command cmd.run with jid 20171030095341666201
2017-10-30 09:53:41,696 [salt.minion      ][INFO    ][32645] Starting a new job with PID 32645
2017-10-30 09:53:41,700 [salt.loaded.int.module.cmdmod][INFO    ][32645] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-30 09:53:41,815 [salt.minion      ][INFO    ][32645] Returning information for job: 20171030095341666201
2017-10-30 10:34:51,307 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command test.ping with jid 20171030103451295865
2017-10-30 10:34:51,322 [salt.minion      ][INFO    ][33366] Starting a new job with PID 33366
2017-10-30 10:34:51,361 [salt.minion      ][INFO    ][33366] Returning information for job: 20171030103451295865
2017-10-30 10:35:25,514 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command state.sls with jid 20171030103525498919
2017-10-30 10:35:25,525 [salt.minion      ][INFO    ][33387] Starting a new job with PID 33387
2017-10-30 10:35:26,078 [salt.state       ][INFO    ][33387] Loading fresh modules for state activity
2017-10-30 10:35:26,109 [salt.fileclient  ][INFO    ][33387] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-10-30 10:35:26,130 [salt.fileclient  ][INFO    ][33387] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-10-30 10:35:26,158 [salt.fileclient  ][INFO    ][33387] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 10:35:26,463 [salt.state       ][INFO    ][33387] Running state [lsof] at time 10:35:26.462635
2017-10-30 10:35:26,463 [salt.state       ][INFO    ][33387] Executing state pkg.installed for lsof
2017-10-30 10:35:26,463 [salt.loaded.int.module.cmdmod][INFO    ][33387] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 10:35:26,752 [salt.state       ][INFO    ][33387] Package lsof is already installed
2017-10-30 10:35:26,752 [salt.state       ][INFO    ][33387] Completed state [lsof] at time 10:35:26.751862 duration_in_ms=289.228
2017-10-30 10:35:26,752 [salt.state       ][INFO    ][33387] Running state [keepalived] at time 10:35:26.752134
2017-10-30 10:35:26,752 [salt.state       ][INFO    ][33387] Executing state pkg.installed for keepalived
2017-10-30 10:35:26,756 [salt.state       ][INFO    ][33387] Package keepalived is already installed
2017-10-30 10:35:26,757 [salt.state       ][INFO    ][33387] Completed state [keepalived] at time 10:35:26.756547 duration_in_ms=4.413
2017-10-30 10:35:26,758 [salt.state       ][INFO    ][33387] Running state [/etc/keepalived/keepalived.conf] at time 10:35:26.758238
2017-10-30 10:35:26,759 [salt.state       ][INFO    ][33387] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-10-30 10:35:26,787 [salt.fileclient  ][INFO    ][33387] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-10-30 10:35:26,831 [salt.fileclient  ][INFO    ][33387] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-10-30 10:35:26,837 [salt.state       ][INFO    ][33387] File /etc/keepalived/keepalived.conf is in the correct state
2017-10-30 10:35:26,837 [salt.state       ][INFO    ][33387] Completed state [/etc/keepalived/keepalived.conf] at time 10:35:26.836998 duration_in_ms=78.76
2017-10-30 10:35:26,838 [salt.state       ][INFO    ][33387] Running state [keepalived] at time 10:35:26.837675
2017-10-30 10:35:26,838 [salt.state       ][INFO    ][33387] Executing state service.running for keepalived
2017-10-30 10:35:26,838 [salt.loaded.int.module.cmdmod][INFO    ][33387] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-10-30 10:35:26,854 [salt.loaded.int.module.cmdmod][INFO    ][33387] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-10-30 10:35:26,863 [salt.loaded.int.module.cmdmod][INFO    ][33387] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-10-30 10:35:26,869 [salt.state       ][INFO    ][33387] The service keepalived is already running
2017-10-30 10:35:26,869 [salt.state       ][INFO    ][33387] Completed state [keepalived] at time 10:35:26.869431 duration_in_ms=31.756
2017-10-30 10:35:26,870 [salt.minion      ][INFO    ][33387] Returning information for job: 20171030103525498919
2017-10-30 10:35:29,262 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command pillar.get with jid 20171030103529250637
2017-10-30 10:35:29,276 [salt.minion      ][INFO    ][33412] Starting a new job with PID 33412
2017-10-30 10:35:29,280 [salt.minion      ][INFO    ][33412] Returning information for job: 20171030103529250637
2017-10-30 10:36:19,839 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command state.sls with jid 20171030103619826166
2017-10-30 10:36:19,851 [salt.minion      ][INFO    ][33424] Starting a new job with PID 33424
2017-10-30 10:36:22,072 [salt.state       ][INFO    ][33424] Loading fresh modules for state activity
2017-10-30 10:36:22,098 [salt.fileclient  ][INFO    ][33424] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 10:36:22,122 [salt.fileclient  ][INFO    ][33424] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 10:36:22,441 [salt.state       ][INFO    ][33424] Running state [attr] at time 10:36:22.440477
2017-10-30 10:36:22,441 [salt.state       ][INFO    ][33424] Executing state pkg.installed for attr
2017-10-30 10:36:22,441 [salt.loaded.int.module.cmdmod][INFO    ][33424] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 10:36:22,749 [salt.state       ][INFO    ][33424] Package attr is already installed
2017-10-30 10:36:22,749 [salt.state       ][INFO    ][33424] Completed state [attr] at time 10:36:22.749348 duration_in_ms=308.872
2017-10-30 10:36:22,750 [salt.state       ][INFO    ][33424] Running state [glusterfs-server] at time 10:36:22.749588
2017-10-30 10:36:22,750 [salt.state       ][INFO    ][33424] Executing state pkg.installed for glusterfs-server
2017-10-30 10:36:22,754 [salt.state       ][INFO    ][33424] Package glusterfs-server is already installed
2017-10-30 10:36:22,754 [salt.state       ][INFO    ][33424] Completed state [glusterfs-server] at time 10:36:22.753671 duration_in_ms=4.083
2017-10-30 10:36:22,754 [salt.state       ][INFO    ][33424] Running state [bc] at time 10:36:22.753839
2017-10-30 10:36:22,754 [salt.state       ][INFO    ][33424] Executing state pkg.installed for bc
2017-10-30 10:36:22,758 [salt.state       ][INFO    ][33424] Package bc is already installed
2017-10-30 10:36:22,758 [salt.state       ][INFO    ][33424] Completed state [bc] at time 10:36:22.757667 duration_in_ms=3.827
2017-10-30 10:36:22,758 [salt.state       ][INFO    ][33424] Running state [glusterfs-server] at time 10:36:22.758427
2017-10-30 10:36:22,759 [salt.state       ][INFO    ][33424] Executing state service.running for glusterfs-server
2017-10-30 10:36:22,759 [salt.loaded.int.module.cmdmod][INFO    ][33424] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 10:36:22,775 [salt.loaded.int.module.cmdmod][INFO    ][33424] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:22,782 [salt.loaded.int.module.cmdmod][INFO    ][33424] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:22,791 [salt.state       ][INFO    ][33424] The service glusterfs-server is already running
2017-10-30 10:36:22,791 [salt.state       ][INFO    ][33424] Completed state [glusterfs-server] at time 10:36:22.791373 duration_in_ms=32.946
2017-10-30 10:36:22,793 [salt.state       ][INFO    ][33424] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:22.793058
2017-10-30 10:36:22,793 [salt.state       ][INFO    ][33424] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 10:36:22,795 [salt.state       ][INFO    ][33424] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-30 10:36:22,796 [salt.state       ][INFO    ][33424] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:22.795573 duration_in_ms=2.515
2017-10-30 10:36:22,796 [salt.state       ][INFO    ][33424] Running state [/srv/glusterfs/glance] at time 10:36:22.795746
2017-10-30 10:36:22,796 [salt.state       ][INFO    ][33424] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 10:36:22,796 [salt.state       ][INFO    ][33424] Directory /srv/glusterfs/glance is in the correct state
2017-10-30 10:36:22,796 [salt.state       ][INFO    ][33424] Completed state [/srv/glusterfs/glance] at time 10:36:22.796468 duration_in_ms=0.721
2017-10-30 10:36:22,797 [salt.state       ][INFO    ][33424] Running state [/srv/glusterfs/keystone-keys] at time 10:36:22.796641
2017-10-30 10:36:22,797 [salt.state       ][INFO    ][33424] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 10:36:22,797 [salt.state       ][INFO    ][33424] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-30 10:36:22,797 [salt.state       ][INFO    ][33424] Completed state [/srv/glusterfs/keystone-keys] at time 10:36:22.797391 duration_in_ms=0.749
2017-10-30 10:36:22,798 [salt.state       ][INFO    ][33424] Running state [/srv/glusterfs/nova_instances] at time 10:36:22.797589
2017-10-30 10:36:22,798 [salt.state       ][INFO    ][33424] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 10:36:22,798 [salt.state       ][INFO    ][33424] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-30 10:36:22,798 [salt.state       ][INFO    ][33424] Completed state [/srv/glusterfs/nova_instances] at time 10:36:22.798242 duration_in_ms=0.652
2017-10-30 10:36:22,798 [salt.state       ][INFO    ][33424] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:36:22.798421
2017-10-30 10:36:22,799 [salt.state       ][INFO    ][33424] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 10:36:22,799 [salt.state       ][INFO    ][33424] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-30 10:36:22,799 [salt.state       ][INFO    ][33424] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:36:22.799143 duration_in_ms=0.723
2017-10-30 10:36:22,800 [salt.minion      ][INFO    ][33424] Returning information for job: 20171030103619826166
2017-10-30 10:36:24,457 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command test.ping with jid 20171030103624444443
2017-10-30 10:36:24,470 [salt.minion      ][INFO    ][33448] Starting a new job with PID 33448
2017-10-30 10:36:24,505 [salt.minion      ][INFO    ][33448] Returning information for job: 20171030103624444443
2017-10-30 10:36:24,661 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command state.sls with jid 20171030103624644469
2017-10-30 10:36:24,672 [salt.minion      ][INFO    ][33453] Starting a new job with PID 33453
2017-10-30 10:36:25,231 [salt.state       ][INFO    ][33453] Loading fresh modules for state activity
2017-10-30 10:36:25,259 [salt.fileclient  ][INFO    ][33453] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-10-30 10:36:25,295 [salt.fileclient  ][INFO    ][33453] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 10:36:25,374 [salt.fileclient  ][INFO    ][33453] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-10-30 10:36:25,395 [salt.fileclient  ][INFO    ][33453] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-10-30 10:36:25,710 [salt.state       ][INFO    ][33453] Running state [attr] at time 10:36:25.710292
2017-10-30 10:36:25,711 [salt.state       ][INFO    ][33453] Executing state pkg.installed for attr
2017-10-30 10:36:25,711 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-10-30 10:36:25,969 [salt.state       ][INFO    ][33453] Package attr is already installed
2017-10-30 10:36:25,970 [salt.state       ][INFO    ][33453] Completed state [attr] at time 10:36:25.969747 duration_in_ms=259.454
2017-10-30 10:36:25,970 [salt.state       ][INFO    ][33453] Running state [glusterfs-server] at time 10:36:25.970027
2017-10-30 10:36:25,970 [salt.state       ][INFO    ][33453] Executing state pkg.installed for glusterfs-server
2017-10-30 10:36:25,974 [salt.state       ][INFO    ][33453] Package glusterfs-server is already installed
2017-10-30 10:36:25,974 [salt.state       ][INFO    ][33453] Completed state [glusterfs-server] at time 10:36:25.974091 duration_in_ms=4.064
2017-10-30 10:36:25,974 [salt.state       ][INFO    ][33453] Running state [bc] at time 10:36:25.974285
2017-10-30 10:36:25,974 [salt.state       ][INFO    ][33453] Executing state pkg.installed for bc
2017-10-30 10:36:25,978 [salt.state       ][INFO    ][33453] Package bc is already installed
2017-10-30 10:36:25,978 [salt.state       ][INFO    ][33453] Completed state [bc] at time 10:36:25.978237 duration_in_ms=3.952
2017-10-30 10:36:25,979 [salt.state       ][INFO    ][33453] Running state [glusterfs-server] at time 10:36:25.979200
2017-10-30 10:36:25,979 [salt.state       ][INFO    ][33453] Executing state service.running for glusterfs-server
2017-10-30 10:36:25,980 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-10-30 10:36:25,997 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:26,005 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-10-30 10:36:26,015 [salt.state       ][INFO    ][33453] The service glusterfs-server is already running
2017-10-30 10:36:26,015 [salt.state       ][INFO    ][33453] Completed state [glusterfs-server] at time 10:36:26.015176 duration_in_ms=35.975
2017-10-30 10:36:26,017 [salt.state       ][INFO    ][33453] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:26.016640
2017-10-30 10:36:26,017 [salt.state       ][INFO    ][33453] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-10-30 10:36:26,019 [salt.state       ][INFO    ][33453] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-10-30 10:36:26,019 [salt.state       ][INFO    ][33453] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 10:36:26.018982 duration_in_ms=2.343
2017-10-30 10:36:26,019 [salt.state       ][INFO    ][33453] Running state [/srv/glusterfs/glance] at time 10:36:26.019188
2017-10-30 10:36:26,019 [salt.state       ][INFO    ][33453] Executing state file.directory for /srv/glusterfs/glance
2017-10-30 10:36:26,020 [salt.state       ][INFO    ][33453] Directory /srv/glusterfs/glance is in the correct state
2017-10-30 10:36:26,020 [salt.state       ][INFO    ][33453] Completed state [/srv/glusterfs/glance] at time 10:36:26.020000 duration_in_ms=0.811
2017-10-30 10:36:26,020 [salt.state       ][INFO    ][33453] Running state [/srv/glusterfs/keystone-keys] at time 10:36:26.020190
2017-10-30 10:36:26,020 [salt.state       ][INFO    ][33453] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-10-30 10:36:26,021 [salt.state       ][INFO    ][33453] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-10-30 10:36:26,021 [salt.state       ][INFO    ][33453] Completed state [/srv/glusterfs/keystone-keys] at time 10:36:26.020956 duration_in_ms=0.765
2017-10-30 10:36:26,021 [salt.state       ][INFO    ][33453] Running state [/srv/glusterfs/nova_instances] at time 10:36:26.021150
2017-10-30 10:36:26,021 [salt.state       ][INFO    ][33453] Executing state file.directory for /srv/glusterfs/nova_instances
2017-10-30 10:36:26,022 [salt.state       ][INFO    ][33453] Directory /srv/glusterfs/nova_instances is in the correct state
2017-10-30 10:36:26,022 [salt.state       ][INFO    ][33453] Completed state [/srv/glusterfs/nova_instances] at time 10:36:26.021977 duration_in_ms=0.827
2017-10-30 10:36:26,022 [salt.state       ][INFO    ][33453] Running state [/srv/glusterfs/keystone-credential-keys] at time 10:36:26.022149
2017-10-30 10:36:26,022 [salt.state       ][INFO    ][33453] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-10-30 10:36:26,023 [salt.state       ][INFO    ][33453] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-10-30 10:36:26,023 [salt.state       ][INFO    ][33453] Completed state [/srv/glusterfs/keystone-credential-keys] at time 10:36:26.022914 duration_in_ms=0.765
2017-10-30 10:36:26,063 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command ['git', '--version'] in directory '/root'
2017-10-30 10:36:26,152 [salt.state       ][INFO    ][33453] Running state [sleep 5] at time 10:36:26.151609
2017-10-30 10:36:26,152 [salt.state       ][INFO    ][33453] Executing state cmd.wait for sleep 5
2017-10-30 10:36:26,152 [salt.state       ][INFO    ][33453] No changes made for sleep 5
2017-10-30 10:36:26,152 [salt.state       ][INFO    ][33453] Completed state [sleep 5] at time 10:36:26.152233 duration_in_ms=0.625
2017-10-30 10:36:26,153 [salt.state       ][INFO    ][33453] Running state [192.168.10.143] at time 10:36:26.152719
2017-10-30 10:36:26,153 [salt.state       ][INFO    ][33453] Executing state glusterfs.peered for 192.168.10.143
2017-10-30 10:36:26,153 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,163 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,218 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,227 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,280 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,288 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,341 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,349 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,400 [salt.state       ][INFO    ][33453] Peering with localhost is not needed
2017-10-30 10:36:26,400 [salt.state       ][INFO    ][33453] Completed state [192.168.10.143] at time 10:36:26.400136 duration_in_ms=247.417
2017-10-30 10:36:26,403 [salt.state       ][INFO    ][33453] Running state [192.168.10.142] at time 10:36:26.403280
2017-10-30 10:36:26,404 [salt.state       ][INFO    ][33453] Executing state glusterfs.peered for 192.168.10.142
2017-10-30 10:36:26,404 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,412 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,464 [salt.state       ][INFO    ][33453] Host 192.168.10.142 already peered
2017-10-30 10:36:26,464 [salt.state       ][INFO    ][33453] Completed state [192.168.10.142] at time 10:36:26.463859 duration_in_ms=60.579
2017-10-30 10:36:26,467 [salt.state       ][INFO    ][33453] Running state [192.168.10.141] at time 10:36:26.466911
2017-10-30 10:36:26,467 [salt.state       ][INFO    ][33453] Executing state glusterfs.peered for 192.168.10.141
2017-10-30 10:36:26,468 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,475 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,528 [salt.state       ][INFO    ][33453] Host 192.168.10.141 already peered
2017-10-30 10:36:26,528 [salt.state       ][INFO    ][33453] Completed state [192.168.10.141] at time 10:36:26.527881 duration_in_ms=60.97
2017-10-30 10:36:26,529 [salt.state       ][INFO    ][33453] Running state [glance] at time 10:36:26.528965
2017-10-30 10:36:26,529 [salt.state       ][INFO    ][33453] Executing state glusterfs.created for glance
2017-10-30 10:36:26,530 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,538 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,590 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,597 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,657 [salt.state       ][INFO    ][33453] Volume glance already exists and is started.
2017-10-30 10:36:26,657 [salt.state       ][INFO    ][33453] Completed state [glance] at time 10:36:26.656980 duration_in_ms=128.015
2017-10-30 10:36:26,661 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:26.660546
2017-10-30 10:36:26,661 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:26,661 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:26,714 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:26,714 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:26.713899 duration_in_ms=53.352
2017-10-30 10:36:26,714 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:36:26.714376
2017-10-30 10:36:26,715 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-10-30 10:36:26,715 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 10:36:26,765 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:26,765 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 10:36:26.765370 duration_in_ms=50.994
2017-10-30 10:36:26,766 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:36:26.765946
2017-10-30 10:36:26,766 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-10-30 10:36:26,767 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 10:36:26,820 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:26,820 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 10:36:26.820298 duration_in_ms=54.352
2017-10-30 10:36:26,821 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:26.820898
2017-10-30 10:36:26,821 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:26,822 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:26,875 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:26,875 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:26.875422 duration_in_ms=54.524
2017-10-30 10:36:26,876 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:36:26.876038
2017-10-30 10:36:26,876 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-10-30 10:36:26,877 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 10:36:26,927 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:26,927 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 10:36:26.926942 duration_in_ms=50.904
2017-10-30 10:36:26,928 [salt.state       ][INFO    ][33453] Running state [glance] at time 10:36:26.928205
2017-10-30 10:36:26,928 [salt.state       ][INFO    ][33453] Executing state glusterfs.started for glance
2017-10-30 10:36:26,929 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,937 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:26,990 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:26,998 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:27,056 [salt.state       ][INFO    ][33453] Volume glance is already started
2017-10-30 10:36:27,056 [salt.state       ][INFO    ][33453] Completed state [glance] at time 10:36:27.055950 duration_in_ms=127.744
2017-10-30 10:36:27,057 [salt.state       ][INFO    ][33453] Running state [keystone-keys] at time 10:36:27.056913
2017-10-30 10:36:27,057 [salt.state       ][INFO    ][33453] Executing state glusterfs.created for keystone-keys
2017-10-30 10:36:27,058 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:27,065 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:27,114 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:27,122 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:27,179 [salt.state       ][INFO    ][33453] Volume keystone-keys already exists and is started.
2017-10-30 10:36:27,180 [salt.state       ][INFO    ][33453] Completed state [keystone-keys] at time 10:36:27.179480 duration_in_ms=122.566
2017-10-30 10:36:27,183 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:36:27.183278
2017-10-30 10:36:27,184 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-10-30 10:36:27,184 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 10:36:27,237 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,238 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 10:36:27.237745 duration_in_ms=54.467
2017-10-30 10:36:27,238 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:27.238373
2017-10-30 10:36:27,239 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:27,239 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:27,293 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,293 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:27.292926 duration_in_ms=54.553
2017-10-30 10:36:27,294 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:27.293590
2017-10-30 10:36:27,294 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:27,294 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:27,347 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,347 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:27.347425 duration_in_ms=53.835
2017-10-30 10:36:27,348 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:36:27.348043
2017-10-30 10:36:27,348 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-10-30 10:36:27,349 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 10:36:27,401 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,401 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 10:36:27.401037 duration_in_ms=52.994
2017-10-30 10:36:27,402 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:27.401682
2017-10-30 10:36:27,402 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 10:36:27,402 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 10:36:27,456 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,456 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:27.456294 duration_in_ms=54.611
2017-10-30 10:36:27,458 [salt.state       ][INFO    ][33453] Running state [keystone-keys] at time 10:36:27.457660
2017-10-30 10:36:27,458 [salt.state       ][INFO    ][33453] Executing state glusterfs.started for keystone-keys
2017-10-30 10:36:27,458 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:27,466 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:27,521 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:27,530 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:27,589 [salt.state       ][INFO    ][33453] Volume keystone-keys is already started
2017-10-30 10:36:27,589 [salt.state       ][INFO    ][33453] Completed state [keystone-keys] at time 10:36:27.589390 duration_in_ms=131.729
2017-10-30 10:36:27,590 [salt.state       ][INFO    ][33453] Running state [nova_instances] at time 10:36:27.590390
2017-10-30 10:36:27,591 [salt.state       ][INFO    ][33453] Executing state glusterfs.created for nova_instances
2017-10-30 10:36:27,591 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:27,599 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:27,651 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:27,659 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:27,720 [salt.state       ][INFO    ][33453] Volume nova_instances already exists and is started.
2017-10-30 10:36:27,720 [salt.state       ][INFO    ][33453] Completed state [nova_instances] at time 10:36:27.720140 duration_in_ms=129.749
2017-10-30 10:36:27,726 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:27.725965
2017-10-30 10:36:27,726 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:27,727 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:27,781 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,782 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:27.781598 duration_in_ms=55.633
2017-10-30 10:36:27,782 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:36:27.782190
2017-10-30 10:36:27,782 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-10-30 10:36:27,783 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-10-30 10:36:27,837 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,837 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 10:36:27.837012 duration_in_ms=54.821
2017-10-30 10:36:27,838 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:36:27.837698
2017-10-30 10:36:27,838 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-10-30 10:36:27,839 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-10-30 10:36:27,892 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,892 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 10:36:27.892270 duration_in_ms=54.572
2017-10-30 10:36:27,893 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:36:27.892974
2017-10-30 10:36:27,893 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-10-30 10:36:27,894 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-10-30 10:36:27,946 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:27,946 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 10:36:27.946432 duration_in_ms=53.457
2017-10-30 10:36:27,947 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:27.947053
2017-10-30 10:36:27,947 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:27,948 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:27,1000 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:28,000 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:28.000027 duration_in_ms=52.974
2017-10-30 10:36:28,001 [salt.state       ][INFO    ][33453] Running state [nova_instances] at time 10:36:28.001417
2017-10-30 10:36:28,002 [salt.state       ][INFO    ][33453] Executing state glusterfs.started for nova_instances
2017-10-30 10:36:28,002 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:28,011 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:28,063 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:28,071 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:28,128 [salt.state       ][INFO    ][33453] Volume nova_instances is already started
2017-10-30 10:36:28,129 [salt.state       ][INFO    ][33453] Completed state [nova_instances] at time 10:36:28.128712 duration_in_ms=127.296
2017-10-30 10:36:28,130 [salt.state       ][INFO    ][33453] Running state [keystone-credential-keys] at time 10:36:28.129814
2017-10-30 10:36:28,130 [salt.state       ][INFO    ][33453] Executing state glusterfs.created for keystone-credential-keys
2017-10-30 10:36:28,131 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:28,139 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:28,191 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:28,199 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:28,257 [salt.state       ][INFO    ][33453] Volume keystone-credential-keys already exists and is started.
2017-10-30 10:36:28,258 [salt.state       ][INFO    ][33453] Completed state [keystone-credential-keys] at time 10:36:28.257700 duration_in_ms=127.885
2017-10-30 10:36:28,262 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:28.261908
2017-10-30 10:36:28,262 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-10-30 10:36:28,263 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-10-30 10:36:28,316 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:28,316 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 10:36:28.316017 duration_in_ms=54.108
2017-10-30 10:36:28,317 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:28.316680
2017-10-30 10:36:28,317 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-10-30 10:36:28,318 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:28,372 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:28,372 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 10:36:28.371773 duration_in_ms=55.093
2017-10-30 10:36:28,372 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:36:28.372368
2017-10-30 10:36:28,373 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-10-30 10:36:28,373 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-10-30 10:36:28,426 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:28,426 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 10:36:28.425914 duration_in_ms=53.546
2017-10-30 10:36:28,427 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:28.426582
2017-10-30 10:36:28,427 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-10-30 10:36:28,427 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-10-30 10:36:28,479 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:28,480 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 10:36:28.479616 duration_in_ms=53.033
2017-10-30 10:36:28,480 [salt.state       ][INFO    ][33453] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:36:28.480301
2017-10-30 10:36:28,481 [salt.state       ][INFO    ][33453] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-10-30 10:36:28,481 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-10-30 10:36:28,535 [salt.state       ][INFO    ][33453] unless execution succeeded
2017-10-30 10:36:28,535 [salt.state       ][INFO    ][33453] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 10:36:28.534798 duration_in_ms=54.497
2017-10-30 10:36:28,536 [salt.state       ][INFO    ][33453] Running state [keystone-credential-keys] at time 10:36:28.536045
2017-10-30 10:36:28,536 [salt.state       ][INFO    ][33453] Executing state glusterfs.started for keystone-credential-keys
2017-10-30 10:36:28,537 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:28,544 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:28,597 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --version' in directory '/root'
2017-10-30 10:36:28,606 [salt.loaded.int.module.cmdmod][INFO    ][33453] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-10-30 10:36:28,663 [salt.state       ][INFO    ][33453] Volume keystone-credential-keys is already started
2017-10-30 10:36:28,664 [salt.state       ][INFO    ][33453] Completed state [keystone-credential-keys] at time 10:36:28.663494 duration_in_ms=127.448
2017-10-30 10:36:28,666 [salt.minion      ][INFO    ][33453] Returning information for job: 20171030103624644469
2017-10-30 10:36:40,631 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command test.ping with jid 20171030103640619817
2017-10-30 10:36:40,644 [salt.minion      ][INFO    ][33837] Starting a new job with PID 33837
2017-10-30 10:36:40,680 [salt.minion      ][INFO    ][33837] Returning information for job: 20171030103640619817
2017-10-30 10:36:40,842 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command cmd.run with jid 20171030103640826984
2017-10-30 10:36:40,853 [salt.minion      ][INFO    ][33842] Starting a new job with PID 33842
2017-10-30 10:36:40,858 [salt.loaded.int.module.cmdmod][INFO    ][33842] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-10-30 10:36:40,981 [salt.minion      ][INFO    ][33842] Returning information for job: 20171030103640826984
2017-10-30 10:39:42,970 [salt.utils.schedule][INFO    ][24807] Running scheduled job: __mine_interval
2017-10-30 10:59:49,853 [salt.minion      ][INFO    ][24807] User sudo_ubuntu Executing command cp.push_dir with jid 20171030105949841940
2017-10-30 10:59:49,867 [salt.minion      ][INFO    ][34109] Starting a new job with PID 34109
