2017-09-20 11:26:15,320 [salt.cli.daemons ][WARNING ][2540] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-20 11:26:24,191 [salt.state       ][ERROR   ][3774] No contents found in top file
2017-09-20 11:26:42,641 [salt.utils.parsers][WARNING ][2540] Minion received a SIGTERM. Exiting.
2017-09-20 11:29:05,377 [salt.cli.daemons ][WARNING ][3234] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-20 11:32:05,389 [py.warnings      ][WARNING ][16286] /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-09-20 11:37:39,497 [salt.loaded.int.module.cmdmod][ERROR   ][16286] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:37:39,498 [salt.loaded.int.module.cmdmod][ERROR   ][16286] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:37:46,818 [salt.loaded.int.module.cmdmod][ERROR   ][16286] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:37:46,818 [salt.loaded.int.module.cmdmod][ERROR   ][16286] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:37:56,081 [salt.loaded.int.module.cmdmod][ERROR   ][16286] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:37:56,082 [salt.loaded.int.module.cmdmod][ERROR   ][16286] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:38:06,321 [salt.loaded.int.module.cmdmod][ERROR   ][16286] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:38:06,322 [salt.loaded.int.module.cmdmod][ERROR   ][16286] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:38:16,766 [salt.loaded.int.module.cmdmod][ERROR   ][16286] Command 'sfdisk -d /dev/nbd9' failed with return code: 1
2017-09-20 11:38:16,767 [salt.loaded.int.module.cmdmod][ERROR   ][16286] output: sfdisk: cannot open /dev/nbd9: Inappropriate ioctl for device
2017-09-20 11:38:23,025 [salt.loaded.int.module.cmdmod][ERROR   ][16286] 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-09-20 11:38:27,100 [salt.state       ][ERROR   ][24057] No contents found in top file
2017-09-20 11:38:31,100 [salt.loaded.int.module.cmdmod][INFO    ][24263] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-20 11:38:31,115 [salt.loaded.int.module.cmdmod][INFO    ][24263] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-20 11:38:31,137 [salt.loaded.int.module.cmdmod][INFO    ][24263] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2017-09-20 11:38:31,154 [salt.utils.parsers][WARNING ][3234] Minion received a SIGTERM. Exiting.
2017-09-20 11:38:31,595 [salt.cli.daemons ][INFO    ][24406] Setting up the Salt Minion "kvm03.baremetal-mcp-ocata-odl-ha.local"
2017-09-20 11:38:32,380 [salt.minion      ][INFO    ][24406] Creating minion process manager
2017-09-20 11:38:32,381 [salt.cli.daemons ][WARNING ][24406] IMPORTANT: Do not use md5 hashing algorithm! Please set "hash_type" to sha256 in Salt Minion config!
2017-09-20 11:38:32,381 [salt.cli.daemons ][INFO    ][24406] The Salt Minion is starting up
2017-09-20 11:38:32,381 [salt.minion      ][INFO    ][24406] Minion is starting as user 'root'
2017-09-20 11:38:32,382 [salt.utils.event ][INFO    ][24406] Starting pull socket on /var/run/salt/minion/minion_event_2d6d7b82d1_pull.ipc
2017-09-20 11:38:33,644 [salt.loaded.int.module.cmdmod][INFO    ][24406] Executing command ['date', '+%z'] in directory '/root'
2017-09-20 11:38:33,660 [salt.utils.schedule][INFO    ][24406] Updating job settings for scheduled job: __mine_interval
2017-09-20 11:38:33,663 [salt.minion      ][INFO    ][24406] Added mine.update to scheduler
2017-09-20 11:38:33,759 [salt.minion      ][INFO    ][24406] Minion is ready to receive requests!
2017-09-20 11:38:34,760 [salt.utils.schedule][INFO    ][24406] Running scheduled job: __mine_interval
2017-09-20 11:38:55,740 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command state.sls with jid 20170920113855726405
2017-09-20 11:38:55,760 [salt.minion      ][INFO    ][24510] Starting a new job with PID 24510
2017-09-20 11:38:58,222 [salt.state       ][INFO    ][24510] Loading fresh modules for state activity
2017-09-20 11:38:58,268 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/control/init.sls'
2017-09-20 11:38:58,303 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/control/virt.sls'
2017-09-20 11:38:58,372 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:38:58,509 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/minion/init.sls'
2017-09-20 11:38:58,532 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/minion/service.sls'
2017-09-20 11:38:58,577 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:38:58,735 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-20 11:38:58,757 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-20 11:38:58,844 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-20 11:38:58,876 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-20 11:38:58,893 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 11:38:58,916 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-20 11:38:58,931 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-20 11:38:58,968 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-20 11:38:59,043 [py.warnings      ][WARNING ][24510] /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-09-20 11:38:59,044 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'salt-minion --version' in directory '/root'
2017-09-20 11:38:59,416 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-20 11:38:59,476 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-20 11:38:59,493 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-20 11:38:59,510 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-20 11:38:59,593 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-20 11:38:59,654 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-20 11:38:59,751 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/minion/grains.sls'
2017-09-20 11:38:59,800 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:38:59,914 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/meta/salt.yml'
2017-09-20 11:38:59,961 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-20 11:39:00,031 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/meta/sphinx.yml'
2017-09-20 11:39:00,054 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/sphinx.yml'
2017-09-20 11:39:00,069 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 11:39:00,109 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/sphinx.yml'
2017-09-20 11:39:00,118 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'gluster --version 2>/dev/null || echo "unknown" |head -1' in directory '/root'
2017-09-20 11:39:00,159 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/meta/sphinx.yml'
2017-09-20 11:39:00,188 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'salt-minion --version' in directory '/root'
2017-09-20 11:39:00,598 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'keepalived/meta/salt.yml'
2017-09-20 11:39:00,621 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'glusterfs/meta/salt.yml'
2017-09-20 11:39:00,636 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'openssh/meta/salt.yml'
2017-09-20 11:39:00,651 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/meta/salt.yml'
2017-09-20 11:39:00,776 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/meta/meta.yml'
2017-09-20 11:39:00,826 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/meta/meta.yml'
2017-09-20 11:39:00,925 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/minion/cert.sls'
2017-09-20 11:39:00,998 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:39:01,626 [salt.state       ][INFO    ][24510] Running state [salt-minion] at time 11:39:01.625835
2017-09-20 11:39:01,626 [salt.state       ][INFO    ][24510] Executing state pkg.installed for salt-minion
2017-09-20 11:39:01,627 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 11:39:02,062 [salt.state       ][INFO    ][24510] Package salt-minion is already installed
2017-09-20 11:39:02,063 [salt.state       ][INFO    ][24510] Completed state [salt-minion] at time 11:39:02.062775 duration_in_ms=436.941
2017-09-20 11:39:02,063 [salt.state       ][INFO    ][24510] Running state [python-yaml] at time 11:39:02.063063
2017-09-20 11:39:02,063 [salt.state       ][INFO    ][24510] Executing state pkg.installed for python-yaml
2017-09-20 11:39:02,067 [salt.state       ][INFO    ][24510] Package python-yaml is already installed
2017-09-20 11:39:02,068 [salt.state       ][INFO    ][24510] Completed state [python-yaml] at time 11:39:02.067570 duration_in_ms=4.507
2017-09-20 11:39:02,068 [salt.state       ][INFO    ][24510] Running state [python-psutil] at time 11:39:02.067781
2017-09-20 11:39:02,068 [salt.state       ][INFO    ][24510] Executing state pkg.installed for python-psutil
2017-09-20 11:39:02,072 [salt.state       ][INFO    ][24510] Package python-psutil is already installed
2017-09-20 11:39:02,072 [salt.state       ][INFO    ][24510] Completed state [python-psutil] at time 11:39:02.072055 duration_in_ms=4.273
2017-09-20 11:39:02,072 [salt.state       ][INFO    ][24510] Running state [python-m2crypto] at time 11:39:02.072263
2017-09-20 11:39:02,072 [salt.state       ][INFO    ][24510] Executing state pkg.installed for python-m2crypto
2017-09-20 11:39:02,076 [salt.state       ][INFO    ][24510] Package python-m2crypto is already installed
2017-09-20 11:39:02,077 [salt.state       ][INFO    ][24510] Completed state [python-m2crypto] at time 11:39:02.076546 duration_in_ms=4.283
2017-09-20 11:39:02,080 [salt.state       ][INFO    ][24510] Running state [/etc/salt/minion.d/minion.conf] at time 11:39:02.079800
2017-09-20 11:39:02,080 [salt.state       ][INFO    ][24510] Executing state file.managed for /etc/salt/minion.d/minion.conf
2017-09-20 11:39:02,115 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/files/minion.conf'
2017-09-20 11:39:02,192 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'salt/map.jinja'
2017-09-20 11:39:02,373 [salt.fileclient  ][INFO    ][24510] Fetching file from saltenv 'base', ** done ** 'linux/map.jinja'
2017-09-20 11:39:02,467 [salt.state       ][INFO    ][24510] File /etc/salt/minion.d/minion.conf is in the correct state
2017-09-20 11:39:02,467 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/minion.d/minion.conf] at time 11:39:02.467360 duration_in_ms=387.56
2017-09-20 11:39:02,468 [salt.state       ][INFO    ][24510] Running state [/etc/salt/minion.d/_virt.conf] at time 11:39:02.468226
2017-09-20 11:39:02,469 [salt.state       ][INFO    ][24510] Executing state file.managed for /etc/salt/minion.d/_virt.conf
2017-09-20 11:39:02,567 [salt.state       ][INFO    ][24510] File /etc/salt/minion.d/_virt.conf is in the correct state
2017-09-20 11:39:02,567 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/minion.d/_virt.conf] at time 11:39:02.567163 duration_in_ms=98.936
2017-09-20 11:39:02,571 [salt.state       ][INFO    ][24510] Running state [salt-minion] at time 11:39:02.570565
2017-09-20 11:39:02,571 [salt.state       ][INFO    ][24510] Executing state service.running for salt-minion
2017-09-20 11:39:02,572 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2017-09-20 11:39:02,605 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2017-09-20 11:39:02,620 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2017-09-20 11:39:02,633 [salt.state       ][INFO    ][24510] The service salt-minion is already running
2017-09-20 11:39:02,634 [salt.state       ][INFO    ][24510] Completed state [salt-minion] at time 11:39:02.633744 duration_in_ms=63.18
2017-09-20 11:39:02,636 [salt.state       ][INFO    ][24510] Running state [/etc/salt/grains.d] at time 11:39:02.636025
2017-09-20 11:39:02,636 [salt.state       ][INFO    ][24510] Executing state file.directory for /etc/salt/grains.d
2017-09-20 11:39:02,638 [salt.state       ][INFO    ][24510] Directory /etc/salt/grains.d is in the correct state
2017-09-20 11:39:02,638 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/grains.d] at time 11:39:02.637817 duration_in_ms=1.793
2017-09-20 11:39:02,639 [salt.state       ][INFO    ][24510] Running state [/etc/salt/grains] at time 11:39:02.638789
2017-09-20 11:39:02,639 [salt.state       ][INFO    ][24510] Executing state file.managed for /etc/salt/grains
2017-09-20 11:39:02,640 [salt.state       ][INFO    ][24510] File /etc/salt/grains exists with proper permissions. No changes made.
2017-09-20 11:39:02,640 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/grains] at time 11:39:02.640111 duration_in_ms=1.322
2017-09-20 11:39:02,641 [salt.state       ][INFO    ][24510] Running state [/etc/salt/grains.d/placeholder] at time 11:39:02.640732
2017-09-20 11:39:02,641 [salt.state       ][INFO    ][24510] Executing state file.managed for /etc/salt/grains.d/placeholder
2017-09-20 11:39:02,642 [salt.state       ][INFO    ][24510] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2017-09-20 11:39:02,642 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/grains.d/placeholder] at time 11:39:02.642011 duration_in_ms=1.278
2017-09-20 11:39:02,643 [salt.state       ][INFO    ][24510] Running state [/etc/salt/grains.d/sphinx] at time 11:39:02.642672
2017-09-20 11:39:02,643 [salt.state       ][INFO    ][24510] Executing state file.managed for /etc/salt/grains.d/sphinx
2017-09-20 11:39:02,740 [salt.state       ][INFO    ][24510] File /etc/salt/grains.d/sphinx is in the correct state
2017-09-20 11:39:02,976 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/grains.d/sphinx] at time 11:39:02.975905 duration_in_ms=333.231
2017-09-20 11:39:02,977 [salt.state       ][INFO    ][24510] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:39:02.977207
2017-09-20 11:39:02,978 [salt.state       ][INFO    ][24510] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:02,978 [salt.state       ][INFO    ][24510] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:02,979 [salt.state       ][INFO    ][24510] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:39:02.978557 duration_in_ms=1.35
2017-09-20 11:39:02,979 [salt.state       ][INFO    ][24510] Running state [/etc/salt/grains.d/dns_records] at time 11:39:02.979323
2017-09-20 11:39:02,980 [salt.state       ][INFO    ][24510] Executing state file.managed for /etc/salt/grains.d/dns_records
2017-09-20 11:39:03,154 [salt.state       ][INFO    ][24510] File /etc/salt/grains.d/dns_records is in the correct state
2017-09-20 11:39:03,155 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/grains.d/dns_records] at time 11:39:03.154827 duration_in_ms=175.5
2017-09-20 11:39:03,157 [salt.state       ][INFO    ][24510] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:39:03.156454
2017-09-20 11:39:03,157 [salt.state       ][INFO    ][24510] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:03,158 [salt.state       ][INFO    ][24510] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:03,158 [salt.state       ][INFO    ][24510] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:39:03.158222 duration_in_ms=1.755
2017-09-20 11:39:03,159 [salt.state       ][INFO    ][24510] Running state [/etc/salt/grains.d/salt] at time 11:39:03.159148
2017-09-20 11:39:03,160 [salt.state       ][INFO    ][24510] Executing state file.managed for /etc/salt/grains.d/salt
2017-09-20 11:39:03,274 [salt.state       ][INFO    ][24510] File /etc/salt/grains.d/salt is in the correct state
2017-09-20 11:39:03,275 [salt.state       ][INFO    ][24510] Completed state [/etc/salt/grains.d/salt] at time 11:39:03.274482 duration_in_ms=115.333
2017-09-20 11:39:03,276 [salt.state       ][INFO    ][24510] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:39:03.275814
2017-09-20 11:39:03,276 [salt.state       ][INFO    ][24510] Executing state cmd.wait for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:03,277 [salt.state       ][INFO    ][24510] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2017-09-20 11:39:03,277 [salt.state       ][INFO    ][24510] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:39:03.277318 duration_in_ms=1.503
2017-09-20 11:39:03,281 [salt.state       ][INFO    ][24510] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:39:03.280501
2017-09-20 11:39:03,281 [salt.state       ][INFO    ][24510] Executing state cmd.wait for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-20 11:39:03,282 [salt.state       ][INFO    ][24510] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2017-09-20 11:39:03,282 [salt.state       ][INFO    ][24510] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:39:03.282004 duration_in_ms=1.502
2017-09-20 11:39:03,283 [salt.state       ][INFO    ][24510] Running state [mine.update] at time 11:39:03.283202
2017-09-20 11:39:03,284 [salt.state       ][INFO    ][24510] Executing state module.wait for mine.update
2017-09-20 11:39:03,284 [salt.state       ][INFO    ][24510] No changes made for mine.update
2017-09-20 11:39:03,285 [salt.state       ][INFO    ][24510] Completed state [mine.update] at time 11:39:03.284601 duration_in_ms=1.398
2017-09-20 11:39:03,285 [salt.state       ][INFO    ][24510] Running state [ca-certificates] at time 11:39:03.285105
2017-09-20 11:39:03,286 [salt.state       ][INFO    ][24510] Executing state pkg.installed for ca-certificates
2017-09-20 11:39:03,293 [salt.state       ][INFO    ][24510] Package ca-certificates is already installed
2017-09-20 11:39:03,293 [salt.state       ][INFO    ][24510] Completed state [ca-certificates] at time 11:39:03.293042 duration_in_ms=7.937
2017-09-20 11:39:03,294 [salt.state       ][INFO    ][24510] Running state [update-ca-certificates] at time 11:39:03.294191
2017-09-20 11:39:03,295 [salt.state       ][INFO    ][24510] Executing state cmd.wait for update-ca-certificates
2017-09-20 11:39:03,295 [salt.state       ][INFO    ][24510] No changes made for update-ca-certificates
2017-09-20 11:39:03,296 [salt.state       ][INFO    ][24510] Completed state [update-ca-certificates] at time 11:39:03.295573 duration_in_ms=1.381
2017-09-20 11:39:03,296 [salt.state       ][INFO    ][24510] Running state [libvirt-dev] at time 11:39:03.296049
2017-09-20 11:39:03,297 [salt.state       ][INFO    ][24510] Executing state pkg.installed for libvirt-dev
2017-09-20 11:39:03,303 [salt.state       ][INFO    ][24510] Package libvirt-dev is already installed
2017-09-20 11:39:03,304 [salt.state       ][INFO    ][24510] Completed state [libvirt-dev] at time 11:39:03.303659 duration_in_ms=7.61
2017-09-20 11:39:03,304 [salt.state       ][INFO    ][24510] Running state [pkg-config] at time 11:39:03.304062
2017-09-20 11:39:03,304 [salt.state       ][INFO    ][24510] Executing state pkg.installed for pkg-config
2017-09-20 11:39:03,310 [salt.state       ][INFO    ][24510] Package pkg-config is already installed
2017-09-20 11:39:03,423 [salt.state       ][INFO    ][24510] Completed state [pkg-config] at time 11:39:03.423299 duration_in_ms=119.236
2017-09-20 11:39:03,424 [salt.state       ][INFO    ][24510] Running state [virtng.init] at time 11:39:03.423774
2017-09-20 11:39:03,424 [salt.state       ][INFO    ][24510] Executing state module.run for virtng.init
2017-09-20 11:39:03,613 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command ['git', '--version'] in directory '/root'
2017-09-20 11:39:03,687 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --all --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:03,749 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:03,749 [salt.state       ][INFO    ][24510] Completed state [virtng.init] at time 11:39:03.749229 duration_in_ms=325.453
2017-09-20 11:39:03,750 [salt.state       ][INFO    ][24510] Running state [virt.set_autostart] at time 11:39:03.749807
2017-09-20 11:39:03,750 [salt.state       ][INFO    ][24510] Executing state module.run for virt.set_autostart
2017-09-20 11:39:03,756 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --autostart --name| grep -E "^mdb03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:03,788 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:03,789 [salt.state       ][INFO    ][24510] Completed state [virt.set_autostart] at time 11:39:03.789327 duration_in_ms=39.518
2017-09-20 11:39:03,790 [salt.state       ][INFO    ][24510] Running state [virtng.init] at time 11:39:03.789866
2017-09-20 11:39:03,790 [salt.state       ][INFO    ][24510] Executing state module.run for virtng.init
2017-09-20 11:39:03,795 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --all --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:03,831 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:03,832 [salt.state       ][INFO    ][24510] Completed state [virtng.init] at time 11:39:03.831562 duration_in_ms=41.695
2017-09-20 11:39:03,832 [salt.state       ][INFO    ][24510] Running state [virt.set_autostart] at time 11:39:03.832109
2017-09-20 11:39:03,833 [salt.state       ][INFO    ][24510] Executing state module.run for virt.set_autostart
2017-09-20 11:39:03,838 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --autostart --name| grep -E "^dbs03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:03,869 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:03,869 [salt.state       ][INFO    ][24510] Completed state [virt.set_autostart] at time 11:39:03.869300 duration_in_ms=37.19
2017-09-20 11:39:03,870 [salt.state       ][INFO    ][24510] Running state [virtng.init] at time 11:39:03.869779
2017-09-20 11:39:03,870 [salt.state       ][INFO    ][24510] Executing state module.run for virtng.init
2017-09-20 11:39:03,875 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --all --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:03,904 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:03,904 [salt.state       ][INFO    ][24510] Completed state [virtng.init] at time 11:39:03.904387 duration_in_ms=34.607
2017-09-20 11:39:03,905 [salt.state       ][INFO    ][24510] Running state [virt.set_autostart] at time 11:39:03.904940
2017-09-20 11:39:03,905 [salt.state       ][INFO    ][24510] Executing state module.run for virt.set_autostart
2017-09-20 11:39:03,911 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --autostart --name| grep -E "^odl01.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:03,939 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:04,047 [salt.state       ][INFO    ][24510] Completed state [virt.set_autostart] at time 11:39:04.047253 duration_in_ms=142.31
2017-09-20 11:39:04,048 [salt.state       ][INFO    ][24510] Running state [virtng.init] at time 11:39:04.047887
2017-09-20 11:39:04,048 [salt.state       ][INFO    ][24510] Executing state module.run for virtng.init
2017-09-20 11:39:04,058 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --all --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:04,095 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:04,095 [salt.state       ][INFO    ][24510] Completed state [virtng.init] at time 11:39:04.095052 duration_in_ms=47.163
2017-09-20 11:39:04,096 [salt.state       ][INFO    ][24510] Running state [virt.set_autostart] at time 11:39:04.095865
2017-09-20 11:39:04,096 [salt.state       ][INFO    ][24510] Executing state module.run for virt.set_autostart
2017-09-20 11:39:04,102 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --autostart --name| grep -E "^ctl03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:04,135 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:04,135 [salt.state       ][INFO    ][24510] Completed state [virt.set_autostart] at time 11:39:04.135278 duration_in_ms=39.412
2017-09-20 11:39:04,136 [salt.state       ][INFO    ][24510] Running state [virtng.init] at time 11:39:04.135771
2017-09-20 11:39:04,136 [salt.state       ][INFO    ][24510] Executing state module.run for virtng.init
2017-09-20 11:39:04,141 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --all --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:04,173 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:04,244 [salt.state       ][INFO    ][24510] Completed state [virtng.init] at time 11:39:04.243911 duration_in_ms=108.14
2017-09-20 11:39:04,245 [salt.state       ][INFO    ][24510] Running state [virt.set_autostart] at time 11:39:04.244515
2017-09-20 11:39:04,245 [salt.state       ][INFO    ][24510] Executing state module.run for virt.set_autostart
2017-09-20 11:39:04,251 [salt.loaded.int.module.cmdmod][INFO    ][24510] Executing command 'virsh list --autostart --name| grep -E "^msg03.baremetal-mcp-ocata-odl-ha.local$"' in directory '/root'
2017-09-20 11:39:04,282 [salt.state       ][INFO    ][24510] unless execution succeeded
2017-09-20 11:39:04,283 [salt.state       ][INFO    ][24510] Completed state [virt.set_autostart] at time 11:39:04.282538 duration_in_ms=38.023
2017-09-20 11:39:04,288 [salt.minion      ][INFO    ][24510] Returning information for job: 20170920113855726405
2017-09-20 11:40:08,827 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command saltutil.sync_all with jid 20170920114008809665
2017-09-20 11:40:08,855 [salt.minion      ][INFO    ][24612] Starting a new job with PID 24612
2017-09-20 11:40:12,178 [salt.state       ][INFO    ][24612] Loading fresh modules for state activity
2017-09-20 11:40:12,617 [salt.state       ][ERROR   ][24612] No contents found in top file
2017-09-20 11:40:12,618 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/beacons'
2017-09-20 11:40:12,624 [salt.utils.extmods][INFO    ][24612] Syncing beacons for environment 'base'
2017-09-20 11:40:12,625 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_beacons, for base)
2017-09-20 11:40:12,625 [salt.fileclient  ][INFO    ][24612] Caching directory '_beacons/' for environment 'base'
2017-09-20 11:40:12,673 [salt.utils.extmods][INFO    ][24612] Syncing modules for environment 'base'
2017-09-20 11:40:12,673 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_modules, for base)
2017-09-20 11:40:12,674 [salt.fileclient  ][INFO    ][24612] Caching directory '_modules/' for environment 'base'
2017-09-20 11:40:12,722 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/ceph_ng.py'
2017-09-20 11:40:12,733 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/cinderng.py'
2017-09-20 11:40:12,746 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/contrail.py'
2017-09-20 11:40:13,182 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/creds.py'
2017-09-20 11:40:13,206 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/devops_utils.py'
2017-09-20 11:40:13,222 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/encode_json.py'
2017-09-20 11:40:13,256 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/gerrit.py'
2017-09-20 11:40:13,288 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/glanceng.py'
2017-09-20 11:40:13,306 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/heat.py'
2017-09-20 11:40:13,321 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/heka_alarming.py'
2017-09-20 11:40:13,336 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/helm.py'
2017-09-20 11:40:13,356 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/jenkins_common.py'
2017-09-20 11:40:13,373 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/keystone_policy.py'
2017-09-20 11:40:13,387 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/linux_hosts.py'
2017-09-20 11:40:13,401 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/linux_netlink.py'
2017-09-20 11:40:13,419 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/maas.py'
2017-09-20 11:40:13,435 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/maas_client.py'
2017-09-20 11:40:13,449 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/multipart.py'
2017-09-20 11:40:13,460 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/nagios_alarming.py'
2017-09-20 11:40:13,472 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/neutronng.py'
2017-09-20 11:40:13,488 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/novang.py'
2017-09-20 11:40:13,517 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/reclass.py'
2017-09-20 11:40:13,536 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/rsyslog_util.py'
2017-09-20 11:40:13,564 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/rundeck.py'
2017-09-20 11:40:13,589 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/saltkey.py'
2017-09-20 11:40:13,599 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/saltresource.py'
2017-09-20 11:40:13,617 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/seedng.py'
2017-09-20 11:40:13,631 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/testing/credentials.py'
2017-09-20 11:40:13,641 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/testing/django.py'
2017-09-20 11:40:13,650 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/testing/django_client_proxy.py'
2017-09-20 11:40:13,663 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/utils.py'
2017-09-20 11:40:13,683 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_modules/virtng.py'
2017-09-20 11:40:13,688 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/__init__.py' to '/var/cache/salt/minion/extmods/modules/__init__.py'
2017-09-20 11:40:13,689 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/ceph_ng.py' to '/var/cache/salt/minion/extmods/modules/ceph_ng.py'
2017-09-20 11:40:13,689 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/cinderng.py' to '/var/cache/salt/minion/extmods/modules/cinderng.py'
2017-09-20 11:40:13,690 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/contrail.py' to '/var/cache/salt/minion/extmods/modules/contrail.py'
2017-09-20 11:40:13,690 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/creds.py' to '/var/cache/salt/minion/extmods/modules/creds.py'
2017-09-20 11:40:13,691 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/devops_utils.py' to '/var/cache/salt/minion/extmods/modules/devops_utils.py'
2017-09-20 11:40:13,691 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/encode_json.py' to '/var/cache/salt/minion/extmods/modules/encode_json.py'
2017-09-20 11:40:13,691 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/gerrit.py' to '/var/cache/salt/minion/extmods/modules/gerrit.py'
2017-09-20 11:40:13,692 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/glanceng.py' to '/var/cache/salt/minion/extmods/modules/glanceng.py'
2017-09-20 11:40:13,692 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/heat.py' to '/var/cache/salt/minion/extmods/modules/heat.py'
2017-09-20 11:40:13,693 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/heka_alarming.py' to '/var/cache/salt/minion/extmods/modules/heka_alarming.py'
2017-09-20 11:40:13,693 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/helm.py' to '/var/cache/salt/minion/extmods/modules/helm.py'
2017-09-20 11:40:13,694 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/jenkins_common.py' to '/var/cache/salt/minion/extmods/modules/jenkins_common.py'
2017-09-20 11:40:13,694 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/keystone_policy.py' to '/var/cache/salt/minion/extmods/modules/keystone_policy.py'
2017-09-20 11:40:13,694 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/linux_hosts.py' to '/var/cache/salt/minion/extmods/modules/linux_hosts.py'
2017-09-20 11:40:13,695 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/linux_netlink.py' to '/var/cache/salt/minion/extmods/modules/linux_netlink.py'
2017-09-20 11:40:13,695 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/maas.py' to '/var/cache/salt/minion/extmods/modules/maas.py'
2017-09-20 11:40:13,696 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/maas_client.py' to '/var/cache/salt/minion/extmods/modules/maas_client.py'
2017-09-20 11:40:13,696 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/multipart.py' to '/var/cache/salt/minion/extmods/modules/multipart.py'
2017-09-20 11:40:13,696 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/nagios_alarming.py' to '/var/cache/salt/minion/extmods/modules/nagios_alarming.py'
2017-09-20 11:40:13,697 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/neutronng.py' to '/var/cache/salt/minion/extmods/modules/neutronng.py'
2017-09-20 11:40:13,697 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/novang.py' to '/var/cache/salt/minion/extmods/modules/novang.py'
2017-09-20 11:40:13,698 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/reclass.py' to '/var/cache/salt/minion/extmods/modules/reclass.py'
2017-09-20 11:40:13,699 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/rsyslog_util.py' to '/var/cache/salt/minion/extmods/modules/rsyslog_util.py'
2017-09-20 11:40:13,699 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/rundeck.py' to '/var/cache/salt/minion/extmods/modules/rundeck.py'
2017-09-20 11:40:13,700 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/saltkey.py' to '/var/cache/salt/minion/extmods/modules/saltkey.py'
2017-09-20 11:40:13,700 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/saltresource.py' to '/var/cache/salt/minion/extmods/modules/saltresource.py'
2017-09-20 11:40:13,701 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/seedng.py' to '/var/cache/salt/minion/extmods/modules/seedng.py'
2017-09-20 11:40:13,701 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/testing/__init__.py' to '/var/cache/salt/minion/extmods/modules/testing/__init__.py'
2017-09-20 11:40:13,701 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/testing/credentials.py' to '/var/cache/salt/minion/extmods/modules/testing/credentials.py'
2017-09-20 11:40:13,702 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/testing/django.py' to '/var/cache/salt/minion/extmods/modules/testing/django.py'
2017-09-20 11:40:13,702 [salt.utils.extmods][INFO    ][24612] 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-09-20 11:40:13,702 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/utils.py' to '/var/cache/salt/minion/extmods/modules/utils.py'
2017-09-20 11:40:13,703 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_modules/virtng.py' to '/var/cache/salt/minion/extmods/modules/virtng.py'
2017-09-20 11:40:13,710 [salt.utils.extmods][INFO    ][24612] Syncing states for environment 'base'
2017-09-20 11:40:13,711 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_states, for base)
2017-09-20 11:40:13,711 [salt.fileclient  ][INFO    ][24612] Caching directory '_states/' for environment 'base'
2017-09-20 11:40:13,791 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/cinderng.py'
2017-09-20 11:40:13,812 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/contrail.py'
2017-09-20 11:40:13,839 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/gerrit.py'
2017-09-20 11:40:13,948 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/glanceng.py'
2017-09-20 11:40:13,965 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/grafana3_dashboard.py'
2017-09-20 11:40:14,017 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/grafana3_datasource.py'
2017-09-20 11:40:14,038 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/heat.py'
2017-09-20 11:40:14,061 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/helm_release.py'
2017-09-20 11:40:14,080 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_approval.py'
2017-09-20 11:40:14,091 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_artifactory.py'
2017-09-20 11:40:14,111 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_credential.py'
2017-09-20 11:40:14,124 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_job.py'
2017-09-20 11:40:14,166 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_lib.py'
2017-09-20 11:40:14,185 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_node.py'
2017-09-20 11:40:14,223 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_plugin.py'
2017-09-20 11:40:14,251 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_security.py'
2017-09-20 11:40:14,288 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_slack.py'
2017-09-20 11:40:14,304 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_smtp.py'
2017-09-20 11:40:14,324 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_theme.py'
2017-09-20 11:40:14,333 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_user.py'
2017-09-20 11:40:14,346 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/jenkins_view.py'
2017-09-20 11:40:14,363 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/keystone_policy.py'
2017-09-20 11:40:14,428 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/kibana_object.py'
2017-09-20 11:40:14,507 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/neutronng.py'
2017-09-20 11:40:14,521 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/novang.py'
2017-09-20 11:40:14,533 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/reclass.py'
2017-09-20 11:40:14,551 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/rundeck_project.py'
2017-09-20 11:40:14,686 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/rundeck_scm.py'
2017-09-20 11:40:14,710 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_states/rundeck_secret.py'
2017-09-20 11:40:14,714 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/cinderng.py' to '/var/cache/salt/minion/extmods/states/cinderng.py'
2017-09-20 11:40:14,714 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/contrail.py' to '/var/cache/salt/minion/extmods/states/contrail.py'
2017-09-20 11:40:14,715 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/gerrit.py' to '/var/cache/salt/minion/extmods/states/gerrit.py'
2017-09-20 11:40:14,715 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/glanceng.py' to '/var/cache/salt/minion/extmods/states/glanceng.py'
2017-09-20 11:40:14,716 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/grafana3_dashboard.py' to '/var/cache/salt/minion/extmods/states/grafana3_dashboard.py'
2017-09-20 11:40:14,716 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/grafana3_datasource.py' to '/var/cache/salt/minion/extmods/states/grafana3_datasource.py'
2017-09-20 11:40:14,716 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/heat.py' to '/var/cache/salt/minion/extmods/states/heat.py'
2017-09-20 11:40:14,717 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/helm_release.py' to '/var/cache/salt/minion/extmods/states/helm_release.py'
2017-09-20 11:40:14,717 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_approval.py' to '/var/cache/salt/minion/extmods/states/jenkins_approval.py'
2017-09-20 11:40:14,717 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_artifactory.py' to '/var/cache/salt/minion/extmods/states/jenkins_artifactory.py'
2017-09-20 11:40:14,717 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_credential.py' to '/var/cache/salt/minion/extmods/states/jenkins_credential.py'
2017-09-20 11:40:14,718 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_job.py' to '/var/cache/salt/minion/extmods/states/jenkins_job.py'
2017-09-20 11:40:14,718 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_lib.py' to '/var/cache/salt/minion/extmods/states/jenkins_lib.py'
2017-09-20 11:40:14,718 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_node.py' to '/var/cache/salt/minion/extmods/states/jenkins_node.py'
2017-09-20 11:40:14,719 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_plugin.py' to '/var/cache/salt/minion/extmods/states/jenkins_plugin.py'
2017-09-20 11:40:14,719 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_security.py' to '/var/cache/salt/minion/extmods/states/jenkins_security.py'
2017-09-20 11:40:14,719 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_slack.py' to '/var/cache/salt/minion/extmods/states/jenkins_slack.py'
2017-09-20 11:40:14,720 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_smtp.py' to '/var/cache/salt/minion/extmods/states/jenkins_smtp.py'
2017-09-20 11:40:14,720 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_theme.py' to '/var/cache/salt/minion/extmods/states/jenkins_theme.py'
2017-09-20 11:40:14,720 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_user.py' to '/var/cache/salt/minion/extmods/states/jenkins_user.py'
2017-09-20 11:40:14,720 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/jenkins_view.py' to '/var/cache/salt/minion/extmods/states/jenkins_view.py'
2017-09-20 11:40:14,721 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/keystone_policy.py' to '/var/cache/salt/minion/extmods/states/keystone_policy.py'
2017-09-20 11:40:14,721 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/kibana_object.py' to '/var/cache/salt/minion/extmods/states/kibana_object.py'
2017-09-20 11:40:14,721 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/maas_cluster.py' to '/var/cache/salt/minion/extmods/states/maas_cluster.py'
2017-09-20 11:40:14,722 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/neutronng.py' to '/var/cache/salt/minion/extmods/states/neutronng.py'
2017-09-20 11:40:14,722 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/novang.py' to '/var/cache/salt/minion/extmods/states/novang.py'
2017-09-20 11:40:14,722 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/reclass.py' to '/var/cache/salt/minion/extmods/states/reclass.py'
2017-09-20 11:40:14,723 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/rundeck_project.py' to '/var/cache/salt/minion/extmods/states/rundeck_project.py'
2017-09-20 11:40:14,723 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/rundeck_scm.py' to '/var/cache/salt/minion/extmods/states/rundeck_scm.py'
2017-09-20 11:40:14,723 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_states/rundeck_secret.py' to '/var/cache/salt/minion/extmods/states/rundeck_secret.py'
2017-09-20 11:40:14,726 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/sdb'
2017-09-20 11:40:14,732 [salt.utils.extmods][INFO    ][24612] Syncing sdb for environment 'base'
2017-09-20 11:40:14,732 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_sdb, for base)
2017-09-20 11:40:14,733 [salt.fileclient  ][INFO    ][24612] Caching directory '_sdb/' for environment 'base'
2017-09-20 11:40:14,820 [salt.utils.extmods][INFO    ][24612] Syncing grains for environment 'base'
2017-09-20 11:40:14,820 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_grains, for base)
2017-09-20 11:40:14,820 [salt.fileclient  ][INFO    ][24612] Caching directory '_grains/' for environment 'base'
2017-09-20 11:40:14,868 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/ceilometer_policy.py'
2017-09-20 11:40:14,928 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/cinder_policy.py'
2017-09-20 11:40:14,954 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/docker_swarm.py'
2017-09-20 11:40:14,966 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/glance_policy.py'
2017-09-20 11:40:14,999 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/heat_policy.py'
2017-09-20 11:40:15,023 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/jenkins_plugins.py'
2017-09-20 11:40:15,036 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/keystone_policy.py'
2017-09-20 11:40:15,055 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/kubernetes.py'
2017-09-20 11:40:15,082 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/neutron_policy.py'
2017-09-20 11:40:15,105 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/nova_policy.py'
2017-09-20 11:40:15,122 [salt.fileclient  ][INFO    ][24612] Fetching file from saltenv 'base', ** done ** '_grains/ssh_fingerprints.py'
2017-09-20 11:40:15,126 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/ceilometer_policy.py' to '/var/cache/salt/minion/extmods/grains/ceilometer_policy.py'
2017-09-20 11:40:15,126 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/cinder_policy.py' to '/var/cache/salt/minion/extmods/grains/cinder_policy.py'
2017-09-20 11:40:15,127 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/docker_swarm.py' to '/var/cache/salt/minion/extmods/grains/docker_swarm.py'
2017-09-20 11:40:15,127 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/glance_policy.py' to '/var/cache/salt/minion/extmods/grains/glance_policy.py'
2017-09-20 11:40:15,127 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/heat_policy.py' to '/var/cache/salt/minion/extmods/grains/heat_policy.py'
2017-09-20 11:40:15,128 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/jenkins_plugins.py' to '/var/cache/salt/minion/extmods/grains/jenkins_plugins.py'
2017-09-20 11:40:15,128 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/keystone_policy.py' to '/var/cache/salt/minion/extmods/grains/keystone_policy.py'
2017-09-20 11:40:15,128 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/kubernetes.py' to '/var/cache/salt/minion/extmods/grains/kubernetes.py'
2017-09-20 11:40:15,129 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/neutron_policy.py' to '/var/cache/salt/minion/extmods/grains/neutron_policy.py'
2017-09-20 11:40:15,129 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/nova_policy.py' to '/var/cache/salt/minion/extmods/grains/nova_policy.py'
2017-09-20 11:40:15,129 [salt.utils.extmods][INFO    ][24612] Copying '/var/cache/salt/minion/files/base/_grains/ssh_fingerprints.py' to '/var/cache/salt/minion/extmods/grains/ssh_fingerprints.py'
2017-09-20 11:40:15,131 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/renderers'
2017-09-20 11:40:15,137 [salt.utils.extmods][INFO    ][24612] Syncing renderers for environment 'base'
2017-09-20 11:40:15,137 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_renderers, for base)
2017-09-20 11:40:15,138 [salt.fileclient  ][INFO    ][24612] Caching directory '_renderers/' for environment 'base'
2017-09-20 11:40:15,253 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/returners'
2017-09-20 11:40:15,259 [salt.utils.extmods][INFO    ][24612] Syncing returners for environment 'base'
2017-09-20 11:40:15,259 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_returners, for base)
2017-09-20 11:40:15,259 [salt.fileclient  ][INFO    ][24612] Caching directory '_returners/' for environment 'base'
2017-09-20 11:40:15,327 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/output'
2017-09-20 11:40:15,332 [salt.utils.extmods][INFO    ][24612] Syncing output for environment 'base'
2017-09-20 11:40:15,332 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_output, for base)
2017-09-20 11:40:15,332 [salt.fileclient  ][INFO    ][24612] Caching directory '_output/' for environment 'base'
2017-09-20 11:40:15,435 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/utils'
2017-09-20 11:40:15,440 [salt.utils.extmods][INFO    ][24612] Syncing utils for environment 'base'
2017-09-20 11:40:15,441 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_utils, for base)
2017-09-20 11:40:15,441 [salt.fileclient  ][INFO    ][24612] Caching directory '_utils/' for environment 'base'
2017-09-20 11:40:15,499 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
2017-09-20 11:40:15,504 [salt.utils.extmods][INFO    ][24612] Syncing log_handlers for environment 'base'
2017-09-20 11:40:15,504 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_log_handlers, for base)
2017-09-20 11:40:15,505 [salt.fileclient  ][INFO    ][24612] Caching directory '_log_handlers/' for environment 'base'
2017-09-20 11:40:16,058 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/proxy'
2017-09-20 11:40:16,064 [salt.utils.extmods][INFO    ][24612] Syncing proxy for environment 'base'
2017-09-20 11:40:16,064 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_proxy, for base)
2017-09-20 11:40:16,064 [salt.fileclient  ][INFO    ][24612] Caching directory '_proxy/' for environment 'base'
2017-09-20 11:40:16,109 [salt.utils.extmods][INFO    ][24612] Creating module dir '/var/cache/salt/minion/extmods/engines'
2017-09-20 11:40:16,115 [salt.utils.extmods][INFO    ][24612] Syncing engines for environment 'base'
2017-09-20 11:40:16,115 [salt.utils.extmods][INFO    ][24612] Loading cache from salt://_engines, for base)
2017-09-20 11:40:16,116 [salt.fileclient  ][INFO    ][24612] Caching directory '_engines/' for environment 'base'
2017-09-20 11:40:16,149 [salt.minion      ][INFO    ][24612] Returning information for job: 20170920114008809665
2017-09-20 12:08:50,020 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command test.ping with jid 20170920120850007187
2017-09-20 12:08:50,048 [salt.minion      ][INFO    ][25160] Starting a new job with PID 25160
2017-09-20 12:08:50,110 [salt.minion      ][INFO    ][25160] Returning information for job: 20170920120850007187
2017-09-20 12:09:22,476 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command state.sls with jid 20170920120922462775
2017-09-20 12:09:22,498 [salt.minion      ][INFO    ][25183] Starting a new job with PID 25183
2017-09-20 12:09:23,965 [salt.state       ][INFO    ][25183] Loading fresh modules for state activity
2017-09-20 12:09:24,075 [salt.fileclient  ][INFO    ][25183] Fetching file from saltenv 'base', ** done ** 'keepalived/init.sls'
2017-09-20 12:09:24,102 [salt.fileclient  ][INFO    ][25183] Fetching file from saltenv 'base', ** done ** 'keepalived/cluster.sls'
2017-09-20 12:09:24,140 [salt.fileclient  ][INFO    ][25183] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 12:09:24,516 [salt.state       ][INFO    ][25183] Running state [lsof] at time 12:09:24.516195
2017-09-20 12:09:24,516 [salt.state       ][INFO    ][25183] Executing state pkg.installed for lsof
2017-09-20 12:09:24,517 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:09:24,953 [salt.state       ][INFO    ][25183] Package lsof is already installed
2017-09-20 12:09:24,954 [salt.state       ][INFO    ][25183] Completed state [lsof] at time 12:09:24.953633 duration_in_ms=437.438
2017-09-20 12:09:24,954 [salt.state       ][INFO    ][25183] Running state [keepalived] at time 12:09:24.953943
2017-09-20 12:09:24,954 [salt.state       ][INFO    ][25183] Executing state pkg.installed for keepalived
2017-09-20 12:09:24,981 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-20 12:09:29,046 [salt.loaded.int.module.cmdmod][INFO    ][25183] 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-09-20 12:09:32,521 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920120932507117
2017-09-20 12:09:32,543 [salt.minion      ][INFO    ][25853] Starting a new job with PID 25853
2017-09-20 12:09:32,565 [salt.minion      ][INFO    ][25853] Returning information for job: 20170920120932507117
2017-09-20 12:09:38,530 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:09:38,585 [salt.state       ][INFO    ][25183] 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-09-20 12:09:38,619 [salt.state       ][INFO    ][25183] Loading fresh modules for state activity
2017-09-20 12:09:38,647 [salt.state       ][INFO    ][25183] Completed state [keepalived] at time 12:09:38.646524 duration_in_ms=13692.58
2017-09-20 12:09:38,651 [salt.state       ][INFO    ][25183] Running state [/etc/keepalived/keepalived.conf] at time 12:09:38.651197
2017-09-20 12:09:38,652 [salt.state       ][INFO    ][25183] Executing state file.managed for /etc/keepalived/keepalived.conf
2017-09-20 12:09:38,683 [salt.fileclient  ][INFO    ][25183] Fetching file from saltenv 'base', ** done ** 'keepalived/files/keepalived.conf'
2017-09-20 12:09:38,727 [salt.fileclient  ][INFO    ][25183] Fetching file from saltenv 'base', ** done ** 'keepalived/map.jinja'
2017-09-20 12:09:38,737 [salt.state       ][INFO    ][25183] File changed:
New file
2017-09-20 12:09:38,737 [salt.state       ][INFO    ][25183] Completed state [/etc/keepalived/keepalived.conf] at time 12:09:38.737349 duration_in_ms=86.152
2017-09-20 12:09:38,834 [salt.state       ][INFO    ][25183] Running state [keepalived] at time 12:09:38.834346
2017-09-20 12:09:38,835 [salt.state       ][INFO    ][25183] Executing state service.running for keepalived
2017-09-20 12:09:38,837 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemctl', 'status', 'keepalived.service', '-n', '0'] in directory '/root'
2017-09-20 12:09:38,851 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-20 12:09:38,864 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:09:38,878 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:09:38,892 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'keepalived.service'] in directory '/root'
2017-09-20 12:09:38,985 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemctl', 'is-active', 'keepalived.service'] in directory '/root'
2017-09-20 12:09:39,000 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:09:39,012 [salt.loaded.int.module.cmdmod][INFO    ][25183] Executing command ['systemctl', 'is-enabled', 'keepalived.service'] in directory '/root'
2017-09-20 12:09:39,021 [salt.state       ][INFO    ][25183] {'keepalived': True}
2017-09-20 12:09:39,022 [salt.state       ][INFO    ][25183] Completed state [keepalived] at time 12:09:39.021815 duration_in_ms=187.468
2017-09-20 12:09:39,024 [salt.minion      ][INFO    ][25183] Returning information for job: 20170920120922462775
2017-09-20 12:13:31,096 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command pillar.get with jid 20170920121331083934
2017-09-20 12:13:31,121 [salt.minion      ][INFO    ][26837] Starting a new job with PID 26837
2017-09-20 12:13:31,131 [salt.minion      ][INFO    ][26837] Returning information for job: 20170920121331083934
2017-09-20 12:19:53,680 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command state.sls with jid 20170920121953673551
2017-09-20 12:19:53,702 [salt.minion      ][INFO    ][26962] Starting a new job with PID 26962
2017-09-20 12:19:55,173 [salt.state       ][INFO    ][26962] Loading fresh modules for state activity
2017-09-20 12:19:55,214 [salt.fileclient  ][INFO    ][26962] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-20 12:19:55,244 [salt.fileclient  ][INFO    ][26962] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-20 12:19:55,655 [salt.state       ][INFO    ][26962] Running state [attr] at time 12:19:55.654913
2017-09-20 12:19:55,655 [salt.state       ][INFO    ][26962] Executing state pkg.installed for attr
2017-09-20 12:19:55,656 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:19:56,114 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2017-09-20 12:19:58,402 [salt.loaded.int.module.cmdmod][INFO    ][26962] 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-09-20 12:20:01,474 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:20:01,531 [salt.state       ][INFO    ][26962] Made the following changes:
'attr' changed from 'absent' to '1:2.4.47-2'

2017-09-20 12:20:01,554 [salt.state       ][INFO    ][26962] Loading fresh modules for state activity
2017-09-20 12:20:01,579 [salt.state       ][INFO    ][26962] Completed state [attr] at time 12:20:01.578780 duration_in_ms=5923.866
2017-09-20 12:20:01,591 [salt.state       ][INFO    ][26962] Running state [glusterfs-server] at time 12:20:01.590864
2017-09-20 12:20:01,591 [salt.state       ][INFO    ][26962] Executing state pkg.installed for glusterfs-server
2017-09-20 12:20:01,830 [salt.loaded.int.module.cmdmod][INFO    ][26962] 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-09-20 12:20:03,771 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122003757704
2017-09-20 12:20:03,792 [salt.minion      ][INFO    ][27953] Starting a new job with PID 27953
2017-09-20 12:20:03,809 [salt.minion      ][INFO    ][27953] Returning information for job: 20170920122003757704
2017-09-20 12:20:13,992 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122013978480
2017-09-20 12:20:14,014 [salt.minion      ][INFO    ][30631] Starting a new job with PID 30631
2017-09-20 12:20:14,033 [salt.minion      ][INFO    ][30631] Returning information for job: 20170920122013978480
2017-09-20 12:20:24,017 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command saltutil.find_job with jid 20170920122024003616
2017-09-20 12:20:24,038 [salt.minion      ][INFO    ][31245] Starting a new job with PID 31245
2017-09-20 12:20:24,054 [salt.minion      ][INFO    ][31245] Returning information for job: 20170920122024003616
2017-09-20 12:20:24,740 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:20:24,798 [salt.state       ][INFO    ][26962] 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-09-20 12:20:24,815 [salt.state       ][INFO    ][26962] Loading fresh modules for state activity
2017-09-20 12:20:24,842 [salt.state       ][INFO    ][26962] Completed state [glusterfs-server] at time 12:20:24.842025 duration_in_ms=23251.158
2017-09-20 12:20:24,854 [salt.state       ][INFO    ][26962] Running state [bc] at time 12:20:24.853803
2017-09-20 12:20:24,854 [salt.state       ][INFO    ][26962] Executing state pkg.installed for bc
2017-09-20 12:20:25,177 [salt.loaded.int.module.cmdmod][INFO    ][26962] 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-09-20 12:20:28,799 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:20:28,857 [salt.state       ][INFO    ][26962] Made the following changes:
'bc' changed from 'absent' to '1.06.95-9build1'

2017-09-20 12:20:28,873 [salt.state       ][INFO    ][26962] Loading fresh modules for state activity
2017-09-20 12:20:28,898 [salt.state       ][INFO    ][26962] Completed state [bc] at time 12:20:28.897903 duration_in_ms=4044.099
2017-09-20 12:20:29,002 [salt.state       ][INFO    ][26962] Running state [glusterfs-server] at time 12:20:29.002264
2017-09-20 12:20:29,003 [salt.state       ][INFO    ][26962] Executing state service.running for glusterfs-server
2017-09-20 12:20:29,006 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-20 12:20:29,024 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:20:29,039 [salt.loaded.int.module.cmdmod][INFO    ][26962] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:20:29,057 [salt.state       ][INFO    ][26962] The service glusterfs-server is already running
2017-09-20 12:20:29,058 [salt.state       ][INFO    ][26962] Completed state [glusterfs-server] at time 12:20:29.057888 duration_in_ms=55.625
2017-09-20 12:20:29,061 [salt.state       ][INFO    ][26962] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:20:29.061352
2017-09-20 12:20:29,062 [salt.state       ][INFO    ][26962] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-20 12:20:29,078 [salt.state       ][INFO    ][26962] File changed:
New file
2017-09-20 12:20:29,078 [salt.state       ][INFO    ][26962] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:20:29.078154 duration_in_ms=16.802
2017-09-20 12:20:29,078 [salt.state       ][INFO    ][26962] Running state [/srv/glusterfs/glance] at time 12:20:29.078428
2017-09-20 12:20:29,079 [salt.state       ][INFO    ][26962] Executing state file.directory for /srv/glusterfs/glance
2017-09-20 12:20:29,080 [salt.state       ][INFO    ][26962] {'/srv/glusterfs/glance': 'New Dir'}
2017-09-20 12:20:29,080 [salt.state       ][INFO    ][26962] Completed state [/srv/glusterfs/glance] at time 12:20:29.079962 duration_in_ms=1.534
2017-09-20 12:20:29,080 [salt.state       ][INFO    ][26962] Running state [/srv/glusterfs/keystone-keys] at time 12:20:29.080215
2017-09-20 12:20:29,080 [salt.state       ][INFO    ][26962] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-20 12:20:29,081 [salt.state       ][INFO    ][26962] {'/srv/glusterfs/keystone-keys': 'New Dir'}
2017-09-20 12:20:29,081 [salt.state       ][INFO    ][26962] Completed state [/srv/glusterfs/keystone-keys] at time 12:20:29.081340 duration_in_ms=1.126
2017-09-20 12:20:29,082 [salt.state       ][INFO    ][26962] Running state [/srv/glusterfs/nova_instances] at time 12:20:29.081592
2017-09-20 12:20:29,082 [salt.state       ][INFO    ][26962] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-20 12:20:29,083 [salt.state       ][INFO    ][26962] {'/srv/glusterfs/nova_instances': 'New Dir'}
2017-09-20 12:20:29,083 [salt.state       ][INFO    ][26962] Completed state [/srv/glusterfs/nova_instances] at time 12:20:29.082723 duration_in_ms=1.13
2017-09-20 12:20:29,083 [salt.state       ][INFO    ][26962] Running state [/srv/glusterfs/keystone-credential-keys] at time 12:20:29.082972
2017-09-20 12:20:29,083 [salt.state       ][INFO    ][26962] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-20 12:20:29,084 [salt.state       ][INFO    ][26962] {'/srv/glusterfs/keystone-credential-keys': 'New Dir'}
2017-09-20 12:20:29,084 [salt.state       ][INFO    ][26962] Completed state [/srv/glusterfs/keystone-credential-keys] at time 12:20:29.084103 duration_in_ms=1.131
2017-09-20 12:20:29,085 [salt.minion      ][INFO    ][26962] Returning information for job: 20170920121953673551
2017-09-20 12:20:30,229 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command test.ping with jid 20170920122030221285
2017-09-20 12:20:30,250 [salt.minion      ][INFO    ][31690] Starting a new job with PID 31690
2017-09-20 12:20:30,309 [salt.minion      ][INFO    ][31690] Returning information for job: 20170920122030221285
2017-09-20 12:21:11,264 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command state.sls with jid 20170920122111254568
2017-09-20 12:21:11,286 [salt.minion      ][INFO    ][31909] Starting a new job with PID 31909
2017-09-20 12:21:12,762 [salt.state       ][INFO    ][31909] Loading fresh modules for state activity
2017-09-20 12:21:12,801 [salt.fileclient  ][INFO    ][31909] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/setup.sls'
2017-09-20 12:21:12,855 [salt.fileclient  ][INFO    ][31909] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-20 12:21:12,950 [salt.fileclient  ][INFO    ][31909] Fetching file from saltenv 'base', ** done ** 'glusterfs/server/service.sls'
2017-09-20 12:21:12,971 [salt.fileclient  ][INFO    ][31909] Fetching file from saltenv 'base', ** done ** 'glusterfs/map.jinja'
2017-09-20 12:21:13,342 [salt.state       ][INFO    ][31909] Running state [attr] at time 12:21:13.342191
2017-09-20 12:21:13,342 [salt.state       ][INFO    ][31909] Executing state pkg.installed for attr
2017-09-20 12:21:13,343 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}\n', '-W'] in directory '/root'
2017-09-20 12:21:13,788 [salt.state       ][INFO    ][31909] Package attr is already installed
2017-09-20 12:21:13,788 [salt.state       ][INFO    ][31909] Completed state [attr] at time 12:21:13.788123 duration_in_ms=445.931
2017-09-20 12:21:13,788 [salt.state       ][INFO    ][31909] Running state [glusterfs-server] at time 12:21:13.788411
2017-09-20 12:21:13,789 [salt.state       ][INFO    ][31909] Executing state pkg.installed for glusterfs-server
2017-09-20 12:21:13,796 [salt.state       ][INFO    ][31909] Package glusterfs-server is already installed
2017-09-20 12:21:13,797 [salt.state       ][INFO    ][31909] Completed state [glusterfs-server] at time 12:21:13.796441 duration_in_ms=8.029
2017-09-20 12:21:13,797 [salt.state       ][INFO    ][31909] Running state [bc] at time 12:21:13.796816
2017-09-20 12:21:13,797 [salt.state       ][INFO    ][31909] Executing state pkg.installed for bc
2017-09-20 12:21:13,802 [salt.state       ][INFO    ][31909] Package bc is already installed
2017-09-20 12:21:13,802 [salt.state       ][INFO    ][31909] Completed state [bc] at time 12:21:13.801692 duration_in_ms=4.876
2017-09-20 12:21:13,803 [salt.state       ][INFO    ][31909] Running state [glusterfs-server] at time 12:21:13.803435
2017-09-20 12:21:13,804 [salt.state       ][INFO    ][31909] Executing state service.running for glusterfs-server
2017-09-20 12:21:13,804 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-20 12:21:13,822 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command ['systemctl', '--system', 'daemon-reload'] in directory '/root'
2017-09-20 12:21:13,914 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command ['systemctl', 'status', 'glusterfs-server.service', '-n', '0'] in directory '/root'
2017-09-20 12:21:13,942 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command ['systemctl', 'is-active', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:21:13,955 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command ['systemctl', 'is-enabled', 'glusterfs-server.service'] in directory '/root'
2017-09-20 12:21:13,973 [salt.state       ][INFO    ][31909] The service glusterfs-server is already running
2017-09-20 12:21:13,973 [salt.state       ][INFO    ][31909] Completed state [glusterfs-server] at time 12:21:13.973125 duration_in_ms=169.688
2017-09-20 12:21:13,979 [salt.state       ][INFO    ][31909] Running state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:21:13.978667
2017-09-20 12:21:13,979 [salt.state       ][INFO    ][31909] Executing state file.managed for /etc/systemd/system/glusterfs-server.service.d/override.conf
2017-09-20 12:21:13,994 [salt.state       ][INFO    ][31909] File /etc/systemd/system/glusterfs-server.service.d/override.conf is in the correct state
2017-09-20 12:21:13,995 [salt.state       ][INFO    ][31909] Completed state [/etc/systemd/system/glusterfs-server.service.d/override.conf] at time 12:21:13.994447 duration_in_ms=15.781
2017-09-20 12:21:13,995 [salt.state       ][INFO    ][31909] Running state [/srv/glusterfs/glance] at time 12:21:13.994771
2017-09-20 12:21:13,995 [salt.state       ][INFO    ][31909] Executing state file.directory for /srv/glusterfs/glance
2017-09-20 12:21:13,996 [salt.state       ][INFO    ][31909] Directory /srv/glusterfs/glance is in the correct state
2017-09-20 12:21:13,996 [salt.state       ][INFO    ][31909] Completed state [/srv/glusterfs/glance] at time 12:21:13.996069 duration_in_ms=1.297
2017-09-20 12:21:13,996 [salt.state       ][INFO    ][31909] Running state [/srv/glusterfs/keystone-keys] at time 12:21:13.996374
2017-09-20 12:21:13,997 [salt.state       ][INFO    ][31909] Executing state file.directory for /srv/glusterfs/keystone-keys
2017-09-20 12:21:13,997 [salt.state       ][INFO    ][31909] Directory /srv/glusterfs/keystone-keys is in the correct state
2017-09-20 12:21:13,998 [salt.state       ][INFO    ][31909] Completed state [/srv/glusterfs/keystone-keys] at time 12:21:13.997620 duration_in_ms=1.246
2017-09-20 12:21:13,998 [salt.state       ][INFO    ][31909] Running state [/srv/glusterfs/nova_instances] at time 12:21:13.997916
2017-09-20 12:21:13,998 [salt.state       ][INFO    ][31909] Executing state file.directory for /srv/glusterfs/nova_instances
2017-09-20 12:21:13,999 [salt.state       ][INFO    ][31909] Directory /srv/glusterfs/nova_instances is in the correct state
2017-09-20 12:21:13,999 [salt.state       ][INFO    ][31909] Completed state [/srv/glusterfs/nova_instances] at time 12:21:13.999131 duration_in_ms=1.215
2017-09-20 12:21:13,999 [salt.state       ][INFO    ][31909] Running state [/srv/glusterfs/keystone-credential-keys] at time 12:21:13.999447
2017-09-20 12:21:13,1000 [salt.state       ][INFO    ][31909] Executing state file.directory for /srv/glusterfs/keystone-credential-keys
2017-09-20 12:21:14,000 [salt.state       ][INFO    ][31909] Directory /srv/glusterfs/keystone-credential-keys is in the correct state
2017-09-20 12:21:14,001 [salt.state       ][INFO    ][31909] Completed state [/srv/glusterfs/keystone-credential-keys] at time 12:21:14.000693 duration_in_ms=1.247
2017-09-20 12:21:14,124 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command ['git', '--version'] in directory '/root'
2017-09-20 12:21:14,190 [salt.state       ][INFO    ][31909] Running state [sleep 5] at time 12:21:14.190077
2017-09-20 12:21:14,190 [salt.state       ][INFO    ][31909] Executing state cmd.wait for sleep 5
2017-09-20 12:21:14,191 [salt.state       ][INFO    ][31909] No changes made for sleep 5
2017-09-20 12:21:14,191 [salt.state       ][INFO    ][31909] Completed state [sleep 5] at time 12:21:14.190874 duration_in_ms=0.799
2017-09-20 12:21:14,191 [salt.state       ][INFO    ][31909] Running state [10.167.4.141] at time 12:21:14.191375
2017-09-20 12:21:14,192 [salt.state       ][INFO    ][31909] Executing state glusterfs.peered for 10.167.4.141
2017-09-20 12:21:14,192 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:14,208 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:14,316 [salt.state       ][INFO    ][31909] Host 10.167.4.141 already peered
2017-09-20 12:21:14,317 [salt.state       ][INFO    ][31909] Completed state [10.167.4.141] at time 12:21:14.316479 duration_in_ms=125.102
2017-09-20 12:21:14,322 [salt.state       ][INFO    ][31909] Running state [10.167.4.142] at time 12:21:14.322416
2017-09-20 12:21:14,323 [salt.state       ][INFO    ][31909] Executing state glusterfs.peered for 10.167.4.142
2017-09-20 12:21:14,324 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:14,341 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:14,452 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:14,467 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:14,575 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:14,591 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:14,692 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:14,710 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:14,822 [salt.state       ][INFO    ][31909] {'new': {'kvm02.baremetal-mcp-ocata-odl-ha.local': ['10.167.4.142'], '10.167.4.141': []}, 'old': {'kvm02.baremetal-mcp-ocata-odl-ha.local': [], '10.167.4.141': []}}
2017-09-20 12:21:14,823 [salt.state       ][INFO    ][31909] Completed state [10.167.4.142] at time 12:21:14.822798 duration_in_ms=500.379
2017-09-20 12:21:14,829 [salt.state       ][INFO    ][31909] Running state [10.167.4.143] at time 12:21:14.828966
2017-09-20 12:21:14,830 [salt.state       ][INFO    ][31909] Executing state glusterfs.peered for 10.167.4.143
2017-09-20 12:21:14,831 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:14,848 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:14,954 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:14,972 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:15,081 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:15,099 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:15,208 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:15,226 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:15,333 [salt.state       ][INFO    ][31909] Peering with localhost is not needed
2017-09-20 12:21:15,333 [salt.state       ][INFO    ][31909] Completed state [10.167.4.143] at time 12:21:15.333237 duration_in_ms=504.269
2017-09-20 12:21:15,335 [salt.state       ][INFO    ][31909] Running state [glance] at time 12:21:15.334963
2017-09-20 12:21:15,335 [salt.state       ][INFO    ][31909] Executing state glusterfs.created for glance
2017-09-20 12:21:15,336 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:15,350 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:15,449 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:15,468 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:15,582 [salt.state       ][INFO    ][31909] Volume glance already exists and is started.
2017-09-20 12:21:15,582 [salt.state       ][INFO    ][31909] Completed state [glance] at time 12:21:15.582303 duration_in_ms=247.338
2017-09-20 12:21:15,589 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:15.589232
2017-09-20 12:21:15,590 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:21:15,670 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'glance' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:15,780 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:15,781 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'glance' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:15.780665 duration_in_ms=191.431
2017-09-20 12:21:15,782 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 12:21:15.781815
2017-09-20 12:21:15,782 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'glance' 'network.remote-dio' 'True'
2017-09-20 12:21:15,783 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'glance' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:21:15,889 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:15,889 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'glance' 'network.remote-dio' 'True'] at time 12:21:15.889127 duration_in_ms=107.311
2017-09-20 12:21:15,890 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 12:21:15.890266
2017-09-20 12:21:15,891 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'glance' 'nfs.disable' 'True'
2017-09-20 12:21:15,892 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'glance' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:21:15,988 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:15,988 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'glance' 'nfs.disable' 'True'] at time 12:21:15.988204 duration_in_ms=97.937
2017-09-20 12:21:15,989 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:15.989337
2017-09-20 12:21:15,990 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:21:15,991 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'glance' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:16,097 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:16,097 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'glance' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:16.097028 duration_in_ms=107.69
2017-09-20 12:21:16,098 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 12:21:16.098207
2017-09-20 12:21:16,099 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'glance' 'cluster.readdir-optimize' 'True'
2017-09-20 12:21:16,100 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'glance' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:21:16,200 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:16,201 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'glance' 'cluster.readdir-optimize' 'True'] at time 12:21:16.200663 duration_in_ms=102.455
2017-09-20 12:21:16,203 [salt.state       ][INFO    ][31909] Running state [glance] at time 12:21:16.203299
2017-09-20 12:21:16,204 [salt.state       ][INFO    ][31909] Executing state glusterfs.started for glance
2017-09-20 12:21:16,205 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:16,223 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:16,317 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:16,333 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:16,445 [salt.state       ][INFO    ][31909] Volume glance is already started
2017-09-20 12:21:16,446 [salt.state       ][INFO    ][31909] Completed state [glance] at time 12:21:16.445488 duration_in_ms=242.188
2017-09-20 12:21:16,448 [salt.state       ][INFO    ][31909] Running state [keystone-keys] at time 12:21:16.447588
2017-09-20 12:21:16,448 [salt.state       ][INFO    ][31909] Executing state glusterfs.created for keystone-keys
2017-09-20 12:21:16,449 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:16,468 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:16,576 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:16,591 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:16,703 [salt.state       ][INFO    ][31909] Volume keystone-keys already exists and is started.
2017-09-20 12:21:16,704 [salt.state       ][INFO    ][31909] Completed state [keystone-keys] at time 12:21:16.703927 duration_in_ms=256.339
2017-09-20 12:21:16,711 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 12:21:16.711358
2017-09-20 12:21:16,712 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-keys' 'network.remote-dio' 'True'
2017-09-20 12:21:16,713 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:21:16,823 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:16,824 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-keys' 'network.remote-dio' 'True'] at time 12:21:16.823929 duration_in_ms=112.57
2017-09-20 12:21:16,825 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:16.825125
2017-09-20 12:21:16,826 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:21:16,827 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:16,926 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:16,926 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:16.926356 duration_in_ms=101.23
2017-09-20 12:21:16,927 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:16.927416
2017-09-20 12:21:16,928 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:21:16,929 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:17,031 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:17,032 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:17.031618 duration_in_ms=104.202
2017-09-20 12:21:17,033 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 12:21:17.032757
2017-09-20 12:21:17,033 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-keys' 'nfs.disable' 'True'
2017-09-20 12:21:17,034 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:21:17,134 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:17,135 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-keys' 'nfs.disable' 'True'] at time 12:21:17.134685 duration_in_ms=101.926
2017-09-20 12:21:17,136 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 12:21:17.135725
2017-09-20 12:21:17,136 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'
2017-09-20 12:21:17,137 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:21:17,223 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:17,223 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-keys' 'cluster.readdir-optimize' 'True'] at time 12:21:17.222960 duration_in_ms=87.235
2017-09-20 12:21:17,226 [salt.state       ][INFO    ][31909] Running state [keystone-keys] at time 12:21:17.225571
2017-09-20 12:21:17,226 [salt.state       ][INFO    ][31909] Executing state glusterfs.started for keystone-keys
2017-09-20 12:21:17,227 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:17,243 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:17,347 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:17,363 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:17,470 [salt.state       ][INFO    ][31909] Volume keystone-keys is already started
2017-09-20 12:21:17,471 [salt.state       ][INFO    ][31909] Completed state [keystone-keys] at time 12:21:17.470723 duration_in_ms=245.151
2017-09-20 12:21:17,473 [salt.state       ][INFO    ][31909] Running state [nova_instances] at time 12:21:17.473171
2017-09-20 12:21:17,474 [salt.state       ][INFO    ][31909] Executing state glusterfs.created for nova_instances
2017-09-20 12:21:17,475 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:17,492 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:17,601 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:17,621 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:17,732 [salt.state       ][INFO    ][31909] Volume nova_instances already exists and is started.
2017-09-20 12:21:17,732 [salt.state       ][INFO    ][31909] Completed state [nova_instances] at time 12:21:17.732074 duration_in_ms=258.902
2017-09-20 12:21:17,740 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:17.739844
2017-09-20 12:21:17,740 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:21:17,741 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:17,841 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:17,842 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'nova_instances' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:17.841929 duration_in_ms=102.082
2017-09-20 12:21:17,843 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 12:21:17.843076
2017-09-20 12:21:17,844 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'
2017-09-20 12:21:17,845 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'nova_instances' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:21:17,947 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:17,948 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'nova_instances' 'cluster.readdir-optimize' 'True'] at time 12:21:17.947762 duration_in_ms=104.684
2017-09-20 12:21:17,949 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 12:21:17.948834
2017-09-20 12:21:17,949 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'nova_instances' 'network.remote-dio' 'True'
2017-09-20 12:21:17,950 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'nova_instances' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:21:18,052 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:18,052 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'nova_instances' 'network.remote-dio' 'True'] at time 12:21:18.052231 duration_in_ms=103.396
2017-09-20 12:21:18,053 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 12:21:18.053330
2017-09-20 12:21:18,054 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'nova_instances' 'nfs.disable' 'True'
2017-09-20 12:21:18,055 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'nova_instances' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:21:18,158 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:18,158 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'nova_instances' 'nfs.disable' 'True'] at time 12:21:18.158275 duration_in_ms=104.944
2017-09-20 12:21:18,159 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:18.159401
2017-09-20 12:21:18,160 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:21:18,161 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'nova_instances' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:18,264 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:18,265 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'nova_instances' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:18.264769 duration_in_ms=105.366
2017-09-20 12:21:18,267 [salt.state       ][INFO    ][31909] Running state [nova_instances] at time 12:21:18.267377
2017-09-20 12:21:18,268 [salt.state       ][INFO    ][31909] Executing state glusterfs.started for nova_instances
2017-09-20 12:21:18,269 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:18,286 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:18,390 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:18,409 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:18,521 [salt.state       ][INFO    ][31909] Volume nova_instances is already started
2017-09-20 12:21:18,522 [salt.state       ][INFO    ][31909] Completed state [nova_instances] at time 12:21:18.521417 duration_in_ms=254.038
2017-09-20 12:21:18,524 [salt.state       ][INFO    ][31909] Running state [keystone-credential-keys] at time 12:21:18.523493
2017-09-20 12:21:18,524 [salt.state       ][INFO    ][31909] Executing state glusterfs.created for keystone-credential-keys
2017-09-20 12:21:18,525 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:18,542 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:18,644 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:18,661 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:18,771 [salt.state       ][INFO    ][31909] Volume keystone-credential-keys already exists and is started.
2017-09-20 12:21:18,772 [salt.state       ][INFO    ][31909] Completed state [keystone-credential-keys] at time 12:21:18.771633 duration_in_ms=248.139
2017-09-20 12:21:18,780 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 12:21:18.779640
2017-09-20 12:21:18,780 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'
2017-09-20 12:21:18,781 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'cluster.readdir-optimize: True'' in directory '/root'
2017-09-20 12:21:18,886 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:18,887 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-credential-keys' 'cluster.readdir-optimize' 'True'] at time 12:21:18.886920 duration_in_ms=107.279
2017-09-20 12:21:18,888 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:18.887896
2017-09-20 12:21:18,888 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'
2017-09-20 12:21:18,889 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.brick-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:18,992 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:18,993 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.brick-log-level' 'WARNING'] at time 12:21:18.992616 duration_in_ms=104.72
2017-09-20 12:21:18,994 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 12:21:18.993633
2017-09-20 12:21:18,994 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'
2017-09-20 12:21:18,995 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'nfs.disable: True'' in directory '/root'
2017-09-20 12:21:19,098 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:19,098 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-credential-keys' 'nfs.disable' 'True'] at time 12:21:19.098201 duration_in_ms=104.567
2017-09-20 12:21:19,099 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:19.099323
2017-09-20 12:21:19,100 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'
2017-09-20 12:21:19,101 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'diagnostics.client-log-level: WARNING'' in directory '/root'
2017-09-20 12:21:19,201 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:19,202 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-credential-keys' 'diagnostics.client-log-level' 'WARNING'] at time 12:21:19.201563 duration_in_ms=102.239
2017-09-20 12:21:19,203 [salt.state       ][INFO    ][31909] Running state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 12:21:19.202737
2017-09-20 12:21:19,203 [salt.state       ][INFO    ][31909] Executing state cmd.run for gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'
2017-09-20 12:21:19,204 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster volume info 'keystone-credential-keys' | grep 'network.remote-dio: True'' in directory '/root'
2017-09-20 12:21:19,309 [salt.state       ][INFO    ][31909] unless execution succeeded
2017-09-20 12:21:19,310 [salt.state       ][INFO    ][31909] Completed state [gluster volume set 'keystone-credential-keys' 'network.remote-dio' 'True'] at time 12:21:19.309548 duration_in_ms=106.81
2017-09-20 12:21:19,312 [salt.state       ][INFO    ][31909] Running state [keystone-credential-keys] at time 12:21:19.312158
2017-09-20 12:21:19,313 [salt.state       ][INFO    ][31909] Executing state glusterfs.started for keystone-credential-keys
2017-09-20 12:21:19,314 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:19,328 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:19,437 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --version' in directory '/root'
2017-09-20 12:21:19,454 [salt.loaded.int.module.cmdmod][INFO    ][31909] Executing command 'gluster --xml --mode=script' in directory '/root'
2017-09-20 12:21:19,564 [salt.state       ][INFO    ][31909] Volume keystone-credential-keys is already started
2017-09-20 12:21:19,565 [salt.state       ][INFO    ][31909] Completed state [keystone-credential-keys] at time 12:21:19.564577 duration_in_ms=252.417
2017-09-20 12:21:19,568 [salt.minion      ][INFO    ][31909] Returning information for job: 20170920122111254568
2017-09-20 12:21:27,896 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command test.ping with jid 20170920122127887816
2017-09-20 12:21:27,919 [salt.minion      ][INFO    ][32335] Starting a new job with PID 32335
2017-09-20 12:21:27,968 [salt.minion      ][INFO    ][32335] Returning information for job: 20170920122127887816
2017-09-20 12:21:28,366 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command cmd.run with jid 20170920122128353628
2017-09-20 12:21:28,389 [salt.minion      ][INFO    ][32350] Starting a new job with PID 32350
2017-09-20 12:21:28,396 [salt.loaded.int.module.cmdmod][INFO    ][32350] Executing command 'gluster peer status; gluster volume status' in directory '/root'
2017-09-20 12:21:28,611 [salt.minion      ][INFO    ][32350] Returning information for job: 20170920122128353628
2017-09-20 12:38:34,760 [salt.utils.schedule][INFO    ][24406] Running scheduled job: __mine_interval
2017-09-20 13:38:12,650 [salt.minion      ][INFO    ][24406] User sudo_ubuntu Executing command cp.push_dir with jid 20170920133812634144
2017-09-20 13:38:12,675 [salt.minion      ][INFO    ][1032] Starting a new job with PID 1032
