2017-11-13 08:35:32,506 [salt.cli.daemons ][WARNING ][2104] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 08:36:50,962 [salt.state       ][ERROR   ][3674] No contents found in top file
2017-11-13 08:38:46,851 [salt.utils.parsers][WARNING ][2104] Minion received a SIGTERM. Exiting.
2017-11-13 08:43:15,075 [salt.cli.daemons ][WARNING ][3150] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 08:45:30,748 [py.warnings      ][WARNING ][16369] /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-13 08:45:58,945 [salt.loaded.int.module.cmdmod][ERROR   ][16369] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:45:58,946 [salt.loaded.int.module.cmdmod][ERROR   ][16369] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:03,948 [salt.loaded.int.module.cmdmod][ERROR   ][16369] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:46:03,949 [salt.loaded.int.module.cmdmod][ERROR   ][16369] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:08,933 [salt.loaded.int.module.cmdmod][ERROR   ][16369] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:46:08,934 [salt.loaded.int.module.cmdmod][ERROR   ][16369] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:13,913 [salt.loaded.int.module.cmdmod][ERROR   ][16369] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-11-13 08:46:13,914 [salt.loaded.int.module.cmdmod][ERROR   ][16369] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-11-13 08:46:17,885 [salt.loaded.int.module.cmdmod][ERROR   ][16369] 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-13 08:46:21,249 [salt.loaded.int.module.cmdmod][INFO    ][23650] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-13 08:46:21,261 [salt.loaded.int.module.cmdmod][INFO    ][23650] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:21,280 [salt.loaded.int.module.cmdmod][INFO    ][23650] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:21,298 [salt.utils.parsers][WARNING ][3150] Minion received a SIGTERM. Exiting.
2017-11-13 08:46:21,836 [salt.cli.daemons ][INFO    ][23737] Setting up the Salt Minion "kvm02.baremetal-mcp-ocata-odl-ha.local"
2017-11-13 08:46:22,740 [salt.minion      ][INFO    ][23737] Creating minion process manager
2017-11-13 08:46:22,741 [salt.cli.daemons ][WARNING ][23737] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-11-13 08:46:22,741 [salt.cli.daemons ][INFO    ][23737] The Salt Minion is starting up
2017-11-13 08:46:22,742 [salt.minion      ][INFO    ][23737] Minion is starting as user 'root'
2017-11-13 08:46:22,744 [salt.utils.event ][INFO    ][23737] Starting pull socket on /var/run/salt/minion/minion_event_0607a05f39_pull.ipc
2017-11-13 08:46:24,183 [salt.loaded.int.module.cmdmod][INFO    ][23737] Executing command ['date', '+%z'] in directory '/root'
2017-11-13 08:46:24,197 [salt.utils.schedule][INFO    ][23737] Updating job settings for scheduled job: __mine_interval
2017-11-13 08:46:24,203 [salt.minion      ][INFO    ][23737] Added mine.update to scheduler
2017-11-13 08:46:24,224 [salt.minion      ][INFO    ][23737] Minion is ready to receive requests!
2017-11-13 08:46:25,226 [salt.utils.schedule][INFO    ][23737] Running scheduled job: __mine_interval
2017-11-13 08:46:31,657 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113084631643611
2017-11-13 08:46:31,677 [salt.minion      ][INFO    ][23862] Starting a new job with PID 23862
2017-11-13 08:46:31,693 [salt.minion      ][INFO    ][23862] Returning information for job: 20171113084631643611
2017-11-13 08:46:42,633 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command state.sls with jid 20171113084642623782
2017-11-13 08:46:42,652 [salt.minion      ][INFO    ][23867] Starting a new job with PID 23867
2017-11-13 08:46:45,616 [salt.state       ][INFO    ][23867] Loading fresh modules for state activity
2017-11-13 08:46:45,652 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-11-13 08:46:45,684 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-11-13 08:46:45,723 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:45,832 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-11-13 08:46:45,855 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-11-13 08:46:45,894 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:45,974 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-13 08:46:46,001 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 08:46:46,140 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-13 08:46:46,167 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-13 08:46:46,182 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:46:46,202 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-13 08:46:46,218 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-13 08:46:46,243 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-13 08:46:46,269 [py.warnings      ][WARNING ][23867] /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-13 08:46:46,270 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'salt-minion --version' in directory '/root'
2017-11-13 08:46:46,612 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-13 08:46:46,638 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-13 08:46:46,655 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-13 08:46:46,668 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-13 08:46:46,735 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-13 08:46:46,784 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-13 08:46:46,881 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-11-13 08:46:46,911 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:46,996 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-11-13 08:46:47,026 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 08:46:47,105 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-11-13 08:46:47,127 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-11-13 08:46:47,149 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:46:47,166 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-11-13 08:46:47,178 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-11-13 08:46:47,204 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-11-13 08:46:47,232 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'salt-minion --version' in directory '/root'
2017-11-13 08:46:47,582 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-11-13 08:46:47,601 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-11-13 08:46:47,615 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-11-13 08:46:47,628 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-11-13 08:46:47,696 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-11-13 08:46:47,739 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-11-13 08:46:47,844 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-11-13 08:46:47,936 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:48,385 [salt.state       ][INFO    ][23867] Running state [salt-minion] at time 08:46:48.385259
2017-11-13 08:46:48,386 [salt.state       ][INFO    ][23867] Executing state pkg.installed for salt-minion
2017-11-13 08:46:48,386 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 08:46:48,728 [salt.state       ][INFO    ][23867] Package salt-minion is already installed
2017-11-13 08:46:48,729 [salt.state       ][INFO    ][23867] Completed state [salt-minion] at time 08:46:48.728689 duration_in_ms=343.43
2017-11-13 08:46:48,729 [salt.state       ][INFO    ][23867] Running state [salt_minion_dependency_packages] at time 08:46:48.729268
2017-11-13 08:46:48,730 [salt.state       ][INFO    ][23867] Executing state pkg.installed for salt_minion_dependency_packages
2017-11-13 08:46:48,734 [salt.state       ][INFO    ][23867] All specified packages are already installed
2017-11-13 08:46:48,735 [salt.state       ][INFO    ][23867] Completed state [salt_minion_dependency_packages] at time 08:46:48.734610 duration_in_ms=5.342
2017-11-13 08:46:48,737 [salt.state       ][INFO    ][23867] Running state [/etc/salt/minion.d/minion.conf] at time 08:46:48.736997
2017-11-13 08:46:48,738 [salt.state       ][INFO    ][23867] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-11-13 08:46:48,761 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-11-13 08:46:48,838 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-11-13 08:46:48,911 [salt.fileclient  ][INFO    ][23867] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-11-13 08:46:48,979 [salt.state       ][INFO    ][23867] File /etc/salt/minion.d/minion.conf is in the correct state
2017-11-13 08:46:48,980 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/minion.d/minion.conf] at time 08:46:48.979625 duration_in_ms=242.627
2017-11-13 08:46:48,980 [salt.state       ][INFO    ][23867] Running state [/etc/salt/minion.d/_virt.conf] at time 08:46:48.980208
2017-11-13 08:46:48,981 [salt.state       ][INFO    ][23867] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-11-13 08:46:48,982 [salt.state       ][INFO    ][23867] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-11-13 08:46:48,983 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/minion.d/_virt.conf] at time 08:46:48.982621 duration_in_ms=2.412
2017-11-13 08:46:48,985 [salt.state       ][INFO    ][23867] Running state [salt-minion] at time 08:46:48.984837
2017-11-13 08:46:48,985 [salt.state       ][INFO    ][23867] Executing state service.running for salt-minion
2017-11-13 08:46:48,986 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-11-13 08:46:49,008 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:49,015 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-11-13 08:46:49,024 [salt.state       ][INFO    ][23867] The service salt-minion is already running
2017-11-13 08:46:49,024 [salt.state       ][INFO    ][23867] Completed state [salt-minion] at time 08:46:49.023851 duration_in_ms=39.013
2017-11-13 08:46:49,025 [salt.state       ][INFO    ][23867] Running state [/etc/salt/grains.d] at time 08:46:49.025414
2017-11-13 08:46:49,026 [salt.state       ][INFO    ][23867] Executing state file.directory for /etc/salt/grains.d
2017-11-13 08:46:49,028 [salt.state       ][INFO    ][23867] Directory /etc/salt/grains.d is in the correct state
2017-11-13 08:46:49,028 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/grains.d] at time 08:46:49.027987 duration_in_ms=2.572
2017-11-13 08:46:49,029 [salt.state       ][INFO    ][23867] Running state [/etc/salt/grains] at time 08:46:49.028659
2017-11-13 08:46:49,029 [salt.state       ][INFO    ][23867] Executing state file.managed for /etc/salt/grains
2017-11-13 08:46:49,031 [salt.state       ][INFO    ][23867] File /etc/salt/grains exists with proper permissions. No changes made.
2017-11-13 08:46:49,031 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/grains] at time 08:46:49.030968 duration_in_ms=2.307
2017-11-13 08:46:49,032 [salt.state       ][INFO    ][23867] Running state [/etc/salt/grains.d/placeholder] at time 08:46:49.031757
2017-11-13 08:46:49,032 [salt.state       ][INFO    ][23867] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-11-13 08:46:49,033 [salt.state       ][INFO    ][23867] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-11-13 08:46:49,033 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/grains.d/placeholder] at time 08:46:49.032701 duration_in_ms=0.943
2017-11-13 08:46:49,033 [salt.state       ][INFO    ][23867] Running state [/etc/salt/grains.d/sphinx] at time 08:46:49.033178
2017-11-13 08:46:49,033 [salt.state       ][INFO    ][23867] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-11-13 08:46:49,035 [salt.state       ][INFO    ][23867] File changed:
--- 
+++ 
@@ -49,7 +49,6 @@
               - 127.0.0.1
               - 192.168.10.142
               - 192.168.11.252
-              - 192.168.122.1
         system:
           name: System
           param:

2017-11-13 08:46:49,036 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/grains.d/sphinx] at time 08:46:49.035521 duration_in_ms=2.343
2017-11-13 08:46:49,037 [salt.state       ][INFO    ][23867] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.036969
2017-11-13 08:46:49,037 [salt.state       ][INFO    ][23867] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:49,038 [salt.state       ][INFO    ][23867] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:49,039 [salt.state       ][INFO    ][23867] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.038677 duration_in_ms=1.707
2017-11-13 08:46:49,039 [salt.state       ][INFO    ][23867] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.038934
2017-11-13 08:46:49,039 [salt.state       ][INFO    ][23867] 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-13 08:46:49,040 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"' in directory '/root'
2017-11-13 08:46:49,137 [salt.state       ][INFO    ][23867] {'pid': 23915, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-13 08:46:49,137 [salt.state       ][INFO    ][23867] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.137257 duration_in_ms=98.323
2017-11-13 08:46:49,138 [salt.state       ][INFO    ][23867] Running state [/etc/salt/grains.d/dns_records] at time 08:46:49.137968
2017-11-13 08:46:49,138 [salt.state       ][INFO    ][23867] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-11-13 08:46:49,139 [salt.state       ][INFO    ][23867] File /etc/salt/grains.d/dns_records is in the correct state
2017-11-13 08:46:49,140 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/grains.d/dns_records] at time 08:46:49.139914 duration_in_ms=1.945
2017-11-13 08:46:49,141 [salt.state       ][INFO    ][23867] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.140827
2017-11-13 08:46:49,141 [salt.state       ][INFO    ][23867] 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-13 08:46:49,141 [salt.state       ][INFO    ][23867] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:49,142 [salt.state       ][INFO    ][23867] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.141656 duration_in_ms=0.828
2017-11-13 08:46:49,143 [salt.state       ][INFO    ][23867] Running state [/etc/salt/grains.d/salt] at time 08:46:49.142517
2017-11-13 08:46:49,143 [salt.state       ][INFO    ][23867] Executing state file.managed for /etc/salt/grains.d/salt
2017-11-13 08:46:49,144 [salt.state       ][INFO    ][23867] File /etc/salt/grains.d/salt is in the correct state
2017-11-13 08:46:49,144 [salt.state       ][INFO    ][23867] Completed state [/etc/salt/grains.d/salt] at time 08:46:49.143742 duration_in_ms=1.225
2017-11-13 08:46:49,145 [salt.state       ][INFO    ][23867] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.144534
2017-11-13 08:46:49,145 [salt.state       ][INFO    ][23867] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:49,145 [salt.state       ][INFO    ][23867] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-11-13 08:46:49,145 [salt.state       ][INFO    ][23867] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:46:49.145376 duration_in_ms=0.841
2017-11-13 08:46:49,148 [salt.state       ][INFO    ][23867] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:49.148063
2017-11-13 08:46:49,148 [salt.state       ][INFO    ][23867] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 08:46:49,149 [salt.state       ][INFO    ][23867] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 08:46:49,149 [salt.state       ][INFO    ][23867] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:49.148867 duration_in_ms=0.803
2017-11-13 08:46:49,149 [salt.state       ][INFO    ][23867] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:49.149391
2017-11-13 08:46:49,150 [salt.state       ][INFO    ][23867] Executing state cmd.mod_watch for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-11-13 08:46:49,152 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'cat /etc/salt/grains.d/* > /etc/salt/grains' in directory '/root'
2017-11-13 08:46:49,159 [salt.state       ][INFO    ][23867] {'pid': 23917, 'retcode': 0, 'stderr': '', 'stdout': ''}
2017-11-13 08:46:49,160 [salt.state       ][INFO    ][23867] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:46:49.159517 duration_in_ms=10.126
2017-11-13 08:46:49,161 [salt.state       ][INFO    ][23867] Running state [mine.update] at time 08:46:49.161147
2017-11-13 08:46:49,161 [salt.state       ][INFO    ][23867] Executing state module.wait for mine.update
2017-11-13 08:46:49,162 [salt.state       ][INFO    ][23867] No changes made for mine.update
2017-11-13 08:46:49,163 [salt.state       ][INFO    ][23867] Completed state [mine.update] at time 08:46:49.162788 duration_in_ms=1.64
2017-11-13 08:46:49,163 [salt.state       ][INFO    ][23867] Running state [mine.update] at time 08:46:49.163024
2017-11-13 08:46:49,163 [salt.state       ][INFO    ][23867] Executing state module.mod_watch for mine.update
2017-11-13 08:46:49,681 [salt.state       ][INFO    ][23867] {'ret': True}
2017-11-13 08:46:49,681 [salt.state       ][INFO    ][23867] Completed state [mine.update] at time 08:46:49.680893 duration_in_ms=517.869
2017-11-13 08:46:49,682 [salt.state       ][INFO    ][23867] Running state [ca-certificates] at time 08:46:49.681472
2017-11-13 08:46:49,682 [salt.state       ][INFO    ][23867] Executing state pkg.installed for ca-certificates
2017-11-13 08:46:49,687 [salt.state       ][INFO    ][23867] Package ca-certificates is already installed
2017-11-13 08:46:49,687 [salt.state       ][INFO    ][23867] Completed state [ca-certificates] at time 08:46:49.686786 duration_in_ms=5.314
2017-11-13 08:46:49,688 [salt.state       ][INFO    ][23867] Running state [update-ca-certificates] at time 08:46:49.687492
2017-11-13 08:46:49,688 [salt.state       ][INFO    ][23867] Executing state cmd.wait for update-ca-certificates
2017-11-13 08:46:49,688 [salt.state       ][INFO    ][23867] No changes made for update-ca-certificates
2017-11-13 08:46:49,688 [salt.state       ][INFO    ][23867] Completed state [update-ca-certificates] at time 08:46:49.688139 duration_in_ms=0.646
2017-11-13 08:46:49,688 [salt.state       ][INFO    ][23867] Running state [libvirt-dev] at time 08:46:49.688326
2017-11-13 08:46:49,689 [salt.state       ][INFO    ][23867] Executing state pkg.installed for libvirt-dev
2017-11-13 08:46:49,692 [salt.state       ][INFO    ][23867] Package libvirt-dev is already installed
2017-11-13 08:46:49,693 [salt.state       ][INFO    ][23867] Completed state [libvirt-dev] at time 08:46:49.692538 duration_in_ms=4.213
2017-11-13 08:46:49,693 [salt.state       ][INFO    ][23867] Running state [pkg-config] at time 08:46:49.692736
2017-11-13 08:46:49,693 [salt.state       ][INFO    ][23867] Executing state pkg.installed for pkg-config
2017-11-13 08:46:49,698 [salt.state       ][INFO    ][23867] Package pkg-config is already installed
2017-11-13 08:46:49,698 [salt.state       ][INFO    ][23867] Completed state [pkg-config] at time 08:46:49.698059 duration_in_ms=5.323
2017-11-13 08:46:49,698 [salt.state       ][INFO    ][23867] Running state [virtng.init] at time 08:46:49.698254
2017-11-13 08:46:49,698 [salt.state       ][INFO    ][23867] Executing state module.run for virtng.init
2017-11-13 08:46:49,762 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command ['git', '--version'] in directory '/root'
2017-11-13 08:46:49,810 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --all --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,832 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:49,833 [salt.state       ][INFO    ][23867] Completed state [virtng.init] at time 08:46:49.832519 duration_in_ms=134.264
2017-11-13 08:46:49,833 [salt.state       ][INFO    ][23867] Running state [virt.set_autostart] at time 08:46:49.833089
2017-11-13 08:46:49,834 [salt.state       ][INFO    ][23867] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,841 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --autostart --name| grep -E "^mdb02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,867 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:49,867 [salt.state       ][INFO    ][23867] Completed state [virt.set_autostart] at time 08:46:49.866988 duration_in_ms=33.898
2017-11-13 08:46:49,868 [salt.state       ][INFO    ][23867] Running state [virtng.init] at time 08:46:49.867760
2017-11-13 08:46:49,868 [salt.state       ][INFO    ][23867] Executing state module.run for virtng.init
2017-11-13 08:46:49,877 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --all --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,910 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:49,912 [salt.state       ][INFO    ][23867] Completed state [virtng.init] at time 08:46:49.911409 duration_in_ms=43.649
2017-11-13 08:46:49,912 [salt.state       ][INFO    ][23867] Running state [virt.set_autostart] at time 08:46:49.911985
2017-11-13 08:46:49,912 [salt.state       ][INFO    ][23867] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,916 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --autostart --name| grep -E "^dbs02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,946 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:49,947 [salt.state       ][INFO    ][23867] Completed state [virt.set_autostart] at time 08:46:49.947043 duration_in_ms=35.057
2017-11-13 08:46:49,947 [salt.state       ][INFO    ][23867] Running state [virtng.init] at time 08:46:49.947354
2017-11-13 08:46:49,948 [salt.state       ][INFO    ][23867] Executing state module.run for virtng.init
2017-11-13 08:46:49,951 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --all --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:49,987 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:49,987 [salt.state       ][INFO    ][23867] Completed state [virtng.init] at time 08:46:49.987084 duration_in_ms=39.73
2017-11-13 08:46:49,988 [salt.state       ][INFO    ][23867] Running state [virt.set_autostart] at time 08:46:49.987526
2017-11-13 08:46:49,988 [salt.state       ][INFO    ][23867] Executing state module.run for virt.set_autostart
2017-11-13 08:46:49,996 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --autostart --name| grep -E "^ctl02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:50,024 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:50,024 [salt.state       ][INFO    ][23867] Completed state [virt.set_autostart] at time 08:46:50.023889 duration_in_ms=36.363
2017-11-13 08:46:50,024 [salt.state       ][INFO    ][23867] Running state [virtng.init] at time 08:46:50.024114
2017-11-13 08:46:50,024 [salt.state       ][INFO    ][23867] Executing state module.run for virtng.init
2017-11-13 08:46:50,029 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --all --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:50,056 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:50,056 [salt.state       ][INFO    ][23867] Completed state [virtng.init] at time 08:46:50.056273 duration_in_ms=32.159
2017-11-13 08:46:50,057 [salt.state       ][INFO    ][23867] Running state [virt.set_autostart] at time 08:46:50.056550
2017-11-13 08:46:50,057 [salt.state       ][INFO    ][23867] Executing state module.run for virt.set_autostart
2017-11-13 08:46:50,061 [salt.loaded.int.module.cmdmod][INFO    ][23867] Executing command 'virsh list --autostart --name| grep -E "^msg02.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-11-13 08:46:50,087 [salt.state       ][INFO    ][23867] unless execution succeeded
2017-11-13 08:46:50,087 [salt.state       ][INFO    ][23867] Completed state [virt.set_autostart] at time 08:46:50.087270 duration_in_ms=30.72
2017-11-13 08:46:50,094 [salt.minion      ][INFO    ][23867] Returning information for job: 20171113084642623782
2017-11-13 08:47:17,660 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command saltutil.sync_all with jid 20171113084717651230
2017-11-13 08:47:17,687 [salt.minion      ][INFO    ][23963] Starting a new job with PID 23963
2017-11-13 08:47:22,112 [salt.state       ][INFO    ][23963] Loading fresh modules for state activity
2017-11-13 08:47:22,165 [salt.state       ][ERROR   ][23963] No contents found in top file
2017-11-13 08:47:22,166 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-11-13 08:47:22,173 [salt.utils.extmods][INFO    ][23963] Syncing beacons for environment 'base'
2017-11-13 08:47:22,173 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_beacons, for base)
2017-11-13 08:47:22,173 [salt.fileclient  ][INFO    ][23963] Caching directory '_beacons/' for environment 'base'
2017-11-13 08:47:22,251 [salt.utils.extmods][INFO    ][23963] Syncing modules for environment 'base'
2017-11-13 08:47:22,251 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_modules, for base)
2017-11-13 08:47:22,252 [salt.fileclient  ][INFO    ][23963] Caching directory '_modules/' for environment 'base'
2017-11-13 08:47:22,311 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-11-13 08:47:22,323 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-11-13 08:47:22,338 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-11-13 08:47:22,352 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-11-13 08:47:22,364 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-11-13 08:47:22,377 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-11-13 08:47:22,388 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-11-13 08:47:22,399 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-11-13 08:47:22,412 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-11-13 08:47:22,423 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-11-13 08:47:22,435 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-11-13 08:47:22,447 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-11-13 08:47:22,460 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-11-13 08:47:22,472 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-11-13 08:47:22,484 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-11-13 08:47:22,507 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-11-13 08:47:22,523 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-11-13 08:47:22,538 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/modelutils.py'
2017-11-13 08:47:22,554 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-11-13 08:47:22,567 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-11-13 08:47:22,581 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-11-13 08:47:22,597 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-11-13 08:47:22,613 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-11-13 08:47:22,625 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-11-13 08:47:22,639 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-11-13 08:47:22,651 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-11-13 08:47:22,665 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-11-13 08:47:22,867 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-11-13 08:47:22,900 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-11-13 08:47:22,917 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-11-13 08:47:22,941 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-11-13 08:47:22,965 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-11-13 08:47:22,986 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-11-13 08:47:22,994 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-11-13 08:47:22,994 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-11-13 08:47:22,995 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-11-13 08:47:22,996 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-11-13 08:47:22,998 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-11-13 08:47:22,999 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-11-13 08:47:22,999 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-11-13 08:47:22,1000 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-11-13 08:47:23,000 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-11-13 08:47:23,001 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-11-13 08:47:23,001 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-11-13 08:47:23,002 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-11-13 08:47:23,003 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-11-13 08:47:23,003 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-11-13 08:47:23,004 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-11-13 08:47:23,004 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-11-13 08:47:23,005 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-11-13 08:47:23,006 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-11-13 08:47:23,007 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/modelutils.py' to '/var/cache/salt/minion/extmods/modules/modelutils.py'
2017-11-13 08:47:23,007 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-11-13 08:47:23,008 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-11-13 08:47:23,009 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-11-13 08:47:23,010 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-11-13 08:47:23,011 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-11-13 08:47:23,012 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-11-13 08:47:23,012 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-11-13 08:47:23,013 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-11-13 08:47:23,013 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-11-13 08:47:23,014 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-11-13 08:47:23,015 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-11-13 08:47:23,015 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-11-13 08:47:23,016 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-11-13 08:47:23,016 [salt.utils.extmods][INFO    ][23963] 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-13 08:47:23,017 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-11-13 08:47:23,017 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-11-13 08:47:23,032 [salt.utils.extmods][INFO    ][23963] Syncing states for environment 'base'
2017-11-13 08:47:23,033 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_states, for base)
2017-11-13 08:47:23,033 [salt.fileclient  ][INFO    ][23963] Caching directory '_states/' for environment 'base'
2017-11-13 08:47:23,118 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-11-13 08:47:23,168 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-11-13 08:47:23,199 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-11-13 08:47:23,230 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-11-13 08:47:23,258 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-11-13 08:47:23,291 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-11-13 08:47:23,308 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-11-13 08:47:23,334 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-11-13 08:47:23,356 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-11-13 08:47:23,371 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-11-13 08:47:23,392 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-11-13 08:47:23,420 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-11-13 08:47:23,449 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-11-13 08:47:23,465 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-11-13 08:47:23,479 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-11-13 08:47:23,501 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-11-13 08:47:23,575 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-11-13 08:47:23,593 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-11-13 08:47:23,648 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-11-13 08:47:23,662 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-11-13 08:47:23,678 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-11-13 08:47:23,696 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-11-13 08:47:23,727 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-11-13 08:47:23,787 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-11-13 08:47:23,806 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-11-13 08:47:23,821 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-11-13 08:47:23,841 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-11-13 08:47:23,860 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-11-13 08:47:23,873 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-11-13 08:47:23,881 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-11-13 08:47:23,881 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-11-13 08:47:23,882 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-11-13 08:47:23,883 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-11-13 08:47:23,883 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-11-13 08:47:23,883 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-11-13 08:47:23,884 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-11-13 08:47:23,884 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-11-13 08:47:23,885 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-11-13 08:47:23,885 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-11-13 08:47:23,886 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-11-13 08:47:23,886 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-11-13 08:47:23,887 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-11-13 08:47:23,887 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-11-13 08:47:23,888 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-11-13 08:47:23,888 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-11-13 08:47:23,889 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-11-13 08:47:23,889 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-11-13 08:47:23,889 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-11-13 08:47:23,890 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-11-13 08:47:23,890 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-11-13 08:47:23,891 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-11-13 08:47:23,891 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-11-13 08:47:23,891 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-11-13 08:47:23,891 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-11-13 08:47:23,892 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-11-13 08:47:23,892 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-11-13 08:47:23,892 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-11-13 08:47:23,893 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-11-13 08:47:23,893 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-11-13 08:47:23,898 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-11-13 08:47:23,908 [salt.utils.extmods][INFO    ][23963] Syncing sdb for environment 'base'
2017-11-13 08:47:23,908 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_sdb, for base)
2017-11-13 08:47:23,909 [salt.fileclient  ][INFO    ][23963] Caching directory '_sdb/' for environment 'base'
2017-11-13 08:47:23,1000 [salt.utils.extmods][INFO    ][23963] Syncing grains for environment 'base'
2017-11-13 08:47:24,000 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_grains, for base)
2017-11-13 08:47:24,000 [salt.fileclient  ][INFO    ][23963] Caching directory '_grains/' for environment 'base'
2017-11-13 08:47:24,109 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-11-13 08:47:24,148 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/ceph.py'
2017-11-13 08:47:24,184 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-11-13 08:47:24,220 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-11-13 08:47:24,241 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-11-13 08:47:24,274 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-11-13 08:47:24,296 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-11-13 08:47:24,319 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-11-13 08:47:24,331 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-11-13 08:47:24,352 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-11-13 08:47:24,398 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-11-13 08:47:24,422 [salt.fileclient  ][INFO    ][23963] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-11-13 08:47:24,431 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-11-13 08:47:24,432 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/ceph.py' to '/var/cache/salt/minion/extmods/grains/ceph.py'
2017-11-13 08:47:24,433 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-11-13 08:47:24,433 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-11-13 08:47:24,434 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-11-13 08:47:24,435 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-11-13 08:47:24,436 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-11-13 08:47:24,437 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-11-13 08:47:24,438 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-11-13 08:47:24,439 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-11-13 08:47:24,439 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-11-13 08:47:24,440 [salt.utils.extmods][INFO    ][23963] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-11-13 08:47:24,442 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-11-13 08:47:24,447 [salt.utils.extmods][INFO    ][23963] Syncing renderers for environment 'base'
2017-11-13 08:47:24,447 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_renderers, for base)
2017-11-13 08:47:24,448 [salt.fileclient  ][INFO    ][23963] Caching directory '_renderers/' for environment 'base'
2017-11-13 08:47:24,539 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-11-13 08:47:24,544 [salt.utils.extmods][INFO    ][23963] Syncing returners for environment 'base'
2017-11-13 08:47:24,544 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_returners, for base)
2017-11-13 08:47:24,544 [salt.fileclient  ][INFO    ][23963] Caching directory '_returners/' for environment 'base'
2017-11-13 08:47:24,600 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-11-13 08:47:24,604 [salt.utils.extmods][INFO    ][23963] Syncing output for environment 'base'
2017-11-13 08:47:24,604 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_output, for base)
2017-11-13 08:47:24,605 [salt.fileclient  ][INFO    ][23963] Caching directory '_output/' for environment 'base'
2017-11-13 08:47:24,660 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-11-13 08:47:24,665 [salt.utils.extmods][INFO    ][23963] Syncing utils for environment 'base'
2017-11-13 08:47:24,666 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_utils, for base)
2017-11-13 08:47:24,666 [salt.fileclient  ][INFO    ][23963] Caching directory '_utils/' for environment 'base'
2017-11-13 08:47:24,737 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-11-13 08:47:24,742 [salt.utils.extmods][INFO    ][23963] Syncing log_handlers for environment 'base'
2017-11-13 08:47:24,743 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_log_handlers, for base)
2017-11-13 08:47:24,743 [salt.fileclient  ][INFO    ][23963] Caching directory '_log_handlers/' for environment 'base'
2017-11-13 08:47:24,789 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-11-13 08:47:24,792 [salt.utils.extmods][INFO    ][23963] Syncing proxy for environment 'base'
2017-11-13 08:47:24,792 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_proxy, for base)
2017-11-13 08:47:24,792 [salt.fileclient  ][INFO    ][23963] Caching directory '_proxy/' for environment 'base'
2017-11-13 08:47:24,838 [salt.utils.extmods][INFO    ][23963] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-11-13 08:47:24,842 [salt.utils.extmods][INFO    ][23963] Syncing engines for environment 'base'
2017-11-13 08:47:24,842 [salt.utils.extmods][INFO    ][23963] Loading cache from salt://_engines, for base)
2017-11-13 08:47:24,843 [salt.fileclient  ][INFO    ][23963] Caching directory '_engines/' for environment 'base'
2017-11-13 08:47:24,892 [salt.minion      ][INFO    ][23963] Returning information for job: 20171113084717651230
2017-11-13 08:57:33,293 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command test.ping with jid 20171113085733284742
2017-11-13 08:57:33,318 [salt.minion      ][INFO    ][24215] Starting a new job with PID 24215
2017-11-13 08:57:33,375 [salt.minion      ][INFO    ][24215] Returning information for job: 20171113085733284742
2017-11-13 08:58:41,532 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command state.sls with jid 20171113085841523241
2017-11-13 08:58:41,556 [salt.minion      ][INFO    ][24245] Starting a new job with PID 24245
2017-11-13 08:58:43,858 [salt.state       ][INFO    ][24245] Loading fresh modules for state activity
2017-11-13 08:58:43,921 [salt.fileclient  ][INFO    ][24245] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-13 08:58:43,942 [salt.fileclient  ][INFO    ][24245] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-13 08:58:43,975 [salt.fileclient  ][INFO    ][24245] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:58:44,387 [salt.state       ][INFO    ][24245] Running state [lsof] at time 08:58:44.387416
2017-11-13 08:58:44,388 [salt.state       ][INFO    ][24245] Executing state pkg.installed for lsof
2017-11-13 08:58:44,388 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 08:58:44,714 [salt.state       ][INFO    ][24245] Package lsof is already installed
2017-11-13 08:58:44,714 [salt.state       ][INFO    ][24245] Completed state [lsof] at time 08:58:44.714315 duration_in_ms=326.899
2017-11-13 08:58:44,715 [salt.state       ][INFO    ][24245] Running state [keepalived] at time 08:58:44.714546
2017-11-13 08:58:44,715 [salt.state       ][INFO    ][24245] Executing state pkg.installed for keepalived
2017-11-13 08:58:44,739 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-13 08:58:47,431 [salt.loaded.int.module.cmdmod][INFO    ][24245] 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-13 08:58:51,593 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113085851575587
2017-11-13 08:58:51,613 [salt.minion      ][INFO    ][25741] Starting a new job with PID 25741
2017-11-13 08:58:51,632 [salt.minion      ][INFO    ][25741] Returning information for job: 20171113085851575587
2017-11-13 08:58:51,702 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 08:58:51,760 [salt.state       ][INFO    ][24245] 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-13 08:58:51,772 [salt.state       ][INFO    ][24245] Loading fresh modules for state activity
2017-11-13 08:58:51,789 [salt.state       ][INFO    ][24245] Completed state [keepalived] at time 08:58:51.789151 duration_in_ms=7074.604
2017-11-13 08:58:51,792 [salt.state       ][INFO    ][24245] Running state [/etc/keepalived/keepalived.conf] at time 08:58:51.791884
2017-11-13 08:58:51,792 [salt.state       ][INFO    ][24245] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-13 08:58:51,817 [salt.fileclient  ][INFO    ][24245] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-13 08:58:51,861 [salt.fileclient  ][INFO    ][24245] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 08:58:51,876 [salt.state       ][INFO    ][24245] File changed:
New file
2017-11-13 08:58:51,876 [salt.state       ][INFO    ][24245] Completed state [/etc/keepalived/keepalived.conf] at time 08:58:51.875721 duration_in_ms=83.837
2017-11-13 08:58:51,989 [salt.state       ][INFO    ][24245] Running state [keepalived] at time 08:58:51.988590
2017-11-13 08:58:51,989 [salt.state       ][INFO    ][24245] Executing state service.running for keepalived
2017-11-13 08:58:51,992 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-13 08:58:52,017 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 08:58:52,042 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:58:52,063 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:58:52,082 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-11-13 08:58:52,154 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 08:58:52,185 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:58:52,212 [salt.loaded.int.module.cmdmod][INFO    ][24245] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 08:58:52,231 [salt.state       ][INFO    ][24245] {'keepalived': True}
2017-11-13 08:58:52,232 [salt.state       ][INFO    ][24245] Completed state [keepalived] at time 08:58:52.231631 duration_in_ms=243.04
2017-11-13 08:58:52,235 [salt.minion      ][INFO    ][24245] Returning information for job: 20171113085841523241
2017-11-13 08:59:46,323 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command pillar.get with jid 20171113085946315696
2017-11-13 08:59:46,345 [salt.minion      ][INFO    ][25856] Starting a new job with PID 25856
2017-11-13 08:59:46,353 [salt.minion      ][INFO    ][25856] Returning information for job: 20171113085946315696
2017-11-13 09:05:14,223 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command state.sls with jid 20171113090514215274
2017-11-13 09:05:14,244 [salt.minion      ][INFO    ][25950] Starting a new job with PID 25950
2017-11-13 09:05:16,892 [salt.state       ][INFO    ][25950] Loading fresh modules for state activity
2017-11-13 09:05:16,922 [salt.fileclient  ][INFO    ][25950] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:05:16,951 [salt.fileclient  ][INFO    ][25950] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:05:17,316 [salt.state       ][INFO    ][25950] Running state [attr] at time 09:05:17.316202
2017-11-13 09:05:17,317 [salt.state       ][INFO    ][25950] Executing state pkg.installed for attr
2017-11-13 09:05:17,317 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:17,649 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-11-13 09:05:20,310 [salt.loaded.int.module.cmdmod][INFO    ][25950] 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-13 09:05:22,257 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:22,310 [salt.state       ][INFO    ][25950] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-11-13 09:05:22,324 [salt.state       ][INFO    ][25950] Loading fresh modules for state activity
2017-11-13 09:05:22,345 [salt.state       ][INFO    ][25950] Completed state [attr] at time 09:05:22.344605 duration_in_ms=5028.403
2017-11-13 09:05:22,362 [salt.state       ][INFO    ][25950] Running state [glusterfs-server] at time 09:05:22.361494
2017-11-13 09:05:22,362 [salt.state       ][INFO    ][25950] Executing state pkg.installed for glusterfs-server
2017-11-13 09:05:22,726 [salt.loaded.int.module.cmdmod][INFO    ][25950] 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-13 09:05:24,335 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113090524318177
2017-11-13 09:05:24,352 [salt.minion      ][INFO    ][27049] Starting a new job with PID 27049
2017-11-13 09:05:24,371 [salt.minion      ][INFO    ][27049] Returning information for job: 20171113090524318177
2017-11-13 09:05:33,321 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:33,377 [salt.state       ][INFO    ][25950] Made the following changes:
'keyutils' changed from 'absent' to '1.5.9-8ubuntu1'
'nfs-common' changed from 'absent' to '1:1.2.8-9ubuntu12.1'
'rpcbind' changed from 'absent' to '0.2.3-0.2'
'glusterfs-common' changed from 'absent' to '3.7.6-1ubuntu1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'libnfsidmap2' changed from 'absent' to '0.25-5'
'portmap' changed from 'absent' to '1'
'libattr1-dev' changed from 'absent' to '1:2.4.47-2'
'attr-dev' changed from 'absent' to '1'
'acl-dev' changed from 'absent' to '1'
'glusterfs-server' changed from 'absent' to '3.7.6-1ubuntu1'
'glusterfs-client' changed from 'absent' to '3.7.6-1ubuntu1'
'libpython2.7' changed from 'absent' to '2.7.12-1ubuntu0~16.04.1'
'nfs-client' changed from 'absent' to '1'
'liburcu4' changed from 'absent' to '0.9.1-3'
'libacl1-dev' changed from 'absent' to '2.2.52-3'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libtirpc1' changed from 'absent' to '0.2.5-1'

2017-11-13 09:05:33,397 [salt.state       ][INFO    ][25950] Loading fresh modules for state activity
2017-11-13 09:05:33,420 [salt.state       ][INFO    ][25950] Completed state [glusterfs-server] at time 09:05:33.420442 duration_in_ms=11058.947
2017-11-13 09:05:33,426 [salt.state       ][INFO    ][25950] Running state [bc] at time 09:05:33.425808
2017-11-13 09:05:33,427 [salt.state       ][INFO    ][25950] Executing state pkg.installed for bc
2017-11-13 09:05:33,789 [salt.loaded.int.module.cmdmod][INFO    ][25950] 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-13 09:05:34,474 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113090534465918
2017-11-13 09:05:34,494 [salt.minion      ][INFO    ][30337] Starting a new job with PID 30337
2017-11-13 09:05:34,515 [salt.minion      ][INFO    ][30337] Returning information for job: 20171113090534465918
2017-11-13 09:05:35,823 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:35,880 [salt.state       ][INFO    ][25950] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-11-13 09:05:35,898 [salt.state       ][INFO    ][25950] Loading fresh modules for state activity
2017-11-13 09:05:35,941 [salt.state       ][INFO    ][25950] Completed state [bc] at time 09:05:35.940473 duration_in_ms=2514.665
2017-11-13 09:05:36,020 [salt.state       ][INFO    ][25950] Running state [glusterfs-server] at time 09:05:36.019766
2017-11-13 09:05:36,020 [salt.state       ][INFO    ][25950] Executing state service.running for glusterfs-server
2017-11-13 09:05:36,022 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:05:36,036 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:05:36,053 [salt.loaded.int.module.cmdmod][INFO    ][25950] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:05:36,073 [salt.state       ][INFO    ][25950] The service glusterfs-server is already running
2017-11-13 09:05:36,073 [salt.state       ][INFO    ][25950] Completed state [glusterfs-server] at time 09:05:36.073185 duration_in_ms=53.418
2017-11-13 09:05:36,078 [salt.state       ][INFO    ][25950] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:05:36.077516
2017-11-13 09:05:36,079 [salt.state       ][INFO    ][25950] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:05:36,089 [salt.state       ][INFO    ][25950] File changed:
New file
2017-11-13 09:05:36,089 [salt.state       ][INFO    ][25950] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:05:36.088688 duration_in_ms=11.173
2017-11-13 09:05:36,089 [salt.state       ][INFO    ][25950] Running state [/srv/glusterfs/glance] at time 09:05:36.088885
2017-11-13 09:05:36,089 [salt.state       ][INFO    ][25950] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:05:36,090 [salt.state       ][INFO    ][25950] {'/srv/glusterfs/glance': 'New Dir'}
2017-11-13 09:05:36,090 [salt.state       ][INFO    ][25950] Completed state [/srv/glusterfs/glance] at time 09:05:36.090453 duration_in_ms=1.294
2017-11-13 09:05:36,091 [salt.state       ][INFO    ][25950] Running state [/srv/glusterfs/keystone-keys] at time 09:05:36.090648
2017-11-13 09:05:36,091 [salt.state       ][INFO    ][25950] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:05:36,091 [salt.state       ][INFO    ][25950] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-11-13 09:05:36,092 [salt.state       ][INFO    ][25950] Completed state [/srv/glusterfs/keystone-keys] at time 09:05:36.091512 duration_in_ms=0.864
2017-11-13 09:05:36,092 [salt.state       ][INFO    ][25950] Running state [/srv/glusterfs/nova_instances] at time 09:05:36.091704
2017-11-13 09:05:36,092 [salt.state       ][INFO    ][25950] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:05:36,092 [salt.state       ][INFO    ][25950] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-11-13 09:05:36,093 [salt.state       ][INFO    ][25950] Completed state [/srv/glusterfs/nova_instances] at time 09:05:36.092790 duration_in_ms=1.085
2017-11-13 09:05:36,093 [salt.state       ][INFO    ][25950] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:05:36.092993
2017-11-13 09:05:36,093 [salt.state       ][INFO    ][25950] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:05:36,094 [salt.state       ][INFO    ][25950] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-11-13 09:05:36,094 [salt.state       ][INFO    ][25950] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:05:36.093788 duration_in_ms=0.794
2017-11-13 09:05:36,096 [salt.minion      ][INFO    ][25950] Returning information for job: 20171113090514215274
2017-11-13 09:05:37,140 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command test.ping with jid 20171113090537130608
2017-11-13 09:05:37,163 [salt.minion      ][INFO    ][30716] Starting a new job with PID 30716
2017-11-13 09:05:37,208 [salt.minion      ][INFO    ][30716] Returning information for job: 20171113090537130608
2017-11-13 09:05:37,329 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command state.sls with jid 20171113090537321672
2017-11-13 09:05:37,340 [salt.minion      ][INFO    ][30721] Starting a new job with PID 30721
2017-11-13 09:05:39,582 [salt.state       ][INFO    ][30721] Loading fresh modules for state activity
2017-11-13 09:05:39,616 [salt.fileclient  ][INFO    ][30721] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-13 09:05:39,664 [salt.fileclient  ][INFO    ][30721] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:05:39,752 [salt.fileclient  ][INFO    ][30721] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:05:39,781 [salt.fileclient  ][INFO    ][30721] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:05:40,136 [salt.state       ][INFO    ][30721] Running state [attr] at time 09:05:40.135730
2017-11-13 09:05:40,136 [salt.state       ][INFO    ][30721] Executing state pkg.installed for attr
2017-11-13 09:05:40,137 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:05:40,471 [salt.state       ][INFO    ][30721] Package attr is already installed
2017-11-13 09:05:40,471 [salt.state       ][INFO    ][30721] Completed state [attr] at time 09:05:40.471139 duration_in_ms=335.408
2017-11-13 09:05:40,471 [salt.state       ][INFO    ][30721] Running state [glusterfs-server] at time 09:05:40.471406
2017-11-13 09:05:40,472 [salt.state       ][INFO    ][30721] Executing state pkg.installed for glusterfs-server
2017-11-13 09:05:40,475 [salt.state       ][INFO    ][30721] Package glusterfs-server is already installed
2017-11-13 09:05:40,475 [salt.state       ][INFO    ][30721] Completed state [glusterfs-server] at time 09:05:40.475398 duration_in_ms=3.992
2017-11-13 09:05:40,476 [salt.state       ][INFO    ][30721] Running state [bc] at time 09:05:40.475592
2017-11-13 09:05:40,476 [salt.state       ][INFO    ][30721] Executing state pkg.installed for bc
2017-11-13 09:05:40,479 [salt.state       ][INFO    ][30721] Package bc is already installed
2017-11-13 09:05:40,480 [salt.state       ][INFO    ][30721] Completed state [bc] at time 09:05:40.479540 duration_in_ms=3.948
2017-11-13 09:05:40,481 [salt.state       ][INFO    ][30721] Running state [glusterfs-server] at time 09:05:40.480515
2017-11-13 09:05:40,481 [salt.state       ][INFO    ][30721] Executing state service.running for glusterfs-server
2017-11-13 09:05:40,481 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:05:40,498 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-11-13 09:05:40,588 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:05:40,617 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:05:40,631 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:05:40,651 [salt.state       ][INFO    ][30721] The service glusterfs-server is already running
2017-11-13 09:05:40,652 [salt.state       ][INFO    ][30721] Completed state [glusterfs-server] at time 09:05:40.651745 duration_in_ms=171.228
2017-11-13 09:05:40,655 [salt.state       ][INFO    ][30721] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:05:40.655195
2017-11-13 09:05:40,656 [salt.state       ][INFO    ][30721] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:05:40,660 [salt.state       ][INFO    ][30721] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 09:05:40,661 [salt.state       ][INFO    ][30721] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:05:40.660745 duration_in_ms=5.55
2017-11-13 09:05:40,661 [salt.state       ][INFO    ][30721] Running state [/srv/glusterfs/glance] at time 09:05:40.661097
2017-11-13 09:05:40,661 [salt.state       ][INFO    ][30721] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:05:40,663 [salt.state       ][INFO    ][30721] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 09:05:40,663 [salt.state       ][INFO    ][30721] Completed state [/srv/glusterfs/glance] at time 09:05:40.663328 duration_in_ms=2.231
2017-11-13 09:05:40,664 [salt.state       ][INFO    ][30721] Running state [/srv/glusterfs/keystone-keys] at time 09:05:40.663656
2017-11-13 09:05:40,664 [salt.state       ][INFO    ][30721] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:05:40,665 [salt.state       ][INFO    ][30721] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 09:05:40,665 [salt.state       ][INFO    ][30721] Completed state [/srv/glusterfs/keystone-keys] at time 09:05:40.664950 duration_in_ms=1.293
2017-11-13 09:05:40,665 [salt.state       ][INFO    ][30721] Running state [/srv/glusterfs/nova_instances] at time 09:05:40.665264
2017-11-13 09:05:40,666 [salt.state       ][INFO    ][30721] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:05:40,667 [salt.state       ][INFO    ][30721] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 09:05:40,667 [salt.state       ][INFO    ][30721] Completed state [/srv/glusterfs/nova_instances] at time 09:05:40.667433 duration_in_ms=2.169
2017-11-13 09:05:40,668 [salt.state       ][INFO    ][30721] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:05:40.667617
2017-11-13 09:05:40,668 [salt.state       ][INFO    ][30721] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:05:40,668 [salt.state       ][INFO    ][30721] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 09:05:40,668 [salt.state       ][INFO    ][30721] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:05:40.668351 duration_in_ms=0.734
2017-11-13 09:05:40,747 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command ['git', '--version'] in directory '/root'
2017-11-13 09:05:40,842 [salt.state       ][INFO    ][30721] Running state [sleep 5] at time 09:05:40.842111
2017-11-13 09:05:40,842 [salt.state       ][INFO    ][30721] Executing state cmd.wait for sleep 5
2017-11-13 09:05:40,843 [salt.state       ][INFO    ][30721] No changes made for sleep 5
2017-11-13 09:05:40,843 [salt.state       ][INFO    ][30721] Completed state [sleep 5] at time 09:05:40.842857 duration_in_ms=0.746
2017-11-13 09:05:40,843 [salt.state       ][INFO    ][30721] Running state [192.168.10.143] at time 09:05:40.843417
2017-11-13 09:05:40,844 [salt.state       ][INFO    ][30721] Executing state glusterfs.peered for 192.168.10.143
2017-11-13 09:05:40,844 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:40,856 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:40,933 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:40,952 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,056 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,081 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,165 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,185 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,266 [salt.state       ][INFO    ][30721] {'new': {'192.168.10.143': []}, 'old': None}
2017-11-13 09:05:41,267 [salt.state       ][INFO    ][30721] Completed state [192.168.10.143] at time 09:05:41.266639 duration_in_ms=423.22
2017-11-13 09:05:41,275 [salt.state       ][INFO    ][30721] Running state [192.168.10.142] at time 09:05:41.275313
2017-11-13 09:05:41,276 [salt.state       ][INFO    ][30721] Executing state glusterfs.peered for 192.168.10.142
2017-11-13 09:05:41,277 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,298 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,385 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,404 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,487 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,505 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,586 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,600 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,678 [salt.state       ][INFO    ][30721] Peering with localhost is not needed
2017-11-13 09:05:41,679 [salt.state       ][INFO    ][30721] Completed state [192.168.10.142] at time 09:05:41.679127 duration_in_ms=403.813
2017-11-13 09:05:41,689 [salt.state       ][INFO    ][30721] Running state [192.168.10.141] at time 09:05:41.688673
2017-11-13 09:05:41,689 [salt.state       ][INFO    ][30721] Executing state glusterfs.peered for 192.168.10.141
2017-11-13 09:05:41,690 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,706 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,787 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,804 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,899 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:41,916 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:41,998 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:42,015 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:42,097 [salt.state       ][INFO    ][30721] {'new': {'192.168.10.143': [], '192.168.10.141': []}, 'old': {'192.168.10.143': []}}
2017-11-13 09:05:42,099 [salt.state       ][INFO    ][30721] Completed state [192.168.10.141] at time 09:05:42.097765 duration_in_ms=409.089
2017-11-13 09:05:42,102 [salt.state       ][INFO    ][30721] Running state [glance] at time 09:05:42.101637
2017-11-13 09:05:42,102 [salt.state       ][INFO    ][30721] Executing state glusterfs.created for glance
2017-11-13 09:05:42,103 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:42,121 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:42,205 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:42,223 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:42,341 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:42,360 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:42,850 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:42,868 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:42,952 [salt.state       ][INFO    ][30721] {'new': ['glance'], 'old': []}
2017-11-13 09:05:42,953 [salt.state       ][INFO    ][30721] Completed state [glance] at time 09:05:42.952721 duration_in_ms=851.082
2017-11-13 09:05:42,965 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:05:42.964944
2017-11-13 09:05:42,965 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:05:42,966 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:05:43,047 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:05:43,195 [salt.state       ][INFO    ][30721] {'pid': 30943, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:43,196 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:05:43.196114 duration_in_ms=231.168
2017-11-13 09:05:43,198 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:05:43.197651
2017-11-13 09:05:43,199 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-13 09:05:43,200 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:05:43,284 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'glance' 'network.remote-dio' 'True'' in directory '/root'
2017-11-13 09:05:43,449 [salt.state       ][INFO    ][30721] {'pid': 30961, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:43,450 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:05:43.449642 duration_in_ms=251.99
2017-11-13 09:05:43,452 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:05:43.451817
2017-11-13 09:05:43,452 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-13 09:05:43,453 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:05:43,544 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'glance' 'nfs.disable' 'True'' in directory '/root'
2017-11-13 09:05:45,709 [salt.state       ][INFO    ][30721] {'pid': 30978, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:45,710 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:05:45.709621 duration_in_ms=2257.802
2017-11-13 09:05:45,712 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:05:45.711464
2017-11-13 09:05:45,712 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:05:45,713 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:05:45,805 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:05:45,954 [salt.state       ][INFO    ][30721] {'pid': 30995, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:45,955 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:05:45.955145 duration_in_ms=243.681
2017-11-13 09:05:45,956 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:05:45.956398
2017-11-13 09:05:45,957 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-13 09:05:45,959 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:05:46,041 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'glance' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-13 09:05:46,188 [salt.state       ][INFO    ][30721] {'pid': 31012, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:46,189 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:05:46.188443 duration_in_ms=232.045
2017-11-13 09:05:46,192 [salt.state       ][INFO    ][30721] Running state [glance] at time 09:05:46.191493
2017-11-13 09:05:46,192 [salt.state       ][INFO    ][30721] Executing state glusterfs.started for glance
2017-11-13 09:05:46,193 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:46,211 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:46,287 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:46,305 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:46,397 [salt.state       ][INFO    ][30721] Volume glance is already started
2017-11-13 09:05:46,398 [salt.state       ][INFO    ][30721] Completed state [glance] at time 09:05:46.397543 duration_in_ms=206.049
2017-11-13 09:05:46,400 [salt.state       ][INFO    ][30721] Running state [keystone-keys] at time 09:05:46.399884
2017-11-13 09:05:46,400 [salt.state       ][INFO    ][30721] Executing state glusterfs.created for keystone-keys
2017-11-13 09:05:46,401 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:46,419 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:46,495 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:46,513 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:46,630 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:46,651 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:47,404 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113090547389238
2017-11-13 09:05:47,429 [salt.minion      ][INFO    ][31092] Starting a new job with PID 31092
2017-11-13 09:05:47,446 [salt.minion      ][INFO    ][31092] Returning information for job: 20171113090547389238
2017-11-13 09:05:48,859 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:48,878 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:48,958 [salt.state       ][INFO    ][30721] {'new': ['glance', 'keystone-keys'], 'old': ['glance']}
2017-11-13 09:05:48,959 [salt.state       ][INFO    ][30721] Completed state [keystone-keys] at time 09:05:48.958688 duration_in_ms=2558.802
2017-11-13 09:05:48,971 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:05:48.970848
2017-11-13 09:05:48,971 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-13 09:05:48,972 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:05:49,056 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-11-13 09:05:49,224 [salt.state       ][INFO    ][30721] {'pid': 31132, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:49,225 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:05:49.224521 duration_in_ms=253.671
2017-11-13 09:05:49,226 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:05:49.225851
2017-11-13 09:05:49,227 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:05:49,228 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:05:49,313 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:05:49,469 [salt.state       ][INFO    ][30721] {'pid': 31149, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:49,469 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:05:49.469278 duration_in_ms=243.425
2017-11-13 09:05:49,471 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:05:49.470998
2017-11-13 09:05:49,472 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:05:49,472 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:05:49,553 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:05:49,716 [salt.state       ][INFO    ][30721] {'pid': 31168, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:49,717 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:05:49.716697 duration_in_ms=245.698
2017-11-13 09:05:49,718 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:05:49.717816
2017-11-13 09:05:49,719 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-13 09:05:49,720 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:05:49,803 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-keys' 'nfs.disable' 'True'' in directory '/root'
2017-11-13 09:05:51,969 [salt.state       ][INFO    ][30721] {'pid': 31185, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:51,970 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:05:51.970328 duration_in_ms=2252.512
2017-11-13 09:05:51,972 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:05:51.971605
2017-11-13 09:05:51,972 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:05:51,973 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:05:52,065 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-13 09:05:52,218 [salt.state       ][INFO    ][30721] {'pid': 31202, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:52,221 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:05:52.220366 duration_in_ms=248.761
2017-11-13 09:05:52,224 [salt.state       ][INFO    ][30721] Running state [keystone-keys] at time 09:05:52.223537
2017-11-13 09:05:52,224 [salt.state       ][INFO    ][30721] Executing state glusterfs.started for keystone-keys
2017-11-13 09:05:52,225 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:52,241 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:52,324 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:52,342 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:52,444 [salt.state       ][INFO    ][30721] Volume keystone-keys is already started
2017-11-13 09:05:52,445 [salt.state       ][INFO    ][30721] Completed state [keystone-keys] at time 09:05:52.444383 duration_in_ms=220.845
2017-11-13 09:05:52,449 [salt.state       ][INFO    ][30721] Running state [nova_instances] at time 09:05:52.448936
2017-11-13 09:05:52,450 [salt.state       ][INFO    ][30721] Executing state glusterfs.created for nova_instances
2017-11-13 09:05:52,451 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:52,464 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:52,549 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:52,566 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:52,688 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:52,706 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:54,910 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:54,937 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:55,023 [salt.state       ][INFO    ][30721] {'new': ['glance', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys']}
2017-11-13 09:05:55,024 [salt.state       ][INFO    ][30721] Completed state [nova_instances] at time 09:05:55.023584 duration_in_ms=2574.647
2017-11-13 09:05:55,033 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:05:55.033195
2017-11-13 09:05:55,034 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:05:55,036 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:05:55,115 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:05:55,271 [salt.state       ][INFO    ][30721] {'pid': 31316, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:55,272 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:05:55.272014 duration_in_ms=238.817
2017-11-13 09:05:55,273 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:05:55.273362
2017-11-13 09:05:55,275 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-13 09:05:55,276 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-13 09:05:55,364 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'' in directory '/root'
2017-11-13 09:05:55,529 [salt.state       ][INFO    ][30721] {'pid': 31334, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:55,530 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:05:55.529795 duration_in_ms=256.433
2017-11-13 09:05:55,531 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:05:55.531159
2017-11-13 09:05:55,532 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-13 09:05:55,533 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-13 09:05:55,620 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'nova_instances' 'network.remote-dio' 'On'' in directory '/root'
2017-11-13 09:05:55,778 [salt.state       ][INFO    ][30721] {'pid': 31351, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:55,779 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:05:55.778611 duration_in_ms=247.451
2017-11-13 09:05:55,781 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:05:55.780512
2017-11-13 09:05:55,781 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-13 09:05:55,783 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-13 09:05:55,876 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'nova_instances' 'nfs.disable' 'On'' in directory '/root'
2017-11-13 09:05:57,590 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command saltutil.find_job with jid 20171113090557572750
2017-11-13 09:05:57,619 [salt.minion      ][INFO    ][31378] Starting a new job with PID 31378
2017-11-13 09:05:57,641 [salt.minion      ][INFO    ][31378] Returning information for job: 20171113090557572750
2017-11-13 09:05:58,049 [salt.state       ][INFO    ][30721] {'pid': 31368, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:58,050 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:05:58.049285 duration_in_ms=2268.772
2017-11-13 09:05:58,051 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:05:58.051114
2017-11-13 09:05:58,052 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:05:58,054 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:05:58,140 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:05:58,290 [salt.state       ][INFO    ][30721] {'pid': 31391, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:05:58,291 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:05:58.291116 duration_in_ms=240.001
2017-11-13 09:05:58,295 [salt.state       ][INFO    ][30721] Running state [nova_instances] at time 09:05:58.295235
2017-11-13 09:05:58,296 [salt.state       ][INFO    ][30721] Executing state glusterfs.started for nova_instances
2017-11-13 09:05:58,297 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:58,321 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:58,395 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:58,416 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:58,504 [salt.state       ][INFO    ][30721] Volume nova_instances is already started
2017-11-13 09:05:58,504 [salt.state       ][INFO    ][30721] Completed state [nova_instances] at time 09:05:58.503994 duration_in_ms=208.758
2017-11-13 09:05:58,507 [salt.state       ][INFO    ][30721] Running state [keystone-credential-keys] at time 09:05:58.507279
2017-11-13 09:05:58,508 [salt.state       ][INFO    ][30721] Executing state glusterfs.created for keystone-credential-keys
2017-11-13 09:05:58,509 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:58,524 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:58,606 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:58,626 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:05:58,750 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:05:58,771 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:00,989 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:01,017 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:01,104 [salt.state       ][INFO    ][30721] {'new': ['glance', 'keystone-credential-keys', 'keystone-keys', 'nova_instances'], 'old': ['glance', 'keystone-keys', 'nova_instances']}
2017-11-13 09:06:01,104 [salt.state       ][INFO    ][30721] Completed state [keystone-credential-keys] at time 09:06:01.104009 duration_in_ms=2596.729
2017-11-13 09:06:01,115 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:06:01.115243
2017-11-13 09:06:01,116 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:06:01,116 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:06:01,196 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'' in directory '/root'
2017-11-13 09:06:01,369 [salt.state       ][INFO    ][30721] {'pid': 31511, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:06:01,371 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:06:01.369809 duration_in_ms=254.565
2017-11-13 09:06:01,372 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:01.372307
2017-11-13 09:06:01,373 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:06:01,374 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:01,460 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:06:01,616 [salt.state       ][INFO    ][30721] {'pid': 31528, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:06:01,617 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:06:01.616861 duration_in_ms=244.553
2017-11-13 09:06:01,620 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:06:01.619559
2017-11-13 09:06:01,620 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-13 09:06:01,621 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:06:01,706 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'' in directory '/root'
2017-11-13 09:06:03,885 [salt.state       ][INFO    ][30721] {'pid': 31546, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:06:03,885 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:06:03.885157 duration_in_ms=2265.597
2017-11-13 09:06:03,888 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:03.887748
2017-11-13 09:06:03,888 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:06:03,889 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:06:03,974 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'' in directory '/root'
2017-11-13 09:06:04,132 [salt.state       ][INFO    ][30721] {'pid': 31563, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:06:04,133 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:06:04.132556 duration_in_ms=244.808
2017-11-13 09:06:04,134 [salt.state       ][INFO    ][30721] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:06:04.133593
2017-11-13 09:06:04,134 [salt.state       ][INFO    ][30721] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-13 09:06:04,136 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:06:04,231 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'' in directory '/root'
2017-11-13 09:06:04,401 [salt.state       ][INFO    ][30721] {'pid': 31580, 'retcode': 0, 'stderr': '', 'stdout': 'volume set: success'}
2017-11-13 09:06:04,402 [salt.state       ][INFO    ][30721] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:06:04.401603 duration_in_ms=268.009
2017-11-13 09:06:04,408 [salt.state       ][INFO    ][30721] Running state [keystone-credential-keys] at time 09:06:04.408089
2017-11-13 09:06:04,409 [salt.state       ][INFO    ][30721] Executing state glusterfs.started for keystone-credential-keys
2017-11-13 09:06:04,410 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:04,434 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:04,511 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:06:04,529 [salt.loaded.int.module.cmdmod][INFO    ][30721] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:06:04,625 [salt.state       ][INFO    ][30721] Volume keystone-credential-keys is already started
2017-11-13 09:06:04,628 [salt.state       ][INFO    ][30721] Completed state [keystone-credential-keys] at time 09:06:04.628235 duration_in_ms=220.146
2017-11-13 09:06:04,633 [salt.minion      ][INFO    ][30721] Returning information for job: 20171113090537321672
2017-11-13 09:06:15,941 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command test.ping with jid 20171113090615931712
2017-11-13 09:06:15,965 [salt.minion      ][INFO    ][31611] Starting a new job with PID 31611
2017-11-13 09:06:16,014 [salt.minion      ][INFO    ][31611] Returning information for job: 20171113090615931712
2017-11-13 09:06:16,137 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command cmd.run with jid 20171113090616129076
2017-11-13 09:06:16,157 [salt.minion      ][INFO    ][31616] Starting a new job with PID 31616
2017-11-13 09:06:16,165 [salt.loaded.int.module.cmdmod][INFO    ][31616] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-13 09:06:16,347 [salt.minion      ][INFO    ][31616] Returning information for job: 20171113090616129076
2017-11-13 09:46:24,031 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command test.ping with jid 20171113094624020784
2017-11-13 09:46:24,057 [salt.minion      ][INFO    ][32180] Starting a new job with PID 32180
2017-11-13 09:46:24,120 [salt.minion      ][INFO    ][32180] Returning information for job: 20171113094624020784
2017-11-13 09:46:25,225 [salt.utils.schedule][INFO    ][23737] Running scheduled job: __mine_interval
2017-11-13 09:46:43,798 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command state.sls with jid 20171113094643786180
2017-11-13 09:46:43,823 [salt.minion      ][INFO    ][32205] Starting a new job with PID 32205
2017-11-13 09:46:44,467 [salt.state       ][INFO    ][32205] Loading fresh modules for state activity
2017-11-13 09:46:44,507 [salt.fileclient  ][INFO    ][32205] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-11-13 09:46:44,533 [salt.fileclient  ][INFO    ][32205] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-11-13 09:46:44,571 [salt.fileclient  ][INFO    ][32205] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 09:46:44,973 [salt.state       ][INFO    ][32205] Running state [lsof] at time 09:46:44.972749
2017-11-13 09:46:44,973 [salt.state       ][INFO    ][32205] Executing state pkg.installed for lsof
2017-11-13 09:46:44,974 [salt.loaded.int.module.cmdmod][INFO    ][32205] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:46:45,304 [salt.state       ][INFO    ][32205] Package lsof is already installed
2017-11-13 09:46:45,305 [salt.state       ][INFO    ][32205] Completed state [lsof] at time 09:46:45.304527 duration_in_ms=331.777
2017-11-13 09:46:45,305 [salt.state       ][INFO    ][32205] Running state [keepalived] at time 09:46:45.304806
2017-11-13 09:46:45,305 [salt.state       ][INFO    ][32205] Executing state pkg.installed for keepalived
2017-11-13 09:46:45,310 [salt.state       ][INFO    ][32205] Package keepalived is already installed
2017-11-13 09:46:45,310 [salt.state       ][INFO    ][32205] Completed state [keepalived] at time 09:46:45.310400 duration_in_ms=5.594
2017-11-13 09:46:45,312 [salt.state       ][INFO    ][32205] Running state [/etc/keepalived/keepalived.conf] at time 09:46:45.312005
2017-11-13 09:46:45,312 [salt.state       ][INFO    ][32205] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-11-13 09:46:45,333 [salt.fileclient  ][INFO    ][32205] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-11-13 09:46:45,385 [salt.fileclient  ][INFO    ][32205] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-11-13 09:46:45,393 [salt.state       ][INFO    ][32205] File /etc/keepalived/keepalived.conf is in the correct state
2017-11-13 09:46:45,394 [salt.state       ][INFO    ][32205] Completed state [/etc/keepalived/keepalived.conf] at time 09:46:45.393601 duration_in_ms=81.595
2017-11-13 09:46:45,395 [salt.state       ][INFO    ][32205] Running state [keepalived] at time 09:46:45.395409
2017-11-13 09:46:45,396 [salt.state       ][INFO    ][32205] Executing state service.running for keepalived
2017-11-13 09:46:45,396 [salt.loaded.int.module.cmdmod][INFO    ][32205] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-11-13 09:46:45,432 [salt.loaded.int.module.cmdmod][INFO    ][32205] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-11-13 09:46:45,447 [salt.loaded.int.module.cmdmod][INFO    ][32205] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-11-13 09:46:45,461 [salt.state       ][INFO    ][32205] The service keepalived is already running
2017-11-13 09:46:45,462 [salt.state       ][INFO    ][32205] Completed state [keepalived] at time 09:46:45.461466 duration_in_ms=66.056
2017-11-13 09:46:45,464 [salt.minion      ][INFO    ][32205] Returning information for job: 20171113094643786180
2017-11-13 09:47:01,745 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command pillar.get with jid 20171113094701736532
2017-11-13 09:47:01,769 [salt.minion      ][INFO    ][32231] Starting a new job with PID 32231
2017-11-13 09:47:01,777 [salt.minion      ][INFO    ][32231] Returning information for job: 20171113094701736532
2017-11-13 09:47:51,889 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command state.sls with jid 20171113094751880184
2017-11-13 09:47:51,915 [salt.minion      ][INFO    ][32238] Starting a new job with PID 32238
2017-11-13 09:47:54,541 [salt.state       ][INFO    ][32238] Loading fresh modules for state activity
2017-11-13 09:47:54,583 [salt.fileclient  ][INFO    ][32238] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:47:54,617 [salt.fileclient  ][INFO    ][32238] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:47:55,019 [salt.state       ][INFO    ][32238] Running state [attr] at time 09:47:55.019265
2017-11-13 09:47:55,020 [salt.state       ][INFO    ][32238] Executing state pkg.installed for attr
2017-11-13 09:47:55,020 [salt.loaded.int.module.cmdmod][INFO    ][32238] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:47:55,364 [salt.state       ][INFO    ][32238] Package attr is already installed
2017-11-13 09:47:55,364 [salt.state       ][INFO    ][32238] Completed state [attr] at time 09:47:55.363988 duration_in_ms=344.724
2017-11-13 09:47:55,365 [salt.state       ][INFO    ][32238] Running state [glusterfs-server] at time 09:47:55.364877
2017-11-13 09:47:55,365 [salt.state       ][INFO    ][32238] Executing state pkg.installed for glusterfs-server
2017-11-13 09:47:55,369 [salt.state       ][INFO    ][32238] Package glusterfs-server is already installed
2017-11-13 09:47:55,369 [salt.state       ][INFO    ][32238] Completed state [glusterfs-server] at time 09:47:55.369014 duration_in_ms=4.136
2017-11-13 09:47:55,369 [salt.state       ][INFO    ][32238] Running state [bc] at time 09:47:55.369214
2017-11-13 09:47:55,369 [salt.state       ][INFO    ][32238] Executing state pkg.installed for bc
2017-11-13 09:47:55,373 [salt.state       ][INFO    ][32238] Package bc is already installed
2017-11-13 09:47:55,373 [salt.state       ][INFO    ][32238] Completed state [bc] at time 09:47:55.373236 duration_in_ms=4.021
2017-11-13 09:47:55,375 [salt.state       ][INFO    ][32238] Running state [glusterfs-server] at time 09:47:55.375287
2017-11-13 09:47:55,376 [salt.state       ][INFO    ][32238] Executing state service.running for glusterfs-server
2017-11-13 09:47:55,376 [salt.loaded.int.module.cmdmod][INFO    ][32238] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:47:55,415 [salt.loaded.int.module.cmdmod][INFO    ][32238] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:47:55,435 [salt.loaded.int.module.cmdmod][INFO    ][32238] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:47:55,461 [salt.state       ][INFO    ][32238] The service glusterfs-server is already running
2017-11-13 09:47:55,461 [salt.state       ][INFO    ][32238] Completed state [glusterfs-server] at time 09:47:55.461224 duration_in_ms=85.936
2017-11-13 09:47:55,465 [salt.state       ][INFO    ][32238] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:47:55.465182
2017-11-13 09:47:55,469 [salt.state       ][INFO    ][32238] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:47:55,473 [salt.state       ][INFO    ][32238] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 09:47:55,474 [salt.state       ][INFO    ][32238] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:47:55.473572 duration_in_ms=8.391
2017-11-13 09:47:55,474 [salt.state       ][INFO    ][32238] Running state [/srv/glusterfs/glance] at time 09:47:55.473877
2017-11-13 09:47:55,474 [salt.state       ][INFO    ][32238] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:47:55,475 [salt.state       ][INFO    ][32238] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 09:47:55,475 [salt.state       ][INFO    ][32238] Completed state [/srv/glusterfs/glance] at time 09:47:55.475341 duration_in_ms=1.464
2017-11-13 09:47:55,476 [salt.state       ][INFO    ][32238] Running state [/srv/glusterfs/keystone-keys] at time 09:47:55.475606
2017-11-13 09:47:55,476 [salt.state       ][INFO    ][32238] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:47:55,477 [salt.state       ][INFO    ][32238] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 09:47:55,477 [salt.state       ][INFO    ][32238] Completed state [/srv/glusterfs/keystone-keys] at time 09:47:55.477321 duration_in_ms=1.715
2017-11-13 09:47:55,480 [salt.state       ][INFO    ][32238] Running state [/srv/glusterfs/nova_instances] at time 09:47:55.479296
2017-11-13 09:47:55,480 [salt.state       ][INFO    ][32238] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:47:55,480 [salt.state       ][INFO    ][32238] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 09:47:55,481 [salt.state       ][INFO    ][32238] Completed state [/srv/glusterfs/nova_instances] at time 09:47:55.480610 duration_in_ms=1.314
2017-11-13 09:47:55,481 [salt.state       ][INFO    ][32238] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:47:55.480769
2017-11-13 09:47:55,481 [salt.state       ][INFO    ][32238] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:47:55,481 [salt.state       ][INFO    ][32238] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 09:47:55,482 [salt.state       ][INFO    ][32238] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:47:55.481472 duration_in_ms=0.703
2017-11-13 09:47:55,484 [salt.minion      ][INFO    ][32238] Returning information for job: 20171113094751880184
2017-11-13 09:47:56,520 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command test.ping with jid 20171113094756513664
2017-11-13 09:47:56,543 [salt.minion      ][INFO    ][32262] Starting a new job with PID 32262
2017-11-13 09:47:56,596 [salt.minion      ][INFO    ][32262] Returning information for job: 20171113094756513664
2017-11-13 09:47:56,721 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command state.sls with jid 20171113094756713717
2017-11-13 09:47:56,742 [salt.minion      ][INFO    ][32267] Starting a new job with PID 32267
2017-11-13 09:47:57,335 [salt.state       ][INFO    ][32267] Loading fresh modules for state activity
2017-11-13 09:47:57,368 [salt.fileclient  ][INFO    ][32267] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-11-13 09:47:57,421 [salt.fileclient  ][INFO    ][32267] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:47:57,521 [salt.fileclient  ][INFO    ][32267] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-11-13 09:47:57,554 [salt.fileclient  ][INFO    ][32267] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-11-13 09:47:57,943 [salt.state       ][INFO    ][32267] Running state [attr] at time 09:47:57.943087
2017-11-13 09:47:57,944 [salt.state       ][INFO    ][32267] Executing state pkg.installed for attr
2017-11-13 09:47:57,944 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-11-13 09:47:58,288 [salt.state       ][INFO    ][32267] Package attr is already installed
2017-11-13 09:47:58,288 [salt.state       ][INFO    ][32267] Completed state [attr] at time 09:47:58.288310 duration_in_ms=345.223
2017-11-13 09:47:58,289 [salt.state       ][INFO    ][32267] Running state [glusterfs-server] at time 09:47:58.288843
2017-11-13 09:47:58,289 [salt.state       ][INFO    ][32267] Executing state pkg.installed for glusterfs-server
2017-11-13 09:47:58,295 [salt.state       ][INFO    ][32267] Package glusterfs-server is already installed
2017-11-13 09:47:58,295 [salt.state       ][INFO    ][32267] Completed state [glusterfs-server] at time 09:47:58.294856 duration_in_ms=6.013
2017-11-13 09:47:58,295 [salt.state       ][INFO    ][32267] Running state [bc] at time 09:47:58.295055
2017-11-13 09:47:58,295 [salt.state       ][INFO    ][32267] Executing state pkg.installed for bc
2017-11-13 09:47:58,299 [salt.state       ][INFO    ][32267] Package bc is already installed
2017-11-13 09:47:58,299 [salt.state       ][INFO    ][32267] Completed state [bc] at time 09:47:58.298950 duration_in_ms=3.895
2017-11-13 09:47:58,300 [salt.state       ][INFO    ][32267] Running state [glusterfs-server] at time 09:47:58.299970
2017-11-13 09:47:58,300 [salt.state       ][INFO    ][32267] Executing state service.running for glusterfs-server
2017-11-13 09:47:58,301 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-11-13 09:47:58,335 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:47:58,353 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-11-13 09:47:58,374 [salt.state       ][INFO    ][32267] The service glusterfs-server is already running
2017-11-13 09:47:58,375 [salt.state       ][INFO    ][32267] Completed state [glusterfs-server] at time 09:47:58.374720 duration_in_ms=74.749
2017-11-13 09:47:58,381 [salt.state       ][INFO    ][32267] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:47:58.380461
2017-11-13 09:47:58,381 [salt.state       ][INFO    ][32267] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-11-13 09:47:58,385 [salt.state       ][INFO    ][32267] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-11-13 09:47:58,386 [salt.state       ][INFO    ][32267] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 09:47:58.385630 duration_in_ms=5.168
2017-11-13 09:47:58,387 [salt.state       ][INFO    ][32267] Running state [/srv/glusterfs/glance] at time 09:47:58.386866
2017-11-13 09:47:58,387 [salt.state       ][INFO    ][32267] Executing state file.directory for /srv/glusterfs/glance
2017-11-13 09:47:58,388 [salt.state       ][INFO    ][32267] Directory /srv/glusterfs/glance is in the correct state
2017-11-13 09:47:58,389 [salt.state       ][INFO    ][32267] Completed state [/srv/glusterfs/glance] at time 09:47:58.388793 duration_in_ms=1.927
2017-11-13 09:47:58,389 [salt.state       ][INFO    ][32267] Running state [/srv/glusterfs/keystone-keys] at time 09:47:58.389089
2017-11-13 09:47:58,389 [salt.state       ][INFO    ][32267] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-11-13 09:47:58,393 [salt.state       ][INFO    ][32267] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-11-13 09:47:58,393 [salt.state       ][INFO    ][32267] Completed state [/srv/glusterfs/keystone-keys] at time 09:47:58.393160 duration_in_ms=4.071
2017-11-13 09:47:58,393 [salt.state       ][INFO    ][32267] Running state [/srv/glusterfs/nova_instances] at time 09:47:58.393357
2017-11-13 09:47:58,394 [salt.state       ][INFO    ][32267] Executing state file.directory for /srv/glusterfs/nova_instances
2017-11-13 09:47:58,395 [salt.state       ][INFO    ][32267] Directory /srv/glusterfs/nova_instances is in the correct state
2017-11-13 09:47:58,395 [salt.state       ][INFO    ][32267] Completed state [/srv/glusterfs/nova_instances] at time 09:47:58.394721 duration_in_ms=1.363
2017-11-13 09:47:58,395 [salt.state       ][INFO    ][32267] Running state [/srv/glusterfs/keystone-credential-keys] at time 09:47:58.394909
2017-11-13 09:47:58,395 [salt.state       ][INFO    ][32267] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-11-13 09:47:58,396 [salt.state       ][INFO    ][32267] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-11-13 09:47:58,396 [salt.state       ][INFO    ][32267] Completed state [/srv/glusterfs/keystone-credential-keys] at time 09:47:58.395655 duration_in_ms=0.746
2017-11-13 09:47:58,474 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command ['git', '--version'] in directory '/root'
2017-11-13 09:47:58,623 [salt.state       ][INFO    ][32267] Running state [sleep 5] at time 09:47:58.623354
2017-11-13 09:47:58,624 [salt.state       ][INFO    ][32267] Executing state cmd.wait for sleep 5
2017-11-13 09:47:58,624 [salt.state       ][INFO    ][32267] No changes made for sleep 5
2017-11-13 09:47:58,624 [salt.state       ][INFO    ][32267] Completed state [sleep 5] at time 09:47:58.624386 duration_in_ms=1.032
2017-11-13 09:47:58,625 [salt.state       ][INFO    ][32267] Running state [192.168.10.143] at time 09:47:58.624941
2017-11-13 09:47:58,625 [salt.state       ][INFO    ][32267] Executing state glusterfs.peered for 192.168.10.143
2017-11-13 09:47:58,626 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:58,641 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:58,729 [salt.state       ][INFO    ][32267] Host 192.168.10.143 already peered
2017-11-13 09:47:58,732 [salt.state       ][INFO    ][32267] Completed state [192.168.10.143] at time 09:47:58.732051 duration_in_ms=107.11
2017-11-13 09:47:58,741 [salt.state       ][INFO    ][32267] Running state [192.168.10.142] at time 09:47:58.741291
2017-11-13 09:47:58,742 [salt.state       ][INFO    ][32267] Executing state glusterfs.peered for 192.168.10.142
2017-11-13 09:47:58,745 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:58,762 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:58,845 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:58,867 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:58,962 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:58,979 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:59,068 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:59,089 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:59,170 [salt.state       ][INFO    ][32267] Peering with localhost is not needed
2017-11-13 09:47:59,171 [salt.state       ][INFO    ][32267] Completed state [192.168.10.142] at time 09:47:59.170813 duration_in_ms=429.523
2017-11-13 09:47:59,182 [salt.state       ][INFO    ][32267] Running state [192.168.10.141] at time 09:47:59.181529
2017-11-13 09:47:59,182 [salt.state       ][INFO    ][32267] Executing state glusterfs.peered for 192.168.10.141
2017-11-13 09:47:59,183 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:59,199 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:59,281 [salt.state       ][INFO    ][32267] Host 192.168.10.141 already peered
2017-11-13 09:47:59,281 [salt.state       ][INFO    ][32267] Completed state [192.168.10.141] at time 09:47:59.281446 duration_in_ms=99.916
2017-11-13 09:47:59,286 [salt.state       ][INFO    ][32267] Running state [glance] at time 09:47:59.285819
2017-11-13 09:47:59,287 [salt.state       ][INFO    ][32267] Executing state glusterfs.created for glance
2017-11-13 09:47:59,288 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:59,311 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:59,399 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:47:59,420 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:47:59,513 [salt.state       ][INFO    ][32267] Volume glance already exists and is started.
2017-11-13 09:47:59,513 [salt.state       ][INFO    ][32267] Completed state [glance] at time 09:47:59.513377 duration_in_ms=227.557
2017-11-13 09:47:59,525 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:47:59.524910
2017-11-13 09:47:59,525 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:47:59,526 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:47:59,623 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:47:59,623 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 09:47:59.623328 duration_in_ms=98.416
2017-11-13 09:47:59,625 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:47:59.624722
2017-11-13 09:47:59,625 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-11-13 09:47:59,626 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:47:59,723 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:47:59,724 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 09:47:59.723534 duration_in_ms=98.811
2017-11-13 09:47:59,726 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:47:59.725633
2017-11-13 09:47:59,727 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-11-13 09:47:59,728 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:47:59,828 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:47:59,828 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 09:47:59.828217 duration_in_ms=102.583
2017-11-13 09:47:59,829 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:47:59.829194
2017-11-13 09:47:59,830 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:47:59,831 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:47:59,925 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:47:59,926 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 09:47:59.925415 duration_in_ms=96.22
2017-11-13 09:47:59,928 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:47:59.927805
2017-11-13 09:47:59,928 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-11-13 09:47:59,929 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:48:00,019 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:00,019 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 09:48:00.019388 duration_in_ms=91.583
2017-11-13 09:48:00,024 [salt.state       ][INFO    ][32267] Running state [glance] at time 09:48:00.024213
2017-11-13 09:48:00,025 [salt.state       ][INFO    ][32267] Executing state glusterfs.started for glance
2017-11-13 09:48:00,025 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:00,047 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:00,135 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:00,152 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:00,248 [salt.state       ][INFO    ][32267] Volume glance is already started
2017-11-13 09:48:00,249 [salt.state       ][INFO    ][32267] Completed state [glance] at time 09:48:00.248960 duration_in_ms=224.745
2017-11-13 09:48:00,253 [salt.state       ][INFO    ][32267] Running state [keystone-keys] at time 09:48:00.253265
2017-11-13 09:48:00,254 [salt.state       ][INFO    ][32267] Executing state glusterfs.created for keystone-keys
2017-11-13 09:48:00,255 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:00,271 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:00,356 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:00,375 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:00,473 [salt.state       ][INFO    ][32267] Volume keystone-keys already exists and is started.
2017-11-13 09:48:00,476 [salt.state       ][INFO    ][32267] Completed state [keystone-keys] at time 09:48:00.475710 duration_in_ms=222.444
2017-11-13 09:48:00,486 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:48:00.485603
2017-11-13 09:48:00,487 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-11-13 09:48:00,488 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:48:00,587 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:00,587 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 09:48:00.587082 duration_in_ms=101.478
2017-11-13 09:48:00,588 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:00.588305
2017-11-13 09:48:00,589 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:48:00,590 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:00,691 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:00,691 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:00.690868 duration_in_ms=102.561
2017-11-13 09:48:00,692 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:00.692125
2017-11-13 09:48:00,693 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:48:00,694 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:00,797 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:00,797 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:00.797042 duration_in_ms=104.917
2017-11-13 09:48:00,799 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:48:00.799207
2017-11-13 09:48:00,800 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-11-13 09:48:00,801 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:48:00,895 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:00,895 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 09:48:00.895356 duration_in_ms=96.149
2017-11-13 09:48:00,897 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:00.896518
2017-11-13 09:48:00,897 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:48:00,898 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:48:00,993 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:00,995 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:00.995109 duration_in_ms=98.59
2017-11-13 09:48:00,999 [salt.state       ][INFO    ][32267] Running state [keystone-keys] at time 09:48:00.999375
2017-11-13 09:48:00,1000 [salt.state       ][INFO    ][32267] Executing state glusterfs.started for keystone-keys
2017-11-13 09:48:01,001 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:01,017 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:01,108 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:01,127 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:01,224 [salt.state       ][INFO    ][32267] Volume keystone-keys is already started
2017-11-13 09:48:01,225 [salt.state       ][INFO    ][32267] Completed state [keystone-keys] at time 09:48:01.224718 duration_in_ms=225.343
2017-11-13 09:48:01,228 [salt.state       ][INFO    ][32267] Running state [nova_instances] at time 09:48:01.227485
2017-11-13 09:48:01,228 [salt.state       ][INFO    ][32267] Executing state glusterfs.created for nova_instances
2017-11-13 09:48:01,229 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:01,245 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:01,320 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:01,340 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:01,437 [salt.state       ][INFO    ][32267] Volume nova_instances already exists and is started.
2017-11-13 09:48:01,437 [salt.state       ][INFO    ][32267] Completed state [nova_instances] at time 09:48:01.437221 duration_in_ms=209.735
2017-11-13 09:48:01,448 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:01.448345
2017-11-13 09:48:01,449 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:48:01,449 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:01,540 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:01,541 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:01.540710 duration_in_ms=92.364
2017-11-13 09:48:01,543 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:48:01.542887
2017-11-13 09:48:01,544 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'
2017-11-13 09:48:01,545 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: On'' in directory '/root'
2017-11-13 09:48:01,647 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:01,648 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'On'] at time 09:48:01.647653 duration_in_ms=104.764
2017-11-13 09:48:01,649 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:48:01.648963
2017-11-13 09:48:01,650 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'On'
2017-11-13 09:48:01,651 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: On'' in directory '/root'
2017-11-13 09:48:01,746 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:01,747 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'On'] at time 09:48:01.746723 duration_in_ms=97.76
2017-11-13 09:48:01,748 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:48:01.747901
2017-11-13 09:48:01,749 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'On'
2017-11-13 09:48:01,750 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: On'' in directory '/root'
2017-11-13 09:48:01,844 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:01,845 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'On'] at time 09:48:01.844662 duration_in_ms=96.761
2017-11-13 09:48:01,846 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:01.845918
2017-11-13 09:48:01,847 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:48:01,848 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:01,936 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:01,937 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:01.936535 duration_in_ms=90.616
2017-11-13 09:48:01,940 [salt.state       ][INFO    ][32267] Running state [nova_instances] at time 09:48:01.940017
2017-11-13 09:48:01,941 [salt.state       ][INFO    ][32267] Executing state glusterfs.started for nova_instances
2017-11-13 09:48:01,941 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:01,960 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:02,044 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:02,065 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:02,161 [salt.state       ][INFO    ][32267] Volume nova_instances is already started
2017-11-13 09:48:02,162 [salt.state       ][INFO    ][32267] Completed state [nova_instances] at time 09:48:02.161464 duration_in_ms=221.446
2017-11-13 09:48:02,166 [salt.state       ][INFO    ][32267] Running state [keystone-credential-keys] at time 09:48:02.165465
2017-11-13 09:48:02,168 [salt.state       ][INFO    ][32267] Executing state glusterfs.created for keystone-credential-keys
2017-11-13 09:48:02,169 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:02,185 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:02,272 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:02,289 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:02,394 [salt.state       ][INFO    ][32267] Volume keystone-credential-keys already exists and is started.
2017-11-13 09:48:02,396 [salt.state       ][INFO    ][32267] Completed state [keystone-credential-keys] at time 09:48:02.396051 duration_in_ms=230.585
2017-11-13 09:48:02,408 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:02.406441
2017-11-13 09:48:02,409 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-11-13 09:48:02,409 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-11-13 09:48:02,496 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:02,497 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 09:48:02.497302 duration_in_ms=91.127
2017-11-13 09:48:02,500 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:02.499461
2017-11-13 09:48:02,500 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-11-13 09:48:02,501 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:02,609 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:02,610 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 09:48:02.609761 duration_in_ms=110.3
2017-11-13 09:48:02,612 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:48:02.611455
2017-11-13 09:48:02,612 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-11-13 09:48:02,613 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-11-13 09:48:02,709 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:02,709 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 09:48:02.709301 duration_in_ms=97.845
2017-11-13 09:48:02,712 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:02.711867
2017-11-13 09:48:02,712 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-11-13 09:48:02,713 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-11-13 09:48:02,814 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:02,817 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 09:48:02.816535 duration_in_ms=104.668
2017-11-13 09:48:02,819 [salt.state       ][INFO    ][32267] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:48:02.818128
2017-11-13 09:48:02,819 [salt.state       ][INFO    ][32267] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-11-13 09:48:02,820 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-11-13 09:48:02,911 [salt.state       ][INFO    ][32267] unless execution succeeded
2017-11-13 09:48:02,911 [salt.state       ][INFO    ][32267] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 09:48:02.911156 duration_in_ms=93.028
2017-11-13 09:48:02,914 [salt.state       ][INFO    ][32267] Running state [keystone-credential-keys] at time 09:48:02.914313
2017-11-13 09:48:02,915 [salt.state       ][INFO    ][32267] Executing state glusterfs.started for keystone-credential-keys
2017-11-13 09:48:02,916 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:02,936 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:03,025 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --version' in directory '/root'
2017-11-13 09:48:03,045 [salt.loaded.int.module.cmdmod][INFO    ][32267] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-11-13 09:48:03,145 [salt.state       ][INFO    ][32267] Volume keystone-credential-keys is already started
2017-11-13 09:48:03,146 [salt.state       ][INFO    ][32267] Completed state [keystone-credential-keys] at time 09:48:03.145582 duration_in_ms=231.267
2017-11-13 09:48:03,154 [salt.minion      ][INFO    ][32267] Returning information for job: 20171113094756713717
2017-11-13 09:48:12,460 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command test.ping with jid 20171113094812453047
2017-11-13 09:48:12,485 [salt.minion      ][INFO    ][32649] Starting a new job with PID 32649
2017-11-13 09:48:12,536 [salt.minion      ][INFO    ][32649] Returning information for job: 20171113094812453047
2017-11-13 09:48:12,660 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command cmd.run with jid 20171113094812650989
2017-11-13 09:48:12,686 [salt.minion      ][INFO    ][32654] Starting a new job with PID 32654
2017-11-13 09:48:12,695 [salt.loaded.int.module.cmdmod][INFO    ][32654] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-11-13 09:48:12,900 [salt.minion      ][INFO    ][32654] Returning information for job: 20171113094812650989
2017-11-13 10:11:20,081 [salt.minion      ][INFO    ][23737] User sudo_ubuntu Executing command cp.push_dir with jid 20171113101120072933
2017-11-13 10:11:20,109 [salt.minion      ][INFO    ][32842] Starting a new job with PID 32842
