2017-11-28 09:17:43,054 [salt.cli.daemons ][WARNING ][2380] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-28 09:18:04,679 [salt.state       ][ERROR   ][3648] No contents found in top file
2017-11-28 09:21:18,810 [salt.utils.parsers][WARNING ][2380] Minion received a SIGTERM. Exiting.
2017-11-28 09:23:34,556 [salt.cli.daemons ][WARNING ][3348] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-28 09:26:15,556 [py.warnings      ][WARNING ][14234] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-28 09:27:38,311 [salt.loaded.int.module.cmdmod][ERROR   ][14234] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-28 09:27:38,312 [salt.loaded.int.module.cmdmod][ERROR   ][14234] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-28 09:27:45,957 [salt.loaded.int.module.cmdmod][ERROR   ][14234] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-28 09:27:45,958 [salt.loaded.int.module.cmdmod][ERROR   ][14234] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-28 09:27:54,935 [salt.loaded.int.module.cmdmod][ERROR   ][14234] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-28 09:27:54,936 [salt.loaded.int.module.cmdmod][ERROR   ][14234] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-28 09:28:09,086 [salt.loaded.int.module.cmdmod][ERROR   ][14234] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-28 09:28:09,086 [salt.loaded.int.module.cmdmod][ERROR   ][14234] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-28 09:28:19,216 [salt.loaded.int.module.cmdmod][ERROR   ][14234] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-28 09:28:19,216 [salt.loaded.int.module.cmdmod][ERROR   ][14234] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-28 09:28:25,890 [salt.loaded.int.module.cmdmod][ERROR   ][14234] Command 'while true; do salt-call saltutil.running|grep fun: && continue; salt-call --local service.restart salt-minion; break; done' failed with return code: None
2017-11-28 09:28:28,988 [salt.loaded.int.module.cmdmod][INFO    ][21716] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-28 09:28:29,000 [salt.loaded.int.module.cmdmod][INFO    ][21716] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-28 09:28:29,015 [salt.loaded.int.module.cmdmod][INFO    ][21716] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-28 09:28:29,028 [salt.utils.parsers][WARNING ][3348] Minion received a SIGTERM. Exiting.
2017-11-28 09:28:29,429 [salt.cli.daemons ][INFO    ][21799] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-ovs-dpdk-ha.local"
2017-11-28 09:28:30,313 [salt.minion      ][INFO    ][21799] Creating minion process manager
2017-11-28 09:28:30,314 [salt.cli.daemons ][WARNING ][21799] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-28 09:28:30,314 [salt.cli.daemons ][INFO    ][21799] The Salt Minion is starting up
2017-11-28 09:28:30,314 [salt.minion      ][INFO    ][21799] Minion is starting as user 'root'
2017-11-28 09:28:30,314 [salt.utils.event ][INFO    ][21799] Starting pull socket on /var/run/salt/minion/minion_event_4fcafbd0f5_pull.ipc
2017-11-28 09:28:31,390 [salt.loaded.int.module.cmdmod][INFO    ][21799] Executing command ['date', '+%z'] in directory '/root'
2017-11-28 09:28:31,402 [salt.utils.schedule][INFO    ][21799] Updating job settings for scheduled job: __mine_interval
2017-11-28 09:28:31,406 [salt.minion      ][INFO    ][21799] Added mine.update to scheduler
2017-11-28 09:28:31,759 [salt.minion      ][INFO    ][21799] Minion is ready to receive requests!
2017-11-28 09:28:32,760 [salt.utils.schedule][INFO    ][21799] Running scheduled job: __mine_interval
2017-11-28 09:28:38,987 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128092838981230
2017-11-28 09:28:39,010 [salt.minion      ][INFO    ][21893] Starting a new job with PID 21893
2017-11-28 09:28:39,027 [salt.minion      ][INFO    ][21893] Returning information for job: 20171128092838981230
2017-11-28 09:28:49,032 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128092849028261
2017-11-28 09:28:49,054 [salt.minion      ][INFO    ][21906] Starting a new job with PID 21906
2017-11-28 09:28:49,069 [salt.minion      ][INFO    ][21906] Returning information for job: 20171128092849028261
2017-11-28 09:29:00,029 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command state.sls with jid 20171128092900022794
2017-11-28 09:29:00,052 [salt.minion      ][INFO    ][21911] Starting a new job with PID 21911
2017-11-28 09:29:02,606 [salt.state       ][INFO    ][21911] Loading fresh modules for state activity
2017-11-28 09:29:02,650 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-28 09:29:02,681 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-28 09:29:02,732 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-28 09:29:02,843 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-28 09:29:02,861 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-28 09:29:02,897 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-28 09:29:02,975 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-28 09:29:03,012 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-28 09:29:03,149 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-28 09:29:03,198 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-28 09:29:03,215 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-28 09:29:03,237 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-28 09:29:03,251 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-28 09:29:03,284 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-28 09:29:03,310 [py.warnings      ][WARNING ][21911] /usr/lib/python2.7/dist-packages/salt/utils/templates.py:73: DeprecationWarning: Starting in 2015.5, cmd.run uses python_shell=False by default, which doesn't support shellisms (pipes, env variables, etc). cmd.run is currently aliased to cmd.shell to prevent breakage. Please switch to cmd.shell or set python_shell=True to avoid breakage in the future, when this aliasing is removed.

2017-11-28 09:29:03,310 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'salt-minion --version' in directory '/root'
2017-11-28 09:29:03,692 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-28 09:29:03,734 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-28 09:29:03,816 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-28 09:29:03,834 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-28 09:29:03,901 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-28 09:29:03,925 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-28 09:29:03,956 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-28 09:29:03,999 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-28 09:29:04,086 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-28 09:29:04,111 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-28 09:29:04,184 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-28 09:29:04,280 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-28 09:29:04,388 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-28 09:29:04,419 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-28 09:29:04,438 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-28 09:29:04,459 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-28 09:29:04,471 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-28 09:29:04,501 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-28 09:29:04,526 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'salt-minion --version' in directory '/root'
2017-11-28 09:29:04,922 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-28 09:29:04,949 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-28 09:29:04,966 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-28 09:29:04,983 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-28 09:29:05,048 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'reclass/meta/meta.yml'
2017-11-28 09:29:05,065 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'reclass/map.jinja'
2017-11-28 09:29:05,097 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-28 09:29:05,143 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-28 09:29:05,231 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-28 09:29:05,442 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-28 09:29:05,789 [salt.state       ][INFO    ][21911] Running state [salt-minion] at time 09:29:05.789367
2017-11-28 09:29:05,790 [salt.state       ][INFO    ][21911] Executing state pkg.installed for salt-minion
2017-11-28 09:29:05,790 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:29:06,175 [salt.state       ][INFO    ][21911] Package salt-minion is already installed
2017-11-28 09:29:06,175 [salt.state       ][INFO    ][21911] Completed state [salt-minion] at time 09:29:06.174901 duration_in_ms=385.534
2017-11-28 09:29:06,175 [salt.state       ][INFO    ][21911] Running state [salt_minion_dependency_packages] at time 09:29:06.175241
2017-11-28 09:29:06,176 [salt.state       ][INFO    ][21911] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-28 09:29:06,180 [salt.state       ][INFO    ][21911] All specified packages are already installed
2017-11-28 09:29:06,180 [salt.state       ][INFO    ][21911] Completed state [salt_minion_dependency_packages] at time 09:29:06.180007 duration_in_ms=4.765
2017-11-28 09:29:06,182 [salt.state       ][INFO    ][21911] Running state [/etc/salt/minion.d/minion.conf] at time 09:29:06.182141
2017-11-28 09:29:06,182 [salt.state       ][INFO    ][21911] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-28 09:29:06,213 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-28 09:29:06,330 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-28 09:29:06,474 [salt.fileclient  ][INFO    ][21911] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-28 09:29:06,552 [salt.state       ][INFO    ][21911] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-28 09:29:06,553 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/minion.d/minion.conf] at time 09:29:06.552530 duration_in_ms=370.388
2017-11-28 09:29:06,553 [salt.state       ][INFO    ][21911] Running state [/etc/salt/minion.d/_virt.conf] at time 09:29:06.553094
2017-11-28 09:29:06,553 [salt.state       ][INFO    ][21911] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-28 09:29:06,662 [salt.state       ][INFO    ][21911] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-28 09:29:06,662 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/minion.d/_virt.conf] at time 09:29:06.662377 duration_in_ms=109.282
2017-11-28 09:29:06,666 [salt.state       ][INFO    ][21911] Running state [salt-minion] at time 09:29:06.666180
2017-11-28 09:29:06,667 [salt.state       ][INFO    ][21911] Executing state service.running for salt-minion
2017-11-28 09:29:06,668 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-28 09:29:06,700 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-28 09:29:06,715 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-28 09:29:06,730 [salt.state       ][INFO    ][21911] The service salt-minion is already running
2017-11-28 09:29:06,730 [salt.state       ][INFO    ][21911] Completed state [salt-minion] at time 09:29:06.730119 duration_in_ms=63.938
2017-11-28 09:29:06,733 [salt.state       ][INFO    ][21911] Running state [/etc/salt/grains.d] at time 09:29:06.732936
2017-11-28 09:29:06,733 [salt.state       ][INFO    ][21911] Executing state file.directory for /etc/salt/grains.d
2017-11-28 09:29:06,735 [salt.state       ][INFO    ][21911] Directory /etc/salt/grains.d is in the correct state
2017-11-28 09:29:06,735 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/grains.d] at time 09:29:06.734823 duration_in_ms=1.887
2017-11-28 09:29:06,736 [salt.state       ][INFO    ][21911] Running state [/etc/salt/grains] at time 09:29:06.735779
2017-11-28 09:29:06,736 [salt.state       ][INFO    ][21911] Executing state file.managed for /etc/salt/grains
2017-11-28 09:29:06,737 [salt.state       ][INFO    ][21911] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-28 09:29:06,737 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/grains] at time 09:29:06.737132 duration_in_ms=1.352
2017-11-28 09:29:06,738 [salt.state       ][INFO    ][21911] Running state [/etc/salt/grains.d/placeholder] at time 09:29:06.737786
2017-11-28 09:29:06,738 [salt.state       ][INFO    ][21911] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-28 09:29:06,739 [salt.state       ][INFO    ][21911] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-28 09:29:06,739 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/grains.d/placeholder] at time 09:29:06.739104 duration_in_ms=1.318
2017-11-28 09:29:06,740 [salt.state       ][INFO    ][21911] Running state [/etc/salt/grains.d/sphinx] at time 09:29:06.739717
2017-11-28 09:29:06,740 [salt.state       ][INFO    ][21911] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-28 09:29:06,788 [salt.state       ][INFO    ][21911] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 10.167.4.143
               - 127.0.0.1
               - 192.168.11.252
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-28 09:29:06,789 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/grains.d/sphinx] at time 09:29:06.788547 duration_in_ms=48.83
2017-11-28 09:29:06,790 [salt.state       ][INFO    ][21911] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:29:06.790086
2017-11-28 09:29:06,791 [salt.state       ][INFO    ][21911] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-28 09:29:06,791 [salt.state       ][INFO    ][21911] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-28 09:29:06,791 [salt.state       ][INFO    ][21911] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:29:06.791357 duration_in_ms=1.271
2017-11-28 09:29:06,792 [salt.state       ][INFO    ][21911] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:29:06.791687
2017-11-28 09:29:06,792 [salt.state       ][INFO    ][21911] Executing state cmd.mod_watch for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-28 09:29:06,794 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-28 09:29:06,939 [salt.state       ][INFO    ][21911] {'pid': 21959, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-28 09:29:06,940 [salt.state       ][INFO    ][21911] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:29:06.939426 duration_in_ms=147.736
2017-11-28 09:29:06,941 [salt.state       ][INFO    ][21911] Running state [/etc/salt/grains.d/dns_records] at time 09:29:06.940462
2017-11-28 09:29:06,941 [salt.state       ][INFO    ][21911] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-28 09:29:07,067 [salt.state       ][INFO    ][21911] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-28 09:29:07,068 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/grains.d/dns_records] at time 09:29:07.067624 duration_in_ms=127.16
2017-11-28 09:29:07,070 [salt.state       ][INFO    ][21911] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:29:07.069520
2017-11-28 09:29:07,070 [salt.state       ][INFO    ][21911] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-28 09:29:07,071 [salt.state       ][INFO    ][21911] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-28 09:29:07,071 [salt.state       ][INFO    ][21911] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:29:07.071269 duration_in_ms=1.748
2017-11-28 09:29:07,072 [salt.state       ][INFO    ][21911] Running state [/etc/salt/grains.d/salt] at time 09:29:07.072203
2017-11-28 09:29:07,073 [salt.state       ][INFO    ][21911] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-28 09:29:07,201 [salt.state       ][INFO    ][21911] File /etc/salt/grains.d/salt is in the correct state
2017-11-28 09:29:07,202 [salt.state       ][INFO    ][21911] Completed state [/etc/salt/grains.d/salt] at time 09:29:07.201739 duration_in_ms=129.535
2017-11-28 09:29:07,203 [salt.state       ][INFO    ][21911] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:29:07.203413
2017-11-28 09:29:07,204 [salt.state       ][INFO    ][21911] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-28 09:29:07,205 [salt.state       ][INFO    ][21911] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-28 09:29:07,205 [salt.state       ][INFO    ][21911] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:29:07.205061 duration_in_ms=1.647
2017-11-28 09:29:07,208 [salt.state       ][INFO    ][21911] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:29:07.208370
2017-11-28 09:29:07,209 [salt.state       ][INFO    ][21911] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-28 09:29:07,210 [salt.state       ][INFO    ][21911] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-28 09:29:07,210 [salt.state       ][INFO    ][21911] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:29:07.209982 duration_in_ms=1.611
2017-11-28 09:29:07,211 [salt.state       ][INFO    ][21911] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:29:07.210419
2017-11-28 09:29:07,211 [salt.state       ][INFO    ][21911] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-28 09:29:07,214 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-28 09:29:07,226 [salt.state       ][INFO    ][21911] {'pid': 21961, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-28 09:29:07,227 [salt.state       ][INFO    ][21911] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:29:07.226459 duration_in_ms=16.039
2017-11-28 09:29:07,228 [salt.state       ][INFO    ][21911] Running state [mine.update] at time 09:29:07.227803
2017-11-28 09:29:07,228 [salt.state       ][INFO    ][21911] Executing state module.wait for mine.update
2017-11-28 09:29:07,229 [salt.state       ][INFO    ][21911] No changes made for mine.update
2017-11-28 09:29:07,229 [salt.state       ][INFO    ][21911] Completed state [mine.update] at time 09:29:07.229187 duration_in_ms=1.383
2017-11-28 09:29:07,230 [salt.state       ][INFO    ][21911] Running state [mine.update] at time 09:29:07.229597
2017-11-28 09:29:07,230 [salt.state       ][INFO    ][21911] Executing state module.mod_watch for mine.update
2017-11-28 09:29:07,764 [salt.state       ][INFO    ][21911] {'ret': True}
2017-11-28 09:29:07,764 [salt.state       ][INFO    ][21911] Completed state [mine.update] at time 09:29:07.764156 duration_in_ms=534.558
2017-11-28 09:29:07,765 [salt.state       ][INFO    ][21911] Running state [ca-certificates] at time 09:29:07.764670
2017-11-28 09:29:07,765 [salt.state       ][INFO    ][21911] Executing state pkg.installed for ca-certificates
2017-11-28 09:29:07,773 [salt.state       ][INFO    ][21911] Package ca-certificates is already installed
2017-11-28 09:29:07,773 [salt.state       ][INFO    ][21911] Completed state [ca-certificates] at time 09:29:07.773317 duration_in_ms=8.646
2017-11-28 09:29:07,775 [salt.state       ][INFO    ][21911] Running state [update-ca-certificates] at time 09:29:07.774555
2017-11-28 09:29:07,775 [salt.state       ][INFO    ][21911] Executing state cmd.wait for update-ca-certificates
2017-11-28 09:29:07,775 [salt.state       ][INFO    ][21911] No changes made for update-ca-certificates
2017-11-28 09:29:07,776 [salt.state       ][INFO    ][21911] Completed state [update-ca-certificates] at time 09:29:07.775655 duration_in_ms=1.101
2017-11-28 09:29:07,776 [salt.state       ][INFO    ][21911] Running state [libvirt-dev] at time 09:29:07.776066
2017-11-28 09:29:07,776 [salt.state       ][INFO    ][21911] Executing state pkg.installed for libvirt-dev
2017-11-28 09:29:07,783 [salt.state       ][INFO    ][21911] Package libvirt-dev is already installed
2017-11-28 09:29:07,783 [salt.state       ][INFO    ][21911] Completed state [libvirt-dev] at time 09:29:07.783297 duration_in_ms=7.23
2017-11-28 09:29:07,784 [salt.state       ][INFO    ][21911] Running state [pkg-config] at time 09:29:07.783639
2017-11-28 09:29:07,784 [salt.state       ][INFO    ][21911] Executing state pkg.installed for pkg-config
2017-11-28 09:29:07,790 [salt.state       ][INFO    ][21911] Package pkg-config is already installed
2017-11-28 09:29:07,791 [salt.state       ][INFO    ][21911] Completed state [pkg-config] at time 09:29:07.790493 duration_in_ms=6.854
2017-11-28 09:29:07,791 [salt.state       ][INFO    ][21911] Running state [virtng.init] at time 09:29:07.790816
2017-11-28 09:29:07,791 [salt.state       ][INFO    ][21911] Executing state module.run for virtng.init
2017-11-28 09:29:07,886 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command ['git', '--version'] in directory '/root'
2017-11-28 09:29:07,948 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:07,985 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:07,986 [salt.state       ][INFO    ][21911] Completed state [virtng.init] at time 09:29:07.985563 duration_in_ms=194.744
2017-11-28 09:29:07,986 [salt.state       ][INFO    ][21911] Running state [virt.set_autostart] at time 09:29:07.986105
2017-11-28 09:29:07,987 [salt.state       ][INFO    ][21911] Executing state module.run for virt.set_autostart
2017-11-28 09:29:07,992 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,024 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,082 [salt.state       ][INFO    ][21911] Completed state [virt.set_autostart] at time 09:29:08.081785 duration_in_ms=95.678
2017-11-28 09:29:08,082 [salt.state       ][INFO    ][21911] Running state [virtng.init] at time 09:29:08.082395
2017-11-28 09:29:08,083 [salt.state       ][INFO    ][21911] Executing state module.run for virtng.init
2017-11-28 09:29:08,088 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,123 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,123 [salt.state       ][INFO    ][21911] Completed state [virtng.init] at time 09:29:08.123028 duration_in_ms=40.632
2017-11-28 09:29:08,124 [salt.state       ][INFO    ][21911] Running state [virt.set_autostart] at time 09:29:08.123583
2017-11-28 09:29:08,124 [salt.state       ][INFO    ][21911] Executing state module.run for virt.set_autostart
2017-11-28 09:29:08,129 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,161 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,161 [salt.state       ][INFO    ][21911] Completed state [virt.set_autostart] at time 09:29:08.161332 duration_in_ms=37.747
2017-11-28 09:29:08,162 [salt.state       ][INFO    ][21911] Running state [virtng.init] at time 09:29:08.161843
2017-11-28 09:29:08,162 [salt.state       ][INFO    ][21911] Executing state module.run for virtng.init
2017-11-28 09:29:08,167 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --all --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,199 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,199 [salt.state       ][INFO    ][21911] Completed state [virtng.init] at time 09:29:08.199140 duration_in_ms=37.296
2017-11-28 09:29:08,200 [salt.state       ][INFO    ][21911] Running state [virt.set_autostart] at time 09:29:08.199572
2017-11-28 09:29:08,200 [salt.state       ][INFO    ][21911] Executing state module.run for virt.set_autostart
2017-11-28 09:29:08,204 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --autostart --name| grep -E "^prx02.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,235 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,236 [salt.state       ][INFO    ][21911] Completed state [virt.set_autostart] at time 09:29:08.235672 duration_in_ms=36.099
2017-11-28 09:29:08,236 [salt.state       ][INFO    ][21911] Running state [virtng.init] at time 09:29:08.236116
2017-11-28 09:29:08,340 [salt.state       ][INFO    ][21911] Executing state module.run for virtng.init
2017-11-28 09:29:08,345 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,379 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,379 [salt.state       ][INFO    ][21911] Completed state [virtng.init] at time 09:29:08.378912 duration_in_ms=142.795
2017-11-28 09:29:08,379 [salt.state       ][INFO    ][21911] Running state [virt.set_autostart] at time 09:29:08.379385
2017-11-28 09:29:08,380 [salt.state       ][INFO    ][21911] Executing state module.run for virt.set_autostart
2017-11-28 09:29:08,384 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,544 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,544 [salt.state       ][INFO    ][21911] Completed state [virt.set_autostart] at time 09:29:08.544110 duration_in_ms=164.723
2017-11-28 09:29:08,545 [salt.state       ][INFO    ][21911] Running state [virtng.init] at time 09:29:08.544666
2017-11-28 09:29:08,545 [salt.state       ][INFO    ][21911] Executing state module.run for virtng.init
2017-11-28 09:29:08,550 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,586 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,587 [salt.state       ][INFO    ][21911] Completed state [virtng.init] at time 09:29:08.586915 duration_in_ms=42.248
2017-11-28 09:29:08,588 [salt.state       ][INFO    ][21911] Running state [virt.set_autostart] at time 09:29:08.587460
2017-11-28 09:29:08,588 [salt.state       ][INFO    ][21911] Executing state module.run for virt.set_autostart
2017-11-28 09:29:08,593 [salt.loaded.int.module.cmdmod][INFO    ][21911] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-ovs-dpdk-ha.local$"' in directory '/root'
2017-11-28 09:29:08,625 [salt.state       ][INFO    ][21911] unless execution succeeded
2017-11-28 09:29:08,625 [salt.state       ][INFO    ][21911] Completed state [virt.set_autostart] at time 09:29:08.625271 duration_in_ms=37.81
2017-11-28 09:29:08,631 [salt.minion      ][INFO    ][21911] Returning information for job: 20171128092900022794
2017-11-28 09:30:02,001 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171128093001997617
2017-11-28 09:30:02,028 [salt.minion      ][INFO    ][22020] Starting a new job with PID 22020
2017-11-28 09:30:05,419 [salt.state       ][INFO    ][22020] Loading fresh modules for state activity
2017-11-28 09:30:05,463 [salt.state       ][ERROR   ][22020] No contents found in top file
2017-11-28 09:30:05,465 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-28 09:30:05,471 [salt.utils.extmods][INFO    ][22020] Syncing beacons for environment 'base'
2017-11-28 09:30:05,471 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_beacons, for base)
2017-11-28 09:30:05,472 [salt.fileclient  ][INFO    ][22020] Caching directory '_beacons/' for environment 'base'
2017-11-28 09:30:05,509 [salt.utils.extmods][INFO    ][22020] Syncing modules for environment 'base'
2017-11-28 09:30:05,510 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_modules, for base)
2017-11-28 09:30:05,510 [salt.fileclient  ][INFO    ][22020] Caching directory '_modules/' for environment 'base'
2017-11-28 09:30:05,647 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/avinetworks.py'
2017-11-28 09:30:05,663 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-28 09:30:05,686 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-28 09:30:05,730 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-28 09:30:05,771 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-28 09:30:05,790 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-28 09:30:05,805 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-28 09:30:05,822 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-28 09:30:05,872 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-28 09:30:05,887 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-28 09:30:05,909 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-28 09:30:05,939 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-28 09:30:06,000 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-28 09:30:06,030 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-28 09:30:06,050 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-28 09:30:06,072 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-28 09:30:06,102 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-28 09:30:06,131 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-28 09:30:06,164 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-28 09:30:06,178 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-28 09:30:06,196 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-28 09:30:06,269 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-28 09:30:06,293 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-28 09:30:06,320 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-28 09:30:06,335 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-28 09:30:06,357 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-28 09:30:06,369 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-28 09:30:06,380 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-28 09:30:06,400 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-28 09:30:06,426 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-28 09:30:06,444 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-28 09:30:06,462 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-28 09:30:06,485 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-28 09:30:06,508 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-28 09:30:06,512 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-28 09:30:06,512 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/avinetworks.py' to '/var/cache/salt/minion/extmods/modules/avinetworks.py'
2017-11-28 09:30:06,514 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-28 09:30:06,515 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-28 09:30:06,515 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-28 09:30:06,516 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-28 09:30:06,517 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-28 09:30:06,517 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-28 09:30:06,518 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-28 09:30:06,518 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-28 09:30:06,519 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-28 09:30:06,520 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-28 09:30:06,520 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-28 09:30:06,521 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-28 09:30:06,521 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-28 09:30:06,522 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-28 09:30:06,523 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-28 09:30:06,523 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-28 09:30:06,529 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-28 09:30:06,530 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-28 09:30:06,530 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-28 09:30:06,536 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-28 09:30:06,537 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-28 09:30:06,538 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-28 09:30:06,544 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-28 09:30:06,545 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-28 09:30:06,545 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-28 09:30:06,546 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-28 09:30:06,547 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-28 09:30:06,547 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-28 09:30:06,548 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-28 09:30:06,548 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-28 09:30:06,549 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-28 09:30:06,550 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/testing/django_client_proxy.py' to '/var/cache/salt/minion/extmods/modules/testing/django_client_proxy.py'
2017-11-28 09:30:06,550 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-28 09:30:06,551 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-28 09:30:06,560 [salt.utils.extmods][INFO    ][22020] Syncing states for environment 'base'
2017-11-28 09:30:06,561 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_states, for base)
2017-11-28 09:30:06,561 [salt.fileclient  ][INFO    ][22020] Caching directory '_states/' for environment 'base'
2017-11-28 09:30:06,605 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/avinetworks.py'
2017-11-28 09:30:06,620 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-28 09:30:06,639 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-28 09:30:06,693 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-28 09:30:06,704 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-28 09:30:06,720 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-28 09:30:06,732 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-28 09:30:06,787 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-28 09:30:06,813 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-28 09:30:06,826 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/helm_repos.py'
2017-11-28 09:30:06,837 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-28 09:30:06,857 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-28 09:30:06,894 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-28 09:30:06,911 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_globalenvprop.py'
2017-11-28 09:30:06,924 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-28 09:30:06,935 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-28 09:30:06,944 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-28 09:30:06,992 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-28 09:30:07,027 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-28 09:30:07,112 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-28 09:30:07,228 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-28 09:30:07,347 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-28 09:30:07,446 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-28 09:30:07,545 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-28 09:30:07,574 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-28 09:30:07,594 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-28 09:30:07,640 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-28 09:30:07,668 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-28 09:30:07,688 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-28 09:30:07,777 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-28 09:30:07,827 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-28 09:30:07,873 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-28 09:30:07,878 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/avinetworks.py' to '/var/cache/salt/minion/extmods/states/avinetworks.py'
2017-11-28 09:30:07,878 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-28 09:30:07,878 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-28 09:30:07,879 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-28 09:30:07,879 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-28 09:30:07,879 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-28 09:30:07,880 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-28 09:30:07,880 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-28 09:30:07,880 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-28 09:30:07,881 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/helm_repos.py' to '/var/cache/salt/minion/extmods/states/helm_repos.py'
2017-11-28 09:30:07,881 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-28 09:30:07,881 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-28 09:30:07,882 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-28 09:30:07,882 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_globalenvprop.py' to '/var/cache/salt/minion/extmods/states/jenkins_globalenvprop.py'
2017-11-28 09:30:07,882 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-28 09:30:07,883 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-28 09:30:07,883 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-28 09:30:07,883 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-28 09:30:07,883 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-28 09:30:07,884 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-28 09:30:07,884 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-28 09:30:07,884 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-28 09:30:07,885 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-28 09:30:07,885 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-28 09:30:07,885 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-28 09:30:07,886 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-28 09:30:07,886 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-28 09:30:07,886 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-28 09:30:07,887 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-28 09:30:07,887 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-28 09:30:07,887 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-28 09:30:07,888 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-28 09:30:07,888 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-28 09:30:07,891 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-28 09:30:07,898 [salt.utils.extmods][INFO    ][22020] Syncing sdb for environment 'base'
2017-11-28 09:30:07,898 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_sdb, for base)
2017-11-28 09:30:07,898 [salt.fileclient  ][INFO    ][22020] Caching directory '_sdb/' for environment 'base'
2017-11-28 09:30:07,948 [salt.utils.extmods][INFO    ][22020] Syncing grains for environment 'base'
2017-11-28 09:30:07,948 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_grains, for base)
2017-11-28 09:30:07,948 [salt.fileclient  ][INFO    ][22020] Caching directory '_grains/' for environment 'base'
2017-11-28 09:30:08,026 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-28 09:30:08,287 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-28 09:30:08,308 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-28 09:30:08,343 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-28 09:30:08,351 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-28 09:30:08,361 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-28 09:30:08,369 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-28 09:30:08,377 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-28 09:30:08,414 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-28 09:30:08,431 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-28 09:30:08,616 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-28 09:30:08,781 [salt.fileclient  ][INFO    ][22020] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-28 09:30:08,790 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-28 09:30:08,791 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-28 09:30:08,791 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-28 09:30:08,792 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-28 09:30:08,793 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-28 09:30:08,800 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-28 09:30:08,800 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-28 09:30:08,801 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-28 09:30:08,801 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-28 09:30:08,802 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-28 09:30:08,802 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-28 09:30:08,803 [salt.utils.extmods][INFO    ][22020] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-28 09:30:08,807 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-28 09:30:08,813 [salt.utils.extmods][INFO    ][22020] Syncing renderers for environment 'base'
2017-11-28 09:30:08,814 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_renderers, for base)
2017-11-28 09:30:08,814 [salt.fileclient  ][INFO    ][22020] Caching directory '_renderers/' for environment 'base'
2017-11-28 09:30:08,848 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-28 09:30:08,853 [salt.utils.extmods][INFO    ][22020] Syncing returners for environment 'base'
2017-11-28 09:30:08,853 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_returners, for base)
2017-11-28 09:30:08,854 [salt.fileclient  ][INFO    ][22020] Caching directory '_returners/' for environment 'base'
2017-11-28 09:30:08,886 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-28 09:30:08,891 [salt.utils.extmods][INFO    ][22020] Syncing output for environment 'base'
2017-11-28 09:30:08,891 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_output, for base)
2017-11-28 09:30:08,891 [salt.fileclient  ][INFO    ][22020] Caching directory '_output/' for environment 'base'
2017-11-28 09:30:08,922 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-28 09:30:08,926 [salt.utils.extmods][INFO    ][22020] Syncing utils for environment 'base'
2017-11-28 09:30:08,926 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_utils, for base)
2017-11-28 09:30:08,927 [salt.fileclient  ][INFO    ][22020] Caching directory '_utils/' for environment 'base'
2017-11-28 09:30:08,965 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-28 09:30:08,969 [salt.utils.extmods][INFO    ][22020] Syncing log_handlers for environment 'base'
2017-11-28 09:30:08,969 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_log_handlers, for base)
2017-11-28 09:30:08,969 [salt.fileclient  ][INFO    ][22020] Caching directory '_log_handlers/' for environment 'base'
2017-11-28 09:30:08,992 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-28 09:30:08,995 [salt.utils.extmods][INFO    ][22020] Syncing proxy for environment 'base'
2017-11-28 09:30:08,995 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_proxy, for base)
2017-11-28 09:30:08,995 [salt.fileclient  ][INFO    ][22020] Caching directory '_proxy/' for environment 'base'
2017-11-28 09:30:09,032 [salt.utils.extmods][INFO    ][22020] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-28 09:30:09,035 [salt.utils.extmods][INFO    ][22020] Syncing engines for environment 'base'
2017-11-28 09:30:09,035 [salt.utils.extmods][INFO    ][22020] Loading cache from salt://_engines, for base)
2017-11-28 09:30:09,035 [salt.fileclient  ][INFO    ][22020] Caching directory '_engines/' for environment 'base'
2017-11-28 09:30:09,067 [salt.minion      ][INFO    ][22020] Returning information for job: 20171128093001997617
2017-11-28 09:39:15,026 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command test.ping with jid 20171128093915019144
2017-11-28 09:39:15,049 [salt.minion      ][INFO    ][22245] Starting a new job with PID 22245
2017-11-28 09:39:15,105 [salt.minion      ][INFO    ][22245] Returning information for job: 20171128093915019144
2017-11-28 09:43:03,694 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command state.sls with jid 20171128094303682095
2017-11-28 09:43:03,718 [salt.minion      ][INFO    ][22309] Starting a new job with PID 22309
2017-11-28 09:43:04,177 [salt.state       ][INFO    ][22309] Loading fresh modules for state activity
2017-11-28 09:43:04,223 [salt.fileclient  ][INFO    ][22309] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-28 09:43:04,318 [salt.fileclient  ][INFO    ][22309] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-28 09:43:04,353 [salt.fileclient  ][INFO    ][22309] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-28 09:43:04,709 [salt.state       ][INFO    ][22309] Running state [lsof] at time 09:43:04.708547
2017-11-28 09:43:04,709 [salt.state       ][INFO    ][22309] Executing state pkg.installed for lsof
2017-11-28 09:43:04,709 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:43:05,104 [salt.state       ][INFO    ][22309] Package lsof is already installed
2017-11-28 09:43:05,104 [salt.state       ][INFO    ][22309] Completed state [lsof] at time 09:43:05.103871 duration_in_ms=395.324
2017-11-28 09:43:05,104 [salt.state       ][INFO    ][22309] Running state [keepalived] at time 09:43:05.104120
2017-11-28 09:43:05,104 [salt.state       ][INFO    ][22309] Executing state pkg.installed for keepalived
2017-11-28 09:43:05,124 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-28 09:43:07,076 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'keepalived'] in directory '/root'
2017-11-28 09:43:13,737 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128094313724559
2017-11-28 09:43:13,757 [salt.minion      ][INFO    ][23496] Starting a new job with PID 23496
2017-11-28 09:43:13,776 [salt.minion      ][INFO    ][23496] Returning information for job: 20171128094313724559
2017-11-28 09:43:16,594 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:43:16,647 [salt.state       ][INFO    ][22309] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4'
'keepalived' changed from 'absent' to '1:1.2.19-1ubuntu0.2'
'ipvsadm' changed from 'absent' to '1:1.28-3'
'libsensors4' changed from 'absent' to '1:3.4.0-2'

2017-11-28 09:43:16,663 [salt.state       ][INFO    ][22309] Loading fresh modules for state activity
2017-11-28 09:43:16,693 [salt.state       ][INFO    ][22309] Completed state [keepalived] at time 09:43:16.692631 duration_in_ms=11588.51
2017-11-28 09:43:16,699 [salt.state       ][INFO    ][22309] Running state [/etc/keepalived/keepalived.conf] at time 09:43:16.698586
2017-11-28 09:43:16,699 [salt.state       ][INFO    ][22309] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-28 09:43:16,726 [salt.fileclient  ][INFO    ][22309] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-28 09:43:16,776 [salt.fileclient  ][INFO    ][22309] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-28 09:43:16,785 [salt.state       ][INFO    ][22309] File changed:
New file
2017-11-28 09:43:16,785 [salt.state       ][INFO    ][22309] Completed state [/etc/keepalived/keepalived.conf] at time 09:43:16.784819 duration_in_ms=86.233
2017-11-28 09:43:16,861 [salt.state       ][INFO    ][22309] Running state [keepalived] at time 09:43:16.861292
2017-11-28 09:43:16,862 [salt.state       ][INFO    ][22309] Executing state service.running for keepalived
2017-11-28 09:43:16,863 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-28 09:43:16,879 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-28 09:43:16,891 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-28 09:43:16,905 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-28 09:43:16,920 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-28 09:43:16,999 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-28 09:43:17,013 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-28 09:43:17,026 [salt.loaded.int.module.cmdmod][INFO    ][22309] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-28 09:43:17,038 [salt.state       ][INFO    ][22309] {'keepalived': True}
2017-11-28 09:43:17,038 [salt.state       ][INFO    ][22309] Completed state [keepalived] at time 09:43:17.037934 duration_in_ms=176.641
2017-11-28 09:43:17,039 [salt.minion      ][INFO    ][22309] Returning information for job: 20171128094303682095
2017-11-28 09:43:50,790 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command pillar.get with jid 20171128094350783760
2017-11-28 09:43:50,813 [salt.minion      ][INFO    ][23776] Starting a new job with PID 23776
2017-11-28 09:43:50,819 [salt.minion      ][INFO    ][23776] Returning information for job: 20171128094350783760
2017-11-28 09:49:48,500 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command state.sls with jid 20171128094948493148
2017-11-28 09:49:48,522 [salt.minion      ][INFO    ][23864] Starting a new job with PID 23864
2017-11-28 09:49:51,127 [salt.state       ][INFO    ][23864] Loading fresh modules for state activity
2017-11-28 09:49:51,170 [salt.fileclient  ][INFO    ][23864] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-28 09:49:51,206 [salt.fileclient  ][INFO    ][23864] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-28 09:49:51,567 [salt.state       ][INFO    ][23864] Running state [attr] at time 09:49:51.566743
2017-11-28 09:49:51,567 [salt.state       ][INFO    ][23864] Executing state pkg.installed for attr
2017-11-28 09:49:51,567 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:49:51,978 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-28 09:49:53,947 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'attr'] in directory '/root'
2017-11-28 09:49:57,212 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:49:57,260 [salt.state       ][INFO    ][23864] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-28 09:49:57,277 [salt.state       ][INFO    ][23864] Loading fresh modules for state activity
2017-11-28 09:49:57,299 [salt.state       ][INFO    ][23864] Completed state [attr] at time 09:49:57.298934 duration_in_ms=5732.19
2017-11-28 09:49:57,307 [salt.state       ][INFO    ][23864] Running state [glusterfs-server] at time 09:49:57.306916
2017-11-28 09:49:57,307 [salt.state       ][INFO    ][23864] Executing state pkg.installed for glusterfs-server
2017-11-28 09:49:57,668 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'glusterfs-server'] in directory '/root'
2017-11-28 09:49:58,596 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128094958583661
2017-11-28 09:49:58,617 [salt.minion      ][INFO    ][24736] Starting a new job with PID 24736
2017-11-28 09:49:58,635 [salt.minion      ][INFO    ][24736] Returning information for job: 20171128094958583661
2017-11-28 09:50:08,808 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128095008795116
2017-11-28 09:50:08,825 [salt.minion      ][INFO    ][25387] Starting a new job with PID 25387
2017-11-28 09:50:08,855 [salt.minion      ][INFO    ][25387] Returning information for job: 20171128095008795116
2017-11-28 09:50:19,027 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128095019014980
2017-11-28 09:50:19,047 [salt.minion      ][INFO    ][26239] Starting a new job with PID 26239
2017-11-28 09:50:19,067 [salt.minion      ][INFO    ][26239] Returning information for job: 20171128095019014980
2017-11-28 09:50:19,869 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:50:19,920 [salt.state       ][INFO    ][23864] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'glusterfs-client' changed from 'absent' to '3.10.7-ubuntu1~xenial1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.1'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'glusterfs-common' changed from 'absent' to '3.10.7-ubuntu1~xenial1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'portmap' changed from 'absent' to '1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'
'nfs-client' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.10.7-ubuntu1~xenial1'

2017-11-28 09:50:19,936 [salt.state       ][INFO    ][23864] Loading fresh modules for state activity
2017-11-28 09:50:19,960 [salt.state       ][INFO    ][23864] Completed state [glusterfs-server] at time 09:50:19.960131 duration_in_ms=22653.214
2017-11-28 09:50:19,969 [salt.state       ][INFO    ][23864] Running state [bc] at time 09:50:19.968622
2017-11-28 09:50:19,969 [salt.state       ][INFO    ][23864] Executing state pkg.installed for bc
2017-11-28 09:50:20,245 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'bc'] in directory '/root'
2017-11-28 09:50:23,623 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:50:23,685 [salt.state       ][INFO    ][23864] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-28 09:50:23,700 [salt.state       ][INFO    ][23864] Loading fresh modules for state activity
2017-11-28 09:50:23,725 [salt.state       ][INFO    ][23864] Completed state [bc] at time 09:50:23.724599 duration_in_ms=3755.975
2017-11-28 09:50:23,860 [salt.state       ][INFO    ][23864] Running state [glusterfs-server] at time 09:50:23.859909
2017-11-28 09:50:23,860 [salt.state       ][INFO    ][23864] Executing state service.running for glusterfs-server
2017-11-28 09:50:23,863 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-28 09:50:23,879 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-28 09:50:23,894 [salt.loaded.int.module.cmdmod][INFO    ][23864] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-28 09:50:23,912 [salt.state       ][INFO    ][23864] The service glusterfs-server is already running
2017-11-28 09:50:23,913 [salt.state       ][INFO    ][23864] Completed state [glusterfs-server] at time 09:50:23.912603 duration_in_ms=52.694
2017-11-28 09:50:23,917 [salt.state       ][INFO    ][23864] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:23.917050
2017-11-28 09:50:23,918 [salt.state       ][INFO    ][23864] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-28 09:50:23,935 [salt.state       ][INFO    ][23864] File changed:
New file
2017-11-28 09:50:23,936 [salt.state       ][INFO    ][23864] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:23.935616 duration_in_ms=18.566
2017-11-28 09:50:23,936 [salt.state       ][INFO    ][23864] Running state [/srv/glusterfs/glance] at time 09:50:23.936011
2017-11-28 09:50:23,936 [salt.state       ][INFO    ][23864] Executing state file.directory for /srv/glusterfs/glance
2017-11-28 09:50:23,938 [salt.state       ][INFO    ][23864] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-28 09:50:23,938 [salt.state       ][INFO    ][23864] Completed state [/srv/glusterfs/glance] at time 09:50:23.937925 duration_in_ms=1.914
2017-11-28 09:50:23,938 [salt.state       ][INFO    ][23864] Running state [/srv/glusterfs/keystone-keys] at time 09:50:23.938258
2017-11-28 09:50:23,939 [salt.state       ][INFO    ][23864] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-28 09:50:23,939 [salt.state       ][INFO    ][23864] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-28 09:50:23,940 [salt.state       ][INFO    ][23864] Completed state [/srv/glusterfs/keystone-keys] at time 09:50:23.939656 duration_in_ms=1.399
2017-11-28 09:50:23,940 [salt.state       ][INFO    ][23864] Running state [/srv/glusterfs/nova_instances] at time 09:50:23.939984
2017-11-28 09:50:23,940 [salt.state       ][INFO    ][23864] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-28 09:50:23,941 [salt.state       ][INFO    ][23864] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-28 09:50:23,941 [salt.state       ][INFO    ][23864] Completed state [/srv/glusterfs/nova_instances] at time 09:50:23.941409 duration_in_ms=1.425
2017-11-28 09:50:23,942 [salt.state       ][INFO    ][23864] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:50:23.941742
2017-11-28 09:50:23,942 [salt.state       ][INFO    ][23864] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-28 09:50:23,943 [salt.state       ][INFO    ][23864] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-28 09:50:23,943 [salt.state       ][INFO    ][23864] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:50:23.943143 duration_in_ms=1.402
2017-11-28 09:50:23,945 [salt.minion      ][INFO    ][23864] Returning information for job: 20171128094948493148
2017-11-28 09:50:26,822 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command test.ping with jid 20171128095026816363
2017-11-28 09:50:26,843 [salt.minion      ][INFO    ][26706] Starting a new job with PID 26706
2017-11-28 09:50:26,895 [salt.minion      ][INFO    ][26706] Returning information for job: 20171128095026816363
2017-11-28 09:50:27,033 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command state.sls with jid 20171128095027020484
2017-11-28 09:50:27,053 [salt.minion      ][INFO    ][26711] Starting a new job with PID 26711
2017-11-28 09:50:28,626 [salt.state       ][INFO    ][26711] Loading fresh modules for state activity
2017-11-28 09:50:28,666 [salt.fileclient  ][INFO    ][26711] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-28 09:50:28,724 [salt.fileclient  ][INFO    ][26711] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-28 09:50:28,829 [salt.fileclient  ][INFO    ][26711] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-28 09:50:28,861 [salt.fileclient  ][INFO    ][26711] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-28 09:50:29,261 [salt.state       ][INFO    ][26711] Running state [attr] at time 09:50:29.260756
2017-11-28 09:50:29,261 [salt.state       ][INFO    ][26711] Executing state pkg.installed for attr
2017-11-28 09:50:29,261 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-28 09:50:29,670 [salt.state       ][INFO    ][26711] Package attr is already installed
2017-11-28 09:50:29,671 [salt.state       ][INFO    ][26711] Completed state [attr] at time 09:50:29.671259 duration_in_ms=410.501
2017-11-28 09:50:29,672 [salt.state       ][INFO    ][26711] Running state [glusterfs-server] at time 09:50:29.672085
2017-11-28 09:50:29,673 [salt.state       ][INFO    ][26711] Executing state pkg.installed for glusterfs-server
2017-11-28 09:50:29,680 [salt.state       ][INFO    ][26711] Package glusterfs-server is already installed
2017-11-28 09:50:29,680 [salt.state       ][INFO    ][26711] Completed state [glusterfs-server] at time 09:50:29.679799 duration_in_ms=7.714
2017-11-28 09:50:29,680 [salt.state       ][INFO    ][26711] Running state [bc] at time 09:50:29.680144
2017-11-28 09:50:29,681 [salt.state       ][INFO    ][26711] Executing state pkg.installed for bc
2017-11-28 09:50:29,687 [salt.state       ][INFO    ][26711] Package bc is already installed
2017-11-28 09:50:29,687 [salt.state       ][INFO    ][26711] Completed state [bc] at time 09:50:29.686961 duration_in_ms=6.816
2017-11-28 09:50:29,690 [salt.state       ][INFO    ][26711] Running state [glusterfs-server] at time 09:50:29.689498
2017-11-28 09:50:29,690 [salt.state       ][INFO    ][26711] Executing state service.running for glusterfs-server
2017-11-28 09:50:29,691 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-28 09:50:29,711 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-28 09:50:29,800 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-28 09:50:29,830 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-28 09:50:29,846 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-28 09:50:29,863 [salt.state       ][INFO    ][26711] The service glusterfs-server is already running
2017-11-28 09:50:29,864 [salt.state       ][INFO    ][26711] Completed state [glusterfs-server] at time 09:50:29.863750 duration_in_ms=174.251
2017-11-28 09:50:29,868 [salt.state       ][INFO    ][26711] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:29.867868
2017-11-28 09:50:29,868 [salt.state       ][INFO    ][26711] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-28 09:50:29,882 [salt.state       ][INFO    ][26711] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-28 09:50:29,882 [salt.state       ][INFO    ][26711] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:50:29.882434 duration_in_ms=14.566
2017-11-28 09:50:29,883 [salt.state       ][INFO    ][26711] Running state [/srv/glusterfs/glance] at time 09:50:29.882792
2017-11-28 09:50:29,883 [salt.state       ][INFO    ][26711] Executing state file.directory for /srv/glusterfs/glance
2017-11-28 09:50:29,884 [salt.state       ][INFO    ][26711] Directory /srv/glusterfs/glance is in the correct state
2017-11-28 09:50:29,884 [salt.state       ][INFO    ][26711] Completed state [/srv/glusterfs/glance] at time 09:50:29.884116 duration_in_ms=1.324
2017-11-28 09:50:29,884 [salt.state       ][INFO    ][26711] Running state [/srv/glusterfs/keystone-keys] at time 09:50:29.884443
2017-11-28 09:50:29,885 [salt.state       ][INFO    ][26711] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-28 09:50:29,886 [salt.state       ][INFO    ][26711] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-28 09:50:29,886 [salt.state       ][INFO    ][26711] Completed state [/srv/glusterfs/keystone-keys] at time 09:50:29.885759 duration_in_ms=1.315
2017-11-28 09:50:29,886 [salt.state       ][INFO    ][26711] Running state [/srv/glusterfs/nova_instances] at time 09:50:29.886093
2017-11-28 09:50:29,886 [salt.state       ][INFO    ][26711] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-28 09:50:29,887 [salt.state       ][INFO    ][26711] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-28 09:50:29,887 [salt.state       ][INFO    ][26711] Completed state [/srv/glusterfs/nova_instances] at time 09:50:29.887403 duration_in_ms=1.31
2017-11-28 09:50:29,888 [salt.state       ][INFO    ][26711] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:50:29.887726
2017-11-28 09:50:29,888 [salt.state       ][INFO    ][26711] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-28 09:50:29,889 [salt.state       ][INFO    ][26711] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-28 09:50:29,889 [salt.state       ][INFO    ][26711] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:50:29.889023 duration_in_ms=1.297
2017-11-28 09:50:30,001 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command ['git', '--version'] in directory '/root'
2017-11-28 09:50:30,121 [salt.state       ][INFO    ][26711] Running state [sleep 5] at time 09:50:30.120899
2017-11-28 09:50:30,121 [salt.state       ][INFO    ][26711] Executing state cmd.wait for sleep 5
2017-11-28 09:50:30,121 [salt.state       ][INFO    ][26711] No changes made for sleep 5
2017-11-28 09:50:30,122 [salt.state       ][INFO    ][26711] Completed state [sleep 5] at time 09:50:30.121559 duration_in_ms=0.661
2017-11-28 09:50:30,122 [salt.state       ][INFO    ][26711] Running state [10.167.4.141] at time 09:50:30.121995
2017-11-28 09:50:30,122 [salt.state       ][INFO    ][26711] Executing state glusterfs.peered for 10.167.4.141
2017-11-28 09:50:30,123 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:30,139 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:30,256 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:30,272 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:30,678 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:30,695 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:30,816 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:30,834 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:30,991 [salt.state       ][INFO    ][26711] {'new': {'10.167.4.141': []}, 'old': None}
2017-11-28 09:50:30,992 [salt.state       ][INFO    ][26711] Completed state [10.167.4.141] at time 09:50:30.991415 duration_in_ms=869.418
2017-11-28 09:50:30,998 [salt.state       ][INFO    ][26711] Running state [10.167.4.142] at time 09:50:30.997626
2017-11-28 09:50:30,998 [salt.state       ][INFO    ][26711] Executing state glusterfs.peered for 10.167.4.142
2017-11-28 09:50:30,999 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:31,016 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:31,135 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:31,152 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:31,495 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:31,513 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:31,639 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:31,655 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:31,797 [salt.state       ][INFO    ][26711] {'new': {'10.167.4.141': [], '10.167.4.142': []}, 'old': {'10.167.4.141': []}}
2017-11-28 09:50:31,798 [salt.state       ][INFO    ][26711] Completed state [10.167.4.142] at time 09:50:31.797453 duration_in_ms=799.826
2017-11-28 09:50:31,804 [salt.state       ][INFO    ][26711] Running state [10.167.4.143] at time 09:50:31.803922
2017-11-28 09:50:31,804 [salt.state       ][INFO    ][26711] Executing state glusterfs.peered for 10.167.4.143
2017-11-28 09:50:31,805 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:31,823 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:31,939 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:31,957 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:32,069 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:32,088 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:32,201 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:32,219 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:32,334 [salt.state       ][INFO    ][26711] Peering with localhost is not needed
2017-11-28 09:50:32,334 [salt.state       ][INFO    ][26711] Completed state [10.167.4.143] at time 09:50:32.334021 duration_in_ms=530.098
2017-11-28 09:50:32,336 [salt.state       ][INFO    ][26711] Running state [glance] at time 09:50:32.336064
2017-11-28 09:50:32,337 [salt.state       ][INFO    ][26711] Executing state glusterfs.created for glance
2017-11-28 09:50:32,338 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:32,354 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:32,473 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:32,492 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:33,263 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:33,281 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:36,175 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:36,192 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:36,300 [salt.state       ][INFO    ][26711] {'new': ['glance'], 'old': []}
2017-11-28 09:50:36,301 [salt.state       ][INFO    ][26711] Completed state [glance] at time 09:50:36.300440 duration_in_ms=3964.374
2017-11-28 09:50:36,310 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:36.309554
2017-11-28 09:50:36,310 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-28 09:50:36,311 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-28 09:50:36,426 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:50:37,075 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128095037062265
2017-11-28 09:50:37,096 [salt.minion      ][INFO    ][27015] Starting a new job with PID 27015
2017-11-28 09:50:37,115 [salt.minion      ][INFO    ][27015] Returning information for job: 20171128095037062265
2017-11-28 09:50:37,154 [salt.state       ][INFO    ][26711] {'pid': 26985, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:37,155 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:37.154721 duration_in_ms=845.166
2017-11-28 09:50:37,156 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:50:37.155801
2017-11-28 09:50:37,156 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-28 09:50:37,157 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-28 09:50:37,278 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-11-28 09:50:38,034 [salt.state       ][INFO    ][26711] {'pid': 27029, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:38,034 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:50:38.034118 duration_in_ms=878.316
2017-11-28 09:50:38,035 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:50:38.035216
2017-11-28 09:50:38,036 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-28 09:50:38,037 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-28 09:50:38,152 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-11-28 09:50:38,921 [salt.state       ][INFO    ][26711] {'pid': 27066, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:38,922 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:50:38.921857 duration_in_ms=886.639
2017-11-28 09:50:38,923 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 09:50:38.922985
2017-11-28 09:50:38,924 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'
2017-11-28 09:50:38,924 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'glance' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-28 09:50:39,038 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-11-28 09:50:39,769 [salt.state       ][INFO    ][26711] {'pid': 27106, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:39,770 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'glance' 'cluster.favorite-child-policy' 'mtime'] at time 09:50:39.769919 duration_in_ms=846.932
2017-11-28 09:50:39,772 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:39.771504
2017-11-28 09:50:39,772 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-28 09:50:39,773 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-28 09:50:39,888 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:50:40,652 [salt.state       ][INFO    ][26711] {'pid': 27143, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:40,652 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:40.652321 duration_in_ms=880.817
2017-11-28 09:50:40,654 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:50:40.653533
2017-11-28 09:50:40,654 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-28 09:50:40,655 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-28 09:50:40,773 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-28 09:50:41,529 [salt.state       ][INFO    ][26711] {'pid': 27180, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:41,529 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:50:41.529242 duration_in_ms=875.708
2017-11-28 09:50:41,532 [salt.state       ][INFO    ][26711] Running state [glance] at time 09:50:41.532305
2017-11-28 09:50:41,533 [salt.state       ][INFO    ][26711] Executing state glusterfs.started for glance
2017-11-28 09:50:41,534 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:41,552 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:41,670 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:41,686 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:41,807 [salt.state       ][INFO    ][26711] Volume glance is already started
2017-11-28 09:50:41,807 [salt.state       ][INFO    ][26711] Completed state [glance] at time 09:50:41.807165 duration_in_ms=274.858
2017-11-28 09:50:41,809 [salt.state       ][INFO    ][26711] Running state [keystone-keys] at time 09:50:41.809309
2017-11-28 09:50:41,810 [salt.state       ][INFO    ][26711] Executing state glusterfs.created for keystone-keys
2017-11-28 09:50:41,811 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:41,829 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:41,942 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:41,961 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:42,773 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:42,791 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:45,595 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:45,613 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:45,721 [salt.state       ][INFO    ][26711] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-11-28 09:50:45,722 [salt.state       ][INFO    ][26711] Completed state [keystone-keys] at time 09:50:45.721548 duration_in_ms=3912.238
2017-11-28 09:50:45,732 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:50:45.731986
2017-11-28 09:50:45,733 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-28 09:50:45,734 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-28 09:50:45,850 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-11-28 09:50:46,599 [salt.state       ][INFO    ][26711] {'pid': 27333, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:46,600 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:50:46.599720 duration_in_ms=867.734
2017-11-28 09:50:46,601 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:46.600877
2017-11-28 09:50:46,601 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-28 09:50:46,603 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-28 09:50:46,716 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:50:47,231 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128095047219684
2017-11-28 09:50:47,255 [salt.minion      ][INFO    ][27400] Starting a new job with PID 27400
2017-11-28 09:50:47,273 [salt.minion      ][INFO    ][27400] Returning information for job: 20171128095047219684
2017-11-28 09:50:47,462 [salt.state       ][INFO    ][26711] {'pid': 27370, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:47,463 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:47.462921 duration_in_ms=862.043
2017-11-28 09:50:47,464 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:50:47.464067
2017-11-28 09:50:47,465 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-28 09:50:47,466 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-28 09:50:47,579 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-11-28 09:50:48,318 [salt.state       ][INFO    ][26711] {'pid': 27414, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:48,318 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:50:48.318013 duration_in_ms=853.945
2017-11-28 09:50:48,319 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:48.319168
2017-11-28 09:50:48,320 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-28 09:50:48,321 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-28 09:50:48,431 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:50:49,376 [salt.state       ][INFO    ][26711] {'pid': 27454, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:49,377 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:50:49.376661 duration_in_ms=1057.491
2017-11-28 09:50:49,378 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:50:49.378018
2017-11-28 09:50:49,379 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-28 09:50:49,380 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-28 09:50:49,493 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-11-28 09:50:50,477 [salt.state       ][INFO    ][26711] {'pid': 27492, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:50,478 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:50:50.477596 duration_in_ms=1099.575
2017-11-28 09:50:50,479 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:50:50.478812
2017-11-28 09:50:50,479 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-28 09:50:50,480 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-28 09:50:50,594 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-28 09:50:51,346 [salt.state       ][INFO    ][26711] {'pid': 27529, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:51,347 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:50:51.346816 duration_in_ms=868.003
2017-11-28 09:50:51,350 [salt.state       ][INFO    ][26711] Running state [keystone-keys] at time 09:50:51.349673
2017-11-28 09:50:51,350 [salt.state       ][INFO    ][26711] Executing state glusterfs.started for keystone-keys
2017-11-28 09:50:51,351 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:51,370 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:51,483 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:51,501 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:51,623 [salt.state       ][INFO    ][26711] Volume keystone-keys is already started
2017-11-28 09:50:51,624 [salt.state       ][INFO    ][26711] Completed state [keystone-keys] at time 09:50:51.623730 duration_in_ms=274.055
2017-11-28 09:50:51,626 [salt.state       ][INFO    ][26711] Running state [nova_instances] at time 09:50:51.625889
2017-11-28 09:50:51,626 [salt.state       ][INFO    ][26711] Executing state glusterfs.created for nova_instances
2017-11-28 09:50:51,627 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:51,646 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:51,756 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:51,773 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:52,615 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:52,634 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:55,516 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:50:55,535 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:50:55,643 [salt.state       ][INFO    ][26711] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-11-28 09:50:55,644 [salt.state       ][INFO    ][26711] Completed state [nova_instances] at time 09:50:55.643522 duration_in_ms=4017.633
2017-11-28 09:50:55,653 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:55.652924
2017-11-28 09:50:55,654 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-28 09:50:55,655 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-28 09:50:55,770 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:50:56,546 [salt.state       ][INFO    ][26711] {'pid': 27683, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:56,546 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:50:56.546204 duration_in_ms=893.28
2017-11-28 09:50:56,548 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:50:56.547422
2017-11-28 09:50:56,548 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-11-28 09:50:56,549 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-28 09:50:56,663 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-28 09:50:57,395 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128095057380700
2017-11-28 09:50:57,419 [salt.state       ][INFO    ][26711] {'pid': 27722, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:57,419 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 09:50:57.419214 duration_in_ms=871.792
2017-11-28 09:50:57,419 [salt.minion      ][INFO    ][27750] Starting a new job with PID 27750
2017-11-28 09:50:57,420 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:50:57.420328
2017-11-28 09:50:57,421 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-11-28 09:50:57,422 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-28 09:50:57,437 [salt.minion      ][INFO    ][27750] Returning information for job: 20171128095057380700
2017-11-28 09:50:57,528 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'True'' in directory '/root'
2017-11-28 09:50:58,249 [salt.state       ][INFO    ][26711] {'pid': 27764, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:58,250 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 09:50:58.249687 duration_in_ms=829.358
2017-11-28 09:50:58,251 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 09:50:58.250843
2017-11-28 09:50:58,251 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'
2017-11-28 09:50:58,252 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-28 09:50:58,374 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-11-28 09:50:59,133 [salt.state       ][INFO    ][26711] {'pid': 27804, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:50:59,134 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'nova_instances' 'cluster.favorite-child-policy' 'mtime'] at time 09:50:59.133399 duration_in_ms=882.49
2017-11-28 09:50:59,135 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:50:59.134923
2017-11-28 09:50:59,136 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-11-28 09:50:59,137 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-11-28 09:50:59,253 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'True'' in directory '/root'
2017-11-28 09:51:00,023 [salt.state       ][INFO    ][26711] {'pid': 27841, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:00,023 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 09:51:00.023240 duration_in_ms=888.315
2017-11-28 09:51:00,025 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:51:00.024449
2017-11-28 09:51:00,025 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-28 09:51:00,026 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-28 09:51:00,139 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:51:00,925 [salt.state       ][INFO    ][26711] {'pid': 27878, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:00,925 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:51:00.925154 duration_in_ms=900.704
2017-11-28 09:51:00,928 [salt.state       ][INFO    ][26711] Running state [nova_instances] at time 09:51:00.928181
2017-11-28 09:51:00,929 [salt.state       ][INFO    ][26711] Executing state glusterfs.started for nova_instances
2017-11-28 09:51:00,930 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:00,950 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:01,063 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:01,081 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:01,202 [salt.state       ][INFO    ][26711] Volume nova_instances is already started
2017-11-28 09:51:01,203 [salt.state       ][INFO    ][26711] Completed state [nova_instances] at time 09:51:01.202433 duration_in_ms=274.25
2017-11-28 09:51:01,205 [salt.state       ][INFO    ][26711] Running state [keystone-credential-keys] at time 09:51:01.205066
2017-11-28 09:51:01,206 [salt.state       ][INFO    ][26711] Executing state glusterfs.created for keystone-credential-keys
2017-11-28 09:51:01,207 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:01,222 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:01,338 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:01,354 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:02,223 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:02,242 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:05,039 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:05,057 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:05,173 [salt.state       ][INFO    ][26711] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-11-28 09:51:05,174 [salt.state       ][INFO    ][26711] Completed state [keystone-credential-keys] at time 09:51:05.173494 duration_in_ms=3968.428
2017-11-28 09:51:05,184 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:51:05.183606
2017-11-28 09:51:05,184 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-28 09:51:05,185 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-28 09:51:05,292 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-28 09:51:06,165 [salt.state       ][INFO    ][26711] {'pid': 28038, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:06,166 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:51:06.165908 duration_in_ms=982.3
2017-11-28 09:51:06,167 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:51:06.167280
2017-11-28 09:51:06,168 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'
2017-11-28 09:51:06,169 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.favorite-child-policy: mtime'' in directory '/root'
2017-11-28 09:51:06,280 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'' in directory '/root'
2017-11-28 09:51:07,136 [salt.state       ][INFO    ][26711] {'pid': 28075, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:07,137 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.favorite-child-policy' 'mtime'] at time 09:51:07.136541 duration_in_ms=969.26
2017-11-28 09:51:07,138 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:51:07.137810
2017-11-28 09:51:07,138 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-28 09:51:07,139 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-28 09:51:07,259 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:51:07,554 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command saltutil.find_job with jid 20171128095107541676
2017-11-28 09:51:07,575 [salt.minion      ][INFO    ][28124] Starting a new job with PID 28124
2017-11-28 09:51:07,596 [salt.minion      ][INFO    ][28124] Returning information for job: 20171128095107541676
2017-11-28 09:51:08,012 [salt.state       ][INFO    ][26711] {'pid': 28112, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:08,012 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:51:08.012363 duration_in_ms=874.553
2017-11-28 09:51:08,014 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:51:08.013570
2017-11-28 09:51:08,014 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-28 09:51:08,015 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-28 09:51:08,127 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-11-28 09:51:08,926 [salt.state       ][INFO    ][26711] {'pid': 28158, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:08,927 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:51:08.926742 duration_in_ms=913.171
2017-11-28 09:51:08,928 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:51:08.927829
2017-11-28 09:51:08,928 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-28 09:51:08,929 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-28 09:51:09,039 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-28 09:51:09,794 [salt.state       ][INFO    ][26711] {'pid': 28195, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:09,794 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:51:09.794259 duration_in_ms=866.429
2017-11-28 09:51:09,795 [salt.state       ][INFO    ][26711] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:51:09.795381
2017-11-28 09:51:09,796 [salt.state       ][INFO    ][26711] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-28 09:51:09,797 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-28 09:51:09,918 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-11-28 09:51:10,692 [salt.state       ][INFO    ][26711] {'pid': 28232, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-28 09:51:10,692 [salt.state       ][INFO    ][26711] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:51:10.692369 duration_in_ms=896.987
2017-11-28 09:51:10,696 [salt.state       ][INFO    ][26711] Running state [keystone-credential-keys] at time 09:51:10.695462
2017-11-28 09:51:10,696 [salt.state       ][INFO    ][26711] Executing state glusterfs.started for keystone-credential-keys
2017-11-28 09:51:10,697 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:10,716 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:10,833 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --version' in directory '/root'
2017-11-28 09:51:10,850 [salt.loaded.int.module.cmdmod][INFO    ][26711] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-28 09:51:10,967 [salt.state       ][INFO    ][26711] Volume keystone-credential-keys is already started
2017-11-28 09:51:10,968 [salt.state       ][INFO    ][26711] Completed state [keystone-credential-keys] at time 09:51:10.967879 duration_in_ms=272.415
2017-11-28 09:51:10,972 [salt.minion      ][INFO    ][26711] Returning information for job: 20171128095027020484
2017-11-28 09:51:29,279 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command test.ping with jid 20171128095129272400
2017-11-28 09:51:29,301 [salt.minion      ][INFO    ][28286] Starting a new job with PID 28286
2017-11-28 09:51:29,358 [salt.minion      ][INFO    ][28286] Returning information for job: 20171128095129272400
2017-11-28 09:51:29,488 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command cmd.run with jid 20171128095129478723
2017-11-28 09:51:29,508 [salt.minion      ][INFO    ][28291] Starting a new job with PID 28291
2017-11-28 09:51:29,515 [salt.loaded.int.module.cmdmod][INFO    ][28291] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-28 09:51:29,742 [salt.minion      ][INFO    ][28291] Returning information for job: 20171128095129478723
2017-11-28 10:28:32,761 [salt.utils.schedule][INFO    ][21799] Running scheduled job: __mine_interval
2017-11-28 11:28:32,760 [salt.utils.schedule][INFO    ][21799] Running scheduled job: __mine_interval
2017-11-28 11:31:03,614 [salt.minion      ][INFO    ][21799] User sudo_ubuntu Executing command cp.push_dir with jid 20171128113103604481
2017-11-28 11:31:03,641 [salt.minion      ][INFO    ][29492] Starting a new job with PID 29492
